builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-066 starttime: 1463548578.73 results: success (0) buildid: 20160517193059 builduid: 61a00e88093d4fc3a5e7f1b57b9c084f revision: 1ef763f4dc5b833dacd5e3863b40c5e87ebeecbd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-17 22:16:18.730233) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-17 22:16:18.730844) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-17 22:16:18.731212) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-17 22:16:18.748210) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 22:16:18.748659) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1463547347.450848-516888591 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-17 22:16:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 88.8M=0s 2016-05-17 22:16:18 (88.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.085813 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 22:16:18.849626) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 22:16:18.850016) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1463547347.450848-516888591 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021326 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 22:16:18.900467) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 22:16:18.900931) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 1ef763f4dc5b833dacd5e3863b40c5e87ebeecbd --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 1ef763f4dc5b833dacd5e3863b40c5e87ebeecbd --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=82c74bd3bc9a11c5833d2010000001e6-1463547347.450848-516888591 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-17 22:16:19,085 truncating revision to first 12 chars 2016-05-17 22:16:19,085 Setting DEBUG logging. 2016-05-17 22:16:19,085 attempt 1/10 2016-05-17 22:16:19,085 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/1ef763f4dc5b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-17 22:16:19,332 unpacking tar archive at: mozilla-inbound-1ef763f4dc5b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.525108 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 22:16:19.441322) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-17 22:16:19.441749) ========= script_repo_revision: 1ef763f4dc5b833dacd5e3863b40c5e87ebeecbd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-17 22:16:19.442211) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-17 22:16:19.443045) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-17 22:16:19.452414) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-05-17 22:16:19.452936) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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=82c74bd3bc9a11c5833d2010000001e6-1463547347.450848-516888591 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:16:19 INFO - MultiFileLogger online at 20160517 22:16:19 in /builds/slave/test 22:16:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 22:16:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:16:19 INFO - {'append_to_log': False, 22:16:19 INFO - 'base_work_dir': '/builds/slave/test', 22:16:19 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 22:16:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:16:19 INFO - 'branch': 'Mozilla-Inbound', 22:16:19 INFO - 'buildbot_json_path': 'buildprops.json', 22:16:19 INFO - 'config_files': ('talos/linux_config.py',), 22:16:19 INFO - 'default_actions': ('clobber', 22:16:19 INFO - 'read-buildbot-config', 22:16:19 INFO - 'download-and-extract', 22:16:19 INFO - 'populate-webroot', 22:16:19 INFO - 'create-virtualenv', 22:16:19 INFO - 'install', 22:16:19 INFO - 'run-tests'), 22:16:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:16:19 INFO - 'download_minidump_stackwalk': True, 22:16:19 INFO - 'download_symbols': 'ondemand', 22:16:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:16:19 INFO - 'tooltool.py': '/tools/tooltool.py', 22:16:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:16:19 INFO - '/tools/misc-python/virtualenv.py')}, 22:16:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:16:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:16:19 INFO - 'installer_path': 'installer.exe', 22:16:19 INFO - 'log_level': 'info', 22:16:19 INFO - 'log_name': 'talos', 22:16:19 INFO - 'log_to_console': True, 22:16:19 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:16:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:16:19 INFO - 'opt_config_files': (), 22:16:19 INFO - 'pip_index': False, 22:16:19 INFO - 'sps_profile': False, 22:16:19 INFO - 'sps_profile_interval': 0, 22:16:19 INFO - 'suite': 'dromaeojs', 22:16:19 INFO - 'system_bits': '32', 22:16:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:16:19 INFO - 'title': 'talos-linux64-ix-066', 22:16:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:16:19 INFO - 'use_talos_json': True, 22:16:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:16:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:16:19 INFO - 'work_dir': 'build'} 22:16:19 INFO - ##### 22:16:19 INFO - ##### Running clobber step. 22:16:19 INFO - ##### 22:16:19 INFO - Running pre-action listener: _resource_record_pre_action 22:16:19 INFO - Running main action method: clobber 22:16:19 INFO - rmtree: /builds/slave/test/build 22:16:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:16:20 INFO - Running post-action listener: _resource_record_post_action 22:16:20 INFO - ##### 22:16:20 INFO - ##### Finished clobber step (success) 22:16:20 INFO - ##### 22:16:20 INFO - ##### 22:16:20 INFO - ##### Running read-buildbot-config step. 22:16:20 INFO - ##### 22:16:20 INFO - Running pre-action listener: _resource_record_pre_action 22:16:20 INFO - Running main action method: read_buildbot_config 22:16:20 INFO - Using buildbot properties: 22:16:20 INFO - { 22:16:20 INFO - "project": "", 22:16:20 INFO - "product": "firefox", 22:16:20 INFO - "script_repo_revision": "production", 22:16:20 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 22:16:20 INFO - "repository": "", 22:16:20 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 22:16:20 INFO - "buildid": "20160517193059", 22:16:20 INFO - "pgo_build": "True", 22:16:20 INFO - "basedir": "/builds/slave/test", 22:16:20 INFO - "buildnumber": 926, 22:16:20 INFO - "slavename": "talos-linux64-ix-066", 22:16:20 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 22:16:20 INFO - "platform": "ubuntu64_hw", 22:16:20 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 22:16:20 INFO - "branch": "mozilla-inbound", 22:16:20 INFO - "repo_path": "integration/mozilla-inbound", 22:16:20 INFO - "revision": "1ef763f4dc5b833dacd5e3863b40c5e87ebeecbd", 22:16:20 INFO - "stage_platform": "linux64-pgo", 22:16:20 INFO - "builduid": "61a00e88093d4fc3a5e7f1b57b9c084f", 22:16:20 INFO - "slavebuilddir": "test" 22:16:20 INFO - } 22:16:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 22:16:20 INFO - Running post-action listener: _resource_record_post_action 22:16:20 INFO - ##### 22:16:20 INFO - ##### Finished read-buildbot-config step (success) 22:16:20 INFO - ##### 22:16:20 INFO - ##### 22:16:20 INFO - ##### Running download-and-extract step. 22:16:20 INFO - ##### 22:16:20 INFO - Running pre-action listener: _resource_record_pre_action 22:16:20 INFO - Running main action method: download_and_extract 22:16:20 INFO - mkdir: /builds/slave/test/build/tests 22:16:20 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:16:20 INFO - https://queue.taskcluster.net/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:16:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:16:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:16:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:16:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:16:20 INFO - Downloaded 1361 bytes. 22:16:20 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:16:20 INFO - Using the following test package requirements: 22:16:20 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 22:16:20 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:16:20 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:16:20 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:16:20 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:16:20 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:16:20 INFO - u'jsshell-linux-x86_64.zip'], 22:16:20 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:16:20 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:16:20 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 22:16:20 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:16:20 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:16:20 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:16:20 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:16:20 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:16:20 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:16:20 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:16:20 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:16:20 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:16:20 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:16:20 INFO - https://queue.taskcluster.net/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:16:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:16:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:16:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:16:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:16:22 INFO - Downloaded 24347965 bytes. 22:16:22 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:16:22 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:16:23 INFO - Return code: 0 22:16:23 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:16: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:16:23 INFO - https://queue.taskcluster.net/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:16:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:16:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:16:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:16:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:16:27 INFO - Downloaded 24347965 bytes. 22:16:27 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:16:27 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:16:29 INFO - Return code: 0 22:16:29 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:16:29 INFO - https://queue.taskcluster.net/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:16:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:16:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:16:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:16:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:16:30 INFO - Downloaded 10840194 bytes. 22:16:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:16:30 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 22:16:30 INFO - Return code: 0 22:16: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:16:30 INFO - https://queue.taskcluster.net/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:16:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:16:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:16:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:16:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:16:33 INFO - Downloaded 58386727 bytes. 22:16:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:16:33 INFO - mkdir: /builds/slave/test/properties 22:16:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:16:33 INFO - Writing to file /builds/slave/test/properties/build_url 22:16:33 INFO - Contents: 22:16:33 INFO - build_url:https://queue.taskcluster.net/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:16:33 INFO - Running post-action listener: _resource_record_post_action 22:16:33 INFO - Running post-action listener: set_extra_try_arguments 22:16:33 INFO - ##### 22:16:33 INFO - ##### Finished download-and-extract step (success) 22:16:33 INFO - ##### 22:16:33 INFO - ##### 22:16:33 INFO - ##### Running populate-webroot step. 22:16:33 INFO - ##### 22:16:33 INFO - Running pre-action listener: _resource_record_pre_action 22:16:33 INFO - Running main action method: populate_webroot 22:16:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:16:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:16:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:16:33 INFO - '--tppagecycles', 22:16:33 INFO - '7'], 22:16:33 INFO - 'tests': ['tsvgm']}}, 22:16:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:16:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:16:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:16:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:16:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:16:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:16:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:16:33 INFO - 'talos_options': ['--disable-e10s'], 22:16:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:16:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:16:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:16:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:16:33 INFO - 'talos_options': ['--disable-e10s'], 22:16:33 INFO - 'tests': ['damp', 'tps']}, 22:16:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:16:33 INFO - 'tests': ['damp', 'tps']}, 22:16:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:16:33 INFO - 'tests': ['dromaeo_dom']}, 22:16:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:16:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:16:33 INFO - 'tests': ['a11yr', 22:16:33 INFO - 'ts_paint', 22:16:33 INFO - 'tpaint', 22:16:33 INFO - 'sessionrestore', 22:16:33 INFO - 'sessionrestore_no_auto_restore', 22:16:33 INFO - 'tabpaint']}, 22:16:33 INFO - 'other-e10s': {'tests': ['a11yr', 22:16:33 INFO - 'ts_paint', 22:16:33 INFO - 'tpaint', 22:16:33 INFO - 'sessionrestore', 22:16:33 INFO - 'sessionrestore_no_auto_restore', 22:16:33 INFO - 'tabpaint']}, 22:16:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:16:33 INFO - 'ts_paint', 22:16:33 INFO - 'tpaint', 22:16:33 INFO - 'sessionrestore', 22:16:33 INFO - 'sessionrestore_no_auto_restore', 22:16:33 INFO - 'tabpaint']}, 22:16:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:16:33 INFO - 'ts_paint', 22:16:33 INFO - 'tpaint', 22:16:33 INFO - 'sessionrestore', 22:16:33 INFO - 'sessionrestore_no_auto_restore', 22:16:33 INFO - 'tabpaint']}, 22:16:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:16:33 INFO - 'tests': ['a11yr', 22:16:33 INFO - 'ts_paint', 22:16:33 INFO - 'tpaint', 22:16:33 INFO - 'sessionrestore', 22:16:33 INFO - 'sessionrestore_no_auto_restore', 22:16:33 INFO - 'tabpaint']}, 22:16:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:16:33 INFO - 'tests': ['a11yr', 22:16:33 INFO - 'ts_paint', 22:16:33 INFO - 'tpaint', 22:16:33 INFO - 'sessionrestore', 22:16:33 INFO - 'sessionrestore_no_auto_restore', 22:16:33 INFO - 'tabpaint']}, 22:16:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:16:33 INFO - 'tests': ['tsvgx', 22:16:33 INFO - 'tsvgr_opacity', 22:16:33 INFO - 'tart', 22:16:33 INFO - 'tscrollx', 22:16:33 INFO - 'cart']}, 22:16:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:16:33 INFO - 'tsvgr_opacity', 22:16:33 INFO - 'tart', 22:16:33 INFO - 'tscrollx', 22:16:33 INFO - 'cart']}, 22:16:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:16:33 INFO - 'talos_options': ['--disable-e10s'], 22:16:33 INFO - 'tests': ['tp5o']}, 22:16:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:16:33 INFO - 'tests': ['tp5o']}, 22:16:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:16:33 INFO - 'talos_options': ['--disable-e10s', 22:16:33 INFO - '--xperf_path', 22:16:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:16:33 INFO - 'tests': ['tp5n']}, 22:16:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:16:33 INFO - 'talos_options': ['--xperf_path', 22:16:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:16:33 INFO - 'tests': ['tp5n']}}, 22:16:33 INFO - 'talos.zip': {'path': '', 22:16:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:16:33 INFO - Running post-action listener: _resource_record_post_action 22:16:33 INFO - ##### 22:16:33 INFO - ##### Finished populate-webroot step (success) 22:16:33 INFO - ##### 22:16:33 INFO - ##### 22:16:33 INFO - ##### Running create-virtualenv step. 22:16:33 INFO - ##### 22:16:33 INFO - Running pre-action listener: _resource_record_pre_action 22:16:33 INFO - Running main action method: create_virtualenv 22:16:33 INFO - Creating virtualenv /builds/slave/test/build/venv 22:16:33 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 22:16:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:16:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:16:34 INFO - Using real prefix '/usr' 22:16:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:16:34 INFO - Installing distribute.............................................................................................................................................................................................done. 22:16:36 INFO - Installing pip.................done. 22:16:36 INFO - Return code: 0 22:16:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:16:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:16:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:16:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:16:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:16:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1b947a4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1191770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12209c0>, '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 0x12218c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1221d50>, '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': '82c74bd3bc9a11c5833d2010000001e6-1463547347.450848-516888591', '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:16:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:16:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:16:36 INFO - Using env: {'DISPLAY': ':0', 22:16:36 INFO - 'HOME': '/home/cltbld', 22:16:36 INFO - 'LANG': 'en_US.UTF-8', 22:16:36 INFO - 'LANGUAGE': 'en_US:en', 22:16:36 INFO - 'LOGNAME': 'cltbld', 22:16:36 INFO - 'MAIL': '/var/mail/cltbld', 22:16:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:16:36 INFO - 'MOZ_NO_REMOTE': '1', 22:16:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:16:36 INFO - 'NO_EM_RESTART': '1', 22:16:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:16:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:16:36 INFO - 'PWD': '/builds/slave/test', 22:16:36 INFO - 'SHELL': '/bin/bash', 22:16:36 INFO - 'SHLVL': '1', 22:16:36 INFO - 'TERM': 'linux', 22:16:36 INFO - 'TMOUT': '86400', 22:16:36 INFO - 'USER': 'cltbld', 22:16:36 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1463547347.450848-516888591', 22:16:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:16:36 INFO - '_': '/tools/buildbot/bin/python'} 22:16:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:16:36 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 22:16:36 INFO - Cleaning up... 22:16:36 INFO - Return code: 0 22:16:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:16:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:16:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:16:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:16:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:16:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1b947a4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1191770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12209c0>, '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 0x12218c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1221d50>, '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': '82c74bd3bc9a11c5833d2010000001e6-1463547347.450848-516888591', '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:16:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 22:16:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 22:16:36 INFO - Using env: {'DISPLAY': ':0', 22:16:36 INFO - 'HOME': '/home/cltbld', 22:16:36 INFO - 'LANG': 'en_US.UTF-8', 22:16:36 INFO - 'LANGUAGE': 'en_US:en', 22:16:36 INFO - 'LOGNAME': 'cltbld', 22:16:36 INFO - 'MAIL': '/var/mail/cltbld', 22:16:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:16:36 INFO - 'MOZ_NO_REMOTE': '1', 22:16:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:16:36 INFO - 'NO_EM_RESTART': '1', 22:16:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:16:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:16:36 INFO - 'PWD': '/builds/slave/test', 22:16:36 INFO - 'SHELL': '/bin/bash', 22:16:36 INFO - 'SHLVL': '1', 22:16:36 INFO - 'TERM': 'linux', 22:16:36 INFO - 'TMOUT': '86400', 22:16:36 INFO - 'USER': 'cltbld', 22:16:36 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1463547347.450848-516888591', 22:16:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:16:36 INFO - '_': '/tools/buildbot/bin/python'} 22:16:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:16:36 INFO - Downloading/unpacking psutil>=3.1.1 22:16:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:16:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:16:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:16:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:16:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:16:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:16:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:16:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:16:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:16:38 INFO - Installing collected packages: psutil 22:16:38 INFO - Running setup.py install for psutil 22:16:38 INFO - building 'psutil._psutil_linux' extension 22:16:38 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 22:16:39 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 22:16:39 INFO - building 'psutil._psutil_posix' extension 22:16:39 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 22:16:39 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 22:16:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:16:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:16:39 INFO - Successfully installed psutil 22:16:39 INFO - Cleaning up... 22:16:39 INFO - Return code: 0 22:16:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:16:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:16:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:16:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:16:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:16:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1b947a4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1191770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12209c0>, '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 0x12218c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1221d50>, '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': '82c74bd3bc9a11c5833d2010000001e6-1463547347.450848-516888591', '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:16:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:16:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 22:16:39 INFO - Using env: {'DISPLAY': ':0', 22:16:39 INFO - 'HOME': '/home/cltbld', 22:16:39 INFO - 'LANG': 'en_US.UTF-8', 22:16:39 INFO - 'LANGUAGE': 'en_US:en', 22:16:39 INFO - 'LOGNAME': 'cltbld', 22:16:39 INFO - 'MAIL': '/var/mail/cltbld', 22:16:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:16:39 INFO - 'MOZ_NO_REMOTE': '1', 22:16:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:16:39 INFO - 'NO_EM_RESTART': '1', 22:16:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:16:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:16:39 INFO - 'PWD': '/builds/slave/test', 22:16:39 INFO - 'SHELL': '/bin/bash', 22:16:39 INFO - 'SHLVL': '1', 22:16:39 INFO - 'TERM': 'linux', 22:16:39 INFO - 'TMOUT': '86400', 22:16:39 INFO - 'USER': 'cltbld', 22:16:39 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1463547347.450848-516888591', 22:16:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:16:39 INFO - '_': '/tools/buildbot/bin/python'} 22:16:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:16:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:16:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:16:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:16:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:16:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:16:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:16:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:16:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:16:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:16:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:16:41 INFO - Installing collected packages: mozsystemmonitor 22:16:41 INFO - Running setup.py install for mozsystemmonitor 22:16:41 INFO - Successfully installed mozsystemmonitor 22:16:41 INFO - Cleaning up... 22:16:41 INFO - Return code: 0 22:16:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:16:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:16:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:16:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:16:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:16:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f1b947a4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1191770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12209c0>, '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 0x12218c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1221d50>, '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': '82c74bd3bc9a11c5833d2010000001e6-1463547347.450848-516888591', '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:16:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:16:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:16:41 INFO - Using env: {'DISPLAY': ':0', 22:16:41 INFO - 'HOME': '/home/cltbld', 22:16:41 INFO - 'LANG': 'en_US.UTF-8', 22:16:41 INFO - 'LANGUAGE': 'en_US:en', 22:16:41 INFO - 'LOGNAME': 'cltbld', 22:16:41 INFO - 'MAIL': '/var/mail/cltbld', 22:16:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:16:41 INFO - 'MOZ_NO_REMOTE': '1', 22:16:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:16:41 INFO - 'NO_EM_RESTART': '1', 22:16:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:16:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:16:41 INFO - 'PWD': '/builds/slave/test', 22:16:41 INFO - 'SHELL': '/bin/bash', 22:16:41 INFO - 'SHLVL': '1', 22:16:41 INFO - 'TERM': 'linux', 22:16:41 INFO - 'TMOUT': '86400', 22:16:41 INFO - 'USER': 'cltbld', 22:16:41 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1463547347.450848-516888591', 22:16:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:16:41 INFO - '_': '/tools/buildbot/bin/python'} 22:16:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:16:41 INFO - Downloading/unpacking blobuploader==1.2.4 22:16:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:16:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:16:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:16:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:16:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:16:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:16:43 INFO - Downloading blobuploader-1.2.4.tar.gz 22:16:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:16:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:16:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:16:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:16:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:16:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:16:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:16:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:16:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:16:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:16:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:16:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:16:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:16:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:16:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:16:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:16:44 INFO - Downloading docopt-0.6.1.tar.gz 22:16:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:16:44 INFO - Installing collected packages: blobuploader, requests, docopt 22:16:44 INFO - Running setup.py install for blobuploader 22:16:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:16:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:16:44 INFO - Running setup.py install for requests 22:16:45 INFO - Running setup.py install for docopt 22:16:45 INFO - Successfully installed blobuploader requests docopt 22:16:45 INFO - Cleaning up... 22:16:45 INFO - Return code: 0 22:16:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:16:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:16:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:16:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:16:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:16:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1b947a4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1191770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12209c0>, '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 0x12218c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1221d50>, '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': '82c74bd3bc9a11c5833d2010000001e6-1463547347.450848-516888591', '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:16:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:16:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:16:45 INFO - Using env: {'DISPLAY': ':0', 22:16:45 INFO - 'HOME': '/home/cltbld', 22:16:45 INFO - 'LANG': 'en_US.UTF-8', 22:16:45 INFO - 'LANGUAGE': 'en_US:en', 22:16:45 INFO - 'LOGNAME': 'cltbld', 22:16:45 INFO - 'MAIL': '/var/mail/cltbld', 22:16:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:16:45 INFO - 'MOZ_NO_REMOTE': '1', 22:16:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:16:45 INFO - 'NO_EM_RESTART': '1', 22:16:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:16:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:16:45 INFO - 'PWD': '/builds/slave/test', 22:16:45 INFO - 'SHELL': '/bin/bash', 22:16:45 INFO - 'SHLVL': '1', 22:16:45 INFO - 'TERM': 'linux', 22:16:45 INFO - 'TMOUT': '86400', 22:16:45 INFO - 'USER': 'cltbld', 22:16:45 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1463547347.450848-516888591', 22:16:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:16:45 INFO - '_': '/tools/buildbot/bin/python'} 22:16:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:16:45 INFO - Running setup.py (path:/tmp/pip-5o8Wy_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:16:45 INFO - Running setup.py (path:/tmp/pip-BRX1WG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:16:45 INFO - Running setup.py (path:/tmp/pip-xfeJgI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:16:45 INFO - Running setup.py (path:/tmp/pip-sQkbxq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:16:45 INFO - Running setup.py (path:/tmp/pip-jOFd5X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:16:45 INFO - Running setup.py (path:/tmp/pip-t9NUyO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:16:45 INFO - Running setup.py (path:/tmp/pip-oA5CC1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:16:45 INFO - Running setup.py (path:/tmp/pip-DwBI3F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:16:45 INFO - Running setup.py (path:/tmp/pip-5_3AOY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:16:46 INFO - Running setup.py (path:/tmp/pip-iNS8_y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:16:46 INFO - Running setup.py (path:/tmp/pip-2VIVer-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:16:46 INFO - Running setup.py (path:/tmp/pip-OMJkzQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:16:46 INFO - Running setup.py (path:/tmp/pip-nXNo1w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:16:46 INFO - Running setup.py (path:/tmp/pip-iv2trt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:16:46 INFO - Running setup.py (path:/tmp/pip-t3JIec-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:16:46 INFO - Running setup.py (path:/tmp/pip-QvUzJf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:16:46 INFO - Running setup.py (path:/tmp/pip-ZYpG_E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:16:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:16:46 INFO - Running setup.py install for manifestparser 22:16:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:16:46 INFO - Running setup.py install for mozcrash 22:16:46 INFO - Running setup.py install for mozdebug 22:16:46 INFO - Running setup.py install for mozdevice 22:16:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:16:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:16:47 INFO - Running setup.py install for mozfile 22:16:47 INFO - Running setup.py install for mozhttpd 22:16:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:16:47 INFO - Running setup.py install for mozinfo 22:16:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:16:47 INFO - Running setup.py install for mozInstall 22:16:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:16:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:16:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:16:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:16:47 INFO - Running setup.py install for mozleak 22:16:47 INFO - Running setup.py install for mozlog 22:16:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:16:47 INFO - Running setup.py install for moznetwork 22:16:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:16:47 INFO - Running setup.py install for mozprocess 22:16:47 INFO - Running setup.py install for mozprofile 22:16:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:16:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:16:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:16:47 INFO - Running setup.py install for mozrunner 22:16:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:16:48 INFO - Running setup.py install for mozscreenshot 22:16:48 INFO - Running setup.py install for moztest 22:16:48 INFO - Running setup.py install for mozversion 22:16:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:16:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:16:48 INFO - Cleaning up... 22:16:48 INFO - Return code: 0 22:16:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:16:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:16:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:16:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:16:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:16:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16:48 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 0x7f1b947a4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1191770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12209c0>, '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 0x12218c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1221d50>, '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': '82c74bd3bc9a11c5833d2010000001e6-1463547347.450848-516888591', '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:16:48 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:16:48 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:16:48 INFO - Using env: {'DISPLAY': ':0', 22:16:48 INFO - 'HOME': '/home/cltbld', 22:16:48 INFO - 'LANG': 'en_US.UTF-8', 22:16:48 INFO - 'LANGUAGE': 'en_US:en', 22:16:48 INFO - 'LOGNAME': 'cltbld', 22:16:48 INFO - 'MAIL': '/var/mail/cltbld', 22:16:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:16:48 INFO - 'MOZ_NO_REMOTE': '1', 22:16:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:16:48 INFO - 'NO_EM_RESTART': '1', 22:16:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:16:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:16:48 INFO - 'PWD': '/builds/slave/test', 22:16:48 INFO - 'SHELL': '/bin/bash', 22:16:48 INFO - 'SHLVL': '1', 22:16:48 INFO - 'TERM': 'linux', 22:16:48 INFO - 'TMOUT': '86400', 22:16:48 INFO - 'USER': 'cltbld', 22:16:48 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1463547347.450848-516888591', 22:16:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:16:48 INFO - '_': '/tools/buildbot/bin/python'} 22:16:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:16:48 INFO - Running setup.py (path:/tmp/pip-QnqhYp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:16:48 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:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:16:48 INFO - Running setup.py (path:/tmp/pip-qyibo7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:16:48 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:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:16:48 INFO - Running setup.py (path:/tmp/pip-W3JTKk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:16:48 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:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:16:48 INFO - Running setup.py (path:/tmp/pip-V67_Y0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:16:48 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:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:16:48 INFO - Running setup.py (path:/tmp/pip-X8CsWZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:16:48 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:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:16:48 INFO - Running setup.py (path:/tmp/pip-ogaJVf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:16:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 22:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:16:49 INFO - Running setup.py (path:/tmp/pip-c4JyUy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:16:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 22:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:16:49 INFO - Running setup.py (path:/tmp/pip-TGZJ4u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:16:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 22:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:16:49 INFO - Running setup.py (path:/tmp/pip-IcBSLH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:16:49 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:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:16:49 INFO - Running setup.py (path:/tmp/pip-km1qcY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:16:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:16:49 INFO - Running setup.py (path:/tmp/pip-m1KQaR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:16:49 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:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:16:49 INFO - Running setup.py (path:/tmp/pip-71UtAu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:16:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:16:49 INFO - Running setup.py (path:/tmp/pip-v0TnLP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:16:49 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:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:16:49 INFO - Running setup.py (path:/tmp/pip-yReM3u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:16:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:16:49 INFO - Running setup.py (path:/tmp/pip-UoCwNz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:16:49 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:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:16:49 INFO - Running setup.py (path:/tmp/pip-xfXyJx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:16:49 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:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:16:49 INFO - Running setup.py (path:/tmp/pip-Xk9Nc6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:16:49 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:16:49 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:16:49 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:16:49 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:16:49 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:16:49 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:16:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:16:49 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:16:49 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:16:49 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:16:49 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:16:49 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:16:49 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:16:51 INFO - Downloading blessings-1.6.tar.gz 22:16:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:16:51 INFO - Installing collected packages: blessings 22:16:51 INFO - Running setup.py install for blessings 22:16:51 INFO - Successfully installed blessings 22:16:51 INFO - Cleaning up... 22:16:51 INFO - Return code: 0 22:16:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:16:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:16:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:16:52 INFO - Reading from file tmpfile_stdout 22:16:52 INFO - Current package versions: 22:16:52 INFO - argparse == 1.2.1 22:16:52 INFO - blessings == 1.6 22:16:52 INFO - blobuploader == 1.2.4 22:16:52 INFO - docopt == 0.6.1 22:16:52 INFO - manifestparser == 1.1 22:16:52 INFO - mozInstall == 1.12 22:16:52 INFO - mozcrash == 0.17 22:16:52 INFO - mozdebug == 0.1 22:16:52 INFO - mozdevice == 0.48 22:16:52 INFO - mozfile == 1.2 22:16:52 INFO - mozhttpd == 0.7 22:16:52 INFO - mozinfo == 0.9 22:16:52 INFO - mozleak == 0.1 22:16:52 INFO - mozlog == 3.2 22:16:52 INFO - moznetwork == 0.27 22:16:52 INFO - mozprocess == 0.22 22:16:52 INFO - mozprofile == 0.28 22:16:52 INFO - mozrunner == 6.11 22:16:52 INFO - mozscreenshot == 0.1 22:16:52 INFO - mozsystemmonitor == 0.3 22:16:52 INFO - moztest == 0.7 22:16:52 INFO - mozversion == 1.4 22:16:52 INFO - psutil == 3.1.1 22:16:52 INFO - requests == 1.2.3 22:16:52 INFO - wsgiref == 0.1.2 22:16:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:16:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:16:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:16:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:16:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:16:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1b947a4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1191770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12209c0>, '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 0x12218c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1221d50>, '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': '82c74bd3bc9a11c5833d2010000001e6-1463547347.450848-516888591', '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:16:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 22:16:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:16:52 INFO - Using env: {'DISPLAY': ':0', 22:16:52 INFO - 'HOME': '/home/cltbld', 22:16:52 INFO - 'LANG': 'en_US.UTF-8', 22:16:52 INFO - 'LANGUAGE': 'en_US:en', 22:16:52 INFO - 'LOGNAME': 'cltbld', 22:16:52 INFO - 'MAIL': '/var/mail/cltbld', 22:16:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:16:52 INFO - 'MOZ_NO_REMOTE': '1', 22:16:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:16:52 INFO - 'NO_EM_RESTART': '1', 22:16:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:16:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:16:52 INFO - 'PWD': '/builds/slave/test', 22:16:52 INFO - 'SHELL': '/bin/bash', 22:16:52 INFO - 'SHLVL': '1', 22:16:52 INFO - 'TERM': 'linux', 22:16:52 INFO - 'TMOUT': '86400', 22:16:52 INFO - 'USER': 'cltbld', 22:16:52 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1463547347.450848-516888591', 22:16:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:16:52 INFO - '_': '/tools/buildbot/bin/python'} 22:16:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:16:52 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:16:52 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:16:52 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:16:52 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:16:52 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:16:52 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:16:52 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:16:52 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:16:52 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:16:52 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:16:52 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:16:52 INFO - Cleaning up... 22:16:52 INFO - Return code: 0 22:16:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:16:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:16:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:16:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:16:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:16:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16:52 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 0x7f1b947a4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1191770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12209c0>, '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 0x12218c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1221d50>, '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': '82c74bd3bc9a11c5833d2010000001e6-1463547347.450848-516888591', '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:16:52 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:16:52 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:16:52 INFO - Using env: {'DISPLAY': ':0', 22:16:52 INFO - 'HOME': '/home/cltbld', 22:16:52 INFO - 'LANG': 'en_US.UTF-8', 22:16:52 INFO - 'LANGUAGE': 'en_US:en', 22:16:52 INFO - 'LOGNAME': 'cltbld', 22:16:52 INFO - 'MAIL': '/var/mail/cltbld', 22:16:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:16:52 INFO - 'MOZ_NO_REMOTE': '1', 22:16:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:16:52 INFO - 'NO_EM_RESTART': '1', 22:16:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:16:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:16:52 INFO - 'PWD': '/builds/slave/test', 22:16:52 INFO - 'SHELL': '/bin/bash', 22:16:52 INFO - 'SHLVL': '1', 22:16:52 INFO - 'TERM': 'linux', 22:16:52 INFO - 'TMOUT': '86400', 22:16:52 INFO - 'USER': 'cltbld', 22:16:52 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1463547347.450848-516888591', 22:16:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:16:52 INFO - '_': '/tools/buildbot/bin/python'} 22:16:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:16:52 INFO - Downloading/unpacking jsonschema 22:16:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:16:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:16:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:16:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:16:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:16:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:16:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:16:54 INFO - Downloading/unpacking functools32 (from jsonschema) 22:16:54 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:16:54 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:16:54 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:16:54 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:16:54 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:16:54 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:16:54 INFO - Downloading functools32-3.2.3-2.tar.gz 22:16:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:16:54 INFO - warning: no files found matching '*.txt' 22:16:54 INFO - no previously-included directories found matching 'build' 22:16:54 INFO - no previously-included directories found matching 'dist' 22:16:54 INFO - no previously-included directories found matching '.git*' 22:16:54 INFO - Installing collected packages: jsonschema, functools32 22:16:54 INFO - Running setup.py install for functools32 22:16:54 INFO - warning: no files found matching '*.txt' 22:16:54 INFO - no previously-included directories found matching 'build' 22:16:54 INFO - no previously-included directories found matching 'dist' 22:16:54 INFO - no previously-included directories found matching '.git*' 22:16:54 INFO - Successfully installed jsonschema functools32 22:16:54 INFO - Cleaning up... 22:16:54 INFO - Return code: 0 22:16:54 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:16: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:16:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:16:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16: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:16:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:16:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16:54 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1b947a4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1191770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12209c0>, '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 0x12218c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1221d50>, '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': '82c74bd3bc9a11c5833d2010000001e6-1463547347.450848-516888591', '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:16:54 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', 'flake8'] in /builds/slave/test/build 22:16:54 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 flake8 22:16:54 INFO - Using env: {'DISPLAY': ':0', 22:16:54 INFO - 'HOME': '/home/cltbld', 22:16:54 INFO - 'LANG': 'en_US.UTF-8', 22:16:54 INFO - 'LANGUAGE': 'en_US:en', 22:16:54 INFO - 'LOGNAME': 'cltbld', 22:16:54 INFO - 'MAIL': '/var/mail/cltbld', 22:16:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:16:54 INFO - 'MOZ_NO_REMOTE': '1', 22:16:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:16:54 INFO - 'NO_EM_RESTART': '1', 22:16:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:16:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:16:54 INFO - 'PWD': '/builds/slave/test', 22:16:54 INFO - 'SHELL': '/bin/bash', 22:16:54 INFO - 'SHLVL': '1', 22:16:54 INFO - 'TERM': 'linux', 22:16:54 INFO - 'TMOUT': '86400', 22:16:54 INFO - 'USER': 'cltbld', 22:16:54 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1463547347.450848-516888591', 22:16:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:16:54 INFO - '_': '/tools/buildbot/bin/python'} 22:16:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:16:55 INFO - Downloading/unpacking flake8 22:16:55 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:16:55 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:16:55 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:16:55 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:16:55 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:16:55 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:16:57 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:16:57 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:16:57 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:16:57 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:16:57 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:16:57 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:16:57 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:16:57 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:16:57 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:16:57 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:16:57 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:16:57 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:16:57 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:16:57 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:16:57 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:16:57 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:16:57 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:16:57 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:16:57 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:16:57 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:16:57 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:16:57 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:16:57 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:16:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:16:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:16:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:16:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:16:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:16:57 INFO - no previously-included directories found matching 'docs/_build' 22:16:57 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:16:57 INFO - Running setup.py install for pep8 22:16:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:16:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:16:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:16:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:16:57 INFO - no previously-included directories found matching 'docs/_build' 22:16:57 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:16:57 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:16:57 INFO - Cleaning up... 22:16:58 INFO - Return code: 0 22:16:58 INFO - Running post-action listener: _resource_record_post_action 22:16:58 INFO - Running post-action listener: _start_resource_monitoring 22:16:58 INFO - Starting resource monitoring. 22:16:58 INFO - ##### 22:16:58 INFO - ##### Finished create-virtualenv step (success) 22:16:58 INFO - ##### 22:16:58 INFO - ##### 22:16:58 INFO - ##### Running install step. 22:16:58 INFO - ##### 22:16:58 INFO - Running pre-action listener: _resource_record_pre_action 22:16:58 INFO - Running main action method: install 22:16:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:16:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:16:58 INFO - Reading from file tmpfile_stdout 22:16:58 INFO - Detecting whether we're running mozinstall >=1.0... 22:16:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:16:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:16:58 INFO - Reading from file tmpfile_stdout 22:16:58 INFO - Output received: 22:16:58 INFO - Usage: mozinstall [options] installer 22:16:58 INFO - Options: 22:16:58 INFO - -h, --help show this help message and exit 22:16:58 INFO - -d DEST, --destination=DEST 22:16:58 INFO - Directory to install application into. [default: 22:16:58 INFO - "/builds/slave/test"] 22:16:58 INFO - --app=APP Application being installed. [default: firefox] 22:16:58 INFO - mkdir: /builds/slave/test/build/application 22:16:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 22:16:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 22:17:14 INFO - Reading from file tmpfile_stdout 22:17:14 INFO - Output received: 22:17:14 INFO - /builds/slave/test/build/application/firefox/firefox 22:17:14 INFO - Running post-action listener: _resource_record_post_action 22:17:14 INFO - ##### 22:17:14 INFO - ##### Finished install step (success) 22:17:14 INFO - ##### 22:17:14 INFO - ##### 22:17:14 INFO - ##### Running run-tests step. 22:17:14 INFO - ##### 22:17:14 INFO - Running pre-action listener: _resource_record_pre_action 22:17:14 INFO - Running main action method: run_tests 22:17:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:17:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:17:14 INFO - Python 2.7.3 22:17:14 INFO - Return code: 0 22:17:14 INFO - grabbing minidump binary from tooltool 22:17:14 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:17:14 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 0x12209c0>, '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 0x12218c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1221d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:17:14 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:17:14 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:17:14 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:17:15 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:17:15 INFO - Return code: 0 22:17:15 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:17:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:17:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:17:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:17:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:17:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:17:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:17:15 INFO - Return code: 0 22:17:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--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:17:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-066 --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:17:15 INFO - Using env: {'DISPLAY': ':0', 22:17:15 INFO - 'HOME': '/home/cltbld', 22:17:15 INFO - 'LANG': 'en_US.UTF-8', 22:17:15 INFO - 'LANGUAGE': 'en_US:en', 22:17:15 INFO - 'LOGNAME': 'cltbld', 22:17:15 INFO - 'MAIL': '/var/mail/cltbld', 22:17:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:17:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:17:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:15 INFO - 'MOZ_NO_REMOTE': '1', 22:17:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:17:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:17:15 INFO - 'NO_EM_RESTART': '1', 22:17:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:17:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:15 INFO - 'PWD': '/builds/slave/test', 22:17:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:17:15 INFO - 'SHELL': '/bin/bash', 22:17:16 INFO - 'SHLVL': '1', 22:17:16 INFO - 'TERM': 'linux', 22:17:16 INFO - 'TMOUT': '86400', 22:17:16 INFO - 'USER': 'cltbld', 22:17:16 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1463547347.450848-516888591', 22:17:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:16 INFO - '_': '/tools/buildbot/bin/python'} 22:17:16 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--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:17:16 INFO - mozversion application_buildid: 20160517193059 22:17:16 INFO - mozversion application_changeset: 1ef763f4dc5b833dacd5e3863b40c5e87ebeecbd 22:17:16 INFO - mozversion application_display_name: Nightly 22:17:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:17:16 INFO - mozversion application_name: Firefox 22:17:16 INFO - mozversion application_remotingname: firefox 22:17:16 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:17:16 INFO - mozversion application_vendor: Mozilla 22:17:16 INFO - mozversion application_version: 49.0a1 22:17:16 INFO - mozversion platform_buildid: 20160517193059 22:17:16 INFO - mozversion platform_changeset: 1ef763f4dc5b833dacd5e3863b40c5e87ebeecbd 22:17:16 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:17:16 INFO - mozversion platform_version: 49.0a1 22:17:16 INFO - using testdate: 1463548636 22:17:16 INFO - actual date: 1463548636 22:17:16 INFO - starting webserver on 'localhost:56395' 22:17:16 INFO - SUITE-START | Running 2 tests 22:17:16 INFO - TEST-START | dromaeo_css 22:17:16 INFO - operating with platform_type : linux_ 22:17:16 INFO - Initialising browser for dromaeo_css test... 22:17:16 INFO - TEST-INFO | started process 13848 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpY6QWWz/profile http://localhost:56395/getInfo.html) 22:17:20 INFO - PROCESS | 13848 | __metrics Screen width/height:1600/1200 22:17:20 INFO - PROCESS | 13848 | colorDepth:24 22:17:20 INFO - PROCESS | 13848 | Browser inner width/height: 1024/697 22:17:20 INFO - PROCESS | 13848 | __metrics 22:17:21 ERROR - PROCESS | 13848 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:17:22 INFO - TEST-INFO | 13848: exit 0 22:17:22 INFO - Browser initialized. 22:17:22 INFO - Running cycle 1/1 for dromaeo_css test... 22:17:22 INFO - TEST-INFO | started process 13929 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpY6QWWz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:17:34 INFO - PROCESS | 13929 | RSS: Main: 153128960 22:17:34 INFO - PROCESS | 13929 | 22:18:25 INFO - PROCESS | 13929 | 1463548705500 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:20:33 INFO - PROCESS | 13929 | Cycle 1(1): loaded http://localhost:56395/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56395/tests/dromaeo/cssquery-ext.html) 22:20:34 INFO - PROCESS | 13929 | RSS: Main: 261775360 22:20:34 INFO - PROCESS | 13929 | 22:23:37 INFO - PROCESS | 13929 | Cycle 1(1): loaded http://localhost:56395/tests/dromaeo/cssquery-ext.html (next: http://localhost:56395/tests/dromaeo/cssquery-jquery.html) 22:23:37 INFO - PROCESS | 13929 | RSS: Main: 232845312 22:23:37 INFO - PROCESS | 13929 | 22:26:39 INFO - PROCESS | 13929 | Cycle 1(1): loaded http://localhost:56395/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56395/tests/dromaeo/cssquery-mootools.html) 22:26:40 INFO - PROCESS | 13929 | RSS: Main: 242053120 22:26:40 INFO - PROCESS | 13929 | 22:29:42 INFO - PROCESS | 13929 | Cycle 1(1): loaded http://localhost:56395/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56395/tests/dromaeo/cssquery-prototype.html) 22:29:42 INFO - PROCESS | 13929 | RSS: Main: 234123264 22:29:42 INFO - PROCESS | 13929 | 22:32:45 INFO - PROCESS | 13929 | Cycle 1(1): loaded http://localhost:56395/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56395/tests/dromaeo/cssquery-yui.html) 22:32:45 INFO - PROCESS | 13929 | RSS: Main: 221585408 22:32:45 INFO - PROCESS | 13929 | 22:35:48 INFO - PROCESS | 13929 | Cycle 1(1): loaded http://localhost:56395/tests/dromaeo/cssquery-yui.html (next: http://localhost:56395/tests/dromaeo/cssquery-dojo.html) 22:35:48 INFO - PROCESS | 13929 | RSS: Main: 262750208 22:35:48 INFO - PROCESS | 13929 | 22:35:48 INFO - PROCESS | 13929 | __start_tp_report 22:35:48 INFO - PROCESS | 13929 | _x_x_mozilla_page_load 22:35:48 INFO - PROCESS | 13929 | _x_x_mozilla_page_load_details 22:35:48 INFO - PROCESS | 13929 | |i|pagename|runs| 22:35:48 INFO - PROCESS | 13929 | |0;dojo.html;3158.0683698308994;3160.6207255129384;3183.952240716389;3194.584703988481;3224.4034853552093;5301.814436494724;5318.175682769167;5422.322209723785;5566.721663916803;5580.972095139524;5462.562994960404;5498.395176530588;5503.944960550393;5510.393856675762;5530.972345138269;4942.777575009115;5022.924656130161;5042.647014708972;5055.191169412895;5191.377034755816;5135.537801597856;5139.871503212412;5164.845054648366;5165.9225111623355;5184.922226166611;4895.657303988723;4917.40991081069;4926;4932.506749325057;5005.374328208992;4973.726445045521;5003.324551185579;5007.749612519396;5020.548150666457;5064.18972964324;4810.494898035691;4952.479989601076;4974.10466116099;4995.8501244962345;5021.096202683516;5357.062514060063;5444.128939369682;5446.455354464521;5470.452954704538;5496.258005169291;5907.4683278505145;5956.136360227742;5958;5960.940390596125;5990.670513121777;5729.226554415142;5850.478875492403;5881.588288819784;5895.882082358372;5946.345901950782;5589.245451863987;5598.076317407664;5607.691576963306;5608;5609.747561359708;6099.672983688876;6107.236595425572;6111.572189946661;6113.266408030976;6115.587704881212;5555.444455554493;5649.435056494318;5705.429457054262;5706.25818643582;5892.499137573269;5807.622504537191;5833.7374818133285;5844.59087863854;5895.616784908967;5902.498287645512;5720.885582288331;5780.566457515703;5782.306123265235;5788.479036886701;5795.710214489259;5586.720663966867;5828.242328497353;5883.294004719375;5911.261092363455;5937.70311484424;4480.641548676098;4480.798364073625;4483.30508771141;4485.080558485568;4523.163214805271;3908.6677632401565;3963.7225394222705;3991.401289806552;4005.539362973237;4057.655099316532;5546.223528705985;5600.383957764642;5613.382527921925;5616.775328986795;5651.265335506358;5985;5991.041433370639;5991.5206783457215;5993.340732519419;6004.0693692477735;5391.29913111293;5472.890542189134;5629.915551266734;5640.971795140998;5644.5484361251;5019.222020586815;5064.5948324134715;5065.949340506621;5069.163588008011;5114.693118412907;6116.571839971249;6131.049687298476;6132.080187971751;6135.233095863017;6139.4781443577795;5761.769529218785;5849.444302791228;5882.882342353043;5892.175095486549;5892.941070589411;6070.0591408331775;6076.361981991828;6094.8171554853425;6117.235345581802;6160.353162917829;5786.739596718074;5833.737481813244;5845;5845.824625261249;5848.853778655568;5705.515031222392;5845.970770146122;5904.291485021653;5925.555583331354;5936.762529498772;5816.389279125631;5818.8254352369495;5821.738021788945;5822.214001109796;5827.621204621686;5736.5984381094595;5807.419258074159;5807.76768929255;5807.8548036299435;5810.883782324246;5450.972745136249;5546.861328466821;5610.887782244414;5622.381538030895;5677.971610141923;5768.990426675231;5781.884362312646;5811.215485909348;5821.825345239649;5822.70886455667;5516.889662206653;5629.155626656035;5636.549076073986;5638.605297629127;5641.379448260606 22:35:48 INFO - PROCESS | 13929 | |1;ext.html;598.1595857819765;633.5185259203005;645.7836624730714;646.3762469217207;660.369347273354;30597;43735.34396984044;44118.338224926614;44351.33472997908;44419.77790111053;47084.76457617716;47515.28727069097;47549.28676069853;47550.482704143134;47683.761581192135;8851.690190843306;9076.047015063423;9082.86375704365;9085.318601104911;9088.77278068047;8853.203211710945;8993.460392376479;8998.775030624254;8999;9000.639974401016;8996.100389960984;9047.95476022619;9064.909350906506;9068.682596109138;9081.364304498687;9023.954880225623;9058.048904864992;9060.77348066297;9064.229540489066;9074.364794464323;8931.106889311017;9017;9031.412958157764;9032.593533291018;9037.141471560191;42837.57162428367;43266.567334326566;43293.56706432927;43316;43352.566474335166;3524.4184709522897;3618.8009659468717;3675.503806986076;3697.1681371691425;3771.340015497277;31565.210869728442;32227.29427024851;32461;32552.186195345304;32619.51070733941;4228.76741779202;4231.259529582341;4269.893252668677;4314.1371725655;4369.300911854088;337349;338089;339239;339956;343844;61394.7814435769;61651.691741541006;61681.38318616846;61926.69036654788;62000;57862.421375786544;63732.81335933185;64537;64988.35011649823;70360.29639703537;17620;17646.735298970525;17804.643907122045;17818.46544603664;17828.46514604564;11916.582919597773;13556.254406007763;13626.523071692642;13632.727345453233;13677.931610341884;17889.7316540252;18056.277748889897;18141.45575632733;18791.624167516573;18792.81207187938;6803.727850886008;6853.862922741518;6973.267806880306;7102.644867756695;7159.3914517265575;977.8826540815147;1077.849101125843;1119.2445099557929;1128.8532490776147;1209.4315671634317;33255.20612090927;34027.48958765621;34100.31799363999;34191;34595.48106778401;1159.686884541169;1174.3775798826464;1188.4473719720372;1251.4994002399112;1254.6424269083268;17128.486145415656;17536.736948945778;17670.734938975926;17830.659598765298;17854.28582856697;156086;156606;156951.21524392304;157367.21316393345;157616;60638.69680651568;60932.69533652304;61128;61130;61289;1280.155097635556;1385.7713477276338;1419.6734751007325;1432.5201057645597;1451.528253317676;33255.667443325256;34844.82577587096;35064.47403289004;35187.82406087953;35238.11904702403;51080.74459627678;51295.74352128216;51567.48432515627;51665.225021623955;51698.74150629223;397970;399852;400012;400403;401132;37268.44097338488;41951.370729439695;42389.576104238564;42441.787791060844;42869.356959646255;49014.50985490099;49782.50217497779;50178.49821501738;50766.238506421694;50795.492045079074;41670.37494437647;42359.15281694411;42411.787941060094;42634.78682606691;43701.125977479634;40657.39013914853;41128.38307425331;41343.37984930168;41791.79104104581;42519.7874010628;38564;38620.6137938617;38716.80641596774;40644.796776017116;43909.56090439055;35686.821565892;37137.972895969724;37379.43930840985;37670.8116459416;37844.810775945945;28199.809440628098;28374.716252838032;28665.14004579866;29279.85360073186;31139.221519461287 22:35:48 INFO - PROCESS | 13929 | |2;jquery.html;382.76459977114075;389.7840775193026;389.94735710679066;396.06528592521653;405.76262886211566;19356.3225287115;19481.51296217592;19984.200631974723;20002.099905504252;20715.689264661043;17910.910445447786;18784.342548010824;18791.342303019395;19496.707549386734;19775.604487910234;5260.8158714445;5405.297311349519;5442.401335853053;5672.914906276409;5708.800191993281;5759.308882934054;5785.681787501686;5830.3586605473365;5834.329052159013;6550.967245163767;6515.869682606345;6530.8040758777315;6551.93448065518;6556.672166391661;6557.95072788354;6256.405641464076;6502.089707440962;6532.41208291256;6533.020046992941;6540.771073012422;5622.522085622727;5791.044477661181;5828.41715828418;5829.038208695602;5861.3552509223955;14372.784408233885;14747.262636868221;14749.410023599043;14759.262036898112;15200.391984320613;2156.8490205685607;2352.9058837646644;2368.3842201027683;2455.4229756007326;2562.1416825363526;10581;10719.83920241197;10746.570137194503;13011.34943252843;14328.355224014944;1959.9804001960083;1967.7048442733562;2025.4025062606565;2047.682609195577;2177.0529819528397;83146;84051;84878.57560712218;86989.56505217496;88130;10832.350058996486;11102.722431939266;11167.822274656526;11342.376169310766;11474.827877581843;14843.925780371244;14867.70264594702;14882;14905.70188596222;15249;8139.308158806568;8468.872966905501;8493.193146651058;8544.658213671508;8568.657253709907;8063.677452901936;8135.30849877767;8180.590970451453;8227.053888802864;8260.173982601691;4916.2625606158645;4960.553550180501;4974.701517908937;5032.421271553818;5053.545180933734;2008.728821609064;2046.2019812273224;2048.918043278282;2060.629086764367;2126.5640543688505;921.0605182713729;1003.7892042670975;1016.5628779624684;1050.175612144463;1108.939008354532;18851.05744712759;19251.711224331648;19470.22119115248;19669.803301966796;19712.21151153938;1028.7376718936623;1049.6483677967813;1065.3607835298758;1086.4839201379439;1101.498818037791;10691.893081069245;11140.164487663458;11164.497597608128;11186.105111591054;11205.159613029055;496486;497781;499129;501011;506434;96726.51636741772;97034.0296597025;98155;99912.50043749735;100352;1050.648032908984;1050.8476270940823;1073.8228192348174;1104.5305745058317;1112.8887111288807;16087.276072576527;16601.750973735645;16962;17243.655126897644;17366.39217627377;41327;42144.15711685811;42608.78695606502;43095;43460;57820.421795783186;58747.825043499746;60242.39757602368;61175.38824611697;61269.38730612815;7333.889991650236;7660.616969151409;7756.457048006585;7956.164602716632;8242.093369729444;9515.096065873731;12238.449269782703;13125.934370328087;13332.800008000082;13801;11766.88233117658;12280.938595306967;12414.62756117318;12539.37303134858;12563.24620522735;11525.481353338952;11595.18833681668;11638.12714046433;11895.405229738306;12006.459709313276;12131.757364852478;12383.318917459626;12440.37798110109;12451.937740311241;12658.240505569695;8624.655013799378;8794.812700285636;8822.647094116164;8982.955085224532;9212;8860.069692682175;11352.716182095513;12104.636860894187;12272.754544908874;12286.139970202 22:35:48 INFO - PROCESS | 13929 | |3;mootools.html;1363.420546267836;1546.4123633019453;1580.1467207707838;1759.8152194019628;1902.4863286912534;8746.475211487315;9174.770630734234;9177.219936305413;9388.90611093889;9402.529873506332;9093.318001149924;9275.53622318883;9288.11762882525;9308.069193080688;9314.347995640308;2480.7147178074574;2496.8876400562017;2502.724700282967;2503.5869081601604;2503.8748062596887;2486.3038349262238;2505.2734706934966;2513.5978243481045;2521.420073383114;2525.1288305534563;2440.804735621146;2445.510897820434;2466.0998735461517;2469.4320306329555;2474.505098980202;2367.266147494274;2368.8223383246327;2380.5000949800565;2426.8665223412704;2433.0267892842826;2356.893939772697;2373.5727569037567;2383.8688872112025;2386.53462574798;2393.904243830262;8888.688895888612;9043.050479699603;9101.271898248191;9101.590428430736;9141.634334626608;5564.360098588673;5681.857953551153;5687.118496633028;5696.971515142398;5698.8290351289525;3666.4866918631415;3720.3489389356755;3731.5708693500324;3733.663970242664;3745.0637340664834;1242;1242.8384310039728;2089.3731880435807;2174.1194816099505;2197.8901054947187;11871.52513899443;11922.165448418615;11940;12363.320017399039;12398.876011239772;4563.429571303587;4683.695559788603;4726.976365118152;4731.550502702238;4733.077049975222;9724.17344525709;9756.609735610638;9774.657886973922;9782.902170978341;9789.853152202722;5339.546138578265;5353;5354.170103633943;5355.678659280377;5356.571474282048;4272.906275924836;4779.808807647655;5061.7975280989085;5245.606579506476;5336.599755018312;3954.149857780591;3963.841446342114;4025.4364388985564;4044.7775372354213;4073.348264277761;1031.5358088860044;1164.2839653613137;1171.3323405658693;1180.9291442513477;1192.1416580062341;929.1823195587974;1008.7982403519326;1011.1001208924064;1019.7144799456164;1025.7589466475372;6127.938720612737;6174.567780255339;6266;6365.586236894587;6380.808575742735;498.94262159851354;499.4181778228323;500.19468655464675;515.7833709841875;670.5239280111176;4313.892152696145;4373.169097871458;4415.183191109591;4454.465464144323;4471.35165401015;11186.608468703718;11436.714082147846;11640.185187036996;11640.53437862476;11658.41707914601;11487.0810335173;11540.769184616262;11560.075193984461;11562.421878906021;11582.768344633228;8198.38512111594;8365;8577.656893724306;8591.742247732578;8774.95612521946;6476.6114033158165;6594.142761440982;6598.779225843235;6653.73385064592;6689.732410703517;10686.145108391465;10748.140148788234;10779.461026948778;10880.836787448354;10909.236353455182;9597.520123993912;10312.690619281433;10350.792984140124;10659.307145035547;10675.412852293199;7641.694332226649;7899.881501777363;7905.56519391439;7934.523928564305;7950.4832185907735;8598.699045533376;8642.740717778477;8660.480371177497;8669.089745576624;8697;7778.92221077782;7926.920730792618;7980.521168729895;8025.097757561397;8087.150849160754;7723.5752033636445;7747.3802095833325;7789.922100778919;7997.480163789335;8001.3598912088055;7247.275272472711;7464.626768661654;7498.962505187439;7548.8490230196185;7562.508436951581;5211.9478805211465;5310.283111780015;5334.11987022137;5471.945280547303;5516.420775818642;5176.456472070529;5337.546308563816;5357.812476563302;5414.160805075219;5551.8056868011045 22:35:48 INFO - PROCESS | 13929 | |4;prototype.html;326.0950861359726;333.22358903754247;344.9017030146409;354.397524208845;356.264314191195;5368.838934831956;5403.513683768465;5405.378381486121;5413.810516631918;5453.045716999519;5292.735363231843;5309.442508536606;5330.706811125387;5351.57187425005;5370.462953704638;4041.7777022263763;4080.0819815541563;4201.739478156556;4206.72656277341;4215.220184265921;4150.149219410029;4168.3326669332355;4183.581641835807;4194.119234960662;4196.510238865205;4016.598340165989;4128.50457945047;4183.518895327031;4190;4192.203481338536;4062.4109504121884;4169.332906734908;4173.2488152132455;4178.185253875496;4183.49798024239;3696.2977034363385;3707.610700876396;3707.6848467880263;3709.332320182366;3713.275911197318;5149.819756308549;5165.889333793215;5175.275461435402;5182.248573956793;5186.377634683862;4506.594406503397;4706.952930470686;4760.404949381327;4764.142454358214;4766.952330476685;5571.220029195916;5574.191742197399;5576.86057848553;5623.690697011662;5627.662340259557;4054.7161698681102;4062.2484840304637;4065.532463766675;4092.6930480214105;4105;5652.208690783294;5799.536037117021;5833.212516310328;5855.736491857834;5858.707064646751;4014.739041962263;4059.7767122807936;4059.9797001014804;4062.3297155968935;4068.4304197412384;4089.672826173899;4098.241825262336;4108.979455102705;4109.239790638741;4125.8968525787095;4516.3902873112365;4652.790624421909;4717.22165842639;4734;4734.7632618369635;5588.86027849307;5596.516923015404;5608.523275521545;5629.099344104923;5655.519280861173;1413.3781136299995;1421.971560568783;1423.868024920191;1425.771876499755;1430.306301443799;921.3274309753783;927.4018258223543;933.3699752667059;945.3760518058049;967.9612815487302;5270.657407268515;5279.630425870152;5285.709285989231;5286;5302.965921645242;5629.943700562942;5661.631993920382;5673.290838645169;5712.485876271156;5713.485786279171;5172.146595811724;5367.275417818622;5371.17380090771;5406.729663516887;5429.375621803464;2983.9552406713915;3037.9088627341216;3051.4965030769677;3053.9541906871414;3060.7551395888277;5711.228984087178;5847.210626565359;5858.297004359504;5859.857327821034;5864.413558644187;5692.9146062809095;5744.482996530249;5758.654480731169;5780.826575202751;5788.0449725795615;5869.735861886225;5889.322727886348;5915.467607915222;5920.940790592125;5930.406959304035;5440.918386224131;5657.773689052392;5676.205331253545;5689.065717656017;5695.259616249861;5578.888422231452;5591.273134492652;5617.910149417886;5618.831435056954;5636.323641163087;5562.944370556243;5899.9970005100095;5907.881842363215;5917.615355002084;5926.67403292823;4130.359794231899;4160.45914031174;4166;4172.102997855475;4180.4147419361925;5822.330432000204;5832.62087964281;5846.181534585076;5851.444112809277;5887.558433117448;5374;5642.576806739429;5730.312362516358;5775.451332123442;5791.391903850035;5649.887002260014;5663.1788390682295;5673.687947162781;5682.266908592919;5703.287089113861;5261.158214685632;5425.83722488326;5575.721213939368;5585.609007369445;5585.916211256916;5670.688112153871;5678.886422271449;5684.971575142098;5690.914636280542;5698.230738850202;5525.281713377235;5536.833894983157;5552.3892371839875;5591.189277554813;5603.663780173039 22:35:48 INFO - PROCESS | 13929 | |5;yui.html;768.5311959704579;832.9605884399386;848.65205265841;863.4387648028777;890.6971629645922;14835.40658373665;15164.772528412082;15228.39086436544;15242.54272371828;15291.464798732033;13641.795373069412;14161.362738676758;14162.433502659907;14584.562463126123;14592.781108283385;699.4649093443494;833.5332213960171;851.3061854588522;859.5616235719765;861.0829466618063;850.3154960256991;854.3464249848871;854.8290341931607;854.9658013679476;855.9871601925976;888.7244954064229;892.2683399612321;893.0355216366341;893.4594570284982;893.821235752852;732.9780106596811;868.8088620503543;881.9955900220458;884.796496805735;885.9103302937391;644.230144976753;644.2365796531126;645.9838504037436;646.0632083478974;646.2083947164732;14687.118772873662;14704.191269480174;14710.779338309827;14711.117332960057;14711.190884501348;13150;13189.604311870658;13253.204807711472;13850;13864.168149911038;12623.305718185497;12650.177738447064;12798.296093714842;12944.611661650166;12953.611391658265;4519.84180553679;4597.264437689953;4652.441706995149;4669.533046695337;4714.83498077569;60876;61447.385526144615;61767.6911615439;62381.68809155925;62841.99999999954;11252;11298.096152307795;11304.32174069542;11359.659210223706;11384.544618215345;19829.30597429083;19853.60292794165;19868.801311986696;19904.50238744014;19936.50158746043;8251.917480825236;8378;8399.244068033906;8558.914410855812;8835.160659737314;4980.701157930535;5009.799608015712;5017.84946451607;5036.748162591855;5087.974560127176;3488.2674638326234;3586.0676224181902;3586.372384832696;3589.0847833802213;3589.1565482111682;630.9148264984168;643.6878114114651;646.6508085633799;664.1697877652933;675.8242856857253;679.6397909108181;699.9370056694922;713.5754226235382;731.7841236835146;734.2730696610317;9518.286128540387;9600.615975360908;9780.657676981271;9926.2059035277;9937.10566049059;599.1910920257617;696.467202590019;729.7409419655945;742.2354974376401;766.1189631923332;4380.4305440292555;4397.6481881450045;4433.800478978389;4460.493200831599;4652.302154676826;60720.267587958646;60900.390996090355;71661.64169179225;71722.64138679273;72203.27796722071;31223.687763122532;37286.44070338947;63086.36913630805;63359.683201584616;65120.67439662771;57581.13628295579;57623.42376576181;58243.4175658238;58644.413555863895;59128;8458.492490450593;8486.61810218554;8885;8911;9342.205912497242;38607.22785544329;38608;38768.80615597017;38775;38802.6119738799;48723.269150963475;51717.22424163716;51816;52043.73978130236;52561;11040.505165557775;11203.71990700239;11427.714307142389;11475.368854712737;11588.710282243012;12085.516579336729;12234.75530489403;12378.071644626509;12596.055295852664;12734.108612397044;11434.371109589052;13232.536861209812;13319.467221311039;13833.515826946008;14161.929190353982;13127.439940871009;13308.46766129344;13334.466621335037;13357.265350405822;13366.06437549401;13516.39176237052;13593.932030339785;13889.357646988561;13902.23537705436;14620.342084606431;11744;11779.941100294443;12105.636830895088;12213.267203967791;12215.328156951453;11854.619565217143;11905.52377904874;12092.45583948742;12184.695382615506;12283.508659653871 22:35:48 INFO - PROCESS | 13929 | __end_tp_report 22:35:48 INFO - PROCESS | 13929 | __start_cc_report 22:35:48 INFO - PROCESS | 13929 | _x_x_mozilla_cycle_collect,845 22:35:48 INFO - PROCESS | 13929 | __end_cc_report 22:35:48 INFO - PROCESS | 13929 | __startTimestamp1463549748785__endTimestamp 22:35:48 INFO - PROCESS | 13929 | 22:35:48 INFO - PROCESS | 13929 | ------- Summary: start ------- 22:35:48 INFO - PROCESS | 13929 | Number of tests: 6 22:35:48 INFO - PROCESS | 13929 | 22:35:48 INFO - PROCESS | 13929 | [#0] dojo.html Cycles:165 Average:5468.46 Median:5640.97 stddev:626.55 (11.1%) stddev-sans-first:601.69 22:35:48 INFO - PROCESS | 13929 | Values: 3158.1 3160.6 3184.0 3194.6 3224.4 5301.8 5318.2 5422.3 5566.7 5581.0 5462.6 5498.4 5503.9 5510.4 5531.0 4942.8 5022.9 5042.6 5055.2 5191.4 5135.5 5139.9 5164.8 5165.9 5184.9 4895.7 4917.4 4926.0 4932.5 5005.4 4973.7 5003.3 5007.7 5020.5 5064.2 4810.5 4952.5 4974.1 4995.9 5021.1 5357.1 5444.1 5446.5 5470.5 5496.3 5907.5 5956.1 5958.0 5960.9 5990.7 5729.2 5850.5 5881.6 5895.9 5946.3 5589.2 5598.1 5607.7 5608.0 5609.7 6099.7 6107.2 6111.6 6113.3 6115.6 5555.4 5649.4 5705.4 5706.3 5892.5 5807.6 5833.7 5844.6 5895.6 5902.5 5720.9 5780.6 5782.3 5788.5 5795.7 5586.7 5828.2 5883.3 5911.3 5937.7 4480.6 4480.8 4483.3 4485.1 4523.2 3908.7 3963.7 3991.4 4005.5 4057.7 5546.2 5600.4 5613.4 5616.8 5651.3 5985.0 5991.0 5991.5 5993.3 6004.1 5391.3 5472.9 5629.9 5641.0 5644.5 5019.2 5064.6 5065.9 5069.2 5114.7 6116.6 6131.0 6132.1 6135.2 6139.5 5761.8 5849.4 5882.9 5892.2 5892.9 6070.1 6076.4 6094.8 6117.2 6160.4 5786.7 5833.7 5845.0 5845.8 5848.9 5705.5 5846.0 5904.3 5925.6 5936.8 5816.4 5818.8 5821.7 5822.2 5827.6 5736.6 5807.4 5807.8 5807.9 5810.9 5451.0 5546.9 5610.9 5622.4 5678.0 5769.0 5781.9 5811.2 5821.8 5822.7 5516.9 5629.2 5636.5 5638.6 5641.4 22:35:48 INFO - PROCESS | 13929 | 22:35:48 INFO - PROCESS | 13929 | [#1] ext.html Cycles:180 Average:49484.06 Median:23090.07 stddev:83333.78 (360.9%) stddev-sans-first:83486.72 22:35:48 INFO - PROCESS | 13929 | Values: 598.2 633.5 645.8 646.4 660.4 30597.0 43735.3 44118.3 44351.3 44419.8 47084.8 47515.3 47549.3 47550.5 47683.8 8851.7 9076.0 9082.9 9085.3 9088.8 8853.2 8993.5 8998.8 8999.0 9000.6 8996.1 9048.0 9064.9 9068.7 9081.4 9024.0 9058.0 9060.8 9064.2 9074.4 8931.1 9017.0 9031.4 9032.6 9037.1 42837.6 43266.6 43293.6 43316.0 43352.6 3524.4 3618.8 3675.5 3697.2 3771.3 31565.2 32227.3 32461.0 32552.2 32619.5 4228.8 4231.3 4269.9 4314.1 4369.3 337349.0 338089.0 339239.0 339956.0 343844.0 61394.8 61651.7 61681.4 61926.7 62000.0 57862.4 63732.8 64537.0 64988.4 70360.3 17620.0 17646.7 17804.6 17818.5 17828.5 11916.6 13556.3 13626.5 13632.7 13677.9 17889.7 18056.3 18141.5 18791.6 18792.8 6803.7 6853.9 6973.3 7102.6 7159.4 977.9 1077.8 1119.2 1128.9 1209.4 33255.2 34027.5 34100.3 34191.0 34595.5 1159.7 1174.4 1188.4 1251.5 1254.6 17128.5 17536.7 17670.7 17830.7 17854.3 156086.0 156606.0 156951.2 157367.2 157616.0 60638.7 60932.7 61128.0 61130.0 61289.0 1280.2 1385.8 1419.7 1432.5 1451.5 33255.7 34844.8 35064.5 35187.8 35238.1 51080.7 51295.7 51567.5 51665.2 51698.7 397970.0 399852.0 400012.0 400403.0 401132.0 37268.4 41951.4 42389.6 42441.8 42869.4 49014.5 49782.5 50178.5 50766.2 50795.5 41670.4 42359.2 42411.8 42634.8 43701.1 40657.4 41128.4 41343.4 41791.8 42519.8 38564.0 38620.6 38716.8 40644.8 43909.6 35686.8 37138.0 37379.4 37670.8 37844.8 28199.8 28374.7 28665.1 29279.9 31139.2 22:35:48 INFO - PROCESS | 13929 | 22:35:48 INFO - PROCESS | 13929 | [#2] jquery.html Cycles:180 Average:29560.53 Median:11421.13 stddev:82657.54 (723.7%) stddev-sans-first:82860.38 22:35:48 INFO - PROCESS | 13929 | Values: 382.8 389.8 389.9 396.1 405.8 19356.3 19481.5 19984.2 20002.1 20715.7 17910.9 18784.3 18791.3 19496.7 19775.6 5260.8 5405.3 5442.4 5672.9 5708.8 5759.3 5785.7 5830.4 5834.3 6551.0 6515.9 6530.8 6551.9 6556.7 6558.0 6256.4 6502.1 6532.4 6533.0 6540.8 5622.5 5791.0 5828.4 5829.0 5861.4 14372.8 14747.3 14749.4 14759.3 15200.4 2156.8 2352.9 2368.4 2455.4 2562.1 10581.0 10719.8 10746.6 13011.3 14328.4 1960.0 1967.7 2025.4 2047.7 2177.1 83146.0 84051.0 84878.6 86989.6 88130.0 10832.4 11102.7 11167.8 11342.4 11474.8 14843.9 14867.7 14882.0 14905.7 15249.0 8139.3 8468.9 8493.2 8544.7 8568.7 8063.7 8135.3 8180.6 8227.1 8260.2 4916.3 4960.6 4974.7 5032.4 5053.5 2008.7 2046.2 2048.9 2060.6 2126.6 921.1 1003.8 1016.6 1050.2 1108.9 18851.1 19251.7 19470.2 19669.8 19712.2 1028.7 1049.6 1065.4 1086.5 1101.5 10691.9 11140.2 11164.5 11186.1 11205.2 496486.0 497781.0 499129.0 501011.0 506434.0 96726.5 97034.0 98155.0 99912.5 100352.0 1050.6 1050.8 1073.8 1104.5 1112.9 16087.3 16601.8 16962.0 17243.7 17366.4 41327.0 42144.2 42608.8 43095.0 43460.0 57820.4 58747.8 60242.4 61175.4 61269.4 7333.9 7660.6 7756.5 7956.2 8242.1 9515.1 12238.4 13125.9 13332.8 13801.0 11766.9 12280.9 12414.6 12539.4 12563.2 11525.5 11595.2 11638.1 11895.4 12006.5 12131.8 12383.3 12440.4 12451.9 12658.2 8624.7 8794.8 8822.6 8983.0 9212.0 8860.1 11352.7 12104.6 12272.8 12286.1 22:35:48 INFO - PROCESS | 13929 | 22:35:48 INFO - PROCESS | 13929 | [#3] mootools.html Cycles:180 Average:6015.55 Median:499.18 stddev:3367.32 (674.6%) stddev-sans-first:3358.61 22:35:48 INFO - PROCESS | 13929 | Values: 1363.4 1546.4 1580.1 1759.8 1902.5 8746.5 9174.8 9177.2 9388.9 9402.5 9093.3 9275.5 9288.1 9308.1 9314.3 2480.7 2496.9 2502.7 2503.6 2503.9 2486.3 2505.3 2513.6 2521.4 2525.1 2440.8 2445.5 2466.1 2469.4 2474.5 2367.3 2368.8 2380.5 2426.9 2433.0 2356.9 2373.6 2383.9 2386.5 2393.9 8888.7 9043.1 9101.3 9101.6 9141.6 5564.4 5681.9 5687.1 5697.0 5698.8 3666.5 3720.3 3731.6 3733.7 3745.1 1242.0 1242.8 2089.4 2174.1 2197.9 11871.5 11922.2 11940.0 12363.3 12398.9 4563.4 4683.7 4727.0 4731.6 4733.1 9724.2 9756.6 9774.7 9782.9 9789.9 5339.5 5353.0 5354.2 5355.7 5356.6 4272.9 4779.8 5061.8 5245.6 5336.6 3954.1 3963.8 4025.4 4044.8 4073.3 1031.5 1164.3 1171.3 1180.9 1192.1 929.2 1008.8 1011.1 1019.7 1025.8 6127.9 6174.6 6266.0 6365.6 6380.8 498.9 499.4 500.2 515.8 670.5 4313.9 4373.2 4415.2 4454.5 4471.4 11186.6 11436.7 11640.2 11640.5 11658.4 11487.1 11540.8 11560.1 11562.4 11582.8 8198.4 8365.0 8577.7 8591.7 8775.0 6476.6 6594.1 6598.8 6653.7 6689.7 10686.1 10748.1 10779.5 10880.8 10909.2 9597.5 10312.7 10350.8 10659.3 10675.4 7641.7 7899.9 7905.6 7934.5 7950.5 8598.7 8642.7 8660.5 8669.1 8697.0 7778.9 7926.9 7980.5 8025.1 8087.2 7723.6 7747.4 7789.9 7997.5 8001.4 7247.3 7464.6 7499.0 7548.8 7562.5 5211.9 5310.3 5334.1 5471.9 5516.4 5176.5 5337.5 5357.8 5414.2 5551.8 22:35:48 INFO - PROCESS | 13929 | 22:35:48 INFO - PROCESS | 13929 | [#4] prototype.html Cycles:180 Average:4710.15 Median:5388.76 stddev:1382.54 (25.7%) stddev-sans-first:1346.69 22:35:48 INFO - PROCESS | 13929 | Values: 326.1 333.2 344.9 354.4 356.3 5368.8 5403.5 5405.4 5413.8 5453.0 5292.7 5309.4 5330.7 5351.6 5370.5 4041.8 4080.1 4201.7 4206.7 4215.2 4150.1 4168.3 4183.6 4194.1 4196.5 4016.6 4128.5 4183.5 4190.0 4192.2 4062.4 4169.3 4173.2 4178.2 4183.5 3696.3 3707.6 3707.7 3709.3 3713.3 5149.8 5165.9 5175.3 5182.2 5186.4 4506.6 4707.0 4760.4 4764.1 4767.0 5571.2 5574.2 5576.9 5623.7 5627.7 4054.7 4062.2 4065.5 4092.7 4105.0 5652.2 5799.5 5833.2 5855.7 5858.7 4014.7 4059.8 4060.0 4062.3 4068.4 4089.7 4098.2 4109.0 4109.2 4125.9 4516.4 4652.8 4717.2 4734.0 4734.8 5588.9 5596.5 5608.5 5629.1 5655.5 1413.4 1422.0 1423.9 1425.8 1430.3 921.3 927.4 933.4 945.4 968.0 5270.7 5279.6 5285.7 5286.0 5303.0 5629.9 5661.6 5673.3 5712.5 5713.5 5172.1 5367.3 5371.2 5406.7 5429.4 2984.0 3037.9 3051.5 3054.0 3060.8 5711.2 5847.2 5858.3 5859.9 5864.4 5692.9 5744.5 5758.7 5780.8 5788.0 5869.7 5889.3 5915.5 5920.9 5930.4 5440.9 5657.8 5676.2 5689.1 5695.3 5578.9 5591.3 5617.9 5618.8 5636.3 5562.9 5900.0 5907.9 5917.6 5926.7 4130.4 4160.5 4166.0 4172.1 4180.4 5822.3 5832.6 5846.2 5851.4 5887.6 5374.0 5642.6 5730.3 5775.5 5791.4 5649.9 5663.2 5673.7 5682.3 5703.3 5261.2 5425.8 5575.7 5585.6 5585.9 5670.7 5678.9 5685.0 5690.9 5698.2 5525.3 5536.8 5552.4 5591.2 5603.7 22:35:48 INFO - PROCESS | 13929 | 22:35:48 INFO - PROCESS | 13929 | [#5] yui.html Cycles:180 Average:15864.88 Median:4692.18 stddev:18880.16 (402.4%) stddev-sans-first:18899.09 22:35:48 INFO - PROCESS | 13929 | Values: 768.5 833.0 848.7 863.4 890.7 14835.4 15164.8 15228.4 15242.5 15291.5 13641.8 14161.4 14162.4 14584.6 14592.8 699.5 833.5 851.3 859.6 861.1 850.3 854.3 854.8 855.0 856.0 888.7 892.3 893.0 893.5 893.8 733.0 868.8 882.0 884.8 885.9 644.2 644.2 646.0 646.1 646.2 14687.1 14704.2 14710.8 14711.1 14711.2 13150.0 13189.6 13253.2 13850.0 13864.2 12623.3 12650.2 12798.3 12944.6 12953.6 4519.8 4597.3 4652.4 4669.5 4714.8 60876.0 61447.4 61767.7 62381.7 62842.0 11252.0 11298.1 11304.3 11359.7 11384.5 19829.3 19853.6 19868.8 19904.5 19936.5 8251.9 8378.0 8399.2 8558.9 8835.2 4980.7 5009.8 5017.8 5036.7 5088.0 3488.3 3586.1 3586.4 3589.1 3589.2 630.9 643.7 646.7 664.2 675.8 679.6 699.9 713.6 731.8 734.3 9518.3 9600.6 9780.7 9926.2 9937.1 599.2 696.5 729.7 742.2 766.1 4380.4 4397.6 4433.8 4460.5 4652.3 60720.3 60900.4 71661.6 71722.6 72203.3 31223.7 37286.4 63086.4 63359.7 65120.7 57581.1 57623.4 58243.4 58644.4 59128.0 8458.5 8486.6 8885.0 8911.0 9342.2 38607.2 38608.0 38768.8 38775.0 38802.6 48723.3 51717.2 51816.0 52043.7 52561.0 11040.5 11203.7 11427.7 11475.4 11588.7 12085.5 12234.8 12378.1 12596.1 12734.1 11434.4 13232.5 13319.5 13833.5 14161.9 13127.4 13308.5 13334.5 13357.3 13366.1 13516.4 13593.9 13889.4 13902.2 14620.3 11744.0 11779.9 12105.6 12213.3 12215.3 11854.6 11905.5 12092.5 12184.7 12283.5 22:35:48 INFO - PROCESS | 13929 | -------- Summary: end -------- 22:35:48 INFO - PROCESS | 13929 | 22:35:49 INFO - TEST-INFO | 13929: exit 0 22:35:49 INFO - TEST-OK | dromaeo_css | took 1113315ms 22:35:49 INFO - TEST-START | kraken 22:35:49 INFO - operating with platform_type : linux_ 22:35:49 INFO - Initialising browser for kraken test... 22:35:49 INFO - TEST-INFO | started process 14113 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpngbPB3/profile http://localhost:56395/getInfo.html) 22:35:53 INFO - PROCESS | 14113 | __metrics Screen width/height:1600/1200 22:35:53 INFO - PROCESS | 14113 | colorDepth:24 22:35:53 INFO - PROCESS | 14113 | Browser inner width/height: 1024/697 22:35:53 INFO - PROCESS | 14113 | __metrics 22:35:54 ERROR - PROCESS | 14113 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:35:55 INFO - TEST-INFO | 14113: exit 0 22:35:55 INFO - Browser initialized. 22:35:55 INFO - Running cycle 1/1 for kraken test... 22:35:55 INFO - TEST-INFO | started process 14194 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpngbPB3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:36:07 INFO - PROCESS | 14194 | RSS: Main: 152489984 22:36:07 INFO - PROCESS | 14194 | 22:36:09 INFO - PROCESS | 14194 | Cycle 1(1): loaded http://localhost:56395/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56395/tests/kraken/driver.html?testName=audio-beat-detection) 22:36:10 INFO - PROCESS | 14194 | RSS: Main: 209215488 22:36:10 INFO - PROCESS | 14194 | 22:36:13 INFO - PROCESS | 14194 | Cycle 1(1): loaded http://localhost:56395/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56395/tests/kraken/driver.html?testName=audio-dft) 22:36:14 INFO - PROCESS | 14194 | RSS: Main: 197689344 22:36:14 INFO - PROCESS | 14194 | 22:36:17 INFO - PROCESS | 14194 | Cycle 1(1): loaded http://localhost:56395/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56395/tests/kraken/driver.html?testName=audio-fft) 22:36:18 INFO - PROCESS | 14194 | RSS: Main: 265674752 22:36:18 INFO - PROCESS | 14194 | 22:36:20 INFO - PROCESS | 14194 | Cycle 1(1): loaded http://localhost:56395/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56395/tests/kraken/driver.html?testName=audio-oscillator) 22:36:20 INFO - PROCESS | 14194 | RSS: Main: 174301184 22:36:20 INFO - PROCESS | 14194 | 22:36:23 INFO - PROCESS | 14194 | Cycle 1(1): loaded http://localhost:56395/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56395/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:36:23 INFO - PROCESS | 14194 | RSS: Main: 178950144 22:36:23 INFO - PROCESS | 14194 | 22:36:26 INFO - PROCESS | 14194 | Cycle 1(1): loaded http://localhost:56395/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56395/tests/kraken/driver.html?testName=imaging-darkroom) 22:36:27 INFO - PROCESS | 14194 | RSS: Main: 214769664 22:36:27 INFO - PROCESS | 14194 | 22:36:30 INFO - PROCESS | 14194 | Cycle 1(1): loaded http://localhost:56395/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56395/tests/kraken/driver.html?testName=imaging-desaturate) 22:36:31 INFO - PROCESS | 14194 | RSS: Main: 212955136 22:36:31 INFO - PROCESS | 14194 | 22:36:34 INFO - PROCESS | 14194 | Cycle 1(1): loaded http://localhost:56395/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56395/tests/kraken/driver.html?testName=json-parse-financial) 22:36:35 INFO - PROCESS | 14194 | RSS: Main: 214081536 22:36:35 INFO - PROCESS | 14194 | 22:36:36 INFO - PROCESS | 14194 | Cycle 1(1): loaded http://localhost:56395/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56395/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:36:37 INFO - PROCESS | 14194 | RSS: Main: 202776576 22:36:37 INFO - PROCESS | 14194 | 22:36:39 INFO - PROCESS | 14194 | Cycle 1(1): loaded http://localhost:56395/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56395/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:36:39 INFO - PROCESS | 14194 | RSS: Main: 206929920 22:36:39 INFO - PROCESS | 14194 | 22:36:41 INFO - PROCESS | 14194 | Cycle 1(1): loaded http://localhost:56395/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56395/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:36:42 INFO - PROCESS | 14194 | RSS: Main: 216956928 22:36:42 INFO - PROCESS | 14194 | 22:36:45 INFO - PROCESS | 14194 | Cycle 1(1): loaded http://localhost:56395/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56395/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:36:45 INFO - PROCESS | 14194 | RSS: Main: 223236096 22:36:45 INFO - PROCESS | 14194 | 22:36:48 INFO - PROCESS | 14194 | Cycle 1(1): loaded http://localhost:56395/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56395/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:36:49 INFO - PROCESS | 14194 | RSS: Main: 219648000 22:36:49 INFO - PROCESS | 14194 | 22:36:50 INFO - PROCESS | 14194 | Cycle 1(1): loaded http://localhost:56395/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56395/tests/kraken/driver.html?testName=ai-astar) 22:36:51 INFO - PROCESS | 14194 | RSS: Main: 265404416 22:36:51 INFO - PROCESS | 14194 | 22:36:51 INFO - PROCESS | 14194 | __start_tp_report 22:36:51 INFO - PROCESS | 14194 | _x_x_mozilla_page_load 22:36:51 INFO - PROCESS | 14194 | _x_x_mozilla_page_load_details 22:36:51 INFO - PROCESS | 14194 | |i|pagename|runs| 22:36:51 INFO - PROCESS | 14194 | |0;ai-astar;110;103;113;110;109;110;109;110;108;109 22:36:51 INFO - PROCESS | 14194 | |1;audio-beat-detection;160;126;153;228;126;151;150;126;193;123 22:36:51 INFO - PROCESS | 14194 | |2;audio-dft;161;160;160;161;159;162;163;161;162;162 22:36:51 INFO - PROCESS | 14194 | |3;audio-fft;73;75;74;146;75;74;76;76;127;77 22:36:51 INFO - PROCESS | 14194 | |4;audio-oscillator;92;92;151;91;91;91;129;91;92;123 22:36:51 INFO - PROCESS | 14194 | |5;imaging-gaussian-blur;117;117;117;116;116;117;118;117;118;116 22:36:51 INFO - PROCESS | 14194 | |6;imaging-darkroom;146;146;146;145;146;145;146;145;145;145 22:36:51 INFO - PROCESS | 14194 | |7;imaging-desaturate;95;94;95;94;95;95;95;96;95;95 22:36:51 INFO - PROCESS | 14194 | |8;json-parse-financial;54;54;56;53;54;54;54;107;51;51 22:36:51 INFO - PROCESS | 14194 | |9;json-stringify-tinderbox;59;60;59;60;60;59;59;60;60;59 22:36:51 INFO - PROCESS | 14194 | |10;stanford-crypto-aes;77;78;79;78;81;78;79;79;78;80 22:36:51 INFO - PROCESS | 14194 | |11;stanford-crypto-ccm;125;127;120;120;124;119;118;119;187;125 22:36:51 INFO - PROCESS | 14194 | |12;stanford-crypto-pbkdf2;159;159;160;157;157;158;163;158;159;164 22:36:51 INFO - PROCESS | 14194 | |13;stanford-crypto-sha256-iterative;64;65;64;65;63;64;61;70;62;61 22:36:51 INFO - PROCESS | 14194 | __end_tp_report 22:36:51 INFO - PROCESS | 14194 | __start_cc_report 22:36:51 INFO - PROCESS | 14194 | _x_x_mozilla_cycle_collect,1877 22:36:51 INFO - PROCESS | 14194 | __end_cc_report 22:36:51 INFO - PROCESS | 14194 | __startTimestamp1463549811143__endTimestamp 22:36:51 INFO - PROCESS | 14194 | 22:36:51 INFO - PROCESS | 14194 | ------- Summary: start ------- 22:36:51 INFO - PROCESS | 14194 | Number of tests: 14 22:36:51 INFO - PROCESS | 14194 | 22:36:51 INFO - PROCESS | 14194 | [#0] ai-astar Cycles:10 Average:109.10 Median:110.00 stddev:2.51 (2.3%) stddev-sans-first:2.65 22:36:51 INFO - PROCESS | 14194 | Values: 110.0 103.0 113.0 110.0 109.0 110.0 109.0 110.0 108.0 109.0 22:36:51 INFO - PROCESS | 14194 | 22:36:51 INFO - PROCESS | 14194 | [#1] audio-beat-detection Cycles:10 Average:153.60 Median:152.00 stddev:33.95 (22.3%) stddev-sans-first:35.93 22:36:51 INFO - PROCESS | 14194 | Values: 160.0 126.0 153.0 228.0 126.0 151.0 150.0 126.0 193.0 123.0 22:36:51 INFO - PROCESS | 14194 | 22:36:51 INFO - PROCESS | 14194 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.50 stddev:1.20 (0.7%) stddev-sans-first:1.27 22:36:51 INFO - PROCESS | 14194 | Values: 161.0 160.0 160.0 161.0 159.0 162.0 163.0 161.0 162.0 162.0 22:36:51 INFO - PROCESS | 14194 | 22:36:51 INFO - PROCESS | 14194 | [#3] audio-fft Cycles:10 Average:87.30 Median:75.00 stddev:26.34 (35.1%) stddev-sans-first:27.42 22:36:51 INFO - PROCESS | 14194 | Values: 73.0 75.0 74.0 146.0 75.0 74.0 76.0 76.0 127.0 77.0 22:36:51 INFO - PROCESS | 14194 | 22:36:51 INFO - PROCESS | 14194 | [#4] audio-oscillator Cycles:10 Average:104.30 Median:91.00 stddev:21.86 (24.0%) stddev-sans-first:22.73 22:36:51 INFO - PROCESS | 14194 | Values: 92.0 92.0 151.0 91.0 91.0 91.0 129.0 91.0 92.0 123.0 22:36:51 INFO - PROCESS | 14194 | 22:36:51 INFO - PROCESS | 14194 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.74 (0.6%) stddev-sans-first:0.78 22:36:51 INFO - PROCESS | 14194 | Values: 117.0 117.0 117.0 116.0 116.0 117.0 118.0 117.0 118.0 116.0 22:36:51 INFO - PROCESS | 14194 | 22:36:51 INFO - PROCESS | 14194 | [#6] imaging-darkroom Cycles:10 Average:145.50 Median:146.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 22:36:51 INFO - PROCESS | 14194 | Values: 146.0 146.0 146.0 145.0 146.0 145.0 146.0 145.0 145.0 145.0 22:36:51 INFO - PROCESS | 14194 | 22:36:51 INFO - PROCESS | 14194 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 22:36:51 INFO - PROCESS | 14194 | Values: 95.0 94.0 95.0 94.0 95.0 95.0 95.0 96.0 95.0 95.0 22:36:51 INFO - PROCESS | 14194 | 22:36:51 INFO - PROCESS | 14194 | [#8] json-parse-financial Cycles:10 Average:58.80 Median:54.00 stddev:17.00 (31.5%) stddev-sans-first:17.94 22:36:51 INFO - PROCESS | 14194 | Values: 54.0 54.0 56.0 53.0 54.0 54.0 54.0 107.0 51.0 51.0 22:36:51 INFO - PROCESS | 14194 | 22:36:51 INFO - PROCESS | 14194 | [#9] json-stringify-tinderbox Cycles:10 Average:59.50 Median:60.00 stddev:0.53 (0.9%) stddev-sans-first:0.53 22:36:51 INFO - PROCESS | 14194 | Values: 59.0 60.0 59.0 60.0 60.0 59.0 59.0 60.0 60.0 59.0 22:36:51 INFO - PROCESS | 14194 | 22:36:51 INFO - PROCESS | 14194 | [#10] stanford-crypto-aes Cycles:10 Average:78.70 Median:79.00 stddev:1.16 (1.5%) stddev-sans-first:1.05 22:36:51 INFO - PROCESS | 14194 | Values: 77.0 78.0 79.0 78.0 81.0 78.0 79.0 79.0 78.0 80.0 22:36:51 INFO - PROCESS | 14194 | 22:36:51 INFO - PROCESS | 14194 | [#11] stanford-crypto-ccm Cycles:10 Average:128.40 Median:124.50 stddev:20.83 (16.7%) stddev-sans-first:22.06 22:36:51 INFO - PROCESS | 14194 | Values: 125.0 127.0 120.0 120.0 124.0 119.0 118.0 119.0 187.0 125.0 22:36:51 INFO - PROCESS | 14194 | 22:36:51 INFO - PROCESS | 14194 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:159.40 Median:159.00 stddev:2.37 (1.5%) stddev-sans-first:2.51 22:36:51 INFO - PROCESS | 14194 | Values: 159.0 159.0 160.0 157.0 157.0 158.0 163.0 158.0 159.0 164.0 22:36:51 INFO - PROCESS | 14194 | 22:36:51 INFO - PROCESS | 14194 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.90 Median:64.00 stddev:2.60 (4.1%) stddev-sans-first:2.76 22:36:51 INFO - PROCESS | 14194 | Values: 64.0 65.0 64.0 65.0 63.0 64.0 61.0 70.0 62.0 61.0 22:36:51 INFO - PROCESS | 14194 | -------- Summary: end -------- 22:36:51 INFO - PROCESS | 14194 | 22:36:52 INFO - TEST-INFO | 14194: exit 0 22:36:52 INFO - TEST-OK | kraken | took 62600ms 22:36:52 INFO - SUITE-END | took 1175s 22:36:52 INFO - Completed test suite (00:19:36) 22:36:52 INFO - javascript benchmark 22:36:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3158.0683698308994, 3160.6207255129384, 3183.952240716389, 3194.584703988481, 3224.4034853552093, 5301.814436494724, 5318.175682769167, 5422.322209723785, 5566.721663916803, 5580.972095139524, 5462.562994960404, 5498.395176530588, 5503.944960550393, 5510.393856675762, 5530.972345138269, 4942.777575009115, 5022.924656130161, 5042.647014708972, 5055.191169412895, 5191.377034755816, 5135.537801597856, 5139.871503212412, 5164.845054648366, 5165.9225111623355, 5184.922226166611, 4895.657303988723, 4917.40991081069, 4926.0, 4932.506749325057, 5005.374328208992, 4973.726445045521, 5003.324551185579, 5007.749612519396, 5020.548150666457, 5064.18972964324, 4810.494898035691, 4952.479989601076, 4974.10466116099, 4995.8501244962345, 5021.096202683516, 5357.062514060063, 5444.128939369682, 5446.455354464521, 5470.452954704538, 5496.258005169291, 5907.4683278505145, 5956.136360227742, 5958.0, 5960.940390596125, 5990.670513121777, 5729.226554415142, 5850.478875492403, 5881.588288819784, 5895.882082358372, 5946.345901950782, 5589.245451863987, 5598.076317407664, 5607.691576963306, 5608.0, 5609.747561359708, 6099.672983688876, 6107.236595425572, 6111.572189946661, 6113.266408030976, 6115.587704881212, 5555.444455554493, 5649.435056494318, 5705.429457054262, 5706.25818643582, 5892.499137573269, 5807.622504537191, 5833.7374818133285, 5844.59087863854, 5895.616784908967, 5902.498287645512, 5720.885582288331, 5780.566457515703, 5782.306123265235, 5788.479036886701, 5795.710214489259, 5586.720663966867, 5828.242328497353, 5883.294004719375, 5911.261092363455, 5937.70311484424, 4480.641548676098, 4480.798364073625, 4483.30508771141, 4485.080558485568, 4523.163214805271, 3908.6677632401565, 3963.7225394222705, 3991.401289806552, 4005.539362973237, 4057.655099316532, 5546.223528705985, 5600.383957764642, 5613.382527921925, 5616.775328986795, 5651.265335506358, 5985.0, 5991.041433370639, 5991.5206783457215, 5993.340732519419, 6004.0693692477735, 5391.29913111293, 5472.890542189134, 5629.915551266734, 5640.971795140998, 5644.5484361251, 5019.222020586815, 5064.5948324134715, 5065.949340506621, 5069.163588008011, 5114.693118412907, 6116.571839971249, 6131.049687298476, 6132.080187971751, 6135.233095863017, 6139.4781443577795, 5761.769529218785, 5849.444302791228, 5882.882342353043, 5892.175095486549, 5892.941070589411, 6070.0591408331775, 6076.361981991828, 6094.8171554853425, 6117.235345581802, 6160.353162917829, 5786.739596718074, 5833.737481813244, 5845.0, 5845.824625261249, 5848.853778655568, 5705.515031222392, 5845.970770146122, 5904.291485021653, 5925.555583331354, 5936.762529498772, 5816.389279125631, 5818.8254352369495, 5821.738021788945, 5822.214001109796, 5827.621204621686, 5736.5984381094595, 5807.419258074159, 5807.76768929255, 5807.8548036299435, 5810.883782324246, 5450.972745136249, 5546.861328466821, 5610.887782244414, 5622.381538030895, 5677.971610141923, 5768.990426675231, 5781.884362312646, 5811.215485909348, 5821.825345239649, 5822.70886455667, 5516.889662206653, 5629.155626656035, 5636.549076073986, 5638.605297629127, 5641.379448260606], "value": 5425.8573720966715, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [598.1595857819765, 633.5185259203005, 645.7836624730714, 646.3762469217207, 660.369347273354, 30597.0, 43735.34396984044, 44118.338224926614, 44351.33472997908, 44419.77790111053, 47084.76457617716, 47515.28727069097, 47549.28676069853, 47550.482704143134, 47683.761581192135, 8851.690190843306, 9076.047015063423, 9082.86375704365, 9085.318601104911, 9088.77278068047, 8853.203211710945, 8993.460392376479, 8998.775030624254, 8999.0, 9000.639974401016, 8996.100389960984, 9047.95476022619, 9064.909350906506, 9068.682596109138, 9081.364304498687, 9023.954880225623, 9058.048904864992, 9060.77348066297, 9064.229540489066, 9074.364794464323, 8931.106889311017, 9017.0, 9031.412958157764, 9032.593533291018, 9037.141471560191, 42837.57162428367, 43266.567334326566, 43293.56706432927, 43316.0, 43352.566474335166, 3524.4184709522897, 3618.8009659468717, 3675.503806986076, 3697.1681371691425, 3771.340015497277, 31565.210869728442, 32227.29427024851, 32461.0, 32552.186195345304, 32619.51070733941, 4228.76741779202, 4231.259529582341, 4269.893252668677, 4314.1371725655, 4369.300911854088, 337349.0, 338089.0, 339239.0, 339956.0, 343844.0, 61394.7814435769, 61651.691741541006, 61681.38318616846, 61926.69036654788, 62000.0, 57862.421375786544, 63732.81335933185, 64537.0, 64988.35011649823, 70360.29639703537, 17620.0, 17646.735298970525, 17804.643907122045, 17818.46544603664, 17828.46514604564, 11916.582919597773, 13556.254406007763, 13626.523071692642, 13632.727345453233, 13677.931610341884, 17889.7316540252, 18056.277748889897, 18141.45575632733, 18791.624167516573, 18792.81207187938, 6803.727850886008, 6853.862922741518, 6973.267806880306, 7102.644867756695, 7159.3914517265575, 977.8826540815147, 1077.849101125843, 1119.2445099557929, 1128.8532490776147, 1209.4315671634317, 33255.20612090927, 34027.48958765621, 34100.31799363999, 34191.0, 34595.48106778401, 1159.686884541169, 1174.3775798826464, 1188.4473719720372, 1251.4994002399112, 1254.6424269083268, 17128.486145415656, 17536.736948945778, 17670.734938975926, 17830.659598765298, 17854.28582856697, 156086.0, 156606.0, 156951.21524392304, 157367.21316393345, 157616.0, 60638.69680651568, 60932.69533652304, 61128.0, 61130.0, 61289.0, 1280.155097635556, 1385.7713477276338, 1419.6734751007325, 1432.5201057645597, 1451.528253317676, 33255.667443325256, 34844.82577587096, 35064.47403289004, 35187.82406087953, 35238.11904702403, 51080.74459627678, 51295.74352128216, 51567.48432515627, 51665.225021623955, 51698.74150629223, 397970.0, 399852.0, 400012.0, 400403.0, 401132.0, 37268.44097338488, 41951.370729439695, 42389.576104238564, 42441.787791060844, 42869.356959646255, 49014.50985490099, 49782.50217497779, 50178.49821501738, 50766.238506421694, 50795.492045079074, 41670.37494437647, 42359.15281694411, 42411.787941060094, 42634.78682606691, 43701.125977479634, 40657.39013914853, 41128.38307425331, 41343.37984930168, 41791.79104104581, 42519.7874010628, 38564.0, 38620.6137938617, 38716.80641596774, 40644.796776017116, 43909.56090439055, 35686.821565892, 37137.972895969724, 37379.43930840985, 37670.8116459416, 37844.810775945945, 28199.809440628098, 28374.716252838032, 28665.14004579866, 29279.85360073186, 31139.221519461287], "value": 20089.747975639035, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.76459977114075, 389.7840775193026, 389.94735710679066, 396.06528592521653, 405.76262886211566, 19356.3225287115, 19481.51296217592, 19984.200631974723, 20002.099905504252, 20715.689264661043, 17910.910445447786, 18784.342548010824, 18791.342303019395, 19496.707549386734, 19775.604487910234, 5260.8158714445, 5405.297311349519, 5442.401335853053, 5672.914906276409, 5708.800191993281, 5759.308882934054, 5785.681787501686, 5830.3586605473365, 5834.329052159013, 6550.967245163767, 6515.869682606345, 6530.8040758777315, 6551.93448065518, 6556.672166391661, 6557.95072788354, 6256.405641464076, 6502.089707440962, 6532.41208291256, 6533.020046992941, 6540.771073012422, 5622.522085622727, 5791.044477661181, 5828.41715828418, 5829.038208695602, 5861.3552509223955, 14372.784408233885, 14747.262636868221, 14749.410023599043, 14759.262036898112, 15200.391984320613, 2156.8490205685607, 2352.9058837646644, 2368.3842201027683, 2455.4229756007326, 2562.1416825363526, 10581.0, 10719.83920241197, 10746.570137194503, 13011.34943252843, 14328.355224014944, 1959.9804001960083, 1967.7048442733562, 2025.4025062606565, 2047.682609195577, 2177.0529819528397, 83146.0, 84051.0, 84878.57560712218, 86989.56505217496, 88130.0, 10832.350058996486, 11102.722431939266, 11167.822274656526, 11342.376169310766, 11474.827877581843, 14843.925780371244, 14867.70264594702, 14882.0, 14905.70188596222, 15249.0, 8139.308158806568, 8468.872966905501, 8493.193146651058, 8544.658213671508, 8568.657253709907, 8063.677452901936, 8135.30849877767, 8180.590970451453, 8227.053888802864, 8260.173982601691, 4916.2625606158645, 4960.553550180501, 4974.701517908937, 5032.421271553818, 5053.545180933734, 2008.728821609064, 2046.2019812273224, 2048.918043278282, 2060.629086764367, 2126.5640543688505, 921.0605182713729, 1003.7892042670975, 1016.5628779624684, 1050.175612144463, 1108.939008354532, 18851.05744712759, 19251.711224331648, 19470.22119115248, 19669.803301966796, 19712.21151153938, 1028.7376718936623, 1049.6483677967813, 1065.3607835298758, 1086.4839201379439, 1101.498818037791, 10691.893081069245, 11140.164487663458, 11164.497597608128, 11186.105111591054, 11205.159613029055, 496486.0, 497781.0, 499129.0, 501011.0, 506434.0, 96726.51636741772, 97034.0296597025, 98155.0, 99912.50043749735, 100352.0, 1050.648032908984, 1050.8476270940823, 1073.8228192348174, 1104.5305745058317, 1112.8887111288807, 16087.276072576527, 16601.750973735645, 16962.0, 17243.655126897644, 17366.39217627377, 41327.0, 42144.15711685811, 42608.78695606502, 43095.0, 43460.0, 57820.421795783186, 58747.825043499746, 60242.39757602368, 61175.38824611697, 61269.38730612815, 7333.889991650236, 7660.616969151409, 7756.457048006585, 7956.164602716632, 8242.093369729444, 9515.096065873731, 12238.449269782703, 13125.934370328087, 13332.800008000082, 13801.0, 11766.88233117658, 12280.938595306967, 12414.62756117318, 12539.37303134858, 12563.24620522735, 11525.481353338952, 11595.18833681668, 11638.12714046433, 11895.405229738306, 12006.459709313276, 12131.757364852478, 12383.318917459626, 12440.37798110109, 12451.937740311241, 12658.240505569695, 8624.655013799378, 8794.812700285636, 8822.647094116164, 8982.955085224532, 9212.0, 8860.069692682175, 11352.716182095513, 12104.636860894187, 12272.754544908874, 12286.139970202], "value": 9369.150908945141, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1363.420546267836, 1546.4123633019453, 1580.1467207707838, 1759.8152194019628, 1902.4863286912534, 8746.475211487315, 9174.770630734234, 9177.219936305413, 9388.90611093889, 9402.529873506332, 9093.318001149924, 9275.53622318883, 9288.11762882525, 9308.069193080688, 9314.347995640308, 2480.7147178074574, 2496.8876400562017, 2502.724700282967, 2503.5869081601604, 2503.8748062596887, 2486.3038349262238, 2505.2734706934966, 2513.5978243481045, 2521.420073383114, 2525.1288305534563, 2440.804735621146, 2445.510897820434, 2466.0998735461517, 2469.4320306329555, 2474.505098980202, 2367.266147494274, 2368.8223383246327, 2380.5000949800565, 2426.8665223412704, 2433.0267892842826, 2356.893939772697, 2373.5727569037567, 2383.8688872112025, 2386.53462574798, 2393.904243830262, 8888.688895888612, 9043.050479699603, 9101.271898248191, 9101.590428430736, 9141.634334626608, 5564.360098588673, 5681.857953551153, 5687.118496633028, 5696.971515142398, 5698.8290351289525, 3666.4866918631415, 3720.3489389356755, 3731.5708693500324, 3733.663970242664, 3745.0637340664834, 1242.0, 1242.8384310039728, 2089.3731880435807, 2174.1194816099505, 2197.8901054947187, 11871.52513899443, 11922.165448418615, 11940.0, 12363.320017399039, 12398.876011239772, 4563.429571303587, 4683.695559788603, 4726.976365118152, 4731.550502702238, 4733.077049975222, 9724.17344525709, 9756.609735610638, 9774.657886973922, 9782.902170978341, 9789.853152202722, 5339.546138578265, 5353.0, 5354.170103633943, 5355.678659280377, 5356.571474282048, 4272.906275924836, 4779.808807647655, 5061.7975280989085, 5245.606579506476, 5336.599755018312, 3954.149857780591, 3963.841446342114, 4025.4364388985564, 4044.7775372354213, 4073.348264277761, 1031.5358088860044, 1164.2839653613137, 1171.3323405658693, 1180.9291442513477, 1192.1416580062341, 929.1823195587974, 1008.7982403519326, 1011.1001208924064, 1019.7144799456164, 1025.7589466475372, 6127.938720612737, 6174.567780255339, 6266.0, 6365.586236894587, 6380.808575742735, 498.94262159851354, 499.4181778228323, 500.19468655464675, 515.7833709841875, 670.5239280111176, 4313.892152696145, 4373.169097871458, 4415.183191109591, 4454.465464144323, 4471.35165401015, 11186.608468703718, 11436.714082147846, 11640.185187036996, 11640.53437862476, 11658.41707914601, 11487.0810335173, 11540.769184616262, 11560.075193984461, 11562.421878906021, 11582.768344633228, 8198.38512111594, 8365.0, 8577.656893724306, 8591.742247732578, 8774.95612521946, 6476.6114033158165, 6594.142761440982, 6598.779225843235, 6653.73385064592, 6689.732410703517, 10686.145108391465, 10748.140148788234, 10779.461026948778, 10880.836787448354, 10909.236353455182, 9597.520123993912, 10312.690619281433, 10350.792984140124, 10659.307145035547, 10675.412852293199, 7641.694332226649, 7899.881501777363, 7905.56519391439, 7934.523928564305, 7950.4832185907735, 8598.699045533376, 8642.740717778477, 8660.480371177497, 8669.089745576624, 8697.0, 7778.92221077782, 7926.920730792618, 7980.521168729895, 8025.097757561397, 8087.150849160754, 7723.5752033636445, 7747.3802095833325, 7789.922100778919, 7997.480163789335, 8001.3598912088055, 7247.275272472711, 7464.626768661654, 7498.962505187439, 7548.8490230196185, 7562.508436951581, 5211.9478805211465, 5310.283111780015, 5334.11987022137, 5471.945280547303, 5516.420775818642, 5176.456472070529, 5337.546308563816, 5357.812476563302, 5414.160805075219, 5551.8056868011045], "value": 4792.629542765636, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [326.0950861359726, 333.22358903754247, 344.9017030146409, 354.397524208845, 356.264314191195, 5368.838934831956, 5403.513683768465, 5405.378381486121, 5413.810516631918, 5453.045716999519, 5292.735363231843, 5309.442508536606, 5330.706811125387, 5351.57187425005, 5370.462953704638, 4041.7777022263763, 4080.0819815541563, 4201.739478156556, 4206.72656277341, 4215.220184265921, 4150.149219410029, 4168.3326669332355, 4183.581641835807, 4194.119234960662, 4196.510238865205, 4016.598340165989, 4128.50457945047, 4183.518895327031, 4190.0, 4192.203481338536, 4062.4109504121884, 4169.332906734908, 4173.2488152132455, 4178.185253875496, 4183.49798024239, 3696.2977034363385, 3707.610700876396, 3707.6848467880263, 3709.332320182366, 3713.275911197318, 5149.819756308549, 5165.889333793215, 5175.275461435402, 5182.248573956793, 5186.377634683862, 4506.594406503397, 4706.952930470686, 4760.404949381327, 4764.142454358214, 4766.952330476685, 5571.220029195916, 5574.191742197399, 5576.86057848553, 5623.690697011662, 5627.662340259557, 4054.7161698681102, 4062.2484840304637, 4065.532463766675, 4092.6930480214105, 4105.0, 5652.208690783294, 5799.536037117021, 5833.212516310328, 5855.736491857834, 5858.707064646751, 4014.739041962263, 4059.7767122807936, 4059.9797001014804, 4062.3297155968935, 4068.4304197412384, 4089.672826173899, 4098.241825262336, 4108.979455102705, 4109.239790638741, 4125.8968525787095, 4516.3902873112365, 4652.790624421909, 4717.22165842639, 4734.0, 4734.7632618369635, 5588.86027849307, 5596.516923015404, 5608.523275521545, 5629.099344104923, 5655.519280861173, 1413.3781136299995, 1421.971560568783, 1423.868024920191, 1425.771876499755, 1430.306301443799, 921.3274309753783, 927.4018258223543, 933.3699752667059, 945.3760518058049, 967.9612815487302, 5270.657407268515, 5279.630425870152, 5285.709285989231, 5286.0, 5302.965921645242, 5629.943700562942, 5661.631993920382, 5673.290838645169, 5712.485876271156, 5713.485786279171, 5172.146595811724, 5367.275417818622, 5371.17380090771, 5406.729663516887, 5429.375621803464, 2983.9552406713915, 3037.9088627341216, 3051.4965030769677, 3053.9541906871414, 3060.7551395888277, 5711.228984087178, 5847.210626565359, 5858.297004359504, 5859.857327821034, 5864.413558644187, 5692.9146062809095, 5744.482996530249, 5758.654480731169, 5780.826575202751, 5788.0449725795615, 5869.735861886225, 5889.322727886348, 5915.467607915222, 5920.940790592125, 5930.406959304035, 5440.918386224131, 5657.773689052392, 5676.205331253545, 5689.065717656017, 5695.259616249861, 5578.888422231452, 5591.273134492652, 5617.910149417886, 5618.831435056954, 5636.323641163087, 5562.944370556243, 5899.9970005100095, 5907.881842363215, 5917.615355002084, 5926.67403292823, 4130.359794231899, 4160.45914031174, 4166.0, 4172.102997855475, 4180.4147419361925, 5822.330432000204, 5832.62087964281, 5846.181534585076, 5851.444112809277, 5887.558433117448, 5374.0, 5642.576806739429, 5730.312362516358, 5775.451332123442, 5791.391903850035, 5649.887002260014, 5663.1788390682295, 5673.687947162781, 5682.266908592919, 5703.287089113861, 5261.158214685632, 5425.83722488326, 5575.721213939368, 5585.609007369445, 5585.916211256916, 5670.688112153871, 5678.886422271449, 5684.971575142098, 5690.914636280542, 5698.230738850202, 5525.281713377235, 5536.833894983157, 5552.3892371839875, 5591.189277554813, 5603.663780173039], "value": 4270.365454600907, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [768.5311959704579, 832.9605884399386, 848.65205265841, 863.4387648028777, 890.6971629645922, 14835.40658373665, 15164.772528412082, 15228.39086436544, 15242.54272371828, 15291.464798732033, 13641.795373069412, 14161.362738676758, 14162.433502659907, 14584.562463126123, 14592.781108283385, 699.4649093443494, 833.5332213960171, 851.3061854588522, 859.5616235719765, 861.0829466618063, 850.3154960256991, 854.3464249848871, 854.8290341931607, 854.9658013679476, 855.9871601925976, 888.7244954064229, 892.2683399612321, 893.0355216366341, 893.4594570284982, 893.821235752852, 732.9780106596811, 868.8088620503543, 881.9955900220458, 884.796496805735, 885.9103302937391, 644.230144976753, 644.2365796531126, 645.9838504037436, 646.0632083478974, 646.2083947164732, 14687.118772873662, 14704.191269480174, 14710.779338309827, 14711.117332960057, 14711.190884501348, 13150.0, 13189.604311870658, 13253.204807711472, 13850.0, 13864.168149911038, 12623.305718185497, 12650.177738447064, 12798.296093714842, 12944.611661650166, 12953.611391658265, 4519.84180553679, 4597.264437689953, 4652.441706995149, 4669.533046695337, 4714.83498077569, 60876.0, 61447.385526144615, 61767.6911615439, 62381.68809155925, 62841.99999999954, 11252.0, 11298.096152307795, 11304.32174069542, 11359.659210223706, 11384.544618215345, 19829.30597429083, 19853.60292794165, 19868.801311986696, 19904.50238744014, 19936.50158746043, 8251.917480825236, 8378.0, 8399.244068033906, 8558.914410855812, 8835.160659737314, 4980.701157930535, 5009.799608015712, 5017.84946451607, 5036.748162591855, 5087.974560127176, 3488.2674638326234, 3586.0676224181902, 3586.372384832696, 3589.0847833802213, 3589.1565482111682, 630.9148264984168, 643.6878114114651, 646.6508085633799, 664.1697877652933, 675.8242856857253, 679.6397909108181, 699.9370056694922, 713.5754226235382, 731.7841236835146, 734.2730696610317, 9518.286128540387, 9600.615975360908, 9780.657676981271, 9926.2059035277, 9937.10566049059, 599.1910920257617, 696.467202590019, 729.7409419655945, 742.2354974376401, 766.1189631923332, 4380.4305440292555, 4397.6481881450045, 4433.800478978389, 4460.493200831599, 4652.302154676826, 60720.267587958646, 60900.390996090355, 71661.64169179225, 71722.64138679273, 72203.27796722071, 31223.687763122532, 37286.44070338947, 63086.36913630805, 63359.683201584616, 65120.67439662771, 57581.13628295579, 57623.42376576181, 58243.4175658238, 58644.413555863895, 59128.0, 8458.492490450593, 8486.61810218554, 8885.0, 8911.0, 9342.205912497242, 38607.22785544329, 38608.0, 38768.80615597017, 38775.0, 38802.6119738799, 48723.269150963475, 51717.22424163716, 51816.0, 52043.73978130236, 52561.0, 11040.505165557775, 11203.71990700239, 11427.714307142389, 11475.368854712737, 11588.710282243012, 12085.516579336729, 12234.75530489403, 12378.071644626509, 12596.055295852664, 12734.108612397044, 11434.371109589052, 13232.536861209812, 13319.467221311039, 13833.515826946008, 14161.929190353982, 13127.439940871009, 13308.46766129344, 13334.466621335037, 13357.265350405822, 13366.06437549401, 13516.39176237052, 13593.932030339785, 13889.357646988561, 13902.23537705436, 14620.342084606431, 11744.0, 11779.941100294443, 12105.636830895088, 12213.267203967791, 12215.328156951453, 11854.619565217143, 11905.52377904874, 12092.45583948742, 12184.695382615506, 12283.508659653871], "value": 7079.328884026157, "unit": "score"}], "name": "dromaeo_css", "value": 7272.8212035796105, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 103.0, 113.0, 110.0, 109.0, 110.0, 109.0, 110.0, 108.0, 109.0], "value": 109.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 126.0, 153.0, 228.0, 126.0, 151.0, 150.0, 126.0, 193.0, 123.0], "value": 153.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 161.0, 159.0, 162.0, 163.0, 161.0, 162.0, 162.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 75.0, 74.0, 146.0, 75.0, 74.0, 76.0, 76.0, 127.0, 77.0], "value": 87.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 151.0, 91.0, 91.0, 91.0, 129.0, 91.0, 92.0, 123.0], "value": 104.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 116.0, 117.0, 118.0, 117.0, 118.0, 116.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 145.0, 146.0, 145.0, 146.0, 145.0, 145.0, 145.0], "value": 145.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 94.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 56.0, 53.0, 54.0, 54.0, 54.0, 107.0, 51.0, 51.0], "value": 58.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 59.0, 60.0, 60.0, 59.0, 59.0, 60.0, 60.0, 59.0], "value": 59.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 78.0, 79.0, 78.0, 81.0, 78.0, 79.0, 79.0, 78.0, 80.0], "value": 78.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 127.0, 120.0, 120.0, 124.0, 119.0, 118.0, 119.0, 187.0, 125.0], "value": 128.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 159.0, 160.0, 157.0, 157.0, 158.0, 163.0, 158.0, 159.0, 164.0], "value": 159.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 64.0, 65.0, 63.0, 64.0, 61.0, 70.0, 62.0, 61.0], "value": 63.9, "unit": "score"}], "name": "kraken", "value": 1521.4000000000003, "alertThreshold": 2.0}]} 22:36:52 INFO - Return code: 0 22:36:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 22:36:52 ERROR - # TBPL SUCCESS # 22:36:52 INFO - Running post-action listener: _resource_record_post_action 22:36:52 INFO - ##### 22:36:52 INFO - ##### Finished run-tests step (success) 22:36:52 INFO - ##### 22:36:52 INFO - Running post-run listener: _resource_record_post_run 22:36:52 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 4096; Write bytes: 546168832; Read time: 8; Write time: 559436 22:36:52 INFO - TinderboxPrint: CPU usage
13.1% 22:36:52 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 22:36:52 INFO - TinderboxPrint: I/O write bytes / time
546,168,832 / 559,436 22:36:52 INFO - TinderboxPrint: CPU idle
8,281.1 (86.9%) 22:36:52 INFO - TinderboxPrint: CPU user
1,202.1 (12.6%) 22:36:52 INFO - TinderboxPrint: Swap in / out
0 / 0 22:36:52 INFO - install - Wall time: 17s; CPU: 15.0%; Read bytes: 0; Write bytes: 250241024; Read time: 0; Write time: 324392 22:36:52 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 4096; Write bytes: 289341440; Read time: 8; Write time: 234228 22:36:53 INFO - Running post-run listener: _upload_blobber_files 22:36:53 INFO - Blob upload gear active. 22:36:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:36:53 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:36:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:36:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:36:53 INFO - (blobuploader) - INFO - Open directory for files ... 22:36:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:36:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:36:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:36:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:36:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:36:57 INFO - (blobuploader) - INFO - Done attempting. 22:36:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:36:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:36:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:36:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:36:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:36:57 INFO - (blobuploader) - INFO - Done attempting. 22:36:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:36:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:36:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:36:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:36:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:36:58 INFO - (blobuploader) - INFO - Done attempting. 22:36:58 INFO - (blobuploader) - INFO - Iteration through files over. 22:36:58 INFO - Return code: 0 22:36:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:36:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:36:58 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/edb83e99837dfabc8b21f8f2e59e5702d55d1b19c0b19581b8eeabcecb451e816d524a4dc3e3cb042113e4d56cecbaf04e53abadc0819242af3f1b44fb409a32", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/605d8a19cf1788eb5e6a8398d6b34fe715c1370a2d094d14ba4fa8f72c8594d38d524bb2c3f8b77d6151fd6d5e1b829bd2e9bb3cd8523bc65dc01391536e412b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/12d9bc14b83305064254a9b54aaa1ba813615248e6872930ce9f9df73bbf333f986b7961d2e86c9b0bde9993ee2a7d28369271e5d8945f1f9d671661bd9e533c"} 22:36:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:36:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:36:58 INFO - Contents: 22:36:58 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/edb83e99837dfabc8b21f8f2e59e5702d55d1b19c0b19581b8eeabcecb451e816d524a4dc3e3cb042113e4d56cecbaf04e53abadc0819242af3f1b44fb409a32", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/605d8a19cf1788eb5e6a8398d6b34fe715c1370a2d094d14ba4fa8f72c8594d38d524bb2c3f8b77d6151fd6d5e1b829bd2e9bb3cd8523bc65dc01391536e412b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/12d9bc14b83305064254a9b54aaa1ba813615248e6872930ce9f9df73bbf333f986b7961d2e86c9b0bde9993ee2a7d28369271e5d8945f1f9d671661bd9e533c"} 22:36:58 INFO - Running post-run listener: copy_logs_to_upload_dir 22:36:58 INFO - Copying logs to upload dir... 22:36:58 INFO - mkdir: /builds/slave/test/build/upload/logs 22:36:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.099296 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-05-17 22:36:58.549422) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-17 22:36:58.553089) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1463547347.450848-516888591 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/edb83e99837dfabc8b21f8f2e59e5702d55d1b19c0b19581b8eeabcecb451e816d524a4dc3e3cb042113e4d56cecbaf04e53abadc0819242af3f1b44fb409a32", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/605d8a19cf1788eb5e6a8398d6b34fe715c1370a2d094d14ba4fa8f72c8594d38d524bb2c3f8b77d6151fd6d5e1b829bd2e9bb3cd8523bc65dc01391536e412b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/12d9bc14b83305064254a9b54aaa1ba813615248e6872930ce9f9df73bbf333f986b7961d2e86c9b0bde9993ee2a7d28369271e5d8945f1f9d671661bd9e533c"} build_url:https://queue.taskcluster.net/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011400 build_url: 'https://queue.taskcluster.net/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/edb83e99837dfabc8b21f8f2e59e5702d55d1b19c0b19581b8eeabcecb451e816d524a4dc3e3cb042113e4d56cecbaf04e53abadc0819242af3f1b44fb409a32", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/605d8a19cf1788eb5e6a8398d6b34fe715c1370a2d094d14ba4fa8f72c8594d38d524bb2c3f8b77d6151fd6d5e1b829bd2e9bb3cd8523bc65dc01391536e412b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/12d9bc14b83305064254a9b54aaa1ba813615248e6872930ce9f9df73bbf333f986b7961d2e86c9b0bde9993ee2a7d28369271e5d8945f1f9d671661bd9e533c"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-17 22:36:58.578543) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 22:36:58.578919) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1463547347.450848-516888591 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004463 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 22:36:58.664044) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-17 22:36:58.664416) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-17 22:36:58.664791) ========= ========= Total master_lag: 0.15 =========