builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-004 starttime: 1463659319.27 results: success (0) buildid: 20160519023015 builduid: 4fc76e07790b44b195bc5181182268a4 revision: 5a4cdb6dfb19b458229c60e0e19f083ba83d0f58 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-19 05:01:59.271109) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-19 05:01:59.271593) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-19 05:01:59.271884) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-19 05:01:59.288843) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 05:01:59.289169) ========= 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=c6927cf182745ad25b1d38b9000001fe-1463659319.262566-2021384392 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-19 05:01:59-- 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% 89.1M=0s 2016-05-19 05:02:00 (89.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.117837 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 05:01:59.421310) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 05:01:59.421849) ========= 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=c6927cf182745ad25b1d38b9000001fe-1463659319.262566-2021384392 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018486 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 05:01:59.472581) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-19 05:01:59.473127) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 5a4cdb6dfb19b458229c60e0e19f083ba83d0f58 --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 5a4cdb6dfb19b458229c60e0e19f083ba83d0f58 --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=c6927cf182745ad25b1d38b9000001fe-1463659319.262566-2021384392 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-19 05:02:00,193 truncating revision to first 12 chars 2016-05-19 05:02:00,193 Setting DEBUG logging. 2016-05-19 05:02:00,193 attempt 1/10 2016-05-19 05:02:00,193 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/5a4cdb6dfb19?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-19 05:02:00,949 unpacking tar archive at: fx-team-5a4cdb6dfb19/testing/mozharness/ program finished with exit code 0 elapsedTime=1.044976 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-19 05:02:00.531305) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-19 05:02:00.531694) ========= script_repo_revision: 5a4cdb6dfb19b458229c60e0e19f083ba83d0f58 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-19 05:02:00.532144) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-19 05:02:00.532448) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-19 05:02:00.542283) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-05-19 05:02:00.542608) ========= /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=c6927cf182745ad25b1d38b9000001fe-1463659319.262566-2021384392 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:02:01 INFO - MultiFileLogger online at 20160519 05:02:01 in /builds/slave/test 05:02:01 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 05:02:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:02:01 INFO - {'append_to_log': False, 05:02:01 INFO - 'base_work_dir': '/builds/slave/test', 05:02:01 INFO - 'blob_upload_branch': 'Fx-Team', 05:02:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:02:01 INFO - 'branch': 'Fx-Team', 05:02:01 INFO - 'buildbot_json_path': 'buildprops.json', 05:02:01 INFO - 'config_files': ('talos/linux_config.py',), 05:02:01 INFO - 'default_actions': ('clobber', 05:02:01 INFO - 'read-buildbot-config', 05:02:01 INFO - 'download-and-extract', 05:02:01 INFO - 'populate-webroot', 05:02:01 INFO - 'create-virtualenv', 05:02:01 INFO - 'install', 05:02:01 INFO - 'run-tests'), 05:02:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:02:01 INFO - 'download_minidump_stackwalk': True, 05:02:01 INFO - 'download_symbols': 'ondemand', 05:02:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:02:01 INFO - 'tooltool.py': '/tools/tooltool.py', 05:02:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:02:01 INFO - '/tools/misc-python/virtualenv.py')}, 05:02:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:02:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:02:01 INFO - 'installer_path': 'installer.exe', 05:02:01 INFO - 'log_level': 'info', 05:02:01 INFO - 'log_name': 'talos', 05:02:01 INFO - 'log_to_console': True, 05:02:01 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:02:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:02:01 INFO - 'opt_config_files': (), 05:02:01 INFO - 'pip_index': False, 05:02:01 INFO - 'sps_profile': False, 05:02:01 INFO - 'sps_profile_interval': 0, 05:02:01 INFO - 'suite': 'dromaeojs', 05:02:01 INFO - 'system_bits': '32', 05:02:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:02:01 INFO - 'title': 'talos-linux64-ix-004', 05:02:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:02:01 INFO - 'use_talos_json': True, 05:02:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:02:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:02:01 INFO - 'work_dir': 'build'} 05:02:01 INFO - ##### 05:02:01 INFO - ##### Running clobber step. 05:02:01 INFO - ##### 05:02:01 INFO - Running pre-action listener: _resource_record_pre_action 05:02:01 INFO - Running main action method: clobber 05:02:01 INFO - rmtree: /builds/slave/test/build 05:02:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:02:02 INFO - Running post-action listener: _resource_record_post_action 05:02:02 INFO - ##### 05:02:02 INFO - ##### Finished clobber step (success) 05:02:02 INFO - ##### 05:02:02 INFO - ##### 05:02:02 INFO - ##### Running read-buildbot-config step. 05:02:02 INFO - ##### 05:02:02 INFO - Running pre-action listener: _resource_record_pre_action 05:02:02 INFO - Running main action method: read_buildbot_config 05:02:02 INFO - Using buildbot properties: 05:02:02 INFO - { 05:02:02 INFO - "project": "", 05:02:02 INFO - "product": "firefox", 05:02:02 INFO - "script_repo_revision": "production", 05:02:02 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 05:02:02 INFO - "repository": "", 05:02:02 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 05:02:02 INFO - "buildid": "20160519023015", 05:02:02 INFO - "pgo_build": "True", 05:02:02 INFO - "basedir": "/builds/slave/test", 05:02:02 INFO - "buildnumber": 699, 05:02:02 INFO - "slavename": "talos-linux64-ix-004", 05:02:02 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 05:02:02 INFO - "platform": "ubuntu64_hw", 05:02:02 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 05:02:02 INFO - "branch": "fx-team", 05:02:02 INFO - "repo_path": "integration/fx-team", 05:02:02 INFO - "revision": "5a4cdb6dfb19b458229c60e0e19f083ba83d0f58", 05:02:02 INFO - "stage_platform": "linux64-pgo", 05:02:02 INFO - "builduid": "4fc76e07790b44b195bc5181182268a4", 05:02:02 INFO - "slavebuilddir": "test" 05:02:02 INFO - } 05:02:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/Aqvk_neNRgK9Xks37UdzWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 05:02:02 INFO - Running post-action listener: _resource_record_post_action 05:02:02 INFO - ##### 05:02:02 INFO - ##### Finished read-buildbot-config step (success) 05:02:02 INFO - ##### 05:02:02 INFO - ##### 05:02:02 INFO - ##### Running download-and-extract step. 05:02:02 INFO - ##### 05:02:02 INFO - Running pre-action listener: _resource_record_pre_action 05:02:02 INFO - Running main action method: download_and_extract 05:02:02 INFO - mkdir: /builds/slave/test/build/tests 05:02:02 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')]} 05:02:02 INFO - https://queue.taskcluster.net/v1/task/Aqvk_neNRgK9Xks37UdzWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 05:02:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Aqvk_neNRgK9Xks37UdzWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 05:02:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Aqvk_neNRgK9Xks37UdzWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 05:02:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Aqvk_neNRgK9Xks37UdzWA/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 05:02:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Aqvk_neNRgK9Xks37UdzWA/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 05:02:03 INFO - Downloaded 1361 bytes. 05:02:03 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 05:02:03 INFO - Using the following test package requirements: 05:02:03 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 05:02:03 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:02:03 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:02:03 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:02:03 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:02:03 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:02:03 INFO - u'jsshell-linux-x86_64.zip'], 05:02:03 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:02:03 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:02:03 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 05:02:03 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:02:03 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:02:03 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:02:03 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:02:03 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:02:03 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:02:03 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:02:03 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:02:03 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:02:03 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')]} 05:02:03 INFO - https://queue.taskcluster.net/v1/task/Aqvk_neNRgK9Xks37UdzWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:02:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Aqvk_neNRgK9Xks37UdzWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 05:02:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Aqvk_neNRgK9Xks37UdzWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 05:02:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Aqvk_neNRgK9Xks37UdzWA/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 05:02:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Aqvk_neNRgK9Xks37UdzWA/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 05:02:04 INFO - Downloaded 24385714 bytes. 05:02:04 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'] 05:02:04 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 05:02:05 INFO - Return code: 0 05:02:05 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 05:02:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:02:05 INFO - https://queue.taskcluster.net/v1/task/Aqvk_neNRgK9Xks37UdzWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:02:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Aqvk_neNRgK9Xks37UdzWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 05:02:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Aqvk_neNRgK9Xks37UdzWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 05:02:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Aqvk_neNRgK9Xks37UdzWA/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 05:02:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Aqvk_neNRgK9Xks37UdzWA/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 05:02:07 INFO - Downloaded 24385714 bytes. 05:02:07 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'] 05:02:07 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 05:02:09 INFO - Return code: 0 05:02:09 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')]} 05:02:09 INFO - https://queue.taskcluster.net/v1/task/Aqvk_neNRgK9Xks37UdzWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:02:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Aqvk_neNRgK9Xks37UdzWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 05:02:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Aqvk_neNRgK9Xks37UdzWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 05:02:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Aqvk_neNRgK9Xks37UdzWA/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 05:02:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Aqvk_neNRgK9Xks37UdzWA/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 05:02:10 INFO - Downloaded 10840194 bytes. 05:02:10 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'] 05:02:10 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 05:02:10 INFO - Return code: 0 05:02:10 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')]} 05:02:10 INFO - https://queue.taskcluster.net/v1/task/Aqvk_neNRgK9Xks37UdzWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:02:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Aqvk_neNRgK9Xks37UdzWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 05:02:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Aqvk_neNRgK9Xks37UdzWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 05:02:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Aqvk_neNRgK9Xks37UdzWA/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 05:02:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Aqvk_neNRgK9Xks37UdzWA/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 05:02:14 INFO - Downloaded 58443694 bytes. 05:02:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Aqvk_neNRgK9Xks37UdzWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 05:02:14 INFO - mkdir: /builds/slave/test/properties 05:02:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:02:14 INFO - Writing to file /builds/slave/test/properties/build_url 05:02:14 INFO - Contents: 05:02:14 INFO - build_url:https://queue.taskcluster.net/v1/task/Aqvk_neNRgK9Xks37UdzWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 05:02:14 INFO - Running post-action listener: _resource_record_post_action 05:02:14 INFO - Running post-action listener: set_extra_try_arguments 05:02:14 INFO - ##### 05:02:14 INFO - ##### Finished download-and-extract step (success) 05:02:14 INFO - ##### 05:02:14 INFO - ##### 05:02:14 INFO - ##### Running populate-webroot step. 05:02:14 INFO - ##### 05:02:14 INFO - Running pre-action listener: _resource_record_pre_action 05:02:14 INFO - Running main action method: populate_webroot 05:02:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:02:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:02:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:02:14 INFO - '--tppagecycles', 05:02:14 INFO - '7'], 05:02:14 INFO - 'tests': ['tsvgm']}}, 05:02:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:02:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:02:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:02:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:02:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:02:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:02:14 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:02:14 INFO - 'talos_options': ['--disable-e10s'], 05:02:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:02:14 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:02:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:02:14 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:02:14 INFO - 'talos_options': ['--disable-e10s'], 05:02:14 INFO - 'tests': ['damp', 'tps']}, 05:02:14 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:02:14 INFO - 'tests': ['damp', 'tps']}, 05:02:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:02:14 INFO - 'tests': ['dromaeo_dom']}, 05:02:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:02:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:02:14 INFO - 'tests': ['a11yr', 05:02:14 INFO - 'ts_paint', 05:02:14 INFO - 'tpaint', 05:02:14 INFO - 'sessionrestore', 05:02:14 INFO - 'sessionrestore_no_auto_restore', 05:02:14 INFO - 'tabpaint']}, 05:02:14 INFO - 'other-e10s': {'tests': ['a11yr', 05:02:14 INFO - 'ts_paint', 05:02:14 INFO - 'tpaint', 05:02:14 INFO - 'sessionrestore', 05:02:14 INFO - 'sessionrestore_no_auto_restore', 05:02:14 INFO - 'tabpaint']}, 05:02:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:02:14 INFO - 'ts_paint', 05:02:14 INFO - 'tpaint', 05:02:14 INFO - 'sessionrestore', 05:02:14 INFO - 'sessionrestore_no_auto_restore', 05:02:14 INFO - 'tabpaint']}, 05:02:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:02:14 INFO - 'ts_paint', 05:02:14 INFO - 'tpaint', 05:02:14 INFO - 'sessionrestore', 05:02:14 INFO - 'sessionrestore_no_auto_restore', 05:02:14 INFO - 'tabpaint']}, 05:02:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:02:14 INFO - 'tests': ['a11yr', 05:02:14 INFO - 'ts_paint', 05:02:14 INFO - 'tpaint', 05:02:14 INFO - 'sessionrestore', 05:02:14 INFO - 'sessionrestore_no_auto_restore', 05:02:14 INFO - 'tabpaint']}, 05:02:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:02:14 INFO - 'tests': ['a11yr', 05:02:14 INFO - 'ts_paint', 05:02:14 INFO - 'tpaint', 05:02:14 INFO - 'sessionrestore', 05:02:14 INFO - 'sessionrestore_no_auto_restore', 05:02:14 INFO - 'tabpaint']}, 05:02:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:02:14 INFO - 'tests': ['tsvgx', 05:02:14 INFO - 'tsvgr_opacity', 05:02:14 INFO - 'tart', 05:02:14 INFO - 'tscrollx', 05:02:14 INFO - 'cart']}, 05:02:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:02:14 INFO - 'tsvgr_opacity', 05:02:14 INFO - 'tart', 05:02:14 INFO - 'tscrollx', 05:02:14 INFO - 'cart']}, 05:02:14 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:02:14 INFO - 'talos_options': ['--disable-e10s'], 05:02:14 INFO - 'tests': ['tp5o']}, 05:02:14 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:02:14 INFO - 'tests': ['tp5o']}, 05:02:14 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:02:14 INFO - 'talos_options': ['--disable-e10s', 05:02:14 INFO - '--xperf_path', 05:02:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:02:14 INFO - 'tests': ['tp5n']}, 05:02:14 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:02:14 INFO - 'talos_options': ['--xperf_path', 05:02:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:02:14 INFO - 'tests': ['tp5n']}}, 05:02:14 INFO - 'talos.zip': {'path': '', 05:02:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:02:14 INFO - Running post-action listener: _resource_record_post_action 05:02:14 INFO - ##### 05:02:14 INFO - ##### Finished populate-webroot step (success) 05:02:14 INFO - ##### 05:02:14 INFO - ##### 05:02:14 INFO - ##### Running create-virtualenv step. 05:02:14 INFO - ##### 05:02:14 INFO - Running pre-action listener: _resource_record_pre_action 05:02:14 INFO - Running main action method: create_virtualenv 05:02:14 INFO - Creating virtualenv /builds/slave/test/build/venv 05:02:14 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 05:02:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:02:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:02:14 INFO - Using real prefix '/usr' 05:02:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:02:15 INFO - Installing distribute.............................................................................................................................................................................................done. 05:02:17 INFO - Installing pip.................done. 05:02:17 INFO - Return code: 0 05:02:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:02:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:02:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:02:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:02:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:02:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:17 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 0x7f6ee5321f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c77770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d069c0>, '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 0x1d078c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d07d50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1463659319.262566-2021384392', '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 05:02:17 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 05:02:17 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 05:02:17 INFO - Using env: {'DISPLAY': ':0', 05:02:17 INFO - 'HOME': '/home/cltbld', 05:02:17 INFO - 'LANG': 'en_US.UTF-8', 05:02:17 INFO - 'LANGUAGE': 'en_US:en', 05:02:17 INFO - 'LOGNAME': 'cltbld', 05:02:17 INFO - 'MAIL': '/var/mail/cltbld', 05:02:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:17 INFO - 'MOZ_NO_REMOTE': '1', 05:02:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:02:17 INFO - 'NO_EM_RESTART': '1', 05:02:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:02:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:17 INFO - 'PWD': '/builds/slave/test', 05:02:17 INFO - 'SHELL': '/bin/bash', 05:02:17 INFO - 'SHLVL': '1', 05:02:17 INFO - 'TERM': 'linux', 05:02:17 INFO - 'TMOUT': '86400', 05:02:17 INFO - 'USER': 'cltbld', 05:02:17 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1463659319.262566-2021384392', 05:02:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:17 INFO - '_': '/tools/buildbot/bin/python'} 05:02:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:02:17 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 05:02:17 INFO - Cleaning up... 05:02:17 INFO - Return code: 0 05:02:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:02:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:02:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:02:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:02:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:02:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:17 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 0x7f6ee5321f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c77770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d069c0>, '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 0x1d078c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d07d50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1463659319.262566-2021384392', '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 05:02:17 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 05:02:17 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 05:02:17 INFO - Using env: {'DISPLAY': ':0', 05:02:17 INFO - 'HOME': '/home/cltbld', 05:02:17 INFO - 'LANG': 'en_US.UTF-8', 05:02:17 INFO - 'LANGUAGE': 'en_US:en', 05:02:17 INFO - 'LOGNAME': 'cltbld', 05:02:17 INFO - 'MAIL': '/var/mail/cltbld', 05:02:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:17 INFO - 'MOZ_NO_REMOTE': '1', 05:02:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:02:17 INFO - 'NO_EM_RESTART': '1', 05:02:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:02:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:17 INFO - 'PWD': '/builds/slave/test', 05:02:17 INFO - 'SHELL': '/bin/bash', 05:02:17 INFO - 'SHLVL': '1', 05:02:17 INFO - 'TERM': 'linux', 05:02:17 INFO - 'TMOUT': '86400', 05:02:17 INFO - 'USER': 'cltbld', 05:02:17 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1463659319.262566-2021384392', 05:02:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:17 INFO - '_': '/tools/buildbot/bin/python'} 05:02:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:02:17 INFO - Downloading/unpacking psutil>=3.1.1 05:02:17 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 05:02:17 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 05:02:17 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 05:02:17 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 05:02:17 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 05:02:17 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 05:02:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:02:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:02:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:02:19 INFO - Installing collected packages: psutil 05:02:19 INFO - Running setup.py install for psutil 05:02:19 INFO - building 'psutil._psutil_linux' extension 05:02:19 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 05:02:20 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 05:02:20 INFO - building 'psutil._psutil_posix' extension 05:02:20 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 05:02:20 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 05:02:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:02:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:02:20 INFO - Successfully installed psutil 05:02:20 INFO - Cleaning up... 05:02:20 INFO - Return code: 0 05:02:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:02:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:02:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:02:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:02:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:02:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:20 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 0x7f6ee5321f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c77770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d069c0>, '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 0x1d078c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d07d50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1463659319.262566-2021384392', '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 05:02:20 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 05:02:20 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 05:02:20 INFO - Using env: {'DISPLAY': ':0', 05:02:20 INFO - 'HOME': '/home/cltbld', 05:02:20 INFO - 'LANG': 'en_US.UTF-8', 05:02:20 INFO - 'LANGUAGE': 'en_US:en', 05:02:20 INFO - 'LOGNAME': 'cltbld', 05:02:20 INFO - 'MAIL': '/var/mail/cltbld', 05:02:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:20 INFO - 'MOZ_NO_REMOTE': '1', 05:02:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:02:20 INFO - 'NO_EM_RESTART': '1', 05:02:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:02:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:20 INFO - 'PWD': '/builds/slave/test', 05:02:20 INFO - 'SHELL': '/bin/bash', 05:02:20 INFO - 'SHLVL': '1', 05:02:20 INFO - 'TERM': 'linux', 05:02:20 INFO - 'TMOUT': '86400', 05:02:20 INFO - 'USER': 'cltbld', 05:02:20 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1463659319.262566-2021384392', 05:02:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:20 INFO - '_': '/tools/buildbot/bin/python'} 05:02:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:02:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:02:20 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 05:02:20 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 05:02:20 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 05:02:20 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 05:02:20 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 05:02:20 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 05:02:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:02:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:02:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:02:22 INFO - Installing collected packages: mozsystemmonitor 05:02:22 INFO - Running setup.py install for mozsystemmonitor 05:02:22 INFO - Successfully installed mozsystemmonitor 05:02:22 INFO - Cleaning up... 05:02:22 INFO - Return code: 0 05:02:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:02:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:02:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:02:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:02:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:02:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:22 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 0x7f6ee5321f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c77770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d069c0>, '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 0x1d078c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d07d50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1463659319.262566-2021384392', '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 05:02:22 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 05:02:22 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 05:02:22 INFO - Using env: {'DISPLAY': ':0', 05:02:22 INFO - 'HOME': '/home/cltbld', 05:02:22 INFO - 'LANG': 'en_US.UTF-8', 05:02:22 INFO - 'LANGUAGE': 'en_US:en', 05:02:22 INFO - 'LOGNAME': 'cltbld', 05:02:22 INFO - 'MAIL': '/var/mail/cltbld', 05:02:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:22 INFO - 'MOZ_NO_REMOTE': '1', 05:02:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:02:22 INFO - 'NO_EM_RESTART': '1', 05:02:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:02:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:22 INFO - 'PWD': '/builds/slave/test', 05:02:22 INFO - 'SHELL': '/bin/bash', 05:02:22 INFO - 'SHLVL': '1', 05:02:22 INFO - 'TERM': 'linux', 05:02:22 INFO - 'TMOUT': '86400', 05:02:22 INFO - 'USER': 'cltbld', 05:02:22 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1463659319.262566-2021384392', 05:02:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:22 INFO - '_': '/tools/buildbot/bin/python'} 05:02:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:02:22 INFO - Downloading/unpacking blobuploader==1.2.4 05:02:22 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 05:02:22 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 05:02:22 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 05:02:22 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 05:02:22 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 05:02:22 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 05:02:24 INFO - Downloading blobuploader-1.2.4.tar.gz 05:02:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:02:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:02:24 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 05:02:24 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 05:02:24 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 05:02:24 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 05:02:24 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 05:02:24 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 05:02:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:02:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:02:25 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 05:02:25 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 05:02:25 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 05:02:25 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 05:02:25 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 05:02:25 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 05:02:25 INFO - Downloading docopt-0.6.1.tar.gz 05:02:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:02:25 INFO - Installing collected packages: blobuploader, requests, docopt 05:02:25 INFO - Running setup.py install for blobuploader 05:02:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:02:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:02:25 INFO - Running setup.py install for requests 05:02:26 INFO - Running setup.py install for docopt 05:02:26 INFO - Successfully installed blobuploader requests docopt 05:02:26 INFO - Cleaning up... 05:02:26 INFO - Return code: 0 05:02:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:02:26 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')]} 05:02:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:02:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:26 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')]} 05:02:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:02:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:26 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 0x7f6ee5321f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c77770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d069c0>, '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 0x1d078c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d07d50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1463659319.262566-2021384392', '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 05:02:26 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 05:02:26 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 05:02:26 INFO - Using env: {'DISPLAY': ':0', 05:02:26 INFO - 'HOME': '/home/cltbld', 05:02:26 INFO - 'LANG': 'en_US.UTF-8', 05:02:26 INFO - 'LANGUAGE': 'en_US:en', 05:02:26 INFO - 'LOGNAME': 'cltbld', 05:02:26 INFO - 'MAIL': '/var/mail/cltbld', 05:02:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:26 INFO - 'MOZ_NO_REMOTE': '1', 05:02:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:02:26 INFO - 'NO_EM_RESTART': '1', 05:02:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:02:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:26 INFO - 'PWD': '/builds/slave/test', 05:02:26 INFO - 'SHELL': '/bin/bash', 05:02:26 INFO - 'SHLVL': '1', 05:02:26 INFO - 'TERM': 'linux', 05:02:26 INFO - 'TMOUT': '86400', 05:02:26 INFO - 'USER': 'cltbld', 05:02:26 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1463659319.262566-2021384392', 05:02:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:26 INFO - '_': '/tools/buildbot/bin/python'} 05:02:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:02:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:02:26 INFO - Running setup.py (path:/tmp/pip-OSXK2m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:02:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:02:26 INFO - Running setup.py (path:/tmp/pip-IrpC3t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:02:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:02:26 INFO - Running setup.py (path:/tmp/pip-UAxlKi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:02:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:02:26 INFO - Running setup.py (path:/tmp/pip-maqG_D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:02:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:02:26 INFO - Running setup.py (path:/tmp/pip-0vbOB6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:02:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:02:26 INFO - Running setup.py (path:/tmp/pip-mPQRrm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:02:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:02:26 INFO - Running setup.py (path:/tmp/pip-DnIn2B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:02:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:02:26 INFO - Running setup.py (path:/tmp/pip-bbZvB5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:02:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:02:26 INFO - Running setup.py (path:/tmp/pip-rz7WW1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:02:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:02:27 INFO - Running setup.py (path:/tmp/pip-4_QuSR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:02:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:02:27 INFO - Running setup.py (path:/tmp/pip-VnM2Pk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:02:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:02:27 INFO - Running setup.py (path:/tmp/pip-mnktng-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:02:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:02:27 INFO - Running setup.py (path:/tmp/pip-ryyQSw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:02:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:02:27 INFO - Running setup.py (path:/tmp/pip-ykqvx7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:02:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:02:27 INFO - Running setup.py (path:/tmp/pip-LMnpCc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:02:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:02:27 INFO - Running setup.py (path:/tmp/pip-4EoWVX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:02:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:02:27 INFO - Running setup.py (path:/tmp/pip-ebsrl3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:02:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:02:27 INFO - Running setup.py install for manifestparser 05:02:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:02:27 INFO - Running setup.py install for mozcrash 05:02:27 INFO - Running setup.py install for mozdebug 05:02:27 INFO - Running setup.py install for mozdevice 05:02:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:02:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:02:28 INFO - Running setup.py install for mozfile 05:02:28 INFO - Running setup.py install for mozhttpd 05:02:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:02:28 INFO - Running setup.py install for mozinfo 05:02:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:02:28 INFO - Running setup.py install for mozInstall 05:02:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:02:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:02:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:02:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:02:28 INFO - Running setup.py install for mozleak 05:02:28 INFO - Running setup.py install for mozlog 05:02:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:02:28 INFO - Running setup.py install for moznetwork 05:02:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:02:28 INFO - Running setup.py install for mozprocess 05:02:28 INFO - Running setup.py install for mozprofile 05:02:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:02:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:02:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:02:29 INFO - Running setup.py install for mozrunner 05:02:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:02:29 INFO - Running setup.py install for mozscreenshot 05:02:29 INFO - Running setup.py install for moztest 05:02:29 INFO - Running setup.py install for mozversion 05:02:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:02:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:02:29 INFO - Cleaning up... 05:02:29 INFO - Return code: 0 05:02:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:02:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:02:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:02:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:02:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:02:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:29 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 0x7f6ee5321f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c77770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d069c0>, '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 0x1d078c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d07d50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1463659319.262566-2021384392', '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 05:02:29 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 05:02:29 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 05:02:29 INFO - Using env: {'DISPLAY': ':0', 05:02:29 INFO - 'HOME': '/home/cltbld', 05:02:29 INFO - 'LANG': 'en_US.UTF-8', 05:02:29 INFO - 'LANGUAGE': 'en_US:en', 05:02:29 INFO - 'LOGNAME': 'cltbld', 05:02:29 INFO - 'MAIL': '/var/mail/cltbld', 05:02:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:29 INFO - 'MOZ_NO_REMOTE': '1', 05:02:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:02:29 INFO - 'NO_EM_RESTART': '1', 05:02:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:02:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:29 INFO - 'PWD': '/builds/slave/test', 05:02:29 INFO - 'SHELL': '/bin/bash', 05:02:29 INFO - 'SHLVL': '1', 05:02:29 INFO - 'TERM': 'linux', 05:02:29 INFO - 'TMOUT': '86400', 05:02:29 INFO - 'USER': 'cltbld', 05:02:29 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1463659319.262566-2021384392', 05:02:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:29 INFO - '_': '/tools/buildbot/bin/python'} 05:02:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:02:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:02:29 INFO - Running setup.py (path:/tmp/pip-VuREPh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:02:29 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)) 05:02:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:02:29 INFO - Running setup.py (path:/tmp/pip-_WJCHg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:02:29 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)) 05:02:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:02:29 INFO - Running setup.py (path:/tmp/pip-AkTTlb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:02:29 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)) 05:02:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:02:29 INFO - Running setup.py (path:/tmp/pip-7HNTQG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:02:29 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)) 05:02:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:02:29 INFO - Running setup.py (path:/tmp/pip-YGWMyu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:02:30 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)) 05:02:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:02:30 INFO - Running setup.py (path:/tmp/pip-Id2nGx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:02:30 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)) 05:02:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:02:30 INFO - Running setup.py (path:/tmp/pip-WT99yV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:02:30 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)) 05:02:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:02:30 INFO - Running setup.py (path:/tmp/pip-HIsI8C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:02:30 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)) 05:02:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:02:30 INFO - Running setup.py (path:/tmp/pip-HpMhzw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:02:30 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)) 05:02:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:02:30 INFO - Running setup.py (path:/tmp/pip-GKM4fG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:02:30 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)) 05:02:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:02:30 INFO - Running setup.py (path:/tmp/pip-GC7MYp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:02:30 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)) 05:02:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:02:30 INFO - Running setup.py (path:/tmp/pip-TaRa98-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:02:30 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)) 05:02:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:02:30 INFO - Running setup.py (path:/tmp/pip-jaz8oA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:02:30 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)) 05:02:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:02:30 INFO - Running setup.py (path:/tmp/pip-STzoJN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:02:30 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)) 05:02:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:02:30 INFO - Running setup.py (path:/tmp/pip-GKutel-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:02:30 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)) 05:02:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:02:30 INFO - Running setup.py (path:/tmp/pip-gszZl4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:02:30 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)) 05:02:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:02:30 INFO - Running setup.py (path:/tmp/pip-bRvFNZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:02:30 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)) 05:02:30 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)) 05:02:30 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)) 05:02:30 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)) 05:02:30 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)) 05:02:30 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)) 05:02:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:02:30 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 05:02:30 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 05:02:30 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 05:02:30 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 05:02:30 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 05:02:30 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 05:02:32 INFO - Downloading blessings-1.6.tar.gz 05:02:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:02:32 INFO - Installing collected packages: blessings 05:02:32 INFO - Running setup.py install for blessings 05:02:32 INFO - Successfully installed blessings 05:02:32 INFO - Cleaning up... 05:02:33 INFO - Return code: 0 05:02:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:02:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:02:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:02:33 INFO - Reading from file tmpfile_stdout 05:02:33 INFO - Current package versions: 05:02:33 INFO - argparse == 1.2.1 05:02:33 INFO - blessings == 1.6 05:02:33 INFO - blobuploader == 1.2.4 05:02:33 INFO - docopt == 0.6.1 05:02:33 INFO - manifestparser == 1.1 05:02:33 INFO - mozInstall == 1.12 05:02:33 INFO - mozcrash == 0.17 05:02:33 INFO - mozdebug == 0.1 05:02:33 INFO - mozdevice == 0.48 05:02:33 INFO - mozfile == 1.2 05:02:33 INFO - mozhttpd == 0.7 05:02:33 INFO - mozinfo == 0.9 05:02:33 INFO - mozleak == 0.1 05:02:33 INFO - mozlog == 3.2 05:02:33 INFO - moznetwork == 0.27 05:02:33 INFO - mozprocess == 0.22 05:02:33 INFO - mozprofile == 0.28 05:02:33 INFO - mozrunner == 6.11 05:02:33 INFO - mozscreenshot == 0.1 05:02:33 INFO - mozsystemmonitor == 0.3 05:02:33 INFO - moztest == 0.7 05:02:33 INFO - mozversion == 1.4 05:02:33 INFO - psutil == 3.1.1 05:02:33 INFO - requests == 1.2.3 05:02:33 INFO - wsgiref == 0.1.2 05:02:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:02:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:02:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:02:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:02:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:02:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:33 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 0x7f6ee5321f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c77770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d069c0>, '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 0x1d078c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d07d50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1463659319.262566-2021384392', '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 05:02:33 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 05:02:33 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 05:02:33 INFO - Using env: {'DISPLAY': ':0', 05:02:33 INFO - 'HOME': '/home/cltbld', 05:02:33 INFO - 'LANG': 'en_US.UTF-8', 05:02:33 INFO - 'LANGUAGE': 'en_US:en', 05:02:33 INFO - 'LOGNAME': 'cltbld', 05:02:33 INFO - 'MAIL': '/var/mail/cltbld', 05:02:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:33 INFO - 'MOZ_NO_REMOTE': '1', 05:02:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:02:33 INFO - 'NO_EM_RESTART': '1', 05:02:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:02:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:33 INFO - 'PWD': '/builds/slave/test', 05:02:33 INFO - 'SHELL': '/bin/bash', 05:02:33 INFO - 'SHLVL': '1', 05:02:33 INFO - 'TERM': 'linux', 05:02:33 INFO - 'TMOUT': '86400', 05:02:33 INFO - 'USER': 'cltbld', 05:02:33 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1463659319.262566-2021384392', 05:02:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:33 INFO - '_': '/tools/buildbot/bin/python'} 05:02:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:02:33 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)) 05:02:33 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)) 05:02:33 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)) 05:02:33 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)) 05:02:33 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)) 05:02:33 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)) 05:02:33 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)) 05:02:33 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)) 05:02:33 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)) 05:02:33 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)) 05:02:33 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)) 05:02:33 INFO - Cleaning up... 05:02:33 INFO - Return code: 0 05:02:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:02:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:02:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:02:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:02:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:02:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6ee5321f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c77770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d069c0>, '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 0x1d078c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d07d50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1463659319.262566-2021384392', '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 05:02:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:02:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:02:33 INFO - Using env: {'DISPLAY': ':0', 05:02:33 INFO - 'HOME': '/home/cltbld', 05:02:33 INFO - 'LANG': 'en_US.UTF-8', 05:02:33 INFO - 'LANGUAGE': 'en_US:en', 05:02:33 INFO - 'LOGNAME': 'cltbld', 05:02:33 INFO - 'MAIL': '/var/mail/cltbld', 05:02:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:33 INFO - 'MOZ_NO_REMOTE': '1', 05:02:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:02:33 INFO - 'NO_EM_RESTART': '1', 05:02:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:02:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:33 INFO - 'PWD': '/builds/slave/test', 05:02:33 INFO - 'SHELL': '/bin/bash', 05:02:33 INFO - 'SHLVL': '1', 05:02:33 INFO - 'TERM': 'linux', 05:02:33 INFO - 'TMOUT': '86400', 05:02:33 INFO - 'USER': 'cltbld', 05:02:33 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1463659319.262566-2021384392', 05:02:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:33 INFO - '_': '/tools/buildbot/bin/python'} 05:02:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:02:33 INFO - Downloading/unpacking jsonschema 05:02:33 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 05:02:33 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 05:02:33 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 05:02:33 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 05:02:33 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 05:02:33 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 05:02:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:02:35 INFO - Downloading/unpacking functools32 (from jsonschema) 05:02:35 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 05:02:35 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 05:02:35 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 05:02:35 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 05:02:35 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 05:02:35 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 05:02:35 INFO - Downloading functools32-3.2.3-2.tar.gz 05:02:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:02:35 INFO - warning: no files found matching '*.txt' 05:02:35 INFO - no previously-included directories found matching 'build' 05:02:35 INFO - no previously-included directories found matching 'dist' 05:02:35 INFO - no previously-included directories found matching '.git*' 05:02:35 INFO - Installing collected packages: jsonschema, functools32 05:02:35 INFO - Running setup.py install for functools32 05:02:35 INFO - warning: no files found matching '*.txt' 05:02:35 INFO - no previously-included directories found matching 'build' 05:02:35 INFO - no previously-included directories found matching 'dist' 05:02:35 INFO - no previously-included directories found matching '.git*' 05:02:36 INFO - Successfully installed jsonschema functools32 05:02:36 INFO - Cleaning up... 05:02:36 INFO - Return code: 0 05:02:36 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:02: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')]} 05:02:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:02:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02: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')]} 05:02:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:02:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6ee5321f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c77770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d069c0>, '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 0x1d078c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d07d50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1463659319.262566-2021384392', '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 05:02:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 05:02:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 05:02:36 INFO - Using env: {'DISPLAY': ':0', 05:02:36 INFO - 'HOME': '/home/cltbld', 05:02:36 INFO - 'LANG': 'en_US.UTF-8', 05:02:36 INFO - 'LANGUAGE': 'en_US:en', 05:02:36 INFO - 'LOGNAME': 'cltbld', 05:02:36 INFO - 'MAIL': '/var/mail/cltbld', 05:02:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:36 INFO - 'MOZ_NO_REMOTE': '1', 05:02:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:02:36 INFO - 'NO_EM_RESTART': '1', 05:02:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:02:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:36 INFO - 'PWD': '/builds/slave/test', 05:02:36 INFO - 'SHELL': '/bin/bash', 05:02:36 INFO - 'SHLVL': '1', 05:02:36 INFO - 'TERM': 'linux', 05:02:36 INFO - 'TMOUT': '86400', 05:02:36 INFO - 'USER': 'cltbld', 05:02:36 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1463659319.262566-2021384392', 05:02:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:36 INFO - '_': '/tools/buildbot/bin/python'} 05:02:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:02:36 INFO - Downloading/unpacking flake8 05:02:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:02:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:02:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:02:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:02:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:02:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:02:38 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:02:38 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:02:38 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 05:02:38 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 05:02:38 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 05:02:38 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 05:02:38 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 05:02:38 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 05:02:38 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:02:38 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:02:38 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 05:02:38 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 05:02:38 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 05:02:38 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 05:02:38 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 05:02:38 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 05:02:38 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:02:38 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 05:02:38 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 05:02:38 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 05:02:38 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 05:02:38 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 05:02:38 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 05:02:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:02:39 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:02:39 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:02:39 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:02:39 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:02:39 INFO - no previously-included directories found matching 'docs/_build' 05:02:39 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:02:39 INFO - Running setup.py install for pep8 05:02:39 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:02:39 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:02:39 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:02:39 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:02:39 INFO - no previously-included directories found matching 'docs/_build' 05:02:39 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:02:39 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:02:39 INFO - Cleaning up... 05:02:39 INFO - Return code: 0 05:02:39 INFO - Running post-action listener: _resource_record_post_action 05:02:39 INFO - Running post-action listener: _start_resource_monitoring 05:02:39 INFO - Starting resource monitoring. 05:02:39 INFO - ##### 05:02:39 INFO - ##### Finished create-virtualenv step (success) 05:02:39 INFO - ##### 05:02:39 INFO - ##### 05:02:39 INFO - ##### Running install step. 05:02:39 INFO - ##### 05:02:39 INFO - Running pre-action listener: _resource_record_pre_action 05:02:39 INFO - Running main action method: install 05:02:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:02:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:02:39 INFO - Reading from file tmpfile_stdout 05:02:39 INFO - Detecting whether we're running mozinstall >=1.0... 05:02:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:02:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:02:39 INFO - Reading from file tmpfile_stdout 05:02:39 INFO - Output received: 05:02:39 INFO - Usage: mozinstall [options] installer 05:02:39 INFO - Options: 05:02:39 INFO - -h, --help show this help message and exit 05:02:39 INFO - -d DEST, --destination=DEST 05:02:39 INFO - Directory to install application into. [default: 05:02:39 INFO - "/builds/slave/test"] 05:02:39 INFO - --app=APP Application being installed. [default: firefox] 05:02:39 INFO - mkdir: /builds/slave/test/build/application 05:02:39 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'] 05:02:39 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 05:02:56 INFO - Reading from file tmpfile_stdout 05:02:56 INFO - Output received: 05:02:56 INFO - /builds/slave/test/build/application/firefox/firefox 05:02:56 INFO - Running post-action listener: _resource_record_post_action 05:02:56 INFO - ##### 05:02:56 INFO - ##### Finished install step (success) 05:02:56 INFO - ##### 05:02:56 INFO - ##### 05:02:56 INFO - ##### Running run-tests step. 05:02:56 INFO - ##### 05:02:56 INFO - Running pre-action listener: _resource_record_pre_action 05:02:56 INFO - Running main action method: run_tests 05:02:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:02:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:02:56 INFO - Python 2.7.3 05:02:56 INFO - Return code: 0 05:02:56 INFO - grabbing minidump binary from tooltool 05:02: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')]} 05:02:56 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 0x1d069c0>, '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 0x1d078c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d07d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:02:56 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 05:02:56 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 05:02:56 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 05:02:56 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:02:56 INFO - Return code: 0 05:02:56 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:02:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:02:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:02:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:02:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:02:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:02:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:02:57 INFO - Return code: 0 05:02:57 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/Aqvk_neNRgK9Xks37UdzWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--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 05:02:57 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/Aqvk_neNRgK9Xks37UdzWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-004 --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 05:02:57 INFO - Using env: {'DISPLAY': ':0', 05:02:57 INFO - 'HOME': '/home/cltbld', 05:02:57 INFO - 'LANG': 'en_US.UTF-8', 05:02:57 INFO - 'LANGUAGE': 'en_US:en', 05:02:57 INFO - 'LOGNAME': 'cltbld', 05:02:57 INFO - 'MAIL': '/var/mail/cltbld', 05:02:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:02:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:02:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:57 INFO - 'MOZ_NO_REMOTE': '1', 05:02:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:02:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:02:57 INFO - 'NO_EM_RESTART': '1', 05:02:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:02:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:57 INFO - 'PWD': '/builds/slave/test', 05:02:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:02:57 INFO - 'SHELL': '/bin/bash', 05:02:57 INFO - 'SHLVL': '1', 05:02:57 INFO - 'TERM': 'linux', 05:02:57 INFO - 'TMOUT': '86400', 05:02:57 INFO - 'USER': 'cltbld', 05:02:57 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1463659319.262566-2021384392', 05:02:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:57 INFO - '_': '/tools/buildbot/bin/python'} 05:02:57 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/Aqvk_neNRgK9Xks37UdzWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--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 05:02:57 INFO - mozversion application_buildid: 20160519023015 05:02:57 INFO - mozversion application_changeset: 5a4cdb6dfb19b458229c60e0e19f083ba83d0f58 05:02:57 INFO - mozversion application_display_name: Nightly 05:02:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:02:57 INFO - mozversion application_name: Firefox 05:02:57 INFO - mozversion application_remotingname: firefox 05:02:57 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:02:57 INFO - mozversion application_vendor: Mozilla 05:02:57 INFO - mozversion application_version: 49.0a1 05:02:57 INFO - mozversion platform_buildid: 20160519023015 05:02:57 INFO - mozversion platform_changeset: 5a4cdb6dfb19b458229c60e0e19f083ba83d0f58 05:02:57 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:02:57 INFO - mozversion platform_version: 49.0a1 05:02:57 INFO - using testdate: 1463659377 05:02:57 INFO - actual date: 1463659377 05:02:57 INFO - starting webserver on 'localhost:37333' 05:02:57 INFO - SUITE-START | Running 2 tests 05:02:57 INFO - TEST-START | dromaeo_css 05:02:57 INFO - operating with platform_type : linux_ 05:02:57 INFO - Initialising browser for dromaeo_css test... 05:02:57 INFO - TEST-INFO | started process 16113 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjWFwdD/profile http://localhost:37333/getInfo.html) 05:03:02 INFO - PROCESS | 16113 | __metrics Screen width/height:1600/1200 05:03:02 INFO - PROCESS | 16113 | colorDepth:24 05:03:02 INFO - PROCESS | 16113 | Browser inner width/height: 1024/697 05:03:02 INFO - PROCESS | 16113 | __metrics 05:03:03 ERROR - PROCESS | 16113 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:03:03 INFO - PROCESS | 16113 | console.error: PushService: 05:03:03 INFO - PROCESS | 16113 | stateChangeProcessEnqueue: Error transitioning state 05:03:03 INFO - PROCESS | 16113 | UnknownError 05:03:04 INFO - TEST-INFO | 16113: exit 0 05:03:04 INFO - Browser initialized. 05:03:04 INFO - Running cycle 1/1 for dromaeo_css test... 05:03:04 INFO - TEST-INFO | started process 16193 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjWFwdD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:03:15 INFO - PROCESS | 16193 | RSS: Main: 152293376 05:03:15 INFO - PROCESS | 16193 | 05:04:07 INFO - PROCESS | 16193 | 1463659447416 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] 05:06:15 INFO - PROCESS | 16193 | Cycle 1(1): loaded http://localhost:37333/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37333/tests/dromaeo/cssquery-ext.html) 05:06:16 INFO - PROCESS | 16193 | RSS: Main: 260354048 05:06:16 INFO - PROCESS | 16193 | 05:09:18 INFO - PROCESS | 16193 | Cycle 1(1): loaded http://localhost:37333/tests/dromaeo/cssquery-ext.html (next: http://localhost:37333/tests/dromaeo/cssquery-jquery.html) 05:09:19 INFO - PROCESS | 16193 | RSS: Main: 233078784 05:09:19 INFO - PROCESS | 16193 | 05:12:21 INFO - PROCESS | 16193 | Cycle 1(1): loaded http://localhost:37333/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37333/tests/dromaeo/cssquery-mootools.html) 05:12:22 INFO - PROCESS | 16193 | RSS: Main: 233115648 05:12:22 INFO - PROCESS | 16193 | 05:15:23 INFO - PROCESS | 16193 | Cycle 1(1): loaded http://localhost:37333/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37333/tests/dromaeo/cssquery-prototype.html) 05:15:24 INFO - PROCESS | 16193 | RSS: Main: 230227968 05:15:24 INFO - PROCESS | 16193 | 05:18:27 INFO - PROCESS | 16193 | Cycle 1(1): loaded http://localhost:37333/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37333/tests/dromaeo/cssquery-yui.html) 05:18:27 INFO - PROCESS | 16193 | RSS: Main: 226762752 05:18:27 INFO - PROCESS | 16193 | 05:21:30 INFO - PROCESS | 16193 | Cycle 1(1): loaded http://localhost:37333/tests/dromaeo/cssquery-yui.html (next: http://localhost:37333/tests/dromaeo/cssquery-dojo.html) 05:21:30 INFO - PROCESS | 16193 | RSS: Main: 310140928 05:21:30 INFO - PROCESS | 16193 | 05:21:30 INFO - PROCESS | 16193 | __start_tp_report 05:21:30 INFO - PROCESS | 16193 | _x_x_mozilla_page_load 05:21:30 INFO - PROCESS | 16193 | _x_x_mozilla_page_load_details 05:21:30 INFO - PROCESS | 16193 | |i|pagename|runs| 05:21:30 INFO - PROCESS | 16193 | |0;dojo.html;3161.604799400075;3172.9841350793245;3207.550942867999;3211.213252753073;3239.1092449576363;5318.627696061278;5435.83692489225;5451.209574611679;5551.472610102039;5600.52395546378;5485.807996720116;5490.231367608528;5495.093309603911;5548.611597188198;5561.054620714478;4914.336564563783;4994.575461085812;5023.422306434752;5038.445770965191;5138.126518491847;4999.975000124994;5067.214581739836;5079.111155547783;5116.641835071547;5127.794888204486;4828.637852161084;4866.051120031595;4886.3403440535785;4888.633352498541;4918.483559226302;4874.488178741252;4885.120678277908;4958.2562615607585;4968.8757781055765;5008.574271186917;4855.562999330077;4907.901841963141;4928.728919909404;4935.925961110586;4957.008598280322;5333.2000199969925;5384.0847056000575;5446.7276636168035;5472.699001554913;5512.39363669996;5886.352501224862;5894.027485464893;5894.4989675877605;5911.349751527328;5917.023691091008;5766.913496297559;5799.4780469757925;5867.8533036674;5882.29412470502;5886.587938844283;5584.832455026356;5584.944150558483;5599.188117722907;5602.439756024406;5606.271184745998;6103.974138059492;6112.0272619931275;6114.449699527064;6116.5718399711595;6117.418845209698;5524.8066317679495;5835.649861008353;5867.530597552186;5920.615160014585;5938.495227905676;5965.164876917235;5967.283925928917;5970.432808883149;6030.517558595302;6033.396660333931;5927.051671732588;5942.70286485674;5945.613535120204;5947.197128387699;5948.940510594925;5721.914171287434;5734.770609175586;5772.538196944234;5822.475977161991;5846.532277417797;4412.580804823537;4440.200763862471;4451.221036318631;4454.1537107950035;4489.551044895485;3894.4353068804867;3972.7020473464604;3976.721629485966;3999.8400063997697;4029.254587901189;5489.176623506506;5510.91733623996;5511;5571.60998730085;5606.383297837235;5962.701864906738;5964.493018093511;5971.104334349795;5972.223610930551;5980.073088671263;5360.0351936650995;5423.891522169534;5597.244372009808;5608.691521966331;5609.27079479665;4994.051130285234;5035.823746168867;5035.823746168867;5052.469490703424;5088.389393272831;6070.332263451017;6076.392360763888;6084.178635884187;6088.11722300264;6091.208142941301;5691.23168372281;5846.590738648424;5856.0630299151935;5858.238429004373;5875.471207591338;6074.36219196978;6155.353687862889;6159.6612186330185;6161.168242287413;6164.969175154096;5764.654120752601;5811.941880581309;5821.446962538552;5837.6497410155525;5858;5671;5783.971080144573;5859.531237501075;5916.911246331395;5928.703564821828;5727.31272247333;5732.226149469935;5739.483446489836;5746.195532625519;5779.7110144493445;5717.799877004285;5724.513416359656;5729.054705973468;5730.74211660468;5737.08206686928;5446.264754258283;5565.3599836019375;5587.106063029895;5603.411641777555;5643.35101463337;5820.650760954356;5851.502622277154;5863.824085277449;5864.384239654775;5869.882602348014;5631;5667.1782591524825;5669.036263835075;5683.5737319700365;5698.487136157766 05:21:30 INFO - PROCESS | 16193 | |1;ext.html;600.3366280260312;637.9505713101945;646.1987135951421;647.3914520350867;659.5515049766159;30976.380472390538;43569.78215108924;44518.109637807225;44537;44589.33116003258;46035.769821150934;46699.299510507284;46744.53255467453;46783;46817;8765.211130998194;8958.059403762609;8961.103889611051;8961.148690874357;8966.237869781044;8715.389922705413;8845.734627961172;8847.867281990775;8861.556922153866;8862.778430539256;8900.955495222515;8933.687320943769;8955.328350373715;8962.238209752179;8969.506677132755;8678.218960293572;8699.782505437352;8707.825843483126;8710.520921349324;8711.651533938635;8760.255378292853;8850.424722393023;8852.070532594049;8858.247049000842;8860.24687901529;41851;42440.787796060824;42448.3632745506;42449.93875153146;42473;3770.8303126359106;3856.749311294757;3867.1298957734566;3920.862769803072;4068.4100805383055;31261.687383126104;31350;32284;32324.515132273034;32505.51241731376;4278.272693642089;4361.302191649321;4377.824887004548;4381.978090109561;4384.408104905829;336966;337362;337498;337948;338099;51204;58475.70762146162;58729;58737.706311468166;58771.41228587745;60028;61337.693311533156;61634;67049.32376690525;67112;16816.83183168177;16851.57871053209;16932.576685582713;16938.915305423394;16945.576360591083;12455.875441245653;12488.438020289108;12716.809247861289;12726.045546584044;12733.55432559856;15304.540863774104;16058.598535036717;16409.179541022902;16432.83567164337;16864.747028794576;6455.806325810139;6486.156799616019;6569.244536878225;6570.8028759137305;6697.129373181553;1036.0268370797924;1039.4542864995938;1079.1043433949803;1166.9008134308508;1252.7557126360455;32387;33105;33117.834410828276;33204.50193247103;33653;1115.537052123376;1150.4995327032768;1151.5451396698425;1152.181950814914;1175.9714492500539;16535.25591348392;17371.913140434215;17386;17416.216270267614;17474.65050698979;477033;479185;479296;479851;480726;57803.13295300574;58081.419185808445;58143.709281453324;58160;58217.41782582205;1330.3348325837082;1391.2070120031688;1427.3220220395438;1450.0647082631854;1450.1154295879505;32414.35171296608;33662.49506257457;34119.65880341264;34125.4405919562;34590;49373.50626493689;52742;54907.176392354944;55090.17364739452;61037;362692;365747;366486;367963;367983;24920.750792492567;26457.867710661325;27006;27330.453390932467;27722.584161238003;46541.53458465372;47137.2929406066;48436;49041.509584903695;49077.263841043125;41004.58995410127;41180.79409603053;41366.37950430686;41696;42271.7886410566;39889;40913.795431022656;41543.376849347886;42015.579844201166;42115.57884421239;41811.7909410451;42498.78750606227;42750;42762.786186070116;43173.78413107914;35479.290414191055;36706.63293367032;36988;37510.8124459376;37710.434343485416;28356;28522.57216141718;28664.283392915346;28699.13902582926;28713.569296460988 05:21:30 INFO - PROCESS | 16193 | |2;jquery.html;402.6798695037444;403.8848928055501;404.76725882617495;410.46639368820524;420.54160964548635;13394.397252123654;17991.280348786047;19334.51663708408;19899.104540295684;19900.800991990076;14720.779188312184;18406.44780656579;18776.967266800322;18943.715844262348;19562.413127606196;5645.802396916099;5765.077587585987;5828.446297601721;5831.446012628793;5852.531797456193;5673.290838645169;5711.600187986843;5715.399883012286;5718.42815718429;5803.245578074844;4079.2861249281405;6009.699515024253;6337.397947195009;6343.555951083426;6446.613203207822;6362.331955144736;6378.681065946684;6410.519211059189;6435.163428754278;6439.420452159305;5475.589330800206;5571.275734154575;5572.359178694462;5606.691631960241;5609.663420194761;19346;20958.895205524026;22936.197233096762;23372.298831035096;23447.17934872271;2228.799408053283;2489.2781093482868;2493;2521.34445044288;2984.1346009657173;9915.206783457306;10121.69634910954;10124.64563740273;10529.473526323729;10639.787204255948;1816.7638207033;1851.5648822526696;1928.1034319041712;1951.8438524917972;2002.339228054753;79407;83172;84045.57977210137;84407.99999999939;84652;11341.773164536828;11345.09239260857;11395.316281023164;11434;11498.770024599462;15739.291731872094;15746.133962631937;15790;15808.36766529349;15819.129947852749;7974.122846486882;8274.926294890336;8277.875831862406;8327.6668933242;8444.071152173256;7810;7812.843743125106;7877.7636670899965;7961.442699011013;8018.759437216893;4418.6023257906945;4493.28107502798;4818.397700287464;4827.927581086286;4997.30037794709;1958.1971391729323;2013.8388928885654;2020.4241791089469;2060.3612880007245;2079.2099002379;935.6444551070686;1036.6319956415364;1063.0591926145262;1085.630885498934;1115.9621551956595;19118.808811911982;19435.611287774165;19474.22103115888;19583.706244406345;19673.90163049204;1023.9897601024044;1028.3829702178782;1044.4255659387306;1111.7220694826294;1141.0244241173843;10476.738081548023;11169.44152792357;11183.83224251637;11193.888061119447;11227.27022743519;492649;494482;543552;546586;552051;67120.66439667868;85690;93097;95942.52028739812;98504.50747746359;1096.9451527423596;1108.3959242213014;1110.2228440091967;1129.9774004519863;1173.5188572685158;17615.735763963807;17688.91155544214;17969.910150449163;18043.639127217644;18235.088245587933;41981;42276.57723422849;42662.78668606637;42745.35881961711;43395.13209735851;58001.4199857996;59181;60203;60285;60429.697851510464;5830.679312637845;6401.455876250571;8615.6984505542;8736.868946965673;8942.73171804847;13433.529826456028;13433.865661343261;13622.250776207404;13690.65773355674;14020.579382618538;11570.19008669385;11803.527858885893;11882.940585297018;11954.402279885797;12061.095417843522;11821.468033938665;11985.28088314704;12056.819147712968;12103.576374826838;12239.20445171061;11228.494717737558;12225.388730563614;12237.449314780679;12251.877481225074;12340.568080117153;8635.654573817228;8773.824523509367;8892.955535222283;8969.50667713282;9026.729198124067;9244.306676999118;11788.410579471163;11884.168108232341;12133.817992730294;12383.62849114528 05:21:30 INFO - PROCESS | 16193 | |3;mootools.html;1357.3688234970737;1538.1155835394643;1576.054367379572;1696.6606678664264;1822.2893071702038;8755.1682590154;9102.362834601581;9124.726258212257;9372.437653740762;9379.812403751921;9127.178553930145;9308.488033158173;9331.253499720024;9335.253179745621;9337.066293370659;2459.3851537115675;2472.8763561821925;2482.726900040994;2483.627455881614;2487.751224877516;2408.747081556429;2550.311415917701;2554.1443616388533;2554.4507930794966;2554.5274124287066;2429.1255148146743;2435.561598912195;2444.9975510040886;2445.425325048612;2458;2372.28831350595;2381.3213234228247;2398.4243781492405;2415.1546958564536;2417.4319035026756;2318.4435735423544;2353.7410884802657;2357.834951553392;2358.2571489980774;2359.775821296974;7826.569538675428;8796.296296296281;9157.313201509847;9274.675386361512;9285.53572321388;5445.156000819839;5678.9432105678825;5680.6591604503865;5683.715814209273;5688.857778555528;3466.168119651291;3486.1981744198974;3623.4927110204594;3644.034330902313;3646.4165733482782;1113.799516087104;1156.1328454545849;1187.7921363761393;1189.0428205294734;1228.680543058811;12536.874631253753;12657.936710316482;12670.873291267062;12676;12679.112462127654;4772.379590653193;4854.441739200037;4932.161532539405;4932.457429682732;4952.653314268039;10188.134008609351;10236.283460157718;10240.231982601335;10243.641472548425;10246.948765256275;5594.9720251398485;5608.49523542875;5615.073512870413;5615.494605485526;5616.354119276336;5000.599952003831;5028.723420211851;5131.7434128293435;5161.845144645667;5162.664426812245;3850.6919446444217;3919.451276821247;3974.324364857981;4013.0168108812945;4020.3366444536323;1165.7959857025157;1186.8575770907546;1208.10600155884;1221.8533775946944;1225.154643296123;982.9557669904872;989.3470309595632;994.9850752238722;998.2712619787451;1009.5961615353917;6300.086487459384;6339.714712837934;6430.260520040161;6499.122618446544;6534;467.95320467952934;475.3748820301293;477.295988417082;483.65177072507737;488.44073535801306;4420.602145806828;4548.795304211319;4555.589996900295;4560.3843481129625;4564.2012647786505;10987.39569323695;11375.829362559569;11409.771804563863;11433.1996760226;11437.085033197323;11327;11329.43352832355;11341.149413793999;11360.886391135982;11378.31730096197;8178.959105204555;8498;8511.57442127891;8695.869561956575;8732.607032683545;6243.937560624336;6505.121808555785;6550.475961922939;6605.735770569316;6620.304867988792;10717.732056698645;10799.946000269949;10822.026017658449;10869.945650271698;10944.56221751153;10856.185786065918;10886.455677216265;11004.614838480615;11074.169437292396;11199.328040317607;7764.456488046008;7801.531908085533;7816.257455541714;7846.293833554779;7867.881981770163;8287.295579875778;8567.271781898358;8665.306775457822;8945;8955.910440895508;7957.880631790412;8021.358291336795;8060.717874874351;8172.305354044973;8192.057913339804;7954.522728636301;7994.800129996796;8003.279704826361;8085.312748416451;8223.671053157806;7268.600226987566;7290.526115802456;7325.523840950321;7333.083364579427;7336.596487193256;5037.445880953165;5052.317937078447;5075.923861142012;5222.97388513055;5246.685198888079;5168.870778230575;5237.371515418175;5277.604179686462;5288.788448462018;5302.920456193083 05:21:30 INFO - PROCESS | 16193 | |4;prototype.html;326.5526229066181;330.9619393769716;339.8912348048624;342.7412303710699;344.52627636999125;5223.3731952165745;5363.89272214556;5375.784968601256;5415.64798288111;5421.268128802611;5332.893342133165;5348.171033489805;5350.8127215547465;5353.277307563478;5372.361429763921;4171.662669864112;4187.183499217661;4205.5163656179475;4205.663546916255;4222.746635201883;3846.4807251021175;3972.6226008529143;4137.1518838638;4159.23054234966;4173.979130104361;4089.2434899543678;4111.787022828258;4128.277551428503;4134.3591743279685;4140.192662430827;3967.404889266604;4140.171965606891;4144.48193975753;4146.854860079898;4152.5432202457705;3544.503769472276;3638.1632224588493;3648.6351364863567;3650.34293827111;3663.9115704048704;5045.899082018339;5069.746512674352;5085.364329458817;5092;5094.363204599425;4698.872270655018;4707.71753694776;4756.167670657621;4770.904581908377;4773.140834649762;5318.255444237771;5481.5340696040885;5571.464983432569;5572.637778544382;5616.550675945915;3958.5645578986287;3964.861229856941;3970.4242884781834;3990.6408423242046;4000.4799376080923;5358.892822143574;5470.617056806025;5805.680687562182;5810.854728631776;5855.238818953551;4038.1318016626565;4042.797860106983;4050.3721923101966;4057.802948130309;4063.658992532457;4056.6957478188665;4070.3080476319096;4077.836886524565;4079.551249362568;4085.3871919212356;4679.298105284234;4692.770933806483;4701.412323459567;4704.553067458586;4711.717296962193;5379.488948549882;5567.079357621614;5659.377468478464;5659.490645841894;5662.0557310198465;1422.8079209306818;1427.5075099090798;1429.8570142985618;1433.7383102869617;1437.525616546558;863.2058506174335;891.9252300977307;897.9135246351857;899.6086702284528;900.1763386501409;4808.326834243208;4811.27830825372;4822.541858523435;4855.927161092586;4858.975705121523;5146.922796158061;5157.226416037549;5169.663971841894;5176.327077479904;5180.8963820723375;4614.930776038362;4671.019085991979;4808.735519546459;4809.44691360491;4812.205986012339;2726.5501192303445;2729.508688436101;2735.8782899011308;2737;2743.3004583831394;5191.377034755778;5224.52979231871;5236.805548615342;5265.420803711589;5324.21638751015;4814.470408255089;5154.381474223117;5158.406783219978;5236.319278493847;5259.894802103937;5134;5207.229541955678;5718.857028574319;5833.82498525045;5860.2967643883;5715.942840571707;5721.141828725725;5723.855228954142;5768.336641286306;5794.449527294901;5440.7551660174595;5648.971755141198;5678.630888992202;5687.687177205294;5730.369659337386;5867.794627188028;5878.911816322673;5960.5231581474245;5972.611780234271;5972.820815375546;4061.654759345489;4067.3288907331143;4092.427060211632;4100.097978444737;4107.137501124797;5420.376656684532;5729.942700572941;5751.166080918327;5765.048766953406;5767.826965191051;5521.785207254397;5545.140503222007;5574.804881829279;5593.244911936837;5600.943990560205;5525.143602741581;5542.889142217214;5569.220309156803;5602.719864006702;5614.185943038318;5306.363236411656;5469.827500049882;5484.232207490874;5490.752916118864;5521.2270282159725;5539.944600554104;5569.412717375529;5621.606487545832;5637;5642.605017648725;5309.734513274244;5499.50504454585;5510.311211098613;5524.8342549723575;5545.611807173459 05:21:30 INFO - PROCESS | 16193 | |5;yui.html;763.5342441110923;800.3957012455592;827.5862068965517;845.932325413967;870.9722527417648;14620.34208460619;14915.105093694387;15059.17174555399;15979.680406391864;16305.91847040763;15430.768538471932;15499;15499.922500387482;15518.922405387986;15526.53420397387;705.344030052054;825.5129473610568;839.6809212499257;847.4660963592928;850.4939560961218;844.7170197983683;848.5841937450635;849.3290300662469;855.1832999485507;860.4622111180512;873.6898401067625;876.0888675777184;876.2945828607968;876.51791514667;877.903430622631;747.8055705516578;764.457235362893;764.8240904591975;850.1201256699349;875.7591662292857;642.2581917884804;643.2281262484989;644.0596728775993;644.5037321262602;644.912936753537;14824.110553366832;14830.406783728746;14955.327010284564;14988.625284367869;14992.700145997129;13495.392707328194;14153.150810951376;14224.35990380425;14236.35936382855;14256.643583910381;12493.687657808536;12774.744505109846;12867.485300587965;12972.351382430936;13279.203247805066;4591.655625828076;4630;4635.327877457784;4635.420572428447;4642.628589712815;62200.68899655473;63228.68385658088;63349.683251583905;63581;64495.355046449404;11035;11070.446477676083;11238.269512481662;11240.38177900207;11268.154888383404;19380.612387752168;19880.403587892386;19919.60160796776;20829.89585052065;20864.956752162332;8618.525981070976;8676.175763302475;8694.391392602583;8790.296776257885;8824.161704638049;4702.976485117552;4740.407449068866;4753.9049219016115;4820.4456487503685;4899;3353.4299169141304;3465.930681386358;3470.6529347065093;3473.4789781532468;3557.1107223194203;692.7263730826295;708.3837061756306;713.8286811165283;716.3409663109951;719.8272414620558;698.7100353353302;699.7271064284932;727.294524311417;744.8584768893893;755.2862544895022;9584.760380990392;9774;9818.950905245429;9902.158316543031;10082.798344033079;715.1918332284486;740.8296091898785;742.5433358484493;751.812046988253;768.9346405555591;4431.490378606437;4662.580367766917;4701.341812146302;4885.5847252983185;5021.4139718045235;60470;60535.09197362043;61393.69303153456;61656.38343616596;62176.68911655413;57105.42894571084;59102.70448647729;59455.702721486115;60528.69735651382;60552;55416;55969.44030559642;56246.43753562412;56365.43634563684;56410.153847692316;9019.413738107001;9310.26587961261;9327.113924177193;9712.417254964725;9778.266630002636;36527.26945461129;37200.25599487941;37968;38554.421683674205;38716.61283387243;49739.75130124326;49928.75035624798;49936.750316249636;50157;50814;12266.815997759863;12394.938025310177;12433.689157771129;12515.74968500643;12575.119741617998;13056.34718264102;13555.661108472366;13709.725805483635;13941.093828901494;13974;13160.670983225496;13202.933985330012;13322.467101315839;13377.66555836112;13482.258475783927;13330.26683532415;13392.196468211938;13770.793438098237;13876.583702488537;14088.225147616979;13188.670283242996;13373.264470453829;14037.298135093;14210.928945355206;14224.217668028357;10604.469776511298;10707.625233116803;10936;11004.614838480935;11037.448127593427;10629.89370106289;10728.356298622108;11080.833787493355;11612.593559225386;11616 05:21:30 INFO - PROCESS | 16193 | __end_tp_report 05:21:30 INFO - PROCESS | 16193 | __start_cc_report 05:21:30 INFO - PROCESS | 16193 | _x_x_mozilla_cycle_collect,896 05:21:30 INFO - PROCESS | 16193 | __end_cc_report 05:21:30 INFO - PROCESS | 16193 | __startTimestamp1463660490659__endTimestamp 05:21:30 INFO - PROCESS | 16193 | 05:21:30 INFO - PROCESS | 16193 | ------- Summary: start ------- 05:21:30 INFO - PROCESS | 16193 | Number of tests: 6 05:21:30 INFO - PROCESS | 16193 | 05:21:30 INFO - PROCESS | 16193 | [#0] dojo.html Cycles:165 Average:5460.38 Median:5667.18 stddev:636.26 (11.2%) stddev-sans-first:612.12 05:21:30 INFO - PROCESS | 16193 | Values: 3161.6 3173.0 3207.6 3211.2 3239.1 5318.6 5435.8 5451.2 5551.5 5600.5 5485.8 5490.2 5495.1 5548.6 5561.1 4914.3 4994.6 5023.4 5038.4 5138.1 5000.0 5067.2 5079.1 5116.6 5127.8 4828.6 4866.1 4886.3 4888.6 4918.5 4874.5 4885.1 4958.3 4968.9 5008.6 4855.6 4907.9 4928.7 4935.9 4957.0 5333.2 5384.1 5446.7 5472.7 5512.4 5886.4 5894.0 5894.5 5911.3 5917.0 5766.9 5799.5 5867.9 5882.3 5886.6 5584.8 5584.9 5599.2 5602.4 5606.3 6104.0 6112.0 6114.4 6116.6 6117.4 5524.8 5835.6 5867.5 5920.6 5938.5 5965.2 5967.3 5970.4 6030.5 6033.4 5927.1 5942.7 5945.6 5947.2 5948.9 5721.9 5734.8 5772.5 5822.5 5846.5 4412.6 4440.2 4451.2 4454.2 4489.6 3894.4 3972.7 3976.7 3999.8 4029.3 5489.2 5510.9 5511.0 5571.6 5606.4 5962.7 5964.5 5971.1 5972.2 5980.1 5360.0 5423.9 5597.2 5608.7 5609.3 4994.1 5035.8 5035.8 5052.5 5088.4 6070.3 6076.4 6084.2 6088.1 6091.2 5691.2 5846.6 5856.1 5858.2 5875.5 6074.4 6155.4 6159.7 6161.2 6165.0 5764.7 5811.9 5821.4 5837.6 5858.0 5671.0 5784.0 5859.5 5916.9 5928.7 5727.3 5732.2 5739.5 5746.2 5779.7 5717.8 5724.5 5729.1 5730.7 5737.1 5446.3 5565.4 5587.1 5603.4 5643.4 5820.7 5851.5 5863.8 5864.4 5869.9 5631.0 5667.2 5669.0 5683.6 5698.5 05:21:30 INFO - PROCESS | 16193 | 05:21:30 INFO - PROCESS | 16193 | [#1] ext.html Cycles:180 Average:56600.82 Median:42065.58 stddev:105300.45 (250.3%) stddev-sans-first:105511.90 05:21:30 INFO - PROCESS | 16193 | Values: 600.3 638.0 646.2 647.4 659.6 30976.4 43569.8 44518.1 44537.0 44589.3 46035.8 46699.3 46744.5 46783.0 46817.0 8765.2 8958.1 8961.1 8961.1 8966.2 8715.4 8845.7 8847.9 8861.6 8862.8 8901.0 8933.7 8955.3 8962.2 8969.5 8678.2 8699.8 8707.8 8710.5 8711.7 8760.3 8850.4 8852.1 8858.2 8860.2 41851.0 42440.8 42448.4 42449.9 42473.0 3770.8 3856.7 3867.1 3920.9 4068.4 31261.7 31350.0 32284.0 32324.5 32505.5 4278.3 4361.3 4377.8 4382.0 4384.4 336966.0 337362.0 337498.0 337948.0 338099.0 51204.0 58475.7 58729.0 58737.7 58771.4 60028.0 61337.7 61634.0 67049.3 67112.0 16816.8 16851.6 16932.6 16938.9 16945.6 12455.9 12488.4 12716.8 12726.0 12733.6 15304.5 16058.6 16409.2 16432.8 16864.7 6455.8 6486.2 6569.2 6570.8 6697.1 1036.0 1039.5 1079.1 1166.9 1252.8 32387.0 33105.0 33117.8 33204.5 33653.0 1115.5 1150.5 1151.5 1152.2 1176.0 16535.3 17371.9 17386.0 17416.2 17474.7 477033.0 479185.0 479296.0 479851.0 480726.0 57803.1 58081.4 58143.7 58160.0 58217.4 1330.3 1391.2 1427.3 1450.1 1450.1 32414.4 33662.5 34119.7 34125.4 34590.0 49373.5 52742.0 54907.2 55090.2 61037.0 362692.0 365747.0 366486.0 367963.0 367983.0 24920.8 26457.9 27006.0 27330.5 27722.6 46541.5 47137.3 48436.0 49041.5 49077.3 41004.6 41180.8 41366.4 41696.0 42271.8 39889.0 40913.8 41543.4 42015.6 42115.6 41811.8 42498.8 42750.0 42762.8 43173.8 35479.3 36706.6 36988.0 37510.8 37710.4 28356.0 28522.6 28664.3 28699.1 28713.6 05:21:30 INFO - PROCESS | 16193 | 05:21:30 INFO - PROCESS | 16193 | [#2] jquery.html Cycles:180 Average:30037.11 Median:23154.25 stddev:86621.66 (374.1%) stddev-sans-first:86836.08 05:21:30 INFO - PROCESS | 16193 | Values: 402.7 403.9 404.8 410.5 420.5 13394.4 17991.3 19334.5 19899.1 19900.8 14720.8 18406.4 18777.0 18943.7 19562.4 5645.8 5765.1 5828.4 5831.4 5852.5 5673.3 5711.6 5715.4 5718.4 5803.2 4079.3 6009.7 6337.4 6343.6 6446.6 6362.3 6378.7 6410.5 6435.2 6439.4 5475.6 5571.3 5572.4 5606.7 5609.7 19346.0 20958.9 22936.2 23372.3 23447.2 2228.8 2489.3 2493.0 2521.3 2984.1 9915.2 10121.7 10124.6 10529.5 10639.8 1816.8 1851.6 1928.1 1951.8 2002.3 79407.0 83172.0 84045.6 84408.0 84652.0 11341.8 11345.1 11395.3 11434.0 11498.8 15739.3 15746.1 15790.0 15808.4 15819.1 7974.1 8274.9 8277.9 8327.7 8444.1 7810.0 7812.8 7877.8 7961.4 8018.8 4418.6 4493.3 4818.4 4827.9 4997.3 1958.2 2013.8 2020.4 2060.4 2079.2 935.6 1036.6 1063.1 1085.6 1116.0 19118.8 19435.6 19474.2 19583.7 19673.9 1024.0 1028.4 1044.4 1111.7 1141.0 10476.7 11169.4 11183.8 11193.9 11227.3 492649.0 494482.0 543552.0 546586.0 552051.0 67120.7 85690.0 93097.0 95942.5 98504.5 1096.9 1108.4 1110.2 1130.0 1173.5 17615.7 17688.9 17969.9 18043.6 18235.1 41981.0 42276.6 42662.8 42745.4 43395.1 58001.4 59181.0 60203.0 60285.0 60429.7 5830.7 6401.5 8615.7 8736.9 8942.7 13433.5 13433.9 13622.3 13690.7 14020.6 11570.2 11803.5 11882.9 11954.4 12061.1 11821.5 11985.3 12056.8 12103.6 12239.2 11228.5 12225.4 12237.4 12251.9 12340.6 8635.7 8773.8 8893.0 8969.5 9026.7 9244.3 11788.4 11884.2 12133.8 12383.6 05:21:30 INFO - PROCESS | 16193 | 05:21:30 INFO - PROCESS | 16193 | [#3] mootools.html Cycles:180 Average:6038.81 Median:2669.05 stddev:3445.75 (129.1%) stddev-sans-first:3437.45 05:21:30 INFO - PROCESS | 16193 | Values: 1357.4 1538.1 1576.1 1696.7 1822.3 8755.2 9102.4 9124.7 9372.4 9379.8 9127.2 9308.5 9331.3 9335.3 9337.1 2459.4 2472.9 2482.7 2483.6 2487.8 2408.7 2550.3 2554.1 2554.5 2554.5 2429.1 2435.6 2445.0 2445.4 2458.0 2372.3 2381.3 2398.4 2415.2 2417.4 2318.4 2353.7 2357.8 2358.3 2359.8 7826.6 8796.3 9157.3 9274.7 9285.5 5445.2 5678.9 5680.7 5683.7 5688.9 3466.2 3486.2 3623.5 3644.0 3646.4 1113.8 1156.1 1187.8 1189.0 1228.7 12536.9 12657.9 12670.9 12676.0 12679.1 4772.4 4854.4 4932.2 4932.5 4952.7 10188.1 10236.3 10240.2 10243.6 10246.9 5595.0 5608.5 5615.1 5615.5 5616.4 5000.6 5028.7 5131.7 5161.8 5162.7 3850.7 3919.5 3974.3 4013.0 4020.3 1165.8 1186.9 1208.1 1221.9 1225.2 983.0 989.3 995.0 998.3 1009.6 6300.1 6339.7 6430.3 6499.1 6534.0 468.0 475.4 477.3 483.7 488.4 4420.6 4548.8 4555.6 4560.4 4564.2 10987.4 11375.8 11409.8 11433.2 11437.1 11327.0 11329.4 11341.1 11360.9 11378.3 8179.0 8498.0 8511.6 8695.9 8732.6 6243.9 6505.1 6550.5 6605.7 6620.3 10717.7 10799.9 10822.0 10869.9 10944.6 10856.2 10886.5 11004.6 11074.2 11199.3 7764.5 7801.5 7816.3 7846.3 7867.9 8287.3 8567.3 8665.3 8945.0 8955.9 7957.9 8021.4 8060.7 8172.3 8192.1 7954.5 7994.8 8003.3 8085.3 8223.7 7268.6 7290.5 7325.5 7333.1 7336.6 5037.4 5052.3 5075.9 5223.0 5246.7 5168.9 5237.4 5277.6 5288.8 5302.9 05:21:30 INFO - PROCESS | 16193 | 05:21:30 INFO - PROCESS | 16193 | [#4] prototype.html Cycles:180 Average:4583.87 Median:5164.04 stddev:1346.14 (26.1%) stddev-sans-first:1311.44 05:21:30 INFO - PROCESS | 16193 | Values: 326.6 331.0 339.9 342.7 344.5 5223.4 5363.9 5375.8 5415.6 5421.3 5332.9 5348.2 5350.8 5353.3 5372.4 4171.7 4187.2 4205.5 4205.7 4222.7 3846.5 3972.6 4137.2 4159.2 4174.0 4089.2 4111.8 4128.3 4134.4 4140.2 3967.4 4140.2 4144.5 4146.9 4152.5 3544.5 3638.2 3648.6 3650.3 3663.9 5045.9 5069.7 5085.4 5092.0 5094.4 4698.9 4707.7 4756.2 4770.9 4773.1 5318.3 5481.5 5571.5 5572.6 5616.6 3958.6 3964.9 3970.4 3990.6 4000.5 5358.9 5470.6 5805.7 5810.9 5855.2 4038.1 4042.8 4050.4 4057.8 4063.7 4056.7 4070.3 4077.8 4079.6 4085.4 4679.3 4692.8 4701.4 4704.6 4711.7 5379.5 5567.1 5659.4 5659.5 5662.1 1422.8 1427.5 1429.9 1433.7 1437.5 863.2 891.9 897.9 899.6 900.2 4808.3 4811.3 4822.5 4855.9 4859.0 5146.9 5157.2 5169.7 5176.3 5180.9 4614.9 4671.0 4808.7 4809.4 4812.2 2726.6 2729.5 2735.9 2737.0 2743.3 5191.4 5224.5 5236.8 5265.4 5324.2 4814.5 5154.4 5158.4 5236.3 5259.9 5134.0 5207.2 5718.9 5833.8 5860.3 5715.9 5721.1 5723.9 5768.3 5794.4 5440.8 5649.0 5678.6 5687.7 5730.4 5867.8 5878.9 5960.5 5972.6 5972.8 4061.7 4067.3 4092.4 4100.1 4107.1 5420.4 5729.9 5751.2 5765.0 5767.8 5521.8 5545.1 5574.8 5593.2 5600.9 5525.1 5542.9 5569.2 5602.7 5614.2 5306.4 5469.8 5484.2 5490.8 5521.2 5539.9 5569.4 5621.6 5637.0 5642.6 5309.7 5499.5 5510.3 5524.8 5545.6 05:21:30 INFO - PROCESS | 16193 | 05:21:30 INFO - PROCESS | 16193 | [#5] yui.html Cycles:180 Average:15930.84 Median:4747.16 stddev:18552.13 (390.8%) stddev-sans-first:18569.21 05:21:30 INFO - PROCESS | 16193 | Values: 763.5 800.4 827.6 845.9 871.0 14620.3 14915.1 15059.2 15979.7 16305.9 15430.8 15499.0 15499.9 15518.9 15526.5 705.3 825.5 839.7 847.5 850.5 844.7 848.6 849.3 855.2 860.5 873.7 876.1 876.3 876.5 877.9 747.8 764.5 764.8 850.1 875.8 642.3 643.2 644.1 644.5 644.9 14824.1 14830.4 14955.3 14988.6 14992.7 13495.4 14153.2 14224.4 14236.4 14256.6 12493.7 12774.7 12867.5 12972.4 13279.2 4591.7 4630.0 4635.3 4635.4 4642.6 62200.7 63228.7 63349.7 63581.0 64495.4 11035.0 11070.4 11238.3 11240.4 11268.2 19380.6 19880.4 19919.6 20829.9 20865.0 8618.5 8676.2 8694.4 8790.3 8824.2 4703.0 4740.4 4753.9 4820.4 4899.0 3353.4 3465.9 3470.7 3473.5 3557.1 692.7 708.4 713.8 716.3 719.8 698.7 699.7 727.3 744.9 755.3 9584.8 9774.0 9819.0 9902.2 10082.8 715.2 740.8 742.5 751.8 768.9 4431.5 4662.6 4701.3 4885.6 5021.4 60470.0 60535.1 61393.7 61656.4 62176.7 57105.4 59102.7 59455.7 60528.7 60552.0 55416.0 55969.4 56246.4 56365.4 56410.2 9019.4 9310.3 9327.1 9712.4 9778.3 36527.3 37200.3 37968.0 38554.4 38716.6 49739.8 49928.8 49936.8 50157.0 50814.0 12266.8 12394.9 12433.7 12515.7 12575.1 13056.3 13555.7 13709.7 13941.1 13974.0 13160.7 13202.9 13322.5 13377.7 13482.3 13330.3 13392.2 13770.8 13876.6 14088.2 13188.7 13373.3 14037.3 14210.9 14224.2 10604.5 10707.6 10936.0 11004.6 11037.4 10629.9 10728.4 11080.8 11612.6 11616.0 05:21:30 INFO - PROCESS | 16193 | -------- Summary: end -------- 05:21:30 INFO - PROCESS | 16193 | 05:21:31 INFO - TEST-INFO | 16193: exit 0 05:21:31 INFO - TEST-OK | dromaeo_css | took 1113899ms 05:21:31 INFO - TEST-START | kraken 05:21:31 INFO - operating with platform_type : linux_ 05:21:31 INFO - Initialising browser for kraken test... 05:21:31 INFO - TEST-INFO | started process 16378 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMp9r69/profile http://localhost:37333/getInfo.html) 05:21:35 INFO - PROCESS | 16378 | __metrics Screen width/height:1600/1200 05:21:35 INFO - PROCESS | 16378 | colorDepth:24 05:21:35 INFO - PROCESS | 16378 | Browser inner width/height: 1024/697 05:21:35 INFO - PROCESS | 16378 | __metrics 05:21:36 ERROR - PROCESS | 16378 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:21:37 INFO - TEST-INFO | 16378: exit 0 05:21:37 INFO - Browser initialized. 05:21:37 INFO - Running cycle 1/1 for kraken test... 05:21:37 INFO - TEST-INFO | started process 16456 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMp9r69/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:21:49 INFO - PROCESS | 16456 | RSS: Main: 152887296 05:21:49 INFO - PROCESS | 16456 | 05:21:51 INFO - PROCESS | 16456 | Cycle 1(1): loaded http://localhost:37333/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37333/tests/kraken/driver.html?testName=audio-beat-detection) 05:21:52 INFO - PROCESS | 16456 | RSS: Main: 209387520 05:21:52 INFO - PROCESS | 16456 | 05:21:55 INFO - PROCESS | 16456 | Cycle 1(1): loaded http://localhost:37333/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37333/tests/kraken/driver.html?testName=audio-dft) 05:21:56 INFO - PROCESS | 16456 | RSS: Main: 198569984 05:21:56 INFO - PROCESS | 16456 | 05:21:59 INFO - PROCESS | 16456 | Cycle 1(1): loaded http://localhost:37333/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37333/tests/kraken/driver.html?testName=audio-fft) 05:21:59 INFO - PROCESS | 16456 | RSS: Main: 266153984 05:21:59 INFO - PROCESS | 16456 | 05:22:02 INFO - PROCESS | 16456 | Cycle 1(1): loaded http://localhost:37333/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37333/tests/kraken/driver.html?testName=audio-oscillator) 05:22:02 INFO - PROCESS | 16456 | RSS: Main: 174780416 05:22:02 INFO - PROCESS | 16456 | 05:22:04 INFO - PROCESS | 16456 | Cycle 1(1): loaded http://localhost:37333/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37333/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:22:05 INFO - PROCESS | 16456 | RSS: Main: 179236864 05:22:05 INFO - PROCESS | 16456 | 05:22:08 INFO - PROCESS | 16456 | Cycle 1(1): loaded http://localhost:37333/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37333/tests/kraken/driver.html?testName=imaging-darkroom) 05:22:09 INFO - PROCESS | 16456 | RSS: Main: 214417408 05:22:09 INFO - PROCESS | 16456 | 05:22:12 INFO - PROCESS | 16456 | Cycle 1(1): loaded http://localhost:37333/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37333/tests/kraken/driver.html?testName=imaging-desaturate) 05:22:13 INFO - PROCESS | 16456 | RSS: Main: 212905984 05:22:13 INFO - PROCESS | 16456 | 05:22:16 INFO - PROCESS | 16456 | Cycle 1(1): loaded http://localhost:37333/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37333/tests/kraken/driver.html?testName=json-parse-financial) 05:22:17 INFO - PROCESS | 16456 | RSS: Main: 213745664 05:22:17 INFO - PROCESS | 16456 | 05:22:18 INFO - PROCESS | 16456 | Cycle 1(1): loaded http://localhost:37333/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37333/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:22:19 INFO - PROCESS | 16456 | RSS: Main: 200585216 05:22:19 INFO - PROCESS | 16456 | 05:22:21 INFO - PROCESS | 16456 | Cycle 1(1): loaded http://localhost:37333/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37333/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:22:21 INFO - PROCESS | 16456 | RSS: Main: 207556608 05:22:21 INFO - PROCESS | 16456 | 05:22:23 INFO - PROCESS | 16456 | Cycle 1(1): loaded http://localhost:37333/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37333/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:22:24 INFO - PROCESS | 16456 | RSS: Main: 216043520 05:22:24 INFO - PROCESS | 16456 | 05:22:27 INFO - PROCESS | 16456 | Cycle 1(1): loaded http://localhost:37333/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37333/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:22:27 INFO - PROCESS | 16456 | RSS: Main: 223666176 05:22:27 INFO - PROCESS | 16456 | 05:22:30 INFO - PROCESS | 16456 | Cycle 1(1): loaded http://localhost:37333/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37333/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:22:31 INFO - PROCESS | 16456 | RSS: Main: 218583040 05:22:31 INFO - PROCESS | 16456 | 05:22:32 INFO - PROCESS | 16456 | Cycle 1(1): loaded http://localhost:37333/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37333/tests/kraken/driver.html?testName=ai-astar) 05:22:33 INFO - PROCESS | 16456 | RSS: Main: 264716288 05:22:33 INFO - PROCESS | 16456 | 05:22:33 INFO - PROCESS | 16456 | __start_tp_report 05:22:33 INFO - PROCESS | 16456 | _x_x_mozilla_page_load 05:22:33 INFO - PROCESS | 16456 | _x_x_mozilla_page_load_details 05:22:33 INFO - PROCESS | 16456 | |i|pagename|runs| 05:22:33 INFO - PROCESS | 16456 | |0;ai-astar;113;105;110;110;111;111;112;111;111;111 05:22:33 INFO - PROCESS | 16456 | |1;audio-beat-detection;154;124;155;222;125;156;151;125;176;123 05:22:33 INFO - PROCESS | 16456 | |2;audio-dft;161;161;162;162;162;164;161;160;162;161 05:22:33 INFO - PROCESS | 16456 | |3;audio-fft;75;75;76;145;76;74;75;74;127;74 05:22:33 INFO - PROCESS | 16456 | |4;audio-oscillator;93;93;157;93;91;93;129;92;91;119 05:22:33 INFO - PROCESS | 16456 | |5;imaging-gaussian-blur;116;117;117;117;116;116;117;116;117;116 05:22:33 INFO - PROCESS | 16456 | |6;imaging-darkroom;145;145;144;145;145;145;145;145;145;145 05:22:33 INFO - PROCESS | 16456 | |7;imaging-desaturate;95;95;95;95;96;95;94;94;95;95 05:22:33 INFO - PROCESS | 16456 | |8;json-parse-financial;54;55;55;53;53;54;54;110;51;51 05:22:33 INFO - PROCESS | 16456 | |9;json-stringify-tinderbox;59;60;60;59;59;59;59;59;58;60 05:22:33 INFO - PROCESS | 16456 | |10;stanford-crypto-aes;78;77;77;78;79;83;79;79;79;78 05:22:33 INFO - PROCESS | 16456 | |11;stanford-crypto-ccm;128;137;125;126;124;133;128;120;191;139 05:22:33 INFO - PROCESS | 16456 | |12;stanford-crypto-pbkdf2;161;159;167;172;165;169;160;158;164;163 05:22:33 INFO - PROCESS | 16456 | |13;stanford-crypto-sha256-iterative;64;63;66;62;66;63;63;63;65;63 05:22:33 INFO - PROCESS | 16456 | __end_tp_report 05:22:33 INFO - PROCESS | 16456 | __start_cc_report 05:22:33 INFO - PROCESS | 16456 | _x_x_mozilla_cycle_collect,1874 05:22:33 INFO - PROCESS | 16456 | __end_cc_report 05:22:33 INFO - PROCESS | 16456 | __startTimestamp1463660553168__endTimestamp 05:22:33 INFO - PROCESS | 16456 | 05:22:33 INFO - PROCESS | 16456 | ------- Summary: start ------- 05:22:33 INFO - PROCESS | 16456 | Number of tests: 14 05:22:33 INFO - PROCESS | 16456 | 05:22:33 INFO - PROCESS | 16456 | [#0] ai-astar Cycles:10 Average:110.50 Median:111.00 stddev:2.12 (1.9%) stddev-sans-first:2.05 05:22:33 INFO - PROCESS | 16456 | Values: 113.0 105.0 110.0 110.0 111.0 111.0 112.0 111.0 111.0 111.0 05:22:33 INFO - PROCESS | 16456 | 05:22:33 INFO - PROCESS | 16456 | [#1] audio-beat-detection Cycles:10 Average:151.10 Median:154.50 stddev:30.88 (20.0%) stddev-sans-first:32.73 05:22:33 INFO - PROCESS | 16456 | Values: 154.0 124.0 155.0 222.0 125.0 156.0 151.0 125.0 176.0 123.0 05:22:33 INFO - PROCESS | 16456 | 05:22:33 INFO - PROCESS | 16456 | [#2] audio-dft Cycles:10 Average:161.60 Median:162.00 stddev:1.07 (0.7%) stddev-sans-first:1.12 05:22:33 INFO - PROCESS | 16456 | Values: 161.0 161.0 162.0 162.0 162.0 164.0 161.0 160.0 162.0 161.0 05:22:33 INFO - PROCESS | 16456 | 05:22:33 INFO - PROCESS | 16456 | [#3] audio-fft Cycles:10 Average:87.10 Median:75.00 stddev:26.13 (34.8%) stddev-sans-first:27.35 05:22:33 INFO - PROCESS | 16456 | Values: 75.0 75.0 76.0 145.0 76.0 74.0 75.0 74.0 127.0 74.0 05:22:33 INFO - PROCESS | 16456 | 05:22:33 INFO - PROCESS | 16456 | [#4] audio-oscillator Cycles:10 Average:105.10 Median:92.50 stddev:22.64 (24.5%) stddev-sans-first:23.59 05:22:33 INFO - PROCESS | 16456 | Values: 93.0 93.0 157.0 93.0 91.0 93.0 129.0 92.0 91.0 119.0 05:22:33 INFO - PROCESS | 16456 | 05:22:33 INFO - PROCESS | 16456 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 05:22:33 INFO - PROCESS | 16456 | Values: 116.0 117.0 117.0 117.0 116.0 116.0 117.0 116.0 117.0 116.0 05:22:33 INFO - PROCESS | 16456 | 05:22:33 INFO - PROCESS | 16456 | [#6] imaging-darkroom Cycles:10 Average:144.90 Median:145.00 stddev:0.32 (0.2%) stddev-sans-first:0.33 05:22:33 INFO - PROCESS | 16456 | Values: 145.0 145.0 144.0 145.0 145.0 145.0 145.0 145.0 145.0 145.0 05:22:33 INFO - PROCESS | 16456 | 05:22:33 INFO - PROCESS | 16456 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 05:22:33 INFO - PROCESS | 16456 | Values: 95.0 95.0 95.0 95.0 96.0 95.0 94.0 94.0 95.0 95.0 05:22:33 INFO - PROCESS | 16456 | 05:22:33 INFO - PROCESS | 16456 | [#8] json-parse-financial Cycles:10 Average:59.00 Median:54.00 stddev:17.98 (33.3%) stddev-sans-first:18.97 05:22:33 INFO - PROCESS | 16456 | Values: 54.0 55.0 55.0 53.0 53.0 54.0 54.0 110.0 51.0 51.0 05:22:33 INFO - PROCESS | 16456 | 05:22:33 INFO - PROCESS | 16456 | [#9] json-stringify-tinderbox Cycles:10 Average:59.20 Median:59.00 stddev:0.63 (1.1%) stddev-sans-first:0.67 05:22:33 INFO - PROCESS | 16456 | Values: 59.0 60.0 60.0 59.0 59.0 59.0 59.0 59.0 58.0 60.0 05:22:33 INFO - PROCESS | 16456 | 05:22:33 INFO - PROCESS | 16456 | [#10] stanford-crypto-aes Cycles:10 Average:78.70 Median:79.00 stddev:1.70 (2.2%) stddev-sans-first:1.79 05:22:33 INFO - PROCESS | 16456 | Values: 78.0 77.0 77.0 78.0 79.0 83.0 79.0 79.0 79.0 78.0 05:22:33 INFO - PROCESS | 16456 | 05:22:33 INFO - PROCESS | 16456 | [#11] stanford-crypto-ccm Cycles:10 Average:135.10 Median:130.50 stddev:20.51 (15.7%) stddev-sans-first:21.59 05:22:33 INFO - PROCESS | 16456 | Values: 128.0 137.0 125.0 126.0 124.0 133.0 128.0 120.0 191.0 139.0 05:22:33 INFO - PROCESS | 16456 | 05:22:33 INFO - PROCESS | 16456 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.80 Median:164.50 stddev:4.54 (2.8%) stddev-sans-first:4.70 05:22:33 INFO - PROCESS | 16456 | Values: 161.0 159.0 167.0 172.0 165.0 169.0 160.0 158.0 164.0 163.0 05:22:33 INFO - PROCESS | 16456 | 05:22:33 INFO - PROCESS | 16456 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.80 Median:63.50 stddev:1.40 (2.2%) stddev-sans-first:1.48 05:22:33 INFO - PROCESS | 16456 | Values: 64.0 63.0 66.0 62.0 66.0 63.0 63.0 63.0 65.0 63.0 05:22:33 INFO - PROCESS | 16456 | -------- Summary: end -------- 05:22:33 INFO - PROCESS | 16456 | 05:22:34 INFO - TEST-INFO | 16456: exit 0 05:22:34 INFO - TEST-OK | kraken | took 62800ms 05:22:34 INFO - SUITE-END | took 1176s 05:22:34 INFO - Completed test suite (00:19:36) 05:22:34 INFO - javascript benchmark 05:22:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3161.604799400075, 3172.9841350793245, 3207.550942867999, 3211.213252753073, 3239.1092449576363, 5318.627696061278, 5435.83692489225, 5451.209574611679, 5551.472610102039, 5600.52395546378, 5485.807996720116, 5490.231367608528, 5495.093309603911, 5548.611597188198, 5561.054620714478, 4914.336564563783, 4994.575461085812, 5023.422306434752, 5038.445770965191, 5138.126518491847, 4999.975000124994, 5067.214581739836, 5079.111155547783, 5116.641835071547, 5127.794888204486, 4828.637852161084, 4866.051120031595, 4886.3403440535785, 4888.633352498541, 4918.483559226302, 4874.488178741252, 4885.120678277908, 4958.2562615607585, 4968.8757781055765, 5008.574271186917, 4855.562999330077, 4907.901841963141, 4928.728919909404, 4935.925961110586, 4957.008598280322, 5333.2000199969925, 5384.0847056000575, 5446.7276636168035, 5472.699001554913, 5512.39363669996, 5886.352501224862, 5894.027485464893, 5894.4989675877605, 5911.349751527328, 5917.023691091008, 5766.913496297559, 5799.4780469757925, 5867.8533036674, 5882.29412470502, 5886.587938844283, 5584.832455026356, 5584.944150558483, 5599.188117722907, 5602.439756024406, 5606.271184745998, 6103.974138059492, 6112.0272619931275, 6114.449699527064, 6116.5718399711595, 6117.418845209698, 5524.8066317679495, 5835.649861008353, 5867.530597552186, 5920.615160014585, 5938.495227905676, 5965.164876917235, 5967.283925928917, 5970.432808883149, 6030.517558595302, 6033.396660333931, 5927.051671732588, 5942.70286485674, 5945.613535120204, 5947.197128387699, 5948.940510594925, 5721.914171287434, 5734.770609175586, 5772.538196944234, 5822.475977161991, 5846.532277417797, 4412.580804823537, 4440.200763862471, 4451.221036318631, 4454.1537107950035, 4489.551044895485, 3894.4353068804867, 3972.7020473464604, 3976.721629485966, 3999.8400063997697, 4029.254587901189, 5489.176623506506, 5510.91733623996, 5511.0, 5571.60998730085, 5606.383297837235, 5962.701864906738, 5964.493018093511, 5971.104334349795, 5972.223610930551, 5980.073088671263, 5360.0351936650995, 5423.891522169534, 5597.244372009808, 5608.691521966331, 5609.27079479665, 4994.051130285234, 5035.823746168867, 5035.823746168867, 5052.469490703424, 5088.389393272831, 6070.332263451017, 6076.392360763888, 6084.178635884187, 6088.11722300264, 6091.208142941301, 5691.23168372281, 5846.590738648424, 5856.0630299151935, 5858.238429004373, 5875.471207591338, 6074.36219196978, 6155.353687862889, 6159.6612186330185, 6161.168242287413, 6164.969175154096, 5764.654120752601, 5811.941880581309, 5821.446962538552, 5837.6497410155525, 5858.0, 5671.0, 5783.971080144573, 5859.531237501075, 5916.911246331395, 5928.703564821828, 5727.31272247333, 5732.226149469935, 5739.483446489836, 5746.195532625519, 5779.7110144493445, 5717.799877004285, 5724.513416359656, 5729.054705973468, 5730.74211660468, 5737.08206686928, 5446.264754258283, 5565.3599836019375, 5587.106063029895, 5603.411641777555, 5643.35101463337, 5820.650760954356, 5851.502622277154, 5863.824085277449, 5864.384239654775, 5869.882602348014, 5631.0, 5667.1782591524825, 5669.036263835075, 5683.5737319700365, 5698.487136157766], "value": 5416.6959652339965, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [600.3366280260312, 637.9505713101945, 646.1987135951421, 647.3914520350867, 659.5515049766159, 30976.380472390538, 43569.78215108924, 44518.109637807225, 44537.0, 44589.33116003258, 46035.769821150934, 46699.299510507284, 46744.53255467453, 46783.0, 46817.0, 8765.211130998194, 8958.059403762609, 8961.103889611051, 8961.148690874357, 8966.237869781044, 8715.389922705413, 8845.734627961172, 8847.867281990775, 8861.556922153866, 8862.778430539256, 8900.955495222515, 8933.687320943769, 8955.328350373715, 8962.238209752179, 8969.506677132755, 8678.218960293572, 8699.782505437352, 8707.825843483126, 8710.520921349324, 8711.651533938635, 8760.255378292853, 8850.424722393023, 8852.070532594049, 8858.247049000842, 8860.24687901529, 41851.0, 42440.787796060824, 42448.3632745506, 42449.93875153146, 42473.0, 3770.8303126359106, 3856.749311294757, 3867.1298957734566, 3920.862769803072, 4068.4100805383055, 31261.687383126104, 31350.0, 32284.0, 32324.515132273034, 32505.51241731376, 4278.272693642089, 4361.302191649321, 4377.824887004548, 4381.978090109561, 4384.408104905829, 336966.0, 337362.0, 337498.0, 337948.0, 338099.0, 51204.0, 58475.70762146162, 58729.0, 58737.706311468166, 58771.41228587745, 60028.0, 61337.693311533156, 61634.0, 67049.32376690525, 67112.0, 16816.83183168177, 16851.57871053209, 16932.576685582713, 16938.915305423394, 16945.576360591083, 12455.875441245653, 12488.438020289108, 12716.809247861289, 12726.045546584044, 12733.55432559856, 15304.540863774104, 16058.598535036717, 16409.179541022902, 16432.83567164337, 16864.747028794576, 6455.806325810139, 6486.156799616019, 6569.244536878225, 6570.8028759137305, 6697.129373181553, 1036.0268370797924, 1039.4542864995938, 1079.1043433949803, 1166.9008134308508, 1252.7557126360455, 32387.0, 33105.0, 33117.834410828276, 33204.50193247103, 33653.0, 1115.537052123376, 1150.4995327032768, 1151.5451396698425, 1152.181950814914, 1175.9714492500539, 16535.25591348392, 17371.913140434215, 17386.0, 17416.216270267614, 17474.65050698979, 477033.0, 479185.0, 479296.0, 479851.0, 480726.0, 57803.13295300574, 58081.419185808445, 58143.709281453324, 58160.0, 58217.41782582205, 1330.3348325837082, 1391.2070120031688, 1427.3220220395438, 1450.0647082631854, 1450.1154295879505, 32414.35171296608, 33662.49506257457, 34119.65880341264, 34125.4405919562, 34590.0, 49373.50626493689, 52742.0, 54907.176392354944, 55090.17364739452, 61037.0, 362692.0, 365747.0, 366486.0, 367963.0, 367983.0, 24920.750792492567, 26457.867710661325, 27006.0, 27330.453390932467, 27722.584161238003, 46541.53458465372, 47137.2929406066, 48436.0, 49041.509584903695, 49077.263841043125, 41004.58995410127, 41180.79409603053, 41366.37950430686, 41696.0, 42271.7886410566, 39889.0, 40913.795431022656, 41543.376849347886, 42015.579844201166, 42115.57884421239, 41811.7909410451, 42498.78750606227, 42750.0, 42762.786186070116, 43173.78413107914, 35479.290414191055, 36706.63293367032, 36988.0, 37510.8124459376, 37710.434343485416, 28356.0, 28522.57216141718, 28664.283392915346, 28699.13902582926, 28713.569296460988], "value": 20099.50053697749, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [402.6798695037444, 403.8848928055501, 404.76725882617495, 410.46639368820524, 420.54160964548635, 13394.397252123654, 17991.280348786047, 19334.51663708408, 19899.104540295684, 19900.800991990076, 14720.779188312184, 18406.44780656579, 18776.967266800322, 18943.715844262348, 19562.413127606196, 5645.802396916099, 5765.077587585987, 5828.446297601721, 5831.446012628793, 5852.531797456193, 5673.290838645169, 5711.600187986843, 5715.399883012286, 5718.42815718429, 5803.245578074844, 4079.2861249281405, 6009.699515024253, 6337.397947195009, 6343.555951083426, 6446.613203207822, 6362.331955144736, 6378.681065946684, 6410.519211059189, 6435.163428754278, 6439.420452159305, 5475.589330800206, 5571.275734154575, 5572.359178694462, 5606.691631960241, 5609.663420194761, 19346.0, 20958.895205524026, 22936.197233096762, 23372.298831035096, 23447.17934872271, 2228.799408053283, 2489.2781093482868, 2493.0, 2521.34445044288, 2984.1346009657173, 9915.206783457306, 10121.69634910954, 10124.64563740273, 10529.473526323729, 10639.787204255948, 1816.7638207033, 1851.5648822526696, 1928.1034319041712, 1951.8438524917972, 2002.339228054753, 79407.0, 83172.0, 84045.57977210137, 84407.99999999939, 84652.0, 11341.773164536828, 11345.09239260857, 11395.316281023164, 11434.0, 11498.770024599462, 15739.291731872094, 15746.133962631937, 15790.0, 15808.36766529349, 15819.129947852749, 7974.122846486882, 8274.926294890336, 8277.875831862406, 8327.6668933242, 8444.071152173256, 7810.0, 7812.843743125106, 7877.7636670899965, 7961.442699011013, 8018.759437216893, 4418.6023257906945, 4493.28107502798, 4818.397700287464, 4827.927581086286, 4997.30037794709, 1958.1971391729323, 2013.8388928885654, 2020.4241791089469, 2060.3612880007245, 2079.2099002379, 935.6444551070686, 1036.6319956415364, 1063.0591926145262, 1085.630885498934, 1115.9621551956595, 19118.808811911982, 19435.611287774165, 19474.22103115888, 19583.706244406345, 19673.90163049204, 1023.9897601024044, 1028.3829702178782, 1044.4255659387306, 1111.7220694826294, 1141.0244241173843, 10476.738081548023, 11169.44152792357, 11183.83224251637, 11193.888061119447, 11227.27022743519, 492649.0, 494482.0, 543552.0, 546586.0, 552051.0, 67120.66439667868, 85690.0, 93097.0, 95942.52028739812, 98504.50747746359, 1096.9451527423596, 1108.3959242213014, 1110.2228440091967, 1129.9774004519863, 1173.5188572685158, 17615.735763963807, 17688.91155544214, 17969.910150449163, 18043.639127217644, 18235.088245587933, 41981.0, 42276.57723422849, 42662.78668606637, 42745.35881961711, 43395.13209735851, 58001.4199857996, 59181.0, 60203.0, 60285.0, 60429.697851510464, 5830.679312637845, 6401.455876250571, 8615.6984505542, 8736.868946965673, 8942.73171804847, 13433.529826456028, 13433.865661343261, 13622.250776207404, 13690.65773355674, 14020.579382618538, 11570.19008669385, 11803.527858885893, 11882.940585297018, 11954.402279885797, 12061.095417843522, 11821.468033938665, 11985.28088314704, 12056.819147712968, 12103.576374826838, 12239.20445171061, 11228.494717737558, 12225.388730563614, 12237.449314780679, 12251.877481225074, 12340.568080117153, 8635.654573817228, 8773.824523509367, 8892.955535222283, 8969.50667713282, 9026.729198124067, 9244.306676999118, 11788.410579471163, 11884.168108232341, 12133.817992730294, 12383.62849114528], "value": 9379.481007003165, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1357.3688234970737, 1538.1155835394643, 1576.054367379572, 1696.6606678664264, 1822.2893071702038, 8755.1682590154, 9102.362834601581, 9124.726258212257, 9372.437653740762, 9379.812403751921, 9127.178553930145, 9308.488033158173, 9331.253499720024, 9335.253179745621, 9337.066293370659, 2459.3851537115675, 2472.8763561821925, 2482.726900040994, 2483.627455881614, 2487.751224877516, 2408.747081556429, 2550.311415917701, 2554.1443616388533, 2554.4507930794966, 2554.5274124287066, 2429.1255148146743, 2435.561598912195, 2444.9975510040886, 2445.425325048612, 2458.0, 2372.28831350595, 2381.3213234228247, 2398.4243781492405, 2415.1546958564536, 2417.4319035026756, 2318.4435735423544, 2353.7410884802657, 2357.834951553392, 2358.2571489980774, 2359.775821296974, 7826.569538675428, 8796.296296296281, 9157.313201509847, 9274.675386361512, 9285.53572321388, 5445.156000819839, 5678.9432105678825, 5680.6591604503865, 5683.715814209273, 5688.857778555528, 3466.168119651291, 3486.1981744198974, 3623.4927110204594, 3644.034330902313, 3646.4165733482782, 1113.799516087104, 1156.1328454545849, 1187.7921363761393, 1189.0428205294734, 1228.680543058811, 12536.874631253753, 12657.936710316482, 12670.873291267062, 12676.0, 12679.112462127654, 4772.379590653193, 4854.441739200037, 4932.161532539405, 4932.457429682732, 4952.653314268039, 10188.134008609351, 10236.283460157718, 10240.231982601335, 10243.641472548425, 10246.948765256275, 5594.9720251398485, 5608.49523542875, 5615.073512870413, 5615.494605485526, 5616.354119276336, 5000.599952003831, 5028.723420211851, 5131.7434128293435, 5161.845144645667, 5162.664426812245, 3850.6919446444217, 3919.451276821247, 3974.324364857981, 4013.0168108812945, 4020.3366444536323, 1165.7959857025157, 1186.8575770907546, 1208.10600155884, 1221.8533775946944, 1225.154643296123, 982.9557669904872, 989.3470309595632, 994.9850752238722, 998.2712619787451, 1009.5961615353917, 6300.086487459384, 6339.714712837934, 6430.260520040161, 6499.122618446544, 6534.0, 467.95320467952934, 475.3748820301293, 477.295988417082, 483.65177072507737, 488.44073535801306, 4420.602145806828, 4548.795304211319, 4555.589996900295, 4560.3843481129625, 4564.2012647786505, 10987.39569323695, 11375.829362559569, 11409.771804563863, 11433.1996760226, 11437.085033197323, 11327.0, 11329.43352832355, 11341.149413793999, 11360.886391135982, 11378.31730096197, 8178.959105204555, 8498.0, 8511.57442127891, 8695.869561956575, 8732.607032683545, 6243.937560624336, 6505.121808555785, 6550.475961922939, 6605.735770569316, 6620.304867988792, 10717.732056698645, 10799.946000269949, 10822.026017658449, 10869.945650271698, 10944.56221751153, 10856.185786065918, 10886.455677216265, 11004.614838480615, 11074.169437292396, 11199.328040317607, 7764.456488046008, 7801.531908085533, 7816.257455541714, 7846.293833554779, 7867.881981770163, 8287.295579875778, 8567.271781898358, 8665.306775457822, 8945.0, 8955.910440895508, 7957.880631790412, 8021.358291336795, 8060.717874874351, 8172.305354044973, 8192.057913339804, 7954.522728636301, 7994.800129996796, 8003.279704826361, 8085.312748416451, 8223.671053157806, 7268.600226987566, 7290.526115802456, 7325.523840950321, 7333.083364579427, 7336.596487193256, 5037.445880953165, 5052.317937078447, 5075.923861142012, 5222.97388513055, 5246.685198888079, 5168.870778230575, 5237.371515418175, 5277.604179686462, 5288.788448462018, 5302.920456193083], "value": 4742.258262194366, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [326.5526229066181, 330.9619393769716, 339.8912348048624, 342.7412303710699, 344.52627636999125, 5223.3731952165745, 5363.89272214556, 5375.784968601256, 5415.64798288111, 5421.268128802611, 5332.893342133165, 5348.171033489805, 5350.8127215547465, 5353.277307563478, 5372.361429763921, 4171.662669864112, 4187.183499217661, 4205.5163656179475, 4205.663546916255, 4222.746635201883, 3846.4807251021175, 3972.6226008529143, 4137.1518838638, 4159.23054234966, 4173.979130104361, 4089.2434899543678, 4111.787022828258, 4128.277551428503, 4134.3591743279685, 4140.192662430827, 3967.404889266604, 4140.171965606891, 4144.48193975753, 4146.854860079898, 4152.5432202457705, 3544.503769472276, 3638.1632224588493, 3648.6351364863567, 3650.34293827111, 3663.9115704048704, 5045.899082018339, 5069.746512674352, 5085.364329458817, 5092.0, 5094.363204599425, 4698.872270655018, 4707.71753694776, 4756.167670657621, 4770.904581908377, 4773.140834649762, 5318.255444237771, 5481.5340696040885, 5571.464983432569, 5572.637778544382, 5616.550675945915, 3958.5645578986287, 3964.861229856941, 3970.4242884781834, 3990.6408423242046, 4000.4799376080923, 5358.892822143574, 5470.617056806025, 5805.680687562182, 5810.854728631776, 5855.238818953551, 4038.1318016626565, 4042.797860106983, 4050.3721923101966, 4057.802948130309, 4063.658992532457, 4056.6957478188665, 4070.3080476319096, 4077.836886524565, 4079.551249362568, 4085.3871919212356, 4679.298105284234, 4692.770933806483, 4701.412323459567, 4704.553067458586, 4711.717296962193, 5379.488948549882, 5567.079357621614, 5659.377468478464, 5659.490645841894, 5662.0557310198465, 1422.8079209306818, 1427.5075099090798, 1429.8570142985618, 1433.7383102869617, 1437.525616546558, 863.2058506174335, 891.9252300977307, 897.9135246351857, 899.6086702284528, 900.1763386501409, 4808.326834243208, 4811.27830825372, 4822.541858523435, 4855.927161092586, 4858.975705121523, 5146.922796158061, 5157.226416037549, 5169.663971841894, 5176.327077479904, 5180.8963820723375, 4614.930776038362, 4671.019085991979, 4808.735519546459, 4809.44691360491, 4812.205986012339, 2726.5501192303445, 2729.508688436101, 2735.8782899011308, 2737.0, 2743.3004583831394, 5191.377034755778, 5224.52979231871, 5236.805548615342, 5265.420803711589, 5324.21638751015, 4814.470408255089, 5154.381474223117, 5158.406783219978, 5236.319278493847, 5259.894802103937, 5134.0, 5207.229541955678, 5718.857028574319, 5833.82498525045, 5860.2967643883, 5715.942840571707, 5721.141828725725, 5723.855228954142, 5768.336641286306, 5794.449527294901, 5440.7551660174595, 5648.971755141198, 5678.630888992202, 5687.687177205294, 5730.369659337386, 5867.794627188028, 5878.911816322673, 5960.5231581474245, 5972.611780234271, 5972.820815375546, 4061.654759345489, 4067.3288907331143, 4092.427060211632, 4100.097978444737, 4107.137501124797, 5420.376656684532, 5729.942700572941, 5751.166080918327, 5765.048766953406, 5767.826965191051, 5521.785207254397, 5545.140503222007, 5574.804881829279, 5593.244911936837, 5600.943990560205, 5525.143602741581, 5542.889142217214, 5569.220309156803, 5602.719864006702, 5614.185943038318, 5306.363236411656, 5469.827500049882, 5484.232207490874, 5490.752916118864, 5521.2270282159725, 5539.944600554104, 5569.412717375529, 5621.606487545832, 5637.0, 5642.605017648725, 5309.734513274244, 5499.50504454585, 5510.311211098613, 5524.8342549723575, 5545.611807173459], "value": 4156.052026266845, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [763.5342441110923, 800.3957012455592, 827.5862068965517, 845.932325413967, 870.9722527417648, 14620.34208460619, 14915.105093694387, 15059.17174555399, 15979.680406391864, 16305.91847040763, 15430.768538471932, 15499.0, 15499.922500387482, 15518.922405387986, 15526.53420397387, 705.344030052054, 825.5129473610568, 839.6809212499257, 847.4660963592928, 850.4939560961218, 844.7170197983683, 848.5841937450635, 849.3290300662469, 855.1832999485507, 860.4622111180512, 873.6898401067625, 876.0888675777184, 876.2945828607968, 876.51791514667, 877.903430622631, 747.8055705516578, 764.457235362893, 764.8240904591975, 850.1201256699349, 875.7591662292857, 642.2581917884804, 643.2281262484989, 644.0596728775993, 644.5037321262602, 644.912936753537, 14824.110553366832, 14830.406783728746, 14955.327010284564, 14988.625284367869, 14992.700145997129, 13495.392707328194, 14153.150810951376, 14224.35990380425, 14236.35936382855, 14256.643583910381, 12493.687657808536, 12774.744505109846, 12867.485300587965, 12972.351382430936, 13279.203247805066, 4591.655625828076, 4630.0, 4635.327877457784, 4635.420572428447, 4642.628589712815, 62200.68899655473, 63228.68385658088, 63349.683251583905, 63581.0, 64495.355046449404, 11035.0, 11070.446477676083, 11238.269512481662, 11240.38177900207, 11268.154888383404, 19380.612387752168, 19880.403587892386, 19919.60160796776, 20829.89585052065, 20864.956752162332, 8618.525981070976, 8676.175763302475, 8694.391392602583, 8790.296776257885, 8824.161704638049, 4702.976485117552, 4740.407449068866, 4753.9049219016115, 4820.4456487503685, 4899.0, 3353.4299169141304, 3465.930681386358, 3470.6529347065093, 3473.4789781532468, 3557.1107223194203, 692.7263730826295, 708.3837061756306, 713.8286811165283, 716.3409663109951, 719.8272414620558, 698.7100353353302, 699.7271064284932, 727.294524311417, 744.8584768893893, 755.2862544895022, 9584.760380990392, 9774.0, 9818.950905245429, 9902.158316543031, 10082.798344033079, 715.1918332284486, 740.8296091898785, 742.5433358484493, 751.812046988253, 768.9346405555591, 4431.490378606437, 4662.580367766917, 4701.341812146302, 4885.5847252983185, 5021.4139718045235, 60470.0, 60535.09197362043, 61393.69303153456, 61656.38343616596, 62176.68911655413, 57105.42894571084, 59102.70448647729, 59455.702721486115, 60528.69735651382, 60552.0, 55416.0, 55969.44030559642, 56246.43753562412, 56365.43634563684, 56410.153847692316, 9019.413738107001, 9310.26587961261, 9327.113924177193, 9712.417254964725, 9778.266630002636, 36527.26945461129, 37200.25599487941, 37968.0, 38554.421683674205, 38716.61283387243, 49739.75130124326, 49928.75035624798, 49936.750316249636, 50157.0, 50814.0, 12266.815997759863, 12394.938025310177, 12433.689157771129, 12515.74968500643, 12575.119741617998, 13056.34718264102, 13555.661108472366, 13709.725805483635, 13941.093828901494, 13974.0, 13160.670983225496, 13202.933985330012, 13322.467101315839, 13377.66555836112, 13482.258475783927, 13330.26683532415, 13392.196468211938, 13770.793438098237, 13876.583702488537, 14088.225147616979, 13188.670283242996, 13373.264470453829, 14037.298135093, 14210.928945355206, 14224.217668028357, 10604.469776511298, 10707.625233116803, 10936.0, 11004.614838480935, 11037.448127593427, 10629.89370106289, 10728.356298622108, 11080.833787493355, 11612.593559225386, 11616.0], "value": 7138.051098400159, "unit": "score"}], "name": "dromaeo_css", "value": 7237.105162546107, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 105.0, 110.0, 110.0, 111.0, 111.0, 112.0, 111.0, 111.0, 111.0], "value": 110.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 124.0, 155.0, 222.0, 125.0, 156.0, 151.0, 125.0, 176.0, 123.0], "value": 151.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 162.0, 162.0, 162.0, 164.0, 161.0, 160.0, 162.0, 161.0], "value": 161.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 145.0, 76.0, 74.0, 75.0, 74.0, 127.0, 74.0], "value": 87.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 157.0, 93.0, 91.0, 93.0, 129.0, 92.0, 91.0, 119.0], "value": 105.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 116.0, 116.0, 117.0, 116.0, 117.0, 116.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 144.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0], "value": 144.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 94.0, 94.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 55.0, 53.0, 53.0, 54.0, 54.0, 110.0, 51.0, 51.0], "value": 59.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 60.0, 59.0, 59.0, 59.0, 59.0, 59.0, 58.0, 60.0], "value": 59.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 77.0, 77.0, 78.0, 79.0, 83.0, 79.0, 79.0, 79.0, 78.0], "value": 78.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 137.0, 125.0, 126.0, 124.0, 133.0, 128.0, 120.0, 191.0, 139.0], "value": 135.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 159.0, 167.0, 172.0, 165.0, 169.0, 160.0, 158.0, 164.0, 163.0], "value": 163.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 66.0, 62.0, 66.0, 63.0, 63.0, 63.0, 65.0, 63.0], "value": 63.8, "unit": "score"}], "name": "kraken", "value": 1531.3, "alertThreshold": 2.0}]} 05:22:34 INFO - Return code: 0 05:22:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:22:34 ERROR - # TBPL SUCCESS # 05:22:34 INFO - Running post-action listener: _resource_record_post_action 05:22:34 INFO - ##### 05:22:34 INFO - ##### Finished run-tests step (success) 05:22:34 INFO - ##### 05:22:34 INFO - Running post-run listener: _resource_record_post_run 05:22:34 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 0; Write bytes: 537411584; Read time: 0; Write time: 686280 05:22:34 INFO - TinderboxPrint: CPU usage
13.2% 05:22:34 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 05:22:34 INFO - TinderboxPrint: I/O write bytes / time
537,411,584 / 686,280 05:22:34 INFO - TinderboxPrint: CPU idle
8,278.8 (86.8%) 05:22:34 INFO - TinderboxPrint: CPU user
1,207.4 (12.7%) 05:22:34 INFO - TinderboxPrint: Swap in / out
0 / 0 05:22:34 INFO - install - Wall time: 17s; CPU: 16.0%; Read bytes: 0; Write bytes: 253243392; Read time: 0; Write time: 371788 05:22:34 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 0; Write bytes: 284168192; Read time: 0; Write time: 314492 05:22:35 INFO - Running post-run listener: _upload_blobber_files 05:22:35 INFO - Blob upload gear active. 05:22:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:22:35 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 05:22:35 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'] 05:22:35 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 05:22:35 INFO - (blobuploader) - INFO - Open directory for files ... 05:22:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:22:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:22:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:22:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:22:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:22:36 INFO - (blobuploader) - INFO - Done attempting. 05:22:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:22:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:22:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:22:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:22:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:22:37 INFO - (blobuploader) - INFO - Done attempting. 05:22:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:22:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:22:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:22:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:22:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:22:43 INFO - (blobuploader) - INFO - Done attempting. 05:22:43 INFO - (blobuploader) - INFO - Iteration through files over. 05:22:43 INFO - Return code: 0 05:22:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:22:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:22:43 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e21b3d926baac5c3c0c632df5c8eab091b7bdeecb00bfa32263bc5dffce2a3e180e0dd08b759bdafb31de4d422289fe5f2f890a3cb7cc5d6975747bc8875301a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1f0156872d8b1688549cd0141ceb752936c4e4299ed620c694885d62a9e2e4c14cca805afc85616aeb9925d79a3986212f86773b3184358351542deeccadb514", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/170bf6529331b2cf8048fef53076621e55bb9dc8bd0d2660b221233e70450cecaebce7f3f2c30bbd750d67a6e7913872a09c2ae7679333533a3d6f19fbaaf615"} 05:22:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:22:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:22:43 INFO - Contents: 05:22:43 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e21b3d926baac5c3c0c632df5c8eab091b7bdeecb00bfa32263bc5dffce2a3e180e0dd08b759bdafb31de4d422289fe5f2f890a3cb7cc5d6975747bc8875301a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1f0156872d8b1688549cd0141ceb752936c4e4299ed620c694885d62a9e2e4c14cca805afc85616aeb9925d79a3986212f86773b3184358351542deeccadb514", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/170bf6529331b2cf8048fef53076621e55bb9dc8bd0d2660b221233e70450cecaebce7f3f2c30bbd750d67a6e7913872a09c2ae7679333533a3d6f19fbaaf615"} 05:22:43 INFO - Running post-run listener: copy_logs_to_upload_dir 05:22:43 INFO - Copying logs to upload dir... 05:22:43 INFO - mkdir: /builds/slave/test/build/upload/logs 05:22:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1242.469065 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-05-19 05:22:43.029673) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-19 05:22:43.032827) ========= 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=c6927cf182745ad25b1d38b9000001fe-1463659319.262566-2021384392 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/e21b3d926baac5c3c0c632df5c8eab091b7bdeecb00bfa32263bc5dffce2a3e180e0dd08b759bdafb31de4d422289fe5f2f890a3cb7cc5d6975747bc8875301a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1f0156872d8b1688549cd0141ceb752936c4e4299ed620c694885d62a9e2e4c14cca805afc85616aeb9925d79a3986212f86773b3184358351542deeccadb514", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/170bf6529331b2cf8048fef53076621e55bb9dc8bd0d2660b221233e70450cecaebce7f3f2c30bbd750d67a6e7913872a09c2ae7679333533a3d6f19fbaaf615"} build_url:https://queue.taskcluster.net/v1/task/Aqvk_neNRgK9Xks37UdzWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011585 build_url: 'https://queue.taskcluster.net/v1/task/Aqvk_neNRgK9Xks37UdzWA/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/e21b3d926baac5c3c0c632df5c8eab091b7bdeecb00bfa32263bc5dffce2a3e180e0dd08b759bdafb31de4d422289fe5f2f890a3cb7cc5d6975747bc8875301a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1f0156872d8b1688549cd0141ceb752936c4e4299ed620c694885d62a9e2e4c14cca805afc85616aeb9925d79a3986212f86773b3184358351542deeccadb514", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/170bf6529331b2cf8048fef53076621e55bb9dc8bd0d2660b221233e70450cecaebce7f3f2c30bbd750d67a6e7913872a09c2ae7679333533a3d6f19fbaaf615"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-19 05:22:43.058279) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 05:22:43.058584) ========= 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=c6927cf182745ad25b1d38b9000001fe-1463659319.262566-2021384392 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004523 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 05:22:43.141126) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-19 05:22:43.141417) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-19 05:22:43.141716) ========= ========= Total master_lag: 0.17 =========