builder: larch_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-014 starttime: 1464389296.88 results: success (0) buildid: 20160527143140 builduid: 0e74ecbca28d4ea286292920e7bce0a8 revision: 39dcfacc4b5b91b0d0c66ecc144aacf00fca7c2a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-27 15:48:16.882845) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-27 15:48:16.883277) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-27 15:48:16.883598) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-27 15:48:16.936486) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 15:48:16.936776) ========= 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=31acecf42b6a79ec49471d4a000001bb-1464388853.767874-1900377598 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-27 15:48:16-- 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% 43.0M=0s 2016-05-27 15:48:17 (43.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.117654 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 15:48:17.068559) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 15:48:17.068876) ========= 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=31acecf42b6a79ec49471d4a000001bb-1464388853.767874-1900377598 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018157 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 15:48:17.119067) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-27 15:48:17.119408) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev 39dcfacc4b5b91b0d0c66ecc144aacf00fca7c2a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev 39dcfacc4b5b91b0d0c66ecc144aacf00fca7c2a --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=31acecf42b6a79ec49471d4a000001bb-1464388853.767874-1900377598 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-27 15:48:17,137 truncating revision to first 12 chars 2016-05-27 15:48:17,138 Setting DEBUG logging. 2016-05-27 15:48:17,138 attempt 1/10 2016-05-27 15:48:17,138 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/39dcfacc4b5b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-27 15:48:17,467 unpacking tar archive at: larch-39dcfacc4b5b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.093873 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-27 15:48:18.227217) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-27 15:48:18.227591) ========= script_repo_revision: 39dcfacc4b5b91b0d0c66ecc144aacf00fca7c2a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-27 15:48:18.228396) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-27 15:48:18.228723) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-27 15:48:18.239628) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-05-27 15:48:18.240051) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch-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', 'Larch-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch-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=31acecf42b6a79ec49471d4a000001bb-1464388853.767874-1900377598 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:48:18 INFO - MultiFileLogger online at 20160527 15:48:18 in /builds/slave/test 15:48:18 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch-Non-PGO 15:48:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:48:18 INFO - {'append_to_log': False, 15:48:18 INFO - 'base_work_dir': '/builds/slave/test', 15:48:18 INFO - 'blob_upload_branch': 'Larch-Non-PGO', 15:48:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:48:18 INFO - 'branch': 'Larch-Non-PGO', 15:48:18 INFO - 'buildbot_json_path': 'buildprops.json', 15:48:18 INFO - 'config_files': ('talos/linux_config.py',), 15:48:18 INFO - 'default_actions': ('clobber', 15:48:18 INFO - 'read-buildbot-config', 15:48:18 INFO - 'download-and-extract', 15:48:18 INFO - 'populate-webroot', 15:48:18 INFO - 'create-virtualenv', 15:48:18 INFO - 'install', 15:48:18 INFO - 'run-tests'), 15:48:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:48:18 INFO - 'download_minidump_stackwalk': True, 15:48:18 INFO - 'download_symbols': 'ondemand', 15:48:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:48:18 INFO - 'tooltool.py': '/tools/tooltool.py', 15:48:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:48:18 INFO - '/tools/misc-python/virtualenv.py')}, 15:48:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:48:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:48:18 INFO - 'installer_path': 'installer.exe', 15:48:18 INFO - 'log_level': 'info', 15:48:18 INFO - 'log_name': 'talos', 15:48:18 INFO - 'log_to_console': True, 15:48:18 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:48:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:48:18 INFO - 'opt_config_files': (), 15:48:18 INFO - 'pip_index': False, 15:48:18 INFO - 'sps_profile': False, 15:48:18 INFO - 'sps_profile_interval': 0, 15:48:18 INFO - 'suite': 'dromaeojs', 15:48:18 INFO - 'system_bits': '32', 15:48:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:48:18 INFO - 'title': 'talos-linux64-ix-014', 15:48:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:48:18 INFO - 'use_talos_json': True, 15:48:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:48:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:48:18 INFO - 'work_dir': 'build'} 15:48:18 INFO - ##### 15:48:18 INFO - ##### Running clobber step. 15:48:18 INFO - ##### 15:48:18 INFO - Running pre-action listener: _resource_record_pre_action 15:48:18 INFO - Running main action method: clobber 15:48:18 INFO - rmtree: /builds/slave/test/build 15:48:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:48:19 INFO - Running post-action listener: _resource_record_post_action 15:48:19 INFO - ##### 15:48:19 INFO - ##### Finished clobber step (success) 15:48:19 INFO - ##### 15:48:19 INFO - ##### 15:48:19 INFO - ##### Running read-buildbot-config step. 15:48:19 INFO - ##### 15:48:19 INFO - Running pre-action listener: _resource_record_pre_action 15:48:19 INFO - Running main action method: read_buildbot_config 15:48:19 INFO - Using buildbot properties: 15:48:19 INFO - { 15:48:19 INFO - "project": "", 15:48:19 INFO - "product": "firefox", 15:48:19 INFO - "script_repo_revision": "production", 15:48:19 INFO - "builddir": "larch_ubuntu64_hw_test-dromaeojs", 15:48:19 INFO - "repository": "", 15:48:19 INFO - "buildername": "Ubuntu HW 12.04 x64 larch talos dromaeojs", 15:48:19 INFO - "buildid": "20160527143140", 15:48:19 INFO - "pgo_build": "False", 15:48:19 INFO - "basedir": "/builds/slave/test", 15:48:19 INFO - "buildnumber": 2, 15:48:19 INFO - "slavename": "talos-linux64-ix-014", 15:48:19 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 15:48:19 INFO - "platform": "ubuntu64_hw", 15:48:19 INFO - "scheduler": "tests-larch-linux64-talos", 15:48:19 INFO - "branch": "larch", 15:48:19 INFO - "repo_path": "projects/larch", 15:48:19 INFO - "revision": "39dcfacc4b5b91b0d0c66ecc144aacf00fca7c2a", 15:48:19 INFO - "stage_platform": "linux64", 15:48:19 INFO - "builduid": "0e74ecbca28d4ea286292920e7bce0a8", 15:48:19 INFO - "slavebuilddir": "test" 15:48:19 INFO - } 15:48:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/YwZzTEZtT3OhzfEGWEmAag/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 15:48:19 INFO - Running post-action listener: _resource_record_post_action 15:48:19 INFO - ##### 15:48:19 INFO - ##### Finished read-buildbot-config step (success) 15:48:19 INFO - ##### 15:48:19 INFO - ##### 15:48:19 INFO - ##### Running download-and-extract step. 15:48:19 INFO - ##### 15:48:19 INFO - Running pre-action listener: _resource_record_pre_action 15:48:19 INFO - Running main action method: download_and_extract 15:48:19 INFO - mkdir: /builds/slave/test/build/tests 15:48: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')]} 15:48:19 INFO - https://queue.taskcluster.net/v1/task/YwZzTEZtT3OhzfEGWEmAag/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 15:48:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YwZzTEZtT3OhzfEGWEmAag/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 15:48:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YwZzTEZtT3OhzfEGWEmAag/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 15:48:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YwZzTEZtT3OhzfEGWEmAag/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 15:48:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YwZzTEZtT3OhzfEGWEmAag/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 15:48:22 INFO - Downloaded 1361 bytes. 15:48:22 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 15:48:22 INFO - Using the following test package requirements: 15:48:22 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 15:48:22 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:48:22 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 15:48:22 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:48:22 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 15:48:22 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:48:22 INFO - u'jsshell-linux-x86_64.zip'], 15:48:22 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:48:22 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 15:48:22 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 15:48:22 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:48:22 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 15:48:22 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:48:22 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 15:48:22 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:48:22 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 15:48:22 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:48:22 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 15:48:22 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:48:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:48:22 INFO - https://queue.taskcluster.net/v1/task/YwZzTEZtT3OhzfEGWEmAag/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:48:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YwZzTEZtT3OhzfEGWEmAag/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:48:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YwZzTEZtT3OhzfEGWEmAag/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:48:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YwZzTEZtT3OhzfEGWEmAag/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:48:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YwZzTEZtT3OhzfEGWEmAag/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:48:39 INFO - Downloaded 22848162 bytes. 15:48:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:48:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:48:41 INFO - Return code: 0 15:48:41 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 15:48:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:48:41 INFO - https://queue.taskcluster.net/v1/task/YwZzTEZtT3OhzfEGWEmAag/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:48:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YwZzTEZtT3OhzfEGWEmAag/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:48:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YwZzTEZtT3OhzfEGWEmAag/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:48:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YwZzTEZtT3OhzfEGWEmAag/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:48:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YwZzTEZtT3OhzfEGWEmAag/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:48:42 INFO - Downloaded 22848162 bytes. 15:48:42 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:48:42 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:48:44 INFO - Return code: 0 15:48:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:48:44 INFO - https://queue.taskcluster.net/v1/task/YwZzTEZtT3OhzfEGWEmAag/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 15:48:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YwZzTEZtT3OhzfEGWEmAag/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 15:48:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YwZzTEZtT3OhzfEGWEmAag/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 15:48:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YwZzTEZtT3OhzfEGWEmAag/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 15:48:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YwZzTEZtT3OhzfEGWEmAag/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:48:45 INFO - Downloaded 10834042 bytes. 15:48:45 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:48:45 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 15:48:45 INFO - Return code: 0 15:48:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:48:45 INFO - https://queue.taskcluster.net/v1/task/YwZzTEZtT3OhzfEGWEmAag/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 15:48:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YwZzTEZtT3OhzfEGWEmAag/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:48:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YwZzTEZtT3OhzfEGWEmAag/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:48:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YwZzTEZtT3OhzfEGWEmAag/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:48:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YwZzTEZtT3OhzfEGWEmAag/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 15:48:47 INFO - Downloaded 54911618 bytes. 15:48:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YwZzTEZtT3OhzfEGWEmAag/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:48:47 INFO - mkdir: /builds/slave/test/properties 15:48:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:48:47 INFO - Writing to file /builds/slave/test/properties/build_url 15:48:47 INFO - Contents: 15:48:47 INFO - build_url:https://queue.taskcluster.net/v1/task/YwZzTEZtT3OhzfEGWEmAag/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:48:48 INFO - Running post-action listener: _resource_record_post_action 15:48:48 INFO - Running post-action listener: set_extra_try_arguments 15:48:48 INFO - ##### 15:48:48 INFO - ##### Finished download-and-extract step (success) 15:48:48 INFO - ##### 15:48:48 INFO - ##### 15:48:48 INFO - ##### Running populate-webroot step. 15:48:48 INFO - ##### 15:48:48 INFO - Running pre-action listener: _resource_record_pre_action 15:48:48 INFO - Running main action method: populate_webroot 15:48:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:48:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:48:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:48:48 INFO - '--tppagecycles', 15:48:48 INFO - '7'], 15:48:48 INFO - 'tests': ['tsvgm']}}, 15:48:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:48:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:48:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:48:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:48:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:48:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:48:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:48:48 INFO - 'talos_options': ['--disable-e10s'], 15:48:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:48:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:48:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:48:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:48:48 INFO - 'talos_options': ['--disable-e10s'], 15:48:48 INFO - 'tests': ['damp', 'tps']}, 15:48:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:48:48 INFO - 'tests': ['damp', 'tps']}, 15:48:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:48:48 INFO - 'tests': ['dromaeo_dom']}, 15:48:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:48:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:48:48 INFO - 'tests': ['a11yr', 15:48:48 INFO - 'ts_paint', 15:48:48 INFO - 'tpaint', 15:48:48 INFO - 'sessionrestore', 15:48:48 INFO - 'sessionrestore_no_auto_restore', 15:48:48 INFO - 'tabpaint']}, 15:48:48 INFO - 'other-e10s': {'tests': ['a11yr', 15:48:48 INFO - 'ts_paint', 15:48:48 INFO - 'tpaint', 15:48:48 INFO - 'sessionrestore', 15:48:48 INFO - 'sessionrestore_no_auto_restore', 15:48:48 INFO - 'tabpaint']}, 15:48:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:48:48 INFO - 'ts_paint', 15:48:48 INFO - 'tpaint', 15:48:48 INFO - 'sessionrestore', 15:48:48 INFO - 'sessionrestore_no_auto_restore', 15:48:48 INFO - 'tabpaint']}, 15:48:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:48:48 INFO - 'ts_paint', 15:48:48 INFO - 'tpaint', 15:48:48 INFO - 'sessionrestore', 15:48:48 INFO - 'sessionrestore_no_auto_restore', 15:48:48 INFO - 'tabpaint']}, 15:48:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:48:48 INFO - 'tests': ['a11yr', 15:48:48 INFO - 'ts_paint', 15:48:48 INFO - 'tpaint', 15:48:48 INFO - 'sessionrestore', 15:48:48 INFO - 'sessionrestore_no_auto_restore', 15:48:48 INFO - 'tabpaint']}, 15:48:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:48:48 INFO - 'tests': ['a11yr', 15:48:48 INFO - 'ts_paint', 15:48:48 INFO - 'tpaint', 15:48:48 INFO - 'sessionrestore', 15:48:48 INFO - 'sessionrestore_no_auto_restore', 15:48:48 INFO - 'tabpaint']}, 15:48:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:48:48 INFO - 'tests': ['tsvgx', 15:48:48 INFO - 'tsvgr_opacity', 15:48:48 INFO - 'tart', 15:48:48 INFO - 'tscrollx', 15:48:48 INFO - 'cart']}, 15:48:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:48:48 INFO - 'tsvgr_opacity', 15:48:48 INFO - 'tart', 15:48:48 INFO - 'tscrollx', 15:48:48 INFO - 'cart']}, 15:48:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:48:48 INFO - 'talos_options': ['--disable-e10s'], 15:48:48 INFO - 'tests': ['tp5o']}, 15:48:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:48:48 INFO - 'tests': ['tp5o']}, 15:48:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:48:48 INFO - 'talos_options': ['--disable-e10s', 15:48:48 INFO - '--xperf_path', 15:48:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:48:48 INFO - 'tests': ['tp5n']}, 15:48:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:48:48 INFO - 'talos_options': ['--xperf_path', 15:48:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:48:48 INFO - 'tests': ['tp5n']}}, 15:48:48 INFO - 'talos.zip': {'path': '', 15:48:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:48:48 INFO - Running post-action listener: _resource_record_post_action 15:48:48 INFO - ##### 15:48:48 INFO - ##### Finished populate-webroot step (success) 15:48:48 INFO - ##### 15:48:48 INFO - ##### 15:48:48 INFO - ##### Running create-virtualenv step. 15:48:48 INFO - ##### 15:48:48 INFO - Running pre-action listener: _resource_record_pre_action 15:48:48 INFO - Running main action method: create_virtualenv 15:48:48 INFO - Creating virtualenv /builds/slave/test/build/venv 15:48:48 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:48:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:48:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:48:48 INFO - Using real prefix '/usr' 15:48:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:48:49 INFO - Installing distribute.............................................................................................................................................................................................done. 15:48:51 INFO - Installing pip.................done. 15:48:51 INFO - Return code: 0 15:48:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:48:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:48:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:48:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:51 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 0x7f32f6f36f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aca8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b95770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c249c0>, '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 0x1c258c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c25d50>, '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': '31acecf42b6a79ec49471d4a000001bb-1464388853.767874-1900377598', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:48:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:48:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:48:51 INFO - Using env: {'DISPLAY': ':0', 15:48:51 INFO - 'HOME': '/home/cltbld', 15:48:51 INFO - 'LANG': 'en_US.UTF-8', 15:48:51 INFO - 'LANGUAGE': 'en_US:en', 15:48:51 INFO - 'LOGNAME': 'cltbld', 15:48:51 INFO - 'MAIL': '/var/mail/cltbld', 15:48:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:51 INFO - 'MOZ_NO_REMOTE': '1', 15:48:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:51 INFO - 'NO_EM_RESTART': '1', 15:48:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:51 INFO - 'PWD': '/builds/slave/test', 15:48:51 INFO - 'SHELL': '/bin/bash', 15:48:51 INFO - 'SHLVL': '1', 15:48:51 INFO - 'TERM': 'linux', 15:48:51 INFO - 'TMOUT': '86400', 15:48:51 INFO - 'USER': 'cltbld', 15:48:51 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1464388853.767874-1900377598', 15:48:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:51 INFO - '_': '/tools/buildbot/bin/python'} 15:48:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:51 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 15:48:51 INFO - Cleaning up... 15:48:51 INFO - Return code: 0 15:48:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:48:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:48:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:48:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:51 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 0x7f32f6f36f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aca8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b95770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c249c0>, '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 0x1c258c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c25d50>, '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': '31acecf42b6a79ec49471d4a000001bb-1464388853.767874-1900377598', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:48:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:48:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:48:51 INFO - Using env: {'DISPLAY': ':0', 15:48:51 INFO - 'HOME': '/home/cltbld', 15:48:51 INFO - 'LANG': 'en_US.UTF-8', 15:48:51 INFO - 'LANGUAGE': 'en_US:en', 15:48:51 INFO - 'LOGNAME': 'cltbld', 15:48:51 INFO - 'MAIL': '/var/mail/cltbld', 15:48:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:51 INFO - 'MOZ_NO_REMOTE': '1', 15:48:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:51 INFO - 'NO_EM_RESTART': '1', 15:48:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:51 INFO - 'PWD': '/builds/slave/test', 15:48:51 INFO - 'SHELL': '/bin/bash', 15:48:51 INFO - 'SHLVL': '1', 15:48:51 INFO - 'TERM': 'linux', 15:48:51 INFO - 'TMOUT': '86400', 15:48:51 INFO - 'USER': 'cltbld', 15:48:51 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1464388853.767874-1900377598', 15:48:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:51 INFO - '_': '/tools/buildbot/bin/python'} 15:48:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:51 INFO - Downloading/unpacking psutil>=3.1.1 15:48:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:48:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:48:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:48:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:48:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:48:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:48:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:48:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:48:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:48:53 INFO - Installing collected packages: psutil 15:48:53 INFO - Running setup.py install for psutil 15:48:53 INFO - building 'psutil._psutil_linux' extension 15:48:53 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 15:48:53 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 15:48:53 INFO - building 'psutil._psutil_posix' extension 15:48:53 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 15:48:54 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 15:48:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:48:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:48:54 INFO - Successfully installed psutil 15:48:54 INFO - Cleaning up... 15:48:54 INFO - Return code: 0 15:48:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:48:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:48:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:48:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:54 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 0x7f32f6f36f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aca8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b95770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c249c0>, '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 0x1c258c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c25d50>, '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': '31acecf42b6a79ec49471d4a000001bb-1464388853.767874-1900377598', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:48:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:48:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:48:54 INFO - Using env: {'DISPLAY': ':0', 15:48:54 INFO - 'HOME': '/home/cltbld', 15:48:54 INFO - 'LANG': 'en_US.UTF-8', 15:48:54 INFO - 'LANGUAGE': 'en_US:en', 15:48:54 INFO - 'LOGNAME': 'cltbld', 15:48:54 INFO - 'MAIL': '/var/mail/cltbld', 15:48:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:54 INFO - 'MOZ_NO_REMOTE': '1', 15:48:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:54 INFO - 'NO_EM_RESTART': '1', 15:48:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:54 INFO - 'PWD': '/builds/slave/test', 15:48:54 INFO - 'SHELL': '/bin/bash', 15:48:54 INFO - 'SHLVL': '1', 15:48:54 INFO - 'TERM': 'linux', 15:48:54 INFO - 'TMOUT': '86400', 15:48:54 INFO - 'USER': 'cltbld', 15:48:54 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1464388853.767874-1900377598', 15:48:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:54 INFO - '_': '/tools/buildbot/bin/python'} 15:48:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:48:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:48:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:48:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:48:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:48:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:48:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:48:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:48:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:48:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:48:56 INFO - Installing collected packages: mozsystemmonitor 15:48:56 INFO - Running setup.py install for mozsystemmonitor 15:48:56 INFO - Successfully installed mozsystemmonitor 15:48:56 INFO - Cleaning up... 15:48:56 INFO - Return code: 0 15:48:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:48:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:48:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:48:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f32f6f36f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aca8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b95770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c249c0>, '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 0x1c258c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c25d50>, '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': '31acecf42b6a79ec49471d4a000001bb-1464388853.767874-1900377598', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:48:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:48:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:48:56 INFO - Using env: {'DISPLAY': ':0', 15:48:56 INFO - 'HOME': '/home/cltbld', 15:48:56 INFO - 'LANG': 'en_US.UTF-8', 15:48:56 INFO - 'LANGUAGE': 'en_US:en', 15:48:56 INFO - 'LOGNAME': 'cltbld', 15:48:56 INFO - 'MAIL': '/var/mail/cltbld', 15:48:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:56 INFO - 'MOZ_NO_REMOTE': '1', 15:48:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:56 INFO - 'NO_EM_RESTART': '1', 15:48:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:56 INFO - 'PWD': '/builds/slave/test', 15:48:56 INFO - 'SHELL': '/bin/bash', 15:48:56 INFO - 'SHLVL': '1', 15:48:56 INFO - 'TERM': 'linux', 15:48:56 INFO - 'TMOUT': '86400', 15:48:56 INFO - 'USER': 'cltbld', 15:48:56 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1464388853.767874-1900377598', 15:48:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:56 INFO - '_': '/tools/buildbot/bin/python'} 15:48:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:56 INFO - Downloading/unpacking blobuploader==1.2.4 15:48:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:48:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:48:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:48:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:48:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:48:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:48:58 INFO - Downloading blobuploader-1.2.4.tar.gz 15:48:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:48:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:48:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:48:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:48:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:48:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:48:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:48:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:48:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:48:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:48:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:48:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:48:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:48:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:48:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:48:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:48:59 INFO - Downloading docopt-0.6.1.tar.gz 15:48:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:48:59 INFO - Installing collected packages: blobuploader, requests, docopt 15:48:59 INFO - Running setup.py install for blobuploader 15:48:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:48:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:48:59 INFO - Running setup.py install for requests 15:48:59 INFO - Running setup.py install for docopt 15:48:59 INFO - Successfully installed blobuploader requests docopt 15:48:59 INFO - Cleaning up... 15:49:00 INFO - Return code: 0 15:49:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:49:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:49:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:49:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:00 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 0x7f32f6f36f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aca8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b95770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c249c0>, '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 0x1c258c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c25d50>, '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': '31acecf42b6a79ec49471d4a000001bb-1464388853.767874-1900377598', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:49:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:49:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:49:00 INFO - Using env: {'DISPLAY': ':0', 15:49:00 INFO - 'HOME': '/home/cltbld', 15:49:00 INFO - 'LANG': 'en_US.UTF-8', 15:49:00 INFO - 'LANGUAGE': 'en_US:en', 15:49:00 INFO - 'LOGNAME': 'cltbld', 15:49:00 INFO - 'MAIL': '/var/mail/cltbld', 15:49:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:00 INFO - 'MOZ_NO_REMOTE': '1', 15:49:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:00 INFO - 'NO_EM_RESTART': '1', 15:49:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:00 INFO - 'PWD': '/builds/slave/test', 15:49:00 INFO - 'SHELL': '/bin/bash', 15:49:00 INFO - 'SHLVL': '1', 15:49:00 INFO - 'TERM': 'linux', 15:49:00 INFO - 'TMOUT': '86400', 15:49:00 INFO - 'USER': 'cltbld', 15:49:00 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1464388853.767874-1900377598', 15:49:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:00 INFO - '_': '/tools/buildbot/bin/python'} 15:49:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:49:00 INFO - Running setup.py (path:/tmp/pip-i8QKge-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:49:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:49:00 INFO - Running setup.py (path:/tmp/pip-e27TB_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:49:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:49:00 INFO - Running setup.py (path:/tmp/pip-XQbdVa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:49:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:49:00 INFO - Running setup.py (path:/tmp/pip-BK4xVu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:49:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:49:00 INFO - Running setup.py (path:/tmp/pip-1OtEh8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:49:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:49:00 INFO - Running setup.py (path:/tmp/pip-4PyMi8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:49:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:49:00 INFO - Running setup.py (path:/tmp/pip-8UCQa3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:49:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:49:00 INFO - Running setup.py (path:/tmp/pip-smBefk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:49:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:49:00 INFO - Running setup.py (path:/tmp/pip-QFDJ5R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:49:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:49:00 INFO - Running setup.py (path:/tmp/pip-Qz2Rwr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:49:01 INFO - Running setup.py (path:/tmp/pip-AME51O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:49:01 INFO - Running setup.py (path:/tmp/pip-Baw_J7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:49:01 INFO - Running setup.py (path:/tmp/pip-QjFXcd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:49:01 INFO - Running setup.py (path:/tmp/pip-WuUxGj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:49:01 INFO - Running setup.py (path:/tmp/pip-kxEOtv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:49:01 INFO - Running setup.py (path:/tmp/pip-g9GpXj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:49:01 INFO - Running setup.py (path:/tmp/pip-C_OCJc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:49:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:49:01 INFO - Running setup.py install for manifestparser 15:49:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:49:01 INFO - Running setup.py install for mozcrash 15:49:01 INFO - Running setup.py install for mozdebug 15:49:01 INFO - Running setup.py install for mozdevice 15:49:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:49:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:49:01 INFO - Running setup.py install for mozfile 15:49:02 INFO - Running setup.py install for mozhttpd 15:49:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:49:02 INFO - Running setup.py install for mozinfo 15:49:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:49:02 INFO - Running setup.py install for mozInstall 15:49:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:49:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:49:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:49:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:49:02 INFO - Running setup.py install for mozleak 15:49:02 INFO - Running setup.py install for mozlog 15:49:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:49:02 INFO - Running setup.py install for moznetwork 15:49:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:49:02 INFO - Running setup.py install for mozprocess 15:49:02 INFO - Running setup.py install for mozprofile 15:49:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:49:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:49:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:49:02 INFO - Running setup.py install for mozrunner 15:49:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:49:02 INFO - Running setup.py install for mozscreenshot 15:49:03 INFO - Running setup.py install for moztest 15:49:03 INFO - Running setup.py install for mozversion 15:49:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:49:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:49:03 INFO - Cleaning up... 15:49:03 INFO - Return code: 0 15:49:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:49:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:49:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:49:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:03 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 0x7f32f6f36f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aca8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b95770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c249c0>, '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 0x1c258c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c25d50>, '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': '31acecf42b6a79ec49471d4a000001bb-1464388853.767874-1900377598', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:49:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:49:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:49:03 INFO - Using env: {'DISPLAY': ':0', 15:49:03 INFO - 'HOME': '/home/cltbld', 15:49:03 INFO - 'LANG': 'en_US.UTF-8', 15:49:03 INFO - 'LANGUAGE': 'en_US:en', 15:49:03 INFO - 'LOGNAME': 'cltbld', 15:49:03 INFO - 'MAIL': '/var/mail/cltbld', 15:49:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:03 INFO - 'MOZ_NO_REMOTE': '1', 15:49:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:03 INFO - 'NO_EM_RESTART': '1', 15:49:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:03 INFO - 'PWD': '/builds/slave/test', 15:49:03 INFO - 'SHELL': '/bin/bash', 15:49:03 INFO - 'SHLVL': '1', 15:49:03 INFO - 'TERM': 'linux', 15:49:03 INFO - 'TMOUT': '86400', 15:49:03 INFO - 'USER': 'cltbld', 15:49:03 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1464388853.767874-1900377598', 15:49:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:03 INFO - '_': '/tools/buildbot/bin/python'} 15:49:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:49:03 INFO - Running setup.py (path:/tmp/pip-Pp6Mwa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:49:03 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:49:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:49:03 INFO - Running setup.py (path:/tmp/pip-dKMT0q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:49:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:49:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:49:03 INFO - Running setup.py (path:/tmp/pip-fG8aD2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:49:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:49:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:49:03 INFO - Running setup.py (path:/tmp/pip-HW6Ueg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:49:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:49:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:49:03 INFO - Running setup.py (path:/tmp/pip-r06MZI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:49:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:49:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:49:03 INFO - Running setup.py (path:/tmp/pip-orGiKy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:49:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:49:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:49:03 INFO - Running setup.py (path:/tmp/pip-Fz42GY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:49:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:49:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:49:03 INFO - Running setup.py (path:/tmp/pip-dlAZOI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:49:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:49:04 INFO - Running setup.py (path:/tmp/pip-LzL1Jz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:49:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:49:04 INFO - Running setup.py (path:/tmp/pip-uiywYV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:49:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:49:04 INFO - Running setup.py (path:/tmp/pip-SqgJWy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:49:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:49:04 INFO - Running setup.py (path:/tmp/pip-JbOhGS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:49:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:49:04 INFO - Running setup.py (path:/tmp/pip-AINEZ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:49:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:49:04 INFO - Running setup.py (path:/tmp/pip-yOrmOo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:49:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:49:04 INFO - Running setup.py (path:/tmp/pip-P6osjG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:49:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:49:04 INFO - Running setup.py (path:/tmp/pip-4u9kkX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:49:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:49:04 INFO - Running setup.py (path:/tmp/pip-AJJpl9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:49:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:49:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:49:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:49:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:49:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:49:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:49:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:49:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:49:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:49:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:06 INFO - Downloading blessings-1.6.tar.gz 15:49:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:49:06 INFO - Installing collected packages: blessings 15:49:06 INFO - Running setup.py install for blessings 15:49:06 INFO - Successfully installed blessings 15:49:06 INFO - Cleaning up... 15:49:06 INFO - Return code: 0 15:49:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:49:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:49:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:49:07 INFO - Reading from file tmpfile_stdout 15:49:07 INFO - Current package versions: 15:49:07 INFO - argparse == 1.2.1 15:49:07 INFO - blessings == 1.6 15:49:07 INFO - blobuploader == 1.2.4 15:49:07 INFO - docopt == 0.6.1 15:49:07 INFO - manifestparser == 1.1 15:49:07 INFO - mozInstall == 1.12 15:49:07 INFO - mozcrash == 0.17 15:49:07 INFO - mozdebug == 0.1 15:49:07 INFO - mozdevice == 0.48 15:49:07 INFO - mozfile == 1.2 15:49:07 INFO - mozhttpd == 0.7 15:49:07 INFO - mozinfo == 0.9 15:49:07 INFO - mozleak == 0.1 15:49:07 INFO - mozlog == 3.2 15:49:07 INFO - moznetwork == 0.27 15:49:07 INFO - mozprocess == 0.22 15:49:07 INFO - mozprofile == 0.28 15:49:07 INFO - mozrunner == 6.11 15:49:07 INFO - mozscreenshot == 0.1 15:49:07 INFO - mozsystemmonitor == 0.3 15:49:07 INFO - moztest == 0.7 15:49:07 INFO - mozversion == 1.4 15:49:07 INFO - psutil == 3.1.1 15:49:07 INFO - requests == 1.2.3 15:49:07 INFO - wsgiref == 0.1.2 15:49:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:49:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:49:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:49:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:07 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 0x7f32f6f36f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aca8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b95770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c249c0>, '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 0x1c258c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c25d50>, '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': '31acecf42b6a79ec49471d4a000001bb-1464388853.767874-1900377598', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:49:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 15:49:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:49:07 INFO - Using env: {'DISPLAY': ':0', 15:49:07 INFO - 'HOME': '/home/cltbld', 15:49:07 INFO - 'LANG': 'en_US.UTF-8', 15:49:07 INFO - 'LANGUAGE': 'en_US:en', 15:49:07 INFO - 'LOGNAME': 'cltbld', 15:49:07 INFO - 'MAIL': '/var/mail/cltbld', 15:49:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:07 INFO - 'MOZ_NO_REMOTE': '1', 15:49:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:07 INFO - 'NO_EM_RESTART': '1', 15:49:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:07 INFO - 'PWD': '/builds/slave/test', 15:49:07 INFO - 'SHELL': '/bin/bash', 15:49:07 INFO - 'SHLVL': '1', 15:49:07 INFO - 'TERM': 'linux', 15:49:07 INFO - 'TMOUT': '86400', 15:49:07 INFO - 'USER': 'cltbld', 15:49:07 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1464388853.767874-1900377598', 15:49:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:07 INFO - '_': '/tools/buildbot/bin/python'} 15:49:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:49:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:49:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:49:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:49:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:49:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:49:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:49:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:49:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:49:07 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:49:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:49:07 INFO - Cleaning up... 15:49:07 INFO - Return code: 0 15:49:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:49:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:49:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:49:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:07 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 0x7f32f6f36f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aca8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b95770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c249c0>, '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 0x1c258c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c25d50>, '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': '31acecf42b6a79ec49471d4a000001bb-1464388853.767874-1900377598', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:49:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:49:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:49:07 INFO - Using env: {'DISPLAY': ':0', 15:49:07 INFO - 'HOME': '/home/cltbld', 15:49:07 INFO - 'LANG': 'en_US.UTF-8', 15:49:07 INFO - 'LANGUAGE': 'en_US:en', 15:49:07 INFO - 'LOGNAME': 'cltbld', 15:49:07 INFO - 'MAIL': '/var/mail/cltbld', 15:49:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:07 INFO - 'MOZ_NO_REMOTE': '1', 15:49:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:07 INFO - 'NO_EM_RESTART': '1', 15:49:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:07 INFO - 'PWD': '/builds/slave/test', 15:49:07 INFO - 'SHELL': '/bin/bash', 15:49:07 INFO - 'SHLVL': '1', 15:49:07 INFO - 'TERM': 'linux', 15:49:07 INFO - 'TMOUT': '86400', 15:49:07 INFO - 'USER': 'cltbld', 15:49:07 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1464388853.767874-1900377598', 15:49:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:07 INFO - '_': '/tools/buildbot/bin/python'} 15:49:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:07 INFO - Downloading/unpacking jsonschema 15:49:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:49:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:49:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:49:09 INFO - Downloading/unpacking functools32 (from jsonschema) 15:49:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:49:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:49:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:09 INFO - Downloading functools32-3.2.3-2.tar.gz 15:49:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:49:09 INFO - warning: no files found matching '*.txt' 15:49:09 INFO - no previously-included directories found matching 'build' 15:49:09 INFO - no previously-included directories found matching 'dist' 15:49:09 INFO - no previously-included directories found matching '.git*' 15:49:09 INFO - Installing collected packages: jsonschema, functools32 15:49:09 INFO - Running setup.py install for functools32 15:49:09 INFO - warning: no files found matching '*.txt' 15:49:09 INFO - no previously-included directories found matching 'build' 15:49:09 INFO - no previously-included directories found matching 'dist' 15:49:09 INFO - no previously-included directories found matching '.git*' 15:49:09 INFO - Successfully installed jsonschema functools32 15:49:09 INFO - Cleaning up... 15:49:09 INFO - Return code: 0 15:49:09 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:49:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:49:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:49:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:09 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 0x7f32f6f36f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aca8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b95770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c249c0>, '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 0x1c258c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c25d50>, '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': '31acecf42b6a79ec49471d4a000001bb-1464388853.767874-1900377598', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:49:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 15:49:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 15:49:09 INFO - Using env: {'DISPLAY': ':0', 15:49:09 INFO - 'HOME': '/home/cltbld', 15:49:09 INFO - 'LANG': 'en_US.UTF-8', 15:49:09 INFO - 'LANGUAGE': 'en_US:en', 15:49:09 INFO - 'LOGNAME': 'cltbld', 15:49:09 INFO - 'MAIL': '/var/mail/cltbld', 15:49:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:09 INFO - 'MOZ_NO_REMOTE': '1', 15:49:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:09 INFO - 'NO_EM_RESTART': '1', 15:49:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:09 INFO - 'PWD': '/builds/slave/test', 15:49:09 INFO - 'SHELL': '/bin/bash', 15:49:09 INFO - 'SHLVL': '1', 15:49:09 INFO - 'TERM': 'linux', 15:49:09 INFO - 'TMOUT': '86400', 15:49:09 INFO - 'USER': 'cltbld', 15:49:09 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1464388853.767874-1900377598', 15:49:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:09 INFO - '_': '/tools/buildbot/bin/python'} 15:49:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:10 INFO - Downloading/unpacking flake8 15:49:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:49:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:49:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:11 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:49:11 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:49:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:49:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:49:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:12 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:49:12 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:49:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:49:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:49:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:12 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:49:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:49:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:49:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:49:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:49:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:49:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:49:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:49:12 INFO - no previously-included directories found matching 'docs/_build' 15:49:12 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:49:12 INFO - Running setup.py install for pep8 15:49:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:49:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:49:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:49:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:49:12 INFO - no previously-included directories found matching 'docs/_build' 15:49:12 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:49:12 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:49:12 INFO - Cleaning up... 15:49:12 INFO - Return code: 0 15:49:12 INFO - Running post-action listener: _resource_record_post_action 15:49:12 INFO - Running post-action listener: _start_resource_monitoring 15:49:12 INFO - Starting resource monitoring. 15:49:12 INFO - ##### 15:49:12 INFO - ##### Finished create-virtualenv step (success) 15:49:12 INFO - ##### 15:49:12 INFO - ##### 15:49:12 INFO - ##### Running install step. 15:49:12 INFO - ##### 15:49:12 INFO - Running pre-action listener: _resource_record_pre_action 15:49:12 INFO - Running main action method: install 15:49:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:49:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:49:13 INFO - Reading from file tmpfile_stdout 15:49:13 INFO - Detecting whether we're running mozinstall >=1.0... 15:49:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:49:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:49:13 INFO - Reading from file tmpfile_stdout 15:49:13 INFO - Output received: 15:49:13 INFO - Usage: mozinstall [options] installer 15:49:13 INFO - Options: 15:49:13 INFO - -h, --help show this help message and exit 15:49:13 INFO - -d DEST, --destination=DEST 15:49:13 INFO - Directory to install application into. [default: 15:49:13 INFO - "/builds/slave/test"] 15:49:13 INFO - --app=APP Application being installed. [default: firefox] 15:49:13 INFO - mkdir: /builds/slave/test/build/application 15:49:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:49:13 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 15:49:29 INFO - Reading from file tmpfile_stdout 15:49:29 INFO - Output received: 15:49:29 INFO - /builds/slave/test/build/application/firefox/firefox 15:49:29 INFO - Running post-action listener: _resource_record_post_action 15:49:29 INFO - ##### 15:49:29 INFO - ##### Finished install step (success) 15:49:29 INFO - ##### 15:49:29 INFO - ##### 15:49:29 INFO - ##### Running run-tests step. 15:49:29 INFO - ##### 15:49:29 INFO - Running pre-action listener: _resource_record_pre_action 15:49:29 INFO - Running main action method: run_tests 15:49:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:49:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:49:29 INFO - Python 2.7.3 15:49:29 INFO - Return code: 0 15:49:29 INFO - grabbing minidump binary from tooltool 15:49: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')]} 15:49:29 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 0x1c249c0>, '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 0x1c258c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c25d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:49:29 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 15:49:29 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 15:49:29 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 15:49:29 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:49:29 INFO - Return code: 0 15:49:29 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:49:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:49:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:49:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:49:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:49:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:49:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:49:30 INFO - Return code: 0 15:49:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YwZzTEZtT3OhzfEGWEmAag/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--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 15:49:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YwZzTEZtT3OhzfEGWEmAag/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-014 --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 15:49:30 INFO - Using env: {'DISPLAY': ':0', 15:49:30 INFO - 'HOME': '/home/cltbld', 15:49:30 INFO - 'LANG': 'en_US.UTF-8', 15:49:30 INFO - 'LANGUAGE': 'en_US:en', 15:49:30 INFO - 'LOGNAME': 'cltbld', 15:49:30 INFO - 'MAIL': '/var/mail/cltbld', 15:49:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:49:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:49:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:30 INFO - 'MOZ_NO_REMOTE': '1', 15:49:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:49:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:30 INFO - 'NO_EM_RESTART': '1', 15:49:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:30 INFO - 'PWD': '/builds/slave/test', 15:49:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:49:30 INFO - 'SHELL': '/bin/bash', 15:49:30 INFO - 'SHLVL': '1', 15:49:30 INFO - 'TERM': 'linux', 15:49:30 INFO - 'TMOUT': '86400', 15:49:30 INFO - 'USER': 'cltbld', 15:49:30 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1464388853.767874-1900377598', 15:49:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:30 INFO - '_': '/tools/buildbot/bin/python'} 15:49:30 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YwZzTEZtT3OhzfEGWEmAag/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--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 15:49:30 INFO - mozversion application_buildid: 20160527143140 15:49:30 INFO - mozversion application_changeset: 39dcfacc4b5b91b0d0c66ecc144aacf00fca7c2a 15:49:30 INFO - mozversion application_display_name: Nightly 15:49:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:49:30 INFO - mozversion application_name: Firefox 15:49:30 INFO - mozversion application_remotingname: firefox 15:49:30 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 15:49:30 INFO - mozversion application_vendor: Mozilla 15:49:30 INFO - mozversion application_version: 49.0a1 15:49:30 INFO - mozversion platform_buildid: 20160527143140 15:49:30 INFO - mozversion platform_changeset: 39dcfacc4b5b91b0d0c66ecc144aacf00fca7c2a 15:49:30 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 15:49:30 INFO - mozversion platform_version: 49.0a1 15:49:30 INFO - using testdate: 1464389370 15:49:30 INFO - actual date: 1464389370 15:49:30 INFO - starting webserver on 'localhost:55488' 15:49:30 INFO - SUITE-START | Running 2 tests 15:49:30 INFO - TEST-START | dromaeo_css 15:49:30 INFO - operating with platform_type : linux_ 15:49:30 INFO - Initialising browser for dromaeo_css test... 15:49:30 INFO - TEST-INFO | started process 23339 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8mUmG7/profile http://localhost:55488/getInfo.html) 15:49:34 INFO - PROCESS | 23339 | __metrics Screen width/height:1600/1200 15:49:34 INFO - PROCESS | 23339 | colorDepth:24 15:49:34 INFO - PROCESS | 23339 | Browser inner width/height: 1024/697 15:49:34 INFO - PROCESS | 23339 | __metrics 15:49:35 ERROR - PROCESS | 23339 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:49:36 INFO - TEST-INFO | 23339: exit 0 15:49:36 INFO - Browser initialized. 15:49:36 INFO - Running cycle 1/1 for dromaeo_css test... 15:49:36 INFO - TEST-INFO | started process 23418 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8mUmG7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:49:48 INFO - PROCESS | 23418 | RSS: Main: 156913664 15:49:48 INFO - PROCESS | 23418 | 15:50:39 INFO - PROCESS | 23418 | 1464389439982 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] 15:52:51 INFO - PROCESS | 23418 | Cycle 1(1): loaded http://localhost:55488/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55488/tests/dromaeo/cssquery-ext.html) 15:52:52 INFO - PROCESS | 23418 | RSS: Main: 259203072 15:52:52 INFO - PROCESS | 23418 | 15:55:55 INFO - PROCESS | 23418 | Cycle 1(1): loaded http://localhost:55488/tests/dromaeo/cssquery-ext.html (next: http://localhost:55488/tests/dromaeo/cssquery-jquery.html) 15:55:55 INFO - PROCESS | 23418 | RSS: Main: 243965952 15:55:55 INFO - PROCESS | 23418 | 15:58:58 INFO - PROCESS | 23418 | Cycle 1(1): loaded http://localhost:55488/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55488/tests/dromaeo/cssquery-mootools.html) 15:58:58 INFO - PROCESS | 23418 | RSS: Main: 239235072 15:58:58 INFO - PROCESS | 23418 | 16:02:01 INFO - PROCESS | 23418 | Cycle 1(1): loaded http://localhost:55488/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55488/tests/dromaeo/cssquery-prototype.html) 16:02:01 INFO - PROCESS | 23418 | RSS: Main: 234274816 16:02:01 INFO - PROCESS | 23418 | 16:05:04 INFO - PROCESS | 23418 | Cycle 1(1): loaded http://localhost:55488/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55488/tests/dromaeo/cssquery-yui.html) 16:05:05 INFO - PROCESS | 23418 | RSS: Main: 204230656 16:05:05 INFO - PROCESS | 23418 | 16:08:08 INFO - PROCESS | 23418 | Cycle 1(1): loaded http://localhost:55488/tests/dromaeo/cssquery-yui.html (next: http://localhost:55488/tests/dromaeo/cssquery-dojo.html) 16:08:08 INFO - PROCESS | 23418 | RSS: Main: 301936640 16:08:08 INFO - PROCESS | 23418 | 16:08:08 INFO - PROCESS | 23418 | __start_tp_report 16:08:08 INFO - PROCESS | 23418 | _x_x_mozilla_page_load 16:08:08 INFO - PROCESS | 23418 | _x_x_mozilla_page_load_details 16:08:08 INFO - PROCESS | 23418 | |i|pagename|runs| 16:08:08 INFO - PROCESS | 23418 | |0;dojo.html;2658.1626787561927;2662.853543055132;2684.0739944719066;2699.4601079784047;2713.7421944915227;4674.4624368197665;4678.181318269302;4699.412573428322;4738.407699037624;4789.544993225642;4711.976440117804;4738.123447162277;4757.548032936874;4764.857054288377;4790.185668436366;4373.978130109345;4398.626116780078;4447.777611119447;4451.88870278244;4455.710378825383;4378.40891479651;4413.4041904342985;4435.512093669694;4437.733735975836;4496.348029535716;4358.019445624741;4390.736555806662;4418.204723149832;4435.512093669694;4453.33200019998;4308.870733877989;4313.029568347129;4345.2613055780275;4349.760763158025;4370.934435983463;4250.574942505756;4281.743095414286;4282.486101667789;4336.067745434747;4382.627476664487;4626.30605409188;4647.674662773574;4650.069986002779;4651.651126165551;4654.394928659286;4956.504349565051;5053.090443720129;5062.392512898476;5111.616628752821;5119.0273847968765;5147.6654017488745;5158.819441319536;5186.740662966837;5204.739763011834;5210.947890521084;4792.185328494164;4796.880077998008;4798.23228283473;4807.687500312469;4841.927371089437;5083.085044691917;5102.9489705103215;5156.04613146569;5161.690298582097;5171.7155556444;5028.748562571856;5059.241113832881;5078.746062696924;5090.720010399464;5091.465396133427;5111.642185047085;5112.284280200774;5117.334746483008;5118.539331460186;5120.641555091182;4927.827526036644;4947.678400903929;4951.059298733229;5001.949980500149;5069.923951140736;4986.102501549756;4992.126377883856;5003.624728145403;5010.3737032870895;5042.495750424928;4260;4262.936055959163;4274.1451709658195;4296.076343586144;4315.180115777992;3692.649198326155;3695.75977561463;3734.2344819311975;3739.5886452490204;3759.9436008459893;4366.868993930188;4377.299632058919;4400.735955842596;4429;4440.2007638625355;5061.392632884003;5087.542121209109;5094.2103973884105;5095.490450954875;5125.666831655931;4284.67864910133;4334.696571239983;4341.196878577473;4380.627646650007;4384.956150438518;4560.543945605413;4560.566746159109;4575.199340115533;4591.150637132075;4601.838935637304;4790.4251489821445;4794.304825800239;4816.060868130751;4823.541763532458;4862.95137048625;4842.249451335048;5063.949360506348;5076.720780356968;5109.8722531937;5118.232265160255;4839.588634966068;4849.127157111754;4854.975725121351;4861.124997500484;4899.6570240084275;5013.248012797963;5026.673266237829;5028.673136246132;5044;5087.058894104454;4948.035133149001;5009.5491405773655;5051.595872330131;5072.492750725046;5095.515925987025;4914.533119353656;4937.333459982857;4941.703497790144;4947.678400903929;4999.375078115236;4882.658213924991;4922;4945.208766597327;4947.3321101652255;4981.402231732215;4763.904721905612;4805.975970120127;4820.831270905502;4868.196747536616;4898;4963.379577552806;4976.900461990812;4986.47641997585;5023.246513023076;5032.899342013066;4743.786529606107;4796.928046079382;4861.756912154449;4876.902461950812;4932.235503496964 16:08:08 INFO - PROCESS | 23418 | |1;ext.html;560.4290845362015;573.9225204597378;590.9143174239734;599.8380437281937;601.9075378188585;39792.204155916865;40850.387244191326;40869.18261634766;40908.79545602268;44321.335179972244;41855;42371;42497.78751106248;42516;42525.574744252546;7412.369948554379;7586.620668966557;7594.354479869217;7748.070231572206;7912.960435197816;7602.201768814278;10308.948455257712;10639.361638301689;10640.935906409335;10641.095506881924;10296.948515257413;10333.689989300296;10357.533910974023;10365.326253793517;10370.844437333446;7492.625368731542;7711.305982461549;10228.386296822177;10293.897061029369;10299.330543514647;7625;9772.41365518064;10198.082172604427;10209.2343074269;10516.68449946503;38895.41656875149;39301;39301.803490982646;39303;39348.60651393478;3023.289526961162;3051.1456792098247;3059.682198755058;3092.412441636082;3190.7287880530184;30163;30555;30700.53949190764;30843.074707758802;31755.84122079398;3443.2080621457203;3508.2457271686453;3525.224450620885;3562.412201986669;3564.679178873888;251473;251541;252021;252782;252935;55212.72393638086;55350.16974745382;55393.16910246349;55557.16664250039;55568.44431555633;51976;52129.47870521322;52188.21717674238;52411.21383179256;52459;14329.713405732036;14518.927405363116;14519.491817786538;14519.927400363142;14537.127772333693;10155.39067655943;10712.089472394933;10814.56741730322;11524.654260372203;11575.073994080454;18338.541536461427;18345.633087338178;18373.816261837477;18388.356407525673;18416.723749143774;6825.726970921207;7006.229314775371;7092.361687448051;7092.482168830094;7104.25405332443;879.2482427524502;906.5467266366817;917.7200953709049;924.5007695844304;953.1755031897497;27120.59319110215;28000.45894213816;28227.6751232844;28310.71689283122;28592.42815143727;967.2310513142069;986.5807032011367;994.4928086675703;1003.0821798054844;1028.7068185567077;14581.270936453135;15096.094234345974;15114.924425378023;15272;15448.459303924534;397619;400967;403510;404190;407136;49325;49720.50279497159;49821.25268120981;49842.50157498451;49900.50099499031;1081.324172392255;1133.6372360844616;1200.6278053803348;1204.8373469581495;1211.745533437988;27530.587041193998;29261.414771704873;29290.56064159082;29741.256468588457;29967;42376.36435453409;42714.5728542723;43024.78487607542;43170;43204.56795432131;227682;229032;229101;229706;230717;21939;23176.536469270857;24459.633105503075;25240.116595919055;25286;40193.65862920026;40681;40803.38794918019;40814.59185408108;41141.1771764569;35736.821315894296;35788.4631730519;35797;36704;36746.63253367432;35325.78299776291;35890.64109358873;36021.27957440889;36242.4563631551;36403.817980910986;35070;35564.466533001505;36168.09579760527;36214.4567831488;36301.0924726884;31186.68813311838;32361.35277294488;32393.83803081064;32472.675273246965;32722.83638581792;24565.508689825747;24891.128810491547;25001.374965625277;25464.872675636503;25659.87170064138 16:08:08 INFO - PROCESS | 23418 | |2;jquery.html;356.0688798791161;359.19361034477595;359.31550396494686;370.497975243545;371.32418997424696;13978.231197284158;15572;17028.659426811457;17455.95264284144;17563.209655565515;14371.64070898228;14733.631659208526;14740.557783266493;14743;14804.6298842529;4897.951020489803;5073.771680274378;5307.964946835368;5330.200469929503;5395.892082158354;3478.9478157827652;3643.653852883985;5085.516875896784;5348.438413966536;5361.597880159004;5382.381026182;5422;5466.098093814516;5473.397926228112;5476.342838859342;5202.2456743771945;5294.894102117955;5341.545968592674;5349.652272602308;5361.651492652965;5204.479552044803;5212.4266330703595;5213.556847667953;5214.947850521484;5216.191490319006;11522;12087.335196564185;12102.878971210263;12107.818382724266;12114.394280285951;1758.2791055667242;1934.661434249015;1989.8209161175419;2136.166894910986;2152.817010554105;9109.908900910972;9163.450192988443;9233.399829011092;9367.812643747155;9571.28215383842;1670.8078570964374;1729.4984454508178;1740.364766860087;1754.4210410564483;1801.5406071451828;64691;65533.67233163804;66821.33178668248;66898;66989.66505167492;9613.423194608346;10282.742931426625;10288.2798204125;10619;10687.14502839771;14118.64703382403;14222.288885555889;14231.715365692628;14236.85763142376;14243.78634320486;7200.207977122513;7283.562986220843;7332.450066245053;7427.108746950401;7478.663460144307;6467.223933127961;6557.049227861933;6663.533552651268;6957.65211739411;7006.334398232262;4320.935185972213;4364.8908777280185;4407.559244075567;4428.756418396954;4435.068635586498;1615.9272832722556;1692.636083242109;1739.1304347826087;1789.3647755047032;1842.9078546072642;761.3528500774297;852.4714676900361;857.8541647919868;878.2798105553512;894.338189739601;15965.36138554468;16865.241064151898;16913.323467061426;17060.488185354458;17374.305027799;867.1675191815928;881.8280435315052;897.8249241397865;912.7261821453537;912.885889263842;9432.103950124727;9453.858192127123;9650;9701.029897010243;9873.160781333523;382361;386279;386901;389690;392007;48428.75785621049;75061.62469187728;75815.62092189504;76426.61786691031;77571.22428775753;910.4719262827545;910.9635614575343;923.298293297099;924.2328867040475;928.6053427293373;14833.368329483965;14918.328675209425;15104.791985293768;15210.923945380202;15315.693686125993;33015.17462063468;33591.10174779922;33890.32219355649;34362.484562732076;34790.47814282737;42345;48357.75821120872;48471.27293090677;48705.26942095942;48857.267140992204;7182.461315401261;7297.635118244173;7425.034745483053;7745.496542724705;7790.493617914817;8579.14208579137;10057.396556206359;10264;10895.400752958663;11127.276727012719;8893.510856902933;9053.68312109073;9074.228690561376;9288.62845486173;9532.780145024823;9432.198263147435;9443.480608566595;9858.162056225301;9935.354201976848;10073;8729.519876406604;9180.54097295146;9258.53707314645;9519.333646644669;9608.951955240458;6770.458363330823;7007.649617519206;7220.38626716735;7227.241139680258;7776.105747839071;7503.624818758931;9368.203702685076;9759.658411955548;10078.143357814668;10376.118029967236 16:08:08 INFO - PROCESS | 23418 | |3;mootools.html;1248.675344410454;1409.429181181621;1441.178528238904;1533.3559904839974;1741.294775615876;6595.439387652049;7520.548767073979;7782.299593036626;7791.259830316125;7968.6015699215095;7707.807304817382;7886.80282992924;7896.842063158733;7898.091719452264;7903.723369682062;1891.2151457145337;2117.1319758898853;2208.072609504004;2241.1707668162762;2270.3642979965553;2273.318004598622;2294.139697613395;2294.403455101677;2295.494991101955;2298.804601608857;2209.436593668612;2214.081156320125;2242.237639202671;2256.6953461282765;2257.209976508217;1820.726890966359;2032.6849338352579;2103.7265155529835;2104.389726979174;2134.5944270588616;2149.6668016457475;2153.095699806085;2160.9243676471406;2175.380016695234;2177.51006023645;7717.459777815555;7771.261730135628;7782.571958542278;7866.567338796364;7869.252421020051;4624.745638989854;4716.669833111683;4753.548412900769;4754.310624959396;4756.096341695102;2743.3278846682692;2868.756155726766;2974.449726800527;2990.177701132184;2999.35513864518;902.0798785239019;906.0531744327194;912.8174365126935;914.6752902719505;921.0559176843722;10927.781444371147;10991.670249892515;11021.173411994132;11028.779424411547;11037;4183.853565125206;4210.642095421862;4286.7856607169515;4321.58944900234;4324.654027677778;9340.906590934004;9355.251579873593;9370.906290937004;9371;9378.249740020781;4512.075024619945;4512.774361281989;4517.480489743722;4522.4346956630425;4525.298578720303;3808.1812410331913;4202.159568086395;4308.246056940023;4316.913661726748;4441.977790111029;3691.5201023866725;3693.3351996640326;3766.7928263945746;3824.4072168813877;3906.7069969752383;1181.3384504677235;1192.5110704611068;1202.1104382756696;1205.3189947679532;1210.0501106631427;754.4266357568288;812.6830536090929;845.6532821543137;868.2359523619175;882.6557642519423;5239.083160446907;5280.128778751539;5310.867228319323;5473.644213126134;5504.944950550524;391.5555844116891;393.02333700753604;394.1506054452659;395.9861404850874;907.7503686486269;3539.7168226541817;3654.6477803212983;3764.5294338207723;3778.263238668488;3781.7541859779026;9371.859422108673;9583.664571739955;9585.329026968046;9652.22782177424;9715.125638692552;9516;9570.33007689469;9589;9599.568019439143;9614.278929080347;7248.130224373109;7272.818179545554;7334.816629584303;7360.889586656204;7507.1742108368035;5129.717865517432;5208.713520756243;5211.322528071327;5218.843434696965;5222.138347172826;8796.560171991247;8924.776880578038;8932.776680583038;9057.547122643973;9089.454632722058;8968.596413161555;9116.36185467011;9141.222996045144;9298.442093474414;9314.44113353201;5804.82585522435;5993.9700301498215;6106.3283038866575;6395.744170233139;6501.837454063497;7083;7256.45576581769;7323.084614423197;7325.816854578678;7330.780076597711;6388.8083357499345;6596.175478065242;6649.567778094409;6708.932910670831;6785.525013249223;6503.512236582181;6532.248791389051;6641.867162656817;6644.568103073478;6715.1941766988275;6257.2804127525915;6348.079528468438;6349.936500634934;6354.586951848115;6367.52243581724;4308;4422.867313980586;4432.911341773211;4465.821367145408;4513.68404211702;4623.907521849612;4633.119707255611;4646.697964632288;4691.741954192552;4694.6009589185305 16:08:08 INFO - PROCESS | 23418 | |4;prototype.html;292.43413993921763;298.1993347860993;300.52967106478354;309.96471784240634;310.2940809658028;4828.468868424475;4845.85462436127;4877.365942427479;4890.6087512998965;4914.410270767504;4733.952660473403;4734.47281775078;4763.285507173926;4801.615870730342;4803.615710743141;3787.3182827091114;3829.5404551453867;3834.9041273968232;3843.1160833008425;3852.8073596320214;3224.4679627861374;3564.7326450516184;3821.636944490269;3835.8465661373652;3874.903127421823;3633.2370202257557;3704.2035962268105;3707.796071216082;3752.3996160614306;3755.136318646713;3546.3616549021167;3731.1791405890663;3740.1023754299044;3754.924901501982;3792.7345085844004;3326.7172290355347;3329.7003269705842;3330.900072997814;3348.5814273215847;3358.697717205463;4434.756088415136;4489.236829738952;4545.340925565807;4569.1318649456825;4575.794089265958;4226.640735537483;4229.555896630871;4242.809073591696;4256.2338779019765;4261.616454519108;5005.9499405005845;5015.749212539358;5022.773975171126;5034.489653104054;5035.84892453227;3605.837737301828;3612.049493273629;3616.7160657966306;3624.2026754114054;3647.4893514907935;5042.949570504248;5229.320188375525;5232.2674825524455;5256.342957130359;5263.894722105575;3604.747667663291;3612.223371975038;3617.8061239790804;3621.6901886754677;3629.074585980558;3561.3973711829262;3587.264610754841;3671.761335513183;3719.702423806089;3747.2880152770886;4220.810063547147;4224.704270701083;4230.894227644333;4231.534531201565;4241.066965267636;4737.6683632146105;4944.70071416793;4987.750612469362;5002.724850133205;5040.697558146523;1297.8183054372396;1319.221659221064;1321.6035189443128;1323.450767931317;1329.7739384304691;819.1235378145327;841.9789505262418;845.3828705045352;850.2560259772699;861.3841103610948;3994.3423479256367;4015.9196816063513;4027.657649099801;4041.818118184689;4045.9682780890685;4556.498785133633;4564.3838081859185;4589.58693717567;4611.492735799059;4611.654125940568;3986.7607943522903;3998.520177578709;4009.979950100231;4023.5574086850424;4024.2293390908494;2293.988530057339;2428.805695544352;2448.9142879999113;2456.299954512991;2470.641756945233;4380.7371557706;4382.496012958487;4390.275604525217;4412.84555040572;4433.46798384196;4692.554207350297;4693.929591056137;4699.835505757282;4711.751385882744;4717.490403071053;4097.91804163921;4369.558045844864;4399.1641588098155;4438.9334159988275;4444.955550444454;4573.885652858572;4580.3587497751005;4590.449146102489;4623.797812568823;4632.305154226893;4453.665975051949;4502.707324023928;4531.886702832456;4543.432070991126;4601.33280674307;4340.88640495338;4396.208682437192;4400.757958312323;4416.6025057744955;4639.260112838768;3617.1499697570525;3706.129059671029;3718.456840411199;3733.6266373362446;3735.4583585379423;5162.741862906764;5172.741362931763;5208.296879921314;5211.270422140828;5218.9739051304505;4910.361652985089;4996.325496057985;5023.317188741812;5065.214891691794;5067.062593420229;4945.381827271591;4984.327115839315;4989.151844186569;4991;5013.548780609617;4822.855314340575;4895.8041678332465;4942.901141977069;4947.406311242674;4949.802007919643;4806.519348065305;4990.076835785391;5062.443131255633;5065.721385323843;5070.974645126751;4909.582685471775;4912.557869791736;4921.778519966539;4927.901441971212;4955.45489996093 16:08:08 INFO - PROCESS | 23418 | |5;yui.html;540.0980362794133;591.8697886464978;593.8348601951599;647.1102234427661;732.6886073418799;11862.703432414182;12539.498420063186;12561.057920655941;12564.246145231282;12614.873851261485;10632;12023.098267629919;12108.152429329928;12125.211861229014;12143;783.1541934710499;807.5760225881401;808.9838203235931;811.0632219786127;813.2071230550196;800.3437181511163;803.5821372886096;806.8144326804838;806.9798255043642;807.0114110214969;662.443547420167;809.6639894443799;840.970566030189;843.439112989861;843.4559708987699;827.7723626002837;828.1097819843692;829.5561874397168;829.784256093414;831.8003679116998;611.7461253579759;611.831746269775;611.9418655227746;612.5895649914568;613.7115555688819;12445.066620003445;12454.190477618926;12498.875011249862;12503.624891253277;12521;11641.708957276136;11858.407079645984;12112.276103101836;12149.514019439212;12513.874861251363;10129.797404051951;10279.617586237879;10406.323588966692;10525.368477891274;10720.195985301134;3867.825947832326;3927.155661532784;3944.1717239379764;3946.7631942083567;4062.5531191568903;48360;49158.26262606064;49185.26222106671;49186;49274.75362623164;8717.389782715276;8958.731238062868;9205.079492050876;9319.860202096974;9777.560009799576;15724.60688482797;15753.68492630141;15764.132972686382;15764.921175394278;15771.369145234292;6056.242969628796;6057.394260573996;6094;6209.534284928648;6272.498200143978;3351.1454579082665;3351.3464874349756;3397.2186397128303;3430.245346023921;3490.406630872758;2483.3046746910895;2569.306287302455;2571.3828681116397;2581.9354516136873;2581.9612705809427;454.8203459633426;455.69696152059174;463.53414818107586;471.5096299848188;472.0393998213619;520.7344254430268;537.6182910133849;546.9671819690832;554.8751530905514;555.3557872867455;8642.22220000203;8644.78388040304;8692.478451292816;8964.238039766562;9150.40522366044;598.5869749872573;601.4586871815313;627.6858188763337;628.3088602537264;632.7627139822566;3619.475176099451;3635.9818200909353;3726.4969229153717;3746.606606306353;3819.1406933440308;53331.73334133304;54244.72877635586;54929.45070549243;55268.44731552713;55371.723141384035;47877;48085;48529.27206091912;48790.75604621954;49497.50502495001;43777;45794.771026145325;45824.08351833012;46183.76908115505;46189.30716039262;7251.166115896739;7433.331000210007;7527.322540971339;7628.084629844454;7647.426443016907;30528.542071868495;30752;30829.537556936215;31129.533057004617;31487.370252594363;37830.62169378271;40083.799581001906;40100.598994010856;40403;40646.79676601598;9220.44677319363;9245.676401325922;9366.765830854283;9432.19826314771;9474.24206063527;9980.950095249478;10221.897781022095;10332.586696532355;10438;10832.404217767784;10926.399048052433;11007.780603958738;11465.828012579985;11966;11971.401429928295;11011.559537618727;11315;11363.090952723927;11495.655130346104;11848.644540663794;10874.293170943864;11184.105271578417;11586.594469193538;11587.073034157078;11593.88406115928;8841.115888411108;8851.557422128997;8866.64533418656;9130.269578433843;9336.813263734552;9801.411915285105;9824.705258842247;9851.211903047595;10177.236707246839;10242.129418999468 16:08:08 INFO - PROCESS | 23418 | __end_tp_report 16:08:08 INFO - PROCESS | 23418 | __start_cc_report 16:08:08 INFO - PROCESS | 23418 | _x_x_mozilla_cycle_collect,939 16:08:08 INFO - PROCESS | 23418 | __end_cc_report 16:08:08 INFO - PROCESS | 23418 | __startTimestamp1464390488479__endTimestamp 16:08:08 INFO - PROCESS | 23418 | 16:08:08 INFO - PROCESS | 23418 | ------- Summary: start ------- 16:08:08 INFO - PROCESS | 23418 | Number of tests: 6 16:08:08 INFO - PROCESS | 23418 | 16:08:08 INFO - PROCESS | 23418 | [#0] dojo.html Cycles:165 Average:4692.23 Median:4823.54 stddev:488.14 (10.1%) stddev-sans-first:462.82 16:08:08 INFO - PROCESS | 23418 | Values: 2658.2 2662.9 2684.1 2699.5 2713.7 4674.5 4678.2 4699.4 4738.4 4789.5 4712.0 4738.1 4757.5 4764.9 4790.2 4374.0 4398.6 4447.8 4451.9 4455.7 4378.4 4413.4 4435.5 4437.7 4496.3 4358.0 4390.7 4418.2 4435.5 4453.3 4308.9 4313.0 4345.3 4349.8 4370.9 4250.6 4281.7 4282.5 4336.1 4382.6 4626.3 4647.7 4650.1 4651.7 4654.4 4956.5 5053.1 5062.4 5111.6 5119.0 5147.7 5158.8 5186.7 5204.7 5210.9 4792.2 4796.9 4798.2 4807.7 4841.9 5083.1 5102.9 5156.0 5161.7 5171.7 5028.7 5059.2 5078.7 5090.7 5091.5 5111.6 5112.3 5117.3 5118.5 5120.6 4927.8 4947.7 4951.1 5001.9 5069.9 4986.1 4992.1 5003.6 5010.4 5042.5 4260.0 4262.9 4274.1 4296.1 4315.2 3692.6 3695.8 3734.2 3739.6 3759.9 4366.9 4377.3 4400.7 4429.0 4440.2 5061.4 5087.5 5094.2 5095.5 5125.7 4284.7 4334.7 4341.2 4380.6 4385.0 4560.5 4560.6 4575.2 4591.2 4601.8 4790.4 4794.3 4816.1 4823.5 4863.0 4842.2 5063.9 5076.7 5109.9 5118.2 4839.6 4849.1 4855.0 4861.1 4899.7 5013.2 5026.7 5028.7 5044.0 5087.1 4948.0 5009.5 5051.6 5072.5 5095.5 4914.5 4937.3 4941.7 4947.7 4999.4 4882.7 4922.0 4945.2 4947.3 4981.4 4763.9 4806.0 4820.8 4868.2 4898.0 4963.4 4976.9 4986.5 5023.2 5032.9 4743.8 4796.9 4861.8 4876.9 4932.2 16:08:08 INFO - PROCESS | 23418 | 16:08:08 INFO - PROCESS | 23418 | [#1] ext.html Cycles:180 Average:45789.49 Median:19425.50 stddev:80114.75 (412.4%) stddev-sans-first:80267.52 16:08:08 INFO - PROCESS | 23418 | Values: 560.4 573.9 590.9 599.8 601.9 39792.2 40850.4 40869.2 40908.8 44321.3 41855.0 42371.0 42497.8 42516.0 42525.6 7412.4 7586.6 7594.4 7748.1 7913.0 7602.2 10308.9 10639.4 10640.9 10641.1 10296.9 10333.7 10357.5 10365.3 10370.8 7492.6 7711.3 10228.4 10293.9 10299.3 7625.0 9772.4 10198.1 10209.2 10516.7 38895.4 39301.0 39301.8 39303.0 39348.6 3023.3 3051.1 3059.7 3092.4 3190.7 30163.0 30555.0 30700.5 30843.1 31755.8 3443.2 3508.2 3525.2 3562.4 3564.7 251473.0 251541.0 252021.0 252782.0 252935.0 55212.7 55350.2 55393.2 55557.2 55568.4 51976.0 52129.5 52188.2 52411.2 52459.0 14329.7 14518.9 14519.5 14519.9 14537.1 10155.4 10712.1 10814.6 11524.7 11575.1 18338.5 18345.6 18373.8 18388.4 18416.7 6825.7 7006.2 7092.4 7092.5 7104.3 879.2 906.5 917.7 924.5 953.2 27120.6 28000.5 28227.7 28310.7 28592.4 967.2 986.6 994.5 1003.1 1028.7 14581.3 15096.1 15114.9 15272.0 15448.5 397619.0 400967.0 403510.0 404190.0 407136.0 49325.0 49720.5 49821.3 49842.5 49900.5 1081.3 1133.6 1200.6 1204.8 1211.7 27530.6 29261.4 29290.6 29741.3 29967.0 42376.4 42714.6 43024.8 43170.0 43204.6 227682.0 229032.0 229101.0 229706.0 230717.0 21939.0 23176.5 24459.6 25240.1 25286.0 40193.7 40681.0 40803.4 40814.6 41141.2 35736.8 35788.5 35797.0 36704.0 36746.6 35325.8 35890.6 36021.3 36242.5 36403.8 35070.0 35564.5 36168.1 36214.5 36301.1 31186.7 32361.4 32393.8 32472.7 32722.8 24565.5 24891.1 25001.4 25464.9 25659.9 16:08:08 INFO - PROCESS | 23418 | 16:08:08 INFO - PROCESS | 23418 | [#2] jquery.html Cycles:180 Average:23307.49 Median:5214.25 stddev:63844.67 (1224.4%) stddev-sans-first:64000.52 16:08:08 INFO - PROCESS | 23418 | Values: 356.1 359.2 359.3 370.5 371.3 13978.2 15572.0 17028.7 17456.0 17563.2 14371.6 14733.6 14740.6 14743.0 14804.6 4898.0 5073.8 5308.0 5330.2 5395.9 3478.9 3643.7 5085.5 5348.4 5361.6 5382.4 5422.0 5466.1 5473.4 5476.3 5202.2 5294.9 5341.5 5349.7 5361.7 5204.5 5212.4 5213.6 5214.9 5216.2 11522.0 12087.3 12102.9 12107.8 12114.4 1758.3 1934.7 1989.8 2136.2 2152.8 9109.9 9163.5 9233.4 9367.8 9571.3 1670.8 1729.5 1740.4 1754.4 1801.5 64691.0 65533.7 66821.3 66898.0 66989.7 9613.4 10282.7 10288.3 10619.0 10687.1 14118.6 14222.3 14231.7 14236.9 14243.8 7200.2 7283.6 7332.5 7427.1 7478.7 6467.2 6557.0 6663.5 6957.7 7006.3 4320.9 4364.9 4407.6 4428.8 4435.1 1615.9 1692.6 1739.1 1789.4 1842.9 761.4 852.5 857.9 878.3 894.3 15965.4 16865.2 16913.3 17060.5 17374.3 867.2 881.8 897.8 912.7 912.9 9432.1 9453.9 9650.0 9701.0 9873.2 382361.0 386279.0 386901.0 389690.0 392007.0 48428.8 75061.6 75815.6 76426.6 77571.2 910.5 911.0 923.3 924.2 928.6 14833.4 14918.3 15104.8 15210.9 15315.7 33015.2 33591.1 33890.3 34362.5 34790.5 42345.0 48357.8 48471.3 48705.3 48857.3 7182.5 7297.6 7425.0 7745.5 7790.5 8579.1 10057.4 10264.0 10895.4 11127.3 8893.5 9053.7 9074.2 9288.6 9532.8 9432.2 9443.5 9858.2 9935.4 10073.0 8729.5 9180.5 9258.5 9519.3 9609.0 6770.5 7007.6 7220.4 7227.2 7776.1 7503.6 9368.2 9759.7 10078.1 10376.1 16:08:08 INFO - PROCESS | 23418 | 16:08:08 INFO - PROCESS | 23418 | [#3] mootools.html Cycles:180 Average:5131.47 Median:5210.02 stddev:2905.54 (55.8%) stddev-sans-first:2899.03 16:08:08 INFO - PROCESS | 23418 | Values: 1248.7 1409.4 1441.2 1533.4 1741.3 6595.4 7520.5 7782.3 7791.3 7968.6 7707.8 7886.8 7896.8 7898.1 7903.7 1891.2 2117.1 2208.1 2241.2 2270.4 2273.3 2294.1 2294.4 2295.5 2298.8 2209.4 2214.1 2242.2 2256.7 2257.2 1820.7 2032.7 2103.7 2104.4 2134.6 2149.7 2153.1 2160.9 2175.4 2177.5 7717.5 7771.3 7782.6 7866.6 7869.3 4624.7 4716.7 4753.5 4754.3 4756.1 2743.3 2868.8 2974.4 2990.2 2999.4 902.1 906.1 912.8 914.7 921.1 10927.8 10991.7 11021.2 11028.8 11037.0 4183.9 4210.6 4286.8 4321.6 4324.7 9340.9 9355.3 9370.9 9371.0 9378.2 4512.1 4512.8 4517.5 4522.4 4525.3 3808.2 4202.2 4308.2 4316.9 4442.0 3691.5 3693.3 3766.8 3824.4 3906.7 1181.3 1192.5 1202.1 1205.3 1210.1 754.4 812.7 845.7 868.2 882.7 5239.1 5280.1 5310.9 5473.6 5504.9 391.6 393.0 394.2 396.0 907.8 3539.7 3654.6 3764.5 3778.3 3781.8 9371.9 9583.7 9585.3 9652.2 9715.1 9516.0 9570.3 9589.0 9599.6 9614.3 7248.1 7272.8 7334.8 7360.9 7507.2 5129.7 5208.7 5211.3 5218.8 5222.1 8796.6 8924.8 8932.8 9057.5 9089.5 8968.6 9116.4 9141.2 9298.4 9314.4 5804.8 5994.0 6106.3 6395.7 6501.8 7083.0 7256.5 7323.1 7325.8 7330.8 6388.8 6596.2 6649.6 6708.9 6785.5 6503.5 6532.2 6641.9 6644.6 6715.2 6257.3 6348.1 6349.9 6354.6 6367.5 4308.0 4422.9 4432.9 4465.8 4513.7 4623.9 4633.1 4646.7 4691.7 4694.6 16:08:08 INFO - PROCESS | 23418 | 16:08:08 INFO - PROCESS | 23418 | [#4] prototype.html Cycles:180 Average:4033.65 Median:4425.04 stddev:1156.23 (26.1%) stddev-sans-first:1124.86 16:08:08 INFO - PROCESS | 23418 | Values: 292.4 298.2 300.5 310.0 310.3 4828.5 4845.9 4877.4 4890.6 4914.4 4734.0 4734.5 4763.3 4801.6 4803.6 3787.3 3829.5 3834.9 3843.1 3852.8 3224.5 3564.7 3821.6 3835.8 3874.9 3633.2 3704.2 3707.8 3752.4 3755.1 3546.4 3731.2 3740.1 3754.9 3792.7 3326.7 3329.7 3330.9 3348.6 3358.7 4434.8 4489.2 4545.3 4569.1 4575.8 4226.6 4229.6 4242.8 4256.2 4261.6 5005.9 5015.7 5022.8 5034.5 5035.8 3605.8 3612.0 3616.7 3624.2 3647.5 5042.9 5229.3 5232.3 5256.3 5263.9 3604.7 3612.2 3617.8 3621.7 3629.1 3561.4 3587.3 3671.8 3719.7 3747.3 4220.8 4224.7 4230.9 4231.5 4241.1 4737.7 4944.7 4987.8 5002.7 5040.7 1297.8 1319.2 1321.6 1323.5 1329.8 819.1 842.0 845.4 850.3 861.4 3994.3 4015.9 4027.7 4041.8 4046.0 4556.5 4564.4 4589.6 4611.5 4611.7 3986.8 3998.5 4010.0 4023.6 4024.2 2294.0 2428.8 2448.9 2456.3 2470.6 4380.7 4382.5 4390.3 4412.8 4433.5 4692.6 4693.9 4699.8 4711.8 4717.5 4097.9 4369.6 4399.2 4438.9 4445.0 4573.9 4580.4 4590.4 4623.8 4632.3 4453.7 4502.7 4531.9 4543.4 4601.3 4340.9 4396.2 4400.8 4416.6 4639.3 3617.1 3706.1 3718.5 3733.6 3735.5 5162.7 5172.7 5208.3 5211.3 5219.0 4910.4 4996.3 5023.3 5065.2 5067.1 4945.4 4984.3 4989.2 4991.0 5013.5 4822.9 4895.8 4942.9 4947.4 4949.8 4806.5 4990.1 5062.4 5065.7 5071.0 4909.6 4912.6 4921.8 4927.9 4955.5 16:08:08 INFO - PROCESS | 23418 | 16:08:08 INFO - PROCESS | 23418 | [#5] yui.html Cycles:180 Average:12993.71 Median:24174.52 stddev:15241.20 (63.0%) stddev-sans-first:15255.27 16:08:08 INFO - PROCESS | 23418 | Values: 540.1 591.9 593.8 647.1 732.7 11862.7 12539.5 12561.1 12564.2 12614.9 10632.0 12023.1 12108.2 12125.2 12143.0 783.2 807.6 809.0 811.1 813.2 800.3 803.6 806.8 807.0 807.0 662.4 809.7 841.0 843.4 843.5 827.8 828.1 829.6 829.8 831.8 611.7 611.8 611.9 612.6 613.7 12445.1 12454.2 12498.9 12503.6 12521.0 11641.7 11858.4 12112.3 12149.5 12513.9 10129.8 10279.6 10406.3 10525.4 10720.2 3867.8 3927.2 3944.2 3946.8 4062.6 48360.0 49158.3 49185.3 49186.0 49274.8 8717.4 8958.7 9205.1 9319.9 9777.6 15724.6 15753.7 15764.1 15764.9 15771.4 6056.2 6057.4 6094.0 6209.5 6272.5 3351.1 3351.3 3397.2 3430.2 3490.4 2483.3 2569.3 2571.4 2581.9 2582.0 454.8 455.7 463.5 471.5 472.0 520.7 537.6 547.0 554.9 555.4 8642.2 8644.8 8692.5 8964.2 9150.4 598.6 601.5 627.7 628.3 632.8 3619.5 3636.0 3726.5 3746.6 3819.1 53331.7 54244.7 54929.5 55268.4 55371.7 47877.0 48085.0 48529.3 48790.8 49497.5 43777.0 45794.8 45824.1 46183.8 46189.3 7251.2 7433.3 7527.3 7628.1 7647.4 30528.5 30752.0 30829.5 31129.5 31487.4 37830.6 40083.8 40100.6 40403.0 40646.8 9220.4 9245.7 9366.8 9432.2 9474.2 9981.0 10221.9 10332.6 10438.0 10832.4 10926.4 11007.8 11465.8 11966.0 11971.4 11011.6 11315.0 11363.1 11495.7 11848.6 10874.3 11184.1 11586.6 11587.1 11593.9 8841.1 8851.6 8866.6 9130.3 9336.8 9801.4 9824.7 9851.2 10177.2 10242.1 16:08:08 INFO - PROCESS | 23418 | -------- Summary: end -------- 16:08:08 INFO - PROCESS | 23418 | 16:08:09 INFO - TEST-INFO | 23418: exit 0 16:08:09 INFO - TEST-OK | dromaeo_css | took 1118946ms 16:08:09 INFO - TEST-START | kraken 16:08:09 INFO - operating with platform_type : linux_ 16:08:09 INFO - Initialising browser for kraken test... 16:08:09 INFO - TEST-INFO | started process 23595 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNqdePe/profile http://localhost:55488/getInfo.html) 16:08:13 INFO - PROCESS | 23595 | __metrics Screen width/height:1600/1200 16:08:13 INFO - PROCESS | 23595 | colorDepth:24 16:08:13 INFO - PROCESS | 23595 | Browser inner width/height: 1024/697 16:08:13 INFO - PROCESS | 23595 | __metrics 16:08:14 ERROR - PROCESS | 23595 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:08:15 INFO - TEST-INFO | 23595: exit 0 16:08:15 INFO - Browser initialized. 16:08:15 INFO - Running cycle 1/1 for kraken test... 16:08:15 INFO - TEST-INFO | started process 23676 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNqdePe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:08:27 INFO - PROCESS | 23676 | RSS: Main: 157519872 16:08:27 INFO - PROCESS | 23676 | 16:08:29 INFO - PROCESS | 23676 | Cycle 1(1): loaded http://localhost:55488/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55488/tests/kraken/driver.html?testName=audio-beat-detection) 16:08:30 INFO - PROCESS | 23676 | RSS: Main: 212590592 16:08:30 INFO - PROCESS | 23676 | 16:08:33 INFO - PROCESS | 23676 | Cycle 1(1): loaded http://localhost:55488/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55488/tests/kraken/driver.html?testName=audio-dft) 16:08:34 INFO - PROCESS | 23676 | RSS: Main: 199503872 16:08:34 INFO - PROCESS | 23676 | 16:08:37 INFO - PROCESS | 23676 | Cycle 1(1): loaded http://localhost:55488/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55488/tests/kraken/driver.html?testName=audio-fft) 16:08:37 INFO - PROCESS | 23676 | RSS: Main: 270147584 16:08:37 INFO - PROCESS | 23676 | 16:08:40 INFO - PROCESS | 23676 | Cycle 1(1): loaded http://localhost:55488/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55488/tests/kraken/driver.html?testName=audio-oscillator) 16:08:40 INFO - PROCESS | 23676 | RSS: Main: 178716672 16:08:40 INFO - PROCESS | 23676 | 16:08:42 INFO - PROCESS | 23676 | Cycle 1(1): loaded http://localhost:55488/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55488/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:08:43 INFO - PROCESS | 23676 | RSS: Main: 182693888 16:08:43 INFO - PROCESS | 23676 | 16:08:46 INFO - PROCESS | 23676 | Cycle 1(1): loaded http://localhost:55488/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55488/tests/kraken/driver.html?testName=imaging-darkroom) 16:08:47 INFO - PROCESS | 23676 | RSS: Main: 218460160 16:08:47 INFO - PROCESS | 23676 | 16:08:50 INFO - PROCESS | 23676 | Cycle 1(1): loaded http://localhost:55488/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55488/tests/kraken/driver.html?testName=imaging-desaturate) 16:08:51 INFO - PROCESS | 23676 | RSS: Main: 219820032 16:08:51 INFO - PROCESS | 23676 | 16:08:54 INFO - PROCESS | 23676 | Cycle 1(1): loaded http://localhost:55488/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55488/tests/kraken/driver.html?testName=json-parse-financial) 16:08:54 INFO - PROCESS | 23676 | RSS: Main: 219463680 16:08:54 INFO - PROCESS | 23676 | 16:08:56 INFO - PROCESS | 23676 | Cycle 1(1): loaded http://localhost:55488/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55488/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:08:57 INFO - PROCESS | 23676 | RSS: Main: 205398016 16:08:57 INFO - PROCESS | 23676 | 16:08:59 INFO - PROCESS | 23676 | Cycle 1(1): loaded http://localhost:55488/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55488/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:08:59 INFO - PROCESS | 23676 | RSS: Main: 209973248 16:08:59 INFO - PROCESS | 23676 | 16:09:01 INFO - PROCESS | 23676 | Cycle 1(1): loaded http://localhost:55488/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55488/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:09:02 INFO - PROCESS | 23676 | RSS: Main: 220631040 16:09:02 INFO - PROCESS | 23676 | 16:09:05 INFO - PROCESS | 23676 | Cycle 1(1): loaded http://localhost:55488/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55488/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:09:05 INFO - PROCESS | 23676 | RSS: Main: 227713024 16:09:05 INFO - PROCESS | 23676 | 16:09:08 INFO - PROCESS | 23676 | Cycle 1(1): loaded http://localhost:55488/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55488/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:09:09 INFO - PROCESS | 23676 | RSS: Main: 224563200 16:09:09 INFO - PROCESS | 23676 | 16:09:11 INFO - PROCESS | 23676 | Cycle 1(1): loaded http://localhost:55488/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55488/tests/kraken/driver.html?testName=ai-astar) 16:09:11 INFO - PROCESS | 23676 | RSS: Main: 268832768 16:09:11 INFO - PROCESS | 23676 | 16:09:11 INFO - PROCESS | 23676 | __start_tp_report 16:09:11 INFO - PROCESS | 23676 | _x_x_mozilla_page_load 16:09:11 INFO - PROCESS | 23676 | _x_x_mozilla_page_load_details 16:09:11 INFO - PROCESS | 23676 | |i|pagename|runs| 16:09:11 INFO - PROCESS | 23676 | |0;ai-astar;114;109;113;112;111;112;113;111;112;113 16:09:11 INFO - PROCESS | 23676 | |1;audio-beat-detection;155;130;132;177;131;132;150;129;164;132 16:09:11 INFO - PROCESS | 23676 | |2;audio-dft;161;160;161;161;161;164;161;163;162;160 16:09:11 INFO - PROCESS | 23676 | |3;audio-fft;75;76;75;104;76;76;74;76;98;75 16:09:11 INFO - PROCESS | 23676 | |4;audio-oscillator;84;84;125;83;84;84;118;83;84;125 16:09:11 INFO - PROCESS | 23676 | |5;imaging-gaussian-blur;117;117;117;117;117;117;118;117;117;116 16:09:11 INFO - PROCESS | 23676 | |6;imaging-darkroom;145;146;145;146;146;146;145;145;145;146 16:09:11 INFO - PROCESS | 23676 | |7;imaging-desaturate;94;95;95;95;96;95;94;94;94;95 16:09:11 INFO - PROCESS | 23676 | |8;json-parse-financial;64;64;65;64;65;64;64;84;64;63 16:09:11 INFO - PROCESS | 23676 | |9;json-stringify-tinderbox;65;66;66;65;66;66;65;66;65;67 16:09:11 INFO - PROCESS | 23676 | |10;stanford-crypto-aes;83;82;82;83;84;85;83;83;84;83 16:09:11 INFO - PROCESS | 23676 | |11;stanford-crypto-ccm;131;131;130;129;138;132;129;129;163;133 16:09:11 INFO - PROCESS | 23676 | |12;stanford-crypto-pbkdf2;174;169;166;172;173;171;170;168;167;167 16:09:11 INFO - PROCESS | 23676 | |13;stanford-crypto-sha256-iterative;66;66;68;67;66;65;66;66;65;67 16:09:11 INFO - PROCESS | 23676 | __end_tp_report 16:09:11 INFO - PROCESS | 23676 | __start_cc_report 16:09:11 INFO - PROCESS | 23676 | _x_x_mozilla_cycle_collect,1923 16:09:11 INFO - PROCESS | 23676 | __end_cc_report 16:09:11 INFO - PROCESS | 23676 | __startTimestamp1464390551378__endTimestamp 16:09:11 INFO - PROCESS | 23676 | 16:09:11 INFO - PROCESS | 23676 | ------- Summary: start ------- 16:09:11 INFO - PROCESS | 23676 | Number of tests: 14 16:09:11 INFO - PROCESS | 23676 | 16:09:11 INFO - PROCESS | 23676 | [#0] ai-astar Cycles:10 Average:112.00 Median:112.50 stddev:1.41 (1.3%) stddev-sans-first:1.30 16:09:11 INFO - PROCESS | 23676 | Values: 114.0 109.0 113.0 112.0 111.0 112.0 113.0 111.0 112.0 113.0 16:09:11 INFO - PROCESS | 23676 | 16:09:11 INFO - PROCESS | 23676 | [#1] audio-beat-detection Cycles:10 Average:143.20 Median:141.00 stddev:17.20 (12.2%) stddev-sans-first:17.70 16:09:11 INFO - PROCESS | 23676 | Values: 155.0 130.0 132.0 177.0 131.0 132.0 150.0 129.0 164.0 132.0 16:09:11 INFO - PROCESS | 23676 | 16:09:11 INFO - PROCESS | 23676 | [#2] audio-dft Cycles:10 Average:161.40 Median:161.00 stddev:1.26 (0.8%) stddev-sans-first:1.33 16:09:11 INFO - PROCESS | 23676 | Values: 161.0 160.0 161.0 161.0 161.0 164.0 161.0 163.0 162.0 160.0 16:09:11 INFO - PROCESS | 23676 | 16:09:11 INFO - PROCESS | 23676 | [#3] audio-fft Cycles:10 Average:80.50 Median:76.00 stddev:10.92 (14.4%) stddev-sans-first:11.40 16:09:11 INFO - PROCESS | 23676 | Values: 75.0 76.0 75.0 104.0 76.0 76.0 74.0 76.0 98.0 75.0 16:09:11 INFO - PROCESS | 23676 | 16:09:11 INFO - PROCESS | 23676 | [#4] audio-oscillator Cycles:10 Average:95.40 Median:84.00 stddev:18.92 (22.5%) stddev-sans-first:19.61 16:09:11 INFO - PROCESS | 23676 | Values: 84.0 84.0 125.0 83.0 84.0 84.0 118.0 83.0 84.0 125.0 16:09:11 INFO - PROCESS | 23676 | 16:09:11 INFO - PROCESS | 23676 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 16:09:11 INFO - PROCESS | 23676 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 118.0 117.0 117.0 116.0 16:09:11 INFO - PROCESS | 23676 | 16:09:11 INFO - PROCESS | 23676 | [#6] imaging-darkroom Cycles:10 Average:145.50 Median:146.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 16:09:11 INFO - PROCESS | 23676 | Values: 145.0 146.0 145.0 146.0 146.0 146.0 145.0 145.0 145.0 146.0 16:09:11 INFO - PROCESS | 23676 | 16:09:11 INFO - PROCESS | 23676 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.67 (0.7%) stddev-sans-first:0.67 16:09:11 INFO - PROCESS | 23676 | Values: 94.0 95.0 95.0 95.0 96.0 95.0 94.0 94.0 94.0 95.0 16:09:11 INFO - PROCESS | 23676 | 16:09:11 INFO - PROCESS | 23676 | [#8] json-parse-financial Cycles:10 Average:66.10 Median:64.00 stddev:6.31 (9.9%) stddev-sans-first:6.65 16:09:11 INFO - PROCESS | 23676 | Values: 64.0 64.0 65.0 64.0 65.0 64.0 64.0 84.0 64.0 63.0 16:09:11 INFO - PROCESS | 23676 | 16:09:11 INFO - PROCESS | 23676 | [#9] json-stringify-tinderbox Cycles:10 Average:65.70 Median:66.00 stddev:0.67 (1.0%) stddev-sans-first:0.67 16:09:11 INFO - PROCESS | 23676 | Values: 65.0 66.0 66.0 65.0 66.0 66.0 65.0 66.0 65.0 67.0 16:09:11 INFO - PROCESS | 23676 | 16:09:11 INFO - PROCESS | 23676 | [#10] stanford-crypto-aes Cycles:10 Average:83.20 Median:83.00 stddev:0.92 (1.1%) stddev-sans-first:0.97 16:09:11 INFO - PROCESS | 23676 | Values: 83.0 82.0 82.0 83.0 84.0 85.0 83.0 83.0 84.0 83.0 16:09:11 INFO - PROCESS | 23676 | 16:09:11 INFO - PROCESS | 23676 | [#11] stanford-crypto-ccm Cycles:10 Average:134.50 Median:131.50 stddev:10.37 (7.9%) stddev-sans-first:10.93 16:09:11 INFO - PROCESS | 23676 | Values: 131.0 131.0 130.0 129.0 138.0 132.0 129.0 129.0 163.0 133.0 16:09:11 INFO - PROCESS | 23676 | 16:09:11 INFO - PROCESS | 23676 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.70 Median:170.50 stddev:2.75 (1.6%) stddev-sans-first:2.44 16:09:11 INFO - PROCESS | 23676 | Values: 174.0 169.0 166.0 172.0 173.0 171.0 170.0 168.0 167.0 167.0 16:09:11 INFO - PROCESS | 23676 | 16:09:11 INFO - PROCESS | 23676 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.20 Median:66.00 stddev:0.92 (1.4%) stddev-sans-first:0.97 16:09:11 INFO - PROCESS | 23676 | Values: 66.0 66.0 68.0 67.0 66.0 65.0 66.0 66.0 65.0 67.0 16:09:11 INFO - PROCESS | 23676 | -------- Summary: end -------- 16:09:11 INFO - PROCESS | 23676 | 16:09:12 INFO - TEST-INFO | 23676: exit 0 16:09:12 INFO - TEST-OK | kraken | took 63228ms 16:09:12 INFO - SUITE-END | took 1182s 16:09:12 INFO - Completed test suite (00:19:42) 16:09:12 INFO - javascript benchmark 16:09:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2658.1626787561927, 2662.853543055132, 2684.0739944719066, 2699.4601079784047, 2713.7421944915227, 4674.4624368197665, 4678.181318269302, 4699.412573428322, 4738.407699037624, 4789.544993225642, 4711.976440117804, 4738.123447162277, 4757.548032936874, 4764.857054288377, 4790.185668436366, 4373.978130109345, 4398.626116780078, 4447.777611119447, 4451.88870278244, 4455.710378825383, 4378.40891479651, 4413.4041904342985, 4435.512093669694, 4437.733735975836, 4496.348029535716, 4358.019445624741, 4390.736555806662, 4418.204723149832, 4435.512093669694, 4453.33200019998, 4308.870733877989, 4313.029568347129, 4345.2613055780275, 4349.760763158025, 4370.934435983463, 4250.574942505756, 4281.743095414286, 4282.486101667789, 4336.067745434747, 4382.627476664487, 4626.30605409188, 4647.674662773574, 4650.069986002779, 4651.651126165551, 4654.394928659286, 4956.504349565051, 5053.090443720129, 5062.392512898476, 5111.616628752821, 5119.0273847968765, 5147.6654017488745, 5158.819441319536, 5186.740662966837, 5204.739763011834, 5210.947890521084, 4792.185328494164, 4796.880077998008, 4798.23228283473, 4807.687500312469, 4841.927371089437, 5083.085044691917, 5102.9489705103215, 5156.04613146569, 5161.690298582097, 5171.7155556444, 5028.748562571856, 5059.241113832881, 5078.746062696924, 5090.720010399464, 5091.465396133427, 5111.642185047085, 5112.284280200774, 5117.334746483008, 5118.539331460186, 5120.641555091182, 4927.827526036644, 4947.678400903929, 4951.059298733229, 5001.949980500149, 5069.923951140736, 4986.102501549756, 4992.126377883856, 5003.624728145403, 5010.3737032870895, 5042.495750424928, 4260.0, 4262.936055959163, 4274.1451709658195, 4296.076343586144, 4315.180115777992, 3692.649198326155, 3695.75977561463, 3734.2344819311975, 3739.5886452490204, 3759.9436008459893, 4366.868993930188, 4377.299632058919, 4400.735955842596, 4429.0, 4440.2007638625355, 5061.392632884003, 5087.542121209109, 5094.2103973884105, 5095.490450954875, 5125.666831655931, 4284.67864910133, 4334.696571239983, 4341.196878577473, 4380.627646650007, 4384.956150438518, 4560.543945605413, 4560.566746159109, 4575.199340115533, 4591.150637132075, 4601.838935637304, 4790.4251489821445, 4794.304825800239, 4816.060868130751, 4823.541763532458, 4862.95137048625, 4842.249451335048, 5063.949360506348, 5076.720780356968, 5109.8722531937, 5118.232265160255, 4839.588634966068, 4849.127157111754, 4854.975725121351, 4861.124997500484, 4899.6570240084275, 5013.248012797963, 5026.673266237829, 5028.673136246132, 5044.0, 5087.058894104454, 4948.035133149001, 5009.5491405773655, 5051.595872330131, 5072.492750725046, 5095.515925987025, 4914.533119353656, 4937.333459982857, 4941.703497790144, 4947.678400903929, 4999.375078115236, 4882.658213924991, 4922.0, 4945.208766597327, 4947.3321101652255, 4981.402231732215, 4763.904721905612, 4805.975970120127, 4820.831270905502, 4868.196747536616, 4898.0, 4963.379577552806, 4976.900461990812, 4986.47641997585, 5023.246513023076, 5032.899342013066, 4743.786529606107, 4796.928046079382, 4861.756912154449, 4876.902461950812, 4932.235503496964], "value": 4661.3734154136955, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [560.4290845362015, 573.9225204597378, 590.9143174239734, 599.8380437281937, 601.9075378188585, 39792.204155916865, 40850.387244191326, 40869.18261634766, 40908.79545602268, 44321.335179972244, 41855.0, 42371.0, 42497.78751106248, 42516.0, 42525.574744252546, 7412.369948554379, 7586.620668966557, 7594.354479869217, 7748.070231572206, 7912.960435197816, 7602.201768814278, 10308.948455257712, 10639.361638301689, 10640.935906409335, 10641.095506881924, 10296.948515257413, 10333.689989300296, 10357.533910974023, 10365.326253793517, 10370.844437333446, 7492.625368731542, 7711.305982461549, 10228.386296822177, 10293.897061029369, 10299.330543514647, 7625.0, 9772.41365518064, 10198.082172604427, 10209.2343074269, 10516.68449946503, 38895.41656875149, 39301.0, 39301.803490982646, 39303.0, 39348.60651393478, 3023.289526961162, 3051.1456792098247, 3059.682198755058, 3092.412441636082, 3190.7287880530184, 30163.0, 30555.0, 30700.53949190764, 30843.074707758802, 31755.84122079398, 3443.2080621457203, 3508.2457271686453, 3525.224450620885, 3562.412201986669, 3564.679178873888, 251473.0, 251541.0, 252021.0, 252782.0, 252935.0, 55212.72393638086, 55350.16974745382, 55393.16910246349, 55557.16664250039, 55568.44431555633, 51976.0, 52129.47870521322, 52188.21717674238, 52411.21383179256, 52459.0, 14329.713405732036, 14518.927405363116, 14519.491817786538, 14519.927400363142, 14537.127772333693, 10155.39067655943, 10712.089472394933, 10814.56741730322, 11524.654260372203, 11575.073994080454, 18338.541536461427, 18345.633087338178, 18373.816261837477, 18388.356407525673, 18416.723749143774, 6825.726970921207, 7006.229314775371, 7092.361687448051, 7092.482168830094, 7104.25405332443, 879.2482427524502, 906.5467266366817, 917.7200953709049, 924.5007695844304, 953.1755031897497, 27120.59319110215, 28000.45894213816, 28227.6751232844, 28310.71689283122, 28592.42815143727, 967.2310513142069, 986.5807032011367, 994.4928086675703, 1003.0821798054844, 1028.7068185567077, 14581.270936453135, 15096.094234345974, 15114.924425378023, 15272.0, 15448.459303924534, 397619.0, 400967.0, 403510.0, 404190.0, 407136.0, 49325.0, 49720.50279497159, 49821.25268120981, 49842.50157498451, 49900.50099499031, 1081.324172392255, 1133.6372360844616, 1200.6278053803348, 1204.8373469581495, 1211.745533437988, 27530.587041193998, 29261.414771704873, 29290.56064159082, 29741.256468588457, 29967.0, 42376.36435453409, 42714.5728542723, 43024.78487607542, 43170.0, 43204.56795432131, 227682.0, 229032.0, 229101.0, 229706.0, 230717.0, 21939.0, 23176.536469270857, 24459.633105503075, 25240.116595919055, 25286.0, 40193.65862920026, 40681.0, 40803.38794918019, 40814.59185408108, 41141.1771764569, 35736.821315894296, 35788.4631730519, 35797.0, 36704.0, 36746.63253367432, 35325.78299776291, 35890.64109358873, 36021.27957440889, 36242.4563631551, 36403.817980910986, 35070.0, 35564.466533001505, 36168.09579760527, 36214.4567831488, 36301.0924726884, 31186.68813311838, 32361.35277294488, 32393.83803081064, 32472.675273246965, 32722.83638581792, 24565.508689825747, 24891.128810491547, 25001.374965625277, 25464.872675636503, 25659.87170064138], "value": 17933.716781701572, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.0688798791161, 359.19361034477595, 359.31550396494686, 370.497975243545, 371.32418997424696, 13978.231197284158, 15572.0, 17028.659426811457, 17455.95264284144, 17563.209655565515, 14371.64070898228, 14733.631659208526, 14740.557783266493, 14743.0, 14804.6298842529, 4897.951020489803, 5073.771680274378, 5307.964946835368, 5330.200469929503, 5395.892082158354, 3478.9478157827652, 3643.653852883985, 5085.516875896784, 5348.438413966536, 5361.597880159004, 5382.381026182, 5422.0, 5466.098093814516, 5473.397926228112, 5476.342838859342, 5202.2456743771945, 5294.894102117955, 5341.545968592674, 5349.652272602308, 5361.651492652965, 5204.479552044803, 5212.4266330703595, 5213.556847667953, 5214.947850521484, 5216.191490319006, 11522.0, 12087.335196564185, 12102.878971210263, 12107.818382724266, 12114.394280285951, 1758.2791055667242, 1934.661434249015, 1989.8209161175419, 2136.166894910986, 2152.817010554105, 9109.908900910972, 9163.450192988443, 9233.399829011092, 9367.812643747155, 9571.28215383842, 1670.8078570964374, 1729.4984454508178, 1740.364766860087, 1754.4210410564483, 1801.5406071451828, 64691.0, 65533.67233163804, 66821.33178668248, 66898.0, 66989.66505167492, 9613.423194608346, 10282.742931426625, 10288.2798204125, 10619.0, 10687.14502839771, 14118.64703382403, 14222.288885555889, 14231.715365692628, 14236.85763142376, 14243.78634320486, 7200.207977122513, 7283.562986220843, 7332.450066245053, 7427.108746950401, 7478.663460144307, 6467.223933127961, 6557.049227861933, 6663.533552651268, 6957.65211739411, 7006.334398232262, 4320.935185972213, 4364.8908777280185, 4407.559244075567, 4428.756418396954, 4435.068635586498, 1615.9272832722556, 1692.636083242109, 1739.1304347826087, 1789.3647755047032, 1842.9078546072642, 761.3528500774297, 852.4714676900361, 857.8541647919868, 878.2798105553512, 894.338189739601, 15965.36138554468, 16865.241064151898, 16913.323467061426, 17060.488185354458, 17374.305027799, 867.1675191815928, 881.8280435315052, 897.8249241397865, 912.7261821453537, 912.885889263842, 9432.103950124727, 9453.858192127123, 9650.0, 9701.029897010243, 9873.160781333523, 382361.0, 386279.0, 386901.0, 389690.0, 392007.0, 48428.75785621049, 75061.62469187728, 75815.62092189504, 76426.61786691031, 77571.22428775753, 910.4719262827545, 910.9635614575343, 923.298293297099, 924.2328867040475, 928.6053427293373, 14833.368329483965, 14918.328675209425, 15104.791985293768, 15210.923945380202, 15315.693686125993, 33015.17462063468, 33591.10174779922, 33890.32219355649, 34362.484562732076, 34790.47814282737, 42345.0, 48357.75821120872, 48471.27293090677, 48705.26942095942, 48857.267140992204, 7182.461315401261, 7297.635118244173, 7425.034745483053, 7745.496542724705, 7790.493617914817, 8579.14208579137, 10057.396556206359, 10264.0, 10895.400752958663, 11127.276727012719, 8893.510856902933, 9053.68312109073, 9074.228690561376, 9288.62845486173, 9532.780145024823, 9432.198263147435, 9443.480608566595, 9858.162056225301, 9935.354201976848, 10073.0, 8729.519876406604, 9180.54097295146, 9258.53707314645, 9519.333646644669, 9608.951955240458, 6770.458363330823, 7007.649617519206, 7220.38626716735, 7227.241139680258, 7776.105747839071, 7503.624818758931, 9368.203702685076, 9759.658411955548, 10078.143357814668, 10376.118029967236], "value": 7818.943442271496, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1248.675344410454, 1409.429181181621, 1441.178528238904, 1533.3559904839974, 1741.294775615876, 6595.439387652049, 7520.548767073979, 7782.299593036626, 7791.259830316125, 7968.6015699215095, 7707.807304817382, 7886.80282992924, 7896.842063158733, 7898.091719452264, 7903.723369682062, 1891.2151457145337, 2117.1319758898853, 2208.072609504004, 2241.1707668162762, 2270.3642979965553, 2273.318004598622, 2294.139697613395, 2294.403455101677, 2295.494991101955, 2298.804601608857, 2209.436593668612, 2214.081156320125, 2242.237639202671, 2256.6953461282765, 2257.209976508217, 1820.726890966359, 2032.6849338352579, 2103.7265155529835, 2104.389726979174, 2134.5944270588616, 2149.6668016457475, 2153.095699806085, 2160.9243676471406, 2175.380016695234, 2177.51006023645, 7717.459777815555, 7771.261730135628, 7782.571958542278, 7866.567338796364, 7869.252421020051, 4624.745638989854, 4716.669833111683, 4753.548412900769, 4754.310624959396, 4756.096341695102, 2743.3278846682692, 2868.756155726766, 2974.449726800527, 2990.177701132184, 2999.35513864518, 902.0798785239019, 906.0531744327194, 912.8174365126935, 914.6752902719505, 921.0559176843722, 10927.781444371147, 10991.670249892515, 11021.173411994132, 11028.779424411547, 11037.0, 4183.853565125206, 4210.642095421862, 4286.7856607169515, 4321.58944900234, 4324.654027677778, 9340.906590934004, 9355.251579873593, 9370.906290937004, 9371.0, 9378.249740020781, 4512.075024619945, 4512.774361281989, 4517.480489743722, 4522.4346956630425, 4525.298578720303, 3808.1812410331913, 4202.159568086395, 4308.246056940023, 4316.913661726748, 4441.977790111029, 3691.5201023866725, 3693.3351996640326, 3766.7928263945746, 3824.4072168813877, 3906.7069969752383, 1181.3384504677235, 1192.5110704611068, 1202.1104382756696, 1205.3189947679532, 1210.0501106631427, 754.4266357568288, 812.6830536090929, 845.6532821543137, 868.2359523619175, 882.6557642519423, 5239.083160446907, 5280.128778751539, 5310.867228319323, 5473.644213126134, 5504.944950550524, 391.5555844116891, 393.02333700753604, 394.1506054452659, 395.9861404850874, 907.7503686486269, 3539.7168226541817, 3654.6477803212983, 3764.5294338207723, 3778.263238668488, 3781.7541859779026, 9371.859422108673, 9583.664571739955, 9585.329026968046, 9652.22782177424, 9715.125638692552, 9516.0, 9570.33007689469, 9589.0, 9599.568019439143, 9614.278929080347, 7248.130224373109, 7272.818179545554, 7334.816629584303, 7360.889586656204, 7507.1742108368035, 5129.717865517432, 5208.713520756243, 5211.322528071327, 5218.843434696965, 5222.138347172826, 8796.560171991247, 8924.776880578038, 8932.776680583038, 9057.547122643973, 9089.454632722058, 8968.596413161555, 9116.36185467011, 9141.222996045144, 9298.442093474414, 9314.44113353201, 5804.82585522435, 5993.9700301498215, 6106.3283038866575, 6395.744170233139, 6501.837454063497, 7083.0, 7256.45576581769, 7323.084614423197, 7325.816854578678, 7330.780076597711, 6388.8083357499345, 6596.175478065242, 6649.567778094409, 6708.932910670831, 6785.525013249223, 6503.512236582181, 6532.248791389051, 6641.867162656817, 6644.568103073478, 6715.1941766988275, 6257.2804127525915, 6348.079528468438, 6349.936500634934, 6354.586951848115, 6367.52243581724, 4308.0, 4422.867313980586, 4432.911341773211, 4465.821367145408, 4513.68404211702, 4623.907521849612, 4633.119707255611, 4646.697964632288, 4691.741954192552, 4694.6009589185305], "value": 4078.737768918412, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [292.43413993921763, 298.1993347860993, 300.52967106478354, 309.96471784240634, 310.2940809658028, 4828.468868424475, 4845.85462436127, 4877.365942427479, 4890.6087512998965, 4914.410270767504, 4733.952660473403, 4734.47281775078, 4763.285507173926, 4801.615870730342, 4803.615710743141, 3787.3182827091114, 3829.5404551453867, 3834.9041273968232, 3843.1160833008425, 3852.8073596320214, 3224.4679627861374, 3564.7326450516184, 3821.636944490269, 3835.8465661373652, 3874.903127421823, 3633.2370202257557, 3704.2035962268105, 3707.796071216082, 3752.3996160614306, 3755.136318646713, 3546.3616549021167, 3731.1791405890663, 3740.1023754299044, 3754.924901501982, 3792.7345085844004, 3326.7172290355347, 3329.7003269705842, 3330.900072997814, 3348.5814273215847, 3358.697717205463, 4434.756088415136, 4489.236829738952, 4545.340925565807, 4569.1318649456825, 4575.794089265958, 4226.640735537483, 4229.555896630871, 4242.809073591696, 4256.2338779019765, 4261.616454519108, 5005.9499405005845, 5015.749212539358, 5022.773975171126, 5034.489653104054, 5035.84892453227, 3605.837737301828, 3612.049493273629, 3616.7160657966306, 3624.2026754114054, 3647.4893514907935, 5042.949570504248, 5229.320188375525, 5232.2674825524455, 5256.342957130359, 5263.894722105575, 3604.747667663291, 3612.223371975038, 3617.8061239790804, 3621.6901886754677, 3629.074585980558, 3561.3973711829262, 3587.264610754841, 3671.761335513183, 3719.702423806089, 3747.2880152770886, 4220.810063547147, 4224.704270701083, 4230.894227644333, 4231.534531201565, 4241.066965267636, 4737.6683632146105, 4944.70071416793, 4987.750612469362, 5002.724850133205, 5040.697558146523, 1297.8183054372396, 1319.221659221064, 1321.6035189443128, 1323.450767931317, 1329.7739384304691, 819.1235378145327, 841.9789505262418, 845.3828705045352, 850.2560259772699, 861.3841103610948, 3994.3423479256367, 4015.9196816063513, 4027.657649099801, 4041.818118184689, 4045.9682780890685, 4556.498785133633, 4564.3838081859185, 4589.58693717567, 4611.492735799059, 4611.654125940568, 3986.7607943522903, 3998.520177578709, 4009.979950100231, 4023.5574086850424, 4024.2293390908494, 2293.988530057339, 2428.805695544352, 2448.9142879999113, 2456.299954512991, 2470.641756945233, 4380.7371557706, 4382.496012958487, 4390.275604525217, 4412.84555040572, 4433.46798384196, 4692.554207350297, 4693.929591056137, 4699.835505757282, 4711.751385882744, 4717.490403071053, 4097.91804163921, 4369.558045844864, 4399.1641588098155, 4438.9334159988275, 4444.955550444454, 4573.885652858572, 4580.3587497751005, 4590.449146102489, 4623.797812568823, 4632.305154226893, 4453.665975051949, 4502.707324023928, 4531.886702832456, 4543.432070991126, 4601.33280674307, 4340.88640495338, 4396.208682437192, 4400.757958312323, 4416.6025057744955, 4639.260112838768, 3617.1499697570525, 3706.129059671029, 3718.456840411199, 3733.6266373362446, 3735.4583585379423, 5162.741862906764, 5172.741362931763, 5208.296879921314, 5211.270422140828, 5218.9739051304505, 4910.361652985089, 4996.325496057985, 5023.317188741812, 5065.214891691794, 5067.062593420229, 4945.381827271591, 4984.327115839315, 4989.151844186569, 4991.0, 5013.548780609617, 4822.855314340575, 4895.8041678332465, 4942.901141977069, 4947.406311242674, 4949.802007919643, 4806.519348065305, 4990.076835785391, 5062.443131255633, 5065.721385323843, 5070.974645126751, 4909.582685471775, 4912.557869791736, 4921.778519966539, 4927.901441971212, 4955.45489996093], "value": 3673.419107014165, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [540.0980362794133, 591.8697886464978, 593.8348601951599, 647.1102234427661, 732.6886073418799, 11862.703432414182, 12539.498420063186, 12561.057920655941, 12564.246145231282, 12614.873851261485, 10632.0, 12023.098267629919, 12108.152429329928, 12125.211861229014, 12143.0, 783.1541934710499, 807.5760225881401, 808.9838203235931, 811.0632219786127, 813.2071230550196, 800.3437181511163, 803.5821372886096, 806.8144326804838, 806.9798255043642, 807.0114110214969, 662.443547420167, 809.6639894443799, 840.970566030189, 843.439112989861, 843.4559708987699, 827.7723626002837, 828.1097819843692, 829.5561874397168, 829.784256093414, 831.8003679116998, 611.7461253579759, 611.831746269775, 611.9418655227746, 612.5895649914568, 613.7115555688819, 12445.066620003445, 12454.190477618926, 12498.875011249862, 12503.624891253277, 12521.0, 11641.708957276136, 11858.407079645984, 12112.276103101836, 12149.514019439212, 12513.874861251363, 10129.797404051951, 10279.617586237879, 10406.323588966692, 10525.368477891274, 10720.195985301134, 3867.825947832326, 3927.155661532784, 3944.1717239379764, 3946.7631942083567, 4062.5531191568903, 48360.0, 49158.26262606064, 49185.26222106671, 49186.0, 49274.75362623164, 8717.389782715276, 8958.731238062868, 9205.079492050876, 9319.860202096974, 9777.560009799576, 15724.60688482797, 15753.68492630141, 15764.132972686382, 15764.921175394278, 15771.369145234292, 6056.242969628796, 6057.394260573996, 6094.0, 6209.534284928648, 6272.498200143978, 3351.1454579082665, 3351.3464874349756, 3397.2186397128303, 3430.245346023921, 3490.406630872758, 2483.3046746910895, 2569.306287302455, 2571.3828681116397, 2581.9354516136873, 2581.9612705809427, 454.8203459633426, 455.69696152059174, 463.53414818107586, 471.5096299848188, 472.0393998213619, 520.7344254430268, 537.6182910133849, 546.9671819690832, 554.8751530905514, 555.3557872867455, 8642.22220000203, 8644.78388040304, 8692.478451292816, 8964.238039766562, 9150.40522366044, 598.5869749872573, 601.4586871815313, 627.6858188763337, 628.3088602537264, 632.7627139822566, 3619.475176099451, 3635.9818200909353, 3726.4969229153717, 3746.606606306353, 3819.1406933440308, 53331.73334133304, 54244.72877635586, 54929.45070549243, 55268.44731552713, 55371.723141384035, 47877.0, 48085.0, 48529.27206091912, 48790.75604621954, 49497.50502495001, 43777.0, 45794.771026145325, 45824.08351833012, 46183.76908115505, 46189.30716039262, 7251.166115896739, 7433.331000210007, 7527.322540971339, 7628.084629844454, 7647.426443016907, 30528.542071868495, 30752.0, 30829.537556936215, 31129.533057004617, 31487.370252594363, 37830.62169378271, 40083.799581001906, 40100.598994010856, 40403.0, 40646.79676601598, 9220.44677319363, 9245.676401325922, 9366.765830854283, 9432.19826314771, 9474.24206063527, 9980.950095249478, 10221.897781022095, 10332.586696532355, 10438.0, 10832.404217767784, 10926.399048052433, 11007.780603958738, 11465.828012579985, 11966.0, 11971.401429928295, 11011.559537618727, 11315.0, 11363.090952723927, 11495.655130346104, 11848.644540663794, 10874.293170943864, 11184.105271578417, 11586.594469193538, 11587.073034157078, 11593.88406115928, 8841.115888411108, 8851.557422128997, 8866.64533418656, 9130.269578433843, 9336.813263734552, 9801.411915285105, 9824.705258842247, 9851.211903047595, 10177.236707246839, 10242.129418999468], "value": 5851.580790772644, "unit": "score"}], "name": "dromaeo_css", "value": 6209.295389655701, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 109.0, 113.0, 112.0, 111.0, 112.0, 113.0, 111.0, 112.0, 113.0], "value": 112.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 130.0, 132.0, 177.0, 131.0, 132.0, 150.0, 129.0, 164.0, 132.0], "value": 143.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 161.0, 161.0, 161.0, 164.0, 161.0, 163.0, 162.0, 160.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 104.0, 76.0, 76.0, 74.0, 76.0, 98.0, 75.0], "value": 80.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 125.0, 83.0, 84.0, 84.0, 118.0, 83.0, 84.0, 125.0], "value": 95.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 117.0, 117.0, 116.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 146.0, 146.0, 146.0, 145.0, 145.0, 145.0, 146.0], "value": 145.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 96.0, 95.0, 94.0, 94.0, 94.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 65.0, 64.0, 65.0, 64.0, 64.0, 84.0, 64.0, 63.0], "value": 66.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 66.0, 65.0, 66.0, 66.0, 65.0, 66.0, 65.0, 67.0], "value": 65.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 82.0, 83.0, 84.0, 85.0, 83.0, 83.0, 84.0, 83.0], "value": 83.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 131.0, 130.0, 129.0, 138.0, 132.0, 129.0, 129.0, 163.0, 133.0], "value": 134.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 169.0, 166.0, 172.0, 173.0, 171.0, 170.0, 168.0, 167.0, 167.0], "value": 169.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 68.0, 67.0, 66.0, 65.0, 66.0, 66.0, 65.0, 67.0], "value": 66.2, "unit": "score"}], "name": "kraken", "value": 1535.1000000000001, "alertThreshold": 2.0}]} 16:09:12 INFO - Return code: 0 16:09:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:09:12 ERROR - # TBPL SUCCESS # 16:09:12 INFO - Running post-action listener: _resource_record_post_action 16:09:12 INFO - ##### 16:09:12 INFO - ##### Finished run-tests step (success) 16:09:12 INFO - ##### 16:09:12 INFO - Running post-run listener: _resource_record_post_run 16:09:12 INFO - Total resource usage - Wall time: 1199s; CPU: 13.0%; Read bytes: 8192; Write bytes: 522080256; Read time: 316; Write time: 429504 16:09:12 INFO - TinderboxPrint: CPU usage
13.2% 16:09:12 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 316 16:09:12 INFO - TinderboxPrint: I/O write bytes / time
522,080,256 / 429,504 16:09:12 INFO - TinderboxPrint: CPU idle
8,309.1 (86.8%) 16:09:12 INFO - TinderboxPrint: CPU user
1,218.0 (12.7%) 16:09:12 INFO - TinderboxPrint: Swap in / out
0 / 0 16:09:12 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 4096; Write bytes: 237174784; Read time: 172; Write time: 268216 16:09:12 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 4096; Write bytes: 284905472; Read time: 144; Write time: 161288 16:09:13 INFO - Running post-run listener: _upload_blobber_files 16:09:13 INFO - Blob upload gear active. 16:09:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:09:13 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:09:13 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', 'Larch-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:09:13 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 Larch-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:09:13 INFO - (blobuploader) - INFO - Open directory for files ... 16:09:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:09:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:09:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:09:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:09:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:09:14 INFO - (blobuploader) - INFO - Done attempting. 16:09:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:09:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:09:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:09:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:09:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:09:19 INFO - (blobuploader) - INFO - Done attempting. 16:09:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:09:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:09:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:09:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:09:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:09:20 INFO - (blobuploader) - INFO - Done attempting. 16:09:20 INFO - (blobuploader) - INFO - Iteration through files over. 16:09:20 INFO - Return code: 0 16:09:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:09:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:09:20 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/2f2ac3a569cd20cae73fb2a8d6193d648093fc26d8042dcbb564eb27321b40f389d68c0ab0228e783208027a71eb08324f7a4c651beac4e6f66e079d23c3adaa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/c4591f5a39a9265045a7eaf7e2d40ded9c60c45b074b201d0d88868fd203db1bdbd9c5123570e4c24b0033a62d12e30343ee082c9d38d153658e69f9b916f30b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/c402b3067b7ec01c4c6b5f98719465cf2459e81353818e078586b445c161cebd898691712153cd26f0aaa8624a023006beff29b1c5e62bf17a45664be49320db"} 16:09:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:09:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:09:20 INFO - Contents: 16:09:20 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/2f2ac3a569cd20cae73fb2a8d6193d648093fc26d8042dcbb564eb27321b40f389d68c0ab0228e783208027a71eb08324f7a4c651beac4e6f66e079d23c3adaa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/c4591f5a39a9265045a7eaf7e2d40ded9c60c45b074b201d0d88868fd203db1bdbd9c5123570e4c24b0033a62d12e30343ee082c9d38d153658e69f9b916f30b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/c402b3067b7ec01c4c6b5f98719465cf2459e81353818e078586b445c161cebd898691712153cd26f0aaa8624a023006beff29b1c5e62bf17a45664be49320db"} 16:09:20 INFO - Running post-run listener: copy_logs_to_upload_dir 16:09:20 INFO - Copying logs to upload dir... 16:09:20 INFO - mkdir: /builds/slave/test/build/upload/logs 16:09:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.927692 ========= master_lag: 1.31 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-05-27 16:09:21.476062) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-27 16:09:21.483832) ========= 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=31acecf42b6a79ec49471d4a000001bb-1464388853.767874-1900377598 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/2f2ac3a569cd20cae73fb2a8d6193d648093fc26d8042dcbb564eb27321b40f389d68c0ab0228e783208027a71eb08324f7a4c651beac4e6f66e079d23c3adaa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/c4591f5a39a9265045a7eaf7e2d40ded9c60c45b074b201d0d88868fd203db1bdbd9c5123570e4c24b0033a62d12e30343ee082c9d38d153658e69f9b916f30b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/c402b3067b7ec01c4c6b5f98719465cf2459e81353818e078586b445c161cebd898691712153cd26f0aaa8624a023006beff29b1c5e62bf17a45664be49320db"} build_url:https://queue.taskcluster.net/v1/task/YwZzTEZtT3OhzfEGWEmAag/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011283 build_url: 'https://queue.taskcluster.net/v1/task/YwZzTEZtT3OhzfEGWEmAag/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/Larch-Non-PGO/sha512/2f2ac3a569cd20cae73fb2a8d6193d648093fc26d8042dcbb564eb27321b40f389d68c0ab0228e783208027a71eb08324f7a4c651beac4e6f66e079d23c3adaa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/c4591f5a39a9265045a7eaf7e2d40ded9c60c45b074b201d0d88868fd203db1bdbd9c5123570e4c24b0033a62d12e30343ee082c9d38d153658e69f9b916f30b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/c402b3067b7ec01c4c6b5f98719465cf2459e81353818e078586b445c161cebd898691712153cd26f0aaa8624a023006beff29b1c5e62bf17a45664be49320db"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-27 16:09:21.511485) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 16:09:21.511791) ========= 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=31acecf42b6a79ec49471d4a000001bb-1464388853.767874-1900377598 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004408 ========= master_lag: 0.24 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 16:09:21.753534) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-27 16:09:21.753844) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-27 16:09:21.754131) ========= ========= Total master_lag: 1.62 =========