builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-026 starttime: 1463601573.32 results: success (0) buildid: 20160517073121 builduid: 91748e3a507046789edb563a3e6ea041 revision: 3780a3a6b83aeda143f9562829c830410a0c961e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-18 12:59:33.323854) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-18 12:59:33.324224) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-18 12:59:33.324637) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-18 12:59:33.429698) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 12:59:33.429956) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=41bf3e9aed84707956354da8000001c5-1463600856.741969-971307083 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-18 12:59:33-- 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% 67.0M=0s 2016-05-18 12:59:33 (67.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.212127 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 12:59:33.672259) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 12:59:33.672609) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=41bf3e9aed84707956354da8000001c5-1463600856.741969-971307083 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.136308 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 12:59:33.820341) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 12:59:33.820704) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3780a3a6b83aeda143f9562829c830410a0c961e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 3780a3a6b83aeda143f9562829c830410a0c961e --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=41bf3e9aed84707956354da8000001c5-1463600856.741969-971307083 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-18 12:59:33,962 truncating revision to first 12 chars 2016-05-18 12:59:33,962 Setting DEBUG logging. 2016-05-18 12:59:33,962 attempt 1/10 2016-05-18 12:59:33,962 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3780a3a6b83a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-18 12:59:34,214 unpacking tar archive at: mozilla-central-3780a3a6b83a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.521765 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 12:59:34.356072) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-18 12:59:34.356377) ========= script_repo_revision: 3780a3a6b83aeda143f9562829c830410a0c961e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-18 12:59:34.356861) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-18 12:59:34.357332) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-18 12:59:34.368984) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-05-18 12:59:34.369299) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=41bf3e9aed84707956354da8000001c5-1463600856.741969-971307083 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:59:34 INFO - MultiFileLogger online at 20160518 12:59:34 in /builds/slave/test 12:59:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 12:59:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:59:34 INFO - {'append_to_log': False, 12:59:34 INFO - 'base_work_dir': '/builds/slave/test', 12:59:34 INFO - 'blob_upload_branch': 'Firefox', 12:59:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:59:34 INFO - 'branch': 'Firefox', 12:59:34 INFO - 'buildbot_json_path': 'buildprops.json', 12:59:34 INFO - 'config_files': ('talos/linux_config.py',), 12:59:34 INFO - 'default_actions': ('clobber', 12:59:34 INFO - 'read-buildbot-config', 12:59:34 INFO - 'download-and-extract', 12:59:34 INFO - 'populate-webroot', 12:59:34 INFO - 'create-virtualenv', 12:59:34 INFO - 'install', 12:59:34 INFO - 'run-tests'), 12:59:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:59:34 INFO - 'download_minidump_stackwalk': True, 12:59:34 INFO - 'download_symbols': 'ondemand', 12:59:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:59:34 INFO - 'tooltool.py': '/tools/tooltool.py', 12:59:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:59:34 INFO - '/tools/misc-python/virtualenv.py')}, 12:59:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:59:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:59:34 INFO - 'installer_path': 'installer.exe', 12:59:34 INFO - 'log_level': 'info', 12:59:34 INFO - 'log_name': 'talos', 12:59:34 INFO - 'log_to_console': True, 12:59:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:59:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:59:34 INFO - 'opt_config_files': (), 12:59:34 INFO - 'pip_index': False, 12:59:34 INFO - 'sps_profile': False, 12:59:34 INFO - 'sps_profile_interval': 0, 12:59:34 INFO - 'suite': 'dromaeojs', 12:59:34 INFO - 'system_bits': '32', 12:59:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:59:34 INFO - 'title': 'talos-linux64-ix-026', 12:59:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:59:34 INFO - 'use_talos_json': True, 12:59:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:59:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:59:34 INFO - 'work_dir': 'build'} 12:59:34 INFO - ##### 12:59:34 INFO - ##### Running clobber step. 12:59:34 INFO - ##### 12:59:34 INFO - Running pre-action listener: _resource_record_pre_action 12:59:34 INFO - Running main action method: clobber 12:59:34 INFO - rmtree: /builds/slave/test/build 12:59:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:59:36 INFO - Running post-action listener: _resource_record_post_action 12:59:36 INFO - ##### 12:59:36 INFO - ##### Finished clobber step (success) 12:59:36 INFO - ##### 12:59:36 INFO - ##### 12:59:36 INFO - ##### Running read-buildbot-config step. 12:59:36 INFO - ##### 12:59:36 INFO - Running pre-action listener: _resource_record_pre_action 12:59:36 INFO - Running main action method: read_buildbot_config 12:59:36 INFO - Using buildbot properties: 12:59:36 INFO - { 12:59:36 INFO - "project": "", 12:59:36 INFO - "product": "firefox", 12:59:36 INFO - "script_repo_revision": "production", 12:59:36 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 12:59:36 INFO - "repository": "", 12:59:36 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 12:59:36 INFO - "buildid": "20160517073121", 12:59:36 INFO - "pgo_build": "True", 12:59:36 INFO - "basedir": "/builds/slave/test", 12:59:36 INFO - "buildnumber": 449, 12:59:36 INFO - "slavename": "talos-linux64-ix-026", 12:59:36 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 12:59:36 INFO - "platform": "ubuntu64_hw", 12:59:36 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 12:59:36 INFO - "branch": "mozilla-central", 12:59:36 INFO - "repo_path": "mozilla-central", 12:59:36 INFO - "revision": "3780a3a6b83aeda143f9562829c830410a0c961e", 12:59:36 INFO - "stage_platform": "linux64-pgo", 12:59:36 INFO - "builduid": "91748e3a507046789edb563a3e6ea041", 12:59:36 INFO - "slavebuilddir": "test" 12:59:36 INFO - } 12:59:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 12:59:36 INFO - Running post-action listener: _resource_record_post_action 12:59:36 INFO - ##### 12:59:36 INFO - ##### Finished read-buildbot-config step (success) 12:59:36 INFO - ##### 12:59:36 INFO - ##### 12:59:36 INFO - ##### Running download-and-extract step. 12:59:36 INFO - ##### 12:59:36 INFO - Running pre-action listener: _resource_record_pre_action 12:59:36 INFO - Running main action method: download_and_extract 12:59:36 INFO - mkdir: /builds/slave/test/build/tests 12: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')]} 12:59:36 INFO - https://queue.taskcluster.net/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 12:59:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 12:59:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 12:59:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/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 12:59:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/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 12:59:47 INFO - Downloaded 1361 bytes. 12:59:47 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 12:59:47 INFO - Using the following test package requirements: 12:59:47 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 12:59:47 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 12:59:47 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 12:59:47 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 12:59:47 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 12:59:47 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 12:59:47 INFO - u'jsshell-linux-x86_64.zip'], 12:59:47 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 12:59:47 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 12:59:47 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 12:59:47 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 12:59:47 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 12:59:47 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 12:59:47 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 12:59:47 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 12:59:47 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 12:59:47 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 12:59:47 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 12:59:47 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 12:59:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:59:47 INFO - https://queue.taskcluster.net/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:59:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 12:59:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 12:59:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/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 12:59:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/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 12:59:48 INFO - Downloaded 24023024 bytes. 12:59:48 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'] 12:59:48 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 12:59:50 INFO - Return code: 0 12:59:50 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 12:59:50 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')]} 12:59:50 INFO - https://queue.taskcluster.net/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:59:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 12:59:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 12:59:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/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 12:59:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/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 13:00:16 INFO - Downloaded 24023024 bytes. 13:00:16 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'] 13:00:16 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 13:00:17 INFO - Return code: 0 13:00:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:00:17 INFO - https://queue.taskcluster.net/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 13:00:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 13:00:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 13:00:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/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 13:00:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/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 13:00:19 INFO - Downloaded 10840194 bytes. 13:00:19 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'] 13:00:19 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 13:00:19 INFO - Return code: 0 13:00:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:00:19 INFO - https://queue.taskcluster.net/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 13:00:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 13:00:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 13:00:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/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 13:00:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/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 13:00:21 INFO - Downloaded 58399391 bytes. 13:00:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 13:00:21 INFO - mkdir: /builds/slave/test/properties 13:00:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:00:21 INFO - Writing to file /builds/slave/test/properties/build_url 13:00:21 INFO - Contents: 13:00:21 INFO - build_url:https://queue.taskcluster.net/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 13:00:22 INFO - Running post-action listener: _resource_record_post_action 13:00:22 INFO - Running post-action listener: set_extra_try_arguments 13:00:22 INFO - ##### 13:00:22 INFO - ##### Finished download-and-extract step (success) 13:00:22 INFO - ##### 13:00:22 INFO - ##### 13:00:22 INFO - ##### Running populate-webroot step. 13:00:22 INFO - ##### 13:00:22 INFO - Running pre-action listener: _resource_record_pre_action 13:00:22 INFO - Running main action method: populate_webroot 13:00:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:00:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:00:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:00:22 INFO - '--tppagecycles', 13:00:22 INFO - '7'], 13:00:22 INFO - 'tests': ['tsvgm']}}, 13:00:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:00:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:00:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:00:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:00:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:00:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:00:22 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:00:22 INFO - 'talos_options': ['--disable-e10s'], 13:00:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:00:22 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:00:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:00:22 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:00:22 INFO - 'talos_options': ['--disable-e10s'], 13:00:22 INFO - 'tests': ['damp', 'tps']}, 13:00:22 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:00:22 INFO - 'tests': ['damp', 'tps']}, 13:00:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:00:22 INFO - 'tests': ['dromaeo_dom']}, 13:00:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:00:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:00:22 INFO - 'tests': ['a11yr', 13:00:22 INFO - 'ts_paint', 13:00:22 INFO - 'tpaint', 13:00:22 INFO - 'sessionrestore', 13:00:22 INFO - 'sessionrestore_no_auto_restore', 13:00:22 INFO - 'tabpaint']}, 13:00:22 INFO - 'other-e10s': {'tests': ['a11yr', 13:00:22 INFO - 'ts_paint', 13:00:22 INFO - 'tpaint', 13:00:22 INFO - 'sessionrestore', 13:00:22 INFO - 'sessionrestore_no_auto_restore', 13:00:22 INFO - 'tabpaint']}, 13:00:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:00:22 INFO - 'ts_paint', 13:00:22 INFO - 'tpaint', 13:00:22 INFO - 'sessionrestore', 13:00:22 INFO - 'sessionrestore_no_auto_restore', 13:00:22 INFO - 'tabpaint']}, 13:00:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:00:22 INFO - 'ts_paint', 13:00:22 INFO - 'tpaint', 13:00:22 INFO - 'sessionrestore', 13:00:22 INFO - 'sessionrestore_no_auto_restore', 13:00:22 INFO - 'tabpaint']}, 13:00:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:00:22 INFO - 'tests': ['a11yr', 13:00:22 INFO - 'ts_paint', 13:00:22 INFO - 'tpaint', 13:00:22 INFO - 'sessionrestore', 13:00:22 INFO - 'sessionrestore_no_auto_restore', 13:00:22 INFO - 'tabpaint']}, 13:00:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:00:22 INFO - 'tests': ['a11yr', 13:00:22 INFO - 'ts_paint', 13:00:22 INFO - 'tpaint', 13:00:22 INFO - 'sessionrestore', 13:00:22 INFO - 'sessionrestore_no_auto_restore', 13:00:22 INFO - 'tabpaint']}, 13:00:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:00:22 INFO - 'tests': ['tsvgx', 13:00:22 INFO - 'tsvgr_opacity', 13:00:22 INFO - 'tart', 13:00:22 INFO - 'tscrollx', 13:00:22 INFO - 'cart']}, 13:00:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:00:22 INFO - 'tsvgr_opacity', 13:00:22 INFO - 'tart', 13:00:22 INFO - 'tscrollx', 13:00:22 INFO - 'cart']}, 13:00:22 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:00:22 INFO - 'talos_options': ['--disable-e10s'], 13:00:22 INFO - 'tests': ['tp5o']}, 13:00:22 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:00:22 INFO - 'tests': ['tp5o']}, 13:00:22 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:00:22 INFO - 'talos_options': ['--disable-e10s', 13:00:22 INFO - '--xperf_path', 13:00:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:00:22 INFO - 'tests': ['tp5n']}, 13:00:22 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:00:22 INFO - 'talos_options': ['--xperf_path', 13:00:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:00:22 INFO - 'tests': ['tp5n']}}, 13:00:22 INFO - 'talos.zip': {'path': '', 13:00:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:00:22 INFO - Running post-action listener: _resource_record_post_action 13:00:22 INFO - ##### 13:00:22 INFO - ##### Finished populate-webroot step (success) 13:00:22 INFO - ##### 13:00:22 INFO - ##### 13:00:22 INFO - ##### Running create-virtualenv step. 13:00:22 INFO - ##### 13:00:22 INFO - Running pre-action listener: _resource_record_pre_action 13:00:22 INFO - Running main action method: create_virtualenv 13:00:22 INFO - Creating virtualenv /builds/slave/test/build/venv 13:00:22 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 13:00:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:00:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:00:22 INFO - Using real prefix '/usr' 13:00:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:00:23 INFO - Installing distribute.............................................................................................................................................................................................done. 13:00:25 INFO - Installing pip.................done. 13:00:25 INFO - Return code: 0 13:00:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:00: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')]} 13:00:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:00:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00: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')]} 13:00:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:00:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:25 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 0x7f3663cbff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdef8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xeba770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf499c0>, '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 0xf4a8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf4ad50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1463600856.741969-971307083', '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 13:00:25 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 13:00:25 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 13:00:25 INFO - Using env: {'DISPLAY': ':0', 13:00:25 INFO - 'HOME': '/home/cltbld', 13:00:25 INFO - 'LANG': 'en_US.UTF-8', 13:00:25 INFO - 'LANGUAGE': 'en_US:en', 13:00:25 INFO - 'LOGNAME': 'cltbld', 13:00:25 INFO - 'MAIL': '/var/mail/cltbld', 13:00:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:00:25 INFO - 'MOZ_NO_REMOTE': '1', 13:00:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:00:25 INFO - 'NO_EM_RESTART': '1', 13:00:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:00:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:00:25 INFO - 'PWD': '/builds/slave/test', 13:00:25 INFO - 'SHELL': '/bin/bash', 13:00:25 INFO - 'SHLVL': '1', 13:00:25 INFO - 'TERM': 'linux', 13:00:25 INFO - 'TMOUT': '86400', 13:00:25 INFO - 'USER': 'cltbld', 13:00:25 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1463600856.741969-971307083', 13:00:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:00:25 INFO - '_': '/tools/buildbot/bin/python'} 13:00:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:00:25 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 13:00:25 INFO - Cleaning up... 13:00:25 INFO - Return code: 0 13:00:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:00: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')]} 13:00:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:00:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00: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')]} 13:00:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:00:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:25 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 0x7f3663cbff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdef8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xeba770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf499c0>, '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 0xf4a8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf4ad50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1463600856.741969-971307083', '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 13:00:25 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 13:00:25 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 13:00:25 INFO - Using env: {'DISPLAY': ':0', 13:00:25 INFO - 'HOME': '/home/cltbld', 13:00:25 INFO - 'LANG': 'en_US.UTF-8', 13:00:25 INFO - 'LANGUAGE': 'en_US:en', 13:00:25 INFO - 'LOGNAME': 'cltbld', 13:00:25 INFO - 'MAIL': '/var/mail/cltbld', 13:00:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:00:25 INFO - 'MOZ_NO_REMOTE': '1', 13:00:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:00:25 INFO - 'NO_EM_RESTART': '1', 13:00:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:00:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:00:25 INFO - 'PWD': '/builds/slave/test', 13:00:25 INFO - 'SHELL': '/bin/bash', 13:00:25 INFO - 'SHLVL': '1', 13:00:25 INFO - 'TERM': 'linux', 13:00:25 INFO - 'TMOUT': '86400', 13:00:25 INFO - 'USER': 'cltbld', 13:00:25 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1463600856.741969-971307083', 13:00:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:00:25 INFO - '_': '/tools/buildbot/bin/python'} 13:00:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:00:26 INFO - Downloading/unpacking psutil>=3.1.1 13:00:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:00:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:00:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:00:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:00:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:00:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:00:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:00:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:00:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:00:28 INFO - Installing collected packages: psutil 13:00:28 INFO - Running setup.py install for psutil 13:00:28 INFO - building 'psutil._psutil_linux' extension 13:00:28 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 13:00:29 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 13:00:29 INFO - building 'psutil._psutil_posix' extension 13:00:29 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 13:00:29 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 13:00:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:00:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:00:29 INFO - Successfully installed psutil 13:00:29 INFO - Cleaning up... 13:00:29 INFO - Return code: 0 13:00:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:00:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:00:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:00:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:00:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:00:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:29 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 0x7f3663cbff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdef8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xeba770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf499c0>, '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 0xf4a8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf4ad50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1463600856.741969-971307083', '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 13:00:29 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 13:00:29 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 13:00:29 INFO - Using env: {'DISPLAY': ':0', 13:00:29 INFO - 'HOME': '/home/cltbld', 13:00:29 INFO - 'LANG': 'en_US.UTF-8', 13:00:29 INFO - 'LANGUAGE': 'en_US:en', 13:00:29 INFO - 'LOGNAME': 'cltbld', 13:00:29 INFO - 'MAIL': '/var/mail/cltbld', 13:00:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:00:29 INFO - 'MOZ_NO_REMOTE': '1', 13:00:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:00:29 INFO - 'NO_EM_RESTART': '1', 13:00:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:00:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:00:29 INFO - 'PWD': '/builds/slave/test', 13:00:29 INFO - 'SHELL': '/bin/bash', 13:00:29 INFO - 'SHLVL': '1', 13:00:29 INFO - 'TERM': 'linux', 13:00:29 INFO - 'TMOUT': '86400', 13:00:29 INFO - 'USER': 'cltbld', 13:00:29 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1463600856.741969-971307083', 13:00:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:00:29 INFO - '_': '/tools/buildbot/bin/python'} 13:00:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:00:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:00:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:00:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:00:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:00:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:00:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:00:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:00:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:00:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:00:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:00:31 INFO - Installing collected packages: mozsystemmonitor 13:00:31 INFO - Running setup.py install for mozsystemmonitor 13:00:31 INFO - Successfully installed mozsystemmonitor 13:00:31 INFO - Cleaning up... 13:00:31 INFO - Return code: 0 13:00:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:00:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:00:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:00:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:00:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:00:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f3663cbff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdef8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xeba770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf499c0>, '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 0xf4a8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf4ad50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1463600856.741969-971307083', '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 13:00:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 13:00:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 13:00:31 INFO - Using env: {'DISPLAY': ':0', 13:00:31 INFO - 'HOME': '/home/cltbld', 13:00:31 INFO - 'LANG': 'en_US.UTF-8', 13:00:31 INFO - 'LANGUAGE': 'en_US:en', 13:00:31 INFO - 'LOGNAME': 'cltbld', 13:00:31 INFO - 'MAIL': '/var/mail/cltbld', 13:00:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:00:31 INFO - 'MOZ_NO_REMOTE': '1', 13:00:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:00:31 INFO - 'NO_EM_RESTART': '1', 13:00:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:00:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:00:31 INFO - 'PWD': '/builds/slave/test', 13:00:31 INFO - 'SHELL': '/bin/bash', 13:00:31 INFO - 'SHLVL': '1', 13:00:31 INFO - 'TERM': 'linux', 13:00:31 INFO - 'TMOUT': '86400', 13:00:31 INFO - 'USER': 'cltbld', 13:00:32 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1463600856.741969-971307083', 13:00:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:00:32 INFO - '_': '/tools/buildbot/bin/python'} 13:00:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:00:32 INFO - Downloading/unpacking blobuploader==1.2.4 13:00:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:00:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:00:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:00:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:00:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:00:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:00:34 INFO - Downloading blobuploader-1.2.4.tar.gz 13:00:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:00:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:00:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:00:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:00:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:00:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:00:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:00:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:00:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:00:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:00:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:00:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:00:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:00:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:00:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:00:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:00:34 INFO - Downloading docopt-0.6.1.tar.gz 13:00:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:00:34 INFO - Installing collected packages: blobuploader, requests, docopt 13:00:34 INFO - Running setup.py install for blobuploader 13:00:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:00:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:00:34 INFO - Running setup.py install for requests 13:00:35 INFO - Running setup.py install for docopt 13:00:35 INFO - Successfully installed blobuploader requests docopt 13:00:35 INFO - Cleaning up... 13:00:35 INFO - Return code: 0 13:00:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:00:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:00:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:00:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:00:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:00:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3663cbff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdef8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xeba770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf499c0>, '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 0xf4a8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf4ad50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1463600856.741969-971307083', '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 13:00:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 13:00:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:00:35 INFO - Using env: {'DISPLAY': ':0', 13:00:35 INFO - 'HOME': '/home/cltbld', 13:00:35 INFO - 'LANG': 'en_US.UTF-8', 13:00:35 INFO - 'LANGUAGE': 'en_US:en', 13:00:35 INFO - 'LOGNAME': 'cltbld', 13:00:35 INFO - 'MAIL': '/var/mail/cltbld', 13:00:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:00:35 INFO - 'MOZ_NO_REMOTE': '1', 13:00:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:00:35 INFO - 'NO_EM_RESTART': '1', 13:00:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:00:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:00:35 INFO - 'PWD': '/builds/slave/test', 13:00:35 INFO - 'SHELL': '/bin/bash', 13:00:35 INFO - 'SHLVL': '1', 13:00:35 INFO - 'TERM': 'linux', 13:00:35 INFO - 'TMOUT': '86400', 13:00:35 INFO - 'USER': 'cltbld', 13:00:35 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1463600856.741969-971307083', 13:00:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:00:35 INFO - '_': '/tools/buildbot/bin/python'} 13:00:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:00:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:00:35 INFO - Running setup.py (path:/tmp/pip-A4jqpm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:00:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:00:35 INFO - Running setup.py (path:/tmp/pip-cW7vCw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:00:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:00:35 INFO - Running setup.py (path:/tmp/pip-svtU_K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:00:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:00:35 INFO - Running setup.py (path:/tmp/pip-TYxqSd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:00:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:00:35 INFO - Running setup.py (path:/tmp/pip-3pjP8Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:00:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:00:36 INFO - Running setup.py (path:/tmp/pip-P573Z_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:00:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:00:36 INFO - Running setup.py (path:/tmp/pip-qi2MMS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:00:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:00:36 INFO - Running setup.py (path:/tmp/pip-ya1yOv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:00:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:00:36 INFO - Running setup.py (path:/tmp/pip-emkW_9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:00:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:00:36 INFO - Running setup.py (path:/tmp/pip-TdyTJo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:00:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:00:36 INFO - Running setup.py (path:/tmp/pip-H2lYjc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:00:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:00:36 INFO - Running setup.py (path:/tmp/pip-829pbJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:00:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:00:36 INFO - Running setup.py (path:/tmp/pip-0lwrjx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:00:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:00:36 INFO - Running setup.py (path:/tmp/pip-hZgXkW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:00:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:00:36 INFO - Running setup.py (path:/tmp/pip-AuZEKZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:00:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:00:36 INFO - Running setup.py (path:/tmp/pip-hluYgj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:00:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:00:36 INFO - Running setup.py (path:/tmp/pip-Qc9ppI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:00:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:00:36 INFO - Running setup.py install for manifestparser 13:00:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:00:37 INFO - Running setup.py install for mozcrash 13:00:37 INFO - Running setup.py install for mozdebug 13:00:37 INFO - Running setup.py install for mozdevice 13:00:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:00:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:00:37 INFO - Running setup.py install for mozfile 13:00:37 INFO - Running setup.py install for mozhttpd 13:00:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:00:37 INFO - Running setup.py install for mozinfo 13:00:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:00:37 INFO - Running setup.py install for mozInstall 13:00:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:00:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:00:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:00:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:00:37 INFO - Running setup.py install for mozleak 13:00:37 INFO - Running setup.py install for mozlog 13:00:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:00:37 INFO - Running setup.py install for moznetwork 13:00:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:00:38 INFO - Running setup.py install for mozprocess 13:00:38 INFO - Running setup.py install for mozprofile 13:00:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:00:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:00:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:00:38 INFO - Running setup.py install for mozrunner 13:00:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:00:38 INFO - Running setup.py install for mozscreenshot 13:00:38 INFO - Running setup.py install for moztest 13:00:38 INFO - Running setup.py install for mozversion 13:00:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:00:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:00:38 INFO - Cleaning up... 13:00:38 INFO - Return code: 0 13:00:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:00:38 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')]} 13:00:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:00:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:38 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')]} 13:00:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:00:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:38 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 0x7f3663cbff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdef8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xeba770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf499c0>, '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 0xf4a8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf4ad50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1463600856.741969-971307083', '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 13:00:38 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 13:00:38 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 13:00:38 INFO - Using env: {'DISPLAY': ':0', 13:00:38 INFO - 'HOME': '/home/cltbld', 13:00:38 INFO - 'LANG': 'en_US.UTF-8', 13:00:38 INFO - 'LANGUAGE': 'en_US:en', 13:00:38 INFO - 'LOGNAME': 'cltbld', 13:00:38 INFO - 'MAIL': '/var/mail/cltbld', 13:00:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:00:38 INFO - 'MOZ_NO_REMOTE': '1', 13:00:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:00:38 INFO - 'NO_EM_RESTART': '1', 13:00:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:00:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:00:38 INFO - 'PWD': '/builds/slave/test', 13:00:38 INFO - 'SHELL': '/bin/bash', 13:00:38 INFO - 'SHLVL': '1', 13:00:38 INFO - 'TERM': 'linux', 13:00:38 INFO - 'TMOUT': '86400', 13:00:38 INFO - 'USER': 'cltbld', 13:00:38 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1463600856.741969-971307083', 13:00:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:00:38 INFO - '_': '/tools/buildbot/bin/python'} 13:00:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:00:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:00:38 INFO - Running setup.py (path:/tmp/pip-5T4274-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:00:38 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)) 13:00:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:00:38 INFO - Running setup.py (path:/tmp/pip-l1sk5V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:00:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:00:39 INFO - Running setup.py (path:/tmp/pip-CCIQoT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:00:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 13:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:00:39 INFO - Running setup.py (path:/tmp/pip-MLnzjD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:00:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:00:39 INFO - Running setup.py (path:/tmp/pip-oJwJjB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:00:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 13:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:00:39 INFO - Running setup.py (path:/tmp/pip-bIuLLp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:00:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 13:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:00:39 INFO - Running setup.py (path:/tmp/pip-7gk0mQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:00:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 13:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:00:39 INFO - Running setup.py (path:/tmp/pip-qX8qXM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:00:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 13:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:00:39 INFO - Running setup.py (path:/tmp/pip-xL7s2T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:00:39 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)) 13:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:00:39 INFO - Running setup.py (path:/tmp/pip-HSREBi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:00:39 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)) 13:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:00:39 INFO - Running setup.py (path:/tmp/pip-Y1subv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:00:39 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)) 13:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:00:39 INFO - Running setup.py (path:/tmp/pip-OIQLXq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:00:39 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)) 13:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:00:39 INFO - Running setup.py (path:/tmp/pip-CIBRYC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:00:39 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)) 13:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:00:39 INFO - Running setup.py (path:/tmp/pip-zPqiVZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:00:39 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)) 13:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:00:39 INFO - Running setup.py (path:/tmp/pip-Guk7Wk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:00:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 13:00:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:00:40 INFO - Running setup.py (path:/tmp/pip-ES8CL9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:00:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 13:00:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:00:40 INFO - Running setup.py (path:/tmp/pip-LLB9SI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:00:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 13:00:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:00:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:00:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 13:00:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:00:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:00:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:00:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:00:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:00:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:00:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:00:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:00:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:00:42 INFO - Downloading blessings-1.6.tar.gz 13:00:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:00:42 INFO - Installing collected packages: blessings 13:00:42 INFO - Running setup.py install for blessings 13:00:42 INFO - Successfully installed blessings 13:00:42 INFO - Cleaning up... 13:00:42 INFO - Return code: 0 13:00:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:00:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:00:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:00:42 INFO - Reading from file tmpfile_stdout 13:00:42 INFO - Current package versions: 13:00:42 INFO - argparse == 1.2.1 13:00:42 INFO - blessings == 1.6 13:00:42 INFO - blobuploader == 1.2.4 13:00:42 INFO - docopt == 0.6.1 13:00:42 INFO - manifestparser == 1.1 13:00:42 INFO - mozInstall == 1.12 13:00:42 INFO - mozcrash == 0.17 13:00:42 INFO - mozdebug == 0.1 13:00:42 INFO - mozdevice == 0.48 13:00:42 INFO - mozfile == 1.2 13:00:42 INFO - mozhttpd == 0.7 13:00:42 INFO - mozinfo == 0.9 13:00:42 INFO - mozleak == 0.1 13:00:42 INFO - mozlog == 3.2 13:00:42 INFO - moznetwork == 0.27 13:00:42 INFO - mozprocess == 0.22 13:00:42 INFO - mozprofile == 0.28 13:00:42 INFO - mozrunner == 6.11 13:00:42 INFO - mozscreenshot == 0.1 13:00:42 INFO - mozsystemmonitor == 0.3 13:00:42 INFO - moztest == 0.7 13:00:42 INFO - mozversion == 1.4 13:00:42 INFO - psutil == 3.1.1 13:00:42 INFO - requests == 1.2.3 13:00:42 INFO - wsgiref == 0.1.2 13:00:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:00: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')]} 13:00:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:00:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00: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')]} 13:00:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:00:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3663cbff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdef8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xeba770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf499c0>, '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 0xf4a8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf4ad50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1463600856.741969-971307083', '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 13:00:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 13:00:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:00:42 INFO - Using env: {'DISPLAY': ':0', 13:00:42 INFO - 'HOME': '/home/cltbld', 13:00:42 INFO - 'LANG': 'en_US.UTF-8', 13:00:42 INFO - 'LANGUAGE': 'en_US:en', 13:00:42 INFO - 'LOGNAME': 'cltbld', 13:00:42 INFO - 'MAIL': '/var/mail/cltbld', 13:00:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:00:42 INFO - 'MOZ_NO_REMOTE': '1', 13:00:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:00:42 INFO - 'NO_EM_RESTART': '1', 13:00:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:00:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:00:42 INFO - 'PWD': '/builds/slave/test', 13:00:42 INFO - 'SHELL': '/bin/bash', 13:00:42 INFO - 'SHLVL': '1', 13:00:42 INFO - 'TERM': 'linux', 13:00:42 INFO - 'TMOUT': '86400', 13:00:42 INFO - 'USER': 'cltbld', 13:00:42 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1463600856.741969-971307083', 13:00:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:00:42 INFO - '_': '/tools/buildbot/bin/python'} 13:00:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:00:42 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)) 13:00:42 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)) 13:00:42 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)) 13:00:42 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)) 13:00:42 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)) 13:00:42 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)) 13:00:42 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)) 13:00:42 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)) 13:00:42 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)) 13:00:42 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)) 13:00:42 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)) 13:00:42 INFO - Cleaning up... 13:00:42 INFO - Return code: 0 13:00:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:00: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')]} 13:00:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:00:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00: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')]} 13:00:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:00:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3663cbff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdef8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xeba770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf499c0>, '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 0xf4a8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf4ad50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1463600856.741969-971307083', '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 13:00: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', 'jsonschema'] in /builds/slave/test/build 13:00: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 jsonschema 13:00:42 INFO - Using env: {'DISPLAY': ':0', 13:00:42 INFO - 'HOME': '/home/cltbld', 13:00:42 INFO - 'LANG': 'en_US.UTF-8', 13:00:42 INFO - 'LANGUAGE': 'en_US:en', 13:00:42 INFO - 'LOGNAME': 'cltbld', 13:00:42 INFO - 'MAIL': '/var/mail/cltbld', 13:00:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:00:42 INFO - 'MOZ_NO_REMOTE': '1', 13:00:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:00:42 INFO - 'NO_EM_RESTART': '1', 13:00:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:00:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:00:42 INFO - 'PWD': '/builds/slave/test', 13:00:42 INFO - 'SHELL': '/bin/bash', 13:00:42 INFO - 'SHLVL': '1', 13:00:42 INFO - 'TERM': 'linux', 13:00:42 INFO - 'TMOUT': '86400', 13:00:42 INFO - 'USER': 'cltbld', 13:00:42 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1463600856.741969-971307083', 13:00:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:00:42 INFO - '_': '/tools/buildbot/bin/python'} 13:00:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:00:42 INFO - Downloading/unpacking jsonschema 13:00: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 13:00: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 13:00: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 13:00:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:00:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:00:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:00:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:00:44 INFO - Downloading/unpacking functools32 (from jsonschema) 13:00: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 13:00: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 13:00: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 13:00: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 13:00: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 13:00: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 13:00:45 INFO - Downloading functools32-3.2.3-2.tar.gz 13:00:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:00:45 INFO - warning: no files found matching '*.txt' 13:00:45 INFO - no previously-included directories found matching 'build' 13:00:45 INFO - no previously-included directories found matching 'dist' 13:00:45 INFO - no previously-included directories found matching '.git*' 13:00:45 INFO - Installing collected packages: jsonschema, functools32 13:00:45 INFO - Running setup.py install for functools32 13:00:45 INFO - warning: no files found matching '*.txt' 13:00:45 INFO - no previously-included directories found matching 'build' 13:00:45 INFO - no previously-included directories found matching 'dist' 13:00:45 INFO - no previously-included directories found matching '.git*' 13:00:45 INFO - Successfully installed jsonschema functools32 13:00:45 INFO - Cleaning up... 13:00:45 INFO - Return code: 0 13:00:45 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 13:00: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')]} 13:00:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:00:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00: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')]} 13:00:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:00:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:45 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 0x7f3663cbff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdef8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xeba770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf499c0>, '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 0xf4a8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf4ad50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1463600856.741969-971307083', '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 13:00:45 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 13:00:45 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 13:00:45 INFO - Using env: {'DISPLAY': ':0', 13:00:45 INFO - 'HOME': '/home/cltbld', 13:00:45 INFO - 'LANG': 'en_US.UTF-8', 13:00:45 INFO - 'LANGUAGE': 'en_US:en', 13:00:45 INFO - 'LOGNAME': 'cltbld', 13:00:45 INFO - 'MAIL': '/var/mail/cltbld', 13:00:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:00:45 INFO - 'MOZ_NO_REMOTE': '1', 13:00:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:00:45 INFO - 'NO_EM_RESTART': '1', 13:00:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:00:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:00:45 INFO - 'PWD': '/builds/slave/test', 13:00:45 INFO - 'SHELL': '/bin/bash', 13:00:45 INFO - 'SHLVL': '1', 13:00:45 INFO - 'TERM': 'linux', 13:00:45 INFO - 'TMOUT': '86400', 13:00:45 INFO - 'USER': 'cltbld', 13:00:45 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1463600856.741969-971307083', 13:00:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:00:45 INFO - '_': '/tools/buildbot/bin/python'} 13:00:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:00:45 INFO - Downloading/unpacking flake8 13:00:45 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 13:00:45 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 13:00:45 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 13:00:45 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 13:00:45 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 13:00:45 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 13:00:47 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 13:00:47 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 13:00:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:00:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:00:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:00:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:00:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:00:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:00:47 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 13:00:47 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 13:00:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:00:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:00:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:00:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:00:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:00:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:00:47 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 13:00:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:00:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:00:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:00:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:00:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:00:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:00:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 13:00:48 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:00:48 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:00:48 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:00:48 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:00:48 INFO - no previously-included directories found matching 'docs/_build' 13:00:48 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 13:00:48 INFO - Running setup.py install for pep8 13:00:48 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:00:48 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:00:48 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:00:48 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:00:48 INFO - no previously-included directories found matching 'docs/_build' 13:00:48 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 13:00:48 INFO - Successfully installed flake8 mccabe pyflakes pep8 13:00:48 INFO - Cleaning up... 13:00:48 INFO - Return code: 0 13:00:48 INFO - Running post-action listener: _resource_record_post_action 13:00:48 INFO - Running post-action listener: _start_resource_monitoring 13:00:48 INFO - Starting resource monitoring. 13:00:48 INFO - ##### 13:00:48 INFO - ##### Finished create-virtualenv step (success) 13:00:48 INFO - ##### 13:00:48 INFO - ##### 13:00:48 INFO - ##### Running install step. 13:00:48 INFO - ##### 13:00:48 INFO - Running pre-action listener: _resource_record_pre_action 13:00:48 INFO - Running main action method: install 13:00:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:00:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:00:48 INFO - Reading from file tmpfile_stdout 13:00:48 INFO - Detecting whether we're running mozinstall >=1.0... 13:00:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:00:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:00:48 INFO - Reading from file tmpfile_stdout 13:00:48 INFO - Output received: 13:00:48 INFO - Usage: mozinstall [options] installer 13:00:48 INFO - Options: 13:00:48 INFO - -h, --help show this help message and exit 13:00:48 INFO - -d DEST, --destination=DEST 13:00:48 INFO - Directory to install application into. [default: 13:00:48 INFO - "/builds/slave/test"] 13:00:48 INFO - --app=APP Application being installed. [default: firefox] 13:00:48 INFO - mkdir: /builds/slave/test/build/application 13:00:48 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'] 13:00:48 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 13:01:05 INFO - Reading from file tmpfile_stdout 13:01:05 INFO - Output received: 13:01:05 INFO - /builds/slave/test/build/application/firefox/firefox 13:01:05 INFO - Running post-action listener: _resource_record_post_action 13:01:05 INFO - ##### 13:01:05 INFO - ##### Finished install step (success) 13:01:05 INFO - ##### 13:01:05 INFO - ##### 13:01:05 INFO - ##### Running run-tests step. 13:01:05 INFO - ##### 13:01:05 INFO - Running pre-action listener: _resource_record_pre_action 13:01:05 INFO - Running main action method: run_tests 13:01:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:01:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:01:05 INFO - Python 2.7.3 13:01:05 INFO - Return code: 0 13:01:05 INFO - grabbing minidump binary from tooltool 13:01:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:01:05 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 0xf499c0>, '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 0xf4a8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf4ad50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:01:05 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 13:01:05 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 13:01:05 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 13:01:05 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:01:05 INFO - Return code: 0 13:01:05 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 13:01:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:01:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:01:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 13:01:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:01:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 13:01:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 13:01:06 INFO - Return code: 0 13:01:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-026', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 13:01:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-026 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 13:01:06 INFO - Using env: {'DISPLAY': ':0', 13:01:06 INFO - 'HOME': '/home/cltbld', 13:01:06 INFO - 'LANG': 'en_US.UTF-8', 13:01:06 INFO - 'LANGUAGE': 'en_US:en', 13:01:06 INFO - 'LOGNAME': 'cltbld', 13:01:06 INFO - 'MAIL': '/var/mail/cltbld', 13:01:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:01:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 13:01:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:01:06 INFO - 'MOZ_NO_REMOTE': '1', 13:01:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:01:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:01:06 INFO - 'NO_EM_RESTART': '1', 13:01:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:01:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:01:06 INFO - 'PWD': '/builds/slave/test', 13:01:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:01:06 INFO - 'SHELL': '/bin/bash', 13:01:06 INFO - 'SHLVL': '1', 13:01:06 INFO - 'TERM': 'linux', 13:01:06 INFO - 'TMOUT': '86400', 13:01:06 INFO - 'USER': 'cltbld', 13:01:06 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1463600856.741969-971307083', 13:01:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:01:06 INFO - '_': '/tools/buildbot/bin/python'} 13:01:06 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-026', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 13:01:06 INFO - mozversion application_buildid: 20160517073121 13:01:06 INFO - mozversion application_changeset: 3780a3a6b83aeda143f9562829c830410a0c961e 13:01:06 INFO - mozversion application_display_name: Nightly 13:01:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:01:06 INFO - mozversion application_name: Firefox 13:01:06 INFO - mozversion application_remotingname: firefox 13:01:06 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 13:01:06 INFO - mozversion application_vendor: Mozilla 13:01:06 INFO - mozversion application_version: 49.0a1 13:01:06 INFO - mozversion platform_buildid: 20160517073121 13:01:06 INFO - mozversion platform_changeset: 3780a3a6b83aeda143f9562829c830410a0c961e 13:01:06 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 13:01:06 INFO - mozversion platform_version: 49.0a1 13:01:06 INFO - using testdate: 1463601666 13:01:06 INFO - actual date: 1463601666 13:01:06 INFO - starting webserver on 'localhost:46147' 13:01:06 INFO - SUITE-START | Running 2 tests 13:01:06 INFO - TEST-START | dromaeo_css 13:01:06 INFO - operating with platform_type : linux_ 13:01:06 INFO - Initialising browser for dromaeo_css test... 13:01:07 INFO - TEST-INFO | started process 3293 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWpWEW3/profile http://localhost:46147/getInfo.html) 13:01:12 INFO - PROCESS | 3293 | __metrics Screen width/height:1600/1200 13:01:12 INFO - PROCESS | 3293 | colorDepth:24 13:01:12 INFO - PROCESS | 3293 | Browser inner width/height: 1024/697 13:01:12 INFO - PROCESS | 3293 | __metrics 13:01:13 ERROR - PROCESS | 3293 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 13:01:14 INFO - TEST-INFO | 3293: exit 0 13:01:14 INFO - Browser initialized. 13:01:14 INFO - Running cycle 1/1 for dromaeo_css test... 13:01:14 INFO - TEST-INFO | started process 3375 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWpWEW3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:01:26 INFO - PROCESS | 3375 | RSS: Main: 152772608 13:01:26 INFO - PROCESS | 3375 | 13:02:17 INFO - PROCESS | 3375 | 1463601737845 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] 13:04:25 INFO - PROCESS | 3375 | Cycle 1(1): loaded http://localhost:46147/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46147/tests/dromaeo/cssquery-ext.html) 13:04:27 INFO - PROCESS | 3375 | RSS: Main: 374456320 13:04:27 INFO - PROCESS | 3375 | 13:07:29 INFO - PROCESS | 3375 | Cycle 1(1): loaded http://localhost:46147/tests/dromaeo/cssquery-ext.html (next: http://localhost:46147/tests/dromaeo/cssquery-jquery.html) 13:07:30 INFO - PROCESS | 3375 | RSS: Main: 239976448 13:07:30 INFO - PROCESS | 3375 | 13:10:32 INFO - PROCESS | 3375 | Cycle 1(1): loaded http://localhost:46147/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46147/tests/dromaeo/cssquery-mootools.html) 13:10:32 INFO - PROCESS | 3375 | RSS: Main: 245919744 13:10:32 INFO - PROCESS | 3375 | 13:13:34 INFO - PROCESS | 3375 | Cycle 1(1): loaded http://localhost:46147/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46147/tests/dromaeo/cssquery-prototype.html) 13:13:35 INFO - PROCESS | 3375 | RSS: Main: 239788032 13:13:35 INFO - PROCESS | 3375 | 13:16:38 INFO - PROCESS | 3375 | Cycle 1(1): loaded http://localhost:46147/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46147/tests/dromaeo/cssquery-yui.html) 13:16:38 INFO - PROCESS | 3375 | RSS: Main: 211279872 13:16:38 INFO - PROCESS | 3375 | 13:19:41 INFO - PROCESS | 3375 | Cycle 1(1): loaded http://localhost:46147/tests/dromaeo/cssquery-yui.html (next: http://localhost:46147/tests/dromaeo/cssquery-dojo.html) 13:19:41 INFO - PROCESS | 3375 | RSS: Main: 269926400 13:19:41 INFO - PROCESS | 3375 | 13:19:41 INFO - PROCESS | 3375 | __start_tp_report 13:19:41 INFO - PROCESS | 3375 | _x_x_mozilla_page_load 13:19:41 INFO - PROCESS | 3375 | _x_x_mozilla_page_load_details 13:19:41 INFO - PROCESS | 3375 | |i|pagename|runs| 13:19:41 INFO - PROCESS | 3375 | |0;dojo.html;3177.42806294867;3193.1857376369026;3208.534762459443;3229.4509933311333;3259.527368531563;5227.712475813838;5326.014687282855;5370.382406023308;5430.565554755615;5462.999999999995;5317.734113294339;5371.946280537193;5395.163749618806;5398.973005134969;5465.836024919249;4848.975755121228;4922.483139270361;4931.802727890898;4953.281774142747;5006.299118123447;4867.975660121694;4921.286413470045;4968.1554135797005;4976.726280054596;4996.225585034325;4814.951850481485;4821.252705830602;4835.879103022435;4893.02139572087;4894.3147959285725;4790.760461976887;4792.7124372537755;4824.541668541483;4895.412550493964;4914.238293064581;4849.054434385296;4857.951420485785;4858.344123543312;4860.198067318888;4881.6338774591695;5183.663061901002;5276.314079169683;5323.1749078892835;5324.281222035014;5415.918761218585;5791.073428251501;5834.5040671542965;5843.941560584425;5860.120981852713;5867.853303667443;5671.461211184931;5842.094475356284;5893.292804863401;5895.322037965603;5901.085331773582;5335.519803217729;5396.298481197458;5415.404305526428;5441.075017247077;5489.451054894478;5880.882382352329;5893.705314734332;5937.7921772737745;5945.494632956161;5962.761489540457;5562.16567514876;5614.438556144353;5695.686737229409;5703.287089113861;5756.856078598069;5757.65454072757;5787.594868359174;5816.534677225812;5821.301443826768;5871.060630299132;5747.1379293106365;5760.452756988079;5762.740676669476;5763.942360576424;5781.19063331134;5484.778664640827;5682.659040457585;5774.162746401748;5781.479666830005;5786.479216870418;4372.868813935587;4398;4400.713953593006;4443.422355093817;4451.888702782457;3905.7851818149707;3927.351986922183;3946.427767973628;3950.9802450987563;3960.405939109099;5325.360956685223;5448.210009548593;5478.342598888079;5484.149956756709;5519.586031047687;5796.275465566804;5823.679697616672;5847.327557330961;5863.736131874076;5882.529397648178;5552.944470555323;5567.721613919288;5567.805126820542;5582.302212223472;5583.134614134816;4861.538153875376;4881.536254055859;4899.461059283476;4914.926276105862;5017.899642007213;5910.734016969247;5913.113033044991;5926.229590153339;5939.554533410012;5973;5720.799772007959;5734.684592347461;5737.799177028951;5741.971290143522;5749.597528173155;5865.882682346414;5926.496247818984;5931.406859314034;5936.525077993837;5952.642841429528;5638.520725738358;5713.028785106625;5720.48515633595;5732.140178973187;5736.627119237236;5785.450382213851;5821.301443826598;5835.708214589169;5836.620619659709;5840.620359676607;5626.212330273846;5646.604737668324;5652.886942261214;5656.773729050957;5657.4908258256955;5608.971955140361;5612.158176273591;5613.691246981455;5615.2700148980375;5615.298087739033;5514.0626093565;5534.695591742493;5544.63959842609;5556.444355564411;5558.694271815089;5711.086226203787;5749.511291540266;5769.59612827098;5774.451427114417;5819.70901454934;5600.35595906476;5600.411956744646;5603.35561410443;5604.495595396271;5644.294463192101 13:19:41 INFO - PROCESS | 3375 | |1;ext.html;629.9968500157499;657.2507341630541;670.5708346658137;675.1762849323824;680.5984469163196;39201;44492;44519.10961780766;44550.554494455086;44556;44151.33772993408;44936.550634493644;44953.55046449543;45013.32480012794;45026.77486612563;8768.29853611711;8956.104389561056;8966.686165984192;8969.865452018224;8972.327075469366;8741.30069594431;8832.514211718353;8850.070742572034;8851.60167792451;8856.911430885706;8888.377813553054;8910.33172512061;8928;8931.196192342688;8931.776705582348;8787.824243515093;8828.911710882872;8856.468611883276;8858.601362938683;8896.19934205918;8626.396152269344;8691.782705432352;8703;8706.521141337224;8711;41418;41674;41819.372709409385;41879.58120418818;41881.37177942333;3431.296584200233;3527.3474407234744;3659.743817932746;3686.760360576554;4100.200460910124;33040.33919321624;33309.16727081818;33392.833035834905;34018;34071.488927666105;4282.892927676833;4350.7172033818015;4391.780410979471;4397.73613583186;4402.735835849827;349256;354859.2257038724;355447;355503;356041;55583.444165558634;60348.39651603516;63013.68493157505;64164.679176603815;64214.67892660507;61860.38139618636;67745.66127169333;68400;68616.6569167151;68761;16069.919650401673;16215.432459863849;16231.26959286835;16239;16263.186840658156;10997.450127493594;11570.132240082028;11766.94116529429;11794.587189448328;11818.822717659243;20694.586108277752;20756.688649669966;20888.268910588056;20925.79074209269;21019.8949005254;7310.378617817545;7468.34873619868;7522.3605993491165;7613.9238607614325;7720.459567830198;1030.7165529479453;1073.2219141122623;1104.9226554141133;1204.602481181208;1218.0438355890583;34190.48714269288;34333.656663433045;34602.307953840784;34696.13259668478;34727;1175.7295821960827;1177.4583691501834;1223.8653748087704;1262.7790136726037;1263.5198624522623;17731.822681773276;17900.641987160445;17990.190441430168;18045.458636240935;18049.819501805076;586311;586366;591456;591559;593017;61180.69409652923;61456.692716536134;61608.38391616116;61666.691666542276;61742.69128654328;1336.8930485561327;1368.6304697731755;1406.5217825939435;1430.612306063117;1450.1951416963475;33965.15087122842;35240.295194096485;35445.46831797577;35672.464913025804;35698.286034279685;51707.741461292455;52168.21747673712;52250.73874630602;52280.21579676232;52303.73848130735;383370;385066;385848;386972;389098;28729.712702872705;29429;29585.852070739507;29735.405291894473;29885;46959;46996.873293959456;50806;51120;52050;41931.7903410481;42283.577164229195;42906.35640465458;43150.78424607857;43629.56370436255;42267.57732422637;42800.5719942809;42810;42977.627796525434;43022.784886075366;43138.35292470553;43241.567584323755;43456.565434345255;43692.12615747731;43769;35390.64609353976;37868.077149970326;37997;38022;38859.22281554296;28695.282617934718;29063.418731625672;29254;29348.11955641334;29407.529623519167 13:19:41 INFO - PROCESS | 3375 | |2;jquery.html;404.5105422438848;406.264660963656;407.911493155046;413.92963196256636;425.2006228290813;13484;18020.36928707494;19316.51708707283;19672.213111475543;19674.311399101036;14230.928845355758;18613.813861861378;18873.81126188738;19113;19155.61688766224;5743.253377060987;5897.11543268511;5907.379725128864;5908.61593996391;5930.614510056854;5822.475977162055;5869.55978301627;5870.090136028923;5871.148683440899;5875.0306199477245;4210.557891421403;4359.062801497677;6155.292141403729;6451.645159516224;6455.61266324022;6380;6438.066480360369;6499.707513161919;6502.902456463157;6544.901826472583;5756.453136952025;5759.913601295984;5778.3643799182055;5788.392218817048;5790.565707571906;22310.442238943993;22823;23484.412889677726;23800.7619923802;23867;764.2968469008525;2066.2354928676336;2181.4075724720883;2208.9116435342567;2310.5032418030037;9772.071653192936;9933.652322168762;9951;10060.295779295453;10479.685609431654;1961.9607607847906;1988.4233572264027;2057.990667251614;2077.2937201351615;2103.116690989788;80502;81087.5945620274;81397;82079.9999999994;82900.17099828985;10801.351918884891;11121;11186.272892262139;11275.210735248455;12197.573084942162;15740.527784166494;15799.921000394925;15848.207589620473;15860.365585376456;15878.285477153557;7622.352100071445;7967.880481792778;8027.357811375076;8054.234847689578;8179.918200818035;7439.181690014094;7461.813454663677;7544.962275188699;7620.085589729268;7713.460057796013;4393.384926110347;4576.130535198302;4857.53853383928;4858.271259311132;4903.4606193318705;1906.9416473856866;1993.8205561499535;2006.7792542820278;2044.703517989883;2053.5482193917314;933.7385532051037;970.9708708738749;1042.9830914857953;1058.3808472043786;1078.439211609974;18494.612904032252;19032.238710451704;19361.709574356395;19386.903065484865;19628.214871404984;994.4530508220449;999.4303247149201;1015.3349556040805;1031.6286136990677;1041.9791604167874;7972.481788683717;10362.533685984148;10620;11028.889711102947;11032.338059716443;517043;517420;526686;530751;531049;90292;92566;94887.52556237312;95365.52317238509;98031.50984245034;1073.6403304892792;1091.2743025887855;1093.4696672114019;1095.315427857589;1123.938183399921;16301.673966520366;16385.42651007209;16439.424620138238;16644.916775416044;16976.91511542484;40229.597704023756;41470.170596588505;43419.34870977001;44200.55799441964;44982.325265120395;51960.480395197075;52500.73749631227;53789;57690.711546442;58974.410255896895;7541.585212813348;7740.535567865946;8534.487930724177;8789.165029322205;8952.418092823946;12885.162464439782;13299.534516291884;13311.534096306583;13366.866331336561;13430.865691343352;6170.5372097092;9516.809663806824;11679.357635329798;11866.703332416759;12110.212836165621;11629.068176598115;11672.474738636613;11694;11793.705157371134;11805.940970295094;6328.868566884557;10420.43693445989;12108.939455302667;12292.48004159163;12328.568500102454;7996.920030799618;8001.3598912088055;8096.583097957951;8233.711820086268;8274.669013239403;10312.742181445525;11945.104117191418;11945.581904633296;11986.940065299967;12190.6952326189 13:19:41 INFO - PROCESS | 3375 | |3;mootools.html;1388.9722205555884;1507.1635242440445;1547.7755725419815;1640.48324777695;1754;8450.239478446947;8769.56152192391;8780.51707156107;9040.773980650487;9079.818403631924;8936.821263574724;9135.36052476327;9139.405938613985;9142.36003479755;9146.131117543839;2070.5858828234414;2334.1480359668767;2402.6396040593877;2428.574999375111;2438.780509754122;2422.9151979680714;2433.1605596069326;2440.365504968703;2441.1211963693145;2448.0574978633176;2329.7204335479764;2354.493783836475;2360.0323867214447;2360.811135109187;2361.4686695493547;1993.6810110382341;2226.076178385968;2293.0369244917174;2294.66727324537;2309.7805708457668;2272.1820144748044;2277.738060123074;2277.89749461273;2278.532900755341;2322.465832858435;8956.731298061068;8979.46123232608;8993.775155621097;8998.730038098867;9017.594208260578;5126.974365128188;5219.321488206547;5225.16397376418;5227.843164705065;5234.450382709837;3412.368711788327;3514.6661067198575;3559.6440355964455;3561.269939662389;3599.4240921452442;934.9532523373872;1068.5351871935654;1082.8862969388178;1148.2478976270472;1164.3363282928733;12176.452059657337;12248.632541023695;12269.386530673431;12275.324857132855;12276.079294052892;4508.684392092522;4545.431821022372;4579.816807327669;4644.117617652635;4647.9767601161775;9686.660966866126;9697.127258546765;9698.224142068617;9701.17540009093;9705.466199358962;4675.111728771522;5274.12976858814;5276.577873770166;5278.2346559749385;5280.20796880471;4869.6591238612955;4999.10016197088;5015.27278544609;5043.445221025684;5044.445111037783;3811.27585758705;3890.902727431837;3934.803259836997;3949.170674158459;3995.780232087205;1144.2562334482652;1153.8788427215022;1154.3074155506797;1159.9594014209463;1189.5182451107316;931.4504442379028;965.7006328038331;971.8639390485338;996.446971930571;1016.3444578246989;5929.911051334234;6038.215032045894;6100.755969761249;6192.767963012806;6280.5603607747935;460.43826531631356;465.5367908930568;465.8765427161805;466.47521538269444;471.8463357091946;4086.4483294754828;4204.579542045771;4224.809883555247;4243.6605071594195;4257.063446041866;11176.944115279372;11510.251833630788;11520.135989800798;11543.76912461746;11544.769104618028;10740;11184.217104802585;11196.272242304223;11376.260543064674;11464;8136.389770767216;8244.587770611446;8256.215659512336;8256.99999999988;8308.875366869503;6168.228971378578;6346.492280617632;6354.142190804368;6401.103845461732;6409.8397540060005;10299.176065914558;10372;10373.377597344184;10434.330507118824;10478.842817357898;10588;10912.345259284468;10947.303168008653;10956.945215273872;10995.780084398428;6909.412699920362;7027.894581581382;7711.922880771121;7796.766097016872;7910.129885712452;8446.535440550828;8581.098984606526;8673.392862499564;8676.869846952175;8682.43564168327;7653.88519172224;7708.768736937901;7750.147483776893;7908.762737117896;7996.1204267529365;7725.49784264021;7781.494202876795;7826.491278066907;7879.566623835744;8035.598220089089;7163.928360716326;7212.567245965259;7220.963895180701;7231.710731570678;7256.310650488195;5145.228215767665;5195.818146364859;5331.573474122139;5394.730263486888;5447.809326673548;5222.164453687392;5287;5305.973470132624;5405.702686352289;5438.809641662523 13:19:41 INFO - PROCESS | 3375 | |4;prototype.html;321.6525267428993;346.4335810949098;348.0880094153318;349.67130896956843;355.39050528343887;5117.5159203830935;5216;5290.1535754279275;5323.8136665216725;5337.332833395825;5219.095030313936;5260.92108618371;5275.261463395118;5286.286351342567;5292.179712144623;4167.729097608661;4186.916261674764;4189.832406703728;4210.810513526899;4218.029853133781;3960.6830004447434;3973.9270396992792;4164.562720914306;4188.4555007849085;4194.4547208862805;4081.4694089768436;4146.3158578834455;4177.728447650893;4181.728187667792;4189.958100419002;4111.958880411203;4122.814473348676;4129.525104612978;4141.461609990712;4177.895552611179;3447.8103704296254;3687.4284485904727;3700.3709369406997;3701.3337599232127;3726.5341832270965;5040.747962601855;5074.720890351029;5079.415867175248;5081.74591270435;5106.208537676666;4741.881452963669;4748.881277968044;4756.310335001405;4786.760661966922;4796.160671882441;5344.706041167734;5474;5475.6181061238485;5499.212755854363;5512.91730624041;3983.780892050936;3998.5601583825755;4004.2992476316526;4009.6391324780616;4010.879673609767;5664.150377443375;5694.206325007609;5718.742656580423;5731.512821410185;5782.653040817564;3901.3757798752063;3985.8405663773124;3994.72036957416;4082.9387559186634;4106.979465102655;4158.355454904495;4158.771267580312;4160.625543701081;4165.729227600196;4179.080602267496;4714.905701885943;4718.268668356411;4727.479977202505;4741.359916411309;4754.976225118921;5244.344456942882;5500.559955203654;5503.862403439866;5512.145617429305;5525.4474552544425;1320.8481024682092;1349.6558377613646;1351.175782772508;1354.7222819322014;1404.1434724817852;931.3480563605503;941.7551436626527;946.2430055955208;954.1126752120456;957.913787759105;4670.346151538787;4781.450133234653;4799.136155492045;4860.246661767432;4882.438519570295;5022.372203474566;5041.269015992661;5047.343845300087;5056.974715126401;5063.443021267658;4771.403574553181;4784.952150478451;4793.472718001017;4833.516648335209;4836.951630483721;2501.9749802502106;2638.934026649311;2662.9201123965922;2677.3306673331667;2701.3516755978426;5080.491950804964;5126.871828204325;5169.139109920426;5179.715115668674;5214.191800270964;5065.392152941597;5086.72023038729;5104.540591346797;5119.10415677255;5165.302684137669;4980.178270585313;5133.460986596354;5144.048351055067;5185.922211166836;5221.477852214748;4974.358461707556;4974.925376119289;4996.6252531061045;5049.520295571915;5052.418971818141;4938.407391112945;4987.80048798044;4992.900141997213;4998.850034498971;4999.65002449825;4957.330760347451;5156.633492124515;5182.1190397631735;5185.792568297225;5192.610554208525;3720.5535335759882;3737.551493820759;3752.718546108998;3763.9435408469444;3793.393057110849;4975.731188547042;4978.3820258415435;4986.950130498649;5020.439575816433;5067.391912970467;4544.954550454453;4864.270359446112;4875.68308059975;4928.852134435972;5269.47185316254;5375.16684913839;5404.351477822686;5408.782399216302;5415.808522125126;5473.589480788877;5422.15956526739;5441.183822426509;5441.347038355423;5454.972725136349;5465.344158700981;5276.2349459327415;5531.557475401878;5534.667919924817;5546.473085056913;5570.693611851225;5398.919016214839;5463.754131064191;5464.316960379952;5464.754086066216;5467.507924286833 13:19:41 INFO - PROCESS | 3375 | |5;yui.html;827.4456114403349;832.3174996502867;860.2730692564783;861.5003298087111;868.4224990381397;14631.561053168409;14722.190279534621;14736.926315368408;14758.040727352714;14943.850561494382;14415.207163605997;14448.566543003726;14459.421623135087;14467.5659730208;14475.855241447609;672.1665135232313;799.7520768561735;819.2790344496834;823.6869989404018;825.145973916996;813.3493205435658;823.3989187892812;826.5412695953743;829.0176141272611;829.5852073963018;725.3942957630359;875.6541166239332;877.863931090682;879.1779685993616;879.3756432932626;861.3711990247124;878.4026861734051;879.7404765594165;880.4497189256715;881.5724373678761;640.3180612647516;640.7565125252421;641.2561428742682;642.8007317731518;644.4007073421716;12560.81158782619;12604.180728252633;12616.369181540886;12628.431720572504;12684.80972785409;13532.797008044887;13640.727185456235;13657;13690.110142840784;13749.381277842422;12088;12213.755724885452;12283.93858030713;12572.30852303123;12661.113722039461;4497.820087196508;4528.75091869947;4584.908301833978;4657.743824089674;4708.623310135181;58486;61566.384336156516;62427.68786156086;63819;63989.3601063988;11119.388433636066;11300.604478843201;11309.547618095348;11311.434428278717;11412;20461;20462.079206435745;20706.275280365116;20966.89516552438;21013.15947362119;8424.620892059873;8445.915540844513;8449.450785699033;8571.871421928676;8716.346274029473;4396.494403143615;4483.50681425043;4564.657650676212;4610.631149508032;4823.2282834746275;3501.3872572299747;3572.035550401429;3578.427451607782;3599.3341231872187;3600.801955892399;541.1071731642821;635.8187916443791;662.2218892800939;665.4609766089483;672.4855485553555;697.272492500845;725.176924191047;730.3645828129455;743.1305372713938;752.8306131120454;10060.496975151213;10138.797224055477;10183.439910804977;10219.335743176818;10332.793344133075;599.2449513612879;618.7555915413386;715.7459102018812;722.512304194677;727.5016613619688;4325.8104021394365;4472.731636101779;4579.931301030487;4613.769311534477;4683.086798074411;74861.62569187119;76967;77381;77404.2259577397;77633;65629;66529.33470665231;68384.6580767093;68709.65645171743;69206.30793692099;58869.70565147147;59208.70395647994;60457;60740.69629651823;61566.692166539775;9048.185663290336;9082.682106126254;9092;9109.817803643757;9129.863052054092;39946;39974.400383993685;40075.398869016404;40086;40349.79825100856;50397;55181;56271.15593266022;56298;57168.42831571631;11856.288622682667;11925.344106073902;11975.341356225492;12121.636350909486;12314.75370492603;12285.508579656713;12353.32056736888;12381.938090309492;12567.560135395217;13277.535286264934;9982.850257246293;10268.383896966207;12501.062420318694;12691.93654031724;13206.273654948685;12045.759084818079;12325;12412.131150819356;12450.813237801622;12475.625731228078;12486.05028345834;13102.803457948328;13240.933795331348;13508.189508629514;13548.254845983565;11371.43142842838;11657.825132622847;11685.415729213335;11708.590199342982;12018.459169337226;10677.879968512336;11139.33164010162;11466.598669046543;11602.18784685064;11616.593419230287 13:19:41 INFO - PROCESS | 3375 | __end_tp_report 13:19:41 INFO - PROCESS | 3375 | __start_cc_report 13:19:41 INFO - PROCESS | 3375 | _x_x_mozilla_cycle_collect,1916 13:19:41 INFO - PROCESS | 3375 | __end_cc_report 13:19:41 INFO - PROCESS | 3375 | __startTimestamp1463602781642__endTimestamp 13:19:41 INFO - PROCESS | 3375 | 13:19:41 INFO - PROCESS | 3375 | ------- Summary: start ------- 13:19:41 INFO - PROCESS | 3375 | Number of tests: 6 13:19:41 INFO - PROCESS | 3375 | 13:19:41 INFO - PROCESS | 3375 | [#0] dojo.html Cycles:165 Average:5366.76 Median:5600.36 stddev:607.31 (10.8%) stddev-sans-first:584.38 13:19:41 INFO - PROCESS | 3375 | Values: 3177.4 3193.2 3208.5 3229.5 3259.5 5227.7 5326.0 5370.4 5430.6 5463.0 5317.7 5371.9 5395.2 5399.0 5465.8 4849.0 4922.5 4931.8 4953.3 5006.3 4868.0 4921.3 4968.2 4976.7 4996.2 4815.0 4821.3 4835.9 4893.0 4894.3 4790.8 4792.7 4824.5 4895.4 4914.2 4849.1 4858.0 4858.3 4860.2 4881.6 5183.7 5276.3 5323.2 5324.3 5415.9 5791.1 5834.5 5843.9 5860.1 5867.9 5671.5 5842.1 5893.3 5895.3 5901.1 5335.5 5396.3 5415.4 5441.1 5489.5 5880.9 5893.7 5937.8 5945.5 5962.8 5562.2 5614.4 5695.7 5703.3 5756.9 5757.7 5787.6 5816.5 5821.3 5871.1 5747.1 5760.5 5762.7 5763.9 5781.2 5484.8 5682.7 5774.2 5781.5 5786.5 4372.9 4398.0 4400.7 4443.4 4451.9 3905.8 3927.4 3946.4 3951.0 3960.4 5325.4 5448.2 5478.3 5484.1 5519.6 5796.3 5823.7 5847.3 5863.7 5882.5 5552.9 5567.7 5567.8 5582.3 5583.1 4861.5 4881.5 4899.5 4914.9 5017.9 5910.7 5913.1 5926.2 5939.6 5973.0 5720.8 5734.7 5737.8 5742.0 5749.6 5865.9 5926.5 5931.4 5936.5 5952.6 5638.5 5713.0 5720.5 5732.1 5736.6 5785.5 5821.3 5835.7 5836.6 5840.6 5626.2 5646.6 5652.9 5656.8 5657.5 5609.0 5612.2 5613.7 5615.3 5615.3 5514.1 5534.7 5544.6 5556.4 5558.7 5711.1 5749.5 5769.6 5774.5 5819.7 5600.4 5600.4 5603.4 5604.5 5644.3 13:19:41 INFO - PROCESS | 3375 | 13:19:41 INFO - PROCESS | 3375 | [#1] ext.html Cycles:180 Average:61421.66 Median:23546.45 stddev:120903.47 (513.5%) stddev-sans-first:121156.48 13:19:41 INFO - PROCESS | 3375 | Values: 630.0 657.3 670.6 675.2 680.6 39201.0 44492.0 44519.1 44550.6 44556.0 44151.3 44936.6 44953.6 45013.3 45026.8 8768.3 8956.1 8966.7 8969.9 8972.3 8741.3 8832.5 8850.1 8851.6 8856.9 8888.4 8910.3 8928.0 8931.2 8931.8 8787.8 8828.9 8856.5 8858.6 8896.2 8626.4 8691.8 8703.0 8706.5 8711.0 41418.0 41674.0 41819.4 41879.6 41881.4 3431.3 3527.3 3659.7 3686.8 4100.2 33040.3 33309.2 33392.8 34018.0 34071.5 4282.9 4350.7 4391.8 4397.7 4402.7 349256.0 354859.2 355447.0 355503.0 356041.0 55583.4 60348.4 63013.7 64164.7 64214.7 61860.4 67745.7 68400.0 68616.7 68761.0 16069.9 16215.4 16231.3 16239.0 16263.2 10997.5 11570.1 11766.9 11794.6 11818.8 20694.6 20756.7 20888.3 20925.8 21019.9 7310.4 7468.3 7522.4 7613.9 7720.5 1030.7 1073.2 1104.9 1204.6 1218.0 34190.5 34333.7 34602.3 34696.1 34727.0 1175.7 1177.5 1223.9 1262.8 1263.5 17731.8 17900.6 17990.2 18045.5 18049.8 586311.0 586366.0 591456.0 591559.0 593017.0 61180.7 61456.7 61608.4 61666.7 61742.7 1336.9 1368.6 1406.5 1430.6 1450.2 33965.2 35240.3 35445.5 35672.5 35698.3 51707.7 52168.2 52250.7 52280.2 52303.7 383370.0 385066.0 385848.0 386972.0 389098.0 28729.7 29429.0 29585.9 29735.4 29885.0 46959.0 46996.9 50806.0 51120.0 52050.0 41931.8 42283.6 42906.4 43150.8 43629.6 42267.6 42800.6 42810.0 42977.6 43022.8 43138.4 43241.6 43456.6 43692.1 43769.0 35390.6 37868.1 37997.0 38022.0 38859.2 28695.3 29063.4 29254.0 29348.1 29407.5 13:19:41 INFO - PROCESS | 3375 | 13:19:41 INFO - PROCESS | 3375 | [#2] jquery.html Cycles:180 Average:29832.00 Median:407.09 stddev:86390.44 (21221.6%) stddev-sans-first:86604.53 13:19:41 INFO - PROCESS | 3375 | Values: 404.5 406.3 407.9 413.9 425.2 13484.0 18020.4 19316.5 19672.2 19674.3 14230.9 18613.8 18873.8 19113.0 19155.6 5743.3 5897.1 5907.4 5908.6 5930.6 5822.5 5869.6 5870.1 5871.1 5875.0 4210.6 4359.1 6155.3 6451.6 6455.6 6380.0 6438.1 6499.7 6502.9 6544.9 5756.5 5759.9 5778.4 5788.4 5790.6 22310.4 22823.0 23484.4 23800.8 23867.0 764.3 2066.2 2181.4 2208.9 2310.5 9772.1 9933.7 9951.0 10060.3 10479.7 1962.0 1988.4 2058.0 2077.3 2103.1 80502.0 81087.6 81397.0 82080.0 82900.2 10801.4 11121.0 11186.3 11275.2 12197.6 15740.5 15799.9 15848.2 15860.4 15878.3 7622.4 7967.9 8027.4 8054.2 8179.9 7439.2 7461.8 7545.0 7620.1 7713.5 4393.4 4576.1 4857.5 4858.3 4903.5 1906.9 1993.8 2006.8 2044.7 2053.5 933.7 971.0 1043.0 1058.4 1078.4 18494.6 19032.2 19361.7 19386.9 19628.2 994.5 999.4 1015.3 1031.6 1042.0 7972.5 10362.5 10620.0 11028.9 11032.3 517043.0 517420.0 526686.0 530751.0 531049.0 90292.0 92566.0 94887.5 95365.5 98031.5 1073.6 1091.3 1093.5 1095.3 1123.9 16301.7 16385.4 16439.4 16644.9 16976.9 40229.6 41470.2 43419.3 44200.6 44982.3 51960.5 52500.7 53789.0 57690.7 58974.4 7541.6 7740.5 8534.5 8789.2 8952.4 12885.2 13299.5 13311.5 13366.9 13430.9 6170.5 9516.8 11679.4 11866.7 12110.2 11629.1 11672.5 11694.0 11793.7 11805.9 6328.9 10420.4 12108.9 12292.5 12328.6 7996.9 8001.4 8096.6 8233.7 8274.7 10312.7 11945.1 11945.6 11986.9 12190.7 13:19:41 INFO - PROCESS | 3375 | 13:19:41 INFO - PROCESS | 3375 | [#3] mootools.html Cycles:180 Average:5875.95 Median:2670.75 stddev:3388.20 (126.9%) stddev-sans-first:3380.93 13:19:41 INFO - PROCESS | 3375 | Values: 1389.0 1507.2 1547.8 1640.5 1754.0 8450.2 8769.6 8780.5 9040.8 9079.8 8936.8 9135.4 9139.4 9142.4 9146.1 2070.6 2334.1 2402.6 2428.6 2438.8 2422.9 2433.2 2440.4 2441.1 2448.1 2329.7 2354.5 2360.0 2360.8 2361.5 1993.7 2226.1 2293.0 2294.7 2309.8 2272.2 2277.7 2277.9 2278.5 2322.5 8956.7 8979.5 8993.8 8998.7 9017.6 5127.0 5219.3 5225.2 5227.8 5234.5 3412.4 3514.7 3559.6 3561.3 3599.4 935.0 1068.5 1082.9 1148.2 1164.3 12176.5 12248.6 12269.4 12275.3 12276.1 4508.7 4545.4 4579.8 4644.1 4648.0 9686.7 9697.1 9698.2 9701.2 9705.5 4675.1 5274.1 5276.6 5278.2 5280.2 4869.7 4999.1 5015.3 5043.4 5044.4 3811.3 3890.9 3934.8 3949.2 3995.8 1144.3 1153.9 1154.3 1160.0 1189.5 931.5 965.7 971.9 996.4 1016.3 5929.9 6038.2 6100.8 6192.8 6280.6 460.4 465.5 465.9 466.5 471.8 4086.4 4204.6 4224.8 4243.7 4257.1 11176.9 11510.3 11520.1 11543.8 11544.8 10740.0 11184.2 11196.3 11376.3 11464.0 8136.4 8244.6 8256.2 8257.0 8308.9 6168.2 6346.5 6354.1 6401.1 6409.8 10299.2 10372.0 10373.4 10434.3 10478.8 10588.0 10912.3 10947.3 10956.9 10995.8 6909.4 7027.9 7711.9 7796.8 7910.1 8446.5 8581.1 8673.4 8676.9 8682.4 7653.9 7708.8 7750.1 7908.8 7996.1 7725.5 7781.5 7826.5 7879.6 8035.6 7163.9 7212.6 7221.0 7231.7 7256.3 5145.2 5195.8 5331.6 5394.7 5447.8 5222.2 5287.0 5306.0 5405.7 5438.8 13:19:41 INFO - PROCESS | 3375 | 13:19:41 INFO - PROCESS | 3375 | [#4] prototype.html Cycles:180 Average:4446.30 Median:4983.56 stddev:1264.45 (25.4%) stddev-sans-first:1229.51 13:19:41 INFO - PROCESS | 3375 | Values: 321.7 346.4 348.1 349.7 355.4 5117.5 5216.0 5290.2 5323.8 5337.3 5219.1 5260.9 5275.3 5286.3 5292.2 4167.7 4186.9 4189.8 4210.8 4218.0 3960.7 3973.9 4164.6 4188.5 4194.5 4081.5 4146.3 4177.7 4181.7 4190.0 4112.0 4122.8 4129.5 4141.5 4177.9 3447.8 3687.4 3700.4 3701.3 3726.5 5040.7 5074.7 5079.4 5081.7 5106.2 4741.9 4748.9 4756.3 4786.8 4796.2 5344.7 5474.0 5475.6 5499.2 5512.9 3983.8 3998.6 4004.3 4009.6 4010.9 5664.2 5694.2 5718.7 5731.5 5782.7 3901.4 3985.8 3994.7 4082.9 4107.0 4158.4 4158.8 4160.6 4165.7 4179.1 4714.9 4718.3 4727.5 4741.4 4755.0 5244.3 5500.6 5503.9 5512.1 5525.4 1320.8 1349.7 1351.2 1354.7 1404.1 931.3 941.8 946.2 954.1 957.9 4670.3 4781.5 4799.1 4860.2 4882.4 5022.4 5041.3 5047.3 5057.0 5063.4 4771.4 4785.0 4793.5 4833.5 4837.0 2502.0 2638.9 2662.9 2677.3 2701.4 5080.5 5126.9 5169.1 5179.7 5214.2 5065.4 5086.7 5104.5 5119.1 5165.3 4980.2 5133.5 5144.0 5185.9 5221.5 4974.4 4974.9 4996.6 5049.5 5052.4 4938.4 4987.8 4992.9 4998.9 4999.7 4957.3 5156.6 5182.1 5185.8 5192.6 3720.6 3737.6 3752.7 3763.9 3793.4 4975.7 4978.4 4987.0 5020.4 5067.4 4545.0 4864.3 4875.7 4928.9 5269.5 5375.2 5404.4 5408.8 5415.8 5473.6 5422.2 5441.2 5441.3 5455.0 5465.3 5276.2 5531.6 5534.7 5546.5 5570.7 5398.9 5463.8 5464.3 5464.8 5467.5 13:19:41 INFO - PROCESS | 3375 | 13:19:41 INFO - PROCESS | 3375 | [#5] yui.html Cycles:180 Average:16627.10 Median:4572.29 stddev:20817.58 (455.3%) stddev-sans-first:20842.17 13:19:41 INFO - PROCESS | 3375 | Values: 827.4 832.3 860.3 861.5 868.4 14631.6 14722.2 14736.9 14758.0 14943.9 14415.2 14448.6 14459.4 14467.6 14475.9 672.2 799.8 819.3 823.7 825.1 813.3 823.4 826.5 829.0 829.6 725.4 875.7 877.9 879.2 879.4 861.4 878.4 879.7 880.4 881.6 640.3 640.8 641.3 642.8 644.4 12560.8 12604.2 12616.4 12628.4 12684.8 13532.8 13640.7 13657.0 13690.1 13749.4 12088.0 12213.8 12283.9 12572.3 12661.1 4497.8 4528.8 4584.9 4657.7 4708.6 58486.0 61566.4 62427.7 63819.0 63989.4 11119.4 11300.6 11309.5 11311.4 11412.0 20461.0 20462.1 20706.3 20966.9 21013.2 8424.6 8445.9 8449.5 8571.9 8716.3 4396.5 4483.5 4564.7 4610.6 4823.2 3501.4 3572.0 3578.4 3599.3 3600.8 541.1 635.8 662.2 665.5 672.5 697.3 725.2 730.4 743.1 752.8 10060.5 10138.8 10183.4 10219.3 10332.8 599.2 618.8 715.7 722.5 727.5 4325.8 4472.7 4579.9 4613.8 4683.1 74861.6 76967.0 77381.0 77404.2 77633.0 65629.0 66529.3 68384.7 68709.7 69206.3 58869.7 59208.7 60457.0 60740.7 61566.7 9048.2 9082.7 9092.0 9109.8 9129.9 39946.0 39974.4 40075.4 40086.0 40349.8 50397.0 55181.0 56271.2 56298.0 57168.4 11856.3 11925.3 11975.3 12121.6 12314.8 12285.5 12353.3 12381.9 12567.6 13277.5 9982.9 10268.4 12501.1 12691.9 13206.3 12045.8 12325.0 12412.1 12450.8 12475.6 12486.1 13102.8 13240.9 13508.2 13548.3 11371.4 11657.8 11685.4 11708.6 12018.5 10677.9 11139.3 11466.6 11602.2 11616.6 13:19:41 INFO - PROCESS | 3375 | -------- Summary: end -------- 13:19:41 INFO - PROCESS | 3375 | 13:19:42 INFO - TEST-INFO | 3375: exit 0 13:19:42 INFO - TEST-OK | dromaeo_css | took 1115434ms 13:19:42 INFO - TEST-START | kraken 13:19:42 INFO - operating with platform_type : linux_ 13:19:42 INFO - Initialising browser for kraken test... 13:19:42 INFO - TEST-INFO | started process 3556 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbtK8Nd/profile http://localhost:46147/getInfo.html) 13:19:46 INFO - PROCESS | 3556 | __metrics Screen width/height:1600/1200 13:19:46 INFO - PROCESS | 3556 | colorDepth:24 13:19:46 INFO - PROCESS | 3556 | Browser inner width/height: 1024/697 13:19:46 INFO - PROCESS | 3556 | __metrics 13:19:47 ERROR - PROCESS | 3556 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 13:19:48 INFO - TEST-INFO | 3556: exit 0 13:19:48 INFO - Browser initialized. 13:19:48 INFO - Running cycle 1/1 for kraken test... 13:19:48 INFO - TEST-INFO | started process 3638 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbtK8Nd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:19:59 INFO - PROCESS | 3638 | RSS: Main: 152616960 13:19:59 INFO - PROCESS | 3638 | 13:20:02 INFO - PROCESS | 3638 | Cycle 1(1): loaded http://localhost:46147/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46147/tests/kraken/driver.html?testName=audio-beat-detection) 13:20:03 INFO - PROCESS | 3638 | RSS: Main: 208097280 13:20:03 INFO - PROCESS | 3638 | 13:20:06 INFO - PROCESS | 3638 | Cycle 1(1): loaded http://localhost:46147/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46147/tests/kraken/driver.html?testName=audio-dft) 13:20:06 INFO - PROCESS | 3638 | RSS: Main: 198230016 13:20:06 INFO - PROCESS | 3638 | 13:20:10 INFO - PROCESS | 3638 | Cycle 1(1): loaded http://localhost:46147/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46147/tests/kraken/driver.html?testName=audio-fft) 13:20:10 INFO - PROCESS | 3638 | RSS: Main: 265453568 13:20:10 INFO - PROCESS | 3638 | 13:20:12 INFO - PROCESS | 3638 | Cycle 1(1): loaded http://localhost:46147/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46147/tests/kraken/driver.html?testName=audio-oscillator) 13:20:13 INFO - PROCESS | 3638 | RSS: Main: 175042560 13:20:13 INFO - PROCESS | 3638 | 13:20:15 INFO - PROCESS | 3638 | Cycle 1(1): loaded http://localhost:46147/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46147/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:20:16 INFO - PROCESS | 3638 | RSS: Main: 178483200 13:20:16 INFO - PROCESS | 3638 | 13:20:19 INFO - PROCESS | 3638 | Cycle 1(1): loaded http://localhost:46147/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46147/tests/kraken/driver.html?testName=imaging-darkroom) 13:20:20 INFO - PROCESS | 3638 | RSS: Main: 214904832 13:20:20 INFO - PROCESS | 3638 | 13:20:23 INFO - PROCESS | 3638 | Cycle 1(1): loaded http://localhost:46147/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46147/tests/kraken/driver.html?testName=imaging-desaturate) 13:20:24 INFO - PROCESS | 3638 | RSS: Main: 212127744 13:20:24 INFO - PROCESS | 3638 | 13:20:27 INFO - PROCESS | 3638 | Cycle 1(1): loaded http://localhost:46147/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46147/tests/kraken/driver.html?testName=json-parse-financial) 13:20:27 INFO - PROCESS | 3638 | RSS: Main: 213303296 13:20:27 INFO - PROCESS | 3638 | 13:20:29 INFO - PROCESS | 3638 | Cycle 1(1): loaded http://localhost:46147/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46147/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:20:29 INFO - PROCESS | 3638 | RSS: Main: 204832768 13:20:29 INFO - PROCESS | 3638 | 13:20:31 INFO - PROCESS | 3638 | Cycle 1(1): loaded http://localhost:46147/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46147/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:20:32 INFO - PROCESS | 3638 | RSS: Main: 208199680 13:20:32 INFO - PROCESS | 3638 | 13:20:34 INFO - PROCESS | 3638 | Cycle 1(1): loaded http://localhost:46147/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46147/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:20:35 INFO - PROCESS | 3638 | RSS: Main: 217706496 13:20:35 INFO - PROCESS | 3638 | 13:20:37 INFO - PROCESS | 3638 | Cycle 1(1): loaded http://localhost:46147/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46147/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:20:38 INFO - PROCESS | 3638 | RSS: Main: 223023104 13:20:38 INFO - PROCESS | 3638 | 13:20:41 INFO - PROCESS | 3638 | Cycle 1(1): loaded http://localhost:46147/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46147/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:20:41 INFO - PROCESS | 3638 | RSS: Main: 218697728 13:20:41 INFO - PROCESS | 3638 | 13:20:43 INFO - PROCESS | 3638 | Cycle 1(1): loaded http://localhost:46147/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46147/tests/kraken/driver.html?testName=ai-astar) 13:20:43 INFO - PROCESS | 3638 | RSS: Main: 264663040 13:20:43 INFO - PROCESS | 3638 | 13:20:43 INFO - PROCESS | 3638 | __start_tp_report 13:20:43 INFO - PROCESS | 3638 | _x_x_mozilla_page_load 13:20:43 INFO - PROCESS | 3638 | _x_x_mozilla_page_load_details 13:20:43 INFO - PROCESS | 3638 | |i|pagename|runs| 13:20:43 INFO - PROCESS | 3638 | |0;ai-astar;111;104;109;109;107;109;110;109;109;108 13:20:43 INFO - PROCESS | 3638 | |1;audio-beat-detection;146;133;152;203;124;153;163;123;189;133 13:20:43 INFO - PROCESS | 3638 | |2;audio-dft;160;161;161;160;161;160;161;161;163;159 13:20:43 INFO - PROCESS | 3638 | |3;audio-fft;74;74;75;146;77;74;75;74;133;78 13:20:43 INFO - PROCESS | 3638 | |4;audio-oscillator;92;90;149;92;91;93;128;91;91;121 13:20:43 INFO - PROCESS | 3638 | |5;imaging-gaussian-blur;116;117;116;117;117;116;117;117;118;118 13:20:43 INFO - PROCESS | 3638 | |6;imaging-darkroom;145;145;145;146;146;145;145;145;145;146 13:20:43 INFO - PROCESS | 3638 | |7;imaging-desaturate;94;94;95;97;95;96;95;94;95;95 13:20:43 INFO - PROCESS | 3638 | |8;json-parse-financial;61;54;54;54;53;55;56;113;52;52 13:20:43 INFO - PROCESS | 3638 | |9;json-stringify-tinderbox;59;60;59;59;61;58;60;59;62;59 13:20:43 INFO - PROCESS | 3638 | |10;stanford-crypto-aes;78;78;79;78;78;80;78;80;84;85 13:20:43 INFO - PROCESS | 3638 | |11;stanford-crypto-ccm;127;121;129;125;118;118;126;118;209;122 13:20:43 INFO - PROCESS | 3638 | |12;stanford-crypto-pbkdf2;167;162;158;160;157;160;162;160;160;158 13:20:43 INFO - PROCESS | 3638 | |13;stanford-crypto-sha256-iterative;61;61;63;62;63;66;62;62;61;66 13:20:43 INFO - PROCESS | 3638 | __end_tp_report 13:20:43 INFO - PROCESS | 3638 | __start_cc_report 13:20:43 INFO - PROCESS | 3638 | _x_x_mozilla_cycle_collect,1888 13:20:43 INFO - PROCESS | 3638 | __end_cc_report 13:20:43 INFO - PROCESS | 3638 | __startTimestamp1463602843744__endTimestamp 13:20:43 INFO - PROCESS | 3638 | 13:20:43 INFO - PROCESS | 3638 | ------- Summary: start ------- 13:20:43 INFO - PROCESS | 3638 | Number of tests: 14 13:20:43 INFO - PROCESS | 3638 | 13:20:43 INFO - PROCESS | 3638 | [#0] ai-astar Cycles:10 Average:108.50 Median:109.00 stddev:1.90 (1.7%) stddev-sans-first:1.79 13:20:43 INFO - PROCESS | 3638 | Values: 111.0 104.0 109.0 109.0 107.0 109.0 110.0 109.0 109.0 108.0 13:20:43 INFO - PROCESS | 3638 | 13:20:43 INFO - PROCESS | 3638 | [#1] audio-beat-detection Cycles:10 Average:151.90 Median:152.50 stddev:26.82 (17.6%) stddev-sans-first:28.36 13:20:43 INFO - PROCESS | 3638 | Values: 146.0 133.0 152.0 203.0 124.0 153.0 163.0 123.0 189.0 133.0 13:20:43 INFO - PROCESS | 3638 | 13:20:43 INFO - PROCESS | 3638 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:1.06 (0.7%) stddev-sans-first:1.09 13:20:43 INFO - PROCESS | 3638 | Values: 160.0 161.0 161.0 160.0 161.0 160.0 161.0 161.0 163.0 159.0 13:20:43 INFO - PROCESS | 3638 | 13:20:43 INFO - PROCESS | 3638 | [#3] audio-fft Cycles:10 Average:88.00 Median:74.50 stddev:27.35 (36.7%) stddev-sans-first:28.54 13:20:43 INFO - PROCESS | 3638 | Values: 74.0 74.0 75.0 146.0 77.0 74.0 75.0 74.0 133.0 78.0 13:20:43 INFO - PROCESS | 3638 | 13:20:43 INFO - PROCESS | 3638 | [#4] audio-oscillator Cycles:10 Average:103.80 Median:91.00 stddev:21.09 (23.2%) stddev-sans-first:21.93 13:20:43 INFO - PROCESS | 3638 | Values: 92.0 90.0 149.0 92.0 91.0 93.0 128.0 91.0 91.0 121.0 13:20:43 INFO - PROCESS | 3638 | 13:20:43 INFO - PROCESS | 3638 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.74 (0.6%) stddev-sans-first:0.71 13:20:43 INFO - PROCESS | 3638 | Values: 116.0 117.0 116.0 117.0 117.0 116.0 117.0 117.0 118.0 118.0 13:20:43 INFO - PROCESS | 3638 | 13:20:43 INFO - PROCESS | 3638 | [#6] imaging-darkroom Cycles:10 Average:145.30 Median:145.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 13:20:43 INFO - PROCESS | 3638 | Values: 145.0 145.0 145.0 146.0 146.0 145.0 145.0 145.0 145.0 146.0 13:20:43 INFO - PROCESS | 3638 | 13:20:43 INFO - PROCESS | 3638 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.94 (1.0%) stddev-sans-first:0.93 13:20:43 INFO - PROCESS | 3638 | Values: 94.0 94.0 95.0 97.0 95.0 96.0 95.0 94.0 95.0 95.0 13:20:43 INFO - PROCESS | 3638 | 13:20:43 INFO - PROCESS | 3638 | [#8] json-parse-financial Cycles:10 Average:60.40 Median:54.00 stddev:18.66 (34.6%) stddev-sans-first:19.79 13:20:43 INFO - PROCESS | 3638 | Values: 61.0 54.0 54.0 54.0 53.0 55.0 56.0 113.0 52.0 52.0 13:20:43 INFO - PROCESS | 3638 | 13:20:43 INFO - PROCESS | 3638 | [#9] json-stringify-tinderbox Cycles:10 Average:59.60 Median:59.50 stddev:1.17 (2.0%) stddev-sans-first:1.22 13:20:43 INFO - PROCESS | 3638 | Values: 59.0 60.0 59.0 59.0 61.0 58.0 60.0 59.0 62.0 59.0 13:20:43 INFO - PROCESS | 3638 | 13:20:43 INFO - PROCESS | 3638 | [#10] stanford-crypto-aes Cycles:10 Average:79.80 Median:79.50 stddev:2.62 (3.3%) stddev-sans-first:2.69 13:20:43 INFO - PROCESS | 3638 | Values: 78.0 78.0 79.0 78.0 78.0 80.0 78.0 80.0 84.0 85.0 13:20:43 INFO - PROCESS | 3638 | 13:20:43 INFO - PROCESS | 3638 | [#11] stanford-crypto-ccm Cycles:10 Average:131.30 Median:125.50 stddev:27.59 (22.0%) stddev-sans-first:29.22 13:20:43 INFO - PROCESS | 3638 | Values: 127.0 121.0 129.0 125.0 118.0 118.0 126.0 118.0 209.0 122.0 13:20:43 INFO - PROCESS | 3638 | 13:20:43 INFO - PROCESS | 3638 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.40 Median:160.00 stddev:2.84 (1.8%) stddev-sans-first:1.73 13:20:43 INFO - PROCESS | 3638 | Values: 167.0 162.0 158.0 160.0 157.0 160.0 162.0 160.0 160.0 158.0 13:20:43 INFO - PROCESS | 3638 | 13:20:43 INFO - PROCESS | 3638 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.70 Median:62.50 stddev:1.89 (3.0%) stddev-sans-first:1.90 13:20:43 INFO - PROCESS | 3638 | Values: 61.0 61.0 63.0 62.0 63.0 66.0 62.0 62.0 61.0 66.0 13:20:43 INFO - PROCESS | 3638 | -------- Summary: end -------- 13:20:43 INFO - PROCESS | 3638 | 13:20:44 INFO - TEST-INFO | 3638: exit 0 13:20:44 INFO - TEST-OK | kraken | took 62289ms 13:20:44 INFO - SUITE-END | took 1177s 13:20:44 INFO - Completed test suite (00:19:37) 13:20:44 INFO - javascript benchmark 13:20:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3177.42806294867, 3193.1857376369026, 3208.534762459443, 3229.4509933311333, 3259.527368531563, 5227.712475813838, 5326.014687282855, 5370.382406023308, 5430.565554755615, 5462.999999999995, 5317.734113294339, 5371.946280537193, 5395.163749618806, 5398.973005134969, 5465.836024919249, 4848.975755121228, 4922.483139270361, 4931.802727890898, 4953.281774142747, 5006.299118123447, 4867.975660121694, 4921.286413470045, 4968.1554135797005, 4976.726280054596, 4996.225585034325, 4814.951850481485, 4821.252705830602, 4835.879103022435, 4893.02139572087, 4894.3147959285725, 4790.760461976887, 4792.7124372537755, 4824.541668541483, 4895.412550493964, 4914.238293064581, 4849.054434385296, 4857.951420485785, 4858.344123543312, 4860.198067318888, 4881.6338774591695, 5183.663061901002, 5276.314079169683, 5323.1749078892835, 5324.281222035014, 5415.918761218585, 5791.073428251501, 5834.5040671542965, 5843.941560584425, 5860.120981852713, 5867.853303667443, 5671.461211184931, 5842.094475356284, 5893.292804863401, 5895.322037965603, 5901.085331773582, 5335.519803217729, 5396.298481197458, 5415.404305526428, 5441.075017247077, 5489.451054894478, 5880.882382352329, 5893.705314734332, 5937.7921772737745, 5945.494632956161, 5962.761489540457, 5562.16567514876, 5614.438556144353, 5695.686737229409, 5703.287089113861, 5756.856078598069, 5757.65454072757, 5787.594868359174, 5816.534677225812, 5821.301443826768, 5871.060630299132, 5747.1379293106365, 5760.452756988079, 5762.740676669476, 5763.942360576424, 5781.19063331134, 5484.778664640827, 5682.659040457585, 5774.162746401748, 5781.479666830005, 5786.479216870418, 4372.868813935587, 4398.0, 4400.713953593006, 4443.422355093817, 4451.888702782457, 3905.7851818149707, 3927.351986922183, 3946.427767973628, 3950.9802450987563, 3960.405939109099, 5325.360956685223, 5448.210009548593, 5478.342598888079, 5484.149956756709, 5519.586031047687, 5796.275465566804, 5823.679697616672, 5847.327557330961, 5863.736131874076, 5882.529397648178, 5552.944470555323, 5567.721613919288, 5567.805126820542, 5582.302212223472, 5583.134614134816, 4861.538153875376, 4881.536254055859, 4899.461059283476, 4914.926276105862, 5017.899642007213, 5910.734016969247, 5913.113033044991, 5926.229590153339, 5939.554533410012, 5973.0, 5720.799772007959, 5734.684592347461, 5737.799177028951, 5741.971290143522, 5749.597528173155, 5865.882682346414, 5926.496247818984, 5931.406859314034, 5936.525077993837, 5952.642841429528, 5638.520725738358, 5713.028785106625, 5720.48515633595, 5732.140178973187, 5736.627119237236, 5785.450382213851, 5821.301443826598, 5835.708214589169, 5836.620619659709, 5840.620359676607, 5626.212330273846, 5646.604737668324, 5652.886942261214, 5656.773729050957, 5657.4908258256955, 5608.971955140361, 5612.158176273591, 5613.691246981455, 5615.2700148980375, 5615.298087739033, 5514.0626093565, 5534.695591742493, 5544.63959842609, 5556.444355564411, 5558.694271815089, 5711.086226203787, 5749.511291540266, 5769.59612827098, 5774.451427114417, 5819.70901454934, 5600.35595906476, 5600.411956744646, 5603.35561410443, 5604.495595396271, 5644.294463192101], "value": 5326.473986992682, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [629.9968500157499, 657.2507341630541, 670.5708346658137, 675.1762849323824, 680.5984469163196, 39201.0, 44492.0, 44519.10961780766, 44550.554494455086, 44556.0, 44151.33772993408, 44936.550634493644, 44953.55046449543, 45013.32480012794, 45026.77486612563, 8768.29853611711, 8956.104389561056, 8966.686165984192, 8969.865452018224, 8972.327075469366, 8741.30069594431, 8832.514211718353, 8850.070742572034, 8851.60167792451, 8856.911430885706, 8888.377813553054, 8910.33172512061, 8928.0, 8931.196192342688, 8931.776705582348, 8787.824243515093, 8828.911710882872, 8856.468611883276, 8858.601362938683, 8896.19934205918, 8626.396152269344, 8691.782705432352, 8703.0, 8706.521141337224, 8711.0, 41418.0, 41674.0, 41819.372709409385, 41879.58120418818, 41881.37177942333, 3431.296584200233, 3527.3474407234744, 3659.743817932746, 3686.760360576554, 4100.200460910124, 33040.33919321624, 33309.16727081818, 33392.833035834905, 34018.0, 34071.488927666105, 4282.892927676833, 4350.7172033818015, 4391.780410979471, 4397.73613583186, 4402.735835849827, 349256.0, 354859.2257038724, 355447.0, 355503.0, 356041.0, 55583.444165558634, 60348.39651603516, 63013.68493157505, 64164.679176603815, 64214.67892660507, 61860.38139618636, 67745.66127169333, 68400.0, 68616.6569167151, 68761.0, 16069.919650401673, 16215.432459863849, 16231.26959286835, 16239.0, 16263.186840658156, 10997.450127493594, 11570.132240082028, 11766.94116529429, 11794.587189448328, 11818.822717659243, 20694.586108277752, 20756.688649669966, 20888.268910588056, 20925.79074209269, 21019.8949005254, 7310.378617817545, 7468.34873619868, 7522.3605993491165, 7613.9238607614325, 7720.459567830198, 1030.7165529479453, 1073.2219141122623, 1104.9226554141133, 1204.602481181208, 1218.0438355890583, 34190.48714269288, 34333.656663433045, 34602.307953840784, 34696.13259668478, 34727.0, 1175.7295821960827, 1177.4583691501834, 1223.8653748087704, 1262.7790136726037, 1263.5198624522623, 17731.822681773276, 17900.641987160445, 17990.190441430168, 18045.458636240935, 18049.819501805076, 586311.0, 586366.0, 591456.0, 591559.0, 593017.0, 61180.69409652923, 61456.692716536134, 61608.38391616116, 61666.691666542276, 61742.69128654328, 1336.8930485561327, 1368.6304697731755, 1406.5217825939435, 1430.612306063117, 1450.1951416963475, 33965.15087122842, 35240.295194096485, 35445.46831797577, 35672.464913025804, 35698.286034279685, 51707.741461292455, 52168.21747673712, 52250.73874630602, 52280.21579676232, 52303.73848130735, 383370.0, 385066.0, 385848.0, 386972.0, 389098.0, 28729.712702872705, 29429.0, 29585.852070739507, 29735.405291894473, 29885.0, 46959.0, 46996.873293959456, 50806.0, 51120.0, 52050.0, 41931.7903410481, 42283.577164229195, 42906.35640465458, 43150.78424607857, 43629.56370436255, 42267.57732422637, 42800.5719942809, 42810.0, 42977.627796525434, 43022.784886075366, 43138.35292470553, 43241.567584323755, 43456.565434345255, 43692.12615747731, 43769.0, 35390.64609353976, 37868.077149970326, 37997.0, 38022.0, 38859.22281554296, 28695.282617934718, 29063.418731625672, 29254.0, 29348.11955641334, 29407.529623519167], "value": 20763.676725392077, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [404.5105422438848, 406.264660963656, 407.911493155046, 413.92963196256636, 425.2006228290813, 13484.0, 18020.36928707494, 19316.51708707283, 19672.213111475543, 19674.311399101036, 14230.928845355758, 18613.813861861378, 18873.81126188738, 19113.0, 19155.61688766224, 5743.253377060987, 5897.11543268511, 5907.379725128864, 5908.61593996391, 5930.614510056854, 5822.475977162055, 5869.55978301627, 5870.090136028923, 5871.148683440899, 5875.0306199477245, 4210.557891421403, 4359.062801497677, 6155.292141403729, 6451.645159516224, 6455.61266324022, 6380.0, 6438.066480360369, 6499.707513161919, 6502.902456463157, 6544.901826472583, 5756.453136952025, 5759.913601295984, 5778.3643799182055, 5788.392218817048, 5790.565707571906, 22310.442238943993, 22823.0, 23484.412889677726, 23800.7619923802, 23867.0, 764.2968469008525, 2066.2354928676336, 2181.4075724720883, 2208.9116435342567, 2310.5032418030037, 9772.071653192936, 9933.652322168762, 9951.0, 10060.295779295453, 10479.685609431654, 1961.9607607847906, 1988.4233572264027, 2057.990667251614, 2077.2937201351615, 2103.116690989788, 80502.0, 81087.5945620274, 81397.0, 82079.9999999994, 82900.17099828985, 10801.351918884891, 11121.0, 11186.272892262139, 11275.210735248455, 12197.573084942162, 15740.527784166494, 15799.921000394925, 15848.207589620473, 15860.365585376456, 15878.285477153557, 7622.352100071445, 7967.880481792778, 8027.357811375076, 8054.234847689578, 8179.918200818035, 7439.181690014094, 7461.813454663677, 7544.962275188699, 7620.085589729268, 7713.460057796013, 4393.384926110347, 4576.130535198302, 4857.53853383928, 4858.271259311132, 4903.4606193318705, 1906.9416473856866, 1993.8205561499535, 2006.7792542820278, 2044.703517989883, 2053.5482193917314, 933.7385532051037, 970.9708708738749, 1042.9830914857953, 1058.3808472043786, 1078.439211609974, 18494.612904032252, 19032.238710451704, 19361.709574356395, 19386.903065484865, 19628.214871404984, 994.4530508220449, 999.4303247149201, 1015.3349556040805, 1031.6286136990677, 1041.9791604167874, 7972.481788683717, 10362.533685984148, 10620.0, 11028.889711102947, 11032.338059716443, 517043.0, 517420.0, 526686.0, 530751.0, 531049.0, 90292.0, 92566.0, 94887.52556237312, 95365.52317238509, 98031.50984245034, 1073.6403304892792, 1091.2743025887855, 1093.4696672114019, 1095.315427857589, 1123.938183399921, 16301.673966520366, 16385.42651007209, 16439.424620138238, 16644.916775416044, 16976.91511542484, 40229.597704023756, 41470.170596588505, 43419.34870977001, 44200.55799441964, 44982.325265120395, 51960.480395197075, 52500.73749631227, 53789.0, 57690.711546442, 58974.410255896895, 7541.585212813348, 7740.535567865946, 8534.487930724177, 8789.165029322205, 8952.418092823946, 12885.162464439782, 13299.534516291884, 13311.534096306583, 13366.866331336561, 13430.865691343352, 6170.5372097092, 9516.809663806824, 11679.357635329798, 11866.703332416759, 12110.212836165621, 11629.068176598115, 11672.474738636613, 11694.0, 11793.705157371134, 11805.940970295094, 6328.868566884557, 10420.43693445989, 12108.939455302667, 12292.48004159163, 12328.568500102454, 7996.920030799618, 8001.3598912088055, 8096.583097957951, 8233.711820086268, 8274.669013239403, 10312.742181445525, 11945.104117191418, 11945.581904633296, 11986.940065299967, 12190.6952326189], "value": 9153.396186077474, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1388.9722205555884, 1507.1635242440445, 1547.7755725419815, 1640.48324777695, 1754.0, 8450.239478446947, 8769.56152192391, 8780.51707156107, 9040.773980650487, 9079.818403631924, 8936.821263574724, 9135.36052476327, 9139.405938613985, 9142.36003479755, 9146.131117543839, 2070.5858828234414, 2334.1480359668767, 2402.6396040593877, 2428.574999375111, 2438.780509754122, 2422.9151979680714, 2433.1605596069326, 2440.365504968703, 2441.1211963693145, 2448.0574978633176, 2329.7204335479764, 2354.493783836475, 2360.0323867214447, 2360.811135109187, 2361.4686695493547, 1993.6810110382341, 2226.076178385968, 2293.0369244917174, 2294.66727324537, 2309.7805708457668, 2272.1820144748044, 2277.738060123074, 2277.89749461273, 2278.532900755341, 2322.465832858435, 8956.731298061068, 8979.46123232608, 8993.775155621097, 8998.730038098867, 9017.594208260578, 5126.974365128188, 5219.321488206547, 5225.16397376418, 5227.843164705065, 5234.450382709837, 3412.368711788327, 3514.6661067198575, 3559.6440355964455, 3561.269939662389, 3599.4240921452442, 934.9532523373872, 1068.5351871935654, 1082.8862969388178, 1148.2478976270472, 1164.3363282928733, 12176.452059657337, 12248.632541023695, 12269.386530673431, 12275.324857132855, 12276.079294052892, 4508.684392092522, 4545.431821022372, 4579.816807327669, 4644.117617652635, 4647.9767601161775, 9686.660966866126, 9697.127258546765, 9698.224142068617, 9701.17540009093, 9705.466199358962, 4675.111728771522, 5274.12976858814, 5276.577873770166, 5278.2346559749385, 5280.20796880471, 4869.6591238612955, 4999.10016197088, 5015.27278544609, 5043.445221025684, 5044.445111037783, 3811.27585758705, 3890.902727431837, 3934.803259836997, 3949.170674158459, 3995.780232087205, 1144.2562334482652, 1153.8788427215022, 1154.3074155506797, 1159.9594014209463, 1189.5182451107316, 931.4504442379028, 965.7006328038331, 971.8639390485338, 996.446971930571, 1016.3444578246989, 5929.911051334234, 6038.215032045894, 6100.755969761249, 6192.767963012806, 6280.5603607747935, 460.43826531631356, 465.5367908930568, 465.8765427161805, 466.47521538269444, 471.8463357091946, 4086.4483294754828, 4204.579542045771, 4224.809883555247, 4243.6605071594195, 4257.063446041866, 11176.944115279372, 11510.251833630788, 11520.135989800798, 11543.76912461746, 11544.769104618028, 10740.0, 11184.217104802585, 11196.272242304223, 11376.260543064674, 11464.0, 8136.389770767216, 8244.587770611446, 8256.215659512336, 8256.99999999988, 8308.875366869503, 6168.228971378578, 6346.492280617632, 6354.142190804368, 6401.103845461732, 6409.8397540060005, 10299.176065914558, 10372.0, 10373.377597344184, 10434.330507118824, 10478.842817357898, 10588.0, 10912.345259284468, 10947.303168008653, 10956.945215273872, 10995.780084398428, 6909.412699920362, 7027.894581581382, 7711.922880771121, 7796.766097016872, 7910.129885712452, 8446.535440550828, 8581.098984606526, 8673.392862499564, 8676.869846952175, 8682.43564168327, 7653.88519172224, 7708.768736937901, 7750.147483776893, 7908.762737117896, 7996.1204267529365, 7725.49784264021, 7781.494202876795, 7826.491278066907, 7879.566623835744, 8035.598220089089, 7163.928360716326, 7212.567245965259, 7220.963895180701, 7231.710731570678, 7256.310650488195, 5145.228215767665, 5195.818146364859, 5331.573474122139, 5394.730263486888, 5447.809326673548, 5222.164453687392, 5287.0, 5305.973470132624, 5405.702686352289, 5438.809641662523], "value": 4595.059076165675, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.6525267428993, 346.4335810949098, 348.0880094153318, 349.67130896956843, 355.39050528343887, 5117.5159203830935, 5216.0, 5290.1535754279275, 5323.8136665216725, 5337.332833395825, 5219.095030313936, 5260.92108618371, 5275.261463395118, 5286.286351342567, 5292.179712144623, 4167.729097608661, 4186.916261674764, 4189.832406703728, 4210.810513526899, 4218.029853133781, 3960.6830004447434, 3973.9270396992792, 4164.562720914306, 4188.4555007849085, 4194.4547208862805, 4081.4694089768436, 4146.3158578834455, 4177.728447650893, 4181.728187667792, 4189.958100419002, 4111.958880411203, 4122.814473348676, 4129.525104612978, 4141.461609990712, 4177.895552611179, 3447.8103704296254, 3687.4284485904727, 3700.3709369406997, 3701.3337599232127, 3726.5341832270965, 5040.747962601855, 5074.720890351029, 5079.415867175248, 5081.74591270435, 5106.208537676666, 4741.881452963669, 4748.881277968044, 4756.310335001405, 4786.760661966922, 4796.160671882441, 5344.706041167734, 5474.0, 5475.6181061238485, 5499.212755854363, 5512.91730624041, 3983.780892050936, 3998.5601583825755, 4004.2992476316526, 4009.6391324780616, 4010.879673609767, 5664.150377443375, 5694.206325007609, 5718.742656580423, 5731.512821410185, 5782.653040817564, 3901.3757798752063, 3985.8405663773124, 3994.72036957416, 4082.9387559186634, 4106.979465102655, 4158.355454904495, 4158.771267580312, 4160.625543701081, 4165.729227600196, 4179.080602267496, 4714.905701885943, 4718.268668356411, 4727.479977202505, 4741.359916411309, 4754.976225118921, 5244.344456942882, 5500.559955203654, 5503.862403439866, 5512.145617429305, 5525.4474552544425, 1320.8481024682092, 1349.6558377613646, 1351.175782772508, 1354.7222819322014, 1404.1434724817852, 931.3480563605503, 941.7551436626527, 946.2430055955208, 954.1126752120456, 957.913787759105, 4670.346151538787, 4781.450133234653, 4799.136155492045, 4860.246661767432, 4882.438519570295, 5022.372203474566, 5041.269015992661, 5047.343845300087, 5056.974715126401, 5063.443021267658, 4771.403574553181, 4784.952150478451, 4793.472718001017, 4833.516648335209, 4836.951630483721, 2501.9749802502106, 2638.934026649311, 2662.9201123965922, 2677.3306673331667, 2701.3516755978426, 5080.491950804964, 5126.871828204325, 5169.139109920426, 5179.715115668674, 5214.191800270964, 5065.392152941597, 5086.72023038729, 5104.540591346797, 5119.10415677255, 5165.302684137669, 4980.178270585313, 5133.460986596354, 5144.048351055067, 5185.922211166836, 5221.477852214748, 4974.358461707556, 4974.925376119289, 4996.6252531061045, 5049.520295571915, 5052.418971818141, 4938.407391112945, 4987.80048798044, 4992.900141997213, 4998.850034498971, 4999.65002449825, 4957.330760347451, 5156.633492124515, 5182.1190397631735, 5185.792568297225, 5192.610554208525, 3720.5535335759882, 3737.551493820759, 3752.718546108998, 3763.9435408469444, 3793.393057110849, 4975.731188547042, 4978.3820258415435, 4986.950130498649, 5020.439575816433, 5067.391912970467, 4544.954550454453, 4864.270359446112, 4875.68308059975, 4928.852134435972, 5269.47185316254, 5375.16684913839, 5404.351477822686, 5408.782399216302, 5415.808522125126, 5473.589480788877, 5422.15956526739, 5441.183822426509, 5441.347038355423, 5454.972725136349, 5465.344158700981, 5276.2349459327415, 5531.557475401878, 5534.667919924817, 5546.473085056913, 5570.693611851225, 5398.919016214839, 5463.754131064191, 5464.316960379952, 5464.754086066216, 5467.507924286833], "value": 4051.061996068541, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [827.4456114403349, 832.3174996502867, 860.2730692564783, 861.5003298087111, 868.4224990381397, 14631.561053168409, 14722.190279534621, 14736.926315368408, 14758.040727352714, 14943.850561494382, 14415.207163605997, 14448.566543003726, 14459.421623135087, 14467.5659730208, 14475.855241447609, 672.1665135232313, 799.7520768561735, 819.2790344496834, 823.6869989404018, 825.145973916996, 813.3493205435658, 823.3989187892812, 826.5412695953743, 829.0176141272611, 829.5852073963018, 725.3942957630359, 875.6541166239332, 877.863931090682, 879.1779685993616, 879.3756432932626, 861.3711990247124, 878.4026861734051, 879.7404765594165, 880.4497189256715, 881.5724373678761, 640.3180612647516, 640.7565125252421, 641.2561428742682, 642.8007317731518, 644.4007073421716, 12560.81158782619, 12604.180728252633, 12616.369181540886, 12628.431720572504, 12684.80972785409, 13532.797008044887, 13640.727185456235, 13657.0, 13690.110142840784, 13749.381277842422, 12088.0, 12213.755724885452, 12283.93858030713, 12572.30852303123, 12661.113722039461, 4497.820087196508, 4528.75091869947, 4584.908301833978, 4657.743824089674, 4708.623310135181, 58486.0, 61566.384336156516, 62427.68786156086, 63819.0, 63989.3601063988, 11119.388433636066, 11300.604478843201, 11309.547618095348, 11311.434428278717, 11412.0, 20461.0, 20462.079206435745, 20706.275280365116, 20966.89516552438, 21013.15947362119, 8424.620892059873, 8445.915540844513, 8449.450785699033, 8571.871421928676, 8716.346274029473, 4396.494403143615, 4483.50681425043, 4564.657650676212, 4610.631149508032, 4823.2282834746275, 3501.3872572299747, 3572.035550401429, 3578.427451607782, 3599.3341231872187, 3600.801955892399, 541.1071731642821, 635.8187916443791, 662.2218892800939, 665.4609766089483, 672.4855485553555, 697.272492500845, 725.176924191047, 730.3645828129455, 743.1305372713938, 752.8306131120454, 10060.496975151213, 10138.797224055477, 10183.439910804977, 10219.335743176818, 10332.793344133075, 599.2449513612879, 618.7555915413386, 715.7459102018812, 722.512304194677, 727.5016613619688, 4325.8104021394365, 4472.731636101779, 4579.931301030487, 4613.769311534477, 4683.086798074411, 74861.62569187119, 76967.0, 77381.0, 77404.2259577397, 77633.0, 65629.0, 66529.33470665231, 68384.6580767093, 68709.65645171743, 69206.30793692099, 58869.70565147147, 59208.70395647994, 60457.0, 60740.69629651823, 61566.692166539775, 9048.185663290336, 9082.682106126254, 9092.0, 9109.817803643757, 9129.863052054092, 39946.0, 39974.400383993685, 40075.398869016404, 40086.0, 40349.79825100856, 50397.0, 55181.0, 56271.15593266022, 56298.0, 57168.42831571631, 11856.288622682667, 11925.344106073902, 11975.341356225492, 12121.636350909486, 12314.75370492603, 12285.508579656713, 12353.32056736888, 12381.938090309492, 12567.560135395217, 13277.535286264934, 9982.850257246293, 10268.383896966207, 12501.062420318694, 12691.93654031724, 13206.273654948685, 12045.759084818079, 12325.0, 12412.131150819356, 12450.813237801622, 12475.625731228078, 12486.05028345834, 13102.803457948328, 13240.933795331348, 13508.189508629514, 13548.254845983565, 11371.43142842838, 11657.825132622847, 11685.415729213335, 11708.590199342982, 12018.459169337226, 10677.879968512336, 11139.33164010162, 11466.598669046543, 11602.18784685064, 11616.593419230287], "value": 7075.188726865414, "unit": "score"}], "name": "dromaeo_css", "value": 7147.631072345926, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 104.0, 109.0, 109.0, 107.0, 109.0, 110.0, 109.0, 109.0, 108.0], "value": 108.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 133.0, 152.0, 203.0, 124.0, 153.0, 163.0, 123.0, 189.0, 133.0], "value": 151.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 161.0, 160.0, 161.0, 160.0, 161.0, 161.0, 163.0, 159.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 75.0, 146.0, 77.0, 74.0, 75.0, 74.0, 133.0, 78.0], "value": 88.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 90.0, 149.0, 92.0, 91.0, 93.0, 128.0, 91.0, 91.0, 121.0], "value": 103.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 117.0, 117.0, 116.0, 117.0, 117.0, 118.0, 118.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 145.0, 146.0, 146.0, 145.0, 145.0, 145.0, 145.0, 146.0], "value": 145.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 97.0, 95.0, 96.0, 95.0, 94.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 54.0, 54.0, 54.0, 53.0, 55.0, 56.0, 113.0, 52.0, 52.0], "value": 60.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 59.0, 59.0, 61.0, 58.0, 60.0, 59.0, 62.0, 59.0], "value": 59.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 79.0, 78.0, 78.0, 80.0, 78.0, 80.0, 84.0, 85.0], "value": 79.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 121.0, 129.0, 125.0, 118.0, 118.0, 126.0, 118.0, 209.0, 122.0], "value": 131.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 162.0, 158.0, 160.0, 157.0, 160.0, 162.0, 160.0, 160.0, 158.0], "value": 160.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 63.0, 62.0, 63.0, 66.0, 62.0, 62.0, 61.0, 66.0], "value": 62.7, "unit": "score"}], "name": "kraken", "value": 1524.3, "alertThreshold": 2.0}]} 13:20:44 INFO - Return code: 0 13:20:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 13:20:45 ERROR - # TBPL SUCCESS # 13:20:45 INFO - Running post-action listener: _resource_record_post_action 13:20:45 INFO - ##### 13:20:45 INFO - ##### Finished run-tests step (success) 13:20:45 INFO - ##### 13:20:45 INFO - Running post-run listener: _resource_record_post_run 13:20:45 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 7979008; Write bytes: 448880640; Read time: 2992; Write time: 366596 13:20:45 INFO - TinderboxPrint: CPU usage
13.2% 13:20:45 INFO - TinderboxPrint: I/O read bytes / time
7,979,008 / 2,992 13:20:45 INFO - TinderboxPrint: I/O write bytes / time
448,880,640 / 366,596 13:20:45 INFO - TinderboxPrint: CPU idle
8,287.3 (86.8%) 13:20:45 INFO - TinderboxPrint: CPU user
1,212.1 (12.7%) 13:20:45 INFO - TinderboxPrint: Swap in / out
0 / 0 13:20:45 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 4096; Write bytes: 83550208; Read time: 116; Write time: 89692 13:20:45 INFO - run-tests - Wall time: 1180s; CPU: 13.0%; Read bytes: 6238208; Write bytes: 365330432; Read time: 2836; Write time: 276904 13:20:45 INFO - Running post-run listener: _upload_blobber_files 13:20:45 INFO - Blob upload gear active. 13:20:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:20:45 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 13:20:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:20:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:20:46 INFO - (blobuploader) - INFO - Open directory for files ... 13:20:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:20:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:20:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:20:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:20:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:20:46 INFO - (blobuploader) - INFO - Done attempting. 13:20:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:20:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:20:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:20:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:20:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:20:47 INFO - (blobuploader) - INFO - Done attempting. 13:20:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:20:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:20:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:20:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:20:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:20:51 INFO - (blobuploader) - INFO - Done attempting. 13:20:51 INFO - (blobuploader) - INFO - Iteration through files over. 13:20:51 INFO - Return code: 0 13:20:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:20:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:20:51 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1b5c67e4a9c5fed5d383e6eee4ef7880825abe0e166d8831f90d14e4e45b2eb499e1bcda97760c0c99e4b0e20747fe8f166a22e4b8e5c76697820ec983e0aa1f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/da00d8950570598a5e4faffc48ac2b9d41f15e5b300b38db14911116e0c605495ce51f043580bd27cb26adb67dbcf9c563170fea10acc937049fd5e2556c6753", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5f1a154b9b4476c3df4edc5ef55cef0475a7195b564769ace2fd7bab1090b0160af9adc50ec63f0046ace1daa3c57d89d846d4d4c81e8c751d6b1c6dfcbed39e"} 13:20:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:20:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:20:51 INFO - Contents: 13:20:51 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1b5c67e4a9c5fed5d383e6eee4ef7880825abe0e166d8831f90d14e4e45b2eb499e1bcda97760c0c99e4b0e20747fe8f166a22e4b8e5c76697820ec983e0aa1f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/da00d8950570598a5e4faffc48ac2b9d41f15e5b300b38db14911116e0c605495ce51f043580bd27cb26adb67dbcf9c563170fea10acc937049fd5e2556c6753", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5f1a154b9b4476c3df4edc5ef55cef0475a7195b564769ace2fd7bab1090b0160af9adc50ec63f0046ace1daa3c57d89d846d4d4c81e8c751d6b1c6dfcbed39e"} 13:20:51 INFO - Running post-run listener: copy_logs_to_upload_dir 13:20:51 INFO - Copying logs to upload dir... 13:20:51 INFO - mkdir: /builds/slave/test/build/upload/logs 13:20:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.371522 ========= master_lag: 0.16 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-05-18 13:20:51.902724) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-18 13:20:51.905933) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=41bf3e9aed84707956354da8000001c5-1463600856.741969-971307083 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1b5c67e4a9c5fed5d383e6eee4ef7880825abe0e166d8831f90d14e4e45b2eb499e1bcda97760c0c99e4b0e20747fe8f166a22e4b8e5c76697820ec983e0aa1f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/da00d8950570598a5e4faffc48ac2b9d41f15e5b300b38db14911116e0c605495ce51f043580bd27cb26adb67dbcf9c563170fea10acc937049fd5e2556c6753", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5f1a154b9b4476c3df4edc5ef55cef0475a7195b564769ace2fd7bab1090b0160af9adc50ec63f0046ace1daa3c57d89d846d4d4c81e8c751d6b1c6dfcbed39e"} build_url:https://queue.taskcluster.net/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011367 build_url: 'https://queue.taskcluster.net/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1b5c67e4a9c5fed5d383e6eee4ef7880825abe0e166d8831f90d14e4e45b2eb499e1bcda97760c0c99e4b0e20747fe8f166a22e4b8e5c76697820ec983e0aa1f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/da00d8950570598a5e4faffc48ac2b9d41f15e5b300b38db14911116e0c605495ce51f043580bd27cb26adb67dbcf9c563170fea10acc937049fd5e2556c6753", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5f1a154b9b4476c3df4edc5ef55cef0475a7195b564769ace2fd7bab1090b0160af9adc50ec63f0046ace1daa3c57d89d846d4d4c81e8c751d6b1c6dfcbed39e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-18 13:20:51.933086) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 13:20:51.933692) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=41bf3e9aed84707956354da8000001c5-1463600856.741969-971307083 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004382 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 13:20:52.016083) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-18 13:20:52.016502) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-18 13:20:52.017003) ========= ========= Total master_lag: 0.31 =========