builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-056 starttime: 1463957957.8 results: success (0) buildid: 20160522133012 builduid: 2f5d2a6adc5e48c196c44a05f24e7e77 revision: eeb73478d98476877f2035f0c330fdc89e361803 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-22 15:59:17.799040) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-22 15:59:17.799847) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-22 15:59:17.800134) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-22 15:59:17.823172) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-22 15:59:17.823445) ========= 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=52faae308a556c440401f8e5000001e8-1463952526.521703-1222471583 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-22 15:59:20-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 57.2K=0.2s 2016-05-22 15:59:20 (57.2 KB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.329398 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-22 15:59:18.166320) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-22 15:59:18.166655) ========= 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=52faae308a556c440401f8e5000001e8-1463952526.521703-1222471583 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.023140 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-22 15:59:18.216394) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-22 15:59:18.216764) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev eeb73478d98476877f2035f0c330fdc89e361803 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev eeb73478d98476877f2035f0c330fdc89e361803 --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=52faae308a556c440401f8e5000001e8-1463952526.521703-1222471583 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-22 15:59:20,893 truncating revision to first 12 chars 2016-05-22 15:59:20,893 Setting DEBUG logging. 2016-05-22 15:59:20,893 attempt 1/10 2016-05-22 15:59:20,893 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/eeb73478d984?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-22 15:59:21,173 unpacking tar archive at: mozilla-inbound-eeb73478d984/testing/mozharness/ program finished with exit code 0 elapsedTime=0.549439 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-22 15:59:18.779442) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-22 15:59:18.779783) ========= script_repo_revision: eeb73478d98476877f2035f0c330fdc89e361803 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-22 15:59:18.780220) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-22 15:59:18.780505) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-22 15:59:18.789005) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-05-22 15:59:18.789300) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=52faae308a556c440401f8e5000001e8-1463952526.521703-1222471583 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:59:21 INFO - MultiFileLogger online at 20160522 15:59:21 in /builds/slave/test 15:59:21 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 15:59:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:59:21 INFO - {'append_to_log': False, 15:59:21 INFO - 'base_work_dir': '/builds/slave/test', 15:59:21 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 15:59:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:59:21 INFO - 'branch': 'Mozilla-Inbound', 15:59:21 INFO - 'buildbot_json_path': 'buildprops.json', 15:59:21 INFO - 'config_files': ('talos/linux_config.py',), 15:59:21 INFO - 'default_actions': ('clobber', 15:59:21 INFO - 'read-buildbot-config', 15:59:21 INFO - 'download-and-extract', 15:59:21 INFO - 'populate-webroot', 15:59:21 INFO - 'create-virtualenv', 15:59:21 INFO - 'install', 15:59:21 INFO - 'run-tests'), 15:59:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:59:21 INFO - 'download_minidump_stackwalk': True, 15:59:21 INFO - 'download_symbols': 'ondemand', 15:59:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:59:21 INFO - 'tooltool.py': '/tools/tooltool.py', 15:59:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:59:21 INFO - '/tools/misc-python/virtualenv.py')}, 15:59:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:59:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:59:21 INFO - 'installer_path': 'installer.exe', 15:59:21 INFO - 'log_level': 'info', 15:59:21 INFO - 'log_name': 'talos', 15:59:21 INFO - 'log_to_console': True, 15:59:21 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:59:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:59:21 INFO - 'opt_config_files': (), 15:59:21 INFO - 'pip_index': False, 15:59:21 INFO - 'sps_profile': False, 15:59:21 INFO - 'sps_profile_interval': 0, 15:59:21 INFO - 'suite': 'dromaeojs', 15:59:21 INFO - 'system_bits': '32', 15:59:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:59:21 INFO - 'title': 'talos-linux64-ix-056', 15:59:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:59:21 INFO - 'use_talos_json': True, 15:59:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:59:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:59:21 INFO - 'work_dir': 'build'} 15:59:21 INFO - ##### 15:59:21 INFO - ##### Running clobber step. 15:59:21 INFO - ##### 15:59:21 INFO - Running pre-action listener: _resource_record_pre_action 15:59:21 INFO - Running main action method: clobber 15:59:21 INFO - rmtree: /builds/slave/test/build 15:59:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:59:22 INFO - Running post-action listener: _resource_record_post_action 15:59:22 INFO - ##### 15:59:22 INFO - ##### Finished clobber step (success) 15:59:22 INFO - ##### 15:59:22 INFO - ##### 15:59:22 INFO - ##### Running read-buildbot-config step. 15:59:22 INFO - ##### 15:59:22 INFO - Running pre-action listener: _resource_record_pre_action 15:59:22 INFO - Running main action method: read_buildbot_config 15:59:22 INFO - Using buildbot properties: 15:59:22 INFO - { 15:59:22 INFO - "project": "", 15:59:22 INFO - "product": "firefox", 15:59:22 INFO - "script_repo_revision": "production", 15:59:22 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 15:59:22 INFO - "repository": "", 15:59:22 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 15:59:22 INFO - "buildid": "20160522133012", 15:59:22 INFO - "pgo_build": "True", 15:59:22 INFO - "basedir": "/builds/slave/test", 15:59:22 INFO - "buildnumber": 970, 15:59:22 INFO - "slavename": "talos-linux64-ix-056", 15:59:22 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 15:59:22 INFO - "platform": "ubuntu64_hw", 15:59:22 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 15:59:22 INFO - "branch": "mozilla-inbound", 15:59:22 INFO - "repo_path": "integration/mozilla-inbound", 15:59:22 INFO - "revision": "eeb73478d98476877f2035f0c330fdc89e361803", 15:59:22 INFO - "stage_platform": "linux64-pgo", 15:59:22 INFO - "builduid": "2f5d2a6adc5e48c196c44a05f24e7e77", 15:59:22 INFO - "slavebuilddir": "test" 15:59:22 INFO - } 15:59:22 INFO - Found installer url https://queue.taskcluster.net/v1/task/AZO1vee8RUuoPb3OLCWhFQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 15:59:22 INFO - Running post-action listener: _resource_record_post_action 15:59:22 INFO - ##### 15:59:22 INFO - ##### Finished read-buildbot-config step (success) 15:59:22 INFO - ##### 15:59:22 INFO - ##### 15:59:22 INFO - ##### Running download-and-extract step. 15:59:22 INFO - ##### 15:59:22 INFO - Running pre-action listener: _resource_record_pre_action 15:59:22 INFO - Running main action method: download_and_extract 15:59:22 INFO - mkdir: /builds/slave/test/build/tests 15:59: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')]} 15:59:22 INFO - https://queue.taskcluster.net/v1/task/AZO1vee8RUuoPb3OLCWhFQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 15:59:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AZO1vee8RUuoPb3OLCWhFQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 15:59:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AZO1vee8RUuoPb3OLCWhFQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 15:59:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AZO1vee8RUuoPb3OLCWhFQ/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 15:59:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AZO1vee8RUuoPb3OLCWhFQ/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 15:59:23 INFO - Downloaded 1361 bytes. 15:59:23 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 15:59:23 INFO - Using the following test package requirements: 15:59:23 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 15:59:23 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:59:23 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 15:59:23 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:59:23 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 15:59:23 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:59:23 INFO - u'jsshell-linux-x86_64.zip'], 15:59:23 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:59:23 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 15:59:23 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 15:59:23 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:59:23 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 15:59:23 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:59:23 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 15:59:23 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:59:23 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 15:59:23 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:59:23 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 15:59:23 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:59: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')]} 15:59:23 INFO - https://queue.taskcluster.net/v1/task/AZO1vee8RUuoPb3OLCWhFQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:59:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AZO1vee8RUuoPb3OLCWhFQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:59:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AZO1vee8RUuoPb3OLCWhFQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:59:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AZO1vee8RUuoPb3OLCWhFQ/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 15:59:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AZO1vee8RUuoPb3OLCWhFQ/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 15:59:24 INFO - Downloaded 24412303 bytes. 15:59:24 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'] 15:59:24 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 15:59:25 INFO - Return code: 0 15:59:25 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 15:59:25 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')]} 15:59:25 INFO - https://queue.taskcluster.net/v1/task/AZO1vee8RUuoPb3OLCWhFQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:59:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AZO1vee8RUuoPb3OLCWhFQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:59:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AZO1vee8RUuoPb3OLCWhFQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:59:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AZO1vee8RUuoPb3OLCWhFQ/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 15:59:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AZO1vee8RUuoPb3OLCWhFQ/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 15:59:26 INFO - Downloaded 24412303 bytes. 15:59:26 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'] 15:59:26 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 15:59:28 INFO - Return code: 0 15:59: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')]} 15:59:28 INFO - https://queue.taskcluster.net/v1/task/AZO1vee8RUuoPb3OLCWhFQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 15:59:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AZO1vee8RUuoPb3OLCWhFQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 15:59:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AZO1vee8RUuoPb3OLCWhFQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 15:59:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AZO1vee8RUuoPb3OLCWhFQ/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 15:59:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AZO1vee8RUuoPb3OLCWhFQ/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 15:59:30 INFO - Downloaded 10840359 bytes. 15:59:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:59:30 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 15:59:30 INFO - Return code: 0 15:59:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:59:30 INFO - https://queue.taskcluster.net/v1/task/AZO1vee8RUuoPb3OLCWhFQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 15:59:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AZO1vee8RUuoPb3OLCWhFQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:59:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AZO1vee8RUuoPb3OLCWhFQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:59:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AZO1vee8RUuoPb3OLCWhFQ/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 15:59:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AZO1vee8RUuoPb3OLCWhFQ/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 15:59:33 INFO - Downloaded 58591011 bytes. 15:59:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AZO1vee8RUuoPb3OLCWhFQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:59:33 INFO - mkdir: /builds/slave/test/properties 15:59:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:59:33 INFO - Writing to file /builds/slave/test/properties/build_url 15:59:33 INFO - Contents: 15:59:33 INFO - build_url:https://queue.taskcluster.net/v1/task/AZO1vee8RUuoPb3OLCWhFQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:59:33 INFO - Running post-action listener: _resource_record_post_action 15:59:33 INFO - Running post-action listener: set_extra_try_arguments 15:59:33 INFO - ##### 15:59:33 INFO - ##### Finished download-and-extract step (success) 15:59:33 INFO - ##### 15:59:33 INFO - ##### 15:59:33 INFO - ##### Running populate-webroot step. 15:59:33 INFO - ##### 15:59:33 INFO - Running pre-action listener: _resource_record_pre_action 15:59:33 INFO - Running main action method: populate_webroot 15:59:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:59:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:59:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:59:34 INFO - '--tppagecycles', 15:59:34 INFO - '7'], 15:59:34 INFO - 'tests': ['tsvgm']}}, 15:59:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:59:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:59:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:59:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:59:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:59:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:59:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:59:34 INFO - 'talos_options': ['--disable-e10s'], 15:59:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:59:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:59:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:59:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:59:34 INFO - 'talos_options': ['--disable-e10s'], 15:59:34 INFO - 'tests': ['damp', 'tps']}, 15:59:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:59:34 INFO - 'tests': ['damp', 'tps']}, 15:59:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:59:34 INFO - 'tests': ['dromaeo_dom']}, 15:59:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:59:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:59:34 INFO - 'tests': ['a11yr', 15:59:34 INFO - 'ts_paint', 15:59:34 INFO - 'tpaint', 15:59:34 INFO - 'sessionrestore', 15:59:34 INFO - 'sessionrestore_no_auto_restore', 15:59:34 INFO - 'tabpaint']}, 15:59:34 INFO - 'other-e10s': {'tests': ['a11yr', 15:59:34 INFO - 'ts_paint', 15:59:34 INFO - 'tpaint', 15:59:34 INFO - 'sessionrestore', 15:59:34 INFO - 'sessionrestore_no_auto_restore', 15:59:34 INFO - 'tabpaint']}, 15:59:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:59:34 INFO - 'ts_paint', 15:59:34 INFO - 'tpaint', 15:59:34 INFO - 'sessionrestore', 15:59:34 INFO - 'sessionrestore_no_auto_restore', 15:59:34 INFO - 'tabpaint']}, 15:59:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:59:34 INFO - 'ts_paint', 15:59:34 INFO - 'tpaint', 15:59:34 INFO - 'sessionrestore', 15:59:34 INFO - 'sessionrestore_no_auto_restore', 15:59:34 INFO - 'tabpaint']}, 15:59:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:59:34 INFO - 'tests': ['a11yr', 15:59:34 INFO - 'ts_paint', 15:59:34 INFO - 'tpaint', 15:59:34 INFO - 'sessionrestore', 15:59:34 INFO - 'sessionrestore_no_auto_restore', 15:59:34 INFO - 'tabpaint']}, 15:59:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:59:34 INFO - 'tests': ['a11yr', 15:59:34 INFO - 'ts_paint', 15:59:34 INFO - 'tpaint', 15:59:34 INFO - 'sessionrestore', 15:59:34 INFO - 'sessionrestore_no_auto_restore', 15:59:34 INFO - 'tabpaint']}, 15:59:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:59:34 INFO - 'tests': ['tsvgx', 15:59:34 INFO - 'tsvgr_opacity', 15:59:34 INFO - 'tart', 15:59:34 INFO - 'tscrollx', 15:59:34 INFO - 'cart']}, 15:59:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:59:34 INFO - 'tsvgr_opacity', 15:59:34 INFO - 'tart', 15:59:34 INFO - 'tscrollx', 15:59:34 INFO - 'cart']}, 15:59:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:59:34 INFO - 'talos_options': ['--disable-e10s'], 15:59:34 INFO - 'tests': ['tp5o']}, 15:59:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:59:34 INFO - 'tests': ['tp5o']}, 15:59:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:59:34 INFO - 'talos_options': ['--disable-e10s', 15:59:34 INFO - '--xperf_path', 15:59:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:59:34 INFO - 'tests': ['tp5n']}, 15:59:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:59:34 INFO - 'talos_options': ['--xperf_path', 15:59:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:59:34 INFO - 'tests': ['tp5n']}}, 15:59:34 INFO - 'talos.zip': {'path': '', 15:59:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:59:34 INFO - Running post-action listener: _resource_record_post_action 15:59:34 INFO - ##### 15:59:34 INFO - ##### Finished populate-webroot step (success) 15:59:34 INFO - ##### 15:59:34 INFO - ##### 15:59:34 INFO - ##### Running create-virtualenv step. 15:59:34 INFO - ##### 15:59:34 INFO - Running pre-action listener: _resource_record_pre_action 15:59:34 INFO - Running main action method: create_virtualenv 15:59:34 INFO - Creating virtualenv /builds/slave/test/build/venv 15:59:34 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 15:59:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:59:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:59:34 INFO - Using real prefix '/usr' 15:59:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:59:34 INFO - Installing distribute.............................................................................................................................................................................................done. 15:59:36 INFO - Installing pip.................done. 15:59:36 INFO - Return code: 0 15:59:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:59:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:59:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:59:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:59:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:59:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:59:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:59:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae81107f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2364770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23f39c0>, '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 0x23f48c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23f4d50>, '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': '52faae308a556c440401f8e5000001e8-1463952526.521703-1222471583', '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 15:59:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:59:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:59:36 INFO - Using env: {'DISPLAY': ':0', 15:59:36 INFO - 'HOME': '/home/cltbld', 15:59:36 INFO - 'LANG': 'en_US.UTF-8', 15:59:36 INFO - 'LANGUAGE': 'en_US:en', 15:59:36 INFO - 'LOGNAME': 'cltbld', 15:59:36 INFO - 'MAIL': '/var/mail/cltbld', 15:59:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:59:36 INFO - 'MOZ_NO_REMOTE': '1', 15:59:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:59:36 INFO - 'NO_EM_RESTART': '1', 15:59:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:59:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:59:36 INFO - 'PWD': '/builds/slave/test', 15:59:36 INFO - 'SHELL': '/bin/bash', 15:59:36 INFO - 'SHLVL': '1', 15:59:36 INFO - 'TERM': 'linux', 15:59:36 INFO - 'TMOUT': '86400', 15:59:36 INFO - 'USER': 'cltbld', 15:59:36 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1463952526.521703-1222471583', 15:59:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:59:36 INFO - '_': '/tools/buildbot/bin/python'} 15:59:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:59:36 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 15:59:36 INFO - Cleaning up... 15:59:36 INFO - Return code: 0 15:59:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:59:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:59:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:59:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:59:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:59:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:59:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:59:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae81107f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2364770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23f39c0>, '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 0x23f48c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23f4d50>, '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': '52faae308a556c440401f8e5000001e8-1463952526.521703-1222471583', '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 15:59:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:59:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:59:36 INFO - Using env: {'DISPLAY': ':0', 15:59:36 INFO - 'HOME': '/home/cltbld', 15:59:36 INFO - 'LANG': 'en_US.UTF-8', 15:59:36 INFO - 'LANGUAGE': 'en_US:en', 15:59:36 INFO - 'LOGNAME': 'cltbld', 15:59:36 INFO - 'MAIL': '/var/mail/cltbld', 15:59:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:59:36 INFO - 'MOZ_NO_REMOTE': '1', 15:59:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:59:36 INFO - 'NO_EM_RESTART': '1', 15:59:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:59:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:59:36 INFO - 'PWD': '/builds/slave/test', 15:59:36 INFO - 'SHELL': '/bin/bash', 15:59:36 INFO - 'SHLVL': '1', 15:59:36 INFO - 'TERM': 'linux', 15:59:36 INFO - 'TMOUT': '86400', 15:59:36 INFO - 'USER': 'cltbld', 15:59:36 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1463952526.521703-1222471583', 15:59:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:59:36 INFO - '_': '/tools/buildbot/bin/python'} 15:59:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:59:37 INFO - Downloading/unpacking psutil>=3.1.1 15:59:37 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 15:59:37 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 15:59:37 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 15:59:37 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 15:59:37 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 15:59:37 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 15:59:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:59:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:59:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:59:38 INFO - Installing collected packages: psutil 15:59:38 INFO - Running setup.py install for psutil 15:59:39 INFO - building 'psutil._psutil_linux' extension 15:59:39 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 15:59:39 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 15:59:39 INFO - building 'psutil._psutil_posix' extension 15:59:39 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 15:59:39 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 15:59:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:59:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:59:39 INFO - Successfully installed psutil 15:59:39 INFO - Cleaning up... 15:59:39 INFO - Return code: 0 15:59:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:59:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:59:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:59:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:59:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:59:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:59:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:59:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae81107f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2364770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23f39c0>, '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 0x23f48c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23f4d50>, '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': '52faae308a556c440401f8e5000001e8-1463952526.521703-1222471583', '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 15:59:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:59:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:59:39 INFO - Using env: {'DISPLAY': ':0', 15:59:39 INFO - 'HOME': '/home/cltbld', 15:59:39 INFO - 'LANG': 'en_US.UTF-8', 15:59:39 INFO - 'LANGUAGE': 'en_US:en', 15:59:39 INFO - 'LOGNAME': 'cltbld', 15:59:39 INFO - 'MAIL': '/var/mail/cltbld', 15:59:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:59:39 INFO - 'MOZ_NO_REMOTE': '1', 15:59:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:59:39 INFO - 'NO_EM_RESTART': '1', 15:59:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:59:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:59:39 INFO - 'PWD': '/builds/slave/test', 15:59:39 INFO - 'SHELL': '/bin/bash', 15:59:39 INFO - 'SHLVL': '1', 15:59:39 INFO - 'TERM': 'linux', 15:59:39 INFO - 'TMOUT': '86400', 15:59:39 INFO - 'USER': 'cltbld', 15:59:39 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1463952526.521703-1222471583', 15:59:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:59:39 INFO - '_': '/tools/buildbot/bin/python'} 15:59:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:59:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:59:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:59:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:59:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:59:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:59:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:59:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:59:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:59:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:59:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:59:41 INFO - Installing collected packages: mozsystemmonitor 15:59:41 INFO - Running setup.py install for mozsystemmonitor 15:59:41 INFO - Successfully installed mozsystemmonitor 15:59:41 INFO - Cleaning up... 15:59:42 INFO - Return code: 0 15:59:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:59:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:59:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:59:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:59:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:59:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:59:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:59:42 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 0x7fae81107f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2364770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23f39c0>, '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 0x23f48c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23f4d50>, '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': '52faae308a556c440401f8e5000001e8-1463952526.521703-1222471583', '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 15:59:42 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 15:59:42 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 15:59:42 INFO - Using env: {'DISPLAY': ':0', 15:59:42 INFO - 'HOME': '/home/cltbld', 15:59:42 INFO - 'LANG': 'en_US.UTF-8', 15:59:42 INFO - 'LANGUAGE': 'en_US:en', 15:59:42 INFO - 'LOGNAME': 'cltbld', 15:59:42 INFO - 'MAIL': '/var/mail/cltbld', 15:59:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:59:42 INFO - 'MOZ_NO_REMOTE': '1', 15:59:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:59:42 INFO - 'NO_EM_RESTART': '1', 15:59:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:59:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:59:42 INFO - 'PWD': '/builds/slave/test', 15:59:42 INFO - 'SHELL': '/bin/bash', 15:59:42 INFO - 'SHLVL': '1', 15:59:42 INFO - 'TERM': 'linux', 15:59:42 INFO - 'TMOUT': '86400', 15:59:42 INFO - 'USER': 'cltbld', 15:59:42 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1463952526.521703-1222471583', 15:59:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:59:42 INFO - '_': '/tools/buildbot/bin/python'} 15:59:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:59:42 INFO - Downloading/unpacking blobuploader==1.2.4 15:59: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 15:59: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 15:59: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 15:59: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 15:59: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 15:59: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 15:59:44 INFO - Downloading blobuploader-1.2.4.tar.gz 15:59:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:59:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:59: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 15:59: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 15:59: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 15:59: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 15:59: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 15:59: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 15:59:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:59:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:59: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 15:59: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 15:59: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 15:59: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 15:59: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 15:59: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 15:59:44 INFO - Downloading docopt-0.6.1.tar.gz 15:59:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:59:44 INFO - Installing collected packages: blobuploader, requests, docopt 15:59:44 INFO - Running setup.py install for blobuploader 15:59:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:59:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:59:44 INFO - Running setup.py install for requests 15:59:45 INFO - Running setup.py install for docopt 15:59:45 INFO - Successfully installed blobuploader requests docopt 15:59:45 INFO - Cleaning up... 15:59:45 INFO - Return code: 0 15:59:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:59:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:59:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:59:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:59:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:59:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:59:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:59:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae81107f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2364770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23f39c0>, '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 0x23f48c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23f4d50>, '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': '52faae308a556c440401f8e5000001e8-1463952526.521703-1222471583', '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 15:59:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:59:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:59:45 INFO - Using env: {'DISPLAY': ':0', 15:59:45 INFO - 'HOME': '/home/cltbld', 15:59:45 INFO - 'LANG': 'en_US.UTF-8', 15:59:45 INFO - 'LANGUAGE': 'en_US:en', 15:59:45 INFO - 'LOGNAME': 'cltbld', 15:59:45 INFO - 'MAIL': '/var/mail/cltbld', 15:59:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:59:45 INFO - 'MOZ_NO_REMOTE': '1', 15:59:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:59:45 INFO - 'NO_EM_RESTART': '1', 15:59:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:59:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:59:45 INFO - 'PWD': '/builds/slave/test', 15:59:45 INFO - 'SHELL': '/bin/bash', 15:59:45 INFO - 'SHLVL': '1', 15:59:45 INFO - 'TERM': 'linux', 15:59:45 INFO - 'TMOUT': '86400', 15:59:45 INFO - 'USER': 'cltbld', 15:59:45 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1463952526.521703-1222471583', 15:59:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:59:45 INFO - '_': '/tools/buildbot/bin/python'} 15:59:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:59:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:59:45 INFO - Running setup.py (path:/tmp/pip-qgFmww-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:59:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:59:45 INFO - Running setup.py (path:/tmp/pip-82CO6H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:59:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:59:45 INFO - Running setup.py (path:/tmp/pip-j2coIt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:59:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:59:45 INFO - Running setup.py (path:/tmp/pip-hjKj3A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:59:46 INFO - Running setup.py (path:/tmp/pip-qI3lrI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:59:46 INFO - Running setup.py (path:/tmp/pip-3V9yES-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:59:46 INFO - Running setup.py (path:/tmp/pip-TgcGZe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:59:46 INFO - Running setup.py (path:/tmp/pip-xTpjm9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:59:46 INFO - Running setup.py (path:/tmp/pip-l_rcZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:59:46 INFO - Running setup.py (path:/tmp/pip-NKMmLj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:59:46 INFO - Running setup.py (path:/tmp/pip-oBq5xR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:59:46 INFO - Running setup.py (path:/tmp/pip-8tYs55-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:59:46 INFO - Running setup.py (path:/tmp/pip-8pUGbJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:59:46 INFO - Running setup.py (path:/tmp/pip-OHf8Mx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:59:46 INFO - Running setup.py (path:/tmp/pip-mu1ruU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:59:46 INFO - Running setup.py (path:/tmp/pip-O4thVM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:59:46 INFO - Running setup.py (path:/tmp/pip-y9WX1s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:59:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:59:46 INFO - Running setup.py install for manifestparser 15:59:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:59:47 INFO - Running setup.py install for mozcrash 15:59:47 INFO - Running setup.py install for mozdebug 15:59:47 INFO - Running setup.py install for mozdevice 15:59:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:59:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:59:47 INFO - Running setup.py install for mozfile 15:59:47 INFO - Running setup.py install for mozhttpd 15:59:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:59:47 INFO - Running setup.py install for mozinfo 15:59:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:59:47 INFO - Running setup.py install for mozInstall 15:59:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:59:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:59:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:59:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:59:47 INFO - Running setup.py install for mozleak 15:59:47 INFO - Running setup.py install for mozlog 15:59:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:59:48 INFO - Running setup.py install for moznetwork 15:59:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:59:48 INFO - Running setup.py install for mozprocess 15:59:48 INFO - Running setup.py install for mozprofile 15:59:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:59:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:59:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:59:48 INFO - Running setup.py install for mozrunner 15:59:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:59:48 INFO - Running setup.py install for mozscreenshot 15:59:48 INFO - Running setup.py install for moztest 15:59:48 INFO - Running setup.py install for mozversion 15:59:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:59:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:59:48 INFO - Cleaning up... 15:59:48 INFO - Return code: 0 15:59:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:59:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:59:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:59:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:59:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:59:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:59:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:59:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae81107f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2364770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23f39c0>, '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 0x23f48c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23f4d50>, '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': '52faae308a556c440401f8e5000001e8-1463952526.521703-1222471583', '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 15:59:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:59:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:59:48 INFO - Using env: {'DISPLAY': ':0', 15:59:48 INFO - 'HOME': '/home/cltbld', 15:59:48 INFO - 'LANG': 'en_US.UTF-8', 15:59:48 INFO - 'LANGUAGE': 'en_US:en', 15:59:48 INFO - 'LOGNAME': 'cltbld', 15:59:48 INFO - 'MAIL': '/var/mail/cltbld', 15:59:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:59:48 INFO - 'MOZ_NO_REMOTE': '1', 15:59:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:59:48 INFO - 'NO_EM_RESTART': '1', 15:59:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:59:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:59:48 INFO - 'PWD': '/builds/slave/test', 15:59:48 INFO - 'SHELL': '/bin/bash', 15:59:48 INFO - 'SHLVL': '1', 15:59:48 INFO - 'TERM': 'linux', 15:59:48 INFO - 'TMOUT': '86400', 15:59:48 INFO - 'USER': 'cltbld', 15:59:48 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1463952526.521703-1222471583', 15:59:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:59:48 INFO - '_': '/tools/buildbot/bin/python'} 15:59:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:59:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:59:48 INFO - Running setup.py (path:/tmp/pip-NtKjDb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:59:49 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)) 15:59:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:59:49 INFO - Running setup.py (path:/tmp/pip-4KtK2x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:59:49 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)) 15:59:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:59:49 INFO - Running setup.py (path:/tmp/pip-kgKdET-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:59:49 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)) 15:59:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:59:49 INFO - Running setup.py (path:/tmp/pip-gR7kuO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:59:49 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)) 15:59:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:59:49 INFO - Running setup.py (path:/tmp/pip-Oeiwz9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:59:49 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)) 15:59:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:59:49 INFO - Running setup.py (path:/tmp/pip-uByVhT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:59:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:59:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:59:49 INFO - Running setup.py (path:/tmp/pip-n0kiXB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:59:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:59:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:59:49 INFO - Running setup.py (path:/tmp/pip-blU0nH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:59:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:59:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:59:49 INFO - Running setup.py (path:/tmp/pip-dbqQWU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:59:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:59:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:59:49 INFO - Running setup.py (path:/tmp/pip-cIoTlp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:59:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:59:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:59:49 INFO - Running setup.py (path:/tmp/pip-JIBQzM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:59:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:59:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:59:49 INFO - Running setup.py (path:/tmp/pip-3bm04P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:59:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:59:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:59:49 INFO - Running setup.py (path:/tmp/pip-weO41x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:59:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:59:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:59:49 INFO - Running setup.py (path:/tmp/pip-oNy8wU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:59:50 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)) 15:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:59:50 INFO - Running setup.py (path:/tmp/pip-RJZKVr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:59:50 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)) 15:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:59:50 INFO - Running setup.py (path:/tmp/pip-qUaJIZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:59:50 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)) 15:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:59:50 INFO - Running setup.py (path:/tmp/pip-b_Ac9h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:59:50 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)) 15:59:50 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)) 15:59:50 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)) 15:59:50 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)) 15:59:50 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)) 15:59:50 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)) 15:59:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:59:50 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 15:59:50 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 15:59:50 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 15:59:50 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 15:59:50 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 15:59:50 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 15:59:52 INFO - Downloading blessings-1.6.tar.gz 15:59:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:59:52 INFO - Installing collected packages: blessings 15:59:52 INFO - Running setup.py install for blessings 15:59:52 INFO - Successfully installed blessings 15:59:52 INFO - Cleaning up... 15:59:52 INFO - Return code: 0 15:59:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:59:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:59:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:59:52 INFO - Reading from file tmpfile_stdout 15:59:52 INFO - Current package versions: 15:59:52 INFO - argparse == 1.2.1 15:59:52 INFO - blessings == 1.6 15:59:52 INFO - blobuploader == 1.2.4 15:59:52 INFO - docopt == 0.6.1 15:59:52 INFO - manifestparser == 1.1 15:59:52 INFO - mozInstall == 1.12 15:59:52 INFO - mozcrash == 0.17 15:59:52 INFO - mozdebug == 0.1 15:59:52 INFO - mozdevice == 0.48 15:59:52 INFO - mozfile == 1.2 15:59:52 INFO - mozhttpd == 0.7 15:59:52 INFO - mozinfo == 0.9 15:59:52 INFO - mozleak == 0.1 15:59:52 INFO - mozlog == 3.2 15:59:52 INFO - moznetwork == 0.27 15:59:52 INFO - mozprocess == 0.22 15:59:52 INFO - mozprofile == 0.28 15:59:52 INFO - mozrunner == 6.11 15:59:52 INFO - mozscreenshot == 0.1 15:59:52 INFO - mozsystemmonitor == 0.3 15:59:52 INFO - moztest == 0.7 15:59:52 INFO - mozversion == 1.4 15:59:52 INFO - psutil == 3.1.1 15:59:52 INFO - requests == 1.2.3 15:59:52 INFO - wsgiref == 0.1.2 15:59:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:59:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:59:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:59:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:59:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:59:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:59:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:59:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae81107f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2364770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23f39c0>, '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 0x23f48c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23f4d50>, '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': '52faae308a556c440401f8e5000001e8-1463952526.521703-1222471583', '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 15:59:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 15:59:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:59:52 INFO - Using env: {'DISPLAY': ':0', 15:59:52 INFO - 'HOME': '/home/cltbld', 15:59:52 INFO - 'LANG': 'en_US.UTF-8', 15:59:52 INFO - 'LANGUAGE': 'en_US:en', 15:59:52 INFO - 'LOGNAME': 'cltbld', 15:59:52 INFO - 'MAIL': '/var/mail/cltbld', 15:59:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:59:52 INFO - 'MOZ_NO_REMOTE': '1', 15:59:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:59:52 INFO - 'NO_EM_RESTART': '1', 15:59:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:59:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:59:52 INFO - 'PWD': '/builds/slave/test', 15:59:52 INFO - 'SHELL': '/bin/bash', 15:59:52 INFO - 'SHLVL': '1', 15:59:52 INFO - 'TERM': 'linux', 15:59:52 INFO - 'TMOUT': '86400', 15:59:52 INFO - 'USER': 'cltbld', 15:59:52 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1463952526.521703-1222471583', 15:59:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:59:52 INFO - '_': '/tools/buildbot/bin/python'} 15:59:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:59:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:59:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:59:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:59:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:59:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:59:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:59:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:59:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:59:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:59:52 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:59:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:59:52 INFO - Cleaning up... 15:59:52 INFO - Return code: 0 15:59:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:59:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:59:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:59:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:59:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:59:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:59:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:59:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae81107f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2364770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23f39c0>, '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 0x23f48c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23f4d50>, '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': '52faae308a556c440401f8e5000001e8-1463952526.521703-1222471583', '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 15:59:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:59:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:59:52 INFO - Using env: {'DISPLAY': ':0', 15:59:52 INFO - 'HOME': '/home/cltbld', 15:59:52 INFO - 'LANG': 'en_US.UTF-8', 15:59:52 INFO - 'LANGUAGE': 'en_US:en', 15:59:52 INFO - 'LOGNAME': 'cltbld', 15:59:52 INFO - 'MAIL': '/var/mail/cltbld', 15:59:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:59:52 INFO - 'MOZ_NO_REMOTE': '1', 15:59:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:59:52 INFO - 'NO_EM_RESTART': '1', 15:59:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:59:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:59:52 INFO - 'PWD': '/builds/slave/test', 15:59:52 INFO - 'SHELL': '/bin/bash', 15:59:52 INFO - 'SHLVL': '1', 15:59:52 INFO - 'TERM': 'linux', 15:59:52 INFO - 'TMOUT': '86400', 15:59:52 INFO - 'USER': 'cltbld', 15:59:52 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1463952526.521703-1222471583', 15:59:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:59:52 INFO - '_': '/tools/buildbot/bin/python'} 15:59:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:59:53 INFO - Downloading/unpacking jsonschema 15:59:53 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 15:59:53 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 15:59:53 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 15:59:53 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 15:59:53 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 15:59:53 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 15:59:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:59:54 INFO - Downloading/unpacking functools32 (from jsonschema) 15:59:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:59:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:59:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:59:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:59:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:59:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:59:55 INFO - Downloading functools32-3.2.3-2.tar.gz 15:59:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:59:55 INFO - warning: no files found matching '*.txt' 15:59:55 INFO - no previously-included directories found matching 'build' 15:59:55 INFO - no previously-included directories found matching 'dist' 15:59:55 INFO - no previously-included directories found matching '.git*' 15:59:55 INFO - Installing collected packages: jsonschema, functools32 15:59:55 INFO - Running setup.py install for functools32 15:59:55 INFO - warning: no files found matching '*.txt' 15:59:55 INFO - no previously-included directories found matching 'build' 15:59:55 INFO - no previously-included directories found matching 'dist' 15:59:55 INFO - no previously-included directories found matching '.git*' 15:59:55 INFO - Successfully installed jsonschema functools32 15:59:55 INFO - Cleaning up... 15:59:55 INFO - Return code: 0 15:59:55 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:59:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:59:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:59:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:59:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:59:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:59:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:59:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae81107f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2364770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23f39c0>, '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 0x23f48c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23f4d50>, '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': '52faae308a556c440401f8e5000001e8-1463952526.521703-1222471583', '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 15:59:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 15:59:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 15:59:55 INFO - Using env: {'DISPLAY': ':0', 15:59:55 INFO - 'HOME': '/home/cltbld', 15:59:55 INFO - 'LANG': 'en_US.UTF-8', 15:59:55 INFO - 'LANGUAGE': 'en_US:en', 15:59:55 INFO - 'LOGNAME': 'cltbld', 15:59:55 INFO - 'MAIL': '/var/mail/cltbld', 15:59:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:59:55 INFO - 'MOZ_NO_REMOTE': '1', 15:59:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:59:55 INFO - 'NO_EM_RESTART': '1', 15:59:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:59:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:59:55 INFO - 'PWD': '/builds/slave/test', 15:59:55 INFO - 'SHELL': '/bin/bash', 15:59:55 INFO - 'SHLVL': '1', 15:59:55 INFO - 'TERM': 'linux', 15:59:55 INFO - 'TMOUT': '86400', 15:59:55 INFO - 'USER': 'cltbld', 15:59:55 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1463952526.521703-1222471583', 15:59:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:59:55 INFO - '_': '/tools/buildbot/bin/python'} 15:59:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:59:55 INFO - Downloading/unpacking flake8 15:59:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:59:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:59:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:59:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:59:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:59:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:59:57 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:59:57 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:59:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:59:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:59:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:59:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:59:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:59:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:59:57 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:59:57 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:59:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:59:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:59:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:59:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:59:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:59:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:59:57 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:59:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:59:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:59:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:59:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:59:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:59:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:59:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:59:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:59:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:59:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:59:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:59:58 INFO - no previously-included directories found matching 'docs/_build' 15:59:58 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:59:58 INFO - Running setup.py install for pep8 15:59:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:59:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:59:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:59:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:59:58 INFO - no previously-included directories found matching 'docs/_build' 15:59:58 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:59:58 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:59:58 INFO - Cleaning up... 15:59:58 INFO - Return code: 0 15:59:58 INFO - Running post-action listener: _resource_record_post_action 15:59:58 INFO - Running post-action listener: _start_resource_monitoring 15:59:58 INFO - Starting resource monitoring. 15:59:58 INFO - ##### 15:59:58 INFO - ##### Finished create-virtualenv step (success) 15:59:58 INFO - ##### 15:59:58 INFO - ##### 15:59:58 INFO - ##### Running install step. 15:59:58 INFO - ##### 15:59:58 INFO - Running pre-action listener: _resource_record_pre_action 15:59:58 INFO - Running main action method: install 15:59:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:59:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:59:58 INFO - Reading from file tmpfile_stdout 15:59:58 INFO - Detecting whether we're running mozinstall >=1.0... 15:59:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:59:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:59:58 INFO - Reading from file tmpfile_stdout 15:59:58 INFO - Output received: 15:59:58 INFO - Usage: mozinstall [options] installer 15:59:58 INFO - Options: 15:59:58 INFO - -h, --help show this help message and exit 15:59:58 INFO - -d DEST, --destination=DEST 15:59:58 INFO - Directory to install application into. [default: 15:59:58 INFO - "/builds/slave/test"] 15:59:58 INFO - --app=APP Application being installed. [default: firefox] 15:59:58 INFO - mkdir: /builds/slave/test/build/application 15:59:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:59:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 16:00:15 INFO - Reading from file tmpfile_stdout 16:00:15 INFO - Output received: 16:00:15 INFO - /builds/slave/test/build/application/firefox/firefox 16:00:15 INFO - Running post-action listener: _resource_record_post_action 16:00:15 INFO - ##### 16:00:15 INFO - ##### Finished install step (success) 16:00:15 INFO - ##### 16:00:15 INFO - ##### 16:00:15 INFO - ##### Running run-tests step. 16:00:15 INFO - ##### 16:00:15 INFO - Running pre-action listener: _resource_record_pre_action 16:00:15 INFO - Running main action method: run_tests 16:00:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:00:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:00:15 INFO - Python 2.7.3 16:00:15 INFO - Return code: 0 16:00:15 INFO - grabbing minidump binary from tooltool 16:00:15 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')]} 16:00:15 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 0x23f39c0>, '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 0x23f48c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23f4d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:00:15 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 16:00:15 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 16:00:15 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 16:00:15 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:00:15 INFO - Return code: 0 16:00:15 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:00:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:00:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:00:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:00:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:00:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 16:00:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 16:00:16 INFO - Return code: 0 16:00:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AZO1vee8RUuoPb3OLCWhFQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--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 16:00:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/AZO1vee8RUuoPb3OLCWhFQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-056 --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 16:00:16 INFO - Using env: {'DISPLAY': ':0', 16:00:16 INFO - 'HOME': '/home/cltbld', 16:00:16 INFO - 'LANG': 'en_US.UTF-8', 16:00:16 INFO - 'LANGUAGE': 'en_US:en', 16:00:16 INFO - 'LOGNAME': 'cltbld', 16:00:16 INFO - 'MAIL': '/var/mail/cltbld', 16:00:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:00:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:00:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:16 INFO - 'MOZ_NO_REMOTE': '1', 16:00:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:00:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:00:16 INFO - 'NO_EM_RESTART': '1', 16:00:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:00:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:16 INFO - 'PWD': '/builds/slave/test', 16:00:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:00:16 INFO - 'SHELL': '/bin/bash', 16:00:16 INFO - 'SHLVL': '1', 16:00:16 INFO - 'TERM': 'linux', 16:00:16 INFO - 'TMOUT': '86400', 16:00:16 INFO - 'USER': 'cltbld', 16:00:16 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1463952526.521703-1222471583', 16:00:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:16 INFO - '_': '/tools/buildbot/bin/python'} 16:00:16 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AZO1vee8RUuoPb3OLCWhFQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--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 16:00:16 INFO - mozversion application_buildid: 20160522133012 16:00:16 INFO - mozversion application_changeset: eeb73478d98476877f2035f0c330fdc89e361803 16:00:16 INFO - mozversion application_display_name: Nightly 16:00:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:00:16 INFO - mozversion application_name: Firefox 16:00:16 INFO - mozversion application_remotingname: firefox 16:00:16 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 16:00:16 INFO - mozversion application_vendor: Mozilla 16:00:16 INFO - mozversion application_version: 49.0a1 16:00:16 INFO - mozversion platform_buildid: 20160522133012 16:00:16 INFO - mozversion platform_changeset: eeb73478d98476877f2035f0c330fdc89e361803 16:00:16 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 16:00:16 INFO - mozversion platform_version: 49.0a1 16:00:16 INFO - using testdate: 1463958016 16:00:16 INFO - actual date: 1463958016 16:00:16 INFO - starting webserver on 'localhost:39422' 16:00:16 INFO - SUITE-START | Running 2 tests 16:00:16 INFO - TEST-START | dromaeo_css 16:00:16 INFO - operating with platform_type : linux_ 16:00:16 INFO - Initialising browser for dromaeo_css test... 16:00:16 INFO - TEST-INFO | started process 29549 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmposeP2j/profile http://localhost:39422/getInfo.html) 16:00:20 INFO - PROCESS | 29549 | __metrics Screen width/height:1600/1200 16:00:20 INFO - PROCESS | 29549 | colorDepth:24 16:00:20 INFO - PROCESS | 29549 | Browser inner width/height: 1024/697 16:00:20 INFO - PROCESS | 29549 | __metrics 16:00:22 ERROR - PROCESS | 29549 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:00:22 INFO - TEST-INFO | 29549: exit 0 16:00:22 INFO - Browser initialized. 16:00:22 INFO - Running cycle 1/1 for dromaeo_css test... 16:00:23 INFO - TEST-INFO | started process 29632 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmposeP2j/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:00:34 INFO - PROCESS | 29632 | RSS: Main: 152788992 16:00:34 INFO - PROCESS | 29632 | 16:01:25 INFO - PROCESS | 29632 | 1463958085762 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] 16:03:33 INFO - PROCESS | 29632 | Cycle 1(1): loaded http://localhost:39422/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39422/tests/dromaeo/cssquery-ext.html) 16:03:34 INFO - PROCESS | 29632 | RSS: Main: 244191232 16:03:34 INFO - PROCESS | 29632 | 16:06:37 INFO - PROCESS | 29632 | Cycle 1(1): loaded http://localhost:39422/tests/dromaeo/cssquery-ext.html (next: http://localhost:39422/tests/dromaeo/cssquery-jquery.html) 16:06:37 INFO - PROCESS | 29632 | RSS: Main: 237641728 16:06:37 INFO - PROCESS | 29632 | 16:09:39 INFO - PROCESS | 29632 | Cycle 1(1): loaded http://localhost:39422/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39422/tests/dromaeo/cssquery-mootools.html) 16:09:40 INFO - PROCESS | 29632 | RSS: Main: 246886400 16:09:40 INFO - PROCESS | 29632 | 16:12:42 INFO - PROCESS | 29632 | Cycle 1(1): loaded http://localhost:39422/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39422/tests/dromaeo/cssquery-prototype.html) 16:12:42 INFO - PROCESS | 29632 | RSS: Main: 242589696 16:12:42 INFO - PROCESS | 29632 | 16:15:45 INFO - PROCESS | 29632 | Cycle 1(1): loaded http://localhost:39422/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39422/tests/dromaeo/cssquery-yui.html) 16:15:46 INFO - PROCESS | 29632 | RSS: Main: 224997376 16:15:46 INFO - PROCESS | 29632 | 16:18:48 INFO - PROCESS | 29632 | Cycle 1(1): loaded http://localhost:39422/tests/dromaeo/cssquery-yui.html (next: http://localhost:39422/tests/dromaeo/cssquery-dojo.html) 16:18:48 INFO - PROCESS | 29632 | RSS: Main: 275505152 16:18:48 INFO - PROCESS | 29632 | 16:18:48 INFO - PROCESS | 29632 | __start_tp_report 16:18:48 INFO - PROCESS | 29632 | _x_x_mozilla_page_load 16:18:48 INFO - PROCESS | 29632 | _x_x_mozilla_page_load_details 16:18:48 INFO - PROCESS | 29632 | |i|pagename|runs| 16:18:48 INFO - PROCESS | 29632 | |0;dojo.html;3031.6513600935896;3034.6510151332604;3065.6474505431884;3070.984645076774;3105.922351941201;5253.474652534745;5319.547838433733;5324.787008519655;5337.919931201035;5394.73026348682;5405;5430.049741295277;5471.316085489314;5534.280543529346;5542.8337149885465;4850.320955066285;4873.658843880929;5016.44819069902;5088.567471764904;5122.231665250224;5016.899662006757;5063.873403164914;5068.138416469209;5072.771725272371;5093.0577843099145;4892.633052521057;4921.704697718148;4944.728039957801;4971.676841005323;4975.104481193383;4958.20668693011;4968.975155124202;4970.850874473772;4982.701037937735;5042.168042273021;4939.975300123512;4951.03454826309;4973.3286006389035;4975.7263350515705;4986.675866068694;5278.709670968095;5335.7865685372535;5381.327334083239;5404.972975135099;5437.157240627709;5812.099124635688;5841.532677385799;5843.707814609253;5916.349201587822;5926.7925622603;5899.144624029492;5900.527957763412;5934.5845790794665;5960.97019514904;5982.880342393128;5358.3569971603265;5397.865053373658;5434.994526012701;5442.972785136049;5509.3664228614225;5926.762929482858;5957.851053723692;5965.612235204698;5973.551983601247;5974.2830860297045;5697.316322041299;5762.855928601734;5765.538756899438;5769.134629805479;5771.509421699203;5789.507891829157;5827.4172582741385;5846.619969701956;5926.703664816742;5931.7627294907725;5843.328017278068;5857.502112320415;5859.355470898283;5868.4424979626865;5870.060790273621;5615.887682246333;5699.971500142556;5705.686187259741;5757.913631295534;5766.250387449605;4368.956310436855;4372.934405983913;4373.956260437355;4415.072834704748;4418.624416924533;3945.4279129526058;3960.5841386653988;3970.9205815884097;3977.244323578511;4007.0383108053857;5420.457954204548;5454.290942177491;5481.479259470344;5576.275084239104;5644.9435505644415;5848.590598658053;5893.14549390336;5917.852053698692;5929.169916211734;5972.940270597325;5561.443855614406;5573.60984731073;5579.944200557942;5584.91622625661;5587.888242235133;4931.432885218174;4967.776450059756;5000.449950505442;5067.923981140286;5071.13790655582;6007.828473447636;6014.849628759316;6018.668973206428;6031.276246850318;6056;5911.645301281937;5915.231019967377;5919.112133180058;5920.467157955804;5921.052631578927;5852.561057920758;5859.033259512133;5951.375105613848;5994.610350327215;6063;5712.914306285326;5742.741576628978;5749.137629355463;5753.367129615655;5755.942440575541;5826.242588463472;5845.386234445322;5858.297004359504;5860.15027820955;5861.413858614275;5596.552275817843;5634.23937768396;5648.23748793924;5653.745581448762;5697.686627235542;5675.489205971482;5677.886442271214;5679.545636348999;5683.147527870852;5688.14677798317;5435.456454354533;5533.889322213453;5535.308086489189;5539.335279766293;5554.444555544414;5693.743781529924;5715.799947001835;5735.7705691773535;5806.970965145316;5808.186853840381;5591.440855914376;5618.747156377891;5633.239512665901;5635.633683810539;5688.886222275614 16:18:48 INFO - PROCESS | 29632 | |1;ext.html;614.413235360231;642.3993566015776;651.3551583931909;652.8400541867242;657.5561495990208;41271.58728412715;42433.78783106084;42501.57498425015;46365.536344636545;46391.768041159834;44423.33364999528;45026.549734502725;45230.32154517685;45238;45298;8506.447080939735;8643.697470588531;8677.088905664908;8708.346873984445;8715.084916083815;8478.830423391528;8638.956805215965;8646;8646.43798153118;8671.393002489829;8643.481391116553;8682.522461264627;8687.174718401742;8706.521141337224;8720.52037137957;8593.699220527284;8662.436941598777;8681.30549556034;8685;8689.174528419822;8527.74416767498;8645.740627781177;8649.264812490946;8653.610587523577;8675.913240867636;40718.796406018075;41263.793681031704;41267.380989285186;41354.58645413568;41436.1712765743;3324.5345651608795;3377.8817741378934;3447.6035255945635;3496.30073985204;3803.505544279226;31412.21469463259;31817.363652727046;32014;33228.66771332304;33237.670493180245;3968.742031767955;3974.960250397488;4058.9797051014557;4103.835846566134;4166.104287578155;324923.37538312393;326915;327118;328103;328515;49322.50677493179;55215.447845521834;56796.14805777834;57183.71408142932;57206.427935720945;55161.448385516436;59880.70059649761;60064;60072.399276007556;60203;16599.751003734953;16864.735144864186;16868.32526698943;16886.324547017983;16915.492535223962;12379.62861114168;12744.61766147017;12986.870131298567;13166.407511661999;13262.071654984251;16130.032198068153;16133;16134;16163.919180404258;16204.189790510427;6202.906956395657;6288.213973253343;6378.266499352541;6400.135981642512;6442.194725659293;1013.5894962540183;1082.1451053667515;1082.929609575375;1129.7288650723767;1138.2715062360096;32097;32146.517802232986;32228.516572251436;32361.35277294488;32476.36523195996;1122.451513571227;1130.739929816147;1150.0626989003936;1189.108168873345;1272.4592048379586;16727.498175054767;16728.916355418143;16850;17450.38923637692;17504.986926503614;544838;556144;556369;557249;559151;58000;58974;59507.40492595105;59531.40468595345;59619.40380596139;1402.4670625162578;1436.7198396312829;1438.079629037417;1456.83974762776;1459.4745891479058;31057.689423106385;33003.83498082494;33139.66860331366;33212.33575328528;33774.83112584421;51530.48469515257;52233.47766522286;52342.738286308326;52401.47598523966;52434;500290;510077;511778;512525;681350;41589;42569.78715106405;42656.5734342665;43681.252749889645;44608.33087503755;48428.515714842404;48995.75502122609;49630.50369496259;49658.75170624124;50123.498765011886;40664;41410.79294603508;41575.37636935388;42608.57391426046;43118;40826.591734083464;40876.1824763509;41065.38401924033;41357.37963930483;41690.79154604208;40584.69131855305;40601.79699101604;41230;42000;42890.35664464973;36806;37694.62305376911;38126;38349;38821;28805;29052.56421153642;29073.12780616585;29154.56268156022;29162 16:18:48 INFO - PROCESS | 29632 | |2;jquery.html;397.56069543154814;404.94128351389065;412.1180673359011;414.56056580025177;419.1554018652416;13274.93362533186;17953.7306940396;19553.41339759806;19578.11898464569;19623.60752784947;14949.701005979874;17373;17751.46745597634;18290.725639115422;18293.817061829377;5605.495505404513;5735.340435849868;5740.225069615611;5751.367349591542;5753.769849206027;5568.220449137124;5632.3804381518;5656.717164141797;5662.915056274159;5666.150077488369;4125.546189919107;5944.138099975501;6236.189295391616;6254.499640028809;6343.33394993526;6269.498440124825;6279.215098112736;6286.4027917348;6316.526260530479;6364.77723279683;5492.670439773627;5565.916511252334;5586.050371436865;5626.240457538221;5640;22547.661785073236;22557.66163507549;23300.76699233003;23414.64878026831;23838.165664201842;2281.600719874032;2333.9299821005397;2497;2849.874299651626;3008.7432290864967;13979.23114228717;14058;14156.36296366666;14525.201113938729;15096.622584435367;1931.3723040011912;1932.9613407731767;1997.1512107354317;2021.3228568429595;2062.226665000625;79951;81698;82459;82810.17189828085;82841;10110.24173187014;10352;10638.361698298128;10685.305455145392;10935.398553079498;14933.178675172752;15010.474633387998;15031.774523382159;15043.473478428203;15069;7530.397568194531;7783.883241751379;7825.413094017971;7846;8064.677412903418;7325.853482930312;7340.743073992491;7358.705651773869;7403.14863790671;7662.387009039264;5833.38749431312;6219.595726277777;6498.902516462257;6686.364795344532;6721.26066132725;1852.6109517001312;1910.5128192310872;1960.1081507913866;1962.479942815155;1975.7629084509952;968;1029.3000759483623;1039.26212389203;1043.634727845248;1125.4912858787593;19183.232670693294;19538.31615893437;19831.900840495706;19840;19841.702374464396;1018.134585602247;1025.8717660292464;1063.6968463987725;1073.1790180511914;1096.4736926275432;10950.780984380428;11450.198486105892;11470;11518.193726439069;11582.47878845454;358112;359761;365644;474876;477290;84903;91169;98425;98782.5060874691;99113.50443247738;1020.5305559442592;1033.7467320506373;1056.287006270764;1056.7846975977686;1062.16089289462;17365;17626.5593360167;17931.37240196587;18025.2789888403;18205.089745512938;42482.36276455918;43648;43689;43734;44584.331235030855;57565.71217143888;58019.12971305518;59416.108758369526;59627.701861490415;60129.39870601413;8137.186281371815;8485.109063548238;11573.88426115728;12511.624651260478;12735.74528509406;10655.8934410658;13271.469141234242;13603;13717.588472345846;13924.860751392356;11885.76228475443;11952.402379880798;12069.698257543632;12235.265884046985;12354.629361119181;11586.536538538701;11769.117316201491;12288.016958643466;12433.626991190278;12474.625761227178;10529.473526323807;11271.887281127412;12355.32045737493;12391.132620716464;12704.237745735285;12881.48474061027;12921.676958076123;13037.739245214852;13109.803352949522;13185.736285274432;11687;12007.519699211934;12189.69525761863;12344.62966111018;12458.377081146087 16:18:48 INFO - PROCESS | 29632 | |3;mootools.html;1272.179444258454;1490.6049896777351;1521.5511424129882;1640.557049596609;1813.7370081338206;8606.74179774607;8990.235829954445;9014.053524379944;9228.492432916204;9312.8603070954;8936.687215947442;9188.310876684242;9203.58583863726;9204.90795092049;9219.35464517484;2058.176729308281;2248.8425810193294;2316.536692661466;2321.663358812971;2358.7287461941837;2347.1784875293683;2355.9175428859994;2356.081128359941;2364.6925899633025;2366.3847399676124;2234.4748983988748;2307.8153747700144;2312.1098377124845;2312.3409828198965;2315.3285547191294;2045.7954204579498;2295.8048565871923;2355.882205889716;2359.1035406545575;2379.464620460383;2244.6969659095976;2247.9438014049615;2251.358362866575;2253.887305634728;2302.9654555181687;9137;9163.450192988443;9190.35667503275;9196.356255062148;9219.907800921972;5221.086309895753;5403.243545903538;5414.35027796663;5415.783368665248;5418.268533747933;3298.2908674634828;3348.648391918862;3405.2508448141616;3473.70473509752;3491.0748651607346;1047.0628787235457;1061.9044286014296;1074.7366895110747;1079.3685693869097;1080.6541906589894;12741.872581274161;12789.10476266671;12817.23096614206;12872.678183045406;12873.742525149444;4441.378207051015;4451.554844515587;4558.931616025762;4567.977160114178;4614.769261536977;9814.509274536245;9841.655542055994;9852.901470985198;9865.816102067798;9901.455419951828;4692.882677933079;5302.867428314245;5303.0454518186325;5305.336832895888;5307.840764777063;4713.835015774432;4860.076585448754;4870.220764677634;4887.34020907173;4891.241857512091;3749.4750734897134;3794.29805485986;3859.3439115349897;3902.23906338267;3931.2137572485617;1120.2662256222188;1122.2761318949233;1125.071815752008;1131.598282609678;1136.3863513702508;892.6518657723493;937.1284705224207;946.0208684012015;949.4208532794989;961.0101595356793;5588.580856435784;5755.22304488897;5885.47030767233;5961.4038596140035;6066.150738896558;418.36408658839;422.1915032712383;423.8156401965155;430.3050573324059;433.12076484736036;3955.940660890089;4026.416169655384;4034.8587799426878;4040.6969477288735;4122.154958233615;11198.496067676973;11499.885001149882;11578.76842463163;11625.24435911663;11626.593069242535;11214.66356009321;11306.378149201708;11414.543418263342;11417.543298268143;11444.313341199555;8465;8573.185547373114;8615.181557752128;8627.956860215785;8633.179847914438;5463.344398672185;5569.888602228014;5625.971870140786;5729.627574207663;5794.446490974781;10556.102731267929;10677.519511622151;10700.090492308242;10701.250912436055;10703.732406689584;10262.179025677779;10724.946375268073;10730.89269107299;10847.511861966388;10925.508352124014;6776.966115169393;7339.486235963432;7569.091708994956;7704.653290601825;7791.2987831093205;8939.106089391269;8981.236594889508;9029.051949545203;9043.457392556467;9054.230390416888;7773;8003.679852806055;8053.718119866011;8066.314363279187;8148.348132149295;7696.307332340116;7723.575203363869;7785.571793551182;7991.160928102466;7993.960030199812;6160.7535698571555;6234.220722409699;6332.715027823851;6345.333739957238;6346.238451385679;4912.533309335608;5072.188449848007;5075.416327122428;5100.48995100487;5118.692878427306;5101.285819985279;5122.718250496109;5128.897422051613;5146.330976972969;5328.227407026037 16:18:48 INFO - PROCESS | 29632 | |4;prototype.html;335.4414899192843;340.74614412262866;342.4144712541554;346.3626926455323;348.50512272572945;5194.896102077961;5248.317718696564;5291.6031297652735;5335.279737235472;5338.652987555806;5250.396204436491;5261.526462618363;5266.789328426858;5279.571397157312;5281.947180528194;3900.941485877707;3933.429652700371;4112.465379500676;4127.587241275864;4164.625183733463;4083.244599749056;4085.468889044435;4108.671306295504;4122.628963393294;4136.607022332889;3874.767513949172;4073.6944729145434;4079.2453396121664;4082.734622249559;4085.611866872642;3929.9410508842248;3973.1259122993038;4015.759054456742;4068.593140685937;4102.2410853991805;3624.8762883506197;3650.7444478886487;3659.408157451517;3661.5240018797654;3663.120850995742;5033.949660503385;5046.243063540462;5111.182210846247;5111.386633603955;5113.974430127862;4477.126960242754;4659.510751371125;4664.86005419838;4710.092412572913;4712.104700107003;5418.349798024262;5449.4005659377435;5498.772996936207;5545.168224766277;5558.666480011212;4006.117124951268;4013.2776100301935;4017.317056100441;4020.6783457323345;4027.9798601006805;5386.003589335985;5659.999999999959;5670.943290567083;5690.5447564194765;5691.231683722727;3921.215756848642;3951.4863067801;3984.183242435294;3989.5611482736876;4007.158496715664;4038.6567141793275;4043.5956404359918;4047.6761859051207;4053.493313335833;4054.1148366009356;4454.688171827941;4590.793414296365;4664.906701865943;4679.181143299909;4680.953190468052;5454.154606036071;5480.780768769205;5553.694546799884;5557.360903496069;5561.777528898799;1370.0751992405087;1375.1817668487233;1393.634238446308;1395.1001603965385;1396.2599822094305;903.290916630442;908.9000209976897;922.7693076730817;948.83869742144;970.3450171134456;5247.291615631917;5263.7894484220205;5302.814401495929;5324.056719297506;5329.280547126088;5568.359638641527;5573.888522229533;5604.32748070226;5617.38208797032;5644.068728659808;5124.538791508708;5345.35855697311;5345.465453454701;5355.866103347447;5428.724249801294;2976.032789343472;2981.0907673159895;3015.909522714322;3015.9396812063637;3016.668166501683;5694.567721474767;5831.533477321804;5866.472017518444;5869.20765696634;5929.495992840657;5711.485966263056;5732.77068917247;5749.9425005750245;5757.251557297608;5771.740271687784;5823.184754134425;5858.414158584107;5870.941290587125;5906.173135760911;5906.7341969612335;5415.1877218415975;5602.327720673546;5644.238027866186;5678.659280443187;5692.1461780733225;5554.278403582296;5563.499285064202;5584.190292407497;5618.887622247614;5635.83092507209;5849.707514624337;5907.139251135878;5915.733791979285;5943.227380440515;5959.374265702038;3956.3867600521967;4188.308929026676;4200.180964711974;4206.831726730897;4207.969047583361;5679.971600141973;5691.174779657009;5712.285964254468;5724.427557244242;5746.856328591819;5250.162443144869;5480.506754392124;5516.531094856982;5586.553075754011;5605.1872478491205;5489.819688766935;5500.752466138953;5529.917051244315;5554.91667624994;5560.36055853582;5204.479552044765;5221.843344699513;5433.891322173614;5439.292891924025;5442.455754424526;5550.916736249041;5553;5585.162225666182;5594.552435805207;5616.747246373841;5434.239206511171;5441.891162176814;5447.891042179213;5459.972700136633;5471.398146203835 16:18:48 INFO - PROCESS | 29632 | |5;yui.html;726.6729971512818;805.8509175802474;838.1618381618382;844.5397258494122;882.6204731965253;13143.802842957364;13155.671108222276;13174.7365052699;13175.736485270312;13277.46890124394;12675.556355527558;12702.237865728062;12711.237325760461;12729.93635031826;12737.808932866015;669;781.0588241169391;781.6951388958311;800.4516905919435;802.2699343597328;787.7754839870638;794.1581923161439;794.7575989323254;798.0822054637184;799.1968072087573;852.40757673417;853.2832420766555;853.3983541603155;853.6372041882206;853.9914600854005;704.5526090932269;826.9379796515256;840.6763396092488;841.7937605286683;845.6997765793117;615.4737699267107;616.3897741236188;616.7594638091148;617.6417677746896;617.7220250887119;12938.805917911239;12943.705629437076;12946.093773435863;12983.740325193538;12989.090763646454;13048.934755326163;13321.26733029683;13395.19628822264;13415.530456434075;13551.39018744159;11906.880931190664;11919.344436056013;11992.880071199264;12014.339211343373;12193.207441516362;4301.483821941356;4362.411074504965;4384.364267181273;4530.954690453086;4533.206688829475;57666;57825.42174578284;57906;59016.40983590152;59286.11070833898;10592.788144237073;10817.621383251548;10949;11085.22403431752;11144.888551114385;18822.435326940213;18875.716864247046;18895;18918.243270269035;19383.903080484506;7765.650545725456;7889.447738658354;7956.880646790303;7986.880196797053;8243.587820608946;4469.910601788011;4471.910561788746;4476.37330773692;4479.96960699041;4570.9943812361225;3340;3412.5734283214597;3421.2473255883665;3427.862885484553;3436.0894362993827;572.0875204049535;599.256921417448;601.5969300568629;606.9757209711562;613.362103412455;633.4805459523238;645.2128403347908;649.9480041596661;665.5540787672271;670.3865962644126;8645.740627781177;8710.651573937099;8717.869231961526;8748.168923952342;8932.240759535382;621.201755743867;657.6514447342915;660.3990368764402;668.3750693101965;683.2040672616346;3914.628110329514;4025.376066709606;4093.4064560639135;4094.63148316647;4157.251694694924;64688;65759.34240657628;66789;66985.3301466979;67558;41517.1696566067;50086.749566251936;57142;57967;62590.687046564475;51137.744311278206;51174.23238651423;51818;51832.99999999924;52226.73886630542;7790.61046947639;7855.567943763148;7881.724139655314;7931.524108553505;8059.959700201462;36417.81791091028;37135.44296835604;37137.81431092935;37716.245675086895;38270.61729382782;47712.52287477081;49313.26030109623;49660.50339496703;49920.50079499158;49925.50074499354;10269.794604107727;10374.58501659925;10505.842412363667;10597.841032384365;10726.249162558546;10834.349939003685;10878.347299161758;10933.234673572773;10944.343339399344;11060.17048721333;10081.193504519475;10256.43589602579;10857.891421086004;11065;11219;9698.127168554864;9932.801343973224;10314;10647;10666.306690065121;10358.430286334022;10634.52144653508;10656.8934310659;10900.454977250947;11049.72375690582;8584.399092063495;9198.770030749285;9308.581113850028;9574.760630984281;9618.807623847344;9403.85894211601;9496.572654230715;9523.714288571355;9552.665656701982;9588.232941364535 16:18:48 INFO - PROCESS | 29632 | __end_tp_report 16:18:48 INFO - PROCESS | 29632 | __start_cc_report 16:18:48 INFO - PROCESS | 29632 | _x_x_mozilla_cycle_collect,944 16:18:48 INFO - PROCESS | 29632 | __end_cc_report 16:18:48 INFO - PROCESS | 29632 | __startTimestamp1463959128963__endTimestamp 16:18:48 INFO - PROCESS | 29632 | 16:18:48 INFO - PROCESS | 29632 | ------- Summary: start ------- 16:18:48 INFO - PROCESS | 29632 | Number of tests: 6 16:18:48 INFO - PROCESS | 29632 | 16:18:48 INFO - PROCESS | 29632 | [#0] dojo.html Cycles:165 Average:5413.43 Median:5618.75 stddev:629.20 (11.2%) stddev-sans-first:602.75 16:18:48 INFO - PROCESS | 29632 | Values: 3031.7 3034.7 3065.6 3071.0 3105.9 5253.5 5319.5 5324.8 5337.9 5394.7 5405.0 5430.0 5471.3 5534.3 5542.8 4850.3 4873.7 5016.4 5088.6 5122.2 5016.9 5063.9 5068.1 5072.8 5093.1 4892.6 4921.7 4944.7 4971.7 4975.1 4958.2 4969.0 4970.9 4982.7 5042.2 4940.0 4951.0 4973.3 4975.7 4986.7 5278.7 5335.8 5381.3 5405.0 5437.2 5812.1 5841.5 5843.7 5916.3 5926.8 5899.1 5900.5 5934.6 5961.0 5982.9 5358.4 5397.9 5435.0 5443.0 5509.4 5926.8 5957.9 5965.6 5973.6 5974.3 5697.3 5762.9 5765.5 5769.1 5771.5 5789.5 5827.4 5846.6 5926.7 5931.8 5843.3 5857.5 5859.4 5868.4 5870.1 5615.9 5700.0 5705.7 5757.9 5766.3 4369.0 4372.9 4374.0 4415.1 4418.6 3945.4 3960.6 3970.9 3977.2 4007.0 5420.5 5454.3 5481.5 5576.3 5644.9 5848.6 5893.1 5917.9 5929.2 5972.9 5561.4 5573.6 5579.9 5584.9 5587.9 4931.4 4967.8 5000.4 5067.9 5071.1 6007.8 6014.8 6018.7 6031.3 6056.0 5911.6 5915.2 5919.1 5920.5 5921.1 5852.6 5859.0 5951.4 5994.6 6063.0 5712.9 5742.7 5749.1 5753.4 5755.9 5826.2 5845.4 5858.3 5860.2 5861.4 5596.6 5634.2 5648.2 5653.7 5697.7 5675.5 5677.9 5679.5 5683.1 5688.1 5435.5 5533.9 5535.3 5539.3 5554.4 5693.7 5715.8 5735.8 5807.0 5808.2 5591.4 5618.7 5633.2 5635.6 5688.9 16:18:48 INFO - PROCESS | 29632 | 16:18:48 INFO - PROCESS | 29632 | [#1] ext.html Cycles:180 Average:63511.31 Median:22877.55 stddev:129911.93 (567.9%) stddev-sans-first:130190.54 16:18:48 INFO - PROCESS | 29632 | Values: 614.4 642.4 651.4 652.8 657.6 41271.6 42433.8 42501.6 46365.5 46391.8 44423.3 45026.5 45230.3 45238.0 45298.0 8506.4 8643.7 8677.1 8708.3 8715.1 8478.8 8639.0 8646.0 8646.4 8671.4 8643.5 8682.5 8687.2 8706.5 8720.5 8593.7 8662.4 8681.3 8685.0 8689.2 8527.7 8645.7 8649.3 8653.6 8675.9 40718.8 41263.8 41267.4 41354.6 41436.2 3324.5 3377.9 3447.6 3496.3 3803.5 31412.2 31817.4 32014.0 33228.7 33237.7 3968.7 3975.0 4059.0 4103.8 4166.1 324923.4 326915.0 327118.0 328103.0 328515.0 49322.5 55215.4 56796.1 57183.7 57206.4 55161.4 59880.7 60064.0 60072.4 60203.0 16599.8 16864.7 16868.3 16886.3 16915.5 12379.6 12744.6 12986.9 13166.4 13262.1 16130.0 16133.0 16134.0 16163.9 16204.2 6202.9 6288.2 6378.3 6400.1 6442.2 1013.6 1082.1 1082.9 1129.7 1138.3 32097.0 32146.5 32228.5 32361.4 32476.4 1122.5 1130.7 1150.1 1189.1 1272.5 16727.5 16728.9 16850.0 17450.4 17505.0 544838.0 556144.0 556369.0 557249.0 559151.0 58000.0 58974.0 59507.4 59531.4 59619.4 1402.5 1436.7 1438.1 1456.8 1459.5 31057.7 33003.8 33139.7 33212.3 33774.8 51530.5 52233.5 52342.7 52401.5 52434.0 500290.0 510077.0 511778.0 512525.0 681350.0 41589.0 42569.8 42656.6 43681.3 44608.3 48428.5 48995.8 49630.5 49658.8 50123.5 40664.0 41410.8 41575.4 42608.6 43118.0 40826.6 40876.2 41065.4 41357.4 41690.8 40584.7 40601.8 41230.0 42000.0 42890.4 36806.0 37694.6 38126.0 38349.0 38821.0 28805.0 29052.6 29073.1 29154.6 29162.0 16:18:48 INFO - PROCESS | 29632 | 16:18:48 INFO - PROCESS | 29632 | [#2] jquery.html Cycles:180 Average:27196.12 Median:10903.83 stddev:68359.57 (626.9%) stddev-sans-first:68521.73 16:18:48 INFO - PROCESS | 29632 | Values: 397.6 404.9 412.1 414.6 419.2 13274.9 17953.7 19553.4 19578.1 19623.6 14949.7 17373.0 17751.5 18290.7 18293.8 5605.5 5735.3 5740.2 5751.4 5753.8 5568.2 5632.4 5656.7 5662.9 5666.2 4125.5 5944.1 6236.2 6254.5 6343.3 6269.5 6279.2 6286.4 6316.5 6364.8 5492.7 5565.9 5586.1 5626.2 5640.0 22547.7 22557.7 23300.8 23414.6 23838.2 2281.6 2333.9 2497.0 2849.9 3008.7 13979.2 14058.0 14156.4 14525.2 15096.6 1931.4 1933.0 1997.2 2021.3 2062.2 79951.0 81698.0 82459.0 82810.2 82841.0 10110.2 10352.0 10638.4 10685.3 10935.4 14933.2 15010.5 15031.8 15043.5 15069.0 7530.4 7783.9 7825.4 7846.0 8064.7 7325.9 7340.7 7358.7 7403.1 7662.4 5833.4 6219.6 6498.9 6686.4 6721.3 1852.6 1910.5 1960.1 1962.5 1975.8 968.0 1029.3 1039.3 1043.6 1125.5 19183.2 19538.3 19831.9 19840.0 19841.7 1018.1 1025.9 1063.7 1073.2 1096.5 10950.8 11450.2 11470.0 11518.2 11582.5 358112.0 359761.0 365644.0 474876.0 477290.0 84903.0 91169.0 98425.0 98782.5 99113.5 1020.5 1033.7 1056.3 1056.8 1062.2 17365.0 17626.6 17931.4 18025.3 18205.1 42482.4 43648.0 43689.0 43734.0 44584.3 57565.7 58019.1 59416.1 59627.7 60129.4 8137.2 8485.1 11573.9 12511.6 12735.7 10655.9 13271.5 13603.0 13717.6 13924.9 11885.8 11952.4 12069.7 12235.3 12354.6 11586.5 11769.1 12288.0 12433.6 12474.6 10529.5 11271.9 12355.3 12391.1 12704.2 12881.5 12921.7 13037.7 13109.8 13185.7 11687.0 12007.5 12189.7 12344.6 12458.4 16:18:48 INFO - PROCESS | 29632 | 16:18:48 INFO - PROCESS | 29632 | [#3] mootools.html Cycles:180 Average:5858.92 Median:4703.36 stddev:3479.66 (74.0%) stddev-sans-first:3472.35 16:18:48 INFO - PROCESS | 29632 | Values: 1272.2 1490.6 1521.6 1640.6 1813.7 8606.7 8990.2 9014.1 9228.5 9312.9 8936.7 9188.3 9203.6 9204.9 9219.4 2058.2 2248.8 2316.5 2321.7 2358.7 2347.2 2355.9 2356.1 2364.7 2366.4 2234.5 2307.8 2312.1 2312.3 2315.3 2045.8 2295.8 2355.9 2359.1 2379.5 2244.7 2247.9 2251.4 2253.9 2303.0 9137.0 9163.5 9190.4 9196.4 9219.9 5221.1 5403.2 5414.4 5415.8 5418.3 3298.3 3348.6 3405.3 3473.7 3491.1 1047.1 1061.9 1074.7 1079.4 1080.7 12741.9 12789.1 12817.2 12872.7 12873.7 4441.4 4451.6 4558.9 4568.0 4614.8 9814.5 9841.7 9852.9 9865.8 9901.5 4692.9 5302.9 5303.0 5305.3 5307.8 4713.8 4860.1 4870.2 4887.3 4891.2 3749.5 3794.3 3859.3 3902.2 3931.2 1120.3 1122.3 1125.1 1131.6 1136.4 892.7 937.1 946.0 949.4 961.0 5588.6 5755.2 5885.5 5961.4 6066.2 418.4 422.2 423.8 430.3 433.1 3955.9 4026.4 4034.9 4040.7 4122.2 11198.5 11499.9 11578.8 11625.2 11626.6 11214.7 11306.4 11414.5 11417.5 11444.3 8465.0 8573.2 8615.2 8628.0 8633.2 5463.3 5569.9 5626.0 5729.6 5794.4 10556.1 10677.5 10700.1 10701.3 10703.7 10262.2 10724.9 10730.9 10847.5 10925.5 6777.0 7339.5 7569.1 7704.7 7791.3 8939.1 8981.2 9029.1 9043.5 9054.2 7773.0 8003.7 8053.7 8066.3 8148.3 7696.3 7723.6 7785.6 7991.2 7994.0 6160.8 6234.2 6332.7 6345.3 6346.2 4912.5 5072.2 5075.4 5100.5 5118.7 5101.3 5122.7 5128.9 5146.3 5328.2 16:18:48 INFO - PROCESS | 29632 | 16:18:48 INFO - PROCESS | 29632 | [#4] prototype.html Cycles:180 Average:4642.47 Median:5326.67 stddev:1368.44 (25.7%) stddev-sans-first:1333.55 16:18:48 INFO - PROCESS | 29632 | Values: 335.4 340.7 342.4 346.4 348.5 5194.9 5248.3 5291.6 5335.3 5338.7 5250.4 5261.5 5266.8 5279.6 5281.9 3900.9 3933.4 4112.5 4127.6 4164.6 4083.2 4085.5 4108.7 4122.6 4136.6 3874.8 4073.7 4079.2 4082.7 4085.6 3929.9 3973.1 4015.8 4068.6 4102.2 3624.9 3650.7 3659.4 3661.5 3663.1 5033.9 5046.2 5111.2 5111.4 5114.0 4477.1 4659.5 4664.9 4710.1 4712.1 5418.3 5449.4 5498.8 5545.2 5558.7 4006.1 4013.3 4017.3 4020.7 4028.0 5386.0 5660.0 5670.9 5690.5 5691.2 3921.2 3951.5 3984.2 3989.6 4007.2 4038.7 4043.6 4047.7 4053.5 4054.1 4454.7 4590.8 4664.9 4679.2 4681.0 5454.2 5480.8 5553.7 5557.4 5561.8 1370.1 1375.2 1393.6 1395.1 1396.3 903.3 908.9 922.8 948.8 970.3 5247.3 5263.8 5302.8 5324.1 5329.3 5568.4 5573.9 5604.3 5617.4 5644.1 5124.5 5345.4 5345.5 5355.9 5428.7 2976.0 2981.1 3015.9 3015.9 3016.7 5694.6 5831.5 5866.5 5869.2 5929.5 5711.5 5732.8 5749.9 5757.3 5771.7 5823.2 5858.4 5870.9 5906.2 5906.7 5415.2 5602.3 5644.2 5678.7 5692.1 5554.3 5563.5 5584.2 5618.9 5635.8 5849.7 5907.1 5915.7 5943.2 5959.4 3956.4 4188.3 4200.2 4206.8 4208.0 5680.0 5691.2 5712.3 5724.4 5746.9 5250.2 5480.5 5516.5 5586.6 5605.2 5489.8 5500.8 5529.9 5554.9 5560.4 5204.5 5221.8 5433.9 5439.3 5442.5 5550.9 5553.0 5585.2 5594.6 5616.7 5434.2 5441.9 5447.9 5460.0 5471.4 16:18:48 INFO - PROCESS | 29632 | 16:18:48 INFO - PROCESS | 29632 | [#5] yui.html Cycles:180 Average:14732.84 Median:51155.99 stddev:18126.86 (35.4%) stddev-sans-first:18147.20 16:18:48 INFO - PROCESS | 29632 | Values: 726.7 805.9 838.2 844.5 882.6 13143.8 13155.7 13174.7 13175.7 13277.5 12675.6 12702.2 12711.2 12729.9 12737.8 669.0 781.1 781.7 800.5 802.3 787.8 794.2 794.8 798.1 799.2 852.4 853.3 853.4 853.6 854.0 704.6 826.9 840.7 841.8 845.7 615.5 616.4 616.8 617.6 617.7 12938.8 12943.7 12946.1 12983.7 12989.1 13048.9 13321.3 13395.2 13415.5 13551.4 11906.9 11919.3 11992.9 12014.3 12193.2 4301.5 4362.4 4384.4 4531.0 4533.2 57666.0 57825.4 57906.0 59016.4 59286.1 10592.8 10817.6 10949.0 11085.2 11144.9 18822.4 18875.7 18895.0 18918.2 19383.9 7765.7 7889.4 7956.9 7986.9 8243.6 4469.9 4471.9 4476.4 4480.0 4571.0 3340.0 3412.6 3421.2 3427.9 3436.1 572.1 599.3 601.6 607.0 613.4 633.5 645.2 649.9 665.6 670.4 8645.7 8710.7 8717.9 8748.2 8932.2 621.2 657.7 660.4 668.4 683.2 3914.6 4025.4 4093.4 4094.6 4157.3 64688.0 65759.3 66789.0 66985.3 67558.0 41517.2 50086.7 57142.0 57967.0 62590.7 51137.7 51174.2 51818.0 51833.0 52226.7 7790.6 7855.6 7881.7 7931.5 8060.0 36417.8 37135.4 37137.8 37716.2 38270.6 47712.5 49313.3 49660.5 49920.5 49925.5 10269.8 10374.6 10505.8 10597.8 10726.2 10834.3 10878.3 10933.2 10944.3 11060.2 10081.2 10256.4 10857.9 11065.0 11219.0 9698.1 9932.8 10314.0 10647.0 10666.3 10358.4 10634.5 10656.9 10900.5 11049.7 8584.4 9198.8 9308.6 9574.8 9618.8 9403.9 9496.6 9523.7 9552.7 9588.2 16:18:48 INFO - PROCESS | 29632 | -------- Summary: end -------- 16:18:48 INFO - PROCESS | 29632 | 16:18:49 INFO - TEST-INFO | 29632: exit 0 16:18:49 INFO - TEST-OK | dromaeo_css | took 1113016ms 16:18:49 INFO - TEST-START | kraken 16:18:49 INFO - operating with platform_type : linux_ 16:18:49 INFO - Initialising browser for kraken test... 16:18:49 INFO - TEST-INFO | started process 29814 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzlg7kR/profile http://localhost:39422/getInfo.html) 16:18:53 INFO - PROCESS | 29814 | __metrics Screen width/height:1600/1200 16:18:53 INFO - PROCESS | 29814 | colorDepth:24 16:18:53 INFO - PROCESS | 29814 | Browser inner width/height: 1024/697 16:18:53 INFO - PROCESS | 29814 | __metrics 16:18:54 ERROR - PROCESS | 29814 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:18:55 INFO - TEST-INFO | 29814: exit 0 16:18:55 INFO - Browser initialized. 16:18:55 INFO - Running cycle 1/1 for kraken test... 16:18:55 INFO - TEST-INFO | started process 29897 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzlg7kR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:19:07 INFO - PROCESS | 29897 | RSS: Main: 152858624 16:19:07 INFO - PROCESS | 29897 | 16:19:10 INFO - PROCESS | 29897 | Cycle 1(1): loaded http://localhost:39422/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39422/tests/kraken/driver.html?testName=audio-beat-detection) 16:19:10 INFO - PROCESS | 29897 | RSS: Main: 209657856 16:19:10 INFO - PROCESS | 29897 | 16:19:13 INFO - PROCESS | 29897 | Cycle 1(1): loaded http://localhost:39422/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39422/tests/kraken/driver.html?testName=audio-dft) 16:19:14 INFO - PROCESS | 29897 | RSS: Main: 197324800 16:19:14 INFO - PROCESS | 29897 | 16:19:17 INFO - PROCESS | 29897 | Cycle 1(1): loaded http://localhost:39422/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39422/tests/kraken/driver.html?testName=audio-fft) 16:19:18 INFO - PROCESS | 29897 | RSS: Main: 266063872 16:19:18 INFO - PROCESS | 29897 | 16:19:20 INFO - PROCESS | 29897 | Cycle 1(1): loaded http://localhost:39422/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39422/tests/kraken/driver.html?testName=audio-oscillator) 16:19:20 INFO - PROCESS | 29897 | RSS: Main: 175214592 16:19:20 INFO - PROCESS | 29897 | 16:19:23 INFO - PROCESS | 29897 | Cycle 1(1): loaded http://localhost:39422/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39422/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:19:23 INFO - PROCESS | 29897 | RSS: Main: 179531776 16:19:23 INFO - PROCESS | 29897 | 16:19:26 INFO - PROCESS | 29897 | Cycle 1(1): loaded http://localhost:39422/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39422/tests/kraken/driver.html?testName=imaging-darkroom) 16:19:27 INFO - PROCESS | 29897 | RSS: Main: 215339008 16:19:27 INFO - PROCESS | 29897 | 16:19:31 INFO - PROCESS | 29897 | Cycle 1(1): loaded http://localhost:39422/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39422/tests/kraken/driver.html?testName=imaging-desaturate) 16:19:31 INFO - PROCESS | 29897 | RSS: Main: 216010752 16:19:31 INFO - PROCESS | 29897 | 16:19:34 INFO - PROCESS | 29897 | Cycle 1(1): loaded http://localhost:39422/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39422/tests/kraken/driver.html?testName=json-parse-financial) 16:19:35 INFO - PROCESS | 29897 | RSS: Main: 216412160 16:19:35 INFO - PROCESS | 29897 | 16:19:36 INFO - PROCESS | 29897 | Cycle 1(1): loaded http://localhost:39422/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39422/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:19:37 INFO - PROCESS | 29897 | RSS: Main: 206229504 16:19:37 INFO - PROCESS | 29897 | 16:19:39 INFO - PROCESS | 29897 | Cycle 1(1): loaded http://localhost:39422/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39422/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:19:39 INFO - PROCESS | 29897 | RSS: Main: 208064512 16:19:39 INFO - PROCESS | 29897 | 16:19:41 INFO - PROCESS | 29897 | Cycle 1(1): loaded http://localhost:39422/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39422/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:19:42 INFO - PROCESS | 29897 | RSS: Main: 218439680 16:19:42 INFO - PROCESS | 29897 | 16:19:45 INFO - PROCESS | 29897 | Cycle 1(1): loaded http://localhost:39422/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39422/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:19:45 INFO - PROCESS | 29897 | RSS: Main: 225202176 16:19:45 INFO - PROCESS | 29897 | 16:19:48 INFO - PROCESS | 29897 | Cycle 1(1): loaded http://localhost:39422/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39422/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:19:49 INFO - PROCESS | 29897 | RSS: Main: 219897856 16:19:49 INFO - PROCESS | 29897 | 16:19:50 INFO - PROCESS | 29897 | Cycle 1(1): loaded http://localhost:39422/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39422/tests/kraken/driver.html?testName=ai-astar) 16:19:51 INFO - PROCESS | 29897 | RSS: Main: 265359360 16:19:51 INFO - PROCESS | 29897 | 16:19:51 INFO - PROCESS | 29897 | __start_tp_report 16:19:51 INFO - PROCESS | 29897 | _x_x_mozilla_page_load 16:19:51 INFO - PROCESS | 29897 | _x_x_mozilla_page_load_details 16:19:51 INFO - PROCESS | 29897 | |i|pagename|runs| 16:19:51 INFO - PROCESS | 29897 | |0;ai-astar;115;105;112;112;111;112;113;109;110;110 16:19:51 INFO - PROCESS | 29897 | |1;audio-beat-detection;156;128;134;181;129;155;165;127;197;124 16:19:51 INFO - PROCESS | 29897 | |2;audio-dft;161;160;162;162;161;162;161;160;162;161 16:19:51 INFO - PROCESS | 29897 | |3;audio-fft;75;76;82;105;74;75;75;74;110;76 16:19:51 INFO - PROCESS | 29897 | |4;audio-oscillator;92;92;129;92;92;92;141;93;93;128 16:19:51 INFO - PROCESS | 29897 | |5;imaging-gaussian-blur;117;117;117;118;117;117;117;117;117;117 16:19:51 INFO - PROCESS | 29897 | |6;imaging-darkroom;145;146;145;146;145;145;146;146;147;146 16:19:51 INFO - PROCESS | 29897 | |7;imaging-desaturate;95;95;95;95;95;95;96;95;95;96 16:19:51 INFO - PROCESS | 29897 | |8;json-parse-financial;54;55;56;54;56;55;55;75;53;52 16:19:51 INFO - PROCESS | 29897 | |9;json-stringify-tinderbox;62;62;62;64;64;63;63;63;63;63 16:19:51 INFO - PROCESS | 29897 | |10;stanford-crypto-aes;79;78;79;78;80;80;80;79;81;81 16:19:51 INFO - PROCESS | 29897 | |11;stanford-crypto-ccm;122;122;121;122;124;117;117;120;161;127 16:19:51 INFO - PROCESS | 29897 | |12;stanford-crypto-pbkdf2;165;168;162;173;163;164;165;160;161;161 16:19:51 INFO - PROCESS | 29897 | |13;stanford-crypto-sha256-iterative;68;62;64;69;63;67;63;63;61;63 16:19:51 INFO - PROCESS | 29897 | __end_tp_report 16:19:51 INFO - PROCESS | 29897 | __start_cc_report 16:19:51 INFO - PROCESS | 29897 | _x_x_mozilla_cycle_collect,1656 16:19:51 INFO - PROCESS | 29897 | __end_cc_report 16:19:51 INFO - PROCESS | 29897 | __startTimestamp1463959191052__endTimestamp 16:19:51 INFO - PROCESS | 29897 | 16:19:51 INFO - PROCESS | 29897 | ------- Summary: start ------- 16:19:51 INFO - PROCESS | 29897 | Number of tests: 14 16:19:51 INFO - PROCESS | 29897 | 16:19:51 INFO - PROCESS | 29897 | [#0] ai-astar Cycles:10 Average:110.90 Median:112.00 stddev:2.69 (2.4%) stddev-sans-first:2.40 16:19:51 INFO - PROCESS | 29897 | Values: 115.0 105.0 112.0 112.0 111.0 112.0 113.0 109.0 110.0 110.0 16:19:51 INFO - PROCESS | 29897 | 16:19:51 INFO - PROCESS | 29897 | [#1] audio-beat-detection Cycles:10 Average:149.60 Median:155.50 stddev:25.47 (16.4%) stddev-sans-first:26.91 16:19:51 INFO - PROCESS | 29897 | Values: 156.0 128.0 134.0 181.0 129.0 155.0 165.0 127.0 197.0 124.0 16:19:51 INFO - PROCESS | 29897 | 16:19:51 INFO - PROCESS | 29897 | [#2] audio-dft Cycles:10 Average:161.20 Median:161.50 stddev:0.79 (0.5%) stddev-sans-first:0.83 16:19:51 INFO - PROCESS | 29897 | Values: 161.0 160.0 162.0 162.0 161.0 162.0 161.0 160.0 162.0 161.0 16:19:51 INFO - PROCESS | 29897 | 16:19:51 INFO - PROCESS | 29897 | [#3] audio-fft Cycles:10 Average:82.20 Median:75.00 stddev:13.58 (18.1%) stddev-sans-first:14.15 16:19:51 INFO - PROCESS | 29897 | Values: 75.0 76.0 82.0 105.0 74.0 75.0 75.0 74.0 110.0 76.0 16:19:51 INFO - PROCESS | 29897 | 16:19:51 INFO - PROCESS | 29897 | [#4] audio-oscillator Cycles:10 Average:104.40 Median:92.00 stddev:19.81 (21.5%) stddev-sans-first:20.49 16:19:51 INFO - PROCESS | 29897 | Values: 92.0 92.0 129.0 92.0 92.0 92.0 141.0 93.0 93.0 128.0 16:19:51 INFO - PROCESS | 29897 | 16:19:51 INFO - PROCESS | 29897 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 16:19:51 INFO - PROCESS | 29897 | Values: 117.0 117.0 117.0 118.0 117.0 117.0 117.0 117.0 117.0 117.0 16:19:51 INFO - PROCESS | 29897 | 16:19:51 INFO - PROCESS | 29897 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.67 16:19:51 INFO - PROCESS | 29897 | Values: 145.0 146.0 145.0 146.0 145.0 145.0 146.0 146.0 147.0 146.0 16:19:51 INFO - PROCESS | 29897 | 16:19:51 INFO - PROCESS | 29897 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 16:19:51 INFO - PROCESS | 29897 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 96.0 16:19:51 INFO - PROCESS | 29897 | 16:19:51 INFO - PROCESS | 29897 | [#8] json-parse-financial Cycles:10 Average:56.50 Median:55.00 stddev:6.62 (12.0%) stddev-sans-first:6.96 16:19:51 INFO - PROCESS | 29897 | Values: 54.0 55.0 56.0 54.0 56.0 55.0 55.0 75.0 53.0 52.0 16:19:51 INFO - PROCESS | 29897 | 16:19:51 INFO - PROCESS | 29897 | [#9] json-stringify-tinderbox Cycles:10 Average:62.90 Median:63.00 stddev:0.74 (1.2%) stddev-sans-first:0.71 16:19:51 INFO - PROCESS | 29897 | Values: 62.0 62.0 62.0 64.0 64.0 63.0 63.0 63.0 63.0 63.0 16:19:51 INFO - PROCESS | 29897 | 16:19:51 INFO - PROCESS | 29897 | [#10] stanford-crypto-aes Cycles:10 Average:79.50 Median:80.00 stddev:1.08 (1.4%) stddev-sans-first:1.13 16:19:51 INFO - PROCESS | 29897 | Values: 79.0 78.0 79.0 78.0 80.0 80.0 80.0 79.0 81.0 81.0 16:19:51 INFO - PROCESS | 29897 | 16:19:51 INFO - PROCESS | 29897 | [#11] stanford-crypto-ccm Cycles:10 Average:125.30 Median:122.00 stddev:12.89 (10.6%) stddev-sans-first:13.62 16:19:51 INFO - PROCESS | 29897 | Values: 122.0 122.0 121.0 122.0 124.0 117.0 117.0 120.0 161.0 127.0 16:19:51 INFO - PROCESS | 29897 | 16:19:51 INFO - PROCESS | 29897 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.20 Median:164.50 stddev:3.91 (2.4%) stddev-sans-first:4.14 16:19:51 INFO - PROCESS | 29897 | Values: 165.0 168.0 162.0 173.0 163.0 164.0 165.0 160.0 161.0 161.0 16:19:51 INFO - PROCESS | 29897 | 16:19:51 INFO - PROCESS | 29897 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.30 Median:63.50 stddev:2.71 (4.3%) stddev-sans-first:2.52 16:19:51 INFO - PROCESS | 29897 | Values: 68.0 62.0 64.0 69.0 63.0 67.0 63.0 63.0 61.0 63.0 16:19:51 INFO - PROCESS | 29897 | -------- Summary: end -------- 16:19:51 INFO - PROCESS | 29897 | 16:19:52 INFO - TEST-INFO | 29897: exit 0 16:19:52 INFO - TEST-OK | kraken | took 62416ms 16:19:52 INFO - SUITE-END | took 1175s 16:19:52 INFO - Completed test suite (00:19:35) 16:19:52 INFO - javascript benchmark 16:19:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3031.6513600935896, 3034.6510151332604, 3065.6474505431884, 3070.984645076774, 3105.922351941201, 5253.474652534745, 5319.547838433733, 5324.787008519655, 5337.919931201035, 5394.73026348682, 5405.0, 5430.049741295277, 5471.316085489314, 5534.280543529346, 5542.8337149885465, 4850.320955066285, 4873.658843880929, 5016.44819069902, 5088.567471764904, 5122.231665250224, 5016.899662006757, 5063.873403164914, 5068.138416469209, 5072.771725272371, 5093.0577843099145, 4892.633052521057, 4921.704697718148, 4944.728039957801, 4971.676841005323, 4975.104481193383, 4958.20668693011, 4968.975155124202, 4970.850874473772, 4982.701037937735, 5042.168042273021, 4939.975300123512, 4951.03454826309, 4973.3286006389035, 4975.7263350515705, 4986.675866068694, 5278.709670968095, 5335.7865685372535, 5381.327334083239, 5404.972975135099, 5437.157240627709, 5812.099124635688, 5841.532677385799, 5843.707814609253, 5916.349201587822, 5926.7925622603, 5899.144624029492, 5900.527957763412, 5934.5845790794665, 5960.97019514904, 5982.880342393128, 5358.3569971603265, 5397.865053373658, 5434.994526012701, 5442.972785136049, 5509.3664228614225, 5926.762929482858, 5957.851053723692, 5965.612235204698, 5973.551983601247, 5974.2830860297045, 5697.316322041299, 5762.855928601734, 5765.538756899438, 5769.134629805479, 5771.509421699203, 5789.507891829157, 5827.4172582741385, 5846.619969701956, 5926.703664816742, 5931.7627294907725, 5843.328017278068, 5857.502112320415, 5859.355470898283, 5868.4424979626865, 5870.060790273621, 5615.887682246333, 5699.971500142556, 5705.686187259741, 5757.913631295534, 5766.250387449605, 4368.956310436855, 4372.934405983913, 4373.956260437355, 4415.072834704748, 4418.624416924533, 3945.4279129526058, 3960.5841386653988, 3970.9205815884097, 3977.244323578511, 4007.0383108053857, 5420.457954204548, 5454.290942177491, 5481.479259470344, 5576.275084239104, 5644.9435505644415, 5848.590598658053, 5893.14549390336, 5917.852053698692, 5929.169916211734, 5972.940270597325, 5561.443855614406, 5573.60984731073, 5579.944200557942, 5584.91622625661, 5587.888242235133, 4931.432885218174, 4967.776450059756, 5000.449950505442, 5067.923981140286, 5071.13790655582, 6007.828473447636, 6014.849628759316, 6018.668973206428, 6031.276246850318, 6056.0, 5911.645301281937, 5915.231019967377, 5919.112133180058, 5920.467157955804, 5921.052631578927, 5852.561057920758, 5859.033259512133, 5951.375105613848, 5994.610350327215, 6063.0, 5712.914306285326, 5742.741576628978, 5749.137629355463, 5753.367129615655, 5755.942440575541, 5826.242588463472, 5845.386234445322, 5858.297004359504, 5860.15027820955, 5861.413858614275, 5596.552275817843, 5634.23937768396, 5648.23748793924, 5653.745581448762, 5697.686627235542, 5675.489205971482, 5677.886442271214, 5679.545636348999, 5683.147527870852, 5688.14677798317, 5435.456454354533, 5533.889322213453, 5535.308086489189, 5539.335279766293, 5554.444555544414, 5693.743781529924, 5715.799947001835, 5735.7705691773535, 5806.970965145316, 5808.186853840381, 5591.440855914376, 5618.747156377891, 5633.239512665901, 5635.633683810539, 5688.886222275614], "value": 5369.288324593985, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [614.413235360231, 642.3993566015776, 651.3551583931909, 652.8400541867242, 657.5561495990208, 41271.58728412715, 42433.78783106084, 42501.57498425015, 46365.536344636545, 46391.768041159834, 44423.33364999528, 45026.549734502725, 45230.32154517685, 45238.0, 45298.0, 8506.447080939735, 8643.697470588531, 8677.088905664908, 8708.346873984445, 8715.084916083815, 8478.830423391528, 8638.956805215965, 8646.0, 8646.43798153118, 8671.393002489829, 8643.481391116553, 8682.522461264627, 8687.174718401742, 8706.521141337224, 8720.52037137957, 8593.699220527284, 8662.436941598777, 8681.30549556034, 8685.0, 8689.174528419822, 8527.74416767498, 8645.740627781177, 8649.264812490946, 8653.610587523577, 8675.913240867636, 40718.796406018075, 41263.793681031704, 41267.380989285186, 41354.58645413568, 41436.1712765743, 3324.5345651608795, 3377.8817741378934, 3447.6035255945635, 3496.30073985204, 3803.505544279226, 31412.21469463259, 31817.363652727046, 32014.0, 33228.66771332304, 33237.670493180245, 3968.742031767955, 3974.960250397488, 4058.9797051014557, 4103.835846566134, 4166.104287578155, 324923.37538312393, 326915.0, 327118.0, 328103.0, 328515.0, 49322.50677493179, 55215.447845521834, 56796.14805777834, 57183.71408142932, 57206.427935720945, 55161.448385516436, 59880.70059649761, 60064.0, 60072.399276007556, 60203.0, 16599.751003734953, 16864.735144864186, 16868.32526698943, 16886.324547017983, 16915.492535223962, 12379.62861114168, 12744.61766147017, 12986.870131298567, 13166.407511661999, 13262.071654984251, 16130.032198068153, 16133.0, 16134.0, 16163.919180404258, 16204.189790510427, 6202.906956395657, 6288.213973253343, 6378.266499352541, 6400.135981642512, 6442.194725659293, 1013.5894962540183, 1082.1451053667515, 1082.929609575375, 1129.7288650723767, 1138.2715062360096, 32097.0, 32146.517802232986, 32228.516572251436, 32361.35277294488, 32476.36523195996, 1122.451513571227, 1130.739929816147, 1150.0626989003936, 1189.108168873345, 1272.4592048379586, 16727.498175054767, 16728.916355418143, 16850.0, 17450.38923637692, 17504.986926503614, 544838.0, 556144.0, 556369.0, 557249.0, 559151.0, 58000.0, 58974.0, 59507.40492595105, 59531.40468595345, 59619.40380596139, 1402.4670625162578, 1436.7198396312829, 1438.079629037417, 1456.83974762776, 1459.4745891479058, 31057.689423106385, 33003.83498082494, 33139.66860331366, 33212.33575328528, 33774.83112584421, 51530.48469515257, 52233.47766522286, 52342.738286308326, 52401.47598523966, 52434.0, 500290.0, 510077.0, 511778.0, 512525.0, 681350.0, 41589.0, 42569.78715106405, 42656.5734342665, 43681.252749889645, 44608.33087503755, 48428.515714842404, 48995.75502122609, 49630.50369496259, 49658.75170624124, 50123.498765011886, 40664.0, 41410.79294603508, 41575.37636935388, 42608.57391426046, 43118.0, 40826.591734083464, 40876.1824763509, 41065.38401924033, 41357.37963930483, 41690.79154604208, 40584.69131855305, 40601.79699101604, 41230.0, 42000.0, 42890.35664464973, 36806.0, 37694.62305376911, 38126.0, 38349.0, 38821.0, 28805.0, 29052.56421153642, 29073.12780616585, 29154.56268156022, 29162.0], "value": 20410.0015744076, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.56069543154814, 404.94128351389065, 412.1180673359011, 414.56056580025177, 419.1554018652416, 13274.93362533186, 17953.7306940396, 19553.41339759806, 19578.11898464569, 19623.60752784947, 14949.701005979874, 17373.0, 17751.46745597634, 18290.725639115422, 18293.817061829377, 5605.495505404513, 5735.340435849868, 5740.225069615611, 5751.367349591542, 5753.769849206027, 5568.220449137124, 5632.3804381518, 5656.717164141797, 5662.915056274159, 5666.150077488369, 4125.546189919107, 5944.138099975501, 6236.189295391616, 6254.499640028809, 6343.33394993526, 6269.498440124825, 6279.215098112736, 6286.4027917348, 6316.526260530479, 6364.77723279683, 5492.670439773627, 5565.916511252334, 5586.050371436865, 5626.240457538221, 5640.0, 22547.661785073236, 22557.66163507549, 23300.76699233003, 23414.64878026831, 23838.165664201842, 2281.600719874032, 2333.9299821005397, 2497.0, 2849.874299651626, 3008.7432290864967, 13979.23114228717, 14058.0, 14156.36296366666, 14525.201113938729, 15096.622584435367, 1931.3723040011912, 1932.9613407731767, 1997.1512107354317, 2021.3228568429595, 2062.226665000625, 79951.0, 81698.0, 82459.0, 82810.17189828085, 82841.0, 10110.24173187014, 10352.0, 10638.361698298128, 10685.305455145392, 10935.398553079498, 14933.178675172752, 15010.474633387998, 15031.774523382159, 15043.473478428203, 15069.0, 7530.397568194531, 7783.883241751379, 7825.413094017971, 7846.0, 8064.677412903418, 7325.853482930312, 7340.743073992491, 7358.705651773869, 7403.14863790671, 7662.387009039264, 5833.38749431312, 6219.595726277777, 6498.902516462257, 6686.364795344532, 6721.26066132725, 1852.6109517001312, 1910.5128192310872, 1960.1081507913866, 1962.479942815155, 1975.7629084509952, 968.0, 1029.3000759483623, 1039.26212389203, 1043.634727845248, 1125.4912858787593, 19183.232670693294, 19538.31615893437, 19831.900840495706, 19840.0, 19841.702374464396, 1018.134585602247, 1025.8717660292464, 1063.6968463987725, 1073.1790180511914, 1096.4736926275432, 10950.780984380428, 11450.198486105892, 11470.0, 11518.193726439069, 11582.47878845454, 358112.0, 359761.0, 365644.0, 474876.0, 477290.0, 84903.0, 91169.0, 98425.0, 98782.5060874691, 99113.50443247738, 1020.5305559442592, 1033.7467320506373, 1056.287006270764, 1056.7846975977686, 1062.16089289462, 17365.0, 17626.5593360167, 17931.37240196587, 18025.2789888403, 18205.089745512938, 42482.36276455918, 43648.0, 43689.0, 43734.0, 44584.331235030855, 57565.71217143888, 58019.12971305518, 59416.108758369526, 59627.701861490415, 60129.39870601413, 8137.186281371815, 8485.109063548238, 11573.88426115728, 12511.624651260478, 12735.74528509406, 10655.8934410658, 13271.469141234242, 13603.0, 13717.588472345846, 13924.860751392356, 11885.76228475443, 11952.402379880798, 12069.698257543632, 12235.265884046985, 12354.629361119181, 11586.536538538701, 11769.117316201491, 12288.016958643466, 12433.626991190278, 12474.625761227178, 10529.473526323807, 11271.887281127412, 12355.32045737493, 12391.132620716464, 12704.237745735285, 12881.48474061027, 12921.676958076123, 13037.739245214852, 13109.803352949522, 13185.736285274432, 11687.0, 12007.519699211934, 12189.69525761863, 12344.62966111018, 12458.377081146087], "value": 9603.200125583204, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1272.179444258454, 1490.6049896777351, 1521.5511424129882, 1640.557049596609, 1813.7370081338206, 8606.74179774607, 8990.235829954445, 9014.053524379944, 9228.492432916204, 9312.8603070954, 8936.687215947442, 9188.310876684242, 9203.58583863726, 9204.90795092049, 9219.35464517484, 2058.176729308281, 2248.8425810193294, 2316.536692661466, 2321.663358812971, 2358.7287461941837, 2347.1784875293683, 2355.9175428859994, 2356.081128359941, 2364.6925899633025, 2366.3847399676124, 2234.4748983988748, 2307.8153747700144, 2312.1098377124845, 2312.3409828198965, 2315.3285547191294, 2045.7954204579498, 2295.8048565871923, 2355.882205889716, 2359.1035406545575, 2379.464620460383, 2244.6969659095976, 2247.9438014049615, 2251.358362866575, 2253.887305634728, 2302.9654555181687, 9137.0, 9163.450192988443, 9190.35667503275, 9196.356255062148, 9219.907800921972, 5221.086309895753, 5403.243545903538, 5414.35027796663, 5415.783368665248, 5418.268533747933, 3298.2908674634828, 3348.648391918862, 3405.2508448141616, 3473.70473509752, 3491.0748651607346, 1047.0628787235457, 1061.9044286014296, 1074.7366895110747, 1079.3685693869097, 1080.6541906589894, 12741.872581274161, 12789.10476266671, 12817.23096614206, 12872.678183045406, 12873.742525149444, 4441.378207051015, 4451.554844515587, 4558.931616025762, 4567.977160114178, 4614.769261536977, 9814.509274536245, 9841.655542055994, 9852.901470985198, 9865.816102067798, 9901.455419951828, 4692.882677933079, 5302.867428314245, 5303.0454518186325, 5305.336832895888, 5307.840764777063, 4713.835015774432, 4860.076585448754, 4870.220764677634, 4887.34020907173, 4891.241857512091, 3749.4750734897134, 3794.29805485986, 3859.3439115349897, 3902.23906338267, 3931.2137572485617, 1120.2662256222188, 1122.2761318949233, 1125.071815752008, 1131.598282609678, 1136.3863513702508, 892.6518657723493, 937.1284705224207, 946.0208684012015, 949.4208532794989, 961.0101595356793, 5588.580856435784, 5755.22304488897, 5885.47030767233, 5961.4038596140035, 6066.150738896558, 418.36408658839, 422.1915032712383, 423.8156401965155, 430.3050573324059, 433.12076484736036, 3955.940660890089, 4026.416169655384, 4034.8587799426878, 4040.6969477288735, 4122.154958233615, 11198.496067676973, 11499.885001149882, 11578.76842463163, 11625.24435911663, 11626.593069242535, 11214.66356009321, 11306.378149201708, 11414.543418263342, 11417.543298268143, 11444.313341199555, 8465.0, 8573.185547373114, 8615.181557752128, 8627.956860215785, 8633.179847914438, 5463.344398672185, 5569.888602228014, 5625.971870140786, 5729.627574207663, 5794.446490974781, 10556.102731267929, 10677.519511622151, 10700.090492308242, 10701.250912436055, 10703.732406689584, 10262.179025677779, 10724.946375268073, 10730.89269107299, 10847.511861966388, 10925.508352124014, 6776.966115169393, 7339.486235963432, 7569.091708994956, 7704.653290601825, 7791.2987831093205, 8939.106089391269, 8981.236594889508, 9029.051949545203, 9043.457392556467, 9054.230390416888, 7773.0, 8003.679852806055, 8053.718119866011, 8066.314363279187, 8148.348132149295, 7696.307332340116, 7723.575203363869, 7785.571793551182, 7991.160928102466, 7993.960030199812, 6160.7535698571555, 6234.220722409699, 6332.715027823851, 6345.333739957238, 6346.238451385679, 4912.533309335608, 5072.188449848007, 5075.416327122428, 5100.48995100487, 5118.692878427306, 5101.285819985279, 5122.718250496109, 5128.897422051613, 5146.330976972969, 5328.227407026037], "value": 4532.898904298132, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.4414899192843, 340.74614412262866, 342.4144712541554, 346.3626926455323, 348.50512272572945, 5194.896102077961, 5248.317718696564, 5291.6031297652735, 5335.279737235472, 5338.652987555806, 5250.396204436491, 5261.526462618363, 5266.789328426858, 5279.571397157312, 5281.947180528194, 3900.941485877707, 3933.429652700371, 4112.465379500676, 4127.587241275864, 4164.625183733463, 4083.244599749056, 4085.468889044435, 4108.671306295504, 4122.628963393294, 4136.607022332889, 3874.767513949172, 4073.6944729145434, 4079.2453396121664, 4082.734622249559, 4085.611866872642, 3929.9410508842248, 3973.1259122993038, 4015.759054456742, 4068.593140685937, 4102.2410853991805, 3624.8762883506197, 3650.7444478886487, 3659.408157451517, 3661.5240018797654, 3663.120850995742, 5033.949660503385, 5046.243063540462, 5111.182210846247, 5111.386633603955, 5113.974430127862, 4477.126960242754, 4659.510751371125, 4664.86005419838, 4710.092412572913, 4712.104700107003, 5418.349798024262, 5449.4005659377435, 5498.772996936207, 5545.168224766277, 5558.666480011212, 4006.117124951268, 4013.2776100301935, 4017.317056100441, 4020.6783457323345, 4027.9798601006805, 5386.003589335985, 5659.999999999959, 5670.943290567083, 5690.5447564194765, 5691.231683722727, 3921.215756848642, 3951.4863067801, 3984.183242435294, 3989.5611482736876, 4007.158496715664, 4038.6567141793275, 4043.5956404359918, 4047.6761859051207, 4053.493313335833, 4054.1148366009356, 4454.688171827941, 4590.793414296365, 4664.906701865943, 4679.181143299909, 4680.953190468052, 5454.154606036071, 5480.780768769205, 5553.694546799884, 5557.360903496069, 5561.777528898799, 1370.0751992405087, 1375.1817668487233, 1393.634238446308, 1395.1001603965385, 1396.2599822094305, 903.290916630442, 908.9000209976897, 922.7693076730817, 948.83869742144, 970.3450171134456, 5247.291615631917, 5263.7894484220205, 5302.814401495929, 5324.056719297506, 5329.280547126088, 5568.359638641527, 5573.888522229533, 5604.32748070226, 5617.38208797032, 5644.068728659808, 5124.538791508708, 5345.35855697311, 5345.465453454701, 5355.866103347447, 5428.724249801294, 2976.032789343472, 2981.0907673159895, 3015.909522714322, 3015.9396812063637, 3016.668166501683, 5694.567721474767, 5831.533477321804, 5866.472017518444, 5869.20765696634, 5929.495992840657, 5711.485966263056, 5732.77068917247, 5749.9425005750245, 5757.251557297608, 5771.740271687784, 5823.184754134425, 5858.414158584107, 5870.941290587125, 5906.173135760911, 5906.7341969612335, 5415.1877218415975, 5602.327720673546, 5644.238027866186, 5678.659280443187, 5692.1461780733225, 5554.278403582296, 5563.499285064202, 5584.190292407497, 5618.887622247614, 5635.83092507209, 5849.707514624337, 5907.139251135878, 5915.733791979285, 5943.227380440515, 5959.374265702038, 3956.3867600521967, 4188.308929026676, 4200.180964711974, 4206.831726730897, 4207.969047583361, 5679.971600141973, 5691.174779657009, 5712.285964254468, 5724.427557244242, 5746.856328591819, 5250.162443144869, 5480.506754392124, 5516.531094856982, 5586.553075754011, 5605.1872478491205, 5489.819688766935, 5500.752466138953, 5529.917051244315, 5554.91667624994, 5560.36055853582, 5204.479552044765, 5221.843344699513, 5433.891322173614, 5439.292891924025, 5442.455754424526, 5550.916736249041, 5553.0, 5585.162225666182, 5594.552435805207, 5616.747246373841, 5434.239206511171, 5441.891162176814, 5447.891042179213, 5459.972700136633, 5471.398146203835], "value": 4208.811228754662, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [726.6729971512818, 805.8509175802474, 838.1618381618382, 844.5397258494122, 882.6204731965253, 13143.802842957364, 13155.671108222276, 13174.7365052699, 13175.736485270312, 13277.46890124394, 12675.556355527558, 12702.237865728062, 12711.237325760461, 12729.93635031826, 12737.808932866015, 669.0, 781.0588241169391, 781.6951388958311, 800.4516905919435, 802.2699343597328, 787.7754839870638, 794.1581923161439, 794.7575989323254, 798.0822054637184, 799.1968072087573, 852.40757673417, 853.2832420766555, 853.3983541603155, 853.6372041882206, 853.9914600854005, 704.5526090932269, 826.9379796515256, 840.6763396092488, 841.7937605286683, 845.6997765793117, 615.4737699267107, 616.3897741236188, 616.7594638091148, 617.6417677746896, 617.7220250887119, 12938.805917911239, 12943.705629437076, 12946.093773435863, 12983.740325193538, 12989.090763646454, 13048.934755326163, 13321.26733029683, 13395.19628822264, 13415.530456434075, 13551.39018744159, 11906.880931190664, 11919.344436056013, 11992.880071199264, 12014.339211343373, 12193.207441516362, 4301.483821941356, 4362.411074504965, 4384.364267181273, 4530.954690453086, 4533.206688829475, 57666.0, 57825.42174578284, 57906.0, 59016.40983590152, 59286.11070833898, 10592.788144237073, 10817.621383251548, 10949.0, 11085.22403431752, 11144.888551114385, 18822.435326940213, 18875.716864247046, 18895.0, 18918.243270269035, 19383.903080484506, 7765.650545725456, 7889.447738658354, 7956.880646790303, 7986.880196797053, 8243.587820608946, 4469.910601788011, 4471.910561788746, 4476.37330773692, 4479.96960699041, 4570.9943812361225, 3340.0, 3412.5734283214597, 3421.2473255883665, 3427.862885484553, 3436.0894362993827, 572.0875204049535, 599.256921417448, 601.5969300568629, 606.9757209711562, 613.362103412455, 633.4805459523238, 645.2128403347908, 649.9480041596661, 665.5540787672271, 670.3865962644126, 8645.740627781177, 8710.651573937099, 8717.869231961526, 8748.168923952342, 8932.240759535382, 621.201755743867, 657.6514447342915, 660.3990368764402, 668.3750693101965, 683.2040672616346, 3914.628110329514, 4025.376066709606, 4093.4064560639135, 4094.63148316647, 4157.251694694924, 64688.0, 65759.34240657628, 66789.0, 66985.3301466979, 67558.0, 41517.1696566067, 50086.749566251936, 57142.0, 57967.0, 62590.687046564475, 51137.744311278206, 51174.23238651423, 51818.0, 51832.99999999924, 52226.73886630542, 7790.61046947639, 7855.567943763148, 7881.724139655314, 7931.524108553505, 8059.959700201462, 36417.81791091028, 37135.44296835604, 37137.81431092935, 37716.245675086895, 38270.61729382782, 47712.52287477081, 49313.26030109623, 49660.50339496703, 49920.50079499158, 49925.50074499354, 10269.794604107727, 10374.58501659925, 10505.842412363667, 10597.841032384365, 10726.249162558546, 10834.349939003685, 10878.347299161758, 10933.234673572773, 10944.343339399344, 11060.17048721333, 10081.193504519475, 10256.43589602579, 10857.891421086004, 11065.0, 11219.0, 9698.127168554864, 9932.801343973224, 10314.0, 10647.0, 10666.306690065121, 10358.430286334022, 10634.52144653508, 10656.8934310659, 10900.454977250947, 11049.72375690582, 8584.399092063495, 9198.770030749285, 9308.581113850028, 9574.760630984281, 9618.807623847344, 9403.85894211601, 9496.572654230715, 9523.714288571355, 9552.665656701982, 9588.232941364535], "value": 6454.020588738421, "unit": "score"}], "name": "dromaeo_css", "value": 7113.742738300303, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 105.0, 112.0, 112.0, 111.0, 112.0, 113.0, 109.0, 110.0, 110.0], "value": 110.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 128.0, 134.0, 181.0, 129.0, 155.0, 165.0, 127.0, 197.0, 124.0], "value": 149.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 162.0, 162.0, 161.0, 162.0, 161.0, 160.0, 162.0, 161.0], "value": 161.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 82.0, 105.0, 74.0, 75.0, 75.0, 74.0, 110.0, 76.0], "value": 82.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 129.0, 92.0, 92.0, 92.0, 141.0, 93.0, 93.0, 128.0], "value": 104.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 146.0, 145.0, 145.0, 146.0, 146.0, 147.0, 146.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 96.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 56.0, 54.0, 56.0, 55.0, 55.0, 75.0, 53.0, 52.0], "value": 56.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 62.0, 64.0, 64.0, 63.0, 63.0, 63.0, 63.0, 63.0], "value": 62.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 79.0, 78.0, 80.0, 80.0, 80.0, 79.0, 81.0, 81.0], "value": 79.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 122.0, 121.0, 122.0, 124.0, 117.0, 117.0, 120.0, 161.0, 127.0], "value": 125.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 168.0, 162.0, 173.0, 163.0, 164.0, 165.0, 160.0, 161.0, 161.0], "value": 164.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 62.0, 64.0, 69.0, 63.0, 67.0, 63.0, 63.0, 61.0, 63.0], "value": 64.3, "unit": "score"}], "name": "kraken", "value": 1519.0, "alertThreshold": 2.0}]} 16:19:52 INFO - Return code: 0 16:19:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:19:52 ERROR - # TBPL SUCCESS # 16:19:52 INFO - Running post-action listener: _resource_record_post_action 16:19:52 INFO - ##### 16:19:52 INFO - ##### Finished run-tests step (success) 16:19:52 INFO - ##### 16:19:52 INFO - Running post-run listener: _resource_record_post_run 16:19:52 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 0; Write bytes: 549265408; Read time: 0; Write time: 656296 16:19:52 INFO - TinderboxPrint: CPU usage
13.2% 16:19:52 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 16:19:52 INFO - TinderboxPrint: I/O write bytes / time
549,265,408 / 656,296 16:19:52 INFO - TinderboxPrint: CPU idle
8,265.0 (86.8%) 16:19:52 INFO - TinderboxPrint: CPU user
1,206.6 (12.7%) 16:19:52 INFO - TinderboxPrint: Swap in / out
0 / 0 16:19:52 INFO - install - Wall time: 17s; CPU: 16.0%; Read bytes: 0; Write bytes: 259559424; Read time: 0; Write time: 442752 16:19:52 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 0; Write bytes: 289705984; Read time: 0; Write time: 213544 16:19:52 INFO - Running post-run listener: _upload_blobber_files 16:19:52 INFO - Blob upload gear active. 16:19:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:19:52 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 16:19:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:19:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:19:53 INFO - (blobuploader) - INFO - Open directory for files ... 16:19:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:19:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:19:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:20:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:20:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:20:03 INFO - (blobuploader) - INFO - Done attempting. 16:20:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:20:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:20:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:20:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:20:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:20:04 INFO - (blobuploader) - INFO - Done attempting. 16:20:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:20:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:20:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:20:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:20:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:20:04 INFO - (blobuploader) - INFO - Done attempting. 16:20:04 INFO - (blobuploader) - INFO - Iteration through files over. 16:20:04 INFO - Return code: 0 16:20:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:20:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:20:04 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/cbec25ac2f0b0df969b7eb907b90df6acf77621ce7b5731dc4fcc3d77ed4ae769204bf5836b9f1ecb3870db202a66176dc6f6bb21cc7ba34d20bc27b9db238cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/96886d099d0fe3861c264b7909817023f87967996e725c4cd072c3a83873ab5fcf264f9107efd3a997ba1c2e1be57ba12dff3d8869541fda2b4a0be4bc66c654", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/f32936704b5a3040e4886b789473a674803edad688b4bce4f1fc1021e66af9fe96a4bf37a9eca8ca2d1b698dfa27597a978dcec8abf76fbc2aa4189c56d0c936"} 16:20:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:20:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:20:04 INFO - Contents: 16:20:04 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/cbec25ac2f0b0df969b7eb907b90df6acf77621ce7b5731dc4fcc3d77ed4ae769204bf5836b9f1ecb3870db202a66176dc6f6bb21cc7ba34d20bc27b9db238cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/96886d099d0fe3861c264b7909817023f87967996e725c4cd072c3a83873ab5fcf264f9107efd3a997ba1c2e1be57ba12dff3d8869541fda2b4a0be4bc66c654", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/f32936704b5a3040e4886b789473a674803edad688b4bce4f1fc1021e66af9fe96a4bf37a9eca8ca2d1b698dfa27597a978dcec8abf76fbc2aa4189c56d0c936"} 16:20:04 INFO - Running post-run listener: copy_logs_to_upload_dir 16:20:04 INFO - Copying logs to upload dir... 16:20:04 INFO - mkdir: /builds/slave/test/build/upload/logs 16:20:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1243.471116 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-05-22 16:20:02.258402) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-22 16:20:02.262036) ========= 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=52faae308a556c440401f8e5000001e8-1463952526.521703-1222471583 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/cbec25ac2f0b0df969b7eb907b90df6acf77621ce7b5731dc4fcc3d77ed4ae769204bf5836b9f1ecb3870db202a66176dc6f6bb21cc7ba34d20bc27b9db238cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/96886d099d0fe3861c264b7909817023f87967996e725c4cd072c3a83873ab5fcf264f9107efd3a997ba1c2e1be57ba12dff3d8869541fda2b4a0be4bc66c654", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/f32936704b5a3040e4886b789473a674803edad688b4bce4f1fc1021e66af9fe96a4bf37a9eca8ca2d1b698dfa27597a978dcec8abf76fbc2aa4189c56d0c936"} build_url:https://queue.taskcluster.net/v1/task/AZO1vee8RUuoPb3OLCWhFQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011182 build_url: 'https://queue.taskcluster.net/v1/task/AZO1vee8RUuoPb3OLCWhFQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/cbec25ac2f0b0df969b7eb907b90df6acf77621ce7b5731dc4fcc3d77ed4ae769204bf5836b9f1ecb3870db202a66176dc6f6bb21cc7ba34d20bc27b9db238cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/96886d099d0fe3861c264b7909817023f87967996e725c4cd072c3a83873ab5fcf264f9107efd3a997ba1c2e1be57ba12dff3d8869541fda2b4a0be4bc66c654", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/f32936704b5a3040e4886b789473a674803edad688b4bce4f1fc1021e66af9fe96a4bf37a9eca8ca2d1b698dfa27597a978dcec8abf76fbc2aa4189c56d0c936"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-22 16:20:02.287729) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-22 16:20:02.288070) ========= 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=52faae308a556c440401f8e5000001e8-1463952526.521703-1222471583 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004480 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-22 16:20:02.373780) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-22 16:20:02.374140) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-22 16:20:02.374481) ========= ========= Total master_lag: 0.15 =========