builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-026 starttime: 1463231473.94 results: success (0) buildid: 20160514030209 builduid: 13fb1502fd2b4d2d90ef0684026c5550 revision: 2afd8fa9bb5df5577e5566468bb423b76c63cc77 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-14 06:11:13.939802) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-14 06:11:13.940910) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-14 06:11:13.941603) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-14 06:11:13.959347) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 06:11:13.960003) ========= 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=41bf3e9aed84707956354da8000001c5-1463231473.648260-192896557 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-14 06:11:14-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 221M=0s 2016-05-14 06:11:14 (221 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.087366 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 06:11:14.064485) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 06:11:14.065018) ========= 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=41bf3e9aed84707956354da8000001c5-1463231473.648260-192896557 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019847 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 06:11:14.117101) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-14 06:11:14.117541) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 2afd8fa9bb5df5577e5566468bb423b76c63cc77 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 2afd8fa9bb5df5577e5566468bb423b76c63cc77 --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=41bf3e9aed84707956354da8000001c5-1463231473.648260-192896557 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-14 06:11:14,643 truncating revision to first 12 chars 2016-05-14 06:11:14,643 Setting DEBUG logging. 2016-05-14 06:11:14,643 attempt 1/10 2016-05-14 06:11:14,643 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/2afd8fa9bb5d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-14 06:11:15,670 unpacking tar archive at: mozilla-central-2afd8fa9bb5d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.331732 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-14 06:11:15.468132) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-14 06:11:15.469060) ========= script_repo_revision: 2afd8fa9bb5df5577e5566468bb423b76c63cc77 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-14 06:11:15.470134) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-14 06:11:15.470848) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-14 06:11:15.481767) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-05-14 06:11:15.482227) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=41bf3e9aed84707956354da8000001c5-1463231473.648260-192896557 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 06:11:16 INFO - MultiFileLogger online at 20160514 06:11:16 in /builds/slave/test 06:11:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 06:11:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:11:16 INFO - {'append_to_log': False, 06:11:16 INFO - 'base_work_dir': '/builds/slave/test', 06:11:16 INFO - 'blob_upload_branch': 'Firefox', 06:11:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:11:16 INFO - 'branch': 'Firefox', 06:11:16 INFO - 'buildbot_json_path': 'buildprops.json', 06:11:16 INFO - 'config_files': ('talos/linux_config.py',), 06:11:16 INFO - 'default_actions': ('clobber', 06:11:16 INFO - 'read-buildbot-config', 06:11:16 INFO - 'download-and-extract', 06:11:16 INFO - 'populate-webroot', 06:11:16 INFO - 'create-virtualenv', 06:11:16 INFO - 'install', 06:11:16 INFO - 'run-tests'), 06:11:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:11:16 INFO - 'download_minidump_stackwalk': True, 06:11:16 INFO - 'download_symbols': 'ondemand', 06:11:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:11:16 INFO - 'tooltool.py': '/tools/tooltool.py', 06:11:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:11:16 INFO - '/tools/misc-python/virtualenv.py')}, 06:11:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:11:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:11:16 INFO - 'installer_path': 'installer.exe', 06:11:16 INFO - 'log_level': 'info', 06:11:16 INFO - 'log_name': 'talos', 06:11:16 INFO - 'log_to_console': True, 06:11:16 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 06:11:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 06:11:16 INFO - 'opt_config_files': (), 06:11:16 INFO - 'pip_index': False, 06:11:16 INFO - 'sps_profile': False, 06:11:16 INFO - 'sps_profile_interval': 0, 06:11:16 INFO - 'suite': 'dromaeojs', 06:11:16 INFO - 'system_bits': '32', 06:11:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:11:16 INFO - 'title': 'talos-linux64-ix-026', 06:11:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:11:16 INFO - 'use_talos_json': True, 06:11:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:11:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:11:16 INFO - 'work_dir': 'build'} 06:11:16 INFO - ##### 06:11:16 INFO - ##### Running clobber step. 06:11:16 INFO - ##### 06:11:16 INFO - Running pre-action listener: _resource_record_pre_action 06:11:16 INFO - Running main action method: clobber 06:11:16 INFO - rmtree: /builds/slave/test/build 06:11:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:11:16 INFO - Running post-action listener: _resource_record_post_action 06:11:16 INFO - ##### 06:11:16 INFO - ##### Finished clobber step (success) 06:11:16 INFO - ##### 06:11:16 INFO - ##### 06:11:16 INFO - ##### Running read-buildbot-config step. 06:11:16 INFO - ##### 06:11:16 INFO - Running pre-action listener: _resource_record_pre_action 06:11:16 INFO - Running main action method: read_buildbot_config 06:11:16 INFO - Using buildbot properties: 06:11:16 INFO - { 06:11:16 INFO - "project": "", 06:11:16 INFO - "product": "firefox", 06:11:16 INFO - "script_repo_revision": "production", 06:11:16 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 06:11:16 INFO - "repository": "", 06:11:16 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 06:11:16 INFO - "buildid": "20160514030209", 06:11:16 INFO - "stage_platform": "linux64-pgo", 06:11:16 INFO - "basedir": "/builds/slave/test", 06:11:16 INFO - "nightly_build": "True", 06:11:16 INFO - "buildnumber": 441, 06:11:16 INFO - "slavename": "talos-linux64-ix-026", 06:11:16 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 06:11:16 INFO - "platform": "ubuntu64_hw", 06:11:16 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 06:11:16 INFO - "branch": "mozilla-central", 06:11:16 INFO - "repo_path": "mozilla-central", 06:11:16 INFO - "revision": "2afd8fa9bb5df5577e5566468bb423b76c63cc77", 06:11:16 INFO - "pgo_build": "True", 06:11:16 INFO - "builduid": "13fb1502fd2b4d2d90ef0684026c5550", 06:11:16 INFO - "slavebuilddir": "test" 06:11:16 INFO - } 06:11:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/f2B5kWO3Q9m937NqcJCxvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 06:11:16 INFO - Running post-action listener: _resource_record_post_action 06:11:16 INFO - ##### 06:11:16 INFO - ##### Finished read-buildbot-config step (success) 06:11:16 INFO - ##### 06:11:16 INFO - ##### 06:11:16 INFO - ##### Running download-and-extract step. 06:11:16 INFO - ##### 06:11:16 INFO - Running pre-action listener: _resource_record_pre_action 06:11:16 INFO - Running main action method: download_and_extract 06:11:16 INFO - mkdir: /builds/slave/test/build/tests 06:11:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:11:16 INFO - https://queue.taskcluster.net/v1/task/f2B5kWO3Q9m937NqcJCxvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 06:11:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f2B5kWO3Q9m937NqcJCxvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 06:11:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f2B5kWO3Q9m937NqcJCxvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 06:11:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f2B5kWO3Q9m937NqcJCxvw/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 06:11:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f2B5kWO3Q9m937NqcJCxvw/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 06:11:17 INFO - Downloaded 1361 bytes. 06:11:17 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 06:11:17 INFO - Using the following test package requirements: 06:11:17 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 06:11:17 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 06:11:17 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 06:11:17 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 06:11:17 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 06:11:17 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 06:11:17 INFO - u'jsshell-linux-x86_64.zip'], 06:11:17 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 06:11:17 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 06:11:17 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 06:11:17 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 06:11:17 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 06:11:17 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 06:11:17 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 06:11:17 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 06:11:17 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 06:11:17 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 06:11:17 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 06:11:17 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 06:11:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:11:17 INFO - https://queue.taskcluster.net/v1/task/f2B5kWO3Q9m937NqcJCxvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 06:11:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f2B5kWO3Q9m937NqcJCxvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 06:11:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f2B5kWO3Q9m937NqcJCxvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 06:11:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f2B5kWO3Q9m937NqcJCxvw/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 06:11:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f2B5kWO3Q9m937NqcJCxvw/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 06:11:18 INFO - Downloaded 24022088 bytes. 06:11:18 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'] 06:11:18 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 06:11:19 INFO - Return code: 0 06:11:19 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 06:11:19 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')]} 06:11:19 INFO - https://queue.taskcluster.net/v1/task/f2B5kWO3Q9m937NqcJCxvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 06:11:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f2B5kWO3Q9m937NqcJCxvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 06:11:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f2B5kWO3Q9m937NqcJCxvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 06:11:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f2B5kWO3Q9m937NqcJCxvw/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 06:11:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f2B5kWO3Q9m937NqcJCxvw/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 06:11:21 INFO - Downloaded 24022088 bytes. 06:11:21 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'] 06:11:21 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 06:11:22 INFO - Return code: 0 06:11:22 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')]} 06:11:22 INFO - https://queue.taskcluster.net/v1/task/f2B5kWO3Q9m937NqcJCxvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 06:11:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f2B5kWO3Q9m937NqcJCxvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 06:11:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f2B5kWO3Q9m937NqcJCxvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 06:11:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f2B5kWO3Q9m937NqcJCxvw/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 06:11:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f2B5kWO3Q9m937NqcJCxvw/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 06:11:23 INFO - Downloaded 10840194 bytes. 06:11:23 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'] 06:11:23 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 06:11:23 INFO - Return code: 0 06:11: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')]} 06:11:23 INFO - https://queue.taskcluster.net/v1/task/f2B5kWO3Q9m937NqcJCxvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 06:11:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f2B5kWO3Q9m937NqcJCxvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 06:11:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f2B5kWO3Q9m937NqcJCxvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 06:11:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f2B5kWO3Q9m937NqcJCxvw/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 06:11:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f2B5kWO3Q9m937NqcJCxvw/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 06:11:25 INFO - Downloaded 58406045 bytes. 06:11:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/f2B5kWO3Q9m937NqcJCxvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 06:11:25 INFO - mkdir: /builds/slave/test/properties 06:11:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:11:25 INFO - Writing to file /builds/slave/test/properties/build_url 06:11:25 INFO - Contents: 06:11:25 INFO - build_url:https://queue.taskcluster.net/v1/task/f2B5kWO3Q9m937NqcJCxvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 06:11:26 INFO - Running post-action listener: _resource_record_post_action 06:11:26 INFO - Running post-action listener: set_extra_try_arguments 06:11:26 INFO - ##### 06:11:26 INFO - ##### Finished download-and-extract step (success) 06:11:26 INFO - ##### 06:11:26 INFO - ##### 06:11:26 INFO - ##### Running populate-webroot step. 06:11:26 INFO - ##### 06:11:26 INFO - Running pre-action listener: _resource_record_pre_action 06:11:26 INFO - Running main action method: populate_webroot 06:11:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:11:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:11:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:11:26 INFO - '--tppagecycles', 06:11:26 INFO - '7'], 06:11:26 INFO - 'tests': ['tsvgm']}}, 06:11:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:11:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:11:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:11:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:11:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:11:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:11:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:11:26 INFO - 'talos_options': ['--disable-e10s'], 06:11:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:11:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:11:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:11:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:11:26 INFO - 'talos_options': ['--disable-e10s'], 06:11:26 INFO - 'tests': ['damp', 'tps']}, 06:11:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:11:26 INFO - 'tests': ['damp', 'tps']}, 06:11:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:11:26 INFO - 'tests': ['dromaeo_dom']}, 06:11:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:11:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:11:26 INFO - 'tests': ['a11yr', 06:11:26 INFO - 'ts_paint', 06:11:26 INFO - 'tpaint', 06:11:26 INFO - 'sessionrestore', 06:11:26 INFO - 'sessionrestore_no_auto_restore', 06:11:26 INFO - 'tabpaint']}, 06:11:26 INFO - 'other-e10s': {'tests': ['a11yr', 06:11:26 INFO - 'ts_paint', 06:11:26 INFO - 'tpaint', 06:11:26 INFO - 'sessionrestore', 06:11:26 INFO - 'sessionrestore_no_auto_restore', 06:11:26 INFO - 'tabpaint']}, 06:11:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:11:26 INFO - 'ts_paint', 06:11:26 INFO - 'tpaint', 06:11:26 INFO - 'sessionrestore', 06:11:26 INFO - 'sessionrestore_no_auto_restore', 06:11:26 INFO - 'tabpaint']}, 06:11:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:11:26 INFO - 'ts_paint', 06:11:26 INFO - 'tpaint', 06:11:26 INFO - 'sessionrestore', 06:11:26 INFO - 'sessionrestore_no_auto_restore', 06:11:26 INFO - 'tabpaint']}, 06:11:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:11:26 INFO - 'tests': ['a11yr', 06:11:26 INFO - 'ts_paint', 06:11:26 INFO - 'tpaint', 06:11:26 INFO - 'sessionrestore', 06:11:26 INFO - 'sessionrestore_no_auto_restore', 06:11:26 INFO - 'tabpaint']}, 06:11:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:11:26 INFO - 'tests': ['a11yr', 06:11:26 INFO - 'ts_paint', 06:11:26 INFO - 'tpaint', 06:11:26 INFO - 'sessionrestore', 06:11:26 INFO - 'sessionrestore_no_auto_restore', 06:11:26 INFO - 'tabpaint']}, 06:11:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:11:26 INFO - 'tests': ['tsvgx', 06:11:26 INFO - 'tsvgr_opacity', 06:11:26 INFO - 'tart', 06:11:26 INFO - 'tscrollx', 06:11:26 INFO - 'cart']}, 06:11:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:11:26 INFO - 'tsvgr_opacity', 06:11:26 INFO - 'tart', 06:11:26 INFO - 'tscrollx', 06:11:26 INFO - 'cart']}, 06:11:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:11:26 INFO - 'talos_options': ['--disable-e10s'], 06:11:26 INFO - 'tests': ['tp5o']}, 06:11:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:11:26 INFO - 'tests': ['tp5o']}, 06:11:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:11:26 INFO - 'talos_options': ['--disable-e10s', 06:11:26 INFO - '--xperf_path', 06:11:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:11:26 INFO - 'tests': ['tp5n']}, 06:11:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:11:26 INFO - 'talos_options': ['--xperf_path', 06:11:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:11:26 INFO - 'tests': ['tp5n']}}, 06:11:26 INFO - 'talos.zip': {'path': '', 06:11:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:11:26 INFO - Running post-action listener: _resource_record_post_action 06:11:26 INFO - ##### 06:11:26 INFO - ##### Finished populate-webroot step (success) 06:11:26 INFO - ##### 06:11:26 INFO - ##### 06:11:26 INFO - ##### Running create-virtualenv step. 06:11:26 INFO - ##### 06:11:26 INFO - Running pre-action listener: _resource_record_pre_action 06:11:26 INFO - Running main action method: create_virtualenv 06:11:26 INFO - Creating virtualenv /builds/slave/test/build/venv 06:11:26 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 06:11:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:11:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:11:26 INFO - Using real prefix '/usr' 06:11:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:11:26 INFO - Installing distribute.............................................................................................................................................................................................done. 06:11:28 INFO - Installing pip.................done. 06:11:28 INFO - Return code: 0 06:11:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:11:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:11:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:11:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:11:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:11:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6a96007f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a1c770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aab9c0>, '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 0x1aac8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aacd50>, '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': '41bf3e9aed84707956354da8000001c5-1463231473.648260-192896557', '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 06:11:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 06:11:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 06:11:28 INFO - Using env: {'DISPLAY': ':0', 06:11:28 INFO - 'HOME': '/home/cltbld', 06:11:28 INFO - 'LANG': 'en_US.UTF-8', 06:11:28 INFO - 'LANGUAGE': 'en_US:en', 06:11:28 INFO - 'LOGNAME': 'cltbld', 06:11:28 INFO - 'MAIL': '/var/mail/cltbld', 06:11:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:11:28 INFO - 'MOZ_NO_REMOTE': '1', 06:11:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:11:28 INFO - 'NO_EM_RESTART': '1', 06:11:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:11:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:11:28 INFO - 'PWD': '/builds/slave/test', 06:11:28 INFO - 'SHELL': '/bin/bash', 06:11:28 INFO - 'SHLVL': '1', 06:11:28 INFO - 'TERM': 'linux', 06:11:28 INFO - 'TMOUT': '86400', 06:11:28 INFO - 'USER': 'cltbld', 06:11:28 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1463231473.648260-192896557', 06:11:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:11:28 INFO - '_': '/tools/buildbot/bin/python'} 06:11:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:11:28 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 06:11:28 INFO - Cleaning up... 06:11:28 INFO - Return code: 0 06:11:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:11:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:11:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:11:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:11:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:11:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f6a96007f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a1c770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aab9c0>, '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 0x1aac8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aacd50>, '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': '41bf3e9aed84707956354da8000001c5-1463231473.648260-192896557', '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 06:11:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 06:11:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 06:11:28 INFO - Using env: {'DISPLAY': ':0', 06:11:28 INFO - 'HOME': '/home/cltbld', 06:11:28 INFO - 'LANG': 'en_US.UTF-8', 06:11:28 INFO - 'LANGUAGE': 'en_US:en', 06:11:28 INFO - 'LOGNAME': 'cltbld', 06:11:28 INFO - 'MAIL': '/var/mail/cltbld', 06:11:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:11:28 INFO - 'MOZ_NO_REMOTE': '1', 06:11:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:11:28 INFO - 'NO_EM_RESTART': '1', 06:11:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:11:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:11:28 INFO - 'PWD': '/builds/slave/test', 06:11:28 INFO - 'SHELL': '/bin/bash', 06:11:28 INFO - 'SHLVL': '1', 06:11:28 INFO - 'TERM': 'linux', 06:11:28 INFO - 'TMOUT': '86400', 06:11:28 INFO - 'USER': 'cltbld', 06:11:28 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1463231473.648260-192896557', 06:11:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:11:28 INFO - '_': '/tools/buildbot/bin/python'} 06:11:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:11:29 INFO - Downloading/unpacking psutil>=3.1.1 06:11:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:11:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:11:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:11:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:11:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:11:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:11:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:11:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:11:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:11:31 INFO - Installing collected packages: psutil 06:11:31 INFO - Running setup.py install for psutil 06:11:31 INFO - building 'psutil._psutil_linux' extension 06:11:31 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 06:11:31 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 06:11:31 INFO - building 'psutil._psutil_posix' extension 06:11:31 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 06:11:31 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 06:11:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:11:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:11:31 INFO - Successfully installed psutil 06:11:31 INFO - Cleaning up... 06:11:31 INFO - Return code: 0 06:11:31 INFO - Installing mozsystemmonitor==0.1 into virtualenv /builds/slave/test/build/venv 06:11:31 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')]} 06:11:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:11:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:31 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')]} 06:11:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:11:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:31 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.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6a96007f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a1c770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aab9c0>, '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 0x1aac8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aacd50>, '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': '41bf3e9aed84707956354da8000001c5-1463231473.648260-192896557', '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 06:11:31 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.1'] in /builds/slave/test/build 06:11:31 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.1 06:11:31 INFO - Using env: {'DISPLAY': ':0', 06:11:31 INFO - 'HOME': '/home/cltbld', 06:11:31 INFO - 'LANG': 'en_US.UTF-8', 06:11:31 INFO - 'LANGUAGE': 'en_US:en', 06:11:31 INFO - 'LOGNAME': 'cltbld', 06:11:31 INFO - 'MAIL': '/var/mail/cltbld', 06:11:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:11:31 INFO - 'MOZ_NO_REMOTE': '1', 06:11:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:11:31 INFO - 'NO_EM_RESTART': '1', 06:11:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:11:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:11:31 INFO - 'PWD': '/builds/slave/test', 06:11:31 INFO - 'SHELL': '/bin/bash', 06:11:31 INFO - 'SHLVL': '1', 06:11:31 INFO - 'TERM': 'linux', 06:11:31 INFO - 'TMOUT': '86400', 06:11:31 INFO - 'USER': 'cltbld', 06:11:31 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1463231473.648260-192896557', 06:11:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:11:31 INFO - '_': '/tools/buildbot/bin/python'} 06:11:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:11:31 INFO - Downloading/unpacking mozsystemmonitor==0.1 06:11:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:11:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:11:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:11:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:11:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:11:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:11:33 INFO - Downloading mozsystemmonitor-0.1.tar.gz 06:11:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:11:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.1) 06:11:33 INFO - Installing collected packages: mozsystemmonitor 06:11:33 INFO - Running setup.py install for mozsystemmonitor 06:11:33 INFO - Successfully installed mozsystemmonitor 06:11:33 INFO - Cleaning up... 06:11:33 INFO - Return code: 0 06:11:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:11:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:11:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:11:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:11:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:11:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:33 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 0x7f6a96007f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a1c770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aab9c0>, '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 0x1aac8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aacd50>, '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': '41bf3e9aed84707956354da8000001c5-1463231473.648260-192896557', '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 06:11:33 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 06:11:33 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 06:11:33 INFO - Using env: {'DISPLAY': ':0', 06:11:33 INFO - 'HOME': '/home/cltbld', 06:11:33 INFO - 'LANG': 'en_US.UTF-8', 06:11:33 INFO - 'LANGUAGE': 'en_US:en', 06:11:33 INFO - 'LOGNAME': 'cltbld', 06:11:33 INFO - 'MAIL': '/var/mail/cltbld', 06:11:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:11:33 INFO - 'MOZ_NO_REMOTE': '1', 06:11:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:11:33 INFO - 'NO_EM_RESTART': '1', 06:11:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:11:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:11:33 INFO - 'PWD': '/builds/slave/test', 06:11:33 INFO - 'SHELL': '/bin/bash', 06:11:33 INFO - 'SHLVL': '1', 06:11:33 INFO - 'TERM': 'linux', 06:11:33 INFO - 'TMOUT': '86400', 06:11:33 INFO - 'USER': 'cltbld', 06:11:33 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1463231473.648260-192896557', 06:11:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:11:33 INFO - '_': '/tools/buildbot/bin/python'} 06:11:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:11:34 INFO - Downloading/unpacking blobuploader==1.2.4 06:11:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:11:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:11:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:11:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:11:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:11:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:11:36 INFO - Downloading blobuploader-1.2.4.tar.gz 06:11:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:11:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:11: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 06:11: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 06:11: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 06:11: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 06:11: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 06:11: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 06:11:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:11:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:11: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 06:11: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 06:11: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 06:11: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 06:11: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 06:11: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 06:11:36 INFO - Downloading docopt-0.6.1.tar.gz 06:11:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:11:36 INFO - Installing collected packages: blobuploader, requests, docopt 06:11:36 INFO - Running setup.py install for blobuploader 06:11:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:11:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:11:36 INFO - Running setup.py install for requests 06:11:37 INFO - Running setup.py install for docopt 06:11:37 INFO - Successfully installed blobuploader requests docopt 06:11:37 INFO - Cleaning up... 06:11:37 INFO - Return code: 0 06:11:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:11:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:11:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:11:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:11:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:11:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:37 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 0x7f6a96007f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a1c770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aab9c0>, '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 0x1aac8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aacd50>, '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': '41bf3e9aed84707956354da8000001c5-1463231473.648260-192896557', '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 06:11:37 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 06:11:37 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 06:11:37 INFO - Using env: {'DISPLAY': ':0', 06:11:37 INFO - 'HOME': '/home/cltbld', 06:11:37 INFO - 'LANG': 'en_US.UTF-8', 06:11:37 INFO - 'LANGUAGE': 'en_US:en', 06:11:37 INFO - 'LOGNAME': 'cltbld', 06:11:37 INFO - 'MAIL': '/var/mail/cltbld', 06:11:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:11:37 INFO - 'MOZ_NO_REMOTE': '1', 06:11:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:11:37 INFO - 'NO_EM_RESTART': '1', 06:11:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:11:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:11:37 INFO - 'PWD': '/builds/slave/test', 06:11:37 INFO - 'SHELL': '/bin/bash', 06:11:37 INFO - 'SHLVL': '1', 06:11:37 INFO - 'TERM': 'linux', 06:11:37 INFO - 'TMOUT': '86400', 06:11:37 INFO - 'USER': 'cltbld', 06:11:37 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1463231473.648260-192896557', 06:11:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:11:37 INFO - '_': '/tools/buildbot/bin/python'} 06:11:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:11:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:11:37 INFO - Running setup.py (path:/tmp/pip-wRzkZs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:11:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:11:37 INFO - Running setup.py (path:/tmp/pip-tvvq45-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:11:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:11:37 INFO - Running setup.py (path:/tmp/pip-J05WxP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:11:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:11:37 INFO - Running setup.py (path:/tmp/pip-RuNgWC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:11:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:11:37 INFO - Running setup.py (path:/tmp/pip-RoDlf8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:11:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:11:37 INFO - Running setup.py (path:/tmp/pip-rsvQhB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:11:38 INFO - Running setup.py (path:/tmp/pip-_im0Ov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:11:38 INFO - Running setup.py (path:/tmp/pip-3eSA0l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:11:38 INFO - Running setup.py (path:/tmp/pip-rjAHEC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:11:38 INFO - Running setup.py (path:/tmp/pip-xdWAIC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:11:38 INFO - Running setup.py (path:/tmp/pip-QWV2WQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:11:38 INFO - Running setup.py (path:/tmp/pip-drBaSf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:11:38 INFO - Running setup.py (path:/tmp/pip-wTXU6n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:11:38 INFO - Running setup.py (path:/tmp/pip-ABvc8m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:11:38 INFO - Running setup.py (path:/tmp/pip-pCtd2d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:11:38 INFO - Running setup.py (path:/tmp/pip-KyO0mG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:11:38 INFO - Running setup.py (path:/tmp/pip-b_3q6G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:11:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:11:38 INFO - Running setup.py install for manifestparser 06:11:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:11:38 INFO - Running setup.py install for mozcrash 06:11:39 INFO - Running setup.py install for mozdebug 06:11:39 INFO - Running setup.py install for mozdevice 06:11:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:11:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:11:39 INFO - Running setup.py install for mozfile 06:11:39 INFO - Running setup.py install for mozhttpd 06:11:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:11:39 INFO - Running setup.py install for mozinfo 06:11:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:11:39 INFO - Running setup.py install for mozInstall 06:11:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:11:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:11:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:11:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:11:39 INFO - Running setup.py install for mozleak 06:11:39 INFO - Running setup.py install for mozlog 06:11:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:11:39 INFO - Running setup.py install for moznetwork 06:11:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:11:39 INFO - Running setup.py install for mozprocess 06:11:40 INFO - Running setup.py install for mozprofile 06:11:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:11:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:11:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:11:40 INFO - Running setup.py install for mozrunner 06:11:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:11:40 INFO - Running setup.py install for mozscreenshot 06:11:40 INFO - Running setup.py install for moztest 06:11:40 INFO - Running setup.py install for mozversion 06:11:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:11:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:11:40 INFO - Cleaning up... 06:11:40 INFO - Return code: 0 06:11:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:11:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:11:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:11:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:11:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:11:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:40 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 0x7f6a96007f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a1c770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aab9c0>, '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 0x1aac8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aacd50>, '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': '41bf3e9aed84707956354da8000001c5-1463231473.648260-192896557', '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 06:11:40 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 06:11:40 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 06:11:40 INFO - Using env: {'DISPLAY': ':0', 06:11:40 INFO - 'HOME': '/home/cltbld', 06:11:40 INFO - 'LANG': 'en_US.UTF-8', 06:11:40 INFO - 'LANGUAGE': 'en_US:en', 06:11:40 INFO - 'LOGNAME': 'cltbld', 06:11:40 INFO - 'MAIL': '/var/mail/cltbld', 06:11:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:11:40 INFO - 'MOZ_NO_REMOTE': '1', 06:11:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:11:40 INFO - 'NO_EM_RESTART': '1', 06:11:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:11:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:11:40 INFO - 'PWD': '/builds/slave/test', 06:11:40 INFO - 'SHELL': '/bin/bash', 06:11:40 INFO - 'SHLVL': '1', 06:11:40 INFO - 'TERM': 'linux', 06:11:40 INFO - 'TMOUT': '86400', 06:11:40 INFO - 'USER': 'cltbld', 06:11:40 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1463231473.648260-192896557', 06:11:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:11:40 INFO - '_': '/tools/buildbot/bin/python'} 06:11:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:11:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:11:40 INFO - Running setup.py (path:/tmp/pip-OQs2OY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:11:40 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)) 06:11:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:11:40 INFO - Running setup.py (path:/tmp/pip-uvH1oY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:11:41 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)) 06:11:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:11:41 INFO - Running setup.py (path:/tmp/pip-LPS1fn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:11:41 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)) 06:11:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:11:41 INFO - Running setup.py (path:/tmp/pip-c_bPSu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:11:41 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)) 06:11:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:11:41 INFO - Running setup.py (path:/tmp/pip-yXbLuu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:11:41 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)) 06:11:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:11:41 INFO - Running setup.py (path:/tmp/pip-V5SZAQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:11:41 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)) 06:11:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:11:41 INFO - Running setup.py (path:/tmp/pip-1GwaSw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:11:41 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)) 06:11:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:11:41 INFO - Running setup.py (path:/tmp/pip-aQxgzZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:11:41 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)) 06:11:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:11:41 INFO - Running setup.py (path:/tmp/pip-d3xZjM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:11:41 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)) 06:11:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:11:41 INFO - Running setup.py (path:/tmp/pip-mULK1a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:11:41 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)) 06:11:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:11:41 INFO - Running setup.py (path:/tmp/pip-IbQWy3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:11:41 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)) 06:11:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:11:41 INFO - Running setup.py (path:/tmp/pip-PVJX0j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:11:41 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)) 06:11:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:11:41 INFO - Running setup.py (path:/tmp/pip-xrsUSU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:11:41 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)) 06:11:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:11:41 INFO - Running setup.py (path:/tmp/pip-BTCqRz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:11:41 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)) 06:11:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:11:41 INFO - Running setup.py (path:/tmp/pip-02mMHr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:11:41 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)) 06:11:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:11:41 INFO - Running setup.py (path:/tmp/pip-k50tVn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:11:42 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)) 06:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:11:42 INFO - Running setup.py (path:/tmp/pip-xTuVV7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:11:42 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)) 06:11:42 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)) 06:11:42 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)) 06:11:42 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)) 06:11:42 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)) 06:11:42 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)) 06:11:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:11:42 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 06:11:42 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 06:11:42 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 06:11:42 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 06:11:42 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 06:11:42 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 06:11:43 INFO - Downloading blessings-1.6.tar.gz 06:11:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:11:44 INFO - Installing collected packages: blessings 06:11:44 INFO - Running setup.py install for blessings 06:11:44 INFO - Successfully installed blessings 06:11:44 INFO - Cleaning up... 06:11:44 INFO - Return code: 0 06:11:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:11:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:11:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:11:44 INFO - Reading from file tmpfile_stdout 06:11:44 INFO - Current package versions: 06:11:44 INFO - argparse == 1.2.1 06:11:44 INFO - blessings == 1.6 06:11:44 INFO - blobuploader == 1.2.4 06:11:44 INFO - docopt == 0.6.1 06:11:44 INFO - manifestparser == 1.1 06:11:44 INFO - mozInstall == 1.12 06:11:44 INFO - mozcrash == 0.17 06:11:44 INFO - mozdebug == 0.1 06:11:44 INFO - mozdevice == 0.48 06:11:44 INFO - mozfile == 1.2 06:11:44 INFO - mozhttpd == 0.7 06:11:44 INFO - mozinfo == 0.9 06:11:44 INFO - mozleak == 0.1 06:11:44 INFO - mozlog == 3.2 06:11:44 INFO - moznetwork == 0.27 06:11:44 INFO - mozprocess == 0.22 06:11:44 INFO - mozprofile == 0.28 06:11:44 INFO - mozrunner == 6.11 06:11:44 INFO - mozscreenshot == 0.1 06:11:44 INFO - mozsystemmonitor == 0.1 06:11:44 INFO - moztest == 0.7 06:11:44 INFO - mozversion == 1.4 06:11:44 INFO - psutil == 3.1.1 06:11:44 INFO - requests == 1.2.3 06:11:44 INFO - wsgiref == 0.1.2 06:11:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:11:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:11:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:11:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:11:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:11:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:44 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 0x7f6a96007f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a1c770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aab9c0>, '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 0x1aac8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aacd50>, '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': '41bf3e9aed84707956354da8000001c5-1463231473.648260-192896557', '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 06:11:44 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 06:11:44 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 06:11:44 INFO - Using env: {'DISPLAY': ':0', 06:11:44 INFO - 'HOME': '/home/cltbld', 06:11:44 INFO - 'LANG': 'en_US.UTF-8', 06:11:44 INFO - 'LANGUAGE': 'en_US:en', 06:11:44 INFO - 'LOGNAME': 'cltbld', 06:11:44 INFO - 'MAIL': '/var/mail/cltbld', 06:11:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:11:44 INFO - 'MOZ_NO_REMOTE': '1', 06:11:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:11:44 INFO - 'NO_EM_RESTART': '1', 06:11:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:11:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:11:44 INFO - 'PWD': '/builds/slave/test', 06:11:44 INFO - 'SHELL': '/bin/bash', 06:11:44 INFO - 'SHLVL': '1', 06:11:44 INFO - 'TERM': 'linux', 06:11:44 INFO - 'TMOUT': '86400', 06:11:44 INFO - 'USER': 'cltbld', 06:11:44 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1463231473.648260-192896557', 06:11:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:11:44 INFO - '_': '/tools/buildbot/bin/python'} 06:11:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:11:44 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)) 06:11:44 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)) 06:11:44 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)) 06:11:44 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)) 06:11:44 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)) 06:11:44 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)) 06:11:44 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)) 06:11:44 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)) 06:11:44 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)) 06:11:44 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)) 06:11:44 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)) 06:11:44 INFO - Cleaning up... 06:11:44 INFO - Return code: 0 06:11:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:11:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:11:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:11:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:11:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:11:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6a96007f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a1c770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aab9c0>, '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 0x1aac8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aacd50>, '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': '41bf3e9aed84707956354da8000001c5-1463231473.648260-192896557', '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 06:11:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 06:11:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 06:11:44 INFO - Using env: {'DISPLAY': ':0', 06:11:44 INFO - 'HOME': '/home/cltbld', 06:11:44 INFO - 'LANG': 'en_US.UTF-8', 06:11:44 INFO - 'LANGUAGE': 'en_US:en', 06:11:44 INFO - 'LOGNAME': 'cltbld', 06:11:44 INFO - 'MAIL': '/var/mail/cltbld', 06:11:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:11:44 INFO - 'MOZ_NO_REMOTE': '1', 06:11:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:11:44 INFO - 'NO_EM_RESTART': '1', 06:11:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:11:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:11:44 INFO - 'PWD': '/builds/slave/test', 06:11:44 INFO - 'SHELL': '/bin/bash', 06:11:44 INFO - 'SHLVL': '1', 06:11:44 INFO - 'TERM': 'linux', 06:11:44 INFO - 'TMOUT': '86400', 06:11:44 INFO - 'USER': 'cltbld', 06:11:44 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1463231473.648260-192896557', 06:11:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:11:44 INFO - '_': '/tools/buildbot/bin/python'} 06:11:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:11:44 INFO - Downloading/unpacking jsonschema 06:11: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 06:11: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 06:11: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 06:11: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 06:11: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 06:11: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 06:11:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:11:46 INFO - Downloading/unpacking functools32 (from jsonschema) 06:11:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:11:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:11:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:11:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:11:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:11:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:11:46 INFO - Downloading functools32-3.2.3-2.tar.gz 06:11:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:11:47 INFO - warning: no files found matching '*.txt' 06:11:47 INFO - no previously-included directories found matching 'build' 06:11:47 INFO - no previously-included directories found matching 'dist' 06:11:47 INFO - no previously-included directories found matching '.git*' 06:11:47 INFO - Installing collected packages: jsonschema, functools32 06:11:47 INFO - Running setup.py install for functools32 06:11:47 INFO - warning: no files found matching '*.txt' 06:11:47 INFO - no previously-included directories found matching 'build' 06:11:47 INFO - no previously-included directories found matching 'dist' 06:11:47 INFO - no previously-included directories found matching '.git*' 06:11:47 INFO - Successfully installed jsonschema functools32 06:11:47 INFO - Cleaning up... 06:11:47 INFO - Return code: 0 06:11:47 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 06:11:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:11:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:11:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:11:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:11:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:47 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 0x7f6a96007f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a1c770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aab9c0>, '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 0x1aac8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aacd50>, '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': '41bf3e9aed84707956354da8000001c5-1463231473.648260-192896557', '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 06:11:47 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 06:11:47 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 06:11:47 INFO - Using env: {'DISPLAY': ':0', 06:11:47 INFO - 'HOME': '/home/cltbld', 06:11:47 INFO - 'LANG': 'en_US.UTF-8', 06:11:47 INFO - 'LANGUAGE': 'en_US:en', 06:11:47 INFO - 'LOGNAME': 'cltbld', 06:11:47 INFO - 'MAIL': '/var/mail/cltbld', 06:11:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:11:47 INFO - 'MOZ_NO_REMOTE': '1', 06:11:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:11:47 INFO - 'NO_EM_RESTART': '1', 06:11:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:11:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:11:47 INFO - 'PWD': '/builds/slave/test', 06:11:47 INFO - 'SHELL': '/bin/bash', 06:11:47 INFO - 'SHLVL': '1', 06:11:47 INFO - 'TERM': 'linux', 06:11:47 INFO - 'TMOUT': '86400', 06:11:47 INFO - 'USER': 'cltbld', 06:11:47 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1463231473.648260-192896557', 06:11:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:11:47 INFO - '_': '/tools/buildbot/bin/python'} 06:11:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:11:47 INFO - Downloading/unpacking flake8 06:11:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:11:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:11:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:11:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:11:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:11:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:11:49 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 06:11:49 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 06:11: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 06:11: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 06:11: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 06:11: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 06:11: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 06:11: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 06:11:49 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 06:11:49 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 06:11: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 06:11: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 06:11: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 06:11: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 06:11: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 06:11: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 06:11:49 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 06:11: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 06:11: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 06:11: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 06:11: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 06:11: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 06:11: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 06:11:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 06:11:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:11:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:11:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:11:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:11:50 INFO - no previously-included directories found matching 'docs/_build' 06:11:50 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 06:11:50 INFO - Running setup.py install for pep8 06:11:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:11:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:11:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:11:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:11:50 INFO - no previously-included directories found matching 'docs/_build' 06:11:50 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 06:11:50 INFO - Successfully installed flake8 mccabe pyflakes pep8 06:11:50 INFO - Cleaning up... 06:11:50 INFO - Return code: 0 06:11:50 INFO - Running post-action listener: _resource_record_post_action 06:11:50 INFO - Running post-action listener: _start_resource_monitoring 06:11:50 INFO - Starting resource monitoring. 06:11:50 INFO - ##### 06:11:50 INFO - ##### Finished create-virtualenv step (success) 06:11:50 INFO - ##### 06:11:50 INFO - ##### 06:11:50 INFO - ##### Running install step. 06:11:50 INFO - ##### 06:11:50 INFO - Running pre-action listener: _resource_record_pre_action 06:11:50 INFO - Running main action method: install 06:11:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:11:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:11:50 INFO - Reading from file tmpfile_stdout 06:11:50 INFO - Detecting whether we're running mozinstall >=1.0... 06:11:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:11:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:11:50 INFO - Reading from file tmpfile_stdout 06:11:50 INFO - Output received: 06:11:50 INFO - Usage: mozinstall [options] installer 06:11:50 INFO - Options: 06:11:50 INFO - -h, --help show this help message and exit 06:11:50 INFO - -d DEST, --destination=DEST 06:11:50 INFO - Directory to install application into. [default: 06:11:50 INFO - "/builds/slave/test"] 06:11:50 INFO - --app=APP Application being installed. [default: firefox] 06:11:50 INFO - mkdir: /builds/slave/test/build/application 06:11:50 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'] 06:11:50 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 06:12:07 INFO - Reading from file tmpfile_stdout 06:12:07 INFO - Output received: 06:12:07 INFO - /builds/slave/test/build/application/firefox/firefox 06:12:07 INFO - Running post-action listener: _resource_record_post_action 06:12:07 INFO - ##### 06:12:07 INFO - ##### Finished install step (success) 06:12:07 INFO - ##### 06:12:07 INFO - ##### 06:12:07 INFO - ##### Running run-tests step. 06:12:07 INFO - ##### 06:12:07 INFO - Running pre-action listener: _resource_record_pre_action 06:12:07 INFO - Running main action method: run_tests 06:12:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:12:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:12:07 INFO - Python 2.7.3 06:12:07 INFO - Return code: 0 06:12:07 INFO - grabbing minidump binary from tooltool 06:12:07 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')]} 06:12:07 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 0x1aab9c0>, '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 0x1aac8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aacd50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:12:07 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 06:12:07 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 06:12:07 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 06:12:07 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:12:07 INFO - Return code: 0 06:12:07 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 06:12:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:12:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:12:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 06:12:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:12:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 06:12:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 06:12:08 INFO - Return code: 0 06:12:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/f2B5kWO3Q9m937NqcJCxvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-026', '--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 06:12:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/f2B5kWO3Q9m937NqcJCxvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-026 --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 06:12:08 INFO - Using env: {'DISPLAY': ':0', 06:12:08 INFO - 'HOME': '/home/cltbld', 06:12:08 INFO - 'LANG': 'en_US.UTF-8', 06:12:08 INFO - 'LANGUAGE': 'en_US:en', 06:12:08 INFO - 'LOGNAME': 'cltbld', 06:12:08 INFO - 'MAIL': '/var/mail/cltbld', 06:12:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:12:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 06:12:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:12:08 INFO - 'MOZ_NO_REMOTE': '1', 06:12:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:12:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:12:08 INFO - 'NO_EM_RESTART': '1', 06:12:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:12:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:12:08 INFO - 'PWD': '/builds/slave/test', 06:12:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:12:08 INFO - 'SHELL': '/bin/bash', 06:12:08 INFO - 'SHLVL': '1', 06:12:08 INFO - 'TERM': 'linux', 06:12:08 INFO - 'TMOUT': '86400', 06:12:08 INFO - 'USER': 'cltbld', 06:12:08 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1463231473.648260-192896557', 06:12:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:12:08 INFO - '_': '/tools/buildbot/bin/python'} 06:12:08 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/f2B5kWO3Q9m937NqcJCxvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-026', '--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 06:12:08 INFO - mozversion application_buildid: 20160514030209 06:12:08 INFO - mozversion application_changeset: 2afd8fa9bb5df5577e5566468bb423b76c63cc77 06:12:08 INFO - mozversion application_display_name: Nightly 06:12:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:12:08 INFO - mozversion application_name: Firefox 06:12:08 INFO - mozversion application_remotingname: firefox 06:12:08 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 06:12:08 INFO - mozversion application_vendor: Mozilla 06:12:08 INFO - mozversion application_version: 49.0a1 06:12:08 INFO - mozversion platform_buildid: 20160514030209 06:12:08 INFO - mozversion platform_changeset: 2afd8fa9bb5df5577e5566468bb423b76c63cc77 06:12:08 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 06:12:08 INFO - mozversion platform_version: 49.0a1 06:12:08 INFO - using testdate: 1463231528 06:12:08 INFO - actual date: 1463231528 06:12:08 INFO - starting webserver on 'localhost:54075' 06:12:08 INFO - SUITE-START | Running 2 tests 06:12:08 INFO - TEST-START | dromaeo_css 06:12:08 INFO - operating with platform_type : linux_ 06:12:08 INFO - Initialising browser for dromaeo_css test... 06:12:08 INFO - TEST-INFO | started process 11724 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSeH2yl/profile http://localhost:54075/getInfo.html) 06:12:12 INFO - PROCESS | 11724 | JavaScript error: resource://app/components/nsBrowserGlue.js, line 1273: ReferenceError: E10SUINotification is not defined 06:12:12 INFO - PROCESS | 11724 | __metrics Screen width/height:1600/1200 06:12:12 INFO - PROCESS | 11724 | colorDepth:24 06:12:12 INFO - PROCESS | 11724 | Browser inner width/height: 1024/697 06:12:12 INFO - PROCESS | 11724 | __metrics 06:12:13 ERROR - PROCESS | 11724 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 06:12:14 INFO - TEST-INFO | 11724: exit 0 06:12:14 INFO - Browser initialized. 06:12:14 INFO - Running cycle 1/1 for dromaeo_css test... 06:12:14 INFO - TEST-INFO | started process 11805 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSeH2yl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:12:26 INFO - PROCESS | 11805 | RSS: Main: 152350720 06:12:26 INFO - PROCESS | 11805 | 06:13:17 INFO - PROCESS | 11805 | 1463231597251 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] 06:15:25 INFO - PROCESS | 11805 | Cycle 1(1): loaded http://localhost:54075/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54075/tests/dromaeo/cssquery-ext.html) 06:15:26 INFO - PROCESS | 11805 | RSS: Main: 261132288 06:15:26 INFO - PROCESS | 11805 | 06:18:28 INFO - PROCESS | 11805 | Cycle 1(1): loaded http://localhost:54075/tests/dromaeo/cssquery-ext.html (next: http://localhost:54075/tests/dromaeo/cssquery-jquery.html) 06:18:28 INFO - PROCESS | 11805 | RSS: Main: 237649920 06:18:28 INFO - PROCESS | 11805 | 06:21:30 INFO - PROCESS | 11805 | Cycle 1(1): loaded http://localhost:54075/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54075/tests/dromaeo/cssquery-mootools.html) 06:21:31 INFO - PROCESS | 11805 | RSS: Main: 235204608 06:21:31 INFO - PROCESS | 11805 | 06:24:33 INFO - PROCESS | 11805 | Cycle 1(1): loaded http://localhost:54075/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54075/tests/dromaeo/cssquery-prototype.html) 06:24:34 INFO - PROCESS | 11805 | RSS: Main: 237854720 06:24:34 INFO - PROCESS | 11805 | 06:27:36 INFO - PROCESS | 11805 | Cycle 1(1): loaded http://localhost:54075/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54075/tests/dromaeo/cssquery-yui.html) 06:27:37 INFO - PROCESS | 11805 | RSS: Main: 221298688 06:27:37 INFO - PROCESS | 11805 | 06:30:40 INFO - PROCESS | 11805 | Cycle 1(1): loaded http://localhost:54075/tests/dromaeo/cssquery-yui.html (next: http://localhost:54075/tests/dromaeo/cssquery-dojo.html) 06:30:40 INFO - PROCESS | 11805 | RSS: Main: 253849600 06:30:40 INFO - PROCESS | 11805 | 06:30:40 INFO - PROCESS | 11805 | __start_tp_report 06:30:40 INFO - PROCESS | 11805 | _x_x_mozilla_page_load 06:30:40 INFO - PROCESS | 11805 | _x_x_mozilla_page_load_details 06:30:40 INFO - PROCESS | 11805 | |i|pagename|runs| 06:30:40 INFO - PROCESS | 11805 | |0;dojo.html;3179.7933134346267;3180.8091514509133;3204.679532046795;3218.7907785993925;3241.35172965407;5331.520163185312;5341.919871201931;5391.353037635479;5420.214068960004;5479.753411096501;5353.518183363496;5360.839174824751;5402.945970540293;5442.537384322328;5488.807891723791;4828.879278018051;4906.656534042619;4925.285833554132;4943.233798761198;5183.688978661293;5074.162763144095;5092.796288148488;5115.028144652522;5143;5147.871303217431;4866.416030076378;4868.975655121737;4900.313956046157;4922.26166075088;4928.162212423897;4911.312416261726;4923.08922849274;4997.05056039355;4997.950020499785;5043.9912017596625;4904.5831104356175;4982.153033984231;4986.027724593706;4998.900021999576;5011.774470148815;5316.255724198615;5383.757730902119;5393.002294575477;5444.91832622511;5485.862853428696;5837.620554663933;5839.8540036499;5857.121431785224;5870.530357571384;5891.23413956183;5658.321001479849;5782.537397008229;5824.854378640525;5937.703114844284;5976.043832986745;5532.75102620383;5544.528715059185;5545.445455454423;5554.333479982428;5555.111182210827;6030.246219222598;6043.939560604425;6045.455908968214;6048.576599637983;6062.848428789315;5620.241267428926;5660;5683.460071293221;5688.345840228427;5823.272090988627;5809.622374545641;5816.563757718103;5818.156367326798;5865.912011319833;5869.794557190563;5811.593188476851;5813.76744930198;5817.418258174233;5820.010598198316;5821.738021788945;5712.600117991784;5748.223989761329;5757.568182386254;5803.477687008105;5862.296524417097;4357.302831546937;4359.106383191438;4362.258416069212;4366.497852746911;4395;3848.441975913533;3880.417937309426;3917.1186483041656;3941.0541470046983;3970.563238043813;5419.99730049942;5472.753726082336;5479.4520547944885;5482.068048431696;5505.862353441116;5824.970875145682;5850.765969361178;5852.912206316909;5858.150568167592;5878.735456904449;5426.782928682887;5458.781648734006;5502.724863756796;5505.064139096363;5509.862253443696;4866.367372241586;4886.218205087169;4890.779914903838;4890.779914903838;4934.8766280843265;6000.939990600125;6002.309734380515;6010.729517171738;6011.098335249748;6013.398660133952;5682.801101961411;5839.5036421904615;5883.970580147072;5884.293884733688;5907.698438395093;6031.788887388921;6038.094285857156;6038.5772995889865;6039.15451836752;6055.333913269449;5833.066709326487;5844.736986835517;5845.18167456548;5847.94152058474;5861.1501332307425;5677.744501497526;5826.883462330815;5862.472377486047;5887.20522729426;5921.259842519685;5729.169270455844;5735.340435849931;5750.338711048115;5752.395998420106;5770.480656740913;5696.344920334214;5730.942690573041;5737.827865164052;5760.337561180518;5763.596548241583;5465.426130256425;5518.255035570307;5573.804916828054;5584.972075139598;5649.406812284651;5772.9711351442975;5776.56675749312;5779.162021506942;5831.970840145942;5834.824955251349;5420.024395608671;5638.295213098363;5644.435556444323;5645;5686.971565142313 06:30:40 INFO - PROCESS | 11805 | |1;ext.html;612.2805703298623;644.6744394080988;657.1752450674403;662.2086606505225;668.0046730371741;31684.366312673763;44250.336244956314;44774.7761261194;44787.328190077176;44826.77586612067;46350.304745428846;46961.53038469614;46988.0602387952;46993.76503117479;47031.82420439491;8916.821663566709;9049.2308153807;9051.637934482613;9062.139096785828;9062.682806101788;8893.066228046058;8967.865482017742;8979.595918183664;8994.10058994102;9002.32482563807;9038.774030649221;9062.637494500212;9067.773305667346;9070.954645226764;9073.591688374006;8961.820763584725;9000.819983600357;9006.729798106067;9007.864882026775;9009.909900900973;8864.556772161366;8956.820863582758;8962.193402593733;8975.506347150904;8981.955090224506;43217;43439.13121737579;43485;43610.56389436097;43634.34548481775;3540.9364608609676;3619.674229319374;3730.757500762442;4027.8791636250958;4183.393407955829;33143.83428082868;33712;34108.48837267443;34294.48558271628;34533.309333813435;3773.3773927301963;3850.979490435037;3906.609339066099;3933.8229779659982;4038.959610403888;367551;369673.1516342428;370089;370182;370932.2906770952;61533.692331538055;62720;62730;62737;62891.685541572;55042;55260.72369638126;55309.44690553043;58872;65708.6714566424;16420.507384778477;16451.753223701653;16453.753193702105;16544.6691066178;16552.668946621;13310;13465.798013029813;13488.05583609138;13501.189928604315;13503.392347344197;17376.565585860455;17761.957374901795;17820.287188512575;19250.80749192518;19461.08081801294;7256.528325658815;7460.515066520658;7476.401887848959;7480.848129288297;7603.695852165852;1013.3362647465922;1091.9344839309667;1111.8776934537195;1137.0505627800715;1158.3860553906443;32501.349973000408;33670;33684.157896052304;33809.323813523595;34063.65936340605;1147.9770404591861;1163.2903928603546;1193.1111322065117;1234.5185377702703;1251.3492983648453;17150.914245428692;17517.386891458736;17902.46292611224;18167.104592728625;19158.32945846889;603293;603607;604641;606651;607633;58726.412735872946;60151.69924150351;60154.69922650359;60303.69848150731;60362.69818650967;1425.301602214928;1447.3486930881145;1453.1862157192006;1461.576142918563;1488.4120772294882;32276.67723322737;33716;33856.66143338535;34264.828675856465;34275.65724342824;50645.746771265905;50958;51391.48608513867;51401.7429912848;51457;516436;519054;519054;519946;521533;26494.205173844817;27713;28280.29299267535;28303.575446368734;28703.856480717463;48371;48966;49260.2610960843;49666.255006175656;49787.50212497829;40570.39144412895;40915.38626920658;41590.37614435725;41945;42854;40295.79852100721;40816.16327265221;41021.17957640771;41693;41718.79140604399;40998.79500602478;41202.38196426996;42758;43179.78410107929;43280.56719432891;36661.266774663825;37865.242695146495;38301.808490957366;39193.07781463584;39276.607233927294;29294.121176364744;29376.55935161017;29390.41219175562;29540;29578.704212958455 06:30:40 INFO - PROCESS | 11805 | |2;jquery.html;413.9999999999996;420.5479109956795;422.1915032712356;426.2859709985774;432.3320469874046;18093.728594071083;19145.90853941671;19333.51666208344;19978.700319495183;20030.09864556095;18399.90800045998;18449.53876153097;19126.330578429723;19157.90421047893;19187.712184317246;5413.188021796733;5625.071863142597;5774.307083150028;5787.565932555054;5789.681567513785;4068.247374235776;5668.178114173463;5683.54531637468;5926.762929482816;6219.813405597839;6273.56085074045;6298.716557754912;6311.147995020682;6313.810585682437;6315.905261421082;6246.843828904268;6374.968125159345;6399.200099987502;6400.839979000516;6401.295857455676;3792.2984247914223;3952.2490726761825;5390.380106287748;5476.439214823796;5615.298087739033;22247.22134725277;22627.886860565755;22839.885800571057;23117.76882231173;23259.88370058156;2603.0368763557417;2683.771879390254;3012.487877060905;3094.1336425800587;3145.2765863851214;13501.59495215145;13513.378394592613;13524.59426217215;13546.390412431465;13619.523316683868;1907.1894444861018;1994.7307113539632;2014.8388128949648;2061.793820617941;2091.5816836632735;71585.64207178983;74203.25796742071;75472;76534.23465765327;87404.56297718534;10809.459527023775;10815.729606759895;10888.891111088788;10890.945545272381;11194.608188713355;15313.923430382776;15391.769123463157;15405.691886162214;15423;15442.613934651723;8150.307223886036;8334.623548507929;8359.791005224797;8416.158384161536;8478.23695867375;8121.4721043132;8285.917140828515;8421.452605580618;8515.403921725543;8551.750334872708;4748.905021899543;4761.6904901181315;4790.281457781361;4808.35087263222;5202.635815492879;1778.04820336004;1857.5634725839418;1926.624308259904;2034.979650203479;2037.9184832606827;936.4849332866896;968.4915419404728;1048.0043958239703;1058.439027315524;1084.8210045342587;18616.255349786126;19224.519387015436;19315.130819113172;19361.32235371755;19492.684771091266;1033.5194134727387;1037.273908264218;1048.250500891866;1056.4189695667505;1099.5326986030905;10440.895591044144;10911.508982095826;10970.670879873616;10982.121430285559;11010.50452729629;479923;481949;484517;498080;501054;89354.55322723345;89440;90999.54500227456;91214;91774;1024.9282550221562;1038.0588299879273;1050.4275170032352;1054.767951050783;1068.3376306689902;16620.335186592885;16931.66136677284;17063;17081.743773843154;17339.133043348036;39838;39973.40039899346;40456.59543404528;40589;41414.47976921875;55887.44112558985;56680;56753.432465674814;57034;57107.71446142743;8828.867566986371;8894.777630559287;8950.82098358016;9108.635654574007;9180.540972951192;10447.68656940293;13748.106373085717;13961.720765584834;13986.720265594427;14088.436462541384;5928.762849485972;8712.30110128519;11529.538818447169;12000.61992870831;12194.390280486117;10894.291871028357;11284.435778210893;11490.54037838477;11611.013063889326;11846.63067386509;11443.713907152387;11757.47091380907;12055.336956467478;12263.570775022832;12357.443915023665;8877.955610221907;8937.329700272376;9131.315151363542;9159.22146617545;9187.356885017985;8668.0898505656;10915.482408396972;11982.820257695967;12047.698807529881;12198.51205951752 06:30:40 INFO - PROCESS | 11805 | |3;mootools.html;1379.8137251471048;1557.8130624325079;1573.2527049651417;1756.306259027684;1869.2896699254281;8735.825283494327;9033.23217526511;9045.63817447302;9244.8613270801;9287.907120928789;9036.231920286784;9260.351775375726;9264.444133352004;9265.073492650732;9277.536123193848;2065.4216819290546;2326.255598208574;2378.4767351182713;2396.940076498084;2407.145463360508;2388.164142550102;2402.231285988484;2410.192585483865;2414.4929564791328;2414.601590737535;2170.1427935965285;2246.168917500519;2248.539049494858;2250.561140577588;2252.7859853313907;2018.5458271888854;2274.351809734226;2324.4421338878715;2326.790588847012;2360.3509035015336;2292.977070229293;2310.7111611048617;2311.653252012195;2315.0855412112123;2357.0571771291447;9309.30180236485;9352.485613291266;9379.202767764747;9388.436693798325;9392.389494682891;5316.494932981399;5437.673739575598;5441.483059109379;5442.6462279951675;5450.182472629098;3373.6795004474534;3375.645557216506;3420.794752314844;3467.3758723429773;3491.17957280039;1066.5733706517378;1077.3320541264466;1094.9726256843562;1095.4522738630685;1114.3481063577817;13095.934520327433;13239;13240.271785051818;13248.470061197444;13256.801147982787;4590.219662657356;4628;4708.835190768306;4724.881877953078;4726.88182795426;10399.27205095636;10413.687589372332;10430.113440357503;10457.790844183073;10460.215483838743;4819.783109760069;5414.6209765316835;5469.988052210274;5492.368377636623;5495.175723641406;5055.646104772704;5166.8966620668125;5178.6116041297055;5201.6878987260125;5213.687178769286;3792.50697409335;3816.484774555455;3886.3393223152134;3920.843166273317;3942.3245120823535;1131.8868113188614;1159.4840296068337;1160.0951258018758;1165.155262434728;1175.988240117605;1036.3729943384294;1044.9111825494917;1051.106559424483;1051.1800795379615;1054.2830875004918;6308.369163083655;6354.110424540568;6366.076918846834;6371.23545174582;6480.157579514727;462.3065401897154;462.7986825730818;466.3354719524664;471.9032598317337;479.0156228949542;4356.542563030899;4472.575105364985;4532.410786597721;4570.8171673133365;4585.151746926829;11113.277636953571;11578.536858525733;11593.130515211393;11634.127440441831;11652;10746;11125.833112503318;11144.164187685956;11471.4264286787;11502.769944601061;8568.52873091986;8673.739787806377;8712.433691810013;8717.171868672465;8760.255378292917;6133.601315914451;6262.530310226843;6304.085907543289;6343.619382837044;6364.268109167319;10478.528466218886;10626.203034772268;10642.148628109888;10693.518791654238;10771.569137234423;10245.641402550875;10600.204984626029;10626.043656070991;10887.346759194474;11019.449027548751;7399.963000184965;7609.391248700202;7924.683012679428;8122.75631731049;8314.16858314188;8993.100689930954;9070.773230669287;9142.908570914205;9155.725328240163;9163.450192988443;7815.960920195363;7992.080910695345;8014.19858014194;8068.112507624274;8079.83840323202;7759.379249660126;7894.644740986554;7914.960425197837;7991.880121798294;8235.094139644754;7484.43866709988;7498.062742157231;7557.130929943127;7667.769966901002;7678.61606919663;5293.708846013506;5423.9186412204635;5544.168374743821;5591.077472217201;5653.604247702621;5671.914921276267;5754.165645981393;5765.827025189251;5779.653220806765;5805.448482394166 06:30:40 INFO - PROCESS | 11805 | |4;prototype.html;333.5580356028262;335.4599095456313;337.4061651493371;346.7711310535047;352.75836052304174;5396.811111611094;5402.37872644646;5408.459154084594;5438.129899216116;5494.148406996912;5338.27933229014;5358.946410535894;5364.463553644634;5369.865753356158;5373.650712703671;4045.110075783323;4123.958760412403;4186.49762028557;4236.449261596003;4267.997020700133;4198.643115335185;4225.535191128974;4233.534311225762;4246.214450326699;4255.8084886180195;3990.1620659661507;4153.252414565377;4185.644220241283;4218.282891908368;4223.577642235782;4077.7961101944784;4083.142540066575;4105.774182419966;4217.8312867485265;4221.430106935555;3748.194138260287;3760.8495660173835;3767.698584113264;3769.208466222097;3779.4141908004303;5104.897902041938;5180.611454140954;5182.730231093368;5183.792648294064;5219.8434046978655;4780.856574302742;4795;4807.351007613962;4815.6388270879825;4826.541478559531;5570.637908535932;5577.414371491016;5619.353774315965;5625.887482250332;5697.3477691469425;4110.794460276974;4197.664186865043;4203.192627170316;4209.810558524874;4211.957880421187;5786.383510306058;5801.332846722638;5824.912626310566;5842.474177324061;5858.589898707092;4199.454070970754;4199.643030342394;4206.537280899099;4228.619424251832;4300.978495107504;4315.201687687788;4326.610605045561;4327.37253098299;4346.239408103633;4351.825926962886;4520.163769702615;4711.905761884743;4723.559314409138;4730.314104454835;4773.809047638125;5618.747156377891;5628.212050312959;5630.690312032878;5676.68778217202;5722.17028530869;1432.5531213474467;1440.1143296872558;1458.555140682081;1459.8467160947948;1462.6635873749096;915.5971372596036;939.9718008459621;942.8255772682076;943.6838659049158;977.8435450327913;5304.655197412156;5341.439148889311;5341.626086174008;5351.57187425005;5362.65142765719;5589.80435684749;5646.463585959327;5646.548276137899;5687.180102367202;5695.658260504383;5089.465606111305;5340.519353258225;5340.546053585411;5353.678779273256;5362.490563396471;2999.5350720638335;3011.8192908425563;3022.7128422799797;3039.98480007603;3044.939101217963;5827.018813603395;5831.271091113611;5846.766129354778;5847.26909136358;5851.473367396869;5629.705167811345;5934.495567876779;5937.821865344046;5985.25184351956;6009.939900600938;5840.415958404125;5947.553933455095;5950.315713692894;5960.135780311917;5994.310654274727;5517.5585953124455;5751.4536119068625;5786.855328616818;5823.213866128019;5835.358110607915;5700.486956173799;5726.484616384545;5738.770449181986;5739.2252045973255;5751.079827227623;5760;5924.689371145343;5927.8221653350465;5932.851678707894;5972.820815375546;4126.896827579335;4183.079722461053;4200.487824383158;4201;4209.557996410333;5713.942860571341;5730.885382292414;5751.511121554715;5769.807473160172;5787.2187254720075;5346.25152478661;5603.8879222416135;5631.8592035199445;5632.211490391266;5662.8301150965535;5555.250041244381;5581.665100094007;5582.218489411404;5596.272484576979;5683.431656834284;5309.309789727311;5417.5665946723375;5463.677936876732;5540.944590554042;5617.578681598815;5612.8213075253025;5644.576656750843;5645.153227015816;5700.25896633435;5734.569907257056;5545;5557.305336832896;5581.274434323512;5587.608867379245;5597.244372009889 06:30:40 INFO - PROCESS | 11805 | |5;yui.html;695.2838576266446;733.8202140475585;795.244517708177;847.2671139464366;896.4083704754859;14292.857071429296;15226.543203703879;15300;15377.461788837392;15582.610434739123;14635.975481716285;14691.77962330566;14702.485413010547;14703.264836758173;14726.190059546721;706.3395724997144;826.1036775098995;827.1397746343795;844.3287586368851;845.2688424512783;838.6603425612606;850.4472093139447;853.0318088969003;853.6457370191363;854.1842540373927;883.8099808541162;889.8086911314099;890.1587999340626;890.3722875372847;890.8084761776216;736.3556887723269;860.9397342185987;886.6364790435955;888.4802390601506;888.5424006636555;638.9106573292526;641.8619996700731;643.4691379611792;643.6234802640461;644.3073695777058;13837.654058648513;13859.4456221751;13876.028677992545;13882.167069975732;13896.93051534746;14235.359408826627;14477.927610361881;14593.343299551545;14608.34262458192;14849.554513364616;12030.69923251917;12377.814332785016;12822.42299096543;13036.478540858354;13140.211587304791;4470.932936005963;4559.224931761609;4567.497575266718;4569.588737013652;4574.588287054181;60538.394616053716;60686;61348.0797788038;62735.6863215681;62827.685861570855;11347.319160850375;11444.94277528607;11573.42132893352;11585.942070289595;11612.535498580131;20650.91608019984;20832.47918802012;20834.374968750963;21102.366928992156;21306.36080917575;8272.683648578408;8429.873551896726;8514.616842242114;8515.914840851636;8544.829103417896;4770.952290477051;4839.177339852234;4841.346418233564;4844.346013288232;4916.631252655993;3496.125968507873;3627.183883626216;3629.0745859806107;3632.146445585285;3642.399004164336;673.1007176441475;686.5537400689492;697.3096634332077;697.8604279144191;699.0981633692581;609.9573029887954;617.8208319587269;692.5913710910587;697.292248367907;705.8200158959432;8702.39083264178;8719.782005449915;8814.77963050916;8863.556822158866;9157.633694652273;660.1978596005875;673.9460843132538;696.7004188199123;711.2034521336136;713.3651050565002;4109.938350924738;4114.238865809834;4151.875443736692;4165.91668166635;4212.3681447782465;67817.32182678209;68653;69838.3016169842;70802.64598677076;70861.6456917712;61949.380506194364;62244;62415.37584624095;62590;63259.367406326266;55247.44752552423;55403.16895246575;56417.43582564204;56519.71740141355;56651.71674141603;8119.228673276029;8251.422400431911;8274.875876861732;8350.707725229588;8413.621387037476;35163.29673406569;37529.24941501209;37822.62177378191;37986.52358372848;38545.6145438542;46052.30921536247;51413.7429312851;51416.48583514117;51900.22149667682;53376.73311633417;12105.939470302592;12456.688582785502;12496.187747796364;13009.47962081544;13401.262930538915;12716;12959.805602915776;13524.59426217215;13534.526291579747;14429.711405772036;10001.949990250003;10993.835092473779;11235.269707468988;12559.811602826148;13331.66670833237;13226.66933326636;13693.589192324247;14017.859821401655;14067.718645626825;14087.718245634826;13257.602271931857;13342.799858002332;13404.478778315717;13420.194788312732;14001.929990349983;9975.950120249352;10080.495975201356;10270.399904071082;10380.532876020749;10578.342576706502;9915.355501892354;10184.490775461345;10187.643432479828;10210;10211.387316761018 06:30:40 INFO - PROCESS | 11805 | __end_tp_report 06:30:40 INFO - PROCESS | 11805 | __start_cc_report 06:30:40 INFO - PROCESS | 11805 | _x_x_mozilla_cycle_collect,885 06:30:40 INFO - PROCESS | 11805 | __end_cc_report 06:30:40 INFO - PROCESS | 11805 | __startTimestamp1463232640347__endTimestamp 06:30:40 INFO - PROCESS | 11805 | 06:30:40 INFO - PROCESS | 11805 | ------- Summary: start ------- 06:30:40 INFO - PROCESS | 11805 | Number of tests: 6 06:30:40 INFO - PROCESS | 11805 | 06:30:40 INFO - PROCESS | 11805 | [#0] dojo.html Cycles:165 Average:5416.49 Median:5644.44 stddev:624.53 (11.1%) stddev-sans-first:601.29 06:30:40 INFO - PROCESS | 11805 | Values: 3179.8 3180.8 3204.7 3218.8 3241.4 5331.5 5341.9 5391.4 5420.2 5479.8 5353.5 5360.8 5402.9 5442.5 5488.8 4828.9 4906.7 4925.3 4943.2 5183.7 5074.2 5092.8 5115.0 5143.0 5147.9 4866.4 4869.0 4900.3 4922.3 4928.2 4911.3 4923.1 4997.1 4998.0 5044.0 4904.6 4982.2 4986.0 4998.9 5011.8 5316.3 5383.8 5393.0 5444.9 5485.9 5837.6 5839.9 5857.1 5870.5 5891.2 5658.3 5782.5 5824.9 5937.7 5976.0 5532.8 5544.5 5545.4 5554.3 5555.1 6030.2 6043.9 6045.5 6048.6 6062.8 5620.2 5660.0 5683.5 5688.3 5823.3 5809.6 5816.6 5818.2 5865.9 5869.8 5811.6 5813.8 5817.4 5820.0 5821.7 5712.6 5748.2 5757.6 5803.5 5862.3 4357.3 4359.1 4362.3 4366.5 4395.0 3848.4 3880.4 3917.1 3941.1 3970.6 5420.0 5472.8 5479.5 5482.1 5505.9 5825.0 5850.8 5852.9 5858.2 5878.7 5426.8 5458.8 5502.7 5505.1 5509.9 4866.4 4886.2 4890.8 4890.8 4934.9 6000.9 6002.3 6010.7 6011.1 6013.4 5682.8 5839.5 5884.0 5884.3 5907.7 6031.8 6038.1 6038.6 6039.2 6055.3 5833.1 5844.7 5845.2 5847.9 5861.2 5677.7 5826.9 5862.5 5887.2 5921.3 5729.2 5735.3 5750.3 5752.4 5770.5 5696.3 5730.9 5737.8 5760.3 5763.6 5465.4 5518.3 5573.8 5585.0 5649.4 5773.0 5776.6 5779.2 5832.0 5834.8 5420.0 5638.3 5644.4 5645.0 5687.0 06:30:40 INFO - PROCESS | 11805 | 06:30:40 INFO - PROCESS | 11805 | [#1] ext.html Cycles:180 Average:65553.50 Median:23064.20 stddev:135048.46 (585.5%) stddev-sans-first:135339.29 06:30:40 INFO - PROCESS | 11805 | Values: 612.3 644.7 657.2 662.2 668.0 31684.4 44250.3 44774.8 44787.3 44826.8 46350.3 46961.5 46988.1 46993.8 47031.8 8916.8 9049.2 9051.6 9062.1 9062.7 8893.1 8967.9 8979.6 8994.1 9002.3 9038.8 9062.6 9067.8 9071.0 9073.6 8961.8 9000.8 9006.7 9007.9 9009.9 8864.6 8956.8 8962.2 8975.5 8982.0 43217.0 43439.1 43485.0 43610.6 43634.3 3540.9 3619.7 3730.8 4027.9 4183.4 33143.8 33712.0 34108.5 34294.5 34533.3 3773.4 3851.0 3906.6 3933.8 4039.0 367551.0 369673.2 370089.0 370182.0 370932.3 61533.7 62720.0 62730.0 62737.0 62891.7 55042.0 55260.7 55309.4 58872.0 65708.7 16420.5 16451.8 16453.8 16544.7 16552.7 13310.0 13465.8 13488.1 13501.2 13503.4 17376.6 17762.0 17820.3 19250.8 19461.1 7256.5 7460.5 7476.4 7480.8 7603.7 1013.3 1091.9 1111.9 1137.1 1158.4 32501.3 33670.0 33684.2 33809.3 34063.7 1148.0 1163.3 1193.1 1234.5 1251.3 17150.9 17517.4 17902.5 18167.1 19158.3 603293.0 603607.0 604641.0 606651.0 607633.0 58726.4 60151.7 60154.7 60303.7 60362.7 1425.3 1447.3 1453.2 1461.6 1488.4 32276.7 33716.0 33856.7 34264.8 34275.7 50645.7 50958.0 51391.5 51401.7 51457.0 516436.0 519054.0 519054.0 519946.0 521533.0 26494.2 27713.0 28280.3 28303.6 28703.9 48371.0 48966.0 49260.3 49666.3 49787.5 40570.4 40915.4 41590.4 41945.0 42854.0 40295.8 40816.2 41021.2 41693.0 41718.8 40998.8 41202.4 42758.0 43179.8 43280.6 36661.3 37865.2 38301.8 39193.1 39276.6 29294.1 29376.6 29390.4 29540.0 29578.7 06:30:40 INFO - PROCESS | 11805 | 06:30:40 INFO - PROCESS | 11805 | [#2] jquery.html Cycles:180 Average:28844.36 Median:2643.40 stddev:80523.79 (3046.2%) stddev-sans-first:80721.38 06:30:40 INFO - PROCESS | 11805 | Values: 414.0 420.5 422.2 426.3 432.3 18093.7 19145.9 19333.5 19978.7 20030.1 18399.9 18449.5 19126.3 19157.9 19187.7 5413.2 5625.1 5774.3 5787.6 5789.7 4068.2 5668.2 5683.5 5926.8 6219.8 6273.6 6298.7 6311.1 6313.8 6315.9 6246.8 6375.0 6399.2 6400.8 6401.3 3792.3 3952.2 5390.4 5476.4 5615.3 22247.2 22627.9 22839.9 23117.8 23259.9 2603.0 2683.8 3012.5 3094.1 3145.3 13501.6 13513.4 13524.6 13546.4 13619.5 1907.2 1994.7 2014.8 2061.8 2091.6 71585.6 74203.3 75472.0 76534.2 87404.6 10809.5 10815.7 10888.9 10890.9 11194.6 15313.9 15391.8 15405.7 15423.0 15442.6 8150.3 8334.6 8359.8 8416.2 8478.2 8121.5 8285.9 8421.5 8515.4 8551.8 4748.9 4761.7 4790.3 4808.4 5202.6 1778.0 1857.6 1926.6 2035.0 2037.9 936.5 968.5 1048.0 1058.4 1084.8 18616.3 19224.5 19315.1 19361.3 19492.7 1033.5 1037.3 1048.3 1056.4 1099.5 10440.9 10911.5 10970.7 10982.1 11010.5 479923.0 481949.0 484517.0 498080.0 501054.0 89354.6 89440.0 90999.5 91214.0 91774.0 1024.9 1038.1 1050.4 1054.8 1068.3 16620.3 16931.7 17063.0 17081.7 17339.1 39838.0 39973.4 40456.6 40589.0 41414.5 55887.4 56680.0 56753.4 57034.0 57107.7 8828.9 8894.8 8950.8 9108.6 9180.5 10447.7 13748.1 13961.7 13986.7 14088.4 5928.8 8712.3 11529.5 12000.6 12194.4 10894.3 11284.4 11490.5 11611.0 11846.6 11443.7 11757.5 12055.3 12263.6 12357.4 8878.0 8937.3 9131.3 9159.2 9187.4 8668.1 10915.5 11982.8 12047.7 12198.5 06:30:40 INFO - PROCESS | 11805 | 06:30:40 INFO - PROCESS | 11805 | [#3] mootools.html Cycles:180 Average:6049.01 Median:2526.26 stddev:3513.83 (139.1%) stddev-sans-first:3506.17 06:30:40 INFO - PROCESS | 11805 | Values: 1379.8 1557.8 1573.3 1756.3 1869.3 8735.8 9033.2 9045.6 9244.9 9287.9 9036.2 9260.4 9264.4 9265.1 9277.5 2065.4 2326.3 2378.5 2396.9 2407.1 2388.2 2402.2 2410.2 2414.5 2414.6 2170.1 2246.2 2248.5 2250.6 2252.8 2018.5 2274.4 2324.4 2326.8 2360.4 2293.0 2310.7 2311.7 2315.1 2357.1 9309.3 9352.5 9379.2 9388.4 9392.4 5316.5 5437.7 5441.5 5442.6 5450.2 3373.7 3375.6 3420.8 3467.4 3491.2 1066.6 1077.3 1095.0 1095.5 1114.3 13095.9 13239.0 13240.3 13248.5 13256.8 4590.2 4628.0 4708.8 4724.9 4726.9 10399.3 10413.7 10430.1 10457.8 10460.2 4819.8 5414.6 5470.0 5492.4 5495.2 5055.6 5166.9 5178.6 5201.7 5213.7 3792.5 3816.5 3886.3 3920.8 3942.3 1131.9 1159.5 1160.1 1165.2 1176.0 1036.4 1044.9 1051.1 1051.2 1054.3 6308.4 6354.1 6366.1 6371.2 6480.2 462.3 462.8 466.3 471.9 479.0 4356.5 4472.6 4532.4 4570.8 4585.2 11113.3 11578.5 11593.1 11634.1 11652.0 10746.0 11125.8 11144.2 11471.4 11502.8 8568.5 8673.7 8712.4 8717.2 8760.3 6133.6 6262.5 6304.1 6343.6 6364.3 10478.5 10626.2 10642.1 10693.5 10771.6 10245.6 10600.2 10626.0 10887.3 11019.4 7400.0 7609.4 7924.7 8122.8 8314.2 8993.1 9070.8 9142.9 9155.7 9163.5 7816.0 7992.1 8014.2 8068.1 8079.8 7759.4 7894.6 7915.0 7991.9 8235.1 7484.4 7498.1 7557.1 7667.8 7678.6 5293.7 5423.9 5544.2 5591.1 5653.6 5671.9 5754.2 5765.8 5779.7 5805.4 06:30:40 INFO - PROCESS | 11805 | 06:30:40 INFO - PROCESS | 11805 | [#4] prototype.html Cycles:180 Average:4741.56 Median:5371.76 stddev:1379.66 (25.7%) stddev-sans-first:1343.27 06:30:40 INFO - PROCESS | 11805 | Values: 333.6 335.5 337.4 346.8 352.8 5396.8 5402.4 5408.5 5438.1 5494.1 5338.3 5358.9 5364.5 5369.9 5373.7 4045.1 4124.0 4186.5 4236.4 4268.0 4198.6 4225.5 4233.5 4246.2 4255.8 3990.2 4153.3 4185.6 4218.3 4223.6 4077.8 4083.1 4105.8 4217.8 4221.4 3748.2 3760.8 3767.7 3769.2 3779.4 5104.9 5180.6 5182.7 5183.8 5219.8 4780.9 4795.0 4807.4 4815.6 4826.5 5570.6 5577.4 5619.4 5625.9 5697.3 4110.8 4197.7 4203.2 4209.8 4212.0 5786.4 5801.3 5824.9 5842.5 5858.6 4199.5 4199.6 4206.5 4228.6 4301.0 4315.2 4326.6 4327.4 4346.2 4351.8 4520.2 4711.9 4723.6 4730.3 4773.8 5618.7 5628.2 5630.7 5676.7 5722.2 1432.6 1440.1 1458.6 1459.8 1462.7 915.6 940.0 942.8 943.7 977.8 5304.7 5341.4 5341.6 5351.6 5362.7 5589.8 5646.5 5646.5 5687.2 5695.7 5089.5 5340.5 5340.5 5353.7 5362.5 2999.5 3011.8 3022.7 3040.0 3044.9 5827.0 5831.3 5846.8 5847.3 5851.5 5629.7 5934.5 5937.8 5985.3 6009.9 5840.4 5947.6 5950.3 5960.1 5994.3 5517.6 5751.5 5786.9 5823.2 5835.4 5700.5 5726.5 5738.8 5739.2 5751.1 5760.0 5924.7 5927.8 5932.9 5972.8 4126.9 4183.1 4200.5 4201.0 4209.6 5713.9 5730.9 5751.5 5769.8 5787.2 5346.3 5603.9 5631.9 5632.2 5662.8 5555.3 5581.7 5582.2 5596.3 5683.4 5309.3 5417.6 5463.7 5540.9 5617.6 5612.8 5644.6 5645.2 5700.3 5734.6 5545.0 5557.3 5581.3 5587.6 5597.2 06:30:40 INFO - PROCESS | 11805 | 06:30:40 INFO - PROCESS | 11805 | [#5] yui.html Cycles:180 Average:16013.55 Median:4840.26 stddev:19339.97 (399.6%) stddev-sans-first:19360.01 06:30:40 INFO - PROCESS | 11805 | Values: 695.3 733.8 795.2 847.3 896.4 14292.9 15226.5 15300.0 15377.5 15582.6 14636.0 14691.8 14702.5 14703.3 14726.2 706.3 826.1 827.1 844.3 845.3 838.7 850.4 853.0 853.6 854.2 883.8 889.8 890.2 890.4 890.8 736.4 860.9 886.6 888.5 888.5 638.9 641.9 643.5 643.6 644.3 13837.7 13859.4 13876.0 13882.2 13896.9 14235.4 14477.9 14593.3 14608.3 14849.6 12030.7 12377.8 12822.4 13036.5 13140.2 4470.9 4559.2 4567.5 4569.6 4574.6 60538.4 60686.0 61348.1 62735.7 62827.7 11347.3 11444.9 11573.4 11585.9 11612.5 20650.9 20832.5 20834.4 21102.4 21306.4 8272.7 8429.9 8514.6 8515.9 8544.8 4771.0 4839.2 4841.3 4844.3 4916.6 3496.1 3627.2 3629.1 3632.1 3642.4 673.1 686.6 697.3 697.9 699.1 610.0 617.8 692.6 697.3 705.8 8702.4 8719.8 8814.8 8863.6 9157.6 660.2 673.9 696.7 711.2 713.4 4109.9 4114.2 4151.9 4165.9 4212.4 67817.3 68653.0 69838.3 70802.6 70861.6 61949.4 62244.0 62415.4 62590.0 63259.4 55247.4 55403.2 56417.4 56519.7 56651.7 8119.2 8251.4 8274.9 8350.7 8413.6 35163.3 37529.2 37822.6 37986.5 38545.6 46052.3 51413.7 51416.5 51900.2 53376.7 12105.9 12456.7 12496.2 13009.5 13401.3 12716.0 12959.8 13524.6 13534.5 14429.7 10001.9 10993.8 11235.3 12559.8 13331.7 13226.7 13693.6 14017.9 14067.7 14087.7 13257.6 13342.8 13404.5 13420.2 14001.9 9976.0 10080.5 10270.4 10380.5 10578.3 9915.4 10184.5 10187.6 10210.0 10211.4 06:30:40 INFO - PROCESS | 11805 | -------- Summary: end -------- 06:30:40 INFO - PROCESS | 11805 | 06:30:41 INFO - TEST-INFO | 11805: exit 0 06:30:41 INFO - TEST-OK | dromaeo_css | took 1112483ms 06:30:41 INFO - TEST-START | kraken 06:30:41 INFO - operating with platform_type : linux_ 06:30:41 INFO - Initialising browser for kraken test... 06:30:41 INFO - TEST-INFO | started process 11990 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpv5D2qS/profile http://localhost:54075/getInfo.html) 06:30:44 INFO - PROCESS | 11990 | JavaScript error: resource://app/components/nsBrowserGlue.js, line 1273: ReferenceError: E10SUINotification is not defined 06:30:44 INFO - PROCESS | 11990 | __metrics Screen width/height:1600/1200 06:30:44 INFO - PROCESS | 11990 | colorDepth:24 06:30:44 INFO - PROCESS | 11990 | Browser inner width/height: 1024/697 06:30:44 INFO - PROCESS | 11990 | __metrics 06:30:45 ERROR - PROCESS | 11990 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 06:30:46 INFO - TEST-INFO | 11990: exit 0 06:30:46 INFO - Browser initialized. 06:30:46 INFO - Running cycle 1/1 for kraken test... 06:30:46 INFO - TEST-INFO | started process 12070 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpv5D2qS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:30:58 INFO - PROCESS | 12070 | RSS: Main: 152764416 06:30:58 INFO - PROCESS | 12070 | 06:31:01 INFO - PROCESS | 12070 | Cycle 1(1): loaded http://localhost:54075/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54075/tests/kraken/driver.html?testName=audio-beat-detection) 06:31:01 INFO - PROCESS | 12070 | RSS: Main: 208613376 06:31:01 INFO - PROCESS | 12070 | 06:31:04 INFO - PROCESS | 12070 | Cycle 1(1): loaded http://localhost:54075/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54075/tests/kraken/driver.html?testName=audio-dft) 06:31:05 INFO - PROCESS | 12070 | RSS: Main: 198557696 06:31:05 INFO - PROCESS | 12070 | 06:31:08 INFO - PROCESS | 12070 | Cycle 1(1): loaded http://localhost:54075/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54075/tests/kraken/driver.html?testName=audio-fft) 06:31:09 INFO - PROCESS | 12070 | RSS: Main: 265043968 06:31:09 INFO - PROCESS | 12070 | 06:31:11 INFO - PROCESS | 12070 | Cycle 1(1): loaded http://localhost:54075/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54075/tests/kraken/driver.html?testName=audio-oscillator) 06:31:11 INFO - PROCESS | 12070 | RSS: Main: 174620672 06:31:11 INFO - PROCESS | 12070 | 06:31:14 INFO - PROCESS | 12070 | Cycle 1(1): loaded http://localhost:54075/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54075/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:31:14 INFO - PROCESS | 12070 | RSS: Main: 178794496 06:31:14 INFO - PROCESS | 12070 | 06:31:17 INFO - PROCESS | 12070 | Cycle 1(1): loaded http://localhost:54075/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54075/tests/kraken/driver.html?testName=imaging-darkroom) 06:31:18 INFO - PROCESS | 12070 | RSS: Main: 215097344 06:31:18 INFO - PROCESS | 12070 | 06:31:22 INFO - PROCESS | 12070 | Cycle 1(1): loaded http://localhost:54075/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54075/tests/kraken/driver.html?testName=imaging-desaturate) 06:31:22 INFO - PROCESS | 12070 | RSS: Main: 212590592 06:31:22 INFO - PROCESS | 12070 | 06:31:25 INFO - PROCESS | 12070 | Cycle 1(1): loaded http://localhost:54075/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54075/tests/kraken/driver.html?testName=json-parse-financial) 06:31:26 INFO - PROCESS | 12070 | RSS: Main: 213635072 06:31:26 INFO - PROCESS | 12070 | 06:31:27 INFO - PROCESS | 12070 | Cycle 1(1): loaded http://localhost:54075/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54075/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:31:28 INFO - PROCESS | 12070 | RSS: Main: 203485184 06:31:28 INFO - PROCESS | 12070 | 06:31:30 INFO - PROCESS | 12070 | Cycle 1(1): loaded http://localhost:54075/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54075/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:31:30 INFO - PROCESS | 12070 | RSS: Main: 208064512 06:31:30 INFO - PROCESS | 12070 | 06:31:32 INFO - PROCESS | 12070 | Cycle 1(1): loaded http://localhost:54075/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54075/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:31:33 INFO - PROCESS | 12070 | RSS: Main: 217477120 06:31:33 INFO - PROCESS | 12070 | 06:31:36 INFO - PROCESS | 12070 | Cycle 1(1): loaded http://localhost:54075/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54075/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:31:36 INFO - PROCESS | 12070 | RSS: Main: 222736384 06:31:36 INFO - PROCESS | 12070 | 06:31:39 INFO - PROCESS | 12070 | Cycle 1(1): loaded http://localhost:54075/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54075/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:31:40 INFO - PROCESS | 12070 | RSS: Main: 219865088 06:31:40 INFO - PROCESS | 12070 | 06:31:41 INFO - PROCESS | 12070 | Cycle 1(1): loaded http://localhost:54075/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54075/tests/kraken/driver.html?testName=ai-astar) 06:31:42 INFO - PROCESS | 12070 | RSS: Main: 265297920 06:31:42 INFO - PROCESS | 12070 | 06:31:42 INFO - PROCESS | 12070 | __start_tp_report 06:31:42 INFO - PROCESS | 12070 | _x_x_mozilla_page_load 06:31:42 INFO - PROCESS | 12070 | _x_x_mozilla_page_load_details 06:31:42 INFO - PROCESS | 12070 | |i|pagename|runs| 06:31:42 INFO - PROCESS | 12070 | |0;ai-astar;110;104;109;109;110;109;109;110;109;109 06:31:42 INFO - PROCESS | 12070 | |1;audio-beat-detection;155;123;155;213;124;153;146;127;191;125 06:31:42 INFO - PROCESS | 12070 | |2;audio-dft;162;163;163;162;162;161;163;163;161;161 06:31:42 INFO - PROCESS | 12070 | |3;audio-fft;75;76;75;157;75;75;77;74;149;73 06:31:42 INFO - PROCESS | 12070 | |4;audio-oscillator;92;92;150;93;92;92;119;92;91;123 06:31:42 INFO - PROCESS | 12070 | |5;imaging-gaussian-blur;116;116;117;117;117;117;116;117;117;116 06:31:42 INFO - PROCESS | 12070 | |6;imaging-darkroom;146;146;145;146;145;145;145;145;146;152 06:31:42 INFO - PROCESS | 12070 | |7;imaging-desaturate;94;95;94;95;95;94;95;95;95;95 06:31:42 INFO - PROCESS | 12070 | |8;json-parse-financial;53;53;54;55;54;54;54;108;52;53 06:31:42 INFO - PROCESS | 12070 | |9;json-stringify-tinderbox;59;59;60;59;60;60;60;60;60;59 06:31:42 INFO - PROCESS | 12070 | |10;stanford-crypto-aes;84;79;78;78;84;82;79;80;86;84 06:31:42 INFO - PROCESS | 12070 | |11;stanford-crypto-ccm;120;121;120;118;128;130;118;124;186;127 06:31:42 INFO - PROCESS | 12070 | |12;stanford-crypto-pbkdf2;160;165;159;159;159;159;163;160;161;162 06:31:42 INFO - PROCESS | 12070 | |13;stanford-crypto-sha256-iterative;62;62;63;62;62;61;64;69;62;65 06:31:42 INFO - PROCESS | 12070 | __end_tp_report 06:31:42 INFO - PROCESS | 12070 | __start_cc_report 06:31:42 INFO - PROCESS | 12070 | _x_x_mozilla_cycle_collect,1857 06:31:42 INFO - PROCESS | 12070 | __end_cc_report 06:31:42 INFO - PROCESS | 12070 | __startTimestamp1463232702155__endTimestamp 06:31:42 INFO - PROCESS | 12070 | 06:31:42 INFO - PROCESS | 12070 | ------- Summary: start ------- 06:31:42 INFO - PROCESS | 12070 | Number of tests: 14 06:31:42 INFO - PROCESS | 12070 | 06:31:42 INFO - PROCESS | 12070 | [#0] ai-astar Cycles:10 Average:108.80 Median:109.00 stddev:1.75 (1.6%) stddev-sans-first:1.80 06:31:42 INFO - PROCESS | 12070 | Values: 110.0 104.0 109.0 109.0 110.0 109.0 109.0 110.0 109.0 109.0 06:31:42 INFO - PROCESS | 12070 | 06:31:42 INFO - PROCESS | 12070 | [#1] audio-beat-detection Cycles:10 Average:151.20 Median:154.00 stddev:30.31 (19.7%) stddev-sans-first:32.12 06:31:42 INFO - PROCESS | 12070 | Values: 155.0 123.0 155.0 213.0 124.0 153.0 146.0 127.0 191.0 125.0 06:31:42 INFO - PROCESS | 12070 | 06:31:42 INFO - PROCESS | 12070 | [#2] audio-dft Cycles:10 Average:162.10 Median:162.50 stddev:0.88 (0.5%) stddev-sans-first:0.93 06:31:42 INFO - PROCESS | 12070 | Values: 162.0 163.0 163.0 162.0 162.0 161.0 163.0 163.0 161.0 161.0 06:31:42 INFO - PROCESS | 12070 | 06:31:42 INFO - PROCESS | 12070 | [#3] audio-fft Cycles:10 Average:90.60 Median:75.00 stddev:32.96 (43.9%) stddev-sans-first:34.47 06:31:42 INFO - PROCESS | 12070 | Values: 75.0 76.0 75.0 157.0 75.0 75.0 77.0 74.0 149.0 73.0 06:31:42 INFO - PROCESS | 12070 | 06:31:42 INFO - PROCESS | 12070 | [#4] audio-oscillator Cycles:10 Average:103.60 Median:92.00 stddev:20.30 (22.1%) stddev-sans-first:21.10 06:31:42 INFO - PROCESS | 12070 | Values: 92.0 92.0 150.0 93.0 92.0 92.0 119.0 92.0 91.0 123.0 06:31:42 INFO - PROCESS | 12070 | 06:31:42 INFO - PROCESS | 12070 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.50 06:31:42 INFO - PROCESS | 12070 | Values: 116.0 116.0 117.0 117.0 117.0 117.0 116.0 117.0 117.0 116.0 06:31:42 INFO - PROCESS | 12070 | 06:31:42 INFO - PROCESS | 12070 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:2.13 (1.5%) stddev-sans-first:2.26 06:31:42 INFO - PROCESS | 12070 | Values: 146.0 146.0 145.0 146.0 145.0 145.0 145.0 145.0 146.0 152.0 06:31:42 INFO - PROCESS | 12070 | 06:31:42 INFO - PROCESS | 12070 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 06:31:42 INFO - PROCESS | 12070 | Values: 94.0 95.0 94.0 95.0 95.0 94.0 95.0 95.0 95.0 95.0 06:31:42 INFO - PROCESS | 12070 | 06:31:42 INFO - PROCESS | 12070 | [#8] json-parse-financial Cycles:10 Average:59.00 Median:54.00 stddev:17.24 (31.9%) stddev-sans-first:18.15 06:31:42 INFO - PROCESS | 12070 | Values: 53.0 53.0 54.0 55.0 54.0 54.0 54.0 108.0 52.0 53.0 06:31:42 INFO - PROCESS | 12070 | 06:31:42 INFO - PROCESS | 12070 | [#9] json-stringify-tinderbox Cycles:10 Average:59.60 Median:60.00 stddev:0.52 (0.9%) stddev-sans-first:0.50 06:31:42 INFO - PROCESS | 12070 | Values: 59.0 59.0 60.0 59.0 60.0 60.0 60.0 60.0 60.0 59.0 06:31:42 INFO - PROCESS | 12070 | 06:31:42 INFO - PROCESS | 12070 | [#10] stanford-crypto-aes Cycles:10 Average:81.40 Median:83.00 stddev:2.95 (3.6%) stddev-sans-first:2.98 06:31:42 INFO - PROCESS | 12070 | Values: 84.0 79.0 78.0 78.0 84.0 82.0 79.0 80.0 86.0 84.0 06:31:42 INFO - PROCESS | 12070 | 06:31:42 INFO - PROCESS | 12070 | [#11] stanford-crypto-ccm Cycles:10 Average:129.20 Median:125.50 stddev:20.41 (16.3%) stddev-sans-first:21.37 06:31:42 INFO - PROCESS | 12070 | Values: 120.0 121.0 120.0 118.0 128.0 130.0 118.0 124.0 186.0 127.0 06:31:42 INFO - PROCESS | 12070 | 06:31:42 INFO - PROCESS | 12070 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.70 Median:160.50 stddev:2.06 (1.3%) stddev-sans-first:2.17 06:31:42 INFO - PROCESS | 12070 | Values: 160.0 165.0 159.0 159.0 159.0 159.0 163.0 160.0 161.0 162.0 06:31:42 INFO - PROCESS | 12070 | 06:31:42 INFO - PROCESS | 12070 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.20 Median:62.50 stddev:2.35 (3.8%) stddev-sans-first:2.45 06:31:42 INFO - PROCESS | 12070 | Values: 62.0 62.0 63.0 62.0 62.0 61.0 64.0 69.0 62.0 65.0 06:31:42 INFO - PROCESS | 12070 | -------- Summary: end -------- 06:31:42 INFO - PROCESS | 12070 | 06:31:43 INFO - TEST-INFO | 12070: exit 0 06:31:43 INFO - TEST-OK | kraken | took 62100ms 06:31:43 INFO - SUITE-END | took 1174s 06:31:43 INFO - Completed test suite (00:19:34) 06:31:43 INFO - javascript benchmark 06:31:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3179.7933134346267, 3180.8091514509133, 3204.679532046795, 3218.7907785993925, 3241.35172965407, 5331.520163185312, 5341.919871201931, 5391.353037635479, 5420.214068960004, 5479.753411096501, 5353.518183363496, 5360.839174824751, 5402.945970540293, 5442.537384322328, 5488.807891723791, 4828.879278018051, 4906.656534042619, 4925.285833554132, 4943.233798761198, 5183.688978661293, 5074.162763144095, 5092.796288148488, 5115.028144652522, 5143.0, 5147.871303217431, 4866.416030076378, 4868.975655121737, 4900.313956046157, 4922.26166075088, 4928.162212423897, 4911.312416261726, 4923.08922849274, 4997.05056039355, 4997.950020499785, 5043.9912017596625, 4904.5831104356175, 4982.153033984231, 4986.027724593706, 4998.900021999576, 5011.774470148815, 5316.255724198615, 5383.757730902119, 5393.002294575477, 5444.91832622511, 5485.862853428696, 5837.620554663933, 5839.8540036499, 5857.121431785224, 5870.530357571384, 5891.23413956183, 5658.321001479849, 5782.537397008229, 5824.854378640525, 5937.703114844284, 5976.043832986745, 5532.75102620383, 5544.528715059185, 5545.445455454423, 5554.333479982428, 5555.111182210827, 6030.246219222598, 6043.939560604425, 6045.455908968214, 6048.576599637983, 6062.848428789315, 5620.241267428926, 5660.0, 5683.460071293221, 5688.345840228427, 5823.272090988627, 5809.622374545641, 5816.563757718103, 5818.156367326798, 5865.912011319833, 5869.794557190563, 5811.593188476851, 5813.76744930198, 5817.418258174233, 5820.010598198316, 5821.738021788945, 5712.600117991784, 5748.223989761329, 5757.568182386254, 5803.477687008105, 5862.296524417097, 4357.302831546937, 4359.106383191438, 4362.258416069212, 4366.497852746911, 4395.0, 3848.441975913533, 3880.417937309426, 3917.1186483041656, 3941.0541470046983, 3970.563238043813, 5419.99730049942, 5472.753726082336, 5479.4520547944885, 5482.068048431696, 5505.862353441116, 5824.970875145682, 5850.765969361178, 5852.912206316909, 5858.150568167592, 5878.735456904449, 5426.782928682887, 5458.781648734006, 5502.724863756796, 5505.064139096363, 5509.862253443696, 4866.367372241586, 4886.218205087169, 4890.779914903838, 4890.779914903838, 4934.8766280843265, 6000.939990600125, 6002.309734380515, 6010.729517171738, 6011.098335249748, 6013.398660133952, 5682.801101961411, 5839.5036421904615, 5883.970580147072, 5884.293884733688, 5907.698438395093, 6031.788887388921, 6038.094285857156, 6038.5772995889865, 6039.15451836752, 6055.333913269449, 5833.066709326487, 5844.736986835517, 5845.18167456548, 5847.94152058474, 5861.1501332307425, 5677.744501497526, 5826.883462330815, 5862.472377486047, 5887.20522729426, 5921.259842519685, 5729.169270455844, 5735.340435849931, 5750.338711048115, 5752.395998420106, 5770.480656740913, 5696.344920334214, 5730.942690573041, 5737.827865164052, 5760.337561180518, 5763.596548241583, 5465.426130256425, 5518.255035570307, 5573.804916828054, 5584.972075139598, 5649.406812284651, 5772.9711351442975, 5776.56675749312, 5779.162021506942, 5831.970840145942, 5834.824955251349, 5420.024395608671, 5638.295213098363, 5644.435556444323, 5645.0, 5686.971565142313], "value": 5374.0067444821625, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [612.2805703298623, 644.6744394080988, 657.1752450674403, 662.2086606505225, 668.0046730371741, 31684.366312673763, 44250.336244956314, 44774.7761261194, 44787.328190077176, 44826.77586612067, 46350.304745428846, 46961.53038469614, 46988.0602387952, 46993.76503117479, 47031.82420439491, 8916.821663566709, 9049.2308153807, 9051.637934482613, 9062.139096785828, 9062.682806101788, 8893.066228046058, 8967.865482017742, 8979.595918183664, 8994.10058994102, 9002.32482563807, 9038.774030649221, 9062.637494500212, 9067.773305667346, 9070.954645226764, 9073.591688374006, 8961.820763584725, 9000.819983600357, 9006.729798106067, 9007.864882026775, 9009.909900900973, 8864.556772161366, 8956.820863582758, 8962.193402593733, 8975.506347150904, 8981.955090224506, 43217.0, 43439.13121737579, 43485.0, 43610.56389436097, 43634.34548481775, 3540.9364608609676, 3619.674229319374, 3730.757500762442, 4027.8791636250958, 4183.393407955829, 33143.83428082868, 33712.0, 34108.48837267443, 34294.48558271628, 34533.309333813435, 3773.3773927301963, 3850.979490435037, 3906.609339066099, 3933.8229779659982, 4038.959610403888, 367551.0, 369673.1516342428, 370089.0, 370182.0, 370932.2906770952, 61533.692331538055, 62720.0, 62730.0, 62737.0, 62891.685541572, 55042.0, 55260.72369638126, 55309.44690553043, 58872.0, 65708.6714566424, 16420.507384778477, 16451.753223701653, 16453.753193702105, 16544.6691066178, 16552.668946621, 13310.0, 13465.798013029813, 13488.05583609138, 13501.189928604315, 13503.392347344197, 17376.565585860455, 17761.957374901795, 17820.287188512575, 19250.80749192518, 19461.08081801294, 7256.528325658815, 7460.515066520658, 7476.401887848959, 7480.848129288297, 7603.695852165852, 1013.3362647465922, 1091.9344839309667, 1111.8776934537195, 1137.0505627800715, 1158.3860553906443, 32501.349973000408, 33670.0, 33684.157896052304, 33809.323813523595, 34063.65936340605, 1147.9770404591861, 1163.2903928603546, 1193.1111322065117, 1234.5185377702703, 1251.3492983648453, 17150.914245428692, 17517.386891458736, 17902.46292611224, 18167.104592728625, 19158.32945846889, 603293.0, 603607.0, 604641.0, 606651.0, 607633.0, 58726.412735872946, 60151.69924150351, 60154.69922650359, 60303.69848150731, 60362.69818650967, 1425.301602214928, 1447.3486930881145, 1453.1862157192006, 1461.576142918563, 1488.4120772294882, 32276.67723322737, 33716.0, 33856.66143338535, 34264.828675856465, 34275.65724342824, 50645.746771265905, 50958.0, 51391.48608513867, 51401.7429912848, 51457.0, 516436.0, 519054.0, 519054.0, 519946.0, 521533.0, 26494.205173844817, 27713.0, 28280.29299267535, 28303.575446368734, 28703.856480717463, 48371.0, 48966.0, 49260.2610960843, 49666.255006175656, 49787.50212497829, 40570.39144412895, 40915.38626920658, 41590.37614435725, 41945.0, 42854.0, 40295.79852100721, 40816.16327265221, 41021.17957640771, 41693.0, 41718.79140604399, 40998.79500602478, 41202.38196426996, 42758.0, 43179.78410107929, 43280.56719432891, 36661.266774663825, 37865.242695146495, 38301.808490957366, 39193.07781463584, 39276.607233927294, 29294.121176364744, 29376.55935161017, 29390.41219175562, 29540.0, 29578.704212958455], "value": 20806.97163369865, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [413.9999999999996, 420.5479109956795, 422.1915032712356, 426.2859709985774, 432.3320469874046, 18093.728594071083, 19145.90853941671, 19333.51666208344, 19978.700319495183, 20030.09864556095, 18399.90800045998, 18449.53876153097, 19126.330578429723, 19157.90421047893, 19187.712184317246, 5413.188021796733, 5625.071863142597, 5774.307083150028, 5787.565932555054, 5789.681567513785, 4068.247374235776, 5668.178114173463, 5683.54531637468, 5926.762929482816, 6219.813405597839, 6273.56085074045, 6298.716557754912, 6311.147995020682, 6313.810585682437, 6315.905261421082, 6246.843828904268, 6374.968125159345, 6399.200099987502, 6400.839979000516, 6401.295857455676, 3792.2984247914223, 3952.2490726761825, 5390.380106287748, 5476.439214823796, 5615.298087739033, 22247.22134725277, 22627.886860565755, 22839.885800571057, 23117.76882231173, 23259.88370058156, 2603.0368763557417, 2683.771879390254, 3012.487877060905, 3094.1336425800587, 3145.2765863851214, 13501.59495215145, 13513.378394592613, 13524.59426217215, 13546.390412431465, 13619.523316683868, 1907.1894444861018, 1994.7307113539632, 2014.8388128949648, 2061.793820617941, 2091.5816836632735, 71585.64207178983, 74203.25796742071, 75472.0, 76534.23465765327, 87404.56297718534, 10809.459527023775, 10815.729606759895, 10888.891111088788, 10890.945545272381, 11194.608188713355, 15313.923430382776, 15391.769123463157, 15405.691886162214, 15423.0, 15442.613934651723, 8150.307223886036, 8334.623548507929, 8359.791005224797, 8416.158384161536, 8478.23695867375, 8121.4721043132, 8285.917140828515, 8421.452605580618, 8515.403921725543, 8551.750334872708, 4748.905021899543, 4761.6904901181315, 4790.281457781361, 4808.35087263222, 5202.635815492879, 1778.04820336004, 1857.5634725839418, 1926.624308259904, 2034.979650203479, 2037.9184832606827, 936.4849332866896, 968.4915419404728, 1048.0043958239703, 1058.439027315524, 1084.8210045342587, 18616.255349786126, 19224.519387015436, 19315.130819113172, 19361.32235371755, 19492.684771091266, 1033.5194134727387, 1037.273908264218, 1048.250500891866, 1056.4189695667505, 1099.5326986030905, 10440.895591044144, 10911.508982095826, 10970.670879873616, 10982.121430285559, 11010.50452729629, 479923.0, 481949.0, 484517.0, 498080.0, 501054.0, 89354.55322723345, 89440.0, 90999.54500227456, 91214.0, 91774.0, 1024.9282550221562, 1038.0588299879273, 1050.4275170032352, 1054.767951050783, 1068.3376306689902, 16620.335186592885, 16931.66136677284, 17063.0, 17081.743773843154, 17339.133043348036, 39838.0, 39973.40039899346, 40456.59543404528, 40589.0, 41414.47976921875, 55887.44112558985, 56680.0, 56753.432465674814, 57034.0, 57107.71446142743, 8828.867566986371, 8894.777630559287, 8950.82098358016, 9108.635654574007, 9180.540972951192, 10447.68656940293, 13748.106373085717, 13961.720765584834, 13986.720265594427, 14088.436462541384, 5928.762849485972, 8712.30110128519, 11529.538818447169, 12000.61992870831, 12194.390280486117, 10894.291871028357, 11284.435778210893, 11490.54037838477, 11611.013063889326, 11846.63067386509, 11443.713907152387, 11757.47091380907, 12055.336956467478, 12263.570775022832, 12357.443915023665, 8877.955610221907, 8937.329700272376, 9131.315151363542, 9159.22146617545, 9187.356885017985, 8668.0898505656, 10915.482408396972, 11982.820257695967, 12047.698807529881, 12198.51205951752], "value": 9374.087519887822, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1379.8137251471048, 1557.8130624325079, 1573.2527049651417, 1756.306259027684, 1869.2896699254281, 8735.825283494327, 9033.23217526511, 9045.63817447302, 9244.8613270801, 9287.907120928789, 9036.231920286784, 9260.351775375726, 9264.444133352004, 9265.073492650732, 9277.536123193848, 2065.4216819290546, 2326.255598208574, 2378.4767351182713, 2396.940076498084, 2407.145463360508, 2388.164142550102, 2402.231285988484, 2410.192585483865, 2414.4929564791328, 2414.601590737535, 2170.1427935965285, 2246.168917500519, 2248.539049494858, 2250.561140577588, 2252.7859853313907, 2018.5458271888854, 2274.351809734226, 2324.4421338878715, 2326.790588847012, 2360.3509035015336, 2292.977070229293, 2310.7111611048617, 2311.653252012195, 2315.0855412112123, 2357.0571771291447, 9309.30180236485, 9352.485613291266, 9379.202767764747, 9388.436693798325, 9392.389494682891, 5316.494932981399, 5437.673739575598, 5441.483059109379, 5442.6462279951675, 5450.182472629098, 3373.6795004474534, 3375.645557216506, 3420.794752314844, 3467.3758723429773, 3491.17957280039, 1066.5733706517378, 1077.3320541264466, 1094.9726256843562, 1095.4522738630685, 1114.3481063577817, 13095.934520327433, 13239.0, 13240.271785051818, 13248.470061197444, 13256.801147982787, 4590.219662657356, 4628.0, 4708.835190768306, 4724.881877953078, 4726.88182795426, 10399.27205095636, 10413.687589372332, 10430.113440357503, 10457.790844183073, 10460.215483838743, 4819.783109760069, 5414.6209765316835, 5469.988052210274, 5492.368377636623, 5495.175723641406, 5055.646104772704, 5166.8966620668125, 5178.6116041297055, 5201.6878987260125, 5213.687178769286, 3792.50697409335, 3816.484774555455, 3886.3393223152134, 3920.843166273317, 3942.3245120823535, 1131.8868113188614, 1159.4840296068337, 1160.0951258018758, 1165.155262434728, 1175.988240117605, 1036.3729943384294, 1044.9111825494917, 1051.106559424483, 1051.1800795379615, 1054.2830875004918, 6308.369163083655, 6354.110424540568, 6366.076918846834, 6371.23545174582, 6480.157579514727, 462.3065401897154, 462.7986825730818, 466.3354719524664, 471.9032598317337, 479.0156228949542, 4356.542563030899, 4472.575105364985, 4532.410786597721, 4570.8171673133365, 4585.151746926829, 11113.277636953571, 11578.536858525733, 11593.130515211393, 11634.127440441831, 11652.0, 10746.0, 11125.833112503318, 11144.164187685956, 11471.4264286787, 11502.769944601061, 8568.52873091986, 8673.739787806377, 8712.433691810013, 8717.171868672465, 8760.255378292917, 6133.601315914451, 6262.530310226843, 6304.085907543289, 6343.619382837044, 6364.268109167319, 10478.528466218886, 10626.203034772268, 10642.148628109888, 10693.518791654238, 10771.569137234423, 10245.641402550875, 10600.204984626029, 10626.043656070991, 10887.346759194474, 11019.449027548751, 7399.963000184965, 7609.391248700202, 7924.683012679428, 8122.75631731049, 8314.16858314188, 8993.100689930954, 9070.773230669287, 9142.908570914205, 9155.725328240163, 9163.450192988443, 7815.960920195363, 7992.080910695345, 8014.19858014194, 8068.112507624274, 8079.83840323202, 7759.379249660126, 7894.644740986554, 7914.960425197837, 7991.880121798294, 8235.094139644754, 7484.43866709988, 7498.062742157231, 7557.130929943127, 7667.769966901002, 7678.61606919663, 5293.708846013506, 5423.9186412204635, 5544.168374743821, 5591.077472217201, 5653.604247702621, 5671.914921276267, 5754.165645981393, 5765.827025189251, 5779.653220806765, 5805.448482394166], "value": 4707.155669009478, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [333.5580356028262, 335.4599095456313, 337.4061651493371, 346.7711310535047, 352.75836052304174, 5396.811111611094, 5402.37872644646, 5408.459154084594, 5438.129899216116, 5494.148406996912, 5338.27933229014, 5358.946410535894, 5364.463553644634, 5369.865753356158, 5373.650712703671, 4045.110075783323, 4123.958760412403, 4186.49762028557, 4236.449261596003, 4267.997020700133, 4198.643115335185, 4225.535191128974, 4233.534311225762, 4246.214450326699, 4255.8084886180195, 3990.1620659661507, 4153.252414565377, 4185.644220241283, 4218.282891908368, 4223.577642235782, 4077.7961101944784, 4083.142540066575, 4105.774182419966, 4217.8312867485265, 4221.430106935555, 3748.194138260287, 3760.8495660173835, 3767.698584113264, 3769.208466222097, 3779.4141908004303, 5104.897902041938, 5180.611454140954, 5182.730231093368, 5183.792648294064, 5219.8434046978655, 4780.856574302742, 4795.0, 4807.351007613962, 4815.6388270879825, 4826.541478559531, 5570.637908535932, 5577.414371491016, 5619.353774315965, 5625.887482250332, 5697.3477691469425, 4110.794460276974, 4197.664186865043, 4203.192627170316, 4209.810558524874, 4211.957880421187, 5786.383510306058, 5801.332846722638, 5824.912626310566, 5842.474177324061, 5858.589898707092, 4199.454070970754, 4199.643030342394, 4206.537280899099, 4228.619424251832, 4300.978495107504, 4315.201687687788, 4326.610605045561, 4327.37253098299, 4346.239408103633, 4351.825926962886, 4520.163769702615, 4711.905761884743, 4723.559314409138, 4730.314104454835, 4773.809047638125, 5618.747156377891, 5628.212050312959, 5630.690312032878, 5676.68778217202, 5722.17028530869, 1432.5531213474467, 1440.1143296872558, 1458.555140682081, 1459.8467160947948, 1462.6635873749096, 915.5971372596036, 939.9718008459621, 942.8255772682076, 943.6838659049158, 977.8435450327913, 5304.655197412156, 5341.439148889311, 5341.626086174008, 5351.57187425005, 5362.65142765719, 5589.80435684749, 5646.463585959327, 5646.548276137899, 5687.180102367202, 5695.658260504383, 5089.465606111305, 5340.519353258225, 5340.546053585411, 5353.678779273256, 5362.490563396471, 2999.5350720638335, 3011.8192908425563, 3022.7128422799797, 3039.98480007603, 3044.939101217963, 5827.018813603395, 5831.271091113611, 5846.766129354778, 5847.26909136358, 5851.473367396869, 5629.705167811345, 5934.495567876779, 5937.821865344046, 5985.25184351956, 6009.939900600938, 5840.415958404125, 5947.553933455095, 5950.315713692894, 5960.135780311917, 5994.310654274727, 5517.5585953124455, 5751.4536119068625, 5786.855328616818, 5823.213866128019, 5835.358110607915, 5700.486956173799, 5726.484616384545, 5738.770449181986, 5739.2252045973255, 5751.079827227623, 5760.0, 5924.689371145343, 5927.8221653350465, 5932.851678707894, 5972.820815375546, 4126.896827579335, 4183.079722461053, 4200.487824383158, 4201.0, 4209.557996410333, 5713.942860571341, 5730.885382292414, 5751.511121554715, 5769.807473160172, 5787.2187254720075, 5346.25152478661, 5603.8879222416135, 5631.8592035199445, 5632.211490391266, 5662.8301150965535, 5555.250041244381, 5581.665100094007, 5582.218489411404, 5596.272484576979, 5683.431656834284, 5309.309789727311, 5417.5665946723375, 5463.677936876732, 5540.944590554042, 5617.578681598815, 5612.8213075253025, 5644.576656750843, 5645.153227015816, 5700.25896633435, 5734.569907257056, 5545.0, 5557.305336832896, 5581.274434323512, 5587.608867379245, 5597.244372009889], "value": 4301.148404291828, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [695.2838576266446, 733.8202140475585, 795.244517708177, 847.2671139464366, 896.4083704754859, 14292.857071429296, 15226.543203703879, 15300.0, 15377.461788837392, 15582.610434739123, 14635.975481716285, 14691.77962330566, 14702.485413010547, 14703.264836758173, 14726.190059546721, 706.3395724997144, 826.1036775098995, 827.1397746343795, 844.3287586368851, 845.2688424512783, 838.6603425612606, 850.4472093139447, 853.0318088969003, 853.6457370191363, 854.1842540373927, 883.8099808541162, 889.8086911314099, 890.1587999340626, 890.3722875372847, 890.8084761776216, 736.3556887723269, 860.9397342185987, 886.6364790435955, 888.4802390601506, 888.5424006636555, 638.9106573292526, 641.8619996700731, 643.4691379611792, 643.6234802640461, 644.3073695777058, 13837.654058648513, 13859.4456221751, 13876.028677992545, 13882.167069975732, 13896.93051534746, 14235.359408826627, 14477.927610361881, 14593.343299551545, 14608.34262458192, 14849.554513364616, 12030.69923251917, 12377.814332785016, 12822.42299096543, 13036.478540858354, 13140.211587304791, 4470.932936005963, 4559.224931761609, 4567.497575266718, 4569.588737013652, 4574.588287054181, 60538.394616053716, 60686.0, 61348.0797788038, 62735.6863215681, 62827.685861570855, 11347.319160850375, 11444.94277528607, 11573.42132893352, 11585.942070289595, 11612.535498580131, 20650.91608019984, 20832.47918802012, 20834.374968750963, 21102.366928992156, 21306.36080917575, 8272.683648578408, 8429.873551896726, 8514.616842242114, 8515.914840851636, 8544.829103417896, 4770.952290477051, 4839.177339852234, 4841.346418233564, 4844.346013288232, 4916.631252655993, 3496.125968507873, 3627.183883626216, 3629.0745859806107, 3632.146445585285, 3642.399004164336, 673.1007176441475, 686.5537400689492, 697.3096634332077, 697.8604279144191, 699.0981633692581, 609.9573029887954, 617.8208319587269, 692.5913710910587, 697.292248367907, 705.8200158959432, 8702.39083264178, 8719.782005449915, 8814.77963050916, 8863.556822158866, 9157.633694652273, 660.1978596005875, 673.9460843132538, 696.7004188199123, 711.2034521336136, 713.3651050565002, 4109.938350924738, 4114.238865809834, 4151.875443736692, 4165.91668166635, 4212.3681447782465, 67817.32182678209, 68653.0, 69838.3016169842, 70802.64598677076, 70861.6456917712, 61949.380506194364, 62244.0, 62415.37584624095, 62590.0, 63259.367406326266, 55247.44752552423, 55403.16895246575, 56417.43582564204, 56519.71740141355, 56651.71674141603, 8119.228673276029, 8251.422400431911, 8274.875876861732, 8350.707725229588, 8413.621387037476, 35163.29673406569, 37529.24941501209, 37822.62177378191, 37986.52358372848, 38545.6145438542, 46052.30921536247, 51413.7429312851, 51416.48583514117, 51900.22149667682, 53376.73311633417, 12105.939470302592, 12456.688582785502, 12496.187747796364, 13009.47962081544, 13401.262930538915, 12716.0, 12959.805602915776, 13524.59426217215, 13534.526291579747, 14429.711405772036, 10001.949990250003, 10993.835092473779, 11235.269707468988, 12559.811602826148, 13331.66670833237, 13226.66933326636, 13693.589192324247, 14017.859821401655, 14067.718645626825, 14087.718245634826, 13257.602271931857, 13342.799858002332, 13404.478778315717, 13420.194788312732, 14001.929990349983, 9975.950120249352, 10080.495975201356, 10270.399904071082, 10380.532876020749, 10578.342576706502, 9915.355501892354, 10184.490775461345, 10187.643432479828, 10210.0, 10211.387316761018], "value": 6998.021437264626, "unit": "score"}], "name": "dromaeo_css", "value": 7277.2382745922505, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 104.0, 109.0, 109.0, 110.0, 109.0, 109.0, 110.0, 109.0, 109.0], "value": 108.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 123.0, 155.0, 213.0, 124.0, 153.0, 146.0, 127.0, 191.0, 125.0], "value": 151.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 163.0, 162.0, 162.0, 161.0, 163.0, 163.0, 161.0, 161.0], "value": 162.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 157.0, 75.0, 75.0, 77.0, 74.0, 149.0, 73.0], "value": 90.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 150.0, 93.0, 92.0, 92.0, 119.0, 92.0, 91.0, 123.0], "value": 103.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 116.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 146.0, 145.0, 145.0, 145.0, 145.0, 146.0, 152.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 54.0, 55.0, 54.0, 54.0, 54.0, 108.0, 52.0, 53.0], "value": 59.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 60.0, 59.0, 60.0, 60.0, 60.0, 60.0, 60.0, 59.0], "value": 59.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 79.0, 78.0, 78.0, 84.0, 82.0, 79.0, 80.0, 86.0, 84.0], "value": 81.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 121.0, 120.0, 118.0, 128.0, 130.0, 118.0, 124.0, 186.0, 127.0], "value": 129.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 165.0, 159.0, 159.0, 159.0, 159.0, 163.0, 160.0, 161.0, 162.0], "value": 160.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 63.0, 62.0, 62.0, 61.0, 64.0, 69.0, 62.0, 65.0], "value": 63.2, "unit": "score"}], "name": "kraken", "value": 1526.8000000000004, "alertThreshold": 2.0}]} 06:31:43 INFO - Return code: 0 06:31:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 06:31:43 ERROR - # TBPL SUCCESS # 06:31:43 INFO - Running post-action listener: _resource_record_post_action 06:31:43 INFO - ##### 06:31:43 INFO - ##### Finished run-tests step (success) 06:31:43 INFO - ##### 06:31:43 INFO - Running post-run listener: _resource_record_post_run 06:31:43 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 0; Write bytes: 544149504; Read time: 0; Write time: 461632 06:31:43 INFO - TinderboxPrint: CPU usage
13.0% 06:31:43 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 06:31:43 INFO - TinderboxPrint: I/O write bytes / time
544,149,504 / 461,632 06:31:43 INFO - TinderboxPrint: CPU idle
8,278.4 (86.9%) 06:31:43 INFO - TinderboxPrint: CPU user
1,201.5 (12.6%) 06:31:43 INFO - TinderboxPrint: Swap in / out
0 / 0 06:31:43 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 254001152; Read time: 0; Write time: 259164 06:31:43 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 0; Write bytes: 290148352; Read time: 0; Write time: 202468 06:31:44 INFO - Running post-run listener: _upload_blobber_files 06:31:44 INFO - Blob upload gear active. 06:31:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:31:44 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 06:31:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:31:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:31:44 INFO - (blobuploader) - INFO - Open directory for files ... 06:31:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:31:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:31:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:31:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:31:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:31:45 INFO - (blobuploader) - INFO - Done attempting. 06:31:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:31:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:31:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:31:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:31:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:31:46 INFO - (blobuploader) - INFO - Done attempting. 06:31:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:31:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:31:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:31:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:31:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:31:50 INFO - (blobuploader) - INFO - Done attempting. 06:31:50 INFO - (blobuploader) - INFO - Iteration through files over. 06:31:50 INFO - Return code: 0 06:31:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:31:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:31:50 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6c9e59cc58e5949636d364b84c6d23ca6a43a569d83fcc6c2dfe721ab6a56383896d08a10a9001dd8569b77b583667922dc7d9f2b5b17dd80ab054d21169ce9d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/13550237546a736ae40bcdaa6dc644cc62954beb36056618e92675c10b7827a5d1af2f8f319c1ce1e4e33a52526e59658bf42b07d24ba437a3431a3c07f30540", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/95f8a4bcc10e66083d717068be5f4632f4884aee2c869068b705dd0f857edcfe5a10e74d447e5bf1f12fa496c555a4494e819c46a20481e1f6b318552a7efeef"} 06:31:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:31:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:31:50 INFO - Contents: 06:31:50 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6c9e59cc58e5949636d364b84c6d23ca6a43a569d83fcc6c2dfe721ab6a56383896d08a10a9001dd8569b77b583667922dc7d9f2b5b17dd80ab054d21169ce9d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/13550237546a736ae40bcdaa6dc644cc62954beb36056618e92675c10b7827a5d1af2f8f319c1ce1e4e33a52526e59658bf42b07d24ba437a3431a3c07f30540", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/95f8a4bcc10e66083d717068be5f4632f4884aee2c869068b705dd0f857edcfe5a10e74d447e5bf1f12fa496c555a4494e819c46a20481e1f6b318552a7efeef"} 06:31:50 INFO - Running post-run listener: copy_logs_to_upload_dir 06:31:50 INFO - Copying logs to upload dir... 06:31:50 INFO - mkdir: /builds/slave/test/build/upload/logs 06:31:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1234.687010 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-05-14 06:31:50.197317) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-14 06:31:50.201048) ========= 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=41bf3e9aed84707956354da8000001c5-1463231473.648260-192896557 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6c9e59cc58e5949636d364b84c6d23ca6a43a569d83fcc6c2dfe721ab6a56383896d08a10a9001dd8569b77b583667922dc7d9f2b5b17dd80ab054d21169ce9d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/13550237546a736ae40bcdaa6dc644cc62954beb36056618e92675c10b7827a5d1af2f8f319c1ce1e4e33a52526e59658bf42b07d24ba437a3431a3c07f30540", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/95f8a4bcc10e66083d717068be5f4632f4884aee2c869068b705dd0f857edcfe5a10e74d447e5bf1f12fa496c555a4494e819c46a20481e1f6b318552a7efeef"} build_url:https://queue.taskcluster.net/v1/task/f2B5kWO3Q9m937NqcJCxvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011338 build_url: 'https://queue.taskcluster.net/v1/task/f2B5kWO3Q9m937NqcJCxvw/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/Firefox/sha512/6c9e59cc58e5949636d364b84c6d23ca6a43a569d83fcc6c2dfe721ab6a56383896d08a10a9001dd8569b77b583667922dc7d9f2b5b17dd80ab054d21169ce9d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/13550237546a736ae40bcdaa6dc644cc62954beb36056618e92675c10b7827a5d1af2f8f319c1ce1e4e33a52526e59658bf42b07d24ba437a3431a3c07f30540", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/95f8a4bcc10e66083d717068be5f4632f4884aee2c869068b705dd0f857edcfe5a10e74d447e5bf1f12fa496c555a4494e819c46a20481e1f6b318552a7efeef"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-14 06:31:50.236288) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 06:31:50.236703) ========= 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=41bf3e9aed84707956354da8000001c5-1463231473.648260-192896557 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004657 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 06:31:50.321859) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-14 06:31:50.322240) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-14 06:31:50.322685) ========= ========= Total master_lag: 0.20 =========