builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-003 starttime: 1463795178.0 results: success (0) buildid: 20160520181836 builduid: dc7f2d7053d74aa08369953fe333fdb8 revision: 06d9362a57a10396f3f7c7989c21f1a416f4c7e9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-20 18:46:17.997378) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-20 18:46:17.997820) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-20 18:46:17.998114) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-20 18:46:18.015188) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 18:46:18.015475) ========= 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=7de08804244eb36560b44971000001c3-1463794583.192789-567490163 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-20 18:46:18-- 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% 83.5M=0s 2016-05-20 18:46:18 (83.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.100353 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 18:46:18.124701) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 18:46:18.125017) ========= 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=7de08804244eb36560b44971000001c3-1463794583.192789-567490163 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.009616 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 18:46:18.175636) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 18:46:18.175963) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 06d9362a57a10396f3f7c7989c21f1a416f4c7e9 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 06d9362a57a10396f3f7c7989c21f1a416f4c7e9 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7de08804244eb36560b44971000001c3-1463794583.192789-567490163 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-20 18:46:18,543 truncating revision to first 12 chars 2016-05-20 18:46:18,543 Setting DEBUG logging. 2016-05-20 18:46:18,543 attempt 1/10 2016-05-20 18:46:18,543 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/06d9362a57a1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-20 18:46:18,785 unpacking tar archive at: fx-team-06d9362a57a1/testing/mozharness/ program finished with exit code 0 elapsedTime=0.478820 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 18:46:18.662656) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-20 18:46:18.662962) ========= script_repo_revision: 06d9362a57a10396f3f7c7989c21f1a416f4c7e9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-20 18:46:18.663360) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-20 18:46:18.663645) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-20 18:46:18.669230) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-05-20 18:46:18.669512) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 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-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] 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=7de08804244eb36560b44971000001c3-1463794583.192789-567490163 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:46:19 INFO - MultiFileLogger online at 20160520 18:46:19 in /builds/slave/test 18:46:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 18:46:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:46:19 INFO - {'append_to_log': False, 18:46:19 INFO - 'base_work_dir': '/builds/slave/test', 18:46:19 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 18:46:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:46:19 INFO - 'branch': 'Fx-Team-Non-PGO', 18:46:19 INFO - 'buildbot_json_path': 'buildprops.json', 18:46:19 INFO - 'config_files': ('talos/linux_config.py',), 18:46:19 INFO - 'default_actions': ('clobber', 18:46:19 INFO - 'read-buildbot-config', 18:46:19 INFO - 'download-and-extract', 18:46:19 INFO - 'populate-webroot', 18:46:19 INFO - 'create-virtualenv', 18:46:19 INFO - 'install', 18:46:19 INFO - 'run-tests'), 18:46:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:46:19 INFO - 'download_minidump_stackwalk': True, 18:46:19 INFO - 'download_symbols': 'ondemand', 18:46:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:46:19 INFO - 'tooltool.py': '/tools/tooltool.py', 18:46:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:46:19 INFO - '/tools/misc-python/virtualenv.py')}, 18:46:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:46:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:46:19 INFO - 'installer_path': 'installer.exe', 18:46:19 INFO - 'log_level': 'info', 18:46:19 INFO - 'log_name': 'talos', 18:46:19 INFO - 'log_to_console': True, 18:46:19 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:46:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:46:19 INFO - 'opt_config_files': (), 18:46:19 INFO - 'pip_index': False, 18:46:19 INFO - 'sps_profile': False, 18:46:19 INFO - 'sps_profile_interval': 0, 18:46:19 INFO - 'suite': 'dromaeojs', 18:46:19 INFO - 'system_bits': '32', 18:46:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:46:19 INFO - 'title': 'talos-linux64-ix-003', 18:46:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:46:19 INFO - 'use_talos_json': True, 18:46:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:46:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:46:19 INFO - 'work_dir': 'build'} 18:46:19 INFO - ##### 18:46:19 INFO - ##### Running clobber step. 18:46:19 INFO - ##### 18:46:19 INFO - Running pre-action listener: _resource_record_pre_action 18:46:19 INFO - Running main action method: clobber 18:46:19 INFO - rmtree: /builds/slave/test/build 18:46:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:46:19 INFO - Running post-action listener: _resource_record_post_action 18:46:19 INFO - ##### 18:46:19 INFO - ##### Finished clobber step (success) 18:46:19 INFO - ##### 18:46:19 INFO - ##### 18:46:19 INFO - ##### Running read-buildbot-config step. 18:46:19 INFO - ##### 18:46:19 INFO - Running pre-action listener: _resource_record_pre_action 18:46:19 INFO - Running main action method: read_buildbot_config 18:46:19 INFO - Using buildbot properties: 18:46:19 INFO - { 18:46:19 INFO - "project": "", 18:46:19 INFO - "product": "firefox", 18:46:19 INFO - "script_repo_revision": "production", 18:46:19 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 18:46:19 INFO - "repository": "", 18:46:19 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 18:46:19 INFO - "buildid": "20160520181836", 18:46:19 INFO - "pgo_build": "False", 18:46:19 INFO - "basedir": "/builds/slave/test", 18:46:19 INFO - "buildnumber": 1762, 18:46:19 INFO - "slavename": "talos-linux64-ix-003", 18:46:19 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 18:46:19 INFO - "platform": "ubuntu64_hw", 18:46:19 INFO - "scheduler": "tests-fx-team-linux64-talos", 18:46:19 INFO - "branch": "fx-team", 18:46:19 INFO - "repo_path": "integration/fx-team", 18:46:19 INFO - "revision": "06d9362a57a10396f3f7c7989c21f1a416f4c7e9", 18:46:19 INFO - "stage_platform": "linux64", 18:46:19 INFO - "builduid": "dc7f2d7053d74aa08369953fe333fdb8", 18:46:19 INFO - "slavebuilddir": "test" 18:46:19 INFO - } 18:46:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/L7LKINAWSi-pcRqJtSMDWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 18:46:19 INFO - Running post-action listener: _resource_record_post_action 18:46:19 INFO - ##### 18:46:19 INFO - ##### Finished read-buildbot-config step (success) 18:46:19 INFO - ##### 18:46:19 INFO - ##### 18:46:19 INFO - ##### Running download-and-extract step. 18:46:19 INFO - ##### 18:46:19 INFO - Running pre-action listener: _resource_record_pre_action 18:46:19 INFO - Running main action method: download_and_extract 18:46:19 INFO - mkdir: /builds/slave/test/build/tests 18:46:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:46:19 INFO - https://queue.taskcluster.net/v1/task/L7LKINAWSi-pcRqJtSMDWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 18:46:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L7LKINAWSi-pcRqJtSMDWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 18:46:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L7LKINAWSi-pcRqJtSMDWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 18:46:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L7LKINAWSi-pcRqJtSMDWA/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 18:46:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L7LKINAWSi-pcRqJtSMDWA/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 18:46:20 INFO - Downloaded 1361 bytes. 18:46:20 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 18:46:20 INFO - Using the following test package requirements: 18:46:20 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 18:46:20 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:46:20 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:46:20 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:46:20 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 18:46:20 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:46:20 INFO - u'jsshell-linux-x86_64.zip'], 18:46:20 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:46:20 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:46:20 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 18:46:20 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:46:20 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:46:20 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:46:20 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:46:20 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:46:20 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:46:20 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:46:20 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:46:20 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:46: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')]} 18:46:20 INFO - https://queue.taskcluster.net/v1/task/L7LKINAWSi-pcRqJtSMDWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:46:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L7LKINAWSi-pcRqJtSMDWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 18:46:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L7LKINAWSi-pcRqJtSMDWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 18:46:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L7LKINAWSi-pcRqJtSMDWA/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 18:46:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L7LKINAWSi-pcRqJtSMDWA/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 18:46:22 INFO - Downloaded 22814444 bytes. 18:46:22 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'] 18:46:22 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 18:46:23 INFO - Return code: 0 18:46:23 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 18:46:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:46:23 INFO - https://queue.taskcluster.net/v1/task/L7LKINAWSi-pcRqJtSMDWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:46:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L7LKINAWSi-pcRqJtSMDWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 18:46:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L7LKINAWSi-pcRqJtSMDWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 18:46:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L7LKINAWSi-pcRqJtSMDWA/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 18:46:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L7LKINAWSi-pcRqJtSMDWA/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 18:46:27 INFO - Downloaded 22814444 bytes. 18:46:27 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:46:27 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 18:46:29 INFO - Return code: 0 18:46: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')]} 18:46:29 INFO - https://queue.taskcluster.net/v1/task/L7LKINAWSi-pcRqJtSMDWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 18:46:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L7LKINAWSi-pcRqJtSMDWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 18:46:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L7LKINAWSi-pcRqJtSMDWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 18:46:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L7LKINAWSi-pcRqJtSMDWA/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 18:46:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L7LKINAWSi-pcRqJtSMDWA/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 18:46:30 INFO - Downloaded 10840359 bytes. 18:46:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:46:30 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 18:46:30 INFO - Return code: 0 18:46:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:46:30 INFO - https://queue.taskcluster.net/v1/task/L7LKINAWSi-pcRqJtSMDWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 18:46:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L7LKINAWSi-pcRqJtSMDWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 18:46:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L7LKINAWSi-pcRqJtSMDWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 18:46:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L7LKINAWSi-pcRqJtSMDWA/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 18:46:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L7LKINAWSi-pcRqJtSMDWA/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 18:46:32 INFO - Downloaded 54817018 bytes. 18:46:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/L7LKINAWSi-pcRqJtSMDWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 18:46:32 INFO - mkdir: /builds/slave/test/properties 18:46:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:46:32 INFO - Writing to file /builds/slave/test/properties/build_url 18:46:32 INFO - Contents: 18:46:32 INFO - build_url:https://queue.taskcluster.net/v1/task/L7LKINAWSi-pcRqJtSMDWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 18:46:32 INFO - Running post-action listener: _resource_record_post_action 18:46:32 INFO - Running post-action listener: set_extra_try_arguments 18:46:32 INFO - ##### 18:46:32 INFO - ##### Finished download-and-extract step (success) 18:46:32 INFO - ##### 18:46:32 INFO - ##### 18:46:32 INFO - ##### Running populate-webroot step. 18:46:32 INFO - ##### 18:46:32 INFO - Running pre-action listener: _resource_record_pre_action 18:46:32 INFO - Running main action method: populate_webroot 18:46:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:46:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:46:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:46:32 INFO - '--tppagecycles', 18:46:32 INFO - '7'], 18:46:32 INFO - 'tests': ['tsvgm']}}, 18:46:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:46:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:46:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:46:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:46:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:46:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:46:32 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:46:32 INFO - 'talos_options': ['--disable-e10s'], 18:46:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:46:32 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:46:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:46:32 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:46:32 INFO - 'talos_options': ['--disable-e10s'], 18:46:32 INFO - 'tests': ['damp', 'tps']}, 18:46:32 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:46:32 INFO - 'tests': ['damp', 'tps']}, 18:46:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:46:32 INFO - 'tests': ['dromaeo_dom']}, 18:46:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:46:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:46:32 INFO - 'tests': ['a11yr', 18:46:32 INFO - 'ts_paint', 18:46:32 INFO - 'tpaint', 18:46:32 INFO - 'sessionrestore', 18:46:32 INFO - 'sessionrestore_no_auto_restore', 18:46:32 INFO - 'tabpaint']}, 18:46:32 INFO - 'other-e10s': {'tests': ['a11yr', 18:46:32 INFO - 'ts_paint', 18:46:32 INFO - 'tpaint', 18:46:32 INFO - 'sessionrestore', 18:46:32 INFO - 'sessionrestore_no_auto_restore', 18:46:32 INFO - 'tabpaint']}, 18:46:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:46:32 INFO - 'ts_paint', 18:46:32 INFO - 'tpaint', 18:46:32 INFO - 'sessionrestore', 18:46:32 INFO - 'sessionrestore_no_auto_restore', 18:46:32 INFO - 'tabpaint']}, 18:46:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:46:32 INFO - 'ts_paint', 18:46:32 INFO - 'tpaint', 18:46:32 INFO - 'sessionrestore', 18:46:32 INFO - 'sessionrestore_no_auto_restore', 18:46:32 INFO - 'tabpaint']}, 18:46:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:46:32 INFO - 'tests': ['a11yr', 18:46:32 INFO - 'ts_paint', 18:46:32 INFO - 'tpaint', 18:46:32 INFO - 'sessionrestore', 18:46:32 INFO - 'sessionrestore_no_auto_restore', 18:46:32 INFO - 'tabpaint']}, 18:46:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:46:32 INFO - 'tests': ['a11yr', 18:46:32 INFO - 'ts_paint', 18:46:32 INFO - 'tpaint', 18:46:32 INFO - 'sessionrestore', 18:46:32 INFO - 'sessionrestore_no_auto_restore', 18:46:32 INFO - 'tabpaint']}, 18:46:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:46:32 INFO - 'tests': ['tsvgx', 18:46:32 INFO - 'tsvgr_opacity', 18:46:32 INFO - 'tart', 18:46:32 INFO - 'tscrollx', 18:46:32 INFO - 'cart']}, 18:46:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:46:32 INFO - 'tsvgr_opacity', 18:46:32 INFO - 'tart', 18:46:32 INFO - 'tscrollx', 18:46:32 INFO - 'cart']}, 18:46:32 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:46:32 INFO - 'talos_options': ['--disable-e10s'], 18:46:32 INFO - 'tests': ['tp5o']}, 18:46:32 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:46:32 INFO - 'tests': ['tp5o']}, 18:46:32 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:46:32 INFO - 'talos_options': ['--disable-e10s', 18:46:32 INFO - '--xperf_path', 18:46:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:46:32 INFO - 'tests': ['tp5n']}, 18:46:32 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:46:32 INFO - 'talos_options': ['--xperf_path', 18:46:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:46:32 INFO - 'tests': ['tp5n']}}, 18:46:32 INFO - 'talos.zip': {'path': '', 18:46:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:46:32 INFO - Running post-action listener: _resource_record_post_action 18:46:32 INFO - ##### 18:46:32 INFO - ##### Finished populate-webroot step (success) 18:46:32 INFO - ##### 18:46:32 INFO - ##### 18:46:32 INFO - ##### Running create-virtualenv step. 18:46:32 INFO - ##### 18:46:32 INFO - Running pre-action listener: _resource_record_pre_action 18:46:32 INFO - Running main action method: create_virtualenv 18:46:32 INFO - Creating virtualenv /builds/slave/test/build/venv 18:46:32 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 18:46:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:46:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:46:32 INFO - Using real prefix '/usr' 18:46:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:46:33 INFO - Installing distribute.............................................................................................................................................................................................done. 18:46:35 INFO - Installing pip.................done. 18:46:35 INFO - Return code: 0 18:46:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:46:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:46:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:46:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:46:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:46:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:46:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:46:35 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 0x7f4b46aabf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11529c0>, '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 0x11538c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1153d50>, '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': '7de08804244eb36560b44971000001c3-1463794583.192789-567490163', '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 18:46:35 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 18:46:35 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 18:46:35 INFO - Using env: {'DISPLAY': ':0', 18:46:35 INFO - 'HOME': '/home/cltbld', 18:46:35 INFO - 'LANG': 'en_US.UTF-8', 18:46:35 INFO - 'LANGUAGE': 'en_US:en', 18:46:35 INFO - 'LOGNAME': 'cltbld', 18:46:35 INFO - 'MAIL': '/var/mail/cltbld', 18:46:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:46:35 INFO - 'MOZ_NO_REMOTE': '1', 18:46:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:46:35 INFO - 'NO_EM_RESTART': '1', 18:46:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:46:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:46:35 INFO - 'PWD': '/builds/slave/test', 18:46:35 INFO - 'SHELL': '/bin/bash', 18:46:35 INFO - 'SHLVL': '1', 18:46:35 INFO - 'TERM': 'linux', 18:46:35 INFO - 'TMOUT': '86400', 18:46:35 INFO - 'USER': 'cltbld', 18:46:35 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1463794583.192789-567490163', 18:46:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:46:35 INFO - '_': '/tools/buildbot/bin/python'} 18:46:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:46:35 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 18:46:35 INFO - Cleaning up... 18:46:35 INFO - Return code: 0 18:46:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:46:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:46:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:46:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:46:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:46:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:46:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:46:35 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 0x7f4b46aabf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11529c0>, '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 0x11538c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1153d50>, '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': '7de08804244eb36560b44971000001c3-1463794583.192789-567490163', '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 18:46:35 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 18:46:35 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 18:46:35 INFO - Using env: {'DISPLAY': ':0', 18:46:35 INFO - 'HOME': '/home/cltbld', 18:46:35 INFO - 'LANG': 'en_US.UTF-8', 18:46:35 INFO - 'LANGUAGE': 'en_US:en', 18:46:35 INFO - 'LOGNAME': 'cltbld', 18:46:35 INFO - 'MAIL': '/var/mail/cltbld', 18:46:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:46:35 INFO - 'MOZ_NO_REMOTE': '1', 18:46:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:46:35 INFO - 'NO_EM_RESTART': '1', 18:46:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:46:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:46:35 INFO - 'PWD': '/builds/slave/test', 18:46:35 INFO - 'SHELL': '/bin/bash', 18:46:35 INFO - 'SHLVL': '1', 18:46:35 INFO - 'TERM': 'linux', 18:46:35 INFO - 'TMOUT': '86400', 18:46:35 INFO - 'USER': 'cltbld', 18:46:35 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1463794583.192789-567490163', 18:46:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:46:35 INFO - '_': '/tools/buildbot/bin/python'} 18:46:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:46:35 INFO - Downloading/unpacking psutil>=3.1.1 18:46: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 18:46: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 18:46: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 18:46: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 18:46: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 18:46: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 18:46:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:46:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:46:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:46:37 INFO - Installing collected packages: psutil 18:46:37 INFO - Running setup.py install for psutil 18:46:37 INFO - building 'psutil._psutil_linux' extension 18:46:37 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 18:46:38 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 18:46:38 INFO - building 'psutil._psutil_posix' extension 18:46:38 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 18:46:38 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 18:46:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:46:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:46:38 INFO - Successfully installed psutil 18:46:38 INFO - Cleaning up... 18:46:38 INFO - Return code: 0 18:46:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:46:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:46:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:46:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:46:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:46:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:46:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:46:38 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 0x7f4b46aabf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11529c0>, '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 0x11538c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1153d50>, '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': '7de08804244eb36560b44971000001c3-1463794583.192789-567490163', '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 18:46:38 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 18:46:38 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 18:46:38 INFO - Using env: {'DISPLAY': ':0', 18:46:38 INFO - 'HOME': '/home/cltbld', 18:46:38 INFO - 'LANG': 'en_US.UTF-8', 18:46:38 INFO - 'LANGUAGE': 'en_US:en', 18:46:38 INFO - 'LOGNAME': 'cltbld', 18:46:38 INFO - 'MAIL': '/var/mail/cltbld', 18:46:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:46:38 INFO - 'MOZ_NO_REMOTE': '1', 18:46:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:46:38 INFO - 'NO_EM_RESTART': '1', 18:46:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:46:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:46:38 INFO - 'PWD': '/builds/slave/test', 18:46:38 INFO - 'SHELL': '/bin/bash', 18:46:38 INFO - 'SHLVL': '1', 18:46:38 INFO - 'TERM': 'linux', 18:46:38 INFO - 'TMOUT': '86400', 18:46:38 INFO - 'USER': 'cltbld', 18:46:38 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1463794583.192789-567490163', 18:46:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:46:38 INFO - '_': '/tools/buildbot/bin/python'} 18:46:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:46:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:46: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 18:46: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 18:46: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 18:46: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 18:46: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 18:46: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 18:46:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:46:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:46:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:46:40 INFO - Installing collected packages: mozsystemmonitor 18:46:40 INFO - Running setup.py install for mozsystemmonitor 18:46:40 INFO - Successfully installed mozsystemmonitor 18:46:40 INFO - Cleaning up... 18:46:40 INFO - Return code: 0 18:46:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:46:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:46:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:46:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:46:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:46:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:46:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:46:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f4b46aabf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11529c0>, '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 0x11538c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1153d50>, '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': '7de08804244eb36560b44971000001c3-1463794583.192789-567490163', '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 18:46:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:46:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:46:40 INFO - Using env: {'DISPLAY': ':0', 18:46:40 INFO - 'HOME': '/home/cltbld', 18:46:40 INFO - 'LANG': 'en_US.UTF-8', 18:46:40 INFO - 'LANGUAGE': 'en_US:en', 18:46:40 INFO - 'LOGNAME': 'cltbld', 18:46:40 INFO - 'MAIL': '/var/mail/cltbld', 18:46:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:46:40 INFO - 'MOZ_NO_REMOTE': '1', 18:46:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:46:40 INFO - 'NO_EM_RESTART': '1', 18:46:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:46:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:46:40 INFO - 'PWD': '/builds/slave/test', 18:46:40 INFO - 'SHELL': '/bin/bash', 18:46:40 INFO - 'SHLVL': '1', 18:46:40 INFO - 'TERM': 'linux', 18:46:40 INFO - 'TMOUT': '86400', 18:46:40 INFO - 'USER': 'cltbld', 18:46:40 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1463794583.192789-567490163', 18:46:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:46:40 INFO - '_': '/tools/buildbot/bin/python'} 18:46:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:46:40 INFO - Downloading/unpacking blobuploader==1.2.4 18:46:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:46:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:46:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:46:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:46:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:46:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:46:42 INFO - Downloading blobuploader-1.2.4.tar.gz 18:46:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:46:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:46:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:46:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:46:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:46:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:46:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:46:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:46:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:46:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:46:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:46:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:46:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:46:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:46:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:46:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:46:43 INFO - Downloading docopt-0.6.1.tar.gz 18:46:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:46:43 INFO - Installing collected packages: blobuploader, requests, docopt 18:46:43 INFO - Running setup.py install for blobuploader 18:46:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:46:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:46:43 INFO - Running setup.py install for requests 18:46:43 INFO - Running setup.py install for docopt 18:46:43 INFO - Successfully installed blobuploader requests docopt 18:46:43 INFO - Cleaning up... 18:46:43 INFO - Return code: 0 18:46:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:46:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:46:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:46:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:46:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:46:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:46:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:46:43 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 0x7f4b46aabf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11529c0>, '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 0x11538c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1153d50>, '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': '7de08804244eb36560b44971000001c3-1463794583.192789-567490163', '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 18:46:43 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 18:46:43 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 18:46:43 INFO - Using env: {'DISPLAY': ':0', 18:46:43 INFO - 'HOME': '/home/cltbld', 18:46:43 INFO - 'LANG': 'en_US.UTF-8', 18:46:43 INFO - 'LANGUAGE': 'en_US:en', 18:46:43 INFO - 'LOGNAME': 'cltbld', 18:46:43 INFO - 'MAIL': '/var/mail/cltbld', 18:46:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:46:43 INFO - 'MOZ_NO_REMOTE': '1', 18:46:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:46:43 INFO - 'NO_EM_RESTART': '1', 18:46:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:46:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:46:43 INFO - 'PWD': '/builds/slave/test', 18:46:43 INFO - 'SHELL': '/bin/bash', 18:46:43 INFO - 'SHLVL': '1', 18:46:43 INFO - 'TERM': 'linux', 18:46:43 INFO - 'TMOUT': '86400', 18:46:43 INFO - 'USER': 'cltbld', 18:46:43 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1463794583.192789-567490163', 18:46:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:46:43 INFO - '_': '/tools/buildbot/bin/python'} 18:46:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:46:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:46:44 INFO - Running setup.py (path:/tmp/pip-Bbfo_w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:46:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:46:44 INFO - Running setup.py (path:/tmp/pip-pRj_l8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:46:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:46:44 INFO - Running setup.py (path:/tmp/pip-ufFHUq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:46:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:46:44 INFO - Running setup.py (path:/tmp/pip-5Jc9py-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:46:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:46:44 INFO - Running setup.py (path:/tmp/pip-2X0P3x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:46:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:46:44 INFO - Running setup.py (path:/tmp/pip-10_GMH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:46:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:46:44 INFO - Running setup.py (path:/tmp/pip-HnCwOx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:46:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:46:44 INFO - Running setup.py (path:/tmp/pip-7g80I2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:46:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:46:44 INFO - Running setup.py (path:/tmp/pip-3uh7N4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:46:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:46:44 INFO - Running setup.py (path:/tmp/pip-cfUXk5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:46:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:46:44 INFO - Running setup.py (path:/tmp/pip-NjMLME-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:46:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:46:44 INFO - Running setup.py (path:/tmp/pip-rwWlcK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:46:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:46:44 INFO - Running setup.py (path:/tmp/pip-FCd9u3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:46:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:46:44 INFO - Running setup.py (path:/tmp/pip-l_Yu1R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:46:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:46:44 INFO - Running setup.py (path:/tmp/pip-cq0aS4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:46:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:46:44 INFO - Running setup.py (path:/tmp/pip-e0N9mA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:46:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:46:44 INFO - Running setup.py (path:/tmp/pip-gldKAV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:46:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:46:44 INFO - Running setup.py install for manifestparser 18:46:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:46:45 INFO - Running setup.py install for mozcrash 18:46:45 INFO - Running setup.py install for mozdebug 18:46:45 INFO - Running setup.py install for mozdevice 18:46:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:46:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:46:45 INFO - Running setup.py install for mozfile 18:46:45 INFO - Running setup.py install for mozhttpd 18:46:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:46:45 INFO - Running setup.py install for mozinfo 18:46:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:46:45 INFO - Running setup.py install for mozInstall 18:46:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:46:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:46:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:46:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:46:45 INFO - Running setup.py install for mozleak 18:46:45 INFO - Running setup.py install for mozlog 18:46:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:46:45 INFO - Running setup.py install for moznetwork 18:46:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:46:46 INFO - Running setup.py install for mozprocess 18:46:46 INFO - Running setup.py install for mozprofile 18:46:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:46:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:46:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:46:46 INFO - Running setup.py install for mozrunner 18:46:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:46:46 INFO - Running setup.py install for mozscreenshot 18:46:46 INFO - Running setup.py install for moztest 18:46:46 INFO - Running setup.py install for mozversion 18:46:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:46:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:46:46 INFO - Cleaning up... 18:46:46 INFO - Return code: 0 18:46:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:46:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:46:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:46:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:46:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:46:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:46:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:46:46 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 0x7f4b46aabf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11529c0>, '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 0x11538c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1153d50>, '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': '7de08804244eb36560b44971000001c3-1463794583.192789-567490163', '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 18:46:46 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 18:46:46 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 18:46:46 INFO - Using env: {'DISPLAY': ':0', 18:46:46 INFO - 'HOME': '/home/cltbld', 18:46:46 INFO - 'LANG': 'en_US.UTF-8', 18:46:46 INFO - 'LANGUAGE': 'en_US:en', 18:46:46 INFO - 'LOGNAME': 'cltbld', 18:46:46 INFO - 'MAIL': '/var/mail/cltbld', 18:46:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:46:46 INFO - 'MOZ_NO_REMOTE': '1', 18:46:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:46:46 INFO - 'NO_EM_RESTART': '1', 18:46:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:46:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:46:46 INFO - 'PWD': '/builds/slave/test', 18:46:46 INFO - 'SHELL': '/bin/bash', 18:46:46 INFO - 'SHLVL': '1', 18:46:46 INFO - 'TERM': 'linux', 18:46:46 INFO - 'TMOUT': '86400', 18:46:46 INFO - 'USER': 'cltbld', 18:46:46 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1463794583.192789-567490163', 18:46:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:46:46 INFO - '_': '/tools/buildbot/bin/python'} 18:46:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:46:46 INFO - Running setup.py (path:/tmp/pip-MjhCLI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:46:46 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)) 18:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:46:46 INFO - Running setup.py (path:/tmp/pip-SQA621-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:46:46 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)) 18:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:46:46 INFO - Running setup.py (path:/tmp/pip-jLYe5c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:46:46 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)) 18:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:46:46 INFO - Running setup.py (path:/tmp/pip-YOBaTX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:46:47 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)) 18:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:46:47 INFO - Running setup.py (path:/tmp/pip-_X0Tsx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:46:47 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)) 18:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:46:47 INFO - Running setup.py (path:/tmp/pip-ooztku-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:46:47 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)) 18:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:46:47 INFO - Running setup.py (path:/tmp/pip-v5Cwqg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:46:47 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)) 18:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:46:47 INFO - Running setup.py (path:/tmp/pip-iObmJQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:46:47 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)) 18:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:46:47 INFO - Running setup.py (path:/tmp/pip-PGNVty-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:46:47 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)) 18:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:46:47 INFO - Running setup.py (path:/tmp/pip-FtSCID-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:46:47 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)) 18:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:46:47 INFO - Running setup.py (path:/tmp/pip-S9XZAV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:46:47 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)) 18:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:46:47 INFO - Running setup.py (path:/tmp/pip-f8i0tF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:46:47 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)) 18:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:46:47 INFO - Running setup.py (path:/tmp/pip-OOeB8g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:46:47 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)) 18:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:46:47 INFO - Running setup.py (path:/tmp/pip-qKdNCS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:46:47 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)) 18:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:46:47 INFO - Running setup.py (path:/tmp/pip-5lwI_r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:46:47 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)) 18:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:46:47 INFO - Running setup.py (path:/tmp/pip-btMgeG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:46:47 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)) 18:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:46:47 INFO - Running setup.py (path:/tmp/pip-T0eS0a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:46:47 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)) 18:46:47 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)) 18:46:47 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)) 18:46:47 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)) 18:46:47 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)) 18:46:47 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)) 18:46:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:46:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:46:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:46:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:46:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:46:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:46:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:46:49 INFO - Downloading blessings-1.6.tar.gz 18:46:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:46:49 INFO - Installing collected packages: blessings 18:46:49 INFO - Running setup.py install for blessings 18:46:49 INFO - Successfully installed blessings 18:46:49 INFO - Cleaning up... 18:46:49 INFO - Return code: 0 18:46:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:46:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:46:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:46:50 INFO - Reading from file tmpfile_stdout 18:46:50 INFO - Current package versions: 18:46:50 INFO - argparse == 1.2.1 18:46:50 INFO - blessings == 1.6 18:46:50 INFO - blobuploader == 1.2.4 18:46:50 INFO - docopt == 0.6.1 18:46:50 INFO - manifestparser == 1.1 18:46:50 INFO - mozInstall == 1.12 18:46:50 INFO - mozcrash == 0.17 18:46:50 INFO - mozdebug == 0.1 18:46:50 INFO - mozdevice == 0.48 18:46:50 INFO - mozfile == 1.2 18:46:50 INFO - mozhttpd == 0.7 18:46:50 INFO - mozinfo == 0.9 18:46:50 INFO - mozleak == 0.1 18:46:50 INFO - mozlog == 3.2 18:46:50 INFO - moznetwork == 0.27 18:46:50 INFO - mozprocess == 0.22 18:46:50 INFO - mozprofile == 0.28 18:46:50 INFO - mozrunner == 6.11 18:46:50 INFO - mozscreenshot == 0.1 18:46:50 INFO - mozsystemmonitor == 0.3 18:46:50 INFO - moztest == 0.7 18:46:50 INFO - mozversion == 1.4 18:46:50 INFO - psutil == 3.1.1 18:46:50 INFO - requests == 1.2.3 18:46:50 INFO - wsgiref == 0.1.2 18:46:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:46:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:46:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:46:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:46:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:46:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:46:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:46:50 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 0x7f4b46aabf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11529c0>, '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 0x11538c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1153d50>, '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': '7de08804244eb36560b44971000001c3-1463794583.192789-567490163', '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 18:46:50 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 18:46:50 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 18:46:50 INFO - Using env: {'DISPLAY': ':0', 18:46:50 INFO - 'HOME': '/home/cltbld', 18:46:50 INFO - 'LANG': 'en_US.UTF-8', 18:46:50 INFO - 'LANGUAGE': 'en_US:en', 18:46:50 INFO - 'LOGNAME': 'cltbld', 18:46:50 INFO - 'MAIL': '/var/mail/cltbld', 18:46:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:46:50 INFO - 'MOZ_NO_REMOTE': '1', 18:46:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:46:50 INFO - 'NO_EM_RESTART': '1', 18:46:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:46:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:46:50 INFO - 'PWD': '/builds/slave/test', 18:46:50 INFO - 'SHELL': '/bin/bash', 18:46:50 INFO - 'SHLVL': '1', 18:46:50 INFO - 'TERM': 'linux', 18:46:50 INFO - 'TMOUT': '86400', 18:46:50 INFO - 'USER': 'cltbld', 18:46:50 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1463794583.192789-567490163', 18:46:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:46:50 INFO - '_': '/tools/buildbot/bin/python'} 18:46:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:46:50 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)) 18:46:50 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)) 18:46:50 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)) 18:46:50 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)) 18:46:50 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)) 18:46:50 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)) 18:46:50 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)) 18:46:50 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)) 18:46:50 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)) 18:46:50 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)) 18:46:50 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)) 18:46:50 INFO - Cleaning up... 18:46:50 INFO - Return code: 0 18:46:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:46:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:46:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:46:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:46:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:46:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:46:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:46:50 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 0x7f4b46aabf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11529c0>, '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 0x11538c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1153d50>, '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': '7de08804244eb36560b44971000001c3-1463794583.192789-567490163', '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 18:46:50 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 18:46:50 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 18:46:50 INFO - Using env: {'DISPLAY': ':0', 18:46:50 INFO - 'HOME': '/home/cltbld', 18:46:50 INFO - 'LANG': 'en_US.UTF-8', 18:46:50 INFO - 'LANGUAGE': 'en_US:en', 18:46:50 INFO - 'LOGNAME': 'cltbld', 18:46:50 INFO - 'MAIL': '/var/mail/cltbld', 18:46:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:46:50 INFO - 'MOZ_NO_REMOTE': '1', 18:46:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:46:50 INFO - 'NO_EM_RESTART': '1', 18:46:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:46:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:46:50 INFO - 'PWD': '/builds/slave/test', 18:46:50 INFO - 'SHELL': '/bin/bash', 18:46:50 INFO - 'SHLVL': '1', 18:46:50 INFO - 'TERM': 'linux', 18:46:50 INFO - 'TMOUT': '86400', 18:46:50 INFO - 'USER': 'cltbld', 18:46:50 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1463794583.192789-567490163', 18:46:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:46:50 INFO - '_': '/tools/buildbot/bin/python'} 18:46:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:46:50 INFO - Downloading/unpacking jsonschema 18:46:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:46:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:46:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:46:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:46:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:46:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:46:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:46:52 INFO - Downloading/unpacking functools32 (from jsonschema) 18:46:52 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 18:46:52 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 18:46:52 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 18:46:52 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 18:46:52 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 18:46:52 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 18:46:52 INFO - Downloading functools32-3.2.3-2.tar.gz 18:46:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:46:52 INFO - warning: no files found matching '*.txt' 18:46:52 INFO - no previously-included directories found matching 'build' 18:46:52 INFO - no previously-included directories found matching 'dist' 18:46:52 INFO - no previously-included directories found matching '.git*' 18:46:52 INFO - Installing collected packages: jsonschema, functools32 18:46:52 INFO - Running setup.py install for functools32 18:46:52 INFO - warning: no files found matching '*.txt' 18:46:52 INFO - no previously-included directories found matching 'build' 18:46:52 INFO - no previously-included directories found matching 'dist' 18:46:52 INFO - no previously-included directories found matching '.git*' 18:46:52 INFO - Successfully installed jsonschema functools32 18:46:52 INFO - Cleaning up... 18:46:52 INFO - Return code: 0 18:46:52 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 18:46:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:46:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:46:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:46:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:46:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:46:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:46:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f4b46aabf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11529c0>, '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 0x11538c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1153d50>, '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': '7de08804244eb36560b44971000001c3-1463794583.192789-567490163', '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 18:46:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 18:46:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 18:46:52 INFO - Using env: {'DISPLAY': ':0', 18:46:52 INFO - 'HOME': '/home/cltbld', 18:46:52 INFO - 'LANG': 'en_US.UTF-8', 18:46:52 INFO - 'LANGUAGE': 'en_US:en', 18:46:52 INFO - 'LOGNAME': 'cltbld', 18:46:52 INFO - 'MAIL': '/var/mail/cltbld', 18:46:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:46:52 INFO - 'MOZ_NO_REMOTE': '1', 18:46:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:46:52 INFO - 'NO_EM_RESTART': '1', 18:46:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:46:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:46:52 INFO - 'PWD': '/builds/slave/test', 18:46:52 INFO - 'SHELL': '/bin/bash', 18:46:52 INFO - 'SHLVL': '1', 18:46:52 INFO - 'TERM': 'linux', 18:46:52 INFO - 'TMOUT': '86400', 18:46:52 INFO - 'USER': 'cltbld', 18:46:52 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1463794583.192789-567490163', 18:46:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:46:52 INFO - '_': '/tools/buildbot/bin/python'} 18:46:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:46:52 INFO - Downloading/unpacking flake8 18:46:52 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 18:46:52 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 18:46:52 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 18:46:52 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 18:46:52 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 18:46:52 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 18:46:54 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:46:54 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:46:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:46:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:46:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:46:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:46:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:46:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:46:54 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:46:55 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:46:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:46:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:46:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:46:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:46:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:46:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:46:55 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:46:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:46:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:46:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:46:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:46:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:46:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:46:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 18:46:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:46:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:46:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:46:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:46:55 INFO - no previously-included directories found matching 'docs/_build' 18:46:55 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:46:55 INFO - Running setup.py install for pep8 18:46:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:46:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:46:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:46:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:46:55 INFO - no previously-included directories found matching 'docs/_build' 18:46:55 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 18:46:55 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:46:55 INFO - Cleaning up... 18:46:55 INFO - Return code: 0 18:46:55 INFO - Running post-action listener: _resource_record_post_action 18:46:55 INFO - Running post-action listener: _start_resource_monitoring 18:46:55 INFO - Starting resource monitoring. 18:46:55 INFO - ##### 18:46:55 INFO - ##### Finished create-virtualenv step (success) 18:46:55 INFO - ##### 18:46:55 INFO - ##### 18:46:55 INFO - ##### Running install step. 18:46:55 INFO - ##### 18:46:55 INFO - Running pre-action listener: _resource_record_pre_action 18:46:55 INFO - Running main action method: install 18:46:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:46:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:46:55 INFO - Reading from file tmpfile_stdout 18:46:55 INFO - Detecting whether we're running mozinstall >=1.0... 18:46:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:46:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:46:56 INFO - Reading from file tmpfile_stdout 18:46:56 INFO - Output received: 18:46:56 INFO - Usage: mozinstall [options] installer 18:46:56 INFO - Options: 18:46:56 INFO - -h, --help show this help message and exit 18:46:56 INFO - -d DEST, --destination=DEST 18:46:56 INFO - Directory to install application into. [default: 18:46:56 INFO - "/builds/slave/test"] 18:46:56 INFO - --app=APP Application being installed. [default: firefox] 18:46:56 INFO - mkdir: /builds/slave/test/build/application 18:46:56 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'] 18:46:56 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 18:47:11 INFO - Reading from file tmpfile_stdout 18:47:11 INFO - Output received: 18:47:11 INFO - /builds/slave/test/build/application/firefox/firefox 18:47:11 INFO - Running post-action listener: _resource_record_post_action 18:47:11 INFO - ##### 18:47:11 INFO - ##### Finished install step (success) 18:47:11 INFO - ##### 18:47:11 INFO - ##### 18:47:11 INFO - ##### Running run-tests step. 18:47:11 INFO - ##### 18:47:11 INFO - Running pre-action listener: _resource_record_pre_action 18:47:11 INFO - Running main action method: run_tests 18:47:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:47:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:47:11 INFO - Python 2.7.3 18:47:11 INFO - Return code: 0 18:47:11 INFO - grabbing minidump binary from tooltool 18:47:11 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')]} 18:47:11 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 0x11529c0>, '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 0x11538c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1153d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:47:11 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 18:47:11 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 18:47:11 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 18:47:11 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:47:11 INFO - Return code: 0 18:47:11 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:47:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:47:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:47:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:47:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:47:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 18:47:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 18:47:12 INFO - Return code: 0 18:47:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/L7LKINAWSi-pcRqJtSMDWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--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 18:47:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/L7LKINAWSi-pcRqJtSMDWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-003 --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 18:47:12 INFO - Using env: {'DISPLAY': ':0', 18:47:12 INFO - 'HOME': '/home/cltbld', 18:47:12 INFO - 'LANG': 'en_US.UTF-8', 18:47:12 INFO - 'LANGUAGE': 'en_US:en', 18:47:12 INFO - 'LOGNAME': 'cltbld', 18:47:12 INFO - 'MAIL': '/var/mail/cltbld', 18:47:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:47:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:47:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:12 INFO - 'MOZ_NO_REMOTE': '1', 18:47:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:47:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:47:12 INFO - 'NO_EM_RESTART': '1', 18:47:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:47:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:12 INFO - 'PWD': '/builds/slave/test', 18:47:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:47:12 INFO - 'SHELL': '/bin/bash', 18:47:12 INFO - 'SHLVL': '1', 18:47:12 INFO - 'TERM': 'linux', 18:47:12 INFO - 'TMOUT': '86400', 18:47:12 INFO - 'USER': 'cltbld', 18:47:12 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1463794583.192789-567490163', 18:47:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:12 INFO - '_': '/tools/buildbot/bin/python'} 18:47:12 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/L7LKINAWSi-pcRqJtSMDWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--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 18:47:13 INFO - mozversion application_buildid: 20160520181836 18:47:13 INFO - mozversion application_changeset: 06d9362a57a10396f3f7c7989c21f1a416f4c7e9 18:47:13 INFO - mozversion application_display_name: Nightly 18:47:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:47:13 INFO - mozversion application_name: Firefox 18:47:13 INFO - mozversion application_remotingname: firefox 18:47:13 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 18:47:13 INFO - mozversion application_vendor: Mozilla 18:47:13 INFO - mozversion application_version: 49.0a1 18:47:13 INFO - mozversion platform_buildid: 20160520181836 18:47:13 INFO - mozversion platform_changeset: 06d9362a57a10396f3f7c7989c21f1a416f4c7e9 18:47:13 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 18:47:13 INFO - mozversion platform_version: 49.0a1 18:47:13 INFO - using testdate: 1463795233 18:47:13 INFO - actual date: 1463795233 18:47:13 INFO - starting webserver on 'localhost:48388' 18:47:13 INFO - SUITE-START | Running 2 tests 18:47:13 INFO - TEST-START | dromaeo_css 18:47:13 INFO - operating with platform_type : linux_ 18:47:13 INFO - Initialising browser for dromaeo_css test... 18:47:13 INFO - TEST-INFO | started process 4741 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpI2cPMJ/profile http://localhost:48388/getInfo.html) 18:47:17 INFO - PROCESS | 4741 | __metrics Screen width/height:1600/1200 18:47:17 INFO - PROCESS | 4741 | colorDepth:24 18:47:17 INFO - PROCESS | 4741 | Browser inner width/height: 1024/697 18:47:17 INFO - PROCESS | 4741 | __metrics 18:47:18 ERROR - PROCESS | 4741 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:47:19 INFO - TEST-INFO | 4741: exit 0 18:47:19 INFO - Browser initialized. 18:47:19 INFO - Running cycle 1/1 for dromaeo_css test... 18:47:19 INFO - TEST-INFO | started process 4822 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpI2cPMJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:47:30 INFO - PROCESS | 4822 | RSS: Main: 153001984 18:47:30 INFO - PROCESS | 4822 | 18:48:22 INFO - PROCESS | 4822 | 1463795302425 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] 18:50:33 INFO - PROCESS | 4822 | Cycle 1(1): loaded http://localhost:48388/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48388/tests/dromaeo/cssquery-ext.html) 18:50:35 INFO - PROCESS | 4822 | RSS: Main: 258822144 18:50:35 INFO - PROCESS | 4822 | 18:53:37 INFO - PROCESS | 4822 | Cycle 1(1): loaded http://localhost:48388/tests/dromaeo/cssquery-ext.html (next: http://localhost:48388/tests/dromaeo/cssquery-jquery.html) 18:53:38 INFO - PROCESS | 4822 | RSS: Main: 233693184 18:53:38 INFO - PROCESS | 4822 | 18:56:40 INFO - PROCESS | 4822 | Cycle 1(1): loaded http://localhost:48388/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48388/tests/dromaeo/cssquery-mootools.html) 18:56:41 INFO - PROCESS | 4822 | RSS: Main: 244404224 18:56:41 INFO - PROCESS | 4822 | 18:59:43 INFO - PROCESS | 4822 | Cycle 1(1): loaded http://localhost:48388/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48388/tests/dromaeo/cssquery-prototype.html) 18:59:43 INFO - PROCESS | 4822 | RSS: Main: 234278912 18:59:43 INFO - PROCESS | 4822 | 19:02:46 INFO - PROCESS | 4822 | Cycle 1(1): loaded http://localhost:48388/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48388/tests/dromaeo/cssquery-yui.html) 19:02:46 INFO - PROCESS | 4822 | RSS: Main: 210419712 19:02:46 INFO - PROCESS | 4822 | 19:05:49 INFO - PROCESS | 4822 | Cycle 1(1): loaded http://localhost:48388/tests/dromaeo/cssquery-yui.html (next: http://localhost:48388/tests/dromaeo/cssquery-dojo.html) 19:05:49 INFO - PROCESS | 4822 | RSS: Main: 268038144 19:05:49 INFO - PROCESS | 4822 | 19:05:49 INFO - PROCESS | 4822 | __start_tp_report 19:05:49 INFO - PROCESS | 4822 | _x_x_mozilla_page_load 19:05:49 INFO - PROCESS | 4822 | _x_x_mozilla_page_load_details 19:05:49 INFO - PROCESS | 4822 | |i|pagename|runs| 19:05:49 INFO - PROCESS | 4822 | |0;dojo.html;2657.933551661208;2664.040945259705;2668.7064422913477;2699.865006749662;2712.9593056104154;4451.154280686667;4515.503294637587;4690.437147542296;4767.713937163772;4850.32095506629;4674.7428891410955;4698.882527936803;4708.43498780146;4789.3294938708605;4793.520647935214;4299.204647140274;4307.547707490708;4337.566243375669;4378.62781663559;4481.775911204443;4322.005938634116;4385.912281754363;4405.977970110145;4413.6027757501815;4429.33559966006;4197.853075142355;4248.426462427564;4265.7440553566885;4318.4170137031415;4325.5025672047805;4218.49378074633;4238.173556156551;4250.128723611652;4283.807228674686;4286.142771445723;4261.616454519108;4283.357496375537;4295.634871035934;4307.396964424983;4324.13517296542;4438.578335058197;4451.488078870939;4480.6191473724775;4533.116042371739;4550.795214215368;4913.385826771653;4949.010197960422;4960.280759289919;4968.1554135797005;4978.253262010691;4913.852584422473;4957.752112394366;4999.250112483157;5015.899682006339;5037.168867136918;4556.179887620227;4669.089527542097;4728.4798672146035;4736.786844592001;4790.353302304214;5227.555657769056;5234.136367499396;5270.077736396115;5270.077736396115;5287.7884884604955;4779.569838714532;4967.528084831935;5011.423686276052;5014.749262536859;5063.1645778446145;4927.334809800703;5006.4492905780335;5018.99620075979;5026.145555255615;5027.245913113063;4894.412670479565;4926.482719314492;4952;4982.302477653131;4984.501549845059;4892.68197567157;4921.212605983025;4926.33494478248;4929.186684197068;4974.27872958419;4145.025918909054;4148.896277593084;4154.273002224599;4160.271952408317;4193.727407718488;3565.3404120237838;3617.6744093031753;3631.8184090795353;3665.87169449068;3724.7206459515646;4394.978025109854;4399.450068741407;4422.579854913778;4433.889152771142;4451.530994771718;5056.873578160576;5078.568321692624;5114.590832733373;5125.794968201305;5163.0190263849745;4271.145770845843;4283.421738065384;4301.870943871689;4360.215161271001;4363.192809330284;4406.559344065632;4414.09511050234;4423.336499525097;4429.136318417942;4498.145352383037;4592.862214133581;4713.622910167247;4720.173969555382;4741.431028276629;4763.428388593391;4939.110960027229;4968.105740966661;5039.496050394931;5048.621353398436;5060.746962651926;4662.090892276109;4739.857804265877;4742.952570474389;4747.430308363018;4750.809967601257;4806.951930480651;4920.237363208708;4937.086638971802;4976.0794253063295;5011.974940125276;5022.522860328263;5051.393832739947;5078;5079.6698214615935;5123.897522049613;4874.95125048745;4890.046440943982;4899.583535399531;4910.8281210157475;4954.207326827835;4730.810767569259;4837.177679794373;4887.218045112765;4913.361263035782;4967.801287948441;4793.352897358812;4834.00902814915;4867.3915760529935;4869.610431165427;4902.926456103232;4919.434265059421;4949.901001980013;4952.851414457572;5011.498850114959;5024.824131155538;4607.378003969556;4670.883227919329;4806.375171227718;4818.903621927471;4832.8791780205775 19:05:49 INFO - PROCESS | 4822 | |1;ext.html;552.8479668091277;553.9750711217995;568.4770011589337;575.2148317546549;584.217149020313;26837.597436038475;39492.80253598731;40222.39666404999;40587.59412405882;44693.7765311173;40815;41029;41184.38223426651;41297.793511032476;41306.38040429389;7345.77962661121;7630.694772209105;7803.8049048773955;7818.960905195466;7828.334591559725;10091.142252908512;10334.483275836215;10354.689359319194;10371.274010819247;10425.218108641882;10051.748706282366;10286.485675716222;10294;10301;10309.020643038937;7333.669984850694;9636.614535418576;10016.098551130397;10017.849732254022;10142.137918276956;9875;9982.800343993153;9986.650467233685;10017.148542373907;10053.899461005369;37872.43191352132;38232.23535529274;38400.42399364012;38416.42375364372;38460.23079538393;3013.909582712522;3121;3146.638136614296;3159.2259896325318;3183.522471629274;28901.27746806347;29027.41945161085;29442.558361624593;29570.26074348137;30030;3291.654376290479;3471.2363280078343;3480.6867381935494;3510.4383298672346;3522.947155792665;249171;250970;251777;251958;253438;33192.50211246833;53566.732166338916;54496.45503544993;54613;54721.45278547243;33310.50034249488;33745.66254337474;33781;33977.49033764496;33994.66005339915;11470.942645286887;11659.76680466386;11669.124815638692;11680.532778688927;11703.941480292715;8224.876626850602;8734.86897696535;8936.151065648754;8966.462012279284;9276.582553784961;7885.369170466349;7902.644381002869;7906.604669766489;7958.243966823143;7967.442279040528;3092.3969825884187;3137.748980081588;3157.4790159623403;3158.98420507896;3164.3987642348343;888.1873086126251;892.4199270474244;929.4516235421135;990.5443495991781;1008.5007921078966;27295.45409091807;27508.312292192855;27685.307867303076;28093.578596321073;28191.57712634312;923.9815203696023;936.9063093690577;974.3179774157976;1016.5577973581516;1034.990883888218;14745.92627036858;14923;15002.549923502313;15074.472393466396;15358.462453814063;383230;384585;385765;386750;388627;48777.5122248773;49326.75336623294;49530.50469495331;49548;49792.25311620328;1077.0737166037202;1135.8409822625003;1174.4714878304626;1178.1635039122318;1184.2302503372875;27046.72953270442;28321.43357132887;28528.144155675363;28977.7102228975;28995.420091598473;41747;41928.3710744333;42140;42341.78829105835;42507.78746106374;288848;289119.32642020774;289721;290327;290948;22086.447838804157;24299.63550546708;25122.24633261005;25269.74730252674;26319.4736105273;40689.796551017054;40854.52736517429;41048;41704.58295417128;41807.1638567233;35205.471917921765;35521.467177991835;35923.640763592026;36055.45916811198;36310.8184459076;34852.128696782784;35343.29313413769;35378.82310588431;35863.64136358603;36070.63929360673;34329.65670343264;35557.64442355647;35645.465318019735;35712.642873570934;36091;31071.533926991564;31755.20611984719;32120;32193.195170120933;32498;24550.263492095266;24794;25036.123735669164;25078.62382064307;25104.874475627505 19:05:49 INFO - PROCESS | 4822 | |2;jquery.html;359.9064243296742;361.8643008871673;364.24419329890475;374.2160174434559;375.75388120780883;15306.54080377589;16335.34658613654;16442.34230630775;16733.16334183289;16910.492685219433;15462.226888655578;16538.09040502772;16726.41457548986;16783.83216167841;16807.32770689171;4885.291632713245;5097.108006098936;5116.667416617926;5163.328767260251;5170.017696637627;3668.7798732076026;3829.2532956073437;5115.155999260136;5636.774529018855;5944.464998150165;5986.551008674345;5988.431099045605;5998.670073145975;6049.092636104598;6055.636661800284;5880.529557635379;5914.142449344842;5987.28152621686;6013.128096426038;6033.969830150843;3355.6308806031316;4985;5295.258663787073;5298.337707786527;5314.415414304423;19777.110030048683;19794.60410791776;19870.304539341054;19903.80096199034;20567.79432205689;1875.165551329659;2030.35028790787;2184.322859913432;2237.8433509654333;2255.4023183856293;12528.185667931555;12863.228206307558;12905.806412903814;13475.528356507573;13476.865231347758;1678.8343037481634;1701.9319227230897;1751.7197248440189;1760.4102625620433;1815.6459490399377;65779.67110164418;68194.65902670505;69231.30768692298;69334.30665693329;69883.65058174677;8489.490630562186;9653.22774178064;9822.017798220264;9858;9992.750181245527;13517.864821351857;13623.114497557626;13631.931840340734;13636.86363136356;13645.454181832814;6480.125183100221;6577.473802095821;6624.238212605612;6703.3296670332575;6768.72925083001;6184.752609895554;6194.256689197326;6234.314225435199;6246.406591373812;6250.374962503804;6048.395160484004;6185.134081228632;6189.319174890758;6209.627422354582;6298.622082675054;1586.8889177757446;1677.0859881364686;1766.1787268920014;1788.3830078622855;1809.1496996411665;806.3307454812492;875.605977310213;947.5877993072897;950.296780382512;989.9901000989898;15496.845031549765;16029.919850400673;16692;17007;17112.828871711372;857.2927334948693;860.165639329849;874.881890944727;886.9201771840566;918.7703074231442;9010.3242256831;9029.367944243972;9130.726078217664;9165.908340916505;9375.390599611004;378557;410124;417978;418888;423537;82828.58585707033;85434.14565854386;86190.56904715436;86387.56806215929;86685;910.321810251367;912.2792993535165;915.2449229385784;932.6176267730198;947.0103741590054;14274.500392486214;14353.92823035878;14407.495737649131;14541.563753087425;14878;33418.331633367685;33489;33577.66422335843;34024.29878506015;34056.1485962843;50859.491405086956;52175.73912130415;52634;52962;53373.19940200978;7091.964540177472;7235.457340699363;7462.850742985219;7529.8117547061765;7678.61606919663;8748.431351962103;10990.615328463466;11076.39079850616;11305;11595.246308989888;9577.952110239683;9590.424574525272;9846.753831154278;9855;10034.598616055277;9814.558344874355;9844.507774611098;9870.259730520096;9894.604215831574;10017.348872323275;9976.401415915068;9993.350432221881;10017.899821001989;10226.28416010872;10285.794284114425;7159.964200178966;7244;7456.217097204715;7609.733659321897;7624.618769061635;8569.614367353359;8782.209601135928;9621.663241786504;9878.308518403643;9939.403635781877 19:05:49 INFO - PROCESS | 4822 | |3;mootools.html;1281.2952875918243;1402.0746307437093;1424.8076509671193;1549.6280892585776;1748.2657283940737;6576.967115164423;7113.85772284554;7293.4894557381;7302.306280903305;7513.624318784053;7270.745523906664;7408.370288525482;7420.072490938633;7432.6655300511475;7459;1987.1852540458415;2018.0313449544228;2026.9087891044865;2029.086910890098;2031.3499680102373;2089.937301880946;2095.1933505600377;2108.641530939736;2131.9467013324715;2140.892955352226;2113.619548481273;2117.6505876530355;2118.7139736135578;2124.7768984256663;2131.1368895597307;2031.8171364577258;2036.175348983664;2037.7045328427444;2047.2834507922257;2052.127845665586;2023.61551305253;2034.745656792901;2066.6486697261503;2068.586282743457;2068.906899189525;7267.927320726778;7364.77905662831;7379.3727533159745;7497.10034795828;7611.65747541362;4366.759828209447;4466.776661166961;4488.192125417424;4504.121696269229;4505.774711264424;2837.744602985741;2849.786266030056;2885.639295088114;2910.8544572771275;2933.574631678395;879.705298724928;889.9777505562348;890.2610833008588;903.9502827344494;907.8774365460645;10537.209709271836;10600.62897798581;10661.360318380923;10679.893201067967;10681.198910081775;3989.601039895987;4028.1742242840733;4089.8568550100604;4149.294619914562;4160.687948403821;8262.297704695167;8286.544240066854;8311;8412;8430.831383372299;4362.411074504901;4382.890427739332;4387.648988080946;4393.231184542692;4408.6693497987135;3426.400379933502;3832.9425058624142;3876.5154355705536;3921.9019524511546;3963.722539422213;3292.9506057409158;3340.8329583520726;3362.9447950627446;3391.1013581400457;3410.2497450560836;1006.5420233793607;1006.6074231049896;1015.147276287907;1017.0490591297013;1020.1226944827442;673.2325149329768;674.5716470041559;687.594319351575;770.4837758701683;788.2629741192004;4821.734804585782;4900.632452566072;5003.074431230234;5036.622253331015;5066.392032956069;407.58426405066666;426.1391988183851;427.0221193466988;428.87420521132026;432.4723836918954;3307.074019274607;3313.5858017747782;3325.73394128474;3344.7825891317475;3450.810205438725;8962.775930601787;9047.638094476279;9215.124563166488;9614.83666175105;9730.902690973;9320.627174912928;9475.668351607661;9743.805123897624;9753.21974242059;9765.85351219718;6966.79099627012;7153.320434558708;7277.981082648327;7316.158641756159;7364.3003914628025;5072.492750724898;5134.409542902614;5297.17893726473;5425.050616142238;5453;8287.62705678234;8381.16188381157;8659.6103175356;8708.564571771512;8825.02924678272;8376.036755773164;8403.83192336162;8471.110533393905;8895.777605559913;8905.154010369004;5729.942700572941;6202.007678771375;6489.5781774184525;6547.574407663486;6639.900401493885;7294.234105418855;7573.166951635206;7590.468667193464;7604.695812167452;7619.847603048019;6469;6836.658167091725;6891.75878844238;6927.27263637331;6991.7902462926195;6585.506087043395;6673.299303573249;6678.332166783282;6773.356531129535;6801.149856267966;5940.346561878104;5962.850928726816;5974.4324289192455;5983.970080149571;6001.7599296027665;4286.571342865688;4385.320275357325;4390.890227744332;4455.26488129468;4461.933071004002;4375.299952007664;4440.7335559866515;4460;4460.665450091191;4501.662375321929 19:05:49 INFO - PROCESS | 4822 | |4;prototype.html;283.54915684062337;288.0695354006559;292.32181339292845;297.12200447677117;297.4586253019505;4619.099275641247;4658.97502549439;4661.627069834413;4667.20657488227;4672.556107169813;4626.935804764906;4638.976805115978;4640.582347588716;4641.512641172679;4665.603423708981;3504.6320136385693;3600.567931848169;3626.6192049834785;3633.073566240605;3634.1459756957092;3528.3648943190215;3615.9276814463697;3624.2207925296057;3661.835217415209;3688.79711615861;3599.1362073102396;3612.489319922655;3655.342038433081;3665.321915445638;3674.375356189441;3485.1809824691177;3628.4194528875387;3628.891133265993;3632.691221246197;3660.4875317455576;3247.7888937219004;3250.8537115829845;3255.235019770352;3259;3262.5269335946155;4396.406485124499;4404.581564751344;4407.867763967054;4431;4454.754988475633;4170.207660544487;4182.163567286555;4196.328587425997;4196.832126714928;4216.789160541961;4551.476580193287;4817.205161148407;4820.855374338776;4824.179889418772;4831.22700367943;3519.9296014079832;3526.1008442847165;3535.2045789697368;3543.291341731638;3546.6522721365973;5041.495850414965;5062.531865758925;5063.11395505785;5090.2364645303505;5098.719570423626;3576.177479179751;3672;3673.632636736305;3684.7604905681046;3689.5203623529305;3694.910001549549;3709.666130048309;3710.22085362077;3723.9069023274633;3733.1227161617;4120.175964806991;4125.546189919107;4132.669386449077;4144.129732756155;4170.645495132948;4789.688670236493;4795.136875362398;4803.18345881201;4831.323614693945;4863.392075990501;1250.8179770117151;1252.3800718644147;1259.5865205293574;1289.8516670583003;1291.877271659191;804.4449329962307;827.5986146718836;829.8008477965366;831.1107115386599;834.5910503853069;4150.896227594274;4248.745075295492;4332.978335108367;4351.369051487517;4351.540525282408;4912.189488734391;4958.256261560795;4961.751912404366;4988.476209997898;5005.27423523587;4335.479742430865;4359.367891655692;4363.80362883671;4365.2797288447055;4375;2487.3905893055953;2608.804339674532;2680.32991752062;2681.8122731408616;2689.9865500672763;4792.065547218329;4795.976020119947;4798.088363210979;4802.303665968415;4823.324734537167;5147.96655321146;5152.458991805881;5160.870978225575;5161.380634323905;5167.560757335668;4582.816687332536;4782.641301902371;4794.616430685467;4796.712197268175;4804.159272127434;5005.049040682259;5005.974970125126;5011.223260394645;5037.622178336566;5040.722760248222;4905.852824415273;4928.556429921181;4930.630202734737;4944.901101978012;4946.950530494649;4540.387047748512;4720.914189736449;4721.622270218443;4764.190087685032;4812.470628230822;3534.4698295255916;3554.893353199408;3559.1635965547557;3567.4292113261754;3582.194006348655;4959.62802789785;4996.575291100297;5002.424721156968;5006.824761133343;5011.824586139468;4734.289856521549;4847.151748443937;4852.878678033077;4863.34344863453;4867.318575399376;4811.109944660249;4817.542333478314;4822.324874517501;4823.2282834747675;4835.734034627989;4763.785629646605;4767.165745994506;4787.473377928494;4789.353437285922;4804.5195480451675;4712.505186955457;4797.304390863235;4822.348982887265;4829.17493266443;4881.4142302922455;4755.144074066563;4756.571908528249;4764.237721964469;4804.711717296974;4817.927731084106 19:05:49 INFO - PROCESS | 4822 | |5;yui.html;623.66945518875;684.0730809752788;728.9088863892014;747.6710247491106;758.1584441270193;11325.943370283147;12044.096692748046;12056.095792815551;12089.214201076924;12090.455929483183;11692.532298708042;12148.69628259294;12155.209911355756;12211.755764884698;12243.938780306109;619.7892716476397;752.345459450279;762.6072572625103;772.7836205862368;777.6772639354662;775.9883601745978;776.6311002273931;779.4972242903324;780.2002946979335;782.8669126248523;816.346922462031;817.8037271054964;818.2880893622527;818.7502811642447;819.7458787775779;649.1949982022282;792.5324058805332;811.3590263691676;812.3622955979557;814.1817473439156;587.8207146820218;588.0825911577953;588.0855270055063;589.1310317282017;589.5165963909553;11205.943970280096;11236.831447528293;11244.212905096647;11248.606298779585;11291.548338066468;11506.539738410453;11879.762404751942;11885.702857428547;12130.211536250115;12357;10589.523471443803;10774.892251077466;10830.72923176919;10925.836112458319;10936.671899843017;4003.919921601581;4026.5168179818315;4076.8980775480554;4077.612626800449;4125.092479654471;43782.56217437817;45701.542984570064;45845;46156.769216153705;46163.76918115455;9435.575399107056;9603.519824008772;9635.614575417045;9737.367071140354;9929.80140397188;17031;17047;17074.914625426794;17087.401940932006;17098.658026839392;6334.3032266450655;6453.128827608213;6492.415682588589;6508.349165083454;6608.306127856506;3552.0587044433246;3569.089882080105;3572.857085716594;3596.2088340565033;3641.5812181599454;2607.10055031014;2686.0464535090155;2687.4087700706204;2691.542437785581;2710.146303914261;463.98376056838526;475.34165181224256;476.98807529811336;483.4223103391484;484.8327327072154;511.9667221630582;529.6980720989;540.7269328988835;549.8625343664085;618.52373672273;7840.41196910234;8147.714829980922;8420.200080992296;8448.57757112142;8507.489550626859;512.2060805751146;569.5301376364516;601.5969300568541;622.18804460179;623.1680706257198;3863.787491687984;3876.7673939563715;3955.149642826806;4033.193361327746;4049.8987525312104;39147.60852391497;53023.46976530185;53154.46845531573;53523.464765352626;53539;46845.531544684796;48662.75668621705;49037.75481122643;49272.260916086285;50503.74748126309;43484.78257608692;43996.120077598905;44879.32681009722;44929.3042417456;45635;7011.929880701127;7019.859602807813;7230.349268565854;7290.3438690518105;7459.254074592497;27012.32469188286;27214.183574492796;28772.856135719187;30064.699353007065;30410.695893041673;38273.425898610985;38359;38683;38753.612463875;39158;10529.210309226686;10867.29362591429;10896.128309735044;10987.450627468435;11072.335659860435;11684.532618695497;11689.357085360387;11873.287602743863;12088.697782555155;12225.44985475638;10634.574617015542;11005.064569511682;11121.221514493907;11232.101431885267;11697;11234.438278086227;11516.366599836754;11522.76954460923;11793.115516336138;11838.28970261787;10789.62236321725;10940.617078402218;10968.835467467834;11218.326900386002;11538.250013749079;8810.779730506789;9249.907500925174;9643.180329672057;9724.367916085432;9921.385212458721;9059.682911098344;9087.863682044643;9211.907880921104;9751.951240243754;9776.818286308267 19:05:49 INFO - PROCESS | 4822 | __end_tp_report 19:05:49 INFO - PROCESS | 4822 | __start_cc_report 19:05:49 INFO - PROCESS | 4822 | _x_x_mozilla_cycle_collect,933 19:05:49 INFO - PROCESS | 4822 | __end_cc_report 19:05:49 INFO - PROCESS | 4822 | __startTimestamp1463796349771__endTimestamp 19:05:49 INFO - PROCESS | 4822 | 19:05:49 INFO - PROCESS | 4822 | ------- Summary: start ------- 19:05:49 INFO - PROCESS | 4822 | Number of tests: 6 19:05:49 INFO - PROCESS | 4822 | 19:05:49 INFO - PROCESS | 4822 | [#0] dojo.html Cycles:165 Average:4628.61 Median:4763.43 stddev:490.04 (10.3%) stddev-sans-first:466.52 19:05:49 INFO - PROCESS | 4822 | Values: 2657.9 2664.0 2668.7 2699.9 2713.0 4451.2 4515.5 4690.4 4767.7 4850.3 4674.7 4698.9 4708.4 4789.3 4793.5 4299.2 4307.5 4337.6 4378.6 4481.8 4322.0 4385.9 4406.0 4413.6 4429.3 4197.9 4248.4 4265.7 4318.4 4325.5 4218.5 4238.2 4250.1 4283.8 4286.1 4261.6 4283.4 4295.6 4307.4 4324.1 4438.6 4451.5 4480.6 4533.1 4550.8 4913.4 4949.0 4960.3 4968.2 4978.3 4913.9 4957.8 4999.3 5015.9 5037.2 4556.2 4669.1 4728.5 4736.8 4790.4 5227.6 5234.1 5270.1 5270.1 5287.8 4779.6 4967.5 5011.4 5014.7 5063.2 4927.3 5006.4 5019.0 5026.1 5027.2 4894.4 4926.5 4952.0 4982.3 4984.5 4892.7 4921.2 4926.3 4929.2 4974.3 4145.0 4148.9 4154.3 4160.3 4193.7 3565.3 3617.7 3631.8 3665.9 3724.7 4395.0 4399.5 4422.6 4433.9 4451.5 5056.9 5078.6 5114.6 5125.8 5163.0 4271.1 4283.4 4301.9 4360.2 4363.2 4406.6 4414.1 4423.3 4429.1 4498.1 4592.9 4713.6 4720.2 4741.4 4763.4 4939.1 4968.1 5039.5 5048.6 5060.7 4662.1 4739.9 4743.0 4747.4 4750.8 4807.0 4920.2 4937.1 4976.1 5012.0 5022.5 5051.4 5078.0 5079.7 5123.9 4875.0 4890.0 4899.6 4910.8 4954.2 4730.8 4837.2 4887.2 4913.4 4967.8 4793.4 4834.0 4867.4 4869.6 4902.9 4919.4 4949.9 4952.9 5011.5 5024.8 4607.4 4670.9 4806.4 4818.9 4832.9 19:05:49 INFO - PROCESS | 4822 | 19:05:49 INFO - PROCESS | 4822 | [#1] ext.html Cycles:180 Average:45545.29 Median:34162.16 stddev:82754.07 (242.2%) stddev-sans-first:82917.27 19:05:49 INFO - PROCESS | 4822 | Values: 552.8 554.0 568.5 575.2 584.2 26837.6 39492.8 40222.4 40587.6 44693.8 40815.0 41029.0 41184.4 41297.8 41306.4 7345.8 7630.7 7803.8 7819.0 7828.3 10091.1 10334.5 10354.7 10371.3 10425.2 10051.7 10286.5 10294.0 10301.0 10309.0 7333.7 9636.6 10016.1 10017.8 10142.1 9875.0 9982.8 9986.7 10017.1 10053.9 37872.4 38232.2 38400.4 38416.4 38460.2 3013.9 3121.0 3146.6 3159.2 3183.5 28901.3 29027.4 29442.6 29570.3 30030.0 3291.7 3471.2 3480.7 3510.4 3522.9 249171.0 250970.0 251777.0 251958.0 253438.0 33192.5 53566.7 54496.5 54613.0 54721.5 33310.5 33745.7 33781.0 33977.5 33994.7 11470.9 11659.8 11669.1 11680.5 11703.9 8224.9 8734.9 8936.2 8966.5 9276.6 7885.4 7902.6 7906.6 7958.2 7967.4 3092.4 3137.7 3157.5 3159.0 3164.4 888.2 892.4 929.5 990.5 1008.5 27295.5 27508.3 27685.3 28093.6 28191.6 924.0 936.9 974.3 1016.6 1035.0 14745.9 14923.0 15002.5 15074.5 15358.5 383230.0 384585.0 385765.0 386750.0 388627.0 48777.5 49326.8 49530.5 49548.0 49792.3 1077.1 1135.8 1174.5 1178.2 1184.2 27046.7 28321.4 28528.1 28977.7 28995.4 41747.0 41928.4 42140.0 42341.8 42507.8 288848.0 289119.3 289721.0 290327.0 290948.0 22086.4 24299.6 25122.2 25269.7 26319.5 40689.8 40854.5 41048.0 41704.6 41807.2 35205.5 35521.5 35923.6 36055.5 36310.8 34852.1 35343.3 35378.8 35863.6 36070.6 34329.7 35557.6 35645.5 35712.6 36091.0 31071.5 31755.2 32120.0 32193.2 32498.0 24550.3 24794.0 25036.1 25078.6 25104.9 19:05:49 INFO - PROCESS | 4822 | 19:05:49 INFO - PROCESS | 4822 | [#2] jquery.html Cycles:180 Average:24949.74 Median:29343.81 stddev:67792.90 (231.0%) stddev-sans-first:67957.94 19:05:49 INFO - PROCESS | 4822 | Values: 359.9 361.9 364.2 374.2 375.8 15306.5 16335.3 16442.3 16733.2 16910.5 15462.2 16538.1 16726.4 16783.8 16807.3 4885.3 5097.1 5116.7 5163.3 5170.0 3668.8 3829.3 5115.2 5636.8 5944.5 5986.6 5988.4 5998.7 6049.1 6055.6 5880.5 5914.1 5987.3 6013.1 6034.0 3355.6 4985.0 5295.3 5298.3 5314.4 19777.1 19794.6 19870.3 19903.8 20567.8 1875.2 2030.4 2184.3 2237.8 2255.4 12528.2 12863.2 12905.8 13475.5 13476.9 1678.8 1701.9 1751.7 1760.4 1815.6 65779.7 68194.7 69231.3 69334.3 69883.7 8489.5 9653.2 9822.0 9858.0 9992.8 13517.9 13623.1 13631.9 13636.9 13645.5 6480.1 6577.5 6624.2 6703.3 6768.7 6184.8 6194.3 6234.3 6246.4 6250.4 6048.4 6185.1 6189.3 6209.6 6298.6 1586.9 1677.1 1766.2 1788.4 1809.1 806.3 875.6 947.6 950.3 990.0 15496.8 16029.9 16692.0 17007.0 17112.8 857.3 860.2 874.9 886.9 918.8 9010.3 9029.4 9130.7 9165.9 9375.4 378557.0 410124.0 417978.0 418888.0 423537.0 82828.6 85434.1 86190.6 86387.6 86685.0 910.3 912.3 915.2 932.6 947.0 14274.5 14353.9 14407.5 14541.6 14878.0 33418.3 33489.0 33577.7 34024.3 34056.1 50859.5 52175.7 52634.0 52962.0 53373.2 7092.0 7235.5 7462.9 7529.8 7678.6 8748.4 10990.6 11076.4 11305.0 11595.2 9578.0 9590.4 9846.8 9855.0 10034.6 9814.6 9844.5 9870.3 9894.6 10017.3 9976.4 9993.4 10017.9 10226.3 10285.8 7160.0 7244.0 7456.2 7609.7 7624.6 8569.6 8782.2 9621.7 9878.3 9939.4 19:05:49 INFO - PROCESS | 4822 | 19:05:49 INFO - PROCESS | 4822 | [#3] mootools.html Cycles:180 Average:4942.58 Median:4861.18 stddev:2829.28 (58.2%) stddev-sans-first:2823.84 19:05:49 INFO - PROCESS | 4822 | Values: 1281.3 1402.1 1424.8 1549.6 1748.3 6577.0 7113.9 7293.5 7302.3 7513.6 7270.7 7408.4 7420.1 7432.7 7459.0 1987.2 2018.0 2026.9 2029.1 2031.3 2089.9 2095.2 2108.6 2131.9 2140.9 2113.6 2117.7 2118.7 2124.8 2131.1 2031.8 2036.2 2037.7 2047.3 2052.1 2023.6 2034.7 2066.6 2068.6 2068.9 7267.9 7364.8 7379.4 7497.1 7611.7 4366.8 4466.8 4488.2 4504.1 4505.8 2837.7 2849.8 2885.6 2910.9 2933.6 879.7 890.0 890.3 904.0 907.9 10537.2 10600.6 10661.4 10679.9 10681.2 3989.6 4028.2 4089.9 4149.3 4160.7 8262.3 8286.5 8311.0 8412.0 8430.8 4362.4 4382.9 4387.6 4393.2 4408.7 3426.4 3832.9 3876.5 3921.9 3963.7 3293.0 3340.8 3362.9 3391.1 3410.2 1006.5 1006.6 1015.1 1017.0 1020.1 673.2 674.6 687.6 770.5 788.3 4821.7 4900.6 5003.1 5036.6 5066.4 407.6 426.1 427.0 428.9 432.5 3307.1 3313.6 3325.7 3344.8 3450.8 8962.8 9047.6 9215.1 9614.8 9730.9 9320.6 9475.7 9743.8 9753.2 9765.9 6966.8 7153.3 7278.0 7316.2 7364.3 5072.5 5134.4 5297.2 5425.1 5453.0 8287.6 8381.2 8659.6 8708.6 8825.0 8376.0 8403.8 8471.1 8895.8 8905.2 5729.9 6202.0 6489.6 6547.6 6639.9 7294.2 7573.2 7590.5 7604.7 7619.8 6469.0 6836.7 6891.8 6927.3 6991.8 6585.5 6673.3 6678.3 6773.4 6801.1 5940.3 5962.9 5974.4 5984.0 6001.8 4286.6 4385.3 4390.9 4455.3 4461.9 4375.3 4440.7 4460.0 4460.7 4501.7 19:05:49 INFO - PROCESS | 4822 | 19:05:49 INFO - PROCESS | 4822 | [#4] prototype.html Cycles:180 Average:4053.47 Median:4639.78 stddev:1166.32 (25.1%) stddev-sans-first:1134.75 19:05:49 INFO - PROCESS | 4822 | Values: 283.5 288.1 292.3 297.1 297.5 4619.1 4659.0 4661.6 4667.2 4672.6 4626.9 4639.0 4640.6 4641.5 4665.6 3504.6 3600.6 3626.6 3633.1 3634.1 3528.4 3615.9 3624.2 3661.8 3688.8 3599.1 3612.5 3655.3 3665.3 3674.4 3485.2 3628.4 3628.9 3632.7 3660.5 3247.8 3250.9 3255.2 3259.0 3262.5 4396.4 4404.6 4407.9 4431.0 4454.8 4170.2 4182.2 4196.3 4196.8 4216.8 4551.5 4817.2 4820.9 4824.2 4831.2 3519.9 3526.1 3535.2 3543.3 3546.7 5041.5 5062.5 5063.1 5090.2 5098.7 3576.2 3672.0 3673.6 3684.8 3689.5 3694.9 3709.7 3710.2 3723.9 3733.1 4120.2 4125.5 4132.7 4144.1 4170.6 4789.7 4795.1 4803.2 4831.3 4863.4 1250.8 1252.4 1259.6 1289.9 1291.9 804.4 827.6 829.8 831.1 834.6 4150.9 4248.7 4333.0 4351.4 4351.5 4912.2 4958.3 4961.8 4988.5 5005.3 4335.5 4359.4 4363.8 4365.3 4375.0 2487.4 2608.8 2680.3 2681.8 2690.0 4792.1 4796.0 4798.1 4802.3 4823.3 5148.0 5152.5 5160.9 5161.4 5167.6 4582.8 4782.6 4794.6 4796.7 4804.2 5005.0 5006.0 5011.2 5037.6 5040.7 4905.9 4928.6 4930.6 4944.9 4947.0 4540.4 4720.9 4721.6 4764.2 4812.5 3534.5 3554.9 3559.2 3567.4 3582.2 4959.6 4996.6 5002.4 5006.8 5011.8 4734.3 4847.2 4852.9 4863.3 4867.3 4811.1 4817.5 4822.3 4823.2 4835.7 4763.8 4767.2 4787.5 4789.4 4804.5 4712.5 4797.3 4822.3 4829.2 4881.4 4755.1 4756.6 4764.2 4804.7 4817.9 19:05:49 INFO - PROCESS | 4822 | 19:05:49 INFO - PROCESS | 4822 | [#5] yui.html Cycles:180 Average:12756.93 Median:45282.15 stddev:14560.08 (32.2%) stddev-sans-first:14572.41 19:05:49 INFO - PROCESS | 4822 | Values: 623.7 684.1 728.9 747.7 758.2 11325.9 12044.1 12056.1 12089.2 12090.5 11692.5 12148.7 12155.2 12211.8 12243.9 619.8 752.3 762.6 772.8 777.7 776.0 776.6 779.5 780.2 782.9 816.3 817.8 818.3 818.8 819.7 649.2 792.5 811.4 812.4 814.2 587.8 588.1 588.1 589.1 589.5 11205.9 11236.8 11244.2 11248.6 11291.5 11506.5 11879.8 11885.7 12130.2 12357.0 10589.5 10774.9 10830.7 10925.8 10936.7 4003.9 4026.5 4076.9 4077.6 4125.1 43782.6 45701.5 45845.0 46156.8 46163.8 9435.6 9603.5 9635.6 9737.4 9929.8 17031.0 17047.0 17074.9 17087.4 17098.7 6334.3 6453.1 6492.4 6508.3 6608.3 3552.1 3569.1 3572.9 3596.2 3641.6 2607.1 2686.0 2687.4 2691.5 2710.1 464.0 475.3 477.0 483.4 484.8 512.0 529.7 540.7 549.9 618.5 7840.4 8147.7 8420.2 8448.6 8507.5 512.2 569.5 601.6 622.2 623.2 3863.8 3876.8 3955.1 4033.2 4049.9 39147.6 53023.5 53154.5 53523.5 53539.0 46845.5 48662.8 49037.8 49272.3 50503.7 43484.8 43996.1 44879.3 44929.3 45635.0 7011.9 7019.9 7230.3 7290.3 7459.3 27012.3 27214.2 28772.9 30064.7 30410.7 38273.4 38359.0 38683.0 38753.6 39158.0 10529.2 10867.3 10896.1 10987.5 11072.3 11684.5 11689.4 11873.3 12088.7 12225.4 10634.6 11005.1 11121.2 11232.1 11697.0 11234.4 11516.4 11522.8 11793.1 11838.3 10789.6 10940.6 10968.8 11218.3 11538.3 8810.8 9249.9 9643.2 9724.4 9921.4 9059.7 9087.9 9211.9 9752.0 9776.8 19:05:49 INFO - PROCESS | 4822 | -------- Summary: end -------- 19:05:49 INFO - PROCESS | 4822 | 19:05:50 INFO - TEST-INFO | 4822: exit 0 19:05:50 INFO - TEST-OK | dromaeo_css | took 1117308ms 19:05:50 INFO - TEST-START | kraken 19:05:50 INFO - operating with platform_type : linux_ 19:05:50 INFO - Initialising browser for kraken test... 19:05:50 INFO - TEST-INFO | started process 4999 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFfRV2E/profile http://localhost:48388/getInfo.html) 19:05:54 INFO - PROCESS | 4999 | __metrics Screen width/height:1600/1200 19:05:54 INFO - PROCESS | 4999 | colorDepth:24 19:05:54 INFO - PROCESS | 4999 | Browser inner width/height: 1024/697 19:05:54 INFO - PROCESS | 4999 | __metrics 19:05:55 ERROR - PROCESS | 4999 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 19:05:56 INFO - TEST-INFO | 4999: exit 0 19:05:56 INFO - Browser initialized. 19:05:56 INFO - Running cycle 1/1 for kraken test... 19:05:56 INFO - TEST-INFO | started process 5079 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFfRV2E/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:06:07 INFO - PROCESS | 5079 | RSS: Main: 154030080 19:06:07 INFO - PROCESS | 5079 | 19:06:10 INFO - PROCESS | 5079 | Cycle 1(1): loaded http://localhost:48388/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48388/tests/kraken/driver.html?testName=audio-beat-detection) 19:06:11 INFO - PROCESS | 5079 | RSS: Main: 209174528 19:06:11 INFO - PROCESS | 5079 | 19:06:14 INFO - PROCESS | 5079 | Cycle 1(1): loaded http://localhost:48388/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48388/tests/kraken/driver.html?testName=audio-dft) 19:06:15 INFO - PROCESS | 5079 | RSS: Main: 196595712 19:06:15 INFO - PROCESS | 5079 | 19:06:18 INFO - PROCESS | 5079 | Cycle 1(1): loaded http://localhost:48388/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48388/tests/kraken/driver.html?testName=audio-fft) 19:06:18 INFO - PROCESS | 5079 | RSS: Main: 266584064 19:06:18 INFO - PROCESS | 5079 | 19:06:21 INFO - PROCESS | 5079 | Cycle 1(1): loaded http://localhost:48388/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48388/tests/kraken/driver.html?testName=audio-oscillator) 19:06:21 INFO - PROCESS | 5079 | RSS: Main: 175517696 19:06:21 INFO - PROCESS | 5079 | 19:06:24 INFO - PROCESS | 5079 | Cycle 1(1): loaded http://localhost:48388/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48388/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:06:24 INFO - PROCESS | 5079 | RSS: Main: 179261440 19:06:24 INFO - PROCESS | 5079 | 19:06:27 INFO - PROCESS | 5079 | Cycle 1(1): loaded http://localhost:48388/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48388/tests/kraken/driver.html?testName=imaging-darkroom) 19:06:28 INFO - PROCESS | 5079 | RSS: Main: 215269376 19:06:28 INFO - PROCESS | 5079 | 19:06:32 INFO - PROCESS | 5079 | Cycle 1(1): loaded http://localhost:48388/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48388/tests/kraken/driver.html?testName=imaging-desaturate) 19:06:32 INFO - PROCESS | 5079 | RSS: Main: 214200320 19:06:32 INFO - PROCESS | 5079 | 19:06:35 INFO - PROCESS | 5079 | Cycle 1(1): loaded http://localhost:48388/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48388/tests/kraken/driver.html?testName=json-parse-financial) 19:06:36 INFO - PROCESS | 5079 | RSS: Main: 214708224 19:06:36 INFO - PROCESS | 5079 | 19:06:38 INFO - PROCESS | 5079 | Cycle 1(1): loaded http://localhost:48388/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48388/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:06:38 INFO - PROCESS | 5079 | RSS: Main: 209756160 19:06:38 INFO - PROCESS | 5079 | 19:06:40 INFO - PROCESS | 5079 | Cycle 1(1): loaded http://localhost:48388/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48388/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:06:41 INFO - PROCESS | 5079 | RSS: Main: 212656128 19:06:41 INFO - PROCESS | 5079 | 19:06:43 INFO - PROCESS | 5079 | Cycle 1(1): loaded http://localhost:48388/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48388/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:06:44 INFO - PROCESS | 5079 | RSS: Main: 218329088 19:06:44 INFO - PROCESS | 5079 | 19:06:47 INFO - PROCESS | 5079 | Cycle 1(1): loaded http://localhost:48388/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48388/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:06:48 INFO - PROCESS | 5079 | RSS: Main: 219774976 19:06:48 INFO - PROCESS | 5079 | 19:06:50 INFO - PROCESS | 5079 | Cycle 1(1): loaded http://localhost:48388/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48388/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:06:51 INFO - PROCESS | 5079 | RSS: Main: 219201536 19:06:51 INFO - PROCESS | 5079 | 19:06:53 INFO - PROCESS | 5079 | Cycle 1(1): loaded http://localhost:48388/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48388/tests/kraken/driver.html?testName=ai-astar) 19:06:53 INFO - PROCESS | 5079 | RSS: Main: 265764864 19:06:53 INFO - PROCESS | 5079 | 19:06:53 INFO - PROCESS | 5079 | __start_tp_report 19:06:53 INFO - PROCESS | 5079 | _x_x_mozilla_page_load 19:06:53 INFO - PROCESS | 5079 | _x_x_mozilla_page_load_details 19:06:53 INFO - PROCESS | 5079 | |i|pagename|runs| 19:06:53 INFO - PROCESS | 5079 | |0;ai-astar;116;125;117;116;115;116;115;115;116;115 19:06:53 INFO - PROCESS | 5079 | |1;audio-beat-detection;160;151;138;220;136;169;158;136;188;135 19:06:53 INFO - PROCESS | 5079 | |2;audio-dft;163;163;162;162;164;163;163;163;163;162 19:06:53 INFO - PROCESS | 5079 | |3;audio-fft;77;78;76;135;77;78;76;79;113;80 19:06:53 INFO - PROCESS | 5079 | |4;audio-oscillator;97;96;155;95;95;98;116;95;95;128 19:06:53 INFO - PROCESS | 5079 | |5;imaging-gaussian-blur;118;119;118;118;118;118;118;118;119;118 19:06:53 INFO - PROCESS | 5079 | |6;imaging-darkroom;149;148;149;148;148;148;148;147;148;148 19:06:53 INFO - PROCESS | 5079 | |7;imaging-desaturate;95;95;95;97;96;96;97;95;96;96 19:06:53 INFO - PROCESS | 5079 | |8;json-parse-financial;66;67;68;67;67;68;67;66;66;67 19:06:53 INFO - PROCESS | 5079 | |9;json-stringify-tinderbox;65;65;65;65;66;65;66;66;65;66 19:06:53 INFO - PROCESS | 5079 | |10;stanford-crypto-aes;89;87;87;88;87;88;88;88;174;88 19:06:53 INFO - PROCESS | 5079 | |11;stanford-crypto-ccm;144;137;148;140;136;135;144;137;163;156 19:06:53 INFO - PROCESS | 5079 | |12;stanford-crypto-pbkdf2;177;180;178;177;179;176;175;178;181;177 19:06:53 INFO - PROCESS | 5079 | |13;stanford-crypto-sha256-iterative;70;69;70;70;69;69;70;72;70;71 19:06:53 INFO - PROCESS | 5079 | __end_tp_report 19:06:53 INFO - PROCESS | 5079 | __start_cc_report 19:06:53 INFO - PROCESS | 5079 | _x_x_mozilla_cycle_collect,1967 19:06:53 INFO - PROCESS | 5079 | __end_cc_report 19:06:53 INFO - PROCESS | 5079 | __startTimestamp1463796413627__endTimestamp 19:06:53 INFO - PROCESS | 5079 | 19:06:53 INFO - PROCESS | 5079 | ------- Summary: start ------- 19:06:53 INFO - PROCESS | 5079 | Number of tests: 14 19:06:53 INFO - PROCESS | 5079 | 19:06:53 INFO - PROCESS | 5079 | [#0] ai-astar Cycles:10 Average:116.60 Median:116.00 stddev:3.03 (2.6%) stddev-sans-first:3.20 19:06:53 INFO - PROCESS | 5079 | Values: 116.0 125.0 117.0 116.0 115.0 116.0 115.0 115.0 116.0 115.0 19:06:53 INFO - PROCESS | 5079 | 19:06:53 INFO - PROCESS | 5079 | [#1] audio-beat-detection Cycles:10 Average:159.10 Median:159.00 stddev:27.49 (17.3%) stddev-sans-first:29.16 19:06:53 INFO - PROCESS | 5079 | Values: 160.0 151.0 138.0 220.0 136.0 169.0 158.0 136.0 188.0 135.0 19:06:53 INFO - PROCESS | 5079 | 19:06:53 INFO - PROCESS | 5079 | [#2] audio-dft Cycles:10 Average:162.80 Median:163.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 19:06:53 INFO - PROCESS | 5079 | Values: 163.0 163.0 162.0 162.0 164.0 163.0 163.0 163.0 163.0 162.0 19:06:53 INFO - PROCESS | 5079 | 19:06:53 INFO - PROCESS | 5079 | [#3] audio-fft Cycles:10 Average:86.90 Median:77.50 stddev:20.27 (26.2%) stddev-sans-first:21.18 19:06:53 INFO - PROCESS | 5079 | Values: 77.0 78.0 76.0 135.0 77.0 78.0 76.0 79.0 113.0 80.0 19:06:53 INFO - PROCESS | 5079 | 19:06:53 INFO - PROCESS | 5079 | [#4] audio-oscillator Cycles:10 Average:107.00 Median:95.00 stddev:20.29 (21.4%) stddev-sans-first:21.19 19:06:53 INFO - PROCESS | 5079 | Values: 97.0 96.0 155.0 95.0 95.0 98.0 116.0 95.0 95.0 128.0 19:06:53 INFO - PROCESS | 5079 | 19:06:53 INFO - PROCESS | 5079 | [#5] imaging-gaussian-blur Cycles:10 Average:118.20 Median:118.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 19:06:53 INFO - PROCESS | 5079 | Values: 118.0 119.0 118.0 118.0 118.0 118.0 118.0 118.0 119.0 118.0 19:06:53 INFO - PROCESS | 5079 | 19:06:53 INFO - PROCESS | 5079 | [#6] imaging-darkroom Cycles:10 Average:148.10 Median:148.00 stddev:0.57 (0.4%) stddev-sans-first:0.50 19:06:53 INFO - PROCESS | 5079 | Values: 149.0 148.0 149.0 148.0 148.0 148.0 148.0 147.0 148.0 148.0 19:06:53 INFO - PROCESS | 5079 | 19:06:53 INFO - PROCESS | 5079 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:96.00 stddev:0.79 (0.8%) stddev-sans-first:0.78 19:06:53 INFO - PROCESS | 5079 | Values: 95.0 95.0 95.0 97.0 96.0 96.0 97.0 95.0 96.0 96.0 19:06:53 INFO - PROCESS | 5079 | 19:06:53 INFO - PROCESS | 5079 | [#8] json-parse-financial Cycles:10 Average:66.90 Median:67.00 stddev:0.74 (1.1%) stddev-sans-first:0.71 19:06:53 INFO - PROCESS | 5079 | Values: 66.0 67.0 68.0 67.0 67.0 68.0 67.0 66.0 66.0 67.0 19:06:53 INFO - PROCESS | 5079 | 19:06:53 INFO - PROCESS | 5079 | [#9] json-stringify-tinderbox Cycles:10 Average:65.40 Median:65.50 stddev:0.52 (0.8%) stddev-sans-first:0.53 19:06:53 INFO - PROCESS | 5079 | Values: 65.0 65.0 65.0 65.0 66.0 65.0 66.0 66.0 65.0 66.0 19:06:53 INFO - PROCESS | 5079 | 19:06:53 INFO - PROCESS | 5079 | [#10] stanford-crypto-aes Cycles:10 Average:96.40 Median:88.00 stddev:27.27 (31.0%) stddev-sans-first:28.80 19:06:53 INFO - PROCESS | 5079 | Values: 89.0 87.0 87.0 88.0 87.0 88.0 88.0 88.0 174.0 88.0 19:06:53 INFO - PROCESS | 5079 | 19:06:53 INFO - PROCESS | 5079 | [#11] stanford-crypto-ccm Cycles:10 Average:144.00 Median:144.00 stddev:9.31 (6.5%) stddev-sans-first:9.87 19:06:53 INFO - PROCESS | 5079 | Values: 144.0 137.0 148.0 140.0 136.0 135.0 144.0 137.0 163.0 156.0 19:06:53 INFO - PROCESS | 5079 | 19:06:53 INFO - PROCESS | 5079 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.80 Median:178.00 stddev:1.81 (1.0%) stddev-sans-first:1.90 19:06:53 INFO - PROCESS | 5079 | Values: 177.0 180.0 178.0 177.0 179.0 176.0 175.0 178.0 181.0 177.0 19:06:53 INFO - PROCESS | 5079 | 19:06:53 INFO - PROCESS | 5079 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.00 Median:70.00 stddev:0.94 (1.3%) stddev-sans-first:1.00 19:06:53 INFO - PROCESS | 5079 | Values: 70.0 69.0 70.0 70.0 69.0 69.0 70.0 72.0 70.0 71.0 19:06:53 INFO - PROCESS | 5079 | -------- Summary: end -------- 19:06:53 INFO - PROCESS | 5079 | 19:06:54 INFO - TEST-INFO | 5079: exit 0 19:06:54 INFO - TEST-OK | kraken | took 64166ms 19:06:54 INFO - SUITE-END | took 1181s 19:06:54 INFO - Completed test suite (00:19:41) 19:06:54 INFO - javascript benchmark 19:06:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2657.933551661208, 2664.040945259705, 2668.7064422913477, 2699.865006749662, 2712.9593056104154, 4451.154280686667, 4515.503294637587, 4690.437147542296, 4767.713937163772, 4850.32095506629, 4674.7428891410955, 4698.882527936803, 4708.43498780146, 4789.3294938708605, 4793.520647935214, 4299.204647140274, 4307.547707490708, 4337.566243375669, 4378.62781663559, 4481.775911204443, 4322.005938634116, 4385.912281754363, 4405.977970110145, 4413.6027757501815, 4429.33559966006, 4197.853075142355, 4248.426462427564, 4265.7440553566885, 4318.4170137031415, 4325.5025672047805, 4218.49378074633, 4238.173556156551, 4250.128723611652, 4283.807228674686, 4286.142771445723, 4261.616454519108, 4283.357496375537, 4295.634871035934, 4307.396964424983, 4324.13517296542, 4438.578335058197, 4451.488078870939, 4480.6191473724775, 4533.116042371739, 4550.795214215368, 4913.385826771653, 4949.010197960422, 4960.280759289919, 4968.1554135797005, 4978.253262010691, 4913.852584422473, 4957.752112394366, 4999.250112483157, 5015.899682006339, 5037.168867136918, 4556.179887620227, 4669.089527542097, 4728.4798672146035, 4736.786844592001, 4790.353302304214, 5227.555657769056, 5234.136367499396, 5270.077736396115, 5270.077736396115, 5287.7884884604955, 4779.569838714532, 4967.528084831935, 5011.423686276052, 5014.749262536859, 5063.1645778446145, 4927.334809800703, 5006.4492905780335, 5018.99620075979, 5026.145555255615, 5027.245913113063, 4894.412670479565, 4926.482719314492, 4952.0, 4982.302477653131, 4984.501549845059, 4892.68197567157, 4921.212605983025, 4926.33494478248, 4929.186684197068, 4974.27872958419, 4145.025918909054, 4148.896277593084, 4154.273002224599, 4160.271952408317, 4193.727407718488, 3565.3404120237838, 3617.6744093031753, 3631.8184090795353, 3665.87169449068, 3724.7206459515646, 4394.978025109854, 4399.450068741407, 4422.579854913778, 4433.889152771142, 4451.530994771718, 5056.873578160576, 5078.568321692624, 5114.590832733373, 5125.794968201305, 5163.0190263849745, 4271.145770845843, 4283.421738065384, 4301.870943871689, 4360.215161271001, 4363.192809330284, 4406.559344065632, 4414.09511050234, 4423.336499525097, 4429.136318417942, 4498.145352383037, 4592.862214133581, 4713.622910167247, 4720.173969555382, 4741.431028276629, 4763.428388593391, 4939.110960027229, 4968.105740966661, 5039.496050394931, 5048.621353398436, 5060.746962651926, 4662.090892276109, 4739.857804265877, 4742.952570474389, 4747.430308363018, 4750.809967601257, 4806.951930480651, 4920.237363208708, 4937.086638971802, 4976.0794253063295, 5011.974940125276, 5022.522860328263, 5051.393832739947, 5078.0, 5079.6698214615935, 5123.897522049613, 4874.95125048745, 4890.046440943982, 4899.583535399531, 4910.8281210157475, 4954.207326827835, 4730.810767569259, 4837.177679794373, 4887.218045112765, 4913.361263035782, 4967.801287948441, 4793.352897358812, 4834.00902814915, 4867.3915760529935, 4869.610431165427, 4902.926456103232, 4919.434265059421, 4949.901001980013, 4952.851414457572, 5011.498850114959, 5024.824131155538, 4607.378003969556, 4670.883227919329, 4806.375171227718, 4818.903621927471, 4832.8791780205775], "value": 4597.771905688371, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [552.8479668091277, 553.9750711217995, 568.4770011589337, 575.2148317546549, 584.217149020313, 26837.597436038475, 39492.80253598731, 40222.39666404999, 40587.59412405882, 44693.7765311173, 40815.0, 41029.0, 41184.38223426651, 41297.793511032476, 41306.38040429389, 7345.77962661121, 7630.694772209105, 7803.8049048773955, 7818.960905195466, 7828.334591559725, 10091.142252908512, 10334.483275836215, 10354.689359319194, 10371.274010819247, 10425.218108641882, 10051.748706282366, 10286.485675716222, 10294.0, 10301.0, 10309.020643038937, 7333.669984850694, 9636.614535418576, 10016.098551130397, 10017.849732254022, 10142.137918276956, 9875.0, 9982.800343993153, 9986.650467233685, 10017.148542373907, 10053.899461005369, 37872.43191352132, 38232.23535529274, 38400.42399364012, 38416.42375364372, 38460.23079538393, 3013.909582712522, 3121.0, 3146.638136614296, 3159.2259896325318, 3183.522471629274, 28901.27746806347, 29027.41945161085, 29442.558361624593, 29570.26074348137, 30030.0, 3291.654376290479, 3471.2363280078343, 3480.6867381935494, 3510.4383298672346, 3522.947155792665, 249171.0, 250970.0, 251777.0, 251958.0, 253438.0, 33192.50211246833, 53566.732166338916, 54496.45503544993, 54613.0, 54721.45278547243, 33310.50034249488, 33745.66254337474, 33781.0, 33977.49033764496, 33994.66005339915, 11470.942645286887, 11659.76680466386, 11669.124815638692, 11680.532778688927, 11703.941480292715, 8224.876626850602, 8734.86897696535, 8936.151065648754, 8966.462012279284, 9276.582553784961, 7885.369170466349, 7902.644381002869, 7906.604669766489, 7958.243966823143, 7967.442279040528, 3092.3969825884187, 3137.748980081588, 3157.4790159623403, 3158.98420507896, 3164.3987642348343, 888.1873086126251, 892.4199270474244, 929.4516235421135, 990.5443495991781, 1008.5007921078966, 27295.45409091807, 27508.312292192855, 27685.307867303076, 28093.578596321073, 28191.57712634312, 923.9815203696023, 936.9063093690577, 974.3179774157976, 1016.5577973581516, 1034.990883888218, 14745.92627036858, 14923.0, 15002.549923502313, 15074.472393466396, 15358.462453814063, 383230.0, 384585.0, 385765.0, 386750.0, 388627.0, 48777.5122248773, 49326.75336623294, 49530.50469495331, 49548.0, 49792.25311620328, 1077.0737166037202, 1135.8409822625003, 1174.4714878304626, 1178.1635039122318, 1184.2302503372875, 27046.72953270442, 28321.43357132887, 28528.144155675363, 28977.7102228975, 28995.420091598473, 41747.0, 41928.3710744333, 42140.0, 42341.78829105835, 42507.78746106374, 288848.0, 289119.32642020774, 289721.0, 290327.0, 290948.0, 22086.447838804157, 24299.63550546708, 25122.24633261005, 25269.74730252674, 26319.4736105273, 40689.796551017054, 40854.52736517429, 41048.0, 41704.58295417128, 41807.1638567233, 35205.471917921765, 35521.467177991835, 35923.640763592026, 36055.45916811198, 36310.8184459076, 34852.128696782784, 35343.29313413769, 35378.82310588431, 35863.64136358603, 36070.63929360673, 34329.65670343264, 35557.64442355647, 35645.465318019735, 35712.642873570934, 36091.0, 31071.533926991564, 31755.20611984719, 32120.0, 32193.195170120933, 32498.0, 24550.263492095266, 24794.0, 25036.123735669164, 25078.62382064307, 25104.874475627505], "value": 16663.923030258637, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.9064243296742, 361.8643008871673, 364.24419329890475, 374.2160174434559, 375.75388120780883, 15306.54080377589, 16335.34658613654, 16442.34230630775, 16733.16334183289, 16910.492685219433, 15462.226888655578, 16538.09040502772, 16726.41457548986, 16783.83216167841, 16807.32770689171, 4885.291632713245, 5097.108006098936, 5116.667416617926, 5163.328767260251, 5170.017696637627, 3668.7798732076026, 3829.2532956073437, 5115.155999260136, 5636.774529018855, 5944.464998150165, 5986.551008674345, 5988.431099045605, 5998.670073145975, 6049.092636104598, 6055.636661800284, 5880.529557635379, 5914.142449344842, 5987.28152621686, 6013.128096426038, 6033.969830150843, 3355.6308806031316, 4985.0, 5295.258663787073, 5298.337707786527, 5314.415414304423, 19777.110030048683, 19794.60410791776, 19870.304539341054, 19903.80096199034, 20567.79432205689, 1875.165551329659, 2030.35028790787, 2184.322859913432, 2237.8433509654333, 2255.4023183856293, 12528.185667931555, 12863.228206307558, 12905.806412903814, 13475.528356507573, 13476.865231347758, 1678.8343037481634, 1701.9319227230897, 1751.7197248440189, 1760.4102625620433, 1815.6459490399377, 65779.67110164418, 68194.65902670505, 69231.30768692298, 69334.30665693329, 69883.65058174677, 8489.490630562186, 9653.22774178064, 9822.017798220264, 9858.0, 9992.750181245527, 13517.864821351857, 13623.114497557626, 13631.931840340734, 13636.86363136356, 13645.454181832814, 6480.125183100221, 6577.473802095821, 6624.238212605612, 6703.3296670332575, 6768.72925083001, 6184.752609895554, 6194.256689197326, 6234.314225435199, 6246.406591373812, 6250.374962503804, 6048.395160484004, 6185.134081228632, 6189.319174890758, 6209.627422354582, 6298.622082675054, 1586.8889177757446, 1677.0859881364686, 1766.1787268920014, 1788.3830078622855, 1809.1496996411665, 806.3307454812492, 875.605977310213, 947.5877993072897, 950.296780382512, 989.9901000989898, 15496.845031549765, 16029.919850400673, 16692.0, 17007.0, 17112.828871711372, 857.2927334948693, 860.165639329849, 874.881890944727, 886.9201771840566, 918.7703074231442, 9010.3242256831, 9029.367944243972, 9130.726078217664, 9165.908340916505, 9375.390599611004, 378557.0, 410124.0, 417978.0, 418888.0, 423537.0, 82828.58585707033, 85434.14565854386, 86190.56904715436, 86387.56806215929, 86685.0, 910.321810251367, 912.2792993535165, 915.2449229385784, 932.6176267730198, 947.0103741590054, 14274.500392486214, 14353.92823035878, 14407.495737649131, 14541.563753087425, 14878.0, 33418.331633367685, 33489.0, 33577.66422335843, 34024.29878506015, 34056.1485962843, 50859.491405086956, 52175.73912130415, 52634.0, 52962.0, 53373.19940200978, 7091.964540177472, 7235.457340699363, 7462.850742985219, 7529.8117547061765, 7678.61606919663, 8748.431351962103, 10990.615328463466, 11076.39079850616, 11305.0, 11595.246308989888, 9577.952110239683, 9590.424574525272, 9846.753831154278, 9855.0, 10034.598616055277, 9814.558344874355, 9844.507774611098, 9870.259730520096, 9894.604215831574, 10017.348872323275, 9976.401415915068, 9993.350432221881, 10017.899821001989, 10226.28416010872, 10285.794284114425, 7159.964200178966, 7244.0, 7456.217097204715, 7609.733659321897, 7624.618769061635, 8569.614367353359, 8782.209601135928, 9621.663241786504, 9878.308518403643, 9939.403635781877], "value": 8221.915734303011, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1281.2952875918243, 1402.0746307437093, 1424.8076509671193, 1549.6280892585776, 1748.2657283940737, 6576.967115164423, 7113.85772284554, 7293.4894557381, 7302.306280903305, 7513.624318784053, 7270.745523906664, 7408.370288525482, 7420.072490938633, 7432.6655300511475, 7459.0, 1987.1852540458415, 2018.0313449544228, 2026.9087891044865, 2029.086910890098, 2031.3499680102373, 2089.937301880946, 2095.1933505600377, 2108.641530939736, 2131.9467013324715, 2140.892955352226, 2113.619548481273, 2117.6505876530355, 2118.7139736135578, 2124.7768984256663, 2131.1368895597307, 2031.8171364577258, 2036.175348983664, 2037.7045328427444, 2047.2834507922257, 2052.127845665586, 2023.61551305253, 2034.745656792901, 2066.6486697261503, 2068.586282743457, 2068.906899189525, 7267.927320726778, 7364.77905662831, 7379.3727533159745, 7497.10034795828, 7611.65747541362, 4366.759828209447, 4466.776661166961, 4488.192125417424, 4504.121696269229, 4505.774711264424, 2837.744602985741, 2849.786266030056, 2885.639295088114, 2910.8544572771275, 2933.574631678395, 879.705298724928, 889.9777505562348, 890.2610833008588, 903.9502827344494, 907.8774365460645, 10537.209709271836, 10600.62897798581, 10661.360318380923, 10679.893201067967, 10681.198910081775, 3989.601039895987, 4028.1742242840733, 4089.8568550100604, 4149.294619914562, 4160.687948403821, 8262.297704695167, 8286.544240066854, 8311.0, 8412.0, 8430.831383372299, 4362.411074504901, 4382.890427739332, 4387.648988080946, 4393.231184542692, 4408.6693497987135, 3426.400379933502, 3832.9425058624142, 3876.5154355705536, 3921.9019524511546, 3963.722539422213, 3292.9506057409158, 3340.8329583520726, 3362.9447950627446, 3391.1013581400457, 3410.2497450560836, 1006.5420233793607, 1006.6074231049896, 1015.147276287907, 1017.0490591297013, 1020.1226944827442, 673.2325149329768, 674.5716470041559, 687.594319351575, 770.4837758701683, 788.2629741192004, 4821.734804585782, 4900.632452566072, 5003.074431230234, 5036.622253331015, 5066.392032956069, 407.58426405066666, 426.1391988183851, 427.0221193466988, 428.87420521132026, 432.4723836918954, 3307.074019274607, 3313.5858017747782, 3325.73394128474, 3344.7825891317475, 3450.810205438725, 8962.775930601787, 9047.638094476279, 9215.124563166488, 9614.83666175105, 9730.902690973, 9320.627174912928, 9475.668351607661, 9743.805123897624, 9753.21974242059, 9765.85351219718, 6966.79099627012, 7153.320434558708, 7277.981082648327, 7316.158641756159, 7364.3003914628025, 5072.492750724898, 5134.409542902614, 5297.17893726473, 5425.050616142238, 5453.0, 8287.62705678234, 8381.16188381157, 8659.6103175356, 8708.564571771512, 8825.02924678272, 8376.036755773164, 8403.83192336162, 8471.110533393905, 8895.777605559913, 8905.154010369004, 5729.942700572941, 6202.007678771375, 6489.5781774184525, 6547.574407663486, 6639.900401493885, 7294.234105418855, 7573.166951635206, 7590.468667193464, 7604.695812167452, 7619.847603048019, 6469.0, 6836.658167091725, 6891.75878844238, 6927.27263637331, 6991.7902462926195, 6585.506087043395, 6673.299303573249, 6678.332166783282, 6773.356531129535, 6801.149856267966, 5940.346561878104, 5962.850928726816, 5974.4324289192455, 5983.970080149571, 6001.7599296027665, 4286.571342865688, 4385.320275357325, 4390.890227744332, 4455.26488129468, 4461.933071004002, 4375.299952007664, 4440.7335559866515, 4460.0, 4460.665450091191, 4501.662375321929], "value": 3888.4730955616988, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [283.54915684062337, 288.0695354006559, 292.32181339292845, 297.12200447677117, 297.4586253019505, 4619.099275641247, 4658.97502549439, 4661.627069834413, 4667.20657488227, 4672.556107169813, 4626.935804764906, 4638.976805115978, 4640.582347588716, 4641.512641172679, 4665.603423708981, 3504.6320136385693, 3600.567931848169, 3626.6192049834785, 3633.073566240605, 3634.1459756957092, 3528.3648943190215, 3615.9276814463697, 3624.2207925296057, 3661.835217415209, 3688.79711615861, 3599.1362073102396, 3612.489319922655, 3655.342038433081, 3665.321915445638, 3674.375356189441, 3485.1809824691177, 3628.4194528875387, 3628.891133265993, 3632.691221246197, 3660.4875317455576, 3247.7888937219004, 3250.8537115829845, 3255.235019770352, 3259.0, 3262.5269335946155, 4396.406485124499, 4404.581564751344, 4407.867763967054, 4431.0, 4454.754988475633, 4170.207660544487, 4182.163567286555, 4196.328587425997, 4196.832126714928, 4216.789160541961, 4551.476580193287, 4817.205161148407, 4820.855374338776, 4824.179889418772, 4831.22700367943, 3519.9296014079832, 3526.1008442847165, 3535.2045789697368, 3543.291341731638, 3546.6522721365973, 5041.495850414965, 5062.531865758925, 5063.11395505785, 5090.2364645303505, 5098.719570423626, 3576.177479179751, 3672.0, 3673.632636736305, 3684.7604905681046, 3689.5203623529305, 3694.910001549549, 3709.666130048309, 3710.22085362077, 3723.9069023274633, 3733.1227161617, 4120.175964806991, 4125.546189919107, 4132.669386449077, 4144.129732756155, 4170.645495132948, 4789.688670236493, 4795.136875362398, 4803.18345881201, 4831.323614693945, 4863.392075990501, 1250.8179770117151, 1252.3800718644147, 1259.5865205293574, 1289.8516670583003, 1291.877271659191, 804.4449329962307, 827.5986146718836, 829.8008477965366, 831.1107115386599, 834.5910503853069, 4150.896227594274, 4248.745075295492, 4332.978335108367, 4351.369051487517, 4351.540525282408, 4912.189488734391, 4958.256261560795, 4961.751912404366, 4988.476209997898, 5005.27423523587, 4335.479742430865, 4359.367891655692, 4363.80362883671, 4365.2797288447055, 4375.0, 2487.3905893055953, 2608.804339674532, 2680.32991752062, 2681.8122731408616, 2689.9865500672763, 4792.065547218329, 4795.976020119947, 4798.088363210979, 4802.303665968415, 4823.324734537167, 5147.96655321146, 5152.458991805881, 5160.870978225575, 5161.380634323905, 5167.560757335668, 4582.816687332536, 4782.641301902371, 4794.616430685467, 4796.712197268175, 4804.159272127434, 5005.049040682259, 5005.974970125126, 5011.223260394645, 5037.622178336566, 5040.722760248222, 4905.852824415273, 4928.556429921181, 4930.630202734737, 4944.901101978012, 4946.950530494649, 4540.387047748512, 4720.914189736449, 4721.622270218443, 4764.190087685032, 4812.470628230822, 3534.4698295255916, 3554.893353199408, 3559.1635965547557, 3567.4292113261754, 3582.194006348655, 4959.62802789785, 4996.575291100297, 5002.424721156968, 5006.824761133343, 5011.824586139468, 4734.289856521549, 4847.151748443937, 4852.878678033077, 4863.34344863453, 4867.318575399376, 4811.109944660249, 4817.542333478314, 4822.324874517501, 4823.2282834747675, 4835.734034627989, 4763.785629646605, 4767.165745994506, 4787.473377928494, 4789.353437285922, 4804.5195480451675, 4712.505186955457, 4797.304390863235, 4822.348982887265, 4829.17493266443, 4881.4142302922455, 4755.144074066563, 4756.571908528249, 4764.237721964469, 4804.711717296974, 4817.927731084106], "value": 3683.1605857961517, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [623.66945518875, 684.0730809752788, 728.9088863892014, 747.6710247491106, 758.1584441270193, 11325.943370283147, 12044.096692748046, 12056.095792815551, 12089.214201076924, 12090.455929483183, 11692.532298708042, 12148.69628259294, 12155.209911355756, 12211.755764884698, 12243.938780306109, 619.7892716476397, 752.345459450279, 762.6072572625103, 772.7836205862368, 777.6772639354662, 775.9883601745978, 776.6311002273931, 779.4972242903324, 780.2002946979335, 782.8669126248523, 816.346922462031, 817.8037271054964, 818.2880893622527, 818.7502811642447, 819.7458787775779, 649.1949982022282, 792.5324058805332, 811.3590263691676, 812.3622955979557, 814.1817473439156, 587.8207146820218, 588.0825911577953, 588.0855270055063, 589.1310317282017, 589.5165963909553, 11205.943970280096, 11236.831447528293, 11244.212905096647, 11248.606298779585, 11291.548338066468, 11506.539738410453, 11879.762404751942, 11885.702857428547, 12130.211536250115, 12357.0, 10589.523471443803, 10774.892251077466, 10830.72923176919, 10925.836112458319, 10936.671899843017, 4003.919921601581, 4026.5168179818315, 4076.8980775480554, 4077.612626800449, 4125.092479654471, 43782.56217437817, 45701.542984570064, 45845.0, 46156.769216153705, 46163.76918115455, 9435.575399107056, 9603.519824008772, 9635.614575417045, 9737.367071140354, 9929.80140397188, 17031.0, 17047.0, 17074.914625426794, 17087.401940932006, 17098.658026839392, 6334.3032266450655, 6453.128827608213, 6492.415682588589, 6508.349165083454, 6608.306127856506, 3552.0587044433246, 3569.089882080105, 3572.857085716594, 3596.2088340565033, 3641.5812181599454, 2607.10055031014, 2686.0464535090155, 2687.4087700706204, 2691.542437785581, 2710.146303914261, 463.98376056838526, 475.34165181224256, 476.98807529811336, 483.4223103391484, 484.8327327072154, 511.9667221630582, 529.6980720989, 540.7269328988835, 549.8625343664085, 618.52373672273, 7840.41196910234, 8147.714829980922, 8420.200080992296, 8448.57757112142, 8507.489550626859, 512.2060805751146, 569.5301376364516, 601.5969300568541, 622.18804460179, 623.1680706257198, 3863.787491687984, 3876.7673939563715, 3955.149642826806, 4033.193361327746, 4049.8987525312104, 39147.60852391497, 53023.46976530185, 53154.46845531573, 53523.464765352626, 53539.0, 46845.531544684796, 48662.75668621705, 49037.75481122643, 49272.260916086285, 50503.74748126309, 43484.78257608692, 43996.120077598905, 44879.32681009722, 44929.3042417456, 45635.0, 7011.929880701127, 7019.859602807813, 7230.349268565854, 7290.3438690518105, 7459.254074592497, 27012.32469188286, 27214.183574492796, 28772.856135719187, 30064.699353007065, 30410.695893041673, 38273.425898610985, 38359.0, 38683.0, 38753.612463875, 39158.0, 10529.210309226686, 10867.29362591429, 10896.128309735044, 10987.450627468435, 11072.335659860435, 11684.532618695497, 11689.357085360387, 11873.287602743863, 12088.697782555155, 12225.44985475638, 10634.574617015542, 11005.064569511682, 11121.221514493907, 11232.101431885267, 11697.0, 11234.438278086227, 11516.366599836754, 11522.76954460923, 11793.115516336138, 11838.28970261787, 10789.62236321725, 10940.617078402218, 10968.835467467834, 11218.326900386002, 11538.250013749079, 8810.779730506789, 9249.907500925174, 9643.180329672057, 9724.367916085432, 9921.385212458721, 9059.682911098344, 9087.863682044643, 9211.907880921104, 9751.951240243754, 9776.818286308267], "value": 5861.089501407573, "unit": "score"}], "name": "dromaeo_css", "value": 6126.624327187049, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 125.0, 117.0, 116.0, 115.0, 116.0, 115.0, 115.0, 116.0, 115.0], "value": 116.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 151.0, 138.0, 220.0, 136.0, 169.0, 158.0, 136.0, 188.0, 135.0], "value": 159.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 162.0, 162.0, 164.0, 163.0, 163.0, 163.0, 163.0, 162.0], "value": 162.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 78.0, 76.0, 135.0, 77.0, 78.0, 76.0, 79.0, 113.0, 80.0], "value": 86.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 155.0, 95.0, 95.0, 98.0, 116.0, 95.0, 95.0, 128.0], "value": 107.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 119.0, 118.0, 118.0, 118.0, 118.0, 118.0, 118.0, 119.0, 118.0], "value": 118.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 148.0, 149.0, 148.0, 148.0, 148.0, 148.0, 147.0, 148.0, 148.0], "value": 148.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 97.0, 96.0, 96.0, 97.0, 95.0, 96.0, 96.0], "value": 95.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 68.0, 67.0, 67.0, 68.0, 67.0, 66.0, 66.0, 67.0], "value": 66.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 65.0, 66.0, 65.0, 66.0, 66.0, 65.0, 66.0], "value": 65.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 87.0, 88.0, 87.0, 88.0, 88.0, 88.0, 174.0, 88.0], "value": 96.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 137.0, 148.0, 140.0, 136.0, 135.0, 144.0, 137.0, 163.0, 156.0], "value": 144.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 180.0, 178.0, 177.0, 179.0, 176.0, 175.0, 178.0, 181.0, 177.0], "value": 177.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 70.0, 70.0, 69.0, 69.0, 70.0, 72.0, 70.0, 71.0], "value": 70.0, "unit": "score"}], "name": "kraken", "value": 1615.0000000000002, "alertThreshold": 2.0}]} 19:06:54 INFO - Return code: 0 19:06:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 19:06:54 ERROR - # TBPL SUCCESS # 19:06:54 INFO - Running post-action listener: _resource_record_post_action 19:06:54 INFO - ##### 19:06:54 INFO - ##### Finished run-tests step (success) 19:06:54 INFO - ##### 19:06:54 INFO - Running post-run listener: _resource_record_post_run 19:06:54 INFO - Total resource usage - Wall time: 1199s; CPU: 26.0%; Read bytes: 8192; Write bytes: 529977344; Read time: 100; Write time: 505868 19:06:54 INFO - TinderboxPrint: CPU usage
25.5% 19:06:54 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 100 19:06:54 INFO - TinderboxPrint: I/O write bytes / time
529,977,344 / 505,868 19:06:54 INFO - TinderboxPrint: CPU idle
7,125.6 (74.4%) 19:06:54 INFO - TinderboxPrint: CPU user
2,408.1 (25.2%) 19:06:54 INFO - TinderboxPrint: Swap in / out
0 / 0 19:06:54 INFO - install - Wall time: 16s; CPU: 26.0%; Read bytes: 4096; Write bytes: 246161408; Read time: 48; Write time: 244324 19:06:54 INFO - run-tests - Wall time: 1183s; CPU: 26.0%; Read bytes: 4096; Write bytes: 283815936; Read time: 52; Write time: 261544 19:06:55 INFO - Running post-run listener: _upload_blobber_files 19:06:55 INFO - Blob upload gear active. 19:06:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:06:55 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 19:06:55 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:06:55 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:06:55 INFO - (blobuploader) - INFO - Open directory for files ... 19:06:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:06:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:06:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:06:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:06:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:06:56 INFO - (blobuploader) - INFO - Done attempting. 19:06:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:06:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:06:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:06:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:06:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:06:57 INFO - (blobuploader) - INFO - Done attempting. 19:06:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:06:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:06:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:07:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:07:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:07:01 INFO - (blobuploader) - INFO - Done attempting. 19:07:01 INFO - (blobuploader) - INFO - Iteration through files over. 19:07:01 INFO - Return code: 0 19:07:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:07:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:07:01 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/c47f7e34449d37e407c53d74dd5e8e0f5bd3e028bae638cbfb9ef59c09d0888ce293e19241efc5aecdc6bc8e8768f46a7fe2133eb577f41c5a478790b56809be", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/d2aaa0bba215363cc6ba8d2aafed764865bd0e09c1d56e82fdd864cbe4994bec8a6832aad41f30d8d100be22f987219c2cfff44c89c363600e957437ee7f2714", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/c4001b8f68705856e2438dff5ee3309e04ea0565216c175f9fb0287c77d2b119d63a90630d0bbc016277dbc100744d006dd63d702e20b739eb6fc167287ef615"} 19:07:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:07:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:07:01 INFO - Contents: 19:07:01 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/c47f7e34449d37e407c53d74dd5e8e0f5bd3e028bae638cbfb9ef59c09d0888ce293e19241efc5aecdc6bc8e8768f46a7fe2133eb577f41c5a478790b56809be", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/d2aaa0bba215363cc6ba8d2aafed764865bd0e09c1d56e82fdd864cbe4994bec8a6832aad41f30d8d100be22f987219c2cfff44c89c363600e957437ee7f2714", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/c4001b8f68705856e2438dff5ee3309e04ea0565216c175f9fb0287c77d2b119d63a90630d0bbc016277dbc100744d006dd63d702e20b739eb6fc167287ef615"} 19:07:01 INFO - Running post-run listener: copy_logs_to_upload_dir 19:07:01 INFO - Copying logs to upload dir... 19:07:01 INFO - mkdir: /builds/slave/test/build/upload/logs 19:07:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1242.952146 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-05-20 19:07:01.632679) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-20 19:07:01.635868) ========= 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=7de08804244eb36560b44971000001c3-1463794583.192789-567490163 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-Non-PGO/sha512/c47f7e34449d37e407c53d74dd5e8e0f5bd3e028bae638cbfb9ef59c09d0888ce293e19241efc5aecdc6bc8e8768f46a7fe2133eb577f41c5a478790b56809be", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/d2aaa0bba215363cc6ba8d2aafed764865bd0e09c1d56e82fdd864cbe4994bec8a6832aad41f30d8d100be22f987219c2cfff44c89c363600e957437ee7f2714", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/c4001b8f68705856e2438dff5ee3309e04ea0565216c175f9fb0287c77d2b119d63a90630d0bbc016277dbc100744d006dd63d702e20b739eb6fc167287ef615"} build_url:https://queue.taskcluster.net/v1/task/L7LKINAWSi-pcRqJtSMDWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.005478 build_url: 'https://queue.taskcluster.net/v1/task/L7LKINAWSi-pcRqJtSMDWA/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-Non-PGO/sha512/c47f7e34449d37e407c53d74dd5e8e0f5bd3e028bae638cbfb9ef59c09d0888ce293e19241efc5aecdc6bc8e8768f46a7fe2133eb577f41c5a478790b56809be", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/d2aaa0bba215363cc6ba8d2aafed764865bd0e09c1d56e82fdd864cbe4994bec8a6832aad41f30d8d100be22f987219c2cfff44c89c363600e957437ee7f2714", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/c4001b8f68705856e2438dff5ee3309e04ea0565216c175f9fb0287c77d2b119d63a90630d0bbc016277dbc100744d006dd63d702e20b739eb6fc167287ef615"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-20 19:07:01.649676) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 19:07:01.649997) ========= 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=7de08804244eb36560b44971000001c3-1463794583.192789-567490163 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.002114 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 19:07:01.729346) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-20 19:07:01.729722) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-20 19:07:01.730009) ========= ========= Total master_lag: 0.15 =========