builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-053 starttime: 1463811554.24 results: success (0) buildid: 20160520203038 builduid: abeca14195a54ba0a7b3eb8e3a460e64 revision: 06d9362a57a10396f3f7c7989c21f1a416f4c7e9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-20 23:19:14.240474) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-20 23:19:14.240939) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-20 23:19:14.241223) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-20 23:19:14.356049) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 23:19:14.356415) ========= 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=920470bf432669ad7e3a5387000001ca-1463810633.710429-220729042 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-20 23:19:14-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.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% 18.8M=0.001s 2016-05-20 23:19:14 (18.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.084235 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 23:19:14.459722) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 23:19:14.460053) ========= 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=920470bf432669ad7e3a5387000001ca-1463810633.710429-220729042 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022659 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 23:19:14.511073) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 23:19:14.511625) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 06d9362a57a10396f3f7c7989c21f1a416f4c7e9 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 06d9362a57a10396f3f7c7989c21f1a416f4c7e9 --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=920470bf432669ad7e3a5387000001ca-1463810633.710429-220729042 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-20 23:19:14,554 truncating revision to first 12 chars 2016-05-20 23:19:14,554 Setting DEBUG logging. 2016-05-20 23:19:14,555 attempt 1/10 2016-05-20 23:19:14,555 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/06d9362a57a1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-20 23:19:14,835 unpacking tar archive at: fx-team-06d9362a57a1/testing/mozharness/ program finished with exit code 0 elapsedTime=0.589572 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 23:19:15.114951) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-20 23:19:15.115273) ========= script_repo_revision: 06d9362a57a10396f3f7c7989c21f1a416f4c7e9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-20 23:19:15.115730) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-20 23:19:15.116005) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-20 23:19:15.132907) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-05-20 23:19:15.133271) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=920470bf432669ad7e3a5387000001ca-1463810633.710429-220729042 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 23:19:15 INFO - MultiFileLogger online at 20160520 23:19:15 in /builds/slave/test 23:19:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 23:19:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:19:15 INFO - {'append_to_log': False, 23:19:15 INFO - 'base_work_dir': '/builds/slave/test', 23:19:15 INFO - 'blob_upload_branch': 'Fx-Team', 23:19:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:19:15 INFO - 'branch': 'Fx-Team', 23:19:15 INFO - 'buildbot_json_path': 'buildprops.json', 23:19:15 INFO - 'config_files': ('talos/linux_config.py',), 23:19:15 INFO - 'default_actions': ('clobber', 23:19:15 INFO - 'read-buildbot-config', 23:19:15 INFO - 'download-and-extract', 23:19:15 INFO - 'populate-webroot', 23:19:15 INFO - 'create-virtualenv', 23:19:15 INFO - 'install', 23:19:15 INFO - 'run-tests'), 23:19:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:19:15 INFO - 'download_minidump_stackwalk': True, 23:19:15 INFO - 'download_symbols': 'ondemand', 23:19:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:19:15 INFO - 'tooltool.py': '/tools/tooltool.py', 23:19:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:19:15 INFO - '/tools/misc-python/virtualenv.py')}, 23:19:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:19:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:19:15 INFO - 'installer_path': 'installer.exe', 23:19:15 INFO - 'log_level': 'info', 23:19:15 INFO - 'log_name': 'talos', 23:19:15 INFO - 'log_to_console': True, 23:19:15 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 23:19:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 23:19:15 INFO - 'opt_config_files': (), 23:19:15 INFO - 'pip_index': False, 23:19:15 INFO - 'sps_profile': False, 23:19:15 INFO - 'sps_profile_interval': 0, 23:19:15 INFO - 'suite': 'dromaeojs', 23:19:15 INFO - 'system_bits': '32', 23:19:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:19:15 INFO - 'title': 'talos-linux64-ix-053', 23:19:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:19:15 INFO - 'use_talos_json': True, 23:19:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:19:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:19:15 INFO - 'work_dir': 'build'} 23:19:15 INFO - ##### 23:19:15 INFO - ##### Running clobber step. 23:19:15 INFO - ##### 23:19:15 INFO - Running pre-action listener: _resource_record_pre_action 23:19:15 INFO - Running main action method: clobber 23:19:15 INFO - rmtree: /builds/slave/test/build 23:19:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:19:16 INFO - Running post-action listener: _resource_record_post_action 23:19:16 INFO - ##### 23:19:16 INFO - ##### Finished clobber step (success) 23:19:16 INFO - ##### 23:19:16 INFO - ##### 23:19:16 INFO - ##### Running read-buildbot-config step. 23:19:16 INFO - ##### 23:19:16 INFO - Running pre-action listener: _resource_record_pre_action 23:19:16 INFO - Running main action method: read_buildbot_config 23:19:16 INFO - Using buildbot properties: 23:19:16 INFO - { 23:19:16 INFO - "project": "", 23:19:16 INFO - "product": "firefox", 23:19:16 INFO - "script_repo_revision": "production", 23:19:16 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 23:19:16 INFO - "repository": "", 23:19:16 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 23:19:16 INFO - "buildid": "20160520203038", 23:19:16 INFO - "pgo_build": "True", 23:19:16 INFO - "basedir": "/builds/slave/test", 23:19:16 INFO - "buildnumber": 704, 23:19:16 INFO - "slavename": "talos-linux64-ix-053", 23:19:16 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 23:19:16 INFO - "platform": "ubuntu64_hw", 23:19:16 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 23:19:16 INFO - "branch": "fx-team", 23:19:16 INFO - "repo_path": "integration/fx-team", 23:19:16 INFO - "revision": "06d9362a57a10396f3f7c7989c21f1a416f4c7e9", 23:19:16 INFO - "stage_platform": "linux64-pgo", 23:19:16 INFO - "builduid": "abeca14195a54ba0a7b3eb8e3a460e64", 23:19:16 INFO - "slavebuilddir": "test" 23:19:16 INFO - } 23:19:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/eKJy6gDWTG-cHMld2N__2w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 23:19:16 INFO - Running post-action listener: _resource_record_post_action 23:19:16 INFO - ##### 23:19:16 INFO - ##### Finished read-buildbot-config step (success) 23:19:16 INFO - ##### 23:19:16 INFO - ##### 23:19:16 INFO - ##### Running download-and-extract step. 23:19:16 INFO - ##### 23:19:16 INFO - Running pre-action listener: _resource_record_pre_action 23:19:16 INFO - Running main action method: download_and_extract 23:19:16 INFO - mkdir: /builds/slave/test/build/tests 23:19:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:19:16 INFO - https://queue.taskcluster.net/v1/task/eKJy6gDWTG-cHMld2N__2w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 23:19:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eKJy6gDWTG-cHMld2N__2w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 23:19:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eKJy6gDWTG-cHMld2N__2w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 23:19:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eKJy6gDWTG-cHMld2N__2w/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 23:19:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eKJy6gDWTG-cHMld2N__2w/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 23:19:23 INFO - Downloaded 1361 bytes. 23:19:23 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 23:19:23 INFO - Using the following test package requirements: 23:19:23 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 23:19:23 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:19:23 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 23:19:23 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:19:23 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 23:19:23 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:19:23 INFO - u'jsshell-linux-x86_64.zip'], 23:19:23 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:19:23 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 23:19:23 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 23:19:23 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:19:23 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 23:19:23 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:19:23 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 23:19:23 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:19:23 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 23:19:23 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:19:23 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 23:19:23 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 23:19:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:19:23 INFO - https://queue.taskcluster.net/v1/task/eKJy6gDWTG-cHMld2N__2w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:19:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eKJy6gDWTG-cHMld2N__2w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 23:19:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eKJy6gDWTG-cHMld2N__2w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 23:19:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eKJy6gDWTG-cHMld2N__2w/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 23:19:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eKJy6gDWTG-cHMld2N__2w/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 23:19:27 INFO - Downloaded 24389251 bytes. 23:19:27 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:19:27 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 23:19:28 INFO - Return code: 0 23:19:28 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 23:19:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:19:28 INFO - https://queue.taskcluster.net/v1/task/eKJy6gDWTG-cHMld2N__2w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:19:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eKJy6gDWTG-cHMld2N__2w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 23:19:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eKJy6gDWTG-cHMld2N__2w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 23:19:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eKJy6gDWTG-cHMld2N__2w/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 23:19:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eKJy6gDWTG-cHMld2N__2w/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 23:19:33 INFO - Downloaded 24389251 bytes. 23:19:33 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'] 23:19:33 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 23:19:34 INFO - Return code: 0 23:19:34 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')]} 23:19:34 INFO - https://queue.taskcluster.net/v1/task/eKJy6gDWTG-cHMld2N__2w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 23:19:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eKJy6gDWTG-cHMld2N__2w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 23:19:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eKJy6gDWTG-cHMld2N__2w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 23:19:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eKJy6gDWTG-cHMld2N__2w/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 23:19:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eKJy6gDWTG-cHMld2N__2w/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 23:19:35 INFO - Downloaded 10840359 bytes. 23:19:35 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'] 23:19:35 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 23:19:36 INFO - Return code: 0 23:19: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')]} 23:19:36 INFO - https://queue.taskcluster.net/v1/task/eKJy6gDWTG-cHMld2N__2w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 23:19:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eKJy6gDWTG-cHMld2N__2w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 23:19:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eKJy6gDWTG-cHMld2N__2w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 23:19:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eKJy6gDWTG-cHMld2N__2w/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 23:19:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eKJy6gDWTG-cHMld2N__2w/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 23:19:37 INFO - Downloaded 58593479 bytes. 23:19:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eKJy6gDWTG-cHMld2N__2w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 23:19:37 INFO - mkdir: /builds/slave/test/properties 23:19:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:19:37 INFO - Writing to file /builds/slave/test/properties/build_url 23:19:37 INFO - Contents: 23:19:37 INFO - build_url:https://queue.taskcluster.net/v1/task/eKJy6gDWTG-cHMld2N__2w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 23:19:38 INFO - Running post-action listener: _resource_record_post_action 23:19:38 INFO - Running post-action listener: set_extra_try_arguments 23:19:38 INFO - ##### 23:19:38 INFO - ##### Finished download-and-extract step (success) 23:19:38 INFO - ##### 23:19:38 INFO - ##### 23:19:38 INFO - ##### Running populate-webroot step. 23:19:38 INFO - ##### 23:19:38 INFO - Running pre-action listener: _resource_record_pre_action 23:19:38 INFO - Running main action method: populate_webroot 23:19:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:19:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:19:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:19:38 INFO - '--tppagecycles', 23:19:38 INFO - '7'], 23:19:38 INFO - 'tests': ['tsvgm']}}, 23:19:38 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:19:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:19:38 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:19:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:19:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:19:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:19:38 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:19:38 INFO - 'talos_options': ['--disable-e10s'], 23:19:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:19:38 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:19:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:19:38 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:19:38 INFO - 'talos_options': ['--disable-e10s'], 23:19:38 INFO - 'tests': ['damp', 'tps']}, 23:19:38 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:19:38 INFO - 'tests': ['damp', 'tps']}, 23:19:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:19:38 INFO - 'tests': ['dromaeo_dom']}, 23:19:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:19:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:19:38 INFO - 'tests': ['a11yr', 23:19:38 INFO - 'ts_paint', 23:19:38 INFO - 'tpaint', 23:19:38 INFO - 'sessionrestore', 23:19:38 INFO - 'sessionrestore_no_auto_restore', 23:19:38 INFO - 'tabpaint']}, 23:19:38 INFO - 'other-e10s': {'tests': ['a11yr', 23:19:38 INFO - 'ts_paint', 23:19:38 INFO - 'tpaint', 23:19:38 INFO - 'sessionrestore', 23:19:38 INFO - 'sessionrestore_no_auto_restore', 23:19:38 INFO - 'tabpaint']}, 23:19:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:19:38 INFO - 'ts_paint', 23:19:38 INFO - 'tpaint', 23:19:38 INFO - 'sessionrestore', 23:19:38 INFO - 'sessionrestore_no_auto_restore', 23:19:38 INFO - 'tabpaint']}, 23:19:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:19:38 INFO - 'ts_paint', 23:19:38 INFO - 'tpaint', 23:19:38 INFO - 'sessionrestore', 23:19:38 INFO - 'sessionrestore_no_auto_restore', 23:19:38 INFO - 'tabpaint']}, 23:19:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:19:38 INFO - 'tests': ['a11yr', 23:19:38 INFO - 'ts_paint', 23:19:38 INFO - 'tpaint', 23:19:38 INFO - 'sessionrestore', 23:19:38 INFO - 'sessionrestore_no_auto_restore', 23:19:38 INFO - 'tabpaint']}, 23:19:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:19:38 INFO - 'tests': ['a11yr', 23:19:38 INFO - 'ts_paint', 23:19:38 INFO - 'tpaint', 23:19:38 INFO - 'sessionrestore', 23:19:38 INFO - 'sessionrestore_no_auto_restore', 23:19:38 INFO - 'tabpaint']}, 23:19:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:19:38 INFO - 'tests': ['tsvgx', 23:19:38 INFO - 'tsvgr_opacity', 23:19:38 INFO - 'tart', 23:19:38 INFO - 'tscrollx', 23:19:38 INFO - 'cart']}, 23:19:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:19:38 INFO - 'tsvgr_opacity', 23:19:38 INFO - 'tart', 23:19:38 INFO - 'tscrollx', 23:19:38 INFO - 'cart']}, 23:19:38 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:19:38 INFO - 'talos_options': ['--disable-e10s'], 23:19:38 INFO - 'tests': ['tp5o']}, 23:19:38 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:19:38 INFO - 'tests': ['tp5o']}, 23:19:38 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:19:38 INFO - 'talos_options': ['--disable-e10s', 23:19:38 INFO - '--xperf_path', 23:19:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:19:38 INFO - 'tests': ['tp5n']}, 23:19:38 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:19:38 INFO - 'talos_options': ['--xperf_path', 23:19:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:19:38 INFO - 'tests': ['tp5n']}}, 23:19:38 INFO - 'talos.zip': {'path': '', 23:19:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:19:38 INFO - Running post-action listener: _resource_record_post_action 23:19:38 INFO - ##### 23:19:38 INFO - ##### Finished populate-webroot step (success) 23:19:38 INFO - ##### 23:19:38 INFO - ##### 23:19:38 INFO - ##### Running create-virtualenv step. 23:19:38 INFO - ##### 23:19:38 INFO - Running pre-action listener: _resource_record_pre_action 23:19:38 INFO - Running main action method: create_virtualenv 23:19:38 INFO - Creating virtualenv /builds/slave/test/build/venv 23:19:38 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 23:19:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:19:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:19:38 INFO - Using real prefix '/usr' 23:19:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:19:38 INFO - Installing distribute.............................................................................................................................................................................................done. 23:19:40 INFO - Installing pip.................done. 23:19:40 INFO - Return code: 0 23:19:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:19:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:19:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:19:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:19:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:19:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:19:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:19:40 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 0x7f8ae66bdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16ac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1777770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18069c0>, '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 0x18078c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1807d50>, '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': '920470bf432669ad7e3a5387000001ca-1463810633.710429-220729042', '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 23:19:40 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 23:19:40 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 23:19:40 INFO - Using env: {'DISPLAY': ':0', 23:19:40 INFO - 'HOME': '/home/cltbld', 23:19:40 INFO - 'LANG': 'en_US.UTF-8', 23:19:40 INFO - 'LANGUAGE': 'en_US:en', 23:19:40 INFO - 'LOGNAME': 'cltbld', 23:19:40 INFO - 'MAIL': '/var/mail/cltbld', 23:19:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:19:40 INFO - 'MOZ_NO_REMOTE': '1', 23:19:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:19:40 INFO - 'NO_EM_RESTART': '1', 23:19:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:19:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:19:40 INFO - 'PWD': '/builds/slave/test', 23:19:40 INFO - 'SHELL': '/bin/bash', 23:19:40 INFO - 'SHLVL': '1', 23:19:40 INFO - 'TERM': 'linux', 23:19:40 INFO - 'TMOUT': '86400', 23:19:40 INFO - 'USER': 'cltbld', 23:19:40 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1463810633.710429-220729042', 23:19:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:19:40 INFO - '_': '/tools/buildbot/bin/python'} 23:19:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:19:40 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 23:19:40 INFO - Cleaning up... 23:19:40 INFO - Return code: 0 23:19:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:19:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:19:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:19:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:19:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:19:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:19:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:19:40 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 0x7f8ae66bdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16ac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1777770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18069c0>, '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 0x18078c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1807d50>, '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': '920470bf432669ad7e3a5387000001ca-1463810633.710429-220729042', '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 23:19:40 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 23:19:40 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 23:19:40 INFO - Using env: {'DISPLAY': ':0', 23:19:40 INFO - 'HOME': '/home/cltbld', 23:19:40 INFO - 'LANG': 'en_US.UTF-8', 23:19:40 INFO - 'LANGUAGE': 'en_US:en', 23:19:40 INFO - 'LOGNAME': 'cltbld', 23:19:40 INFO - 'MAIL': '/var/mail/cltbld', 23:19:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:19:40 INFO - 'MOZ_NO_REMOTE': '1', 23:19:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:19:40 INFO - 'NO_EM_RESTART': '1', 23:19:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:19:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:19:40 INFO - 'PWD': '/builds/slave/test', 23:19:40 INFO - 'SHELL': '/bin/bash', 23:19:40 INFO - 'SHLVL': '1', 23:19:40 INFO - 'TERM': 'linux', 23:19:40 INFO - 'TMOUT': '86400', 23:19:40 INFO - 'USER': 'cltbld', 23:19:40 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1463810633.710429-220729042', 23:19:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:19:40 INFO - '_': '/tools/buildbot/bin/python'} 23:19:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:19:41 INFO - Downloading/unpacking psutil>=3.1.1 23:19:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:19:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:19:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:19:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:19:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:19:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:19:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:19:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:19:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:19:43 INFO - Installing collected packages: psutil 23:19:43 INFO - Running setup.py install for psutil 23:19:43 INFO - building 'psutil._psutil_linux' extension 23:19:43 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 23:19:43 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 23:19:43 INFO - building 'psutil._psutil_posix' extension 23:19:43 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 23:19:43 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 23:19:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:19:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:19:43 INFO - Successfully installed psutil 23:19:43 INFO - Cleaning up... 23:19:43 INFO - Return code: 0 23:19:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:19:43 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')]} 23:19:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:19:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:19:43 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')]} 23:19:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:19:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:19:43 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 0x7f8ae66bdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16ac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1777770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18069c0>, '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 0x18078c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1807d50>, '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': '920470bf432669ad7e3a5387000001ca-1463810633.710429-220729042', '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 23:19:43 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 23:19:43 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 23:19:43 INFO - Using env: {'DISPLAY': ':0', 23:19:43 INFO - 'HOME': '/home/cltbld', 23:19:43 INFO - 'LANG': 'en_US.UTF-8', 23:19:43 INFO - 'LANGUAGE': 'en_US:en', 23:19:43 INFO - 'LOGNAME': 'cltbld', 23:19:43 INFO - 'MAIL': '/var/mail/cltbld', 23:19:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:19:43 INFO - 'MOZ_NO_REMOTE': '1', 23:19:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:19:43 INFO - 'NO_EM_RESTART': '1', 23:19:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:19:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:19:43 INFO - 'PWD': '/builds/slave/test', 23:19:43 INFO - 'SHELL': '/bin/bash', 23:19:43 INFO - 'SHLVL': '1', 23:19:43 INFO - 'TERM': 'linux', 23:19:43 INFO - 'TMOUT': '86400', 23:19:43 INFO - 'USER': 'cltbld', 23:19:43 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1463810633.710429-220729042', 23:19:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:19:43 INFO - '_': '/tools/buildbot/bin/python'} 23:19:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:19:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:19: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 23:19: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 23:19:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:19: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 23:19: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 23:19: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 23:19:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:19:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:19:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:19:45 INFO - Installing collected packages: mozsystemmonitor 23:19:45 INFO - Running setup.py install for mozsystemmonitor 23:19:45 INFO - Successfully installed mozsystemmonitor 23:19:45 INFO - Cleaning up... 23:19:46 INFO - Return code: 0 23:19:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:19:46 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')]} 23:19:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:19:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:19:46 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')]} 23:19:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:19:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:19:46 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 0x7f8ae66bdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16ac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1777770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18069c0>, '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 0x18078c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1807d50>, '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': '920470bf432669ad7e3a5387000001ca-1463810633.710429-220729042', '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 23:19:46 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 23:19:46 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 23:19:46 INFO - Using env: {'DISPLAY': ':0', 23:19:46 INFO - 'HOME': '/home/cltbld', 23:19:46 INFO - 'LANG': 'en_US.UTF-8', 23:19:46 INFO - 'LANGUAGE': 'en_US:en', 23:19:46 INFO - 'LOGNAME': 'cltbld', 23:19:46 INFO - 'MAIL': '/var/mail/cltbld', 23:19:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:19:46 INFO - 'MOZ_NO_REMOTE': '1', 23:19:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:19:46 INFO - 'NO_EM_RESTART': '1', 23:19:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:19:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:19:46 INFO - 'PWD': '/builds/slave/test', 23:19:46 INFO - 'SHELL': '/bin/bash', 23:19:46 INFO - 'SHLVL': '1', 23:19:46 INFO - 'TERM': 'linux', 23:19:46 INFO - 'TMOUT': '86400', 23:19:46 INFO - 'USER': 'cltbld', 23:19:46 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1463810633.710429-220729042', 23:19:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:19:46 INFO - '_': '/tools/buildbot/bin/python'} 23:19:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:19:46 INFO - Downloading/unpacking blobuploader==1.2.4 23:19:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:19:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:19:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:19:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:19:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:19:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:19:48 INFO - Downloading blobuploader-1.2.4.tar.gz 23:19:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:19:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:19:48 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 23:19:48 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 23:19:48 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 23:19:48 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 23:19:48 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 23:19:48 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 23:19:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:19:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:19:48 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 23:19:48 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 23:19:48 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 23:19:48 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 23:19:48 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 23:19:48 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 23:19:48 INFO - Downloading docopt-0.6.1.tar.gz 23:19:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:19:48 INFO - Installing collected packages: blobuploader, requests, docopt 23:19:48 INFO - Running setup.py install for blobuploader 23:19:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:19:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:19:48 INFO - Running setup.py install for requests 23:19:49 INFO - Running setup.py install for docopt 23:19:49 INFO - Successfully installed blobuploader requests docopt 23:19:49 INFO - Cleaning up... 23:19:49 INFO - Return code: 0 23:19:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:19:49 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')]} 23:19:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:19:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:19:49 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')]} 23:19:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:19:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:19:49 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 0x7f8ae66bdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16ac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1777770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18069c0>, '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 0x18078c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1807d50>, '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': '920470bf432669ad7e3a5387000001ca-1463810633.710429-220729042', '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 23:19:49 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 23:19:49 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 23:19:49 INFO - Using env: {'DISPLAY': ':0', 23:19:49 INFO - 'HOME': '/home/cltbld', 23:19:49 INFO - 'LANG': 'en_US.UTF-8', 23:19:49 INFO - 'LANGUAGE': 'en_US:en', 23:19:49 INFO - 'LOGNAME': 'cltbld', 23:19:49 INFO - 'MAIL': '/var/mail/cltbld', 23:19:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:19:49 INFO - 'MOZ_NO_REMOTE': '1', 23:19:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:19:49 INFO - 'NO_EM_RESTART': '1', 23:19:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:19:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:19:49 INFO - 'PWD': '/builds/slave/test', 23:19:49 INFO - 'SHELL': '/bin/bash', 23:19:49 INFO - 'SHLVL': '1', 23:19:49 INFO - 'TERM': 'linux', 23:19:49 INFO - 'TMOUT': '86400', 23:19:49 INFO - 'USER': 'cltbld', 23:19:49 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1463810633.710429-220729042', 23:19:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:19:49 INFO - '_': '/tools/buildbot/bin/python'} 23:19:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:19:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:19:49 INFO - Running setup.py (path:/tmp/pip-8c08nW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:19:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:19:49 INFO - Running setup.py (path:/tmp/pip-g1KRKG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:19:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:19:49 INFO - Running setup.py (path:/tmp/pip-yksJpt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:19:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:19:49 INFO - Running setup.py (path:/tmp/pip-0lurIj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:19:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:19:49 INFO - Running setup.py (path:/tmp/pip-GyjFgn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:19:50 INFO - Running setup.py (path:/tmp/pip-Wft_bA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:19:50 INFO - Running setup.py (path:/tmp/pip-Sl23Al-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:19:50 INFO - Running setup.py (path:/tmp/pip-1hcNGr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:19:50 INFO - Running setup.py (path:/tmp/pip-_f2cth-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:19:50 INFO - Running setup.py (path:/tmp/pip-kmOjYR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:19:50 INFO - Running setup.py (path:/tmp/pip-_TVpLZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:19:50 INFO - Running setup.py (path:/tmp/pip-mIF9sL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:19:50 INFO - Running setup.py (path:/tmp/pip-P6MGcZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:19:50 INFO - Running setup.py (path:/tmp/pip-bs0Jin-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:19:50 INFO - Running setup.py (path:/tmp/pip-KgdqgM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:19:50 INFO - Running setup.py (path:/tmp/pip-fL01Xb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:19:50 INFO - Running setup.py (path:/tmp/pip-VI5Z9G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:19:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:19:50 INFO - Running setup.py install for manifestparser 23:19:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:19:50 INFO - Running setup.py install for mozcrash 23:19:51 INFO - Running setup.py install for mozdebug 23:19:51 INFO - Running setup.py install for mozdevice 23:19:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:19:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:19:51 INFO - Running setup.py install for mozfile 23:19:51 INFO - Running setup.py install for mozhttpd 23:19:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:19:51 INFO - Running setup.py install for mozinfo 23:19:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:19:51 INFO - Running setup.py install for mozInstall 23:19:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:19:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:19:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:19:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:19:51 INFO - Running setup.py install for mozleak 23:19:51 INFO - Running setup.py install for mozlog 23:19:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:19:51 INFO - Running setup.py install for moznetwork 23:19:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:19:52 INFO - Running setup.py install for mozprocess 23:19:52 INFO - Running setup.py install for mozprofile 23:19:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:19:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:19:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:19:52 INFO - Running setup.py install for mozrunner 23:19:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:19:52 INFO - Running setup.py install for mozscreenshot 23:19:52 INFO - Running setup.py install for moztest 23:19:52 INFO - Running setup.py install for mozversion 23:19:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:19:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:19:52 INFO - Cleaning up... 23:19:52 INFO - Return code: 0 23:19:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:19:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:19:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:19:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:19:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:19:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:19:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:19:52 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 0x7f8ae66bdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16ac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1777770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18069c0>, '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 0x18078c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1807d50>, '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': '920470bf432669ad7e3a5387000001ca-1463810633.710429-220729042', '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 23:19:52 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 23:19:52 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 23:19:52 INFO - Using env: {'DISPLAY': ':0', 23:19:52 INFO - 'HOME': '/home/cltbld', 23:19:52 INFO - 'LANG': 'en_US.UTF-8', 23:19:52 INFO - 'LANGUAGE': 'en_US:en', 23:19:52 INFO - 'LOGNAME': 'cltbld', 23:19:52 INFO - 'MAIL': '/var/mail/cltbld', 23:19:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:19:52 INFO - 'MOZ_NO_REMOTE': '1', 23:19:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:19:52 INFO - 'NO_EM_RESTART': '1', 23:19:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:19:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:19:52 INFO - 'PWD': '/builds/slave/test', 23:19:52 INFO - 'SHELL': '/bin/bash', 23:19:52 INFO - 'SHLVL': '1', 23:19:52 INFO - 'TERM': 'linux', 23:19:52 INFO - 'TMOUT': '86400', 23:19:52 INFO - 'USER': 'cltbld', 23:19:52 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1463810633.710429-220729042', 23:19:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:19:52 INFO - '_': '/tools/buildbot/bin/python'} 23:19:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:19:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:19:52 INFO - Running setup.py (path:/tmp/pip-KXYeKO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:19:52 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)) 23:19:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:19:52 INFO - Running setup.py (path:/tmp/pip-CJenKC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:19:53 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)) 23:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:19:53 INFO - Running setup.py (path:/tmp/pip-gROQeJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:19:53 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)) 23:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:19:53 INFO - Running setup.py (path:/tmp/pip-aJTbFR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:19:53 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)) 23:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:19:53 INFO - Running setup.py (path:/tmp/pip-FxXQ_S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:19:53 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)) 23:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:19:53 INFO - Running setup.py (path:/tmp/pip-BJZE2F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:19:53 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)) 23:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:19:53 INFO - Running setup.py (path:/tmp/pip-vIIBki-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:19:53 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)) 23:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:19:53 INFO - Running setup.py (path:/tmp/pip-UkZdj_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:19:53 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)) 23:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:19:53 INFO - Running setup.py (path:/tmp/pip-4Xk6ym-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:19:53 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)) 23:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:19:53 INFO - Running setup.py (path:/tmp/pip-XsvMdo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:19:53 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)) 23:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:19:53 INFO - Running setup.py (path:/tmp/pip-ChYGC8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:19:53 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)) 23:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:19:53 INFO - Running setup.py (path:/tmp/pip-sPNjy7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:19:53 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)) 23:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:19:53 INFO - Running setup.py (path:/tmp/pip-RJVoPp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:19:53 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)) 23:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:19:53 INFO - Running setup.py (path:/tmp/pip-zmORIC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:19:53 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)) 23:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:19:53 INFO - Running setup.py (path:/tmp/pip-etqGAJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:19:53 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)) 23:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:19:53 INFO - Running setup.py (path:/tmp/pip-BPSTIz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:19:54 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)) 23:19:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:19:54 INFO - Running setup.py (path:/tmp/pip-HgvLGA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:19:54 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)) 23:19:54 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)) 23:19:54 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)) 23:19:54 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)) 23:19:54 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)) 23:19:54 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)) 23:19:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:19:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:19:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:19:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:19:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:19:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:19:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:19:55 INFO - Downloading blessings-1.6.tar.gz 23:19:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:19:56 INFO - Installing collected packages: blessings 23:19:56 INFO - Running setup.py install for blessings 23:19:56 INFO - Successfully installed blessings 23:19:56 INFO - Cleaning up... 23:19:56 INFO - Return code: 0 23:19:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:19:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:19:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:19:56 INFO - Reading from file tmpfile_stdout 23:19:56 INFO - Current package versions: 23:19:56 INFO - argparse == 1.2.1 23:19:56 INFO - blessings == 1.6 23:19:56 INFO - blobuploader == 1.2.4 23:19:56 INFO - docopt == 0.6.1 23:19:56 INFO - manifestparser == 1.1 23:19:56 INFO - mozInstall == 1.12 23:19:56 INFO - mozcrash == 0.17 23:19:56 INFO - mozdebug == 0.1 23:19:56 INFO - mozdevice == 0.48 23:19:56 INFO - mozfile == 1.2 23:19:56 INFO - mozhttpd == 0.7 23:19:56 INFO - mozinfo == 0.9 23:19:56 INFO - mozleak == 0.1 23:19:56 INFO - mozlog == 3.2 23:19:56 INFO - moznetwork == 0.27 23:19:56 INFO - mozprocess == 0.22 23:19:56 INFO - mozprofile == 0.28 23:19:56 INFO - mozrunner == 6.11 23:19:56 INFO - mozscreenshot == 0.1 23:19:56 INFO - mozsystemmonitor == 0.3 23:19:56 INFO - moztest == 0.7 23:19:56 INFO - mozversion == 1.4 23:19:56 INFO - psutil == 3.1.1 23:19:56 INFO - requests == 1.2.3 23:19:56 INFO - wsgiref == 0.1.2 23:19:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:19:56 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')]} 23:19:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:19:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:19:56 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')]} 23:19:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:19:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:19:56 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 0x7f8ae66bdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16ac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1777770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18069c0>, '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 0x18078c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1807d50>, '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': '920470bf432669ad7e3a5387000001ca-1463810633.710429-220729042', '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 23:19:56 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 23:19:56 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 23:19:56 INFO - Using env: {'DISPLAY': ':0', 23:19:56 INFO - 'HOME': '/home/cltbld', 23:19:56 INFO - 'LANG': 'en_US.UTF-8', 23:19:56 INFO - 'LANGUAGE': 'en_US:en', 23:19:56 INFO - 'LOGNAME': 'cltbld', 23:19:56 INFO - 'MAIL': '/var/mail/cltbld', 23:19:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:19:56 INFO - 'MOZ_NO_REMOTE': '1', 23:19:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:19:56 INFO - 'NO_EM_RESTART': '1', 23:19:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:19:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:19:56 INFO - 'PWD': '/builds/slave/test', 23:19:56 INFO - 'SHELL': '/bin/bash', 23:19:56 INFO - 'SHLVL': '1', 23:19:56 INFO - 'TERM': 'linux', 23:19:56 INFO - 'TMOUT': '86400', 23:19:56 INFO - 'USER': 'cltbld', 23:19:56 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1463810633.710429-220729042', 23:19:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:19:56 INFO - '_': '/tools/buildbot/bin/python'} 23:19:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:19:56 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)) 23:19:56 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)) 23:19:56 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)) 23:19:56 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)) 23:19:56 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)) 23:19:56 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)) 23:19:56 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)) 23:19:56 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)) 23:19:56 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)) 23:19:56 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)) 23:19:56 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)) 23:19:56 INFO - Cleaning up... 23:19:56 INFO - Return code: 0 23:19:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:19:56 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')]} 23:19:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:19:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:19:56 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')]} 23:19:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:19:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:19:56 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 0x7f8ae66bdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16ac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1777770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18069c0>, '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 0x18078c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1807d50>, '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': '920470bf432669ad7e3a5387000001ca-1463810633.710429-220729042', '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 23:19:56 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 23:19:56 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 23:19:56 INFO - Using env: {'DISPLAY': ':0', 23:19:56 INFO - 'HOME': '/home/cltbld', 23:19:56 INFO - 'LANG': 'en_US.UTF-8', 23:19:56 INFO - 'LANGUAGE': 'en_US:en', 23:19:56 INFO - 'LOGNAME': 'cltbld', 23:19:56 INFO - 'MAIL': '/var/mail/cltbld', 23:19:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:19:56 INFO - 'MOZ_NO_REMOTE': '1', 23:19:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:19:56 INFO - 'NO_EM_RESTART': '1', 23:19:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:19:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:19:56 INFO - 'PWD': '/builds/slave/test', 23:19:56 INFO - 'SHELL': '/bin/bash', 23:19:56 INFO - 'SHLVL': '1', 23:19:56 INFO - 'TERM': 'linux', 23:19:56 INFO - 'TMOUT': '86400', 23:19:56 INFO - 'USER': 'cltbld', 23:19:56 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1463810633.710429-220729042', 23:19:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:19:56 INFO - '_': '/tools/buildbot/bin/python'} 23:19:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:19:56 INFO - Downloading/unpacking jsonschema 23:19:56 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 23:19:56 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 23:19:56 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 23:19:56 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 23:19:56 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 23:19:56 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 23:19:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:19:58 INFO - Downloading/unpacking functools32 (from jsonschema) 23:19:58 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 23:19:58 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 23:19:58 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 23:19:58 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 23:19:58 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 23:19:58 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 23:19:58 INFO - Downloading functools32-3.2.3-2.tar.gz 23:19:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:19:59 INFO - warning: no files found matching '*.txt' 23:19:59 INFO - no previously-included directories found matching 'build' 23:19:59 INFO - no previously-included directories found matching 'dist' 23:19:59 INFO - no previously-included directories found matching '.git*' 23:19:59 INFO - Installing collected packages: jsonschema, functools32 23:19:59 INFO - Running setup.py install for functools32 23:19:59 INFO - warning: no files found matching '*.txt' 23:19:59 INFO - no previously-included directories found matching 'build' 23:19:59 INFO - no previously-included directories found matching 'dist' 23:19:59 INFO - no previously-included directories found matching '.git*' 23:19:59 INFO - Successfully installed jsonschema functools32 23:19:59 INFO - Cleaning up... 23:19:59 INFO - Return code: 0 23:19:59 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 23:19:59 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')]} 23:19:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:19:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:19:59 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')]} 23:19:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:19:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:19:59 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 0x7f8ae66bdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16ac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1777770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18069c0>, '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 0x18078c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1807d50>, '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': '920470bf432669ad7e3a5387000001ca-1463810633.710429-220729042', '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 23:19:59 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 23:19:59 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 23:19:59 INFO - Using env: {'DISPLAY': ':0', 23:19:59 INFO - 'HOME': '/home/cltbld', 23:19:59 INFO - 'LANG': 'en_US.UTF-8', 23:19:59 INFO - 'LANGUAGE': 'en_US:en', 23:19:59 INFO - 'LOGNAME': 'cltbld', 23:19:59 INFO - 'MAIL': '/var/mail/cltbld', 23:19:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:19:59 INFO - 'MOZ_NO_REMOTE': '1', 23:19:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:19:59 INFO - 'NO_EM_RESTART': '1', 23:19:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:19:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:19:59 INFO - 'PWD': '/builds/slave/test', 23:19:59 INFO - 'SHELL': '/bin/bash', 23:19:59 INFO - 'SHLVL': '1', 23:19:59 INFO - 'TERM': 'linux', 23:19:59 INFO - 'TMOUT': '86400', 23:19:59 INFO - 'USER': 'cltbld', 23:19:59 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1463810633.710429-220729042', 23:19:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:19:59 INFO - '_': '/tools/buildbot/bin/python'} 23:19:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:19:59 INFO - Downloading/unpacking flake8 23:19:59 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 23:19:59 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 23:19:59 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 23:19:59 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 23:19:59 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 23:19:59 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 23:20:01 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 23:20:01 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 23:20:01 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 23:20:01 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 23:20:01 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 23:20:01 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 23:20:01 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 23:20:01 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 23:20:01 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 23:20:01 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 23:20:01 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 23:20:01 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 23:20:01 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 23:20:01 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 23:20:01 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 23:20:01 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 23:20:01 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 23:20:01 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 23:20:01 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 23:20:01 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 23:20:01 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 23:20:01 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 23:20:01 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 23:20:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 23:20:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:20:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:20:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:20:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:20:01 INFO - no previously-included directories found matching 'docs/_build' 23:20:02 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 23:20:02 INFO - Running setup.py install for pep8 23:20:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:20:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:20:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:20:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:20:02 INFO - no previously-included directories found matching 'docs/_build' 23:20:02 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 23:20:02 INFO - Successfully installed flake8 mccabe pyflakes pep8 23:20:02 INFO - Cleaning up... 23:20:02 INFO - Return code: 0 23:20:02 INFO - Running post-action listener: _resource_record_post_action 23:20:02 INFO - Running post-action listener: _start_resource_monitoring 23:20:02 INFO - Starting resource monitoring. 23:20:02 INFO - ##### 23:20:02 INFO - ##### Finished create-virtualenv step (success) 23:20:02 INFO - ##### 23:20:02 INFO - ##### 23:20:02 INFO - ##### Running install step. 23:20:02 INFO - ##### 23:20:02 INFO - Running pre-action listener: _resource_record_pre_action 23:20:02 INFO - Running main action method: install 23:20:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:20:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:20:02 INFO - Reading from file tmpfile_stdout 23:20:02 INFO - Detecting whether we're running mozinstall >=1.0... 23:20:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:20:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:20:02 INFO - Reading from file tmpfile_stdout 23:20:02 INFO - Output received: 23:20:02 INFO - Usage: mozinstall [options] installer 23:20:02 INFO - Options: 23:20:02 INFO - -h, --help show this help message and exit 23:20:02 INFO - -d DEST, --destination=DEST 23:20:02 INFO - Directory to install application into. [default: 23:20:02 INFO - "/builds/slave/test"] 23:20:02 INFO - --app=APP Application being installed. [default: firefox] 23:20:02 INFO - mkdir: /builds/slave/test/build/application 23:20:02 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'] 23:20:02 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 23:20:19 INFO - Reading from file tmpfile_stdout 23:20:19 INFO - Output received: 23:20:19 INFO - /builds/slave/test/build/application/firefox/firefox 23:20:19 INFO - Running post-action listener: _resource_record_post_action 23:20:19 INFO - ##### 23:20:19 INFO - ##### Finished install step (success) 23:20:19 INFO - ##### 23:20:19 INFO - ##### 23:20:19 INFO - ##### Running run-tests step. 23:20:19 INFO - ##### 23:20:19 INFO - Running pre-action listener: _resource_record_pre_action 23:20:19 INFO - Running main action method: run_tests 23:20:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:20:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:20:19 INFO - Python 2.7.3 23:20:19 INFO - Return code: 0 23:20:19 INFO - grabbing minidump binary from tooltool 23:20: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')]} 23:20:19 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 0x18069c0>, '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 0x18078c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1807d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:20:19 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 23:20:19 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 23:20:19 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 23:20:19 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:20:19 INFO - Return code: 0 23:20:19 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 23:20:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:20:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:20:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 23:20:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:20:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 23:20:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 23:20:20 INFO - Return code: 0 23:20:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eKJy6gDWTG-cHMld2N__2w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--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 23:20:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/eKJy6gDWTG-cHMld2N__2w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-053 --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 23:20:20 INFO - Using env: {'DISPLAY': ':0', 23:20:20 INFO - 'HOME': '/home/cltbld', 23:20:20 INFO - 'LANG': 'en_US.UTF-8', 23:20:20 INFO - 'LANGUAGE': 'en_US:en', 23:20:20 INFO - 'LOGNAME': 'cltbld', 23:20:20 INFO - 'MAIL': '/var/mail/cltbld', 23:20:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:20:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:20:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:20:20 INFO - 'MOZ_NO_REMOTE': '1', 23:20:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:20:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:20:20 INFO - 'NO_EM_RESTART': '1', 23:20:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:20:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:20:20 INFO - 'PWD': '/builds/slave/test', 23:20:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:20:20 INFO - 'SHELL': '/bin/bash', 23:20:20 INFO - 'SHLVL': '1', 23:20:20 INFO - 'TERM': 'linux', 23:20:20 INFO - 'TMOUT': '86400', 23:20:20 INFO - 'USER': 'cltbld', 23:20:20 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1463810633.710429-220729042', 23:20:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:20:20 INFO - '_': '/tools/buildbot/bin/python'} 23:20:20 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eKJy6gDWTG-cHMld2N__2w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--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 23:20:20 INFO - mozversion application_buildid: 20160520203038 23:20:20 INFO - mozversion application_changeset: 06d9362a57a10396f3f7c7989c21f1a416f4c7e9 23:20:20 INFO - mozversion application_display_name: Nightly 23:20:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:20:20 INFO - mozversion application_name: Firefox 23:20:20 INFO - mozversion application_remotingname: firefox 23:20:20 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 23:20:20 INFO - mozversion application_vendor: Mozilla 23:20:20 INFO - mozversion application_version: 49.0a1 23:20:20 INFO - mozversion platform_buildid: 20160520203038 23:20:20 INFO - mozversion platform_changeset: 06d9362a57a10396f3f7c7989c21f1a416f4c7e9 23:20:20 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 23:20:20 INFO - mozversion platform_version: 49.0a1 23:20:20 INFO - using testdate: 1463811620 23:20:20 INFO - actual date: 1463811620 23:20:20 INFO - starting webserver on 'localhost:50332' 23:20:20 INFO - SUITE-START | Running 2 tests 23:20:20 INFO - TEST-START | dromaeo_css 23:20:20 INFO - operating with platform_type : linux_ 23:20:20 INFO - Initialising browser for dromaeo_css test... 23:20:20 INFO - TEST-INFO | started process 10859 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp84BeW0/profile http://localhost:50332/getInfo.html) 23:20:24 INFO - PROCESS | 10859 | __metrics Screen width/height:1600/1200 23:20:24 INFO - PROCESS | 10859 | colorDepth:24 23:20:24 INFO - PROCESS | 10859 | Browser inner width/height: 1024/697 23:20:24 INFO - PROCESS | 10859 | __metrics 23:20:26 ERROR - PROCESS | 10859 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 23:20:26 INFO - TEST-INFO | 10859: exit 0 23:20:26 INFO - Browser initialized. 23:20:26 INFO - Running cycle 1/1 for dromaeo_css test... 23:20:26 INFO - TEST-INFO | started process 10941 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp84BeW0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:20:38 INFO - PROCESS | 10941 | RSS: Main: 152702976 23:20:38 INFO - PROCESS | 10941 | 23:21:29 INFO - PROCESS | 10941 | 1463811689773 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] 23:23:38 INFO - PROCESS | 10941 | Cycle 1(1): loaded http://localhost:50332/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50332/tests/dromaeo/cssquery-ext.html) 23:23:39 INFO - PROCESS | 10941 | RSS: Main: 241389568 23:23:39 INFO - PROCESS | 10941 | 23:26:41 INFO - PROCESS | 10941 | Cycle 1(1): loaded http://localhost:50332/tests/dromaeo/cssquery-ext.html (next: http://localhost:50332/tests/dromaeo/cssquery-jquery.html) 23:26:41 INFO - PROCESS | 10941 | RSS: Main: 235061248 23:26:41 INFO - PROCESS | 10941 | 23:29:43 INFO - PROCESS | 10941 | Cycle 1(1): loaded http://localhost:50332/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50332/tests/dromaeo/cssquery-mootools.html) 23:29:44 INFO - PROCESS | 10941 | RSS: Main: 235249664 23:29:44 INFO - PROCESS | 10941 | 23:32:46 INFO - PROCESS | 10941 | Cycle 1(1): loaded http://localhost:50332/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50332/tests/dromaeo/cssquery-prototype.html) 23:32:47 INFO - PROCESS | 10941 | RSS: Main: 235229184 23:32:47 INFO - PROCESS | 10941 | 23:35:49 INFO - PROCESS | 10941 | Cycle 1(1): loaded http://localhost:50332/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50332/tests/dromaeo/cssquery-yui.html) 23:35:50 INFO - PROCESS | 10941 | RSS: Main: 218222592 23:35:50 INFO - PROCESS | 10941 | 23:38:52 INFO - PROCESS | 10941 | Cycle 1(1): loaded http://localhost:50332/tests/dromaeo/cssquery-yui.html (next: http://localhost:50332/tests/dromaeo/cssquery-dojo.html) 23:38:53 INFO - PROCESS | 10941 | RSS: Main: 302862336 23:38:53 INFO - PROCESS | 10941 | 23:38:53 INFO - PROCESS | 10941 | __start_tp_report 23:38:53 INFO - PROCESS | 10941 | _x_x_mozilla_page_load 23:38:53 INFO - PROCESS | 10941 | _x_x_mozilla_page_load_details 23:38:53 INFO - PROCESS | 10941 | |i|pagename|runs| 23:38:53 INFO - PROCESS | 10941 | |0;dojo.html;3187.6971687689675;3198.1844629619445;3198.7600929930245;3208.4866421372585;3249.658785827488;5383.246345511631;5439.673619582828;5489.58828087893;5526.585506087044;5608.074667679838;5463.9180412293845;5504.504594586487;5508.504234618884;5565.582581306408;5590.86022849429;4938.2345736410825;4957.405111386621;5113.284140220372;5188.37739471264;5200.115980283361;5097.439281679012;5117.309163262968;5156.690598564098;5178.8705282368055;5184.611154163434;4883.706977581339;4909.8772530686665;4958.107540642683;5029.471905449912;5053.140966035764;5027.773750181214;5041.621878359138;5043.117454445494;5054.342935418409;5099.031184075051;5008.774605142778;5038.219076043182;5046.4701206373165;5049.141645920203;5064.746762661852;5316.893662126736;5421.322334708161;5481.095619222823;5484.533814625762;5515.779368825242;5798.42015798421;5866.823995280106;5869.970650146722;5877.941220587825;5882.147088672119;5979.611325263887;5996.4603185713495;6005.039193729026;6005.819825405245;6006.879862402728;5396.325459317585;5471.534919531845;5525.088360420566;5527.419620939824;5587.8882422351735;6047.667378294236;6061.543373710356;6072.4222871560005;6090.878182436415;6114.602550834182;5857.26784151981;5865.442782935615;5867.501262392659;5869.119632055141;5886.381929897385;5714.171445140431;5865.266841644794;5954.196183515257;5956.76172953077;6001.759929602855;5965.522758179335;5976.282846058501;5979.372165922602;5980.910286345708;5988.850278743066;5762.711864406763;5935.0503919372695;5981.790637327673;5994.6403215807195;6003.159557661931;4437.400950871655;4472.9776351118035;4485.775711214426;4497.437820272466;4505.639548836085;3986.6412022918075;3997.2605068062503;4007.458993035961;4021.3163762160502;4034.576369481162;5442.863928401822;5494.587905907073;5610.719464026783;5615.157726341082;5627.493525582717;5904.822855314347;5969.015112506475;5974.820755377345;6000.759969601254;6021.337652858182;5617.915731264035;5621.241132447068;5622.7750889963945;5625.09998400254;5626.240457538262;4993.1262029144755;5026.723530205873;5130.461301563357;5131.846044618593;5135.281060651511;6064.727087281083;6137.775380989496;6144.324124346318;6146.6004709693725;6148.047052706837;5938.8218353449465;5957.553183511167;5963.0459126539545;5967.6419414835245;5997.13041608973;5919.112133180058;6109.541784366279;6115.483736156635;6133.539984501091;6145;5875.706214689163;5895.233619629421;5912.054071348563;5915.822525324247;5969.194158788508;6026.487748541423;6032.728527216198;6036.758529658764;6038.5169186465855;6039.124326972652;5568.359638641446;5791.478766910998;5840.532757379485;5864.354920958605;5887.882242355044;5853.8536536585225;5874.559408044328;5876.412358764261;5878.382769809279;5880;5767.048437007847;5779.797707080232;5785.161151633074;5789.102689083199;5790.1893734877995;5612.663240205601;5778.133280008033;5861.267341582302;5865;5960.403959604005;5746;5766.59633825645;5773.797917072882;5776.7400466979925;5787.768489260382 23:38:53 INFO - PROCESS | 10941 | |1;ext.html;613.5643692977985;644.4361183964031;652.057776512939;662.4336192555367;662.9966850165748;41671;42977;43214;43296.567034329644;46266;44463.77768111163;44946.7752661237;45006;45055;45106.54893451065;8734.737957861258;8896.332775041865;8918.375713700043;8920.776980575472;8923.598438070303;8776.868346974801;8786;8808.295336373074;8867.068957759438;8869.822603547926;8841.071687472819;8958.059403762609;8976.057513961037;8979.102089791035;8985.95507022464;8882.333824963122;8916.910830891673;8936.910630893673;8942.016378198394;8944.955275223614;8732.12678732128;8808.823823523559;8827.16141966512;8828.24959878411;8834.425762325429;41075.178496430206;41309.586904130876;41463;41480.79259603713;41504.1699166015;3470.16715988161;3608.891733248007;4209.663226941837;4228.978855105735;4241.300185469394;31947;32246;32488;32503.6749632503;33433.6656633433;4381;4388.758618275993;4404.625606823424;4476.35092911526;4523.9347711730725;309736;309892;310079;310092;310665;55680;56757.14864277039;56933.14600280999;56968.14547781787;57134.142987855215;60413.39586604077;60445.395546044856;60492;60617;60679.39320606826;16178.676426471404;16313.347466101222;16322.591935201715;16330.591735206477;16332.265048072864;13027.283499407624;13105.14816536922;13111.868881311064;13114.540991065458;13153;14637.341319640642;16980.660386792195;17154;17223.39718109885;17229.3969711062;5586.888262234814;6226.59527130735;6344.397282258178;6566.868662626721;6656.434203092791;936.4381371177375;974.8001659659752;983.5426526664992;1028.1723212813756;1102.6251074634663;31596;32248.54881530337;32650.18374540608;33018.834905825795;33188.668113318556;1079.9298045627008;1083.5719890643309;1117.2235296468875;1121.2599684208383;1209.0086129374006;16575.336986520644;16924.323027079026;17032.2335494903;17125.314987400365;17269.740953885703;444435;447903;448727;450986;452124;55279.72360138254;55340;55473.167902481495;55630.72184639051;55733.16400254;1306.1314226039767;1342.744878473087;1349.001738713345;1365.2900491744238;1401.985980140206;29912.466193754226;31737.682623173474;32713;32928;35451.68432234904;55662.44337556572;56219;56280.718596406754;56340.71829640825;56345.71827140838;427539;436238;436419;439037;440395;25089.49821003606;27707.168784936483;27776.722232777414;28214.294642634097;28557.42885142244;47512;48574.271385928536;49079;49776.50223497719;49784.502154977985;39637.40543891901;41077;41651.583484164774;41778.582214178685;42334.57665423306;40288.59711402848;40365.59634403618;41151.794241028605;41418;41464.79267603643;39457.80271098626;40377.79811100926;40871;41365.37951930783;41386.58613413948;34222;35652.64347356493;36418;36502;36789.632103679694;27775.305617358918;27835.582466263426;28078.2980425491;28090.2977425566;28368.574471382533 23:38:53 INFO - PROCESS | 10941 | |2;jquery.html;381.39548815128023;383.35213489203227;386.61531775883003;400.0638505896203;414.6848395219632;14463.855361446382;16031;17848;19155.329563465264;19349;11026.889731102687;11448.255863368897;18681.439556813282;19044.5238869028;19063.332783352584;5001.249812528114;5146.871328216787;5151.665141765792;5175.767090480937;5183.974080129594;3863.5750067492554;5067.898642027158;5129.051125541787;5479.698616576086;5727.9427205727825;5727.026405511073;5756.683382413966;5775.971120144393;5786.218860453848;5786.855328616797;5571.0807716726695;5739.024365857773;5752.942470575304;5753.252077229975;5757.913631295534;5552.333719953632;5559.610827242094;5566.248556444869;5568.777248910039;5572.944270557283;19897.50256243591;22024.44938876525;22704.88647556768;22716.091356345725;23087.191948281896;2175.5431359414542;2305.4236440889777;2378.940526486834;2461.4707837814776;2606.4787042591556;9651;9741.805163896754;9751.073648003428;9780.853287200698;9971.152452041586;1802.306112146833;1901.4485799118238;1905.0950798370802;1945.202466988528;1951.834094102003;79067;81514.59242703748;82768;83295.16704832934;83374.58312708458;10612.681619551426;10805.83791243132;10841;10955.561777528968;11051.72370690723;15395.61510962235;15489.457868974532;15498.225088745517;15500.457483988232;15513.534593962198;7956.7215147470715;8322.6670933162;8366.456180348257;8521.914780852112;8549.273311768446;8139.593020349077;8141.592920353959;8258.091609922903;8348.833023339499;8349.123342049119;4518.4126063612175;4553.795079221443;4726.834560790356;4738.218193998021;4843.24929635907;1770.4069136839303;1829.606634573573;1913.7320775091498;1925;1947.805219478041;954.6181527388961;965.7537327981461;1005.5776573839005;1029.902159294866;1044.4464433849919;16375.918120409322;18037.007964561813;18226.544336391697;18531.629367412577;20267.493312667008;964.3056998960737;992.1963209799941;1016.2581315639625;1030.2427715629008;1034.6637342863598;10445.425501597334;10694.518746656418;10758.354498730101;10797.028267455968;10839.536662550454;454142;467511;474760;477109;482927.1219234237;64224.67887660625;90339;91568.54215728879;91686.54156729307;91701.79043733438;995.447102520071;1019.0879163148941;1042.95828166874;1049.459528342904;1053.984190237147;16819.495415137564;17456.563585910455;17549.736753948957;17621.606532207432;17646.294148233927;36822.631773682995;42203;43052.56947430611;43807.561924380345;44264.77867610641;47729.761351193025;54403;55041.44958550363;55240.723796380764;55578;7882.448228623941;7997.520148791091;8036.6383512740895;8306.252437280673;8339.20777526134;13448.93275533616;13451.529196478075;13629;13642.93178534101;13714.451421943011;10835.891641083488;11272.785417333078;11362.829557556477;11857.762844742885;12128;12064.51741930348;12118.697032574257;12143.271403715453;12248.571300004458;12488;9772.511374431393;11837.638671552882;12388.752224955631;12463.572745474903;12549.372531373212;7312.122545294386;7704.6918123274445;7712.267334603203;7979.241972012649;8818.5590720465;11395.658130256106;11994.280343179436;12026.458809353426;12047.819282710941;12095.57665481704 23:38:53 INFO - PROCESS | 10941 | |3;mootools.html;1322.9404676789543;1497.0568541818657;1542.1595230599323;1686.2327640923381;1847.8244566766152;8415.621297041633;8677.479351238922;8689.261412779913;9044.547772611377;9044.728658140266;8719.5640217989;8825.51459669718;8890.466572005667;8929.642814287437;8935.329850261223;2376.1327115602853;2394.4373072328076;2404.3989002749313;2405.9639105413435;2409.494006258688;2358.929232123039;2386.916457923973;2408.8675122868235;2413.227767114524;2415.0219161239725;2351.6942797436395;2353.976460235393;2387.7612238776155;2387.7970372518357;2388.0567175965484;2276.4877902471976;2279.430142464384;2305.688732021181;2306.8039216666602;2308.215206829669;2228.9554208915893;2277.9772202278095;2294.8967296471533;2295.1049090854412;2295.5064661097776;7423.183449820515;7521.05986751656;8719.912800871973;8810.251128654008;8812.8678069829;5083.466236045199;5268.315119034501;5277.419483856773;5358.892822143574;5359.356877174725;3340.0981734931433;3355.832208389595;3426.126337783874;3457.2566898116775;3481.9651803481893;1055.2771351624162;1056.973575660607;1057.7197042783669;1070.6413351527249;1099.9890001099966;12365.443555039954;12488.25070495773;12495.375231238402;12532.81200781989;12545.874541254561;4430.158269928767;4606.723596584216;4675.368825208553;4766.428028636586;4797.992421591505;10418.68743937683;10420.531076101593;10422;10434.895651043544;10436.739081523023;5300.09898317287;5314.28257185275;5321.281626980308;5325.627206095536;5330.093884039723;4863.489333619989;4873.439554451213;4886.242632391985;4956.975215123901;4961.454240033593;3850.5956874528333;3954.367301231789;3985.4021896715726;4062.532808727034;4063.9390409143884;1234.7839128152716;1243.278898239019;1250.7123361626877;1253.7868562344424;1259.722860970585;942.7643089227693;949.9667511637059;952.9803110671515;955.2739917662626;977.7751117242932;5691.772329106872;5695.829125126253;5720.399358067343;5769.913451298234;5932.7330270137945;428.88634511854383;429.9054208074219;433.60541906865234;438.94074299969736;442.17313623523575;3909.569947305794;3923.6664883484655;3929.1945151243926;3930.7248492605813;3977.54258260298;10789.136869050457;10966;11140.832887506695;11157.051650609788;11161.665150045512;10914.508847101899;10939.453027348602;10973.286736362112;11008.174386921013;11027.889721102847;7890.092639346434;7896.763097107096;8070.0719417267765;8096.311813495919;8106.63520141595;5701.087825947829;5779.537636988946;5796.739146738322;5848.444397782204;5850.328678499416;10224.437655928996;10286.434246116236;10334.431606261727;10368;10380.948095259475;10182.23633227496;10202.846957295498;10219.437930913871;10359.84460233082;10491.475426228812;7605.847883042419;7643.885341719767;7700.845983080195;7704.884426733715;7710.537367757953;8445.87331190045;8455.74632761018;8518.531480768617;8595.914040859761;8608.526531040852;7710.691572337268;7801.882971755542;7817.335526480313;7847.843043138991;7895.960520197362;7566.129895061918;7610.9238907612435;7643.464957452925;7652.885206722015;7850.371970242481;6381.298057213796;6479.514036447191;6480.319566445455;6481.805545833632;6485.156929599122;4041.3331800253804;4803.495632958489;4827.203511420576;4907.803687852446;4970.975145124251;5065.822696205615;5134.76893539799;5140.897182056263;5184.740762961761;5197.769265881971 23:38:53 INFO - PROCESS | 10941 | |4;prototype.html;318.99681003189977;326.5330577274497;327.6723276723277;329.18362461096467;334.4464910573002;5288.973555132224;5340.946590534093;5347.144456886899;5350.759215835287;5372.313843078461;5277.340332458442;5287.735613219334;5289.629725919187;5291.761870715817;5296.894062118765;3932.862349817764;4011.7191796574252;4132.97933510332;4141.295979683461;4149.626533611974;4168.374743788426;4172.812223449937;4180.265189946169;4191.916161676764;4198.580141985807;3939.2909276330247;4122.773247471388;4123.9793801030955;4129;4171.290880550314;3998.2203470323298;4121.5878412158845;4127.195196936598;4128.60778226071;4136.358864376027;3672.6143754905615;3689.6863766579872;3706.981465092684;3716.7584107033226;3719.1074142205675;5045.142325804622;5077.314562534085;5131.717755523445;5147.510986456281;5155.350287907871;4525.7284562926;4719.669623126349;4738.336632871401;4751.263554149113;4752.1921273383605;5390;5441.891162176735;5459.672419654794;5465.754041068121;5469.917951230735;4013.7391069580385;4029.3754468057205;4042.413849991735;4045.4336392905016;4056.2090392373498;5596.2165296858475;5651.180578816049;5768.653880767168;5777.480026797609;5778.77517155898;4159.313713237282;4166.270902592095;4172.144710334374;4175.06061136248;4185.790710464526;4255;4264.658827293809;4267.8292868284925;4270.252705776476;4285.121550082225;4664.696794708333;4764.998700545779;4771.928421073687;4804.9669321096135;4860;5461;5540.426424955961;5545.750441230154;5566.944330556724;5567.220589117527;1418.5886093032905;1434.9166379383498;1440.171901156839;1455.6069861137435;1457.4898785425016;908.8091500784909;948.3361646847096;948.4735971535864;969.6945462179391;1002.819492491344;5215;5228.9477105229225;5270.367555893317;5301.496357845998;5303.443138470405;5529.861753456115;5570.498655121059;5583.162525621189;5599.580031497654;5651.180578816131;5038;5178.71517066565;5220.556252718486;5254.710990895461;5275.261463395128;2945.8379789111805;2949.8230106193696;2956.1574951138823;2980.344324248662;2987.6862929392537;5762.971185144047;5776.451237132465;5780.566457515703;5781.19063331134;5787.7138530281145;5440.6735595864375;5698.8290351289525;5724.971375143097;5757.848430313954;5764;5744.942550574608;5748.757984386093;5817.7963771267805;5829.766809327664;5830.912536311958;5375.4087050425205;5576.749046292845;5585.609007369445;5615.691136987505;5688.63023903445;5637.57718171147;5655.773769049192;5660.122680984454;5676.574256930664;5680.829575112753;5898.557608179318;5923.170756094237;5926.703664816828;5928.851778705567;5938.346781853907;3875;4013.7391069580385;4030.697697672745;4071.9389209162478;4077.592240776017;5685.545156387397;5746.454086861741;5756.798512052225;5775.9133612995;5790.681512516852;5402.243685884058;5404.432534583812;5597.832065038056;5632.394767491245;5639.492445679908;5554.805581804618;5570.832875013756;5583.776648933997;5593.664380137205;5594.957840388148;5262.763175657028;5493.5442107842255;5509.449055094458;5546.972265138648;5574;5522.392536821027;5578.525825304732;5579.6652200868075;5593.881223755347;5609.298837645294;5464.480874316933;5485.6434331768305;5519.779208831602;5606.355269144101;5610.859728506657 23:38:53 INFO - PROCESS | 10941 | |5;yui.html;783.0720596093629;802.79127426869;821.4660470694049;845.3617518773325;848.1476116502915;14059;14130.293485325745;14691.632709182275;14706.264686765646;14746.852531474695;13978.440862365493;14014.089084209545;14052.227127507984;14061.50784722535;14092.295385230724;671.8992151177338;799.3964556759638;810.704093006053;821.5974172655385;830.3398797955609;790.9841803163933;793.2424534569473;793.5159552672894;793.6983946100503;794.3446656508374;834.407570624857;835.4527784301262;835.5488036460304;835.7075023741703;835.9916400835974;704.0706267726603;824.8803923431099;830.0371568979984;844.0462277626305;845.0999685335098;612.1796792298342;612.338674231833;612.862106026145;613.5367797313;613.7821073518926;13961.441542338294;14006.159630422208;14019.369128389248;14036.228007459586;14063.64840878976;13297.800532992012;13549.170861933611;13637.659058523517;13642.931785341108;14114.435422583085;12323.260604363677;12498;12518.311492867888;12621.368931553385;12766.61700148997;4437.822487100512;4473.127740090684;4476.171908196994;4478.0596074824325;4699.483056863743;54643.180352294745;54903.450965490236;55758.16362754562;55994.16008759913;56187.43812561863;11265.549378024787;11301.26541774782;11346.546138154546;11676.124290678234;11792.29246245228;20265.29071482491;20320;20327.695084573745;20354.18583256653;20362.28731994373;7974.720884769005;8127.552984585905;8189.099199088096;8446.366522510836;8667.91332086671;4736.834210802605;4797.280407938767;4807.879803004953;4814.807407703723;4928.654994150375;3298.86804527821;3375.561177046968;3387.1532116970757;3406.1314364837303;3406.148462884279;603.8309273403454;612.1093808508698;614.923134608174;616.1927874484439;622.9906551401733;706.4948561778353;731.7987553422852;748.3003391828657;753.732424989121;773.7717373374868;9092.727218183465;9519.428834269966;9639.132478077006;9782.51087445625;9817.509124543743;592.6088781404253;599.4065874783932;614.8493619063269;736.3814395907464;738.5863916206941;4315.8489452869;4383.868483945423;4435.600795928382;4506.414166158378;4518.118966801508;64966.35033649697;65210.7176248829;65218;65494.34505654978;66085.33914660888;58641;59015.40984590185;59112.11331830026;60142.69928650329;60682;53491.19763203555;53605.1959220612;53875.46124538783;54644.45355546473;54910.72544637331;8840.557972101498;9062.22971047442;9094.499802510662;9105.635774568944;9252.259819214582;36706.4494032595;36862.8156859214;36993.81503092467;37278;37441.4383784238;49431.75284123556;50298.49701503084;50344;50804.745976269885;51404.74297628488;11047.337159770439;11122.666320010412;11171.888281117084;11241.775164496828;11290;12121.151519393557;12199.759458232082;12245.69385765363;12465.501379945063;12472.1448120801;10687.46562671879;11460.541578336773;11898.524059037542;12126.333051682243;12149.392530373269;11762.529498819951;11950.163488555718;12225;12240.877591223974;12733.745325093261;11151.88848111541;11376.08991280679;11771.352575608424;11845.76308473843;12390.194637348544;9363.29775266844;9430.430519653963;9456.95973442907;9513.89818077292;9628.18160456369;10124.291299608956;10126.657696528124;10139.720956153236;10173.84739228927;10201.642942496977 23:38:53 INFO - PROCESS | 10941 | __end_tp_report 23:38:53 INFO - PROCESS | 10941 | __start_cc_report 23:38:53 INFO - PROCESS | 10941 | _x_x_mozilla_cycle_collect,1048 23:38:53 INFO - PROCESS | 10941 | __end_cc_report 23:38:53 INFO - PROCESS | 10941 | __startTimestamp1463812733182__endTimestamp 23:38:53 INFO - PROCESS | 10941 | 23:38:53 INFO - PROCESS | 10941 | ------- Summary: start ------- 23:38:53 INFO - PROCESS | 10941 | Number of tests: 6 23:38:53 INFO - PROCESS | 10941 | 23:38:53 INFO - PROCESS | 10941 | [#0] dojo.html Cycles:165 Average:5513.93 Median:5773.80 stddev:637.68 (11.0%) stddev-sans-first:612.96 23:38:53 INFO - PROCESS | 10941 | Values: 3187.7 3198.2 3198.8 3208.5 3249.7 5383.2 5439.7 5489.6 5526.6 5608.1 5463.9 5504.5 5508.5 5565.6 5590.9 4938.2 4957.4 5113.3 5188.4 5200.1 5097.4 5117.3 5156.7 5178.9 5184.6 4883.7 4909.9 4958.1 5029.5 5053.1 5027.8 5041.6 5043.1 5054.3 5099.0 5008.8 5038.2 5046.5 5049.1 5064.7 5316.9 5421.3 5481.1 5484.5 5515.8 5798.4 5866.8 5870.0 5877.9 5882.1 5979.6 5996.5 6005.0 6005.8 6006.9 5396.3 5471.5 5525.1 5527.4 5587.9 6047.7 6061.5 6072.4 6090.9 6114.6 5857.3 5865.4 5867.5 5869.1 5886.4 5714.2 5865.3 5954.2 5956.8 6001.8 5965.5 5976.3 5979.4 5980.9 5988.9 5762.7 5935.1 5981.8 5994.6 6003.2 4437.4 4473.0 4485.8 4497.4 4505.6 3986.6 3997.3 4007.5 4021.3 4034.6 5442.9 5494.6 5610.7 5615.2 5627.5 5904.8 5969.0 5974.8 6000.8 6021.3 5617.9 5621.2 5622.8 5625.1 5626.2 4993.1 5026.7 5130.5 5131.8 5135.3 6064.7 6137.8 6144.3 6146.6 6148.0 5938.8 5957.6 5963.0 5967.6 5997.1 5919.1 6109.5 6115.5 6133.5 6145.0 5875.7 5895.2 5912.1 5915.8 5969.2 6026.5 6032.7 6036.8 6038.5 6039.1 5568.4 5791.5 5840.5 5864.4 5887.9 5853.9 5874.6 5876.4 5878.4 5880.0 5767.0 5779.8 5785.2 5789.1 5790.2 5612.7 5778.1 5861.3 5865.0 5960.4 5746.0 5766.6 5773.8 5776.7 5787.8 23:38:53 INFO - PROCESS | 10941 | 23:38:53 INFO - PROCESS | 10941 | [#1] ext.html Cycles:180 Average:56683.39 Median:23281.78 stddev:106448.52 (457.2%) stddev-sans-first:106663.89 23:38:53 INFO - PROCESS | 10941 | Values: 613.6 644.4 652.1 662.4 663.0 41671.0 42977.0 43214.0 43296.6 46266.0 44463.8 44946.8 45006.0 45055.0 45106.5 8734.7 8896.3 8918.4 8920.8 8923.6 8776.9 8786.0 8808.3 8867.1 8869.8 8841.1 8958.1 8976.1 8979.1 8986.0 8882.3 8916.9 8936.9 8942.0 8945.0 8732.1 8808.8 8827.2 8828.2 8834.4 41075.2 41309.6 41463.0 41480.8 41504.2 3470.2 3608.9 4209.7 4229.0 4241.3 31947.0 32246.0 32488.0 32503.7 33433.7 4381.0 4388.8 4404.6 4476.4 4523.9 309736.0 309892.0 310079.0 310092.0 310665.0 55680.0 56757.1 56933.1 56968.1 57134.1 60413.4 60445.4 60492.0 60617.0 60679.4 16178.7 16313.3 16322.6 16330.6 16332.3 13027.3 13105.1 13111.9 13114.5 13153.0 14637.3 16980.7 17154.0 17223.4 17229.4 5586.9 6226.6 6344.4 6566.9 6656.4 936.4 974.8 983.5 1028.2 1102.6 31596.0 32248.5 32650.2 33018.8 33188.7 1079.9 1083.6 1117.2 1121.3 1209.0 16575.3 16924.3 17032.2 17125.3 17269.7 444435.0 447903.0 448727.0 450986.0 452124.0 55279.7 55340.0 55473.2 55630.7 55733.2 1306.1 1342.7 1349.0 1365.3 1402.0 29912.5 31737.7 32713.0 32928.0 35451.7 55662.4 56219.0 56280.7 56340.7 56345.7 427539.0 436238.0 436419.0 439037.0 440395.0 25089.5 27707.2 27776.7 28214.3 28557.4 47512.0 48574.3 49079.0 49776.5 49784.5 39637.4 41077.0 41651.6 41778.6 42334.6 40288.6 40365.6 41151.8 41418.0 41464.8 39457.8 40377.8 40871.0 41365.4 41386.6 34222.0 35652.6 36418.0 36502.0 36789.6 27775.3 27835.6 28078.3 28090.3 28368.6 23:38:53 INFO - PROCESS | 10941 | 23:38:53 INFO - PROCESS | 10941 | [#2] jquery.html Cycles:180 Average:27993.58 Median:382.37 stddev:77738.55 (20330.5%) stddev-sans-first:77928.98 23:38:53 INFO - PROCESS | 10941 | Values: 381.4 383.4 386.6 400.1 414.7 14463.9 16031.0 17848.0 19155.3 19349.0 11026.9 11448.3 18681.4 19044.5 19063.3 5001.2 5146.9 5151.7 5175.8 5184.0 3863.6 5067.9 5129.1 5479.7 5727.9 5727.0 5756.7 5776.0 5786.2 5786.9 5571.1 5739.0 5752.9 5753.3 5757.9 5552.3 5559.6 5566.2 5568.8 5572.9 19897.5 22024.4 22704.9 22716.1 23087.2 2175.5 2305.4 2378.9 2461.5 2606.5 9651.0 9741.8 9751.1 9780.9 9971.2 1802.3 1901.4 1905.1 1945.2 1951.8 79067.0 81514.6 82768.0 83295.2 83374.6 10612.7 10805.8 10841.0 10955.6 11051.7 15395.6 15489.5 15498.2 15500.5 15513.5 7956.7 8322.7 8366.5 8521.9 8549.3 8139.6 8141.6 8258.1 8348.8 8349.1 4518.4 4553.8 4726.8 4738.2 4843.2 1770.4 1829.6 1913.7 1925.0 1947.8 954.6 965.8 1005.6 1029.9 1044.4 16375.9 18037.0 18226.5 18531.6 20267.5 964.3 992.2 1016.3 1030.2 1034.7 10445.4 10694.5 10758.4 10797.0 10839.5 454142.0 467511.0 474760.0 477109.0 482927.1 64224.7 90339.0 91568.5 91686.5 91701.8 995.4 1019.1 1043.0 1049.5 1054.0 16819.5 17456.6 17549.7 17621.6 17646.3 36822.6 42203.0 43052.6 43807.6 44264.8 47729.8 54403.0 55041.4 55240.7 55578.0 7882.4 7997.5 8036.6 8306.3 8339.2 13448.9 13451.5 13629.0 13642.9 13714.5 10835.9 11272.8 11362.8 11857.8 12128.0 12064.5 12118.7 12143.3 12248.6 12488.0 9772.5 11837.6 12388.8 12463.6 12549.4 7312.1 7704.7 7712.3 7979.2 8818.6 11395.7 11994.3 12026.5 12047.8 12095.6 23:38:53 INFO - PROCESS | 10941 | 23:38:53 INFO - PROCESS | 10941 | [#3] mootools.html Cycles:180 Average:5763.97 Median:2436.17 stddev:3339.16 (137.1%) stddev-sans-first:3331.85 23:38:53 INFO - PROCESS | 10941 | Values: 1322.9 1497.1 1542.2 1686.2 1847.8 8415.6 8677.5 8689.3 9044.5 9044.7 8719.6 8825.5 8890.5 8929.6 8935.3 2376.1 2394.4 2404.4 2406.0 2409.5 2358.9 2386.9 2408.9 2413.2 2415.0 2351.7 2354.0 2387.8 2387.8 2388.1 2276.5 2279.4 2305.7 2306.8 2308.2 2229.0 2278.0 2294.9 2295.1 2295.5 7423.2 7521.1 8719.9 8810.3 8812.9 5083.5 5268.3 5277.4 5358.9 5359.4 3340.1 3355.8 3426.1 3457.3 3482.0 1055.3 1057.0 1057.7 1070.6 1100.0 12365.4 12488.3 12495.4 12532.8 12545.9 4430.2 4606.7 4675.4 4766.4 4798.0 10418.7 10420.5 10422.0 10434.9 10436.7 5300.1 5314.3 5321.3 5325.6 5330.1 4863.5 4873.4 4886.2 4957.0 4961.5 3850.6 3954.4 3985.4 4062.5 4063.9 1234.8 1243.3 1250.7 1253.8 1259.7 942.8 950.0 953.0 955.3 977.8 5691.8 5695.8 5720.4 5769.9 5932.7 428.9 429.9 433.6 438.9 442.2 3909.6 3923.7 3929.2 3930.7 3977.5 10789.1 10966.0 11140.8 11157.1 11161.7 10914.5 10939.5 10973.3 11008.2 11027.9 7890.1 7896.8 8070.1 8096.3 8106.6 5701.1 5779.5 5796.7 5848.4 5850.3 10224.4 10286.4 10334.4 10368.0 10380.9 10182.2 10202.8 10219.4 10359.8 10491.5 7605.8 7643.9 7700.8 7704.9 7710.5 8445.9 8455.7 8518.5 8595.9 8608.5 7710.7 7801.9 7817.3 7847.8 7896.0 7566.1 7610.9 7643.5 7652.9 7850.4 6381.3 6479.5 6480.3 6481.8 6485.2 4041.3 4803.5 4827.2 4907.8 4971.0 5065.8 5134.8 5140.9 5184.7 5197.8 23:38:53 INFO - PROCESS | 10941 | 23:38:53 INFO - PROCESS | 10941 | [#4] prototype.html Cycles:180 Average:4670.79 Median:5299.20 stddev:1361.01 (25.7%) stddev-sans-first:1325.05 23:38:53 INFO - PROCESS | 10941 | Values: 319.0 326.5 327.7 329.2 334.4 5289.0 5340.9 5347.1 5350.8 5372.3 5277.3 5287.7 5289.6 5291.8 5296.9 3932.9 4011.7 4133.0 4141.3 4149.6 4168.4 4172.8 4180.3 4191.9 4198.6 3939.3 4122.8 4124.0 4129.0 4171.3 3998.2 4121.6 4127.2 4128.6 4136.4 3672.6 3689.7 3707.0 3716.8 3719.1 5045.1 5077.3 5131.7 5147.5 5155.4 4525.7 4719.7 4738.3 4751.3 4752.2 5390.0 5441.9 5459.7 5465.8 5469.9 4013.7 4029.4 4042.4 4045.4 4056.2 5596.2 5651.2 5768.7 5777.5 5778.8 4159.3 4166.3 4172.1 4175.1 4185.8 4255.0 4264.7 4267.8 4270.3 4285.1 4664.7 4765.0 4771.9 4805.0 4860.0 5461.0 5540.4 5545.8 5566.9 5567.2 1418.6 1434.9 1440.2 1455.6 1457.5 908.8 948.3 948.5 969.7 1002.8 5215.0 5228.9 5270.4 5301.5 5303.4 5529.9 5570.5 5583.2 5599.6 5651.2 5038.0 5178.7 5220.6 5254.7 5275.3 2945.8 2949.8 2956.2 2980.3 2987.7 5763.0 5776.5 5780.6 5781.2 5787.7 5440.7 5698.8 5725.0 5757.8 5764.0 5744.9 5748.8 5817.8 5829.8 5830.9 5375.4 5576.7 5585.6 5615.7 5688.6 5637.6 5655.8 5660.1 5676.6 5680.8 5898.6 5923.2 5926.7 5928.9 5938.3 3875.0 4013.7 4030.7 4071.9 4077.6 5685.5 5746.5 5756.8 5775.9 5790.7 5402.2 5404.4 5597.8 5632.4 5639.5 5554.8 5570.8 5583.8 5593.7 5595.0 5262.8 5493.5 5509.4 5547.0 5574.0 5522.4 5578.5 5579.7 5593.9 5609.3 5464.5 5485.6 5519.8 5606.4 5610.9 23:38:53 INFO - PROCESS | 10941 | 23:38:53 INFO - PROCESS | 10941 | [#5] yui.html Cycles:180 Average:15331.71 Median:49865.12 stddev:18300.91 (36.7%) stddev-sans-first:18319.64 23:38:53 INFO - PROCESS | 10941 | Values: 783.1 802.8 821.5 845.4 848.1 14059.0 14130.3 14691.6 14706.3 14746.9 13978.4 14014.1 14052.2 14061.5 14092.3 671.9 799.4 810.7 821.6 830.3 791.0 793.2 793.5 793.7 794.3 834.4 835.5 835.5 835.7 836.0 704.1 824.9 830.0 844.0 845.1 612.2 612.3 612.9 613.5 613.8 13961.4 14006.2 14019.4 14036.2 14063.6 13297.8 13549.2 13637.7 13642.9 14114.4 12323.3 12498.0 12518.3 12621.4 12766.6 4437.8 4473.1 4476.2 4478.1 4699.5 54643.2 54903.5 55758.2 55994.2 56187.4 11265.5 11301.3 11346.5 11676.1 11792.3 20265.3 20320.0 20327.7 20354.2 20362.3 7974.7 8127.6 8189.1 8446.4 8667.9 4736.8 4797.3 4807.9 4814.8 4928.7 3298.9 3375.6 3387.2 3406.1 3406.1 603.8 612.1 614.9 616.2 623.0 706.5 731.8 748.3 753.7 773.8 9092.7 9519.4 9639.1 9782.5 9817.5 592.6 599.4 614.8 736.4 738.6 4315.8 4383.9 4435.6 4506.4 4518.1 64966.4 65210.7 65218.0 65494.3 66085.3 58641.0 59015.4 59112.1 60142.7 60682.0 53491.2 53605.2 53875.5 54644.5 54910.7 8840.6 9062.2 9094.5 9105.6 9252.3 36706.4 36862.8 36993.8 37278.0 37441.4 49431.8 50298.5 50344.0 50804.7 51404.7 11047.3 11122.7 11171.9 11241.8 11290.0 12121.2 12199.8 12245.7 12465.5 12472.1 10687.5 11460.5 11898.5 12126.3 12149.4 11762.5 11950.2 12225.0 12240.9 12733.7 11151.9 11376.1 11771.4 11845.8 12390.2 9363.3 9430.4 9457.0 9513.9 9628.2 10124.3 10126.7 10139.7 10173.8 10201.6 23:38:53 INFO - PROCESS | 10941 | -------- Summary: end -------- 23:38:53 INFO - PROCESS | 10941 | 23:38:53 INFO - TEST-INFO | 10941: exit 0 23:38:53 INFO - TEST-OK | dromaeo_css | took 1113528ms 23:38:53 INFO - TEST-START | kraken 23:38:53 INFO - operating with platform_type : linux_ 23:38:53 INFO - Initialising browser for kraken test... 23:38:53 INFO - TEST-INFO | started process 11121 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOPU6Wi/profile http://localhost:50332/getInfo.html) 23:38:57 INFO - PROCESS | 11121 | __metrics Screen width/height:1600/1200 23:38:57 INFO - PROCESS | 11121 | colorDepth:24 23:38:57 INFO - PROCESS | 11121 | Browser inner width/height: 1024/697 23:38:57 INFO - PROCESS | 11121 | __metrics 23:38:58 ERROR - PROCESS | 11121 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 23:38:59 INFO - TEST-INFO | 11121: exit 0 23:38:59 INFO - Browser initialized. 23:38:59 INFO - Running cycle 1/1 for kraken test... 23:38:59 INFO - TEST-INFO | started process 11203 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOPU6Wi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:39:11 INFO - PROCESS | 11203 | RSS: Main: 153788416 23:39:11 INFO - PROCESS | 11203 | 23:39:14 INFO - PROCESS | 11203 | Cycle 1(1): loaded http://localhost:50332/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50332/tests/kraken/driver.html?testName=audio-beat-detection) 23:39:15 INFO - PROCESS | 11203 | RSS: Main: 209858560 23:39:15 INFO - PROCESS | 11203 | 23:39:17 INFO - PROCESS | 11203 | Cycle 1(1): loaded http://localhost:50332/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50332/tests/kraken/driver.html?testName=audio-dft) 23:39:18 INFO - PROCESS | 11203 | RSS: Main: 197660672 23:39:18 INFO - PROCESS | 11203 | 23:39:21 INFO - PROCESS | 11203 | Cycle 1(1): loaded http://localhost:50332/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50332/tests/kraken/driver.html?testName=audio-fft) 23:39:22 INFO - PROCESS | 11203 | RSS: Main: 265920512 23:39:22 INFO - PROCESS | 11203 | 23:39:24 INFO - PROCESS | 11203 | Cycle 1(1): loaded http://localhost:50332/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50332/tests/kraken/driver.html?testName=audio-oscillator) 23:39:24 INFO - PROCESS | 11203 | RSS: Main: 174514176 23:39:24 INFO - PROCESS | 11203 | 23:39:27 INFO - PROCESS | 11203 | Cycle 1(1): loaded http://localhost:50332/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50332/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:39:27 INFO - PROCESS | 11203 | RSS: Main: 179752960 23:39:27 INFO - PROCESS | 11203 | 23:39:31 INFO - PROCESS | 11203 | Cycle 1(1): loaded http://localhost:50332/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50332/tests/kraken/driver.html?testName=imaging-darkroom) 23:39:31 INFO - PROCESS | 11203 | RSS: Main: 215678976 23:39:31 INFO - PROCESS | 11203 | 23:39:35 INFO - PROCESS | 11203 | Cycle 1(1): loaded http://localhost:50332/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50332/tests/kraken/driver.html?testName=imaging-desaturate) 23:39:35 INFO - PROCESS | 11203 | RSS: Main: 213331968 23:39:35 INFO - PROCESS | 11203 | 23:39:38 INFO - PROCESS | 11203 | Cycle 1(1): loaded http://localhost:50332/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50332/tests/kraken/driver.html?testName=json-parse-financial) 23:39:39 INFO - PROCESS | 11203 | RSS: Main: 214544384 23:39:39 INFO - PROCESS | 11203 | 23:39:41 INFO - PROCESS | 11203 | Cycle 1(1): loaded http://localhost:50332/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50332/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:39:41 INFO - PROCESS | 11203 | RSS: Main: 203595776 23:39:41 INFO - PROCESS | 11203 | 23:39:43 INFO - PROCESS | 11203 | Cycle 1(1): loaded http://localhost:50332/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50332/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:39:44 INFO - PROCESS | 11203 | RSS: Main: 206397440 23:39:44 INFO - PROCESS | 11203 | 23:39:46 INFO - PROCESS | 11203 | Cycle 1(1): loaded http://localhost:50332/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50332/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:39:46 INFO - PROCESS | 11203 | RSS: Main: 216051712 23:39:46 INFO - PROCESS | 11203 | 23:39:49 INFO - PROCESS | 11203 | Cycle 1(1): loaded http://localhost:50332/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50332/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:39:50 INFO - PROCESS | 11203 | RSS: Main: 225026048 23:39:50 INFO - PROCESS | 11203 | 23:39:52 INFO - PROCESS | 11203 | Cycle 1(1): loaded http://localhost:50332/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50332/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:39:53 INFO - PROCESS | 11203 | RSS: Main: 221360128 23:39:53 INFO - PROCESS | 11203 | 23:39:55 INFO - PROCESS | 11203 | Cycle 1(1): loaded http://localhost:50332/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50332/tests/kraken/driver.html?testName=ai-astar) 23:39:55 INFO - PROCESS | 11203 | RSS: Main: 267886592 23:39:55 INFO - PROCESS | 11203 | 23:39:55 INFO - PROCESS | 11203 | __start_tp_report 23:39:55 INFO - PROCESS | 11203 | _x_x_mozilla_page_load 23:39:55 INFO - PROCESS | 11203 | _x_x_mozilla_page_load_details 23:39:55 INFO - PROCESS | 11203 | |i|pagename|runs| 23:39:55 INFO - PROCESS | 11203 | |0;ai-astar;110;105;110;111;112;109;110;109;110;109 23:39:55 INFO - PROCESS | 11203 | |1;audio-beat-detection;151;123;126;217;134;131;161;130;155;123 23:39:55 INFO - PROCESS | 11203 | |2;audio-dft;161;160;161;161;161;161;161;162;162;161 23:39:55 INFO - PROCESS | 11203 | |3;audio-fft;74;77;75;146;75;76;75;75;127;76 23:39:55 INFO - PROCESS | 11203 | |4;audio-oscillator;91;92;152;92;93;91;131;91;91;139 23:39:55 INFO - PROCESS | 11203 | |5;imaging-gaussian-blur;117;117;116;116;116;117;117;119;117;116 23:39:55 INFO - PROCESS | 11203 | |6;imaging-darkroom;147;148;146;149;149;148;147;148;148;149 23:39:55 INFO - PROCESS | 11203 | |7;imaging-desaturate;95;94;96;95;95;95;94;96;95;95 23:39:55 INFO - PROCESS | 11203 | |8;json-parse-financial;55;54;55;56;55;55;55;106;53;53 23:39:55 INFO - PROCESS | 11203 | |9;json-stringify-tinderbox;62;63;63;62;63;62;62;62;62;63 23:39:55 INFO - PROCESS | 11203 | |10;stanford-crypto-aes;78;77;78;78;84;80;79;86;79;78 23:39:55 INFO - PROCESS | 11203 | |11;stanford-crypto-ccm;119;122;119;125;125;116;118;117;190;130 23:39:55 INFO - PROCESS | 11203 | |12;stanford-crypto-pbkdf2;159;164;159;158;157;162;158;158;156;157 23:39:55 INFO - PROCESS | 11203 | |13;stanford-crypto-sha256-iterative;62;63;63;65;63;61;63;64;60;61 23:39:55 INFO - PROCESS | 11203 | __end_tp_report 23:39:55 INFO - PROCESS | 11203 | __start_cc_report 23:39:55 INFO - PROCESS | 11203 | _x_x_mozilla_cycle_collect,1899 23:39:55 INFO - PROCESS | 11203 | __end_cc_report 23:39:55 INFO - PROCESS | 11203 | __startTimestamp1463812795416__endTimestamp 23:39:55 INFO - PROCESS | 11203 | 23:39:55 INFO - PROCESS | 11203 | ------- Summary: start ------- 23:39:55 INFO - PROCESS | 11203 | Number of tests: 14 23:39:55 INFO - PROCESS | 11203 | 23:39:55 INFO - PROCESS | 11203 | [#0] ai-astar Cycles:10 Average:109.50 Median:110.00 stddev:1.84 (1.7%) stddev-sans-first:1.94 23:39:55 INFO - PROCESS | 11203 | Values: 110.0 105.0 110.0 111.0 112.0 109.0 110.0 109.0 110.0 109.0 23:39:55 INFO - PROCESS | 11203 | 23:39:55 INFO - PROCESS | 11203 | [#1] audio-beat-detection Cycles:10 Average:145.10 Median:142.50 stddev:28.77 (20.2%) stddev-sans-first:30.43 23:39:55 INFO - PROCESS | 11203 | Values: 151.0 123.0 126.0 217.0 134.0 131.0 161.0 130.0 155.0 123.0 23:39:55 INFO - PROCESS | 11203 | 23:39:55 INFO - PROCESS | 11203 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 23:39:55 INFO - PROCESS | 11203 | Values: 161.0 160.0 161.0 161.0 161.0 161.0 161.0 162.0 162.0 161.0 23:39:55 INFO - PROCESS | 11203 | 23:39:55 INFO - PROCESS | 11203 | [#3] audio-fft Cycles:10 Average:87.60 Median:75.00 stddev:26.17 (34.9%) stddev-sans-first:27.29 23:39:55 INFO - PROCESS | 11203 | Values: 74.0 77.0 75.0 146.0 75.0 76.0 75.0 75.0 127.0 76.0 23:39:55 INFO - PROCESS | 11203 | 23:39:55 INFO - PROCESS | 11203 | [#4] audio-oscillator Cycles:10 Average:106.30 Median:91.00 stddev:24.24 (26.6%) stddev-sans-first:25.07 23:39:55 INFO - PROCESS | 11203 | Values: 91.0 92.0 152.0 92.0 93.0 91.0 131.0 91.0 91.0 139.0 23:39:55 INFO - PROCESS | 11203 | 23:39:55 INFO - PROCESS | 11203 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.92 (0.8%) stddev-sans-first:0.97 23:39:55 INFO - PROCESS | 11203 | Values: 117.0 117.0 116.0 116.0 116.0 117.0 117.0 119.0 117.0 116.0 23:39:55 INFO - PROCESS | 11203 | 23:39:55 INFO - PROCESS | 11203 | [#6] imaging-darkroom Cycles:10 Average:147.90 Median:148.00 stddev:0.99 (0.7%) stddev-sans-first:1.00 23:39:55 INFO - PROCESS | 11203 | Values: 147.0 148.0 146.0 149.0 149.0 148.0 147.0 148.0 148.0 149.0 23:39:55 INFO - PROCESS | 11203 | 23:39:55 INFO - PROCESS | 11203 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 23:39:55 INFO - PROCESS | 11203 | Values: 95.0 94.0 96.0 95.0 95.0 95.0 94.0 96.0 95.0 95.0 23:39:55 INFO - PROCESS | 11203 | 23:39:55 INFO - PROCESS | 11203 | [#8] json-parse-financial Cycles:10 Average:59.70 Median:55.00 stddev:16.30 (29.6%) stddev-sans-first:17.20 23:39:55 INFO - PROCESS | 11203 | Values: 55.0 54.0 55.0 56.0 55.0 55.0 55.0 106.0 53.0 53.0 23:39:55 INFO - PROCESS | 11203 | 23:39:55 INFO - PROCESS | 11203 | [#9] json-stringify-tinderbox Cycles:10 Average:62.40 Median:62.50 stddev:0.52 (0.8%) stddev-sans-first:0.53 23:39:55 INFO - PROCESS | 11203 | Values: 62.0 63.0 63.0 62.0 63.0 62.0 62.0 62.0 62.0 63.0 23:39:55 INFO - PROCESS | 11203 | 23:39:55 INFO - PROCESS | 11203 | [#10] stanford-crypto-aes Cycles:10 Average:79.70 Median:79.00 stddev:2.95 (3.7%) stddev-sans-first:3.06 23:39:55 INFO - PROCESS | 11203 | Values: 78.0 77.0 78.0 78.0 84.0 80.0 79.0 86.0 79.0 78.0 23:39:55 INFO - PROCESS | 11203 | 23:39:55 INFO - PROCESS | 11203 | [#11] stanford-crypto-ccm Cycles:10 Average:128.10 Median:123.50 stddev:22.18 (18.0%) stddev-sans-first:23.28 23:39:55 INFO - PROCESS | 11203 | Values: 119.0 122.0 119.0 125.0 125.0 116.0 118.0 117.0 190.0 130.0 23:39:55 INFO - PROCESS | 11203 | 23:39:55 INFO - PROCESS | 11203 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:158.80 Median:158.50 stddev:2.44 (1.5%) stddev-sans-first:2.59 23:39:55 INFO - PROCESS | 11203 | Values: 159.0 164.0 159.0 158.0 157.0 162.0 158.0 158.0 156.0 157.0 23:39:55 INFO - PROCESS | 11203 | 23:39:55 INFO - PROCESS | 11203 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.50 Median:63.00 stddev:1.51 (2.4%) stddev-sans-first:1.59 23:39:55 INFO - PROCESS | 11203 | Values: 62.0 63.0 63.0 65.0 63.0 61.0 63.0 64.0 60.0 61.0 23:39:55 INFO - PROCESS | 11203 | -------- Summary: end -------- 23:39:55 INFO - PROCESS | 11203 | 23:39:56 INFO - TEST-INFO | 11203: exit 0 23:39:56 INFO - TEST-OK | kraken | took 62518ms 23:39:56 INFO - SUITE-END | took 1176s 23:39:56 INFO - Completed test suite (00:19:36) 23:39:56 INFO - javascript benchmark 23:39:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3187.6971687689675, 3198.1844629619445, 3198.7600929930245, 3208.4866421372585, 3249.658785827488, 5383.246345511631, 5439.673619582828, 5489.58828087893, 5526.585506087044, 5608.074667679838, 5463.9180412293845, 5504.504594586487, 5508.504234618884, 5565.582581306408, 5590.86022849429, 4938.2345736410825, 4957.405111386621, 5113.284140220372, 5188.37739471264, 5200.115980283361, 5097.439281679012, 5117.309163262968, 5156.690598564098, 5178.8705282368055, 5184.611154163434, 4883.706977581339, 4909.8772530686665, 4958.107540642683, 5029.471905449912, 5053.140966035764, 5027.773750181214, 5041.621878359138, 5043.117454445494, 5054.342935418409, 5099.031184075051, 5008.774605142778, 5038.219076043182, 5046.4701206373165, 5049.141645920203, 5064.746762661852, 5316.893662126736, 5421.322334708161, 5481.095619222823, 5484.533814625762, 5515.779368825242, 5798.42015798421, 5866.823995280106, 5869.970650146722, 5877.941220587825, 5882.147088672119, 5979.611325263887, 5996.4603185713495, 6005.039193729026, 6005.819825405245, 6006.879862402728, 5396.325459317585, 5471.534919531845, 5525.088360420566, 5527.419620939824, 5587.8882422351735, 6047.667378294236, 6061.543373710356, 6072.4222871560005, 6090.878182436415, 6114.602550834182, 5857.26784151981, 5865.442782935615, 5867.501262392659, 5869.119632055141, 5886.381929897385, 5714.171445140431, 5865.266841644794, 5954.196183515257, 5956.76172953077, 6001.759929602855, 5965.522758179335, 5976.282846058501, 5979.372165922602, 5980.910286345708, 5988.850278743066, 5762.711864406763, 5935.0503919372695, 5981.790637327673, 5994.6403215807195, 6003.159557661931, 4437.400950871655, 4472.9776351118035, 4485.775711214426, 4497.437820272466, 4505.639548836085, 3986.6412022918075, 3997.2605068062503, 4007.458993035961, 4021.3163762160502, 4034.576369481162, 5442.863928401822, 5494.587905907073, 5610.719464026783, 5615.157726341082, 5627.493525582717, 5904.822855314347, 5969.015112506475, 5974.820755377345, 6000.759969601254, 6021.337652858182, 5617.915731264035, 5621.241132447068, 5622.7750889963945, 5625.09998400254, 5626.240457538262, 4993.1262029144755, 5026.723530205873, 5130.461301563357, 5131.846044618593, 5135.281060651511, 6064.727087281083, 6137.775380989496, 6144.324124346318, 6146.6004709693725, 6148.047052706837, 5938.8218353449465, 5957.553183511167, 5963.0459126539545, 5967.6419414835245, 5997.13041608973, 5919.112133180058, 6109.541784366279, 6115.483736156635, 6133.539984501091, 6145.0, 5875.706214689163, 5895.233619629421, 5912.054071348563, 5915.822525324247, 5969.194158788508, 6026.487748541423, 6032.728527216198, 6036.758529658764, 6038.5169186465855, 6039.124326972652, 5568.359638641446, 5791.478766910998, 5840.532757379485, 5864.354920958605, 5887.882242355044, 5853.8536536585225, 5874.559408044328, 5876.412358764261, 5878.382769809279, 5880.0, 5767.048437007847, 5779.797707080232, 5785.161151633074, 5789.102689083199, 5790.1893734877995, 5612.663240205601, 5778.133280008033, 5861.267341582302, 5865.0, 5960.403959604005, 5746.0, 5766.59633825645, 5773.797917072882, 5776.7400466979925, 5787.768489260382], "value": 5470.164262574381, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [613.5643692977985, 644.4361183964031, 652.057776512939, 662.4336192555367, 662.9966850165748, 41671.0, 42977.0, 43214.0, 43296.567034329644, 46266.0, 44463.77768111163, 44946.7752661237, 45006.0, 45055.0, 45106.54893451065, 8734.737957861258, 8896.332775041865, 8918.375713700043, 8920.776980575472, 8923.598438070303, 8776.868346974801, 8786.0, 8808.295336373074, 8867.068957759438, 8869.822603547926, 8841.071687472819, 8958.059403762609, 8976.057513961037, 8979.102089791035, 8985.95507022464, 8882.333824963122, 8916.910830891673, 8936.910630893673, 8942.016378198394, 8944.955275223614, 8732.12678732128, 8808.823823523559, 8827.16141966512, 8828.24959878411, 8834.425762325429, 41075.178496430206, 41309.586904130876, 41463.0, 41480.79259603713, 41504.1699166015, 3470.16715988161, 3608.891733248007, 4209.663226941837, 4228.978855105735, 4241.300185469394, 31947.0, 32246.0, 32488.0, 32503.6749632503, 33433.6656633433, 4381.0, 4388.758618275993, 4404.625606823424, 4476.35092911526, 4523.9347711730725, 309736.0, 309892.0, 310079.0, 310092.0, 310665.0, 55680.0, 56757.14864277039, 56933.14600280999, 56968.14547781787, 57134.142987855215, 60413.39586604077, 60445.395546044856, 60492.0, 60617.0, 60679.39320606826, 16178.676426471404, 16313.347466101222, 16322.591935201715, 16330.591735206477, 16332.265048072864, 13027.283499407624, 13105.14816536922, 13111.868881311064, 13114.540991065458, 13153.0, 14637.341319640642, 16980.660386792195, 17154.0, 17223.39718109885, 17229.3969711062, 5586.888262234814, 6226.59527130735, 6344.397282258178, 6566.868662626721, 6656.434203092791, 936.4381371177375, 974.8001659659752, 983.5426526664992, 1028.1723212813756, 1102.6251074634663, 31596.0, 32248.54881530337, 32650.18374540608, 33018.834905825795, 33188.668113318556, 1079.9298045627008, 1083.5719890643309, 1117.2235296468875, 1121.2599684208383, 1209.0086129374006, 16575.336986520644, 16924.323027079026, 17032.2335494903, 17125.314987400365, 17269.740953885703, 444435.0, 447903.0, 448727.0, 450986.0, 452124.0, 55279.72360138254, 55340.0, 55473.167902481495, 55630.72184639051, 55733.16400254, 1306.1314226039767, 1342.744878473087, 1349.001738713345, 1365.2900491744238, 1401.985980140206, 29912.466193754226, 31737.682623173474, 32713.0, 32928.0, 35451.68432234904, 55662.44337556572, 56219.0, 56280.718596406754, 56340.71829640825, 56345.71827140838, 427539.0, 436238.0, 436419.0, 439037.0, 440395.0, 25089.49821003606, 27707.168784936483, 27776.722232777414, 28214.294642634097, 28557.42885142244, 47512.0, 48574.271385928536, 49079.0, 49776.50223497719, 49784.502154977985, 39637.40543891901, 41077.0, 41651.583484164774, 41778.582214178685, 42334.57665423306, 40288.59711402848, 40365.59634403618, 41151.794241028605, 41418.0, 41464.79267603643, 39457.80271098626, 40377.79811100926, 40871.0, 41365.37951930783, 41386.58613413948, 34222.0, 35652.64347356493, 36418.0, 36502.0, 36789.632103679694, 27775.305617358918, 27835.582466263426, 28078.2980425491, 28090.2977425566, 28368.574471382533], "value": 19933.928551778867, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.39548815128023, 383.35213489203227, 386.61531775883003, 400.0638505896203, 414.6848395219632, 14463.855361446382, 16031.0, 17848.0, 19155.329563465264, 19349.0, 11026.889731102687, 11448.255863368897, 18681.439556813282, 19044.5238869028, 19063.332783352584, 5001.249812528114, 5146.871328216787, 5151.665141765792, 5175.767090480937, 5183.974080129594, 3863.5750067492554, 5067.898642027158, 5129.051125541787, 5479.698616576086, 5727.9427205727825, 5727.026405511073, 5756.683382413966, 5775.971120144393, 5786.218860453848, 5786.855328616797, 5571.0807716726695, 5739.024365857773, 5752.942470575304, 5753.252077229975, 5757.913631295534, 5552.333719953632, 5559.610827242094, 5566.248556444869, 5568.777248910039, 5572.944270557283, 19897.50256243591, 22024.44938876525, 22704.88647556768, 22716.091356345725, 23087.191948281896, 2175.5431359414542, 2305.4236440889777, 2378.940526486834, 2461.4707837814776, 2606.4787042591556, 9651.0, 9741.805163896754, 9751.073648003428, 9780.853287200698, 9971.152452041586, 1802.306112146833, 1901.4485799118238, 1905.0950798370802, 1945.202466988528, 1951.834094102003, 79067.0, 81514.59242703748, 82768.0, 83295.16704832934, 83374.58312708458, 10612.681619551426, 10805.83791243132, 10841.0, 10955.561777528968, 11051.72370690723, 15395.61510962235, 15489.457868974532, 15498.225088745517, 15500.457483988232, 15513.534593962198, 7956.7215147470715, 8322.6670933162, 8366.456180348257, 8521.914780852112, 8549.273311768446, 8139.593020349077, 8141.592920353959, 8258.091609922903, 8348.833023339499, 8349.123342049119, 4518.4126063612175, 4553.795079221443, 4726.834560790356, 4738.218193998021, 4843.24929635907, 1770.4069136839303, 1829.606634573573, 1913.7320775091498, 1925.0, 1947.805219478041, 954.6181527388961, 965.7537327981461, 1005.5776573839005, 1029.902159294866, 1044.4464433849919, 16375.918120409322, 18037.007964561813, 18226.544336391697, 18531.629367412577, 20267.493312667008, 964.3056998960737, 992.1963209799941, 1016.2581315639625, 1030.2427715629008, 1034.6637342863598, 10445.425501597334, 10694.518746656418, 10758.354498730101, 10797.028267455968, 10839.536662550454, 454142.0, 467511.0, 474760.0, 477109.0, 482927.1219234237, 64224.67887660625, 90339.0, 91568.54215728879, 91686.54156729307, 91701.79043733438, 995.447102520071, 1019.0879163148941, 1042.95828166874, 1049.459528342904, 1053.984190237147, 16819.495415137564, 17456.563585910455, 17549.736753948957, 17621.606532207432, 17646.294148233927, 36822.631773682995, 42203.0, 43052.56947430611, 43807.561924380345, 44264.77867610641, 47729.761351193025, 54403.0, 55041.44958550363, 55240.723796380764, 55578.0, 7882.448228623941, 7997.520148791091, 8036.6383512740895, 8306.252437280673, 8339.20777526134, 13448.93275533616, 13451.529196478075, 13629.0, 13642.93178534101, 13714.451421943011, 10835.891641083488, 11272.785417333078, 11362.829557556477, 11857.762844742885, 12128.0, 12064.51741930348, 12118.697032574257, 12143.271403715453, 12248.571300004458, 12488.0, 9772.511374431393, 11837.638671552882, 12388.752224955631, 12463.572745474903, 12549.372531373212, 7312.122545294386, 7704.6918123274445, 7712.267334603203, 7979.241972012649, 8818.5590720465, 11395.658130256106, 11994.280343179436, 12026.458809353426, 12047.819282710941, 12095.57665481704], "value": 9010.178890026582, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1322.9404676789543, 1497.0568541818657, 1542.1595230599323, 1686.2327640923381, 1847.8244566766152, 8415.621297041633, 8677.479351238922, 8689.261412779913, 9044.547772611377, 9044.728658140266, 8719.5640217989, 8825.51459669718, 8890.466572005667, 8929.642814287437, 8935.329850261223, 2376.1327115602853, 2394.4373072328076, 2404.3989002749313, 2405.9639105413435, 2409.494006258688, 2358.929232123039, 2386.916457923973, 2408.8675122868235, 2413.227767114524, 2415.0219161239725, 2351.6942797436395, 2353.976460235393, 2387.7612238776155, 2387.7970372518357, 2388.0567175965484, 2276.4877902471976, 2279.430142464384, 2305.688732021181, 2306.8039216666602, 2308.215206829669, 2228.9554208915893, 2277.9772202278095, 2294.8967296471533, 2295.1049090854412, 2295.5064661097776, 7423.183449820515, 7521.05986751656, 8719.912800871973, 8810.251128654008, 8812.8678069829, 5083.466236045199, 5268.315119034501, 5277.419483856773, 5358.892822143574, 5359.356877174725, 3340.0981734931433, 3355.832208389595, 3426.126337783874, 3457.2566898116775, 3481.9651803481893, 1055.2771351624162, 1056.973575660607, 1057.7197042783669, 1070.6413351527249, 1099.9890001099966, 12365.443555039954, 12488.25070495773, 12495.375231238402, 12532.81200781989, 12545.874541254561, 4430.158269928767, 4606.723596584216, 4675.368825208553, 4766.428028636586, 4797.992421591505, 10418.68743937683, 10420.531076101593, 10422.0, 10434.895651043544, 10436.739081523023, 5300.09898317287, 5314.28257185275, 5321.281626980308, 5325.627206095536, 5330.093884039723, 4863.489333619989, 4873.439554451213, 4886.242632391985, 4956.975215123901, 4961.454240033593, 3850.5956874528333, 3954.367301231789, 3985.4021896715726, 4062.532808727034, 4063.9390409143884, 1234.7839128152716, 1243.278898239019, 1250.7123361626877, 1253.7868562344424, 1259.722860970585, 942.7643089227693, 949.9667511637059, 952.9803110671515, 955.2739917662626, 977.7751117242932, 5691.772329106872, 5695.829125126253, 5720.399358067343, 5769.913451298234, 5932.7330270137945, 428.88634511854383, 429.9054208074219, 433.60541906865234, 438.94074299969736, 442.17313623523575, 3909.569947305794, 3923.6664883484655, 3929.1945151243926, 3930.7248492605813, 3977.54258260298, 10789.136869050457, 10966.0, 11140.832887506695, 11157.051650609788, 11161.665150045512, 10914.508847101899, 10939.453027348602, 10973.286736362112, 11008.174386921013, 11027.889721102847, 7890.092639346434, 7896.763097107096, 8070.0719417267765, 8096.311813495919, 8106.63520141595, 5701.087825947829, 5779.537636988946, 5796.739146738322, 5848.444397782204, 5850.328678499416, 10224.437655928996, 10286.434246116236, 10334.431606261727, 10368.0, 10380.948095259475, 10182.23633227496, 10202.846957295498, 10219.437930913871, 10359.84460233082, 10491.475426228812, 7605.847883042419, 7643.885341719767, 7700.845983080195, 7704.884426733715, 7710.537367757953, 8445.87331190045, 8455.74632761018, 8518.531480768617, 8595.914040859761, 8608.526531040852, 7710.691572337268, 7801.882971755542, 7817.335526480313, 7847.843043138991, 7895.960520197362, 7566.129895061918, 7610.9238907612435, 7643.464957452925, 7652.885206722015, 7850.371970242481, 6381.298057213796, 6479.514036447191, 6480.319566445455, 6481.805545833632, 6485.156929599122, 4041.3331800253804, 4803.495632958489, 4827.203511420576, 4907.803687852446, 4970.975145124251, 5065.822696205615, 5134.76893539799, 5140.897182056263, 5184.740762961761, 5197.769265881971], "value": 4518.098644836527, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [318.99681003189977, 326.5330577274497, 327.6723276723277, 329.18362461096467, 334.4464910573002, 5288.973555132224, 5340.946590534093, 5347.144456886899, 5350.759215835287, 5372.313843078461, 5277.340332458442, 5287.735613219334, 5289.629725919187, 5291.761870715817, 5296.894062118765, 3932.862349817764, 4011.7191796574252, 4132.97933510332, 4141.295979683461, 4149.626533611974, 4168.374743788426, 4172.812223449937, 4180.265189946169, 4191.916161676764, 4198.580141985807, 3939.2909276330247, 4122.773247471388, 4123.9793801030955, 4129.0, 4171.290880550314, 3998.2203470323298, 4121.5878412158845, 4127.195196936598, 4128.60778226071, 4136.358864376027, 3672.6143754905615, 3689.6863766579872, 3706.981465092684, 3716.7584107033226, 3719.1074142205675, 5045.142325804622, 5077.314562534085, 5131.717755523445, 5147.510986456281, 5155.350287907871, 4525.7284562926, 4719.669623126349, 4738.336632871401, 4751.263554149113, 4752.1921273383605, 5390.0, 5441.891162176735, 5459.672419654794, 5465.754041068121, 5469.917951230735, 4013.7391069580385, 4029.3754468057205, 4042.413849991735, 4045.4336392905016, 4056.2090392373498, 5596.2165296858475, 5651.180578816049, 5768.653880767168, 5777.480026797609, 5778.77517155898, 4159.313713237282, 4166.270902592095, 4172.144710334374, 4175.06061136248, 4185.790710464526, 4255.0, 4264.658827293809, 4267.8292868284925, 4270.252705776476, 4285.121550082225, 4664.696794708333, 4764.998700545779, 4771.928421073687, 4804.9669321096135, 4860.0, 5461.0, 5540.426424955961, 5545.750441230154, 5566.944330556724, 5567.220589117527, 1418.5886093032905, 1434.9166379383498, 1440.171901156839, 1455.6069861137435, 1457.4898785425016, 908.8091500784909, 948.3361646847096, 948.4735971535864, 969.6945462179391, 1002.819492491344, 5215.0, 5228.9477105229225, 5270.367555893317, 5301.496357845998, 5303.443138470405, 5529.861753456115, 5570.498655121059, 5583.162525621189, 5599.580031497654, 5651.180578816131, 5038.0, 5178.71517066565, 5220.556252718486, 5254.710990895461, 5275.261463395128, 2945.8379789111805, 2949.8230106193696, 2956.1574951138823, 2980.344324248662, 2987.6862929392537, 5762.971185144047, 5776.451237132465, 5780.566457515703, 5781.19063331134, 5787.7138530281145, 5440.6735595864375, 5698.8290351289525, 5724.971375143097, 5757.848430313954, 5764.0, 5744.942550574608, 5748.757984386093, 5817.7963771267805, 5829.766809327664, 5830.912536311958, 5375.4087050425205, 5576.749046292845, 5585.609007369445, 5615.691136987505, 5688.63023903445, 5637.57718171147, 5655.773769049192, 5660.122680984454, 5676.574256930664, 5680.829575112753, 5898.557608179318, 5923.170756094237, 5926.703664816828, 5928.851778705567, 5938.346781853907, 3875.0, 4013.7391069580385, 4030.697697672745, 4071.9389209162478, 4077.592240776017, 5685.545156387397, 5746.454086861741, 5756.798512052225, 5775.9133612995, 5790.681512516852, 5402.243685884058, 5404.432534583812, 5597.832065038056, 5632.394767491245, 5639.492445679908, 5554.805581804618, 5570.832875013756, 5583.776648933997, 5593.664380137205, 5594.957840388148, 5262.763175657028, 5493.5442107842255, 5509.449055094458, 5546.972265138648, 5574.0, 5522.392536821027, 5578.525825304732, 5579.6652200868075, 5593.881223755347, 5609.298837645294, 5464.480874316933, 5485.6434331768305, 5519.779208831602, 5606.355269144101, 5610.859728506657], "value": 4236.162915358198, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [783.0720596093629, 802.79127426869, 821.4660470694049, 845.3617518773325, 848.1476116502915, 14059.0, 14130.293485325745, 14691.632709182275, 14706.264686765646, 14746.852531474695, 13978.440862365493, 14014.089084209545, 14052.227127507984, 14061.50784722535, 14092.295385230724, 671.8992151177338, 799.3964556759638, 810.704093006053, 821.5974172655385, 830.3398797955609, 790.9841803163933, 793.2424534569473, 793.5159552672894, 793.6983946100503, 794.3446656508374, 834.407570624857, 835.4527784301262, 835.5488036460304, 835.7075023741703, 835.9916400835974, 704.0706267726603, 824.8803923431099, 830.0371568979984, 844.0462277626305, 845.0999685335098, 612.1796792298342, 612.338674231833, 612.862106026145, 613.5367797313, 613.7821073518926, 13961.441542338294, 14006.159630422208, 14019.369128389248, 14036.228007459586, 14063.64840878976, 13297.800532992012, 13549.170861933611, 13637.659058523517, 13642.931785341108, 14114.435422583085, 12323.260604363677, 12498.0, 12518.311492867888, 12621.368931553385, 12766.61700148997, 4437.822487100512, 4473.127740090684, 4476.171908196994, 4478.0596074824325, 4699.483056863743, 54643.180352294745, 54903.450965490236, 55758.16362754562, 55994.16008759913, 56187.43812561863, 11265.549378024787, 11301.26541774782, 11346.546138154546, 11676.124290678234, 11792.29246245228, 20265.29071482491, 20320.0, 20327.695084573745, 20354.18583256653, 20362.28731994373, 7974.720884769005, 8127.552984585905, 8189.099199088096, 8446.366522510836, 8667.91332086671, 4736.834210802605, 4797.280407938767, 4807.879803004953, 4814.807407703723, 4928.654994150375, 3298.86804527821, 3375.561177046968, 3387.1532116970757, 3406.1314364837303, 3406.148462884279, 603.8309273403454, 612.1093808508698, 614.923134608174, 616.1927874484439, 622.9906551401733, 706.4948561778353, 731.7987553422852, 748.3003391828657, 753.732424989121, 773.7717373374868, 9092.727218183465, 9519.428834269966, 9639.132478077006, 9782.51087445625, 9817.509124543743, 592.6088781404253, 599.4065874783932, 614.8493619063269, 736.3814395907464, 738.5863916206941, 4315.8489452869, 4383.868483945423, 4435.600795928382, 4506.414166158378, 4518.118966801508, 64966.35033649697, 65210.7176248829, 65218.0, 65494.34505654978, 66085.33914660888, 58641.0, 59015.40984590185, 59112.11331830026, 60142.69928650329, 60682.0, 53491.19763203555, 53605.1959220612, 53875.46124538783, 54644.45355546473, 54910.72544637331, 8840.557972101498, 9062.22971047442, 9094.499802510662, 9105.635774568944, 9252.259819214582, 36706.4494032595, 36862.8156859214, 36993.81503092467, 37278.0, 37441.4383784238, 49431.75284123556, 50298.49701503084, 50344.0, 50804.745976269885, 51404.74297628488, 11047.337159770439, 11122.666320010412, 11171.888281117084, 11241.775164496828, 11290.0, 12121.151519393557, 12199.759458232082, 12245.69385765363, 12465.501379945063, 12472.1448120801, 10687.46562671879, 11460.541578336773, 11898.524059037542, 12126.333051682243, 12149.392530373269, 11762.529498819951, 11950.163488555718, 12225.0, 12240.877591223974, 12733.745325093261, 11151.88848111541, 11376.08991280679, 11771.352575608424, 11845.76308473843, 12390.194637348544, 9363.29775266844, 9430.430519653963, 9456.95973442907, 9513.89818077292, 9628.18160456369, 10124.291299608956, 10126.657696528124, 10139.720956153236, 10173.84739228927, 10201.642942496977], "value": 6775.419995128181, "unit": "score"}], "name": "dromaeo_css", "value": 7093.697636752431, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 105.0, 110.0, 111.0, 112.0, 109.0, 110.0, 109.0, 110.0, 109.0], "value": 109.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 123.0, 126.0, 217.0, 134.0, 131.0, 161.0, 130.0, 155.0, 123.0], "value": 145.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 161.0, 161.0, 161.0, 161.0, 161.0, 162.0, 162.0, 161.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 77.0, 75.0, 146.0, 75.0, 76.0, 75.0, 75.0, 127.0, 76.0], "value": 87.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 152.0, 92.0, 93.0, 91.0, 131.0, 91.0, 91.0, 139.0], "value": 106.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 116.0, 116.0, 117.0, 117.0, 119.0, 117.0, 116.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 146.0, 149.0, 149.0, 148.0, 147.0, 148.0, 148.0, 149.0], "value": 147.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 96.0, 95.0, 95.0, 95.0, 94.0, 96.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 55.0, 56.0, 55.0, 55.0, 55.0, 106.0, 53.0, 53.0], "value": 59.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 63.0, 62.0, 63.0, 62.0, 62.0, 62.0, 62.0, 63.0], "value": 62.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 77.0, 78.0, 78.0, 84.0, 80.0, 79.0, 86.0, 79.0, 78.0], "value": 79.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 122.0, 119.0, 125.0, 125.0, 116.0, 118.0, 117.0, 190.0, 130.0], "value": 128.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 164.0, 159.0, 158.0, 157.0, 162.0, 158.0, 158.0, 156.0, 157.0], "value": 158.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 63.0, 65.0, 63.0, 61.0, 63.0, 64.0, 60.0, 61.0], "value": 62.5, "unit": "score"}], "name": "kraken", "value": 1520.4999999999998, "alertThreshold": 2.0}]} 23:39:56 INFO - Return code: 0 23:39:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 23:39:56 ERROR - # TBPL SUCCESS # 23:39:56 INFO - Running post-action listener: _resource_record_post_action 23:39:56 INFO - ##### 23:39:56 INFO - ##### Finished run-tests step (success) 23:39:56 INFO - ##### 23:39:56 INFO - Running post-run listener: _resource_record_post_run 23:39:56 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 12288; Write bytes: 292786176; Read time: 188; Write time: 195636 23:39:56 INFO - TinderboxPrint: CPU usage
13.1% 23:39:56 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 188 23:39:56 INFO - TinderboxPrint: I/O write bytes / time
292,786,176 / 195,636 23:39:56 INFO - TinderboxPrint: CPU idle
8,281.8 (86.9%) 23:39:56 INFO - TinderboxPrint: CPU user
1,203.7 (12.6%) 23:39:56 INFO - TinderboxPrint: Swap in / out
0 / 0 23:39:56 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 5197824; Read time: 0; Write time: 580 23:39:56 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 12288; Write bytes: 287588352; Read time: 188; Write time: 195056 23:39:57 INFO - Running post-run listener: _upload_blobber_files 23:39:57 INFO - Blob upload gear active. 23:39:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:39:57 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 23:39:57 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:39:57 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:39:57 INFO - (blobuploader) - INFO - Open directory for files ... 23:39:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:39:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:39:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:39:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:39:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:39:58 INFO - (blobuploader) - INFO - Done attempting. 23:39:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:39:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:39:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:40:02 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:40:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:40:02 INFO - (blobuploader) - INFO - Done attempting. 23:40:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:40:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:40:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:40:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:40:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:40:03 INFO - (blobuploader) - INFO - Done attempting. 23:40:03 INFO - (blobuploader) - INFO - Iteration through files over. 23:40:03 INFO - Return code: 0 23:40:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:40:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:40:03 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7859838e799d266140065ac36fc6c4516aaf00102dacbfb5c573dfb646aa415908b08426c2d466e89e2e16a865cbfe48cefa4558bdeccf7aa6da9b354b1b0818", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c8ee052855b20eded642abb1cf309e0df52153a4e591c0e9106b4d89d1440ff974df945e170699285b1874e967897bb644265dd20bfbec73ff2e88a137d7d68b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/531c405f2f6f9dfc8be68cada39ff6197ed9f241a2f4af760bb56bb05c36d7aeab6d8fcc98f7020772b39cc8baf5768162966f4a1c5a3c1b579e4b48b6ecd79b"} 23:40:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:40:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:40:03 INFO - Contents: 23:40:03 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7859838e799d266140065ac36fc6c4516aaf00102dacbfb5c573dfb646aa415908b08426c2d466e89e2e16a865cbfe48cefa4558bdeccf7aa6da9b354b1b0818", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c8ee052855b20eded642abb1cf309e0df52153a4e591c0e9106b4d89d1440ff974df945e170699285b1874e967897bb644265dd20bfbec73ff2e88a137d7d68b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/531c405f2f6f9dfc8be68cada39ff6197ed9f241a2f4af760bb56bb05c36d7aeab6d8fcc98f7020772b39cc8baf5768162966f4a1c5a3c1b579e4b48b6ecd79b"} 23:40:03 INFO - Running post-run listener: copy_logs_to_upload_dir 23:40:03 INFO - Copying logs to upload dir... 23:40:03 INFO - mkdir: /builds/slave/test/build/upload/logs 23:40:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1248.612228 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-05-20 23:40:03.759893) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-20 23:40:03.764139) ========= 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=920470bf432669ad7e3a5387000001ca-1463810633.710429-220729042 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7859838e799d266140065ac36fc6c4516aaf00102dacbfb5c573dfb646aa415908b08426c2d466e89e2e16a865cbfe48cefa4558bdeccf7aa6da9b354b1b0818", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c8ee052855b20eded642abb1cf309e0df52153a4e591c0e9106b4d89d1440ff974df945e170699285b1874e967897bb644265dd20bfbec73ff2e88a137d7d68b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/531c405f2f6f9dfc8be68cada39ff6197ed9f241a2f4af760bb56bb05c36d7aeab6d8fcc98f7020772b39cc8baf5768162966f4a1c5a3c1b579e4b48b6ecd79b"} build_url:https://queue.taskcluster.net/v1/task/eKJy6gDWTG-cHMld2N__2w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011359 build_url: 'https://queue.taskcluster.net/v1/task/eKJy6gDWTG-cHMld2N__2w/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/Fx-Team/sha512/7859838e799d266140065ac36fc6c4516aaf00102dacbfb5c573dfb646aa415908b08426c2d466e89e2e16a865cbfe48cefa4558bdeccf7aa6da9b354b1b0818", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c8ee052855b20eded642abb1cf309e0df52153a4e591c0e9106b4d89d1440ff974df945e170699285b1874e967897bb644265dd20bfbec73ff2e88a137d7d68b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/531c405f2f6f9dfc8be68cada39ff6197ed9f241a2f4af760bb56bb05c36d7aeab6d8fcc98f7020772b39cc8baf5768162966f4a1c5a3c1b579e4b48b6ecd79b"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-20 23:40:03.789608) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 23:40:03.789942) ========= 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=920470bf432669ad7e3a5387000001ca-1463810633.710429-220729042 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004470 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 23:40:03.875867) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-20 23:40:03.876199) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-20 23:40:03.876528) ========= ========= Total master_lag: 0.17 =========