builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0364 starttime: 1464079277.26 results: success (0) buildid: 20160523171639 builduid: 2df6f63a3d754fefa021eb2c65da7322 revision: 46fe2115d46a5bb40523b8466341d8f9a26e1bdf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 01:41:17.256741) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 01:41:17.257171) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-24 01:41:17.257443) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-24 01:41:17.278239) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 01:41:17.278633) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lRJUWiDs7d/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.Ln7z5DDoVF/Listeners TMPDIR=/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-24 01:41:17-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to 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% 170M=0s 2016-05-24 01:41:17 (170 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.127723 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 01:41:17.425829) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 01:41:17.426155) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lRJUWiDs7d/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.Ln7z5DDoVF/Listeners TMPDIR=/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.025451 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 01:41:17.474376) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-24 01:41:17.474720) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 46fe2115d46a5bb40523b8466341d8f9a26e1bdf --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 46fe2115d46a5bb40523b8466341d8f9a26e1bdf --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lRJUWiDs7d/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.Ln7z5DDoVF/Listeners TMPDIR=/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-24 01:41:17,549 truncating revision to first 12 chars 2016-05-24 01:41:17,549 Setting DEBUG logging. 2016-05-24 01:41:17,549 attempt 1/10 2016-05-24 01:41:17,549 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/46fe2115d46a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-24 01:41:18,252 unpacking tar archive at: mozilla-central-46fe2115d46a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.959541 ========= master_lag: 0.13 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-24 01:41:18.559592) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 01:41:18.559927) ========= script_repo_revision: 46fe2115d46a5bb40523b8466341d8f9a26e1bdf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 01:41:18.560354) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 01:41:18.560619) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 01:41:18.577565) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-05-24 01:41:18.577974) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lRJUWiDs7d/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.Ln7z5DDoVF/Listeners TMPDIR=/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 01:41:18 INFO - MultiFileLogger online at 20160524 01:41:18 in /builds/slave/test 01:41:18 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 01:41:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:41:18 INFO - {'append_to_log': False, 01:41:18 INFO - 'base_work_dir': '/builds/slave/test', 01:41:18 INFO - 'blob_upload_branch': 'Firefox', 01:41:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:41:18 INFO - 'branch': 'Firefox', 01:41:18 INFO - 'buildbot_json_path': 'buildprops.json', 01:41:18 INFO - 'config_files': ('talos/mac_config.py',), 01:41:18 INFO - 'default_actions': ('clobber', 01:41:18 INFO - 'read-buildbot-config', 01:41:18 INFO - 'download-and-extract', 01:41:18 INFO - 'populate-webroot', 01:41:18 INFO - 'create-virtualenv', 01:41:18 INFO - 'install', 01:41:18 INFO - 'run-tests'), 01:41:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:41:18 INFO - 'download_minidump_stackwalk': True, 01:41:18 INFO - 'download_symbols': 'ondemand', 01:41:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:41:18 INFO - 'tooltool.py': '/tools/tooltool.py', 01:41:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:41:18 INFO - '/tools/misc-python/virtualenv.py')}, 01:41:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:41:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:41:18 INFO - 'installer_path': 'installer.exe', 01:41:18 INFO - 'log_level': 'info', 01:41:18 INFO - 'log_name': 'talos', 01:41:18 INFO - 'log_to_console': True, 01:41:18 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:41:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:41:18 INFO - 'opt_config_files': (), 01:41:18 INFO - 'pip_index': False, 01:41:18 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:41:18 INFO - 'cmd': ('bash', 01:41:18 INFO - '-c', 01:41:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:41:18 INFO - 'enabled': True, 01:41:18 INFO - 'halt_on_failure': False, 01:41:18 INFO - 'name': 'check_screen_resolution'},), 01:41:18 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:41:18 INFO - 'cmd': ('bash', 01:41:18 INFO - '-c', 01:41:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:41:18 INFO - 'enabled': True, 01:41:18 INFO - 'halt_on_failure': False, 01:41:18 INFO - 'name': 'check_screen_resolution'},), 01:41:18 INFO - 'run_cmd_checks_enabled': True, 01:41:18 INFO - 'sps_profile': False, 01:41:18 INFO - 'sps_profile_interval': 0, 01:41:18 INFO - 'suite': 'dromaeojs', 01:41:18 INFO - 'system_bits': '32', 01:41:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:41:18 INFO - 'title': 't-yosemite-r7-0364', 01:41:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:41:18 INFO - 'use_talos_json': True, 01:41:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:41:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:41:18 INFO - 'work_dir': 'build'} 01:41:18 INFO - ##### 01:41:18 INFO - ##### Running clobber step. 01:41:18 INFO - ##### 01:41:18 INFO - Running pre-action listener: _resource_record_pre_action 01:41:18 INFO - Running main action method: clobber 01:41:18 INFO - rmtree: /builds/slave/test/build 01:41:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:41:20 INFO - Running post-action listener: _resource_record_post_action 01:41:20 INFO - ##### 01:41:20 INFO - ##### Finished clobber step (success) 01:41:20 INFO - ##### 01:41:20 INFO - ##### 01:41:20 INFO - ##### Running read-buildbot-config step. 01:41:20 INFO - ##### 01:41:20 INFO - Running pre-action listener: _resource_record_pre_action 01:41:20 INFO - Running main action method: read_buildbot_config 01:41:20 INFO - Using buildbot properties: 01:41:20 INFO - { 01:41:20 INFO - "project": "", 01:41:20 INFO - "product": "firefox", 01:41:20 INFO - "script_repo_revision": "production", 01:41:20 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 01:41:20 INFO - "repository": "", 01:41:20 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 01:41:20 INFO - "buildid": "20160523171639", 01:41:20 INFO - "pgo_build": "False", 01:41:20 INFO - "basedir": "/builds/slave/test", 01:41:20 INFO - "buildnumber": 250, 01:41:20 INFO - "slavename": "t-yosemite-r7-0364", 01:41:20 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 01:41:20 INFO - "platform": "yosemite_r7", 01:41:20 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 01:41:20 INFO - "branch": "mozilla-central", 01:41:20 INFO - "repo_path": "mozilla-central", 01:41:20 INFO - "revision": "46fe2115d46a5bb40523b8466341d8f9a26e1bdf", 01:41:20 INFO - "stage_platform": "macosx64", 01:41:20 INFO - "builduid": "2df6f63a3d754fefa021eb2c65da7322", 01:41:20 INFO - "slavebuilddir": "test" 01:41:20 INFO - } 01:41:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 01:41:20 INFO - Running post-action listener: _resource_record_post_action 01:41:20 INFO - ##### 01:41:20 INFO - ##### Finished read-buildbot-config step (success) 01:41:20 INFO - ##### 01:41:20 INFO - ##### 01:41:20 INFO - ##### Running download-and-extract step. 01:41:20 INFO - ##### 01:41:20 INFO - Running pre-action listener: _resource_record_pre_action 01:41:20 INFO - Running main action method: download_and_extract 01:41:20 INFO - mkdir: /builds/slave/test/build/tests 01:41:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:41:20 INFO - https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 01:41:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 01:41:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 01:41:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 01:41:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 01:41:24 INFO - Downloaded 1199 bytes. 01:41:24 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 01:41:24 INFO - Using the following test package requirements: 01:41:24 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 01:41:24 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:41:24 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 01:41:24 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:41:24 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 01:41:24 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:41:24 INFO - u'jsshell-mac.zip'], 01:41:24 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:41:24 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 01:41:24 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 01:41:24 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:41:24 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 01:41:24 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:41:24 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 01:41:24 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:41:24 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 01:41:24 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:41:24 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 01:41:24 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 01:41:24 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')]} 01:41:24 INFO - https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 01:41:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 01:41:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 01:41:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 01:41:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 01:41:25 INFO - Downloaded 18630520 bytes. 01:41:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 01:41:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 01:41:28 INFO - Return code: 0 01:41:28 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 01:41:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:41:28 INFO - https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 01:41:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 01:41:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 01:41:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 01:41:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 01:41:29 INFO - Downloaded 18630520 bytes. 01:41:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 01:41:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 01:41:33 INFO - Return code: 0 01:41:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:41:33 INFO - https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 01:41:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 01:41:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 01:41:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 01:41:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 01:41:34 INFO - Downloaded 10840359 bytes. 01:41:34 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 01:41:34 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 01:41:34 INFO - Return code: 0 01:41:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:41:34 INFO - https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 01:41:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 01:41:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 01:41:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 01:41:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 01:41:37 INFO - Downloaded 110231948 bytes. 01:41:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 01:41:37 INFO - mkdir: /builds/slave/test/properties 01:41:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:41:37 INFO - Writing to file /builds/slave/test/properties/build_url 01:41:37 INFO - Contents: 01:41:37 INFO - build_url:https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 01:41:38 INFO - Running post-action listener: _resource_record_post_action 01:41:38 INFO - Running post-action listener: set_extra_try_arguments 01:41:38 INFO - ##### 01:41:38 INFO - ##### Finished download-and-extract step (success) 01:41:38 INFO - ##### 01:41:38 INFO - ##### 01:41:38 INFO - ##### Running populate-webroot step. 01:41:38 INFO - ##### 01:41:38 INFO - Running pre-action listener: _resource_record_pre_action 01:41:38 INFO - Running main action method: populate_webroot 01:41:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:41:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:41:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:41:38 INFO - '--tppagecycles', 01:41:38 INFO - '7'], 01:41:38 INFO - 'tests': ['tsvgm']}}, 01:41:38 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:41:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:41:38 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:41:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:41:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:41:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:41:38 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:41:38 INFO - 'talos_options': ['--disable-e10s'], 01:41:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:41:38 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:41:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:41:38 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:41:38 INFO - 'talos_options': ['--disable-e10s'], 01:41:38 INFO - 'tests': ['damp', 'tps']}, 01:41:38 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:41:38 INFO - 'tests': ['damp', 'tps']}, 01:41:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:41:38 INFO - 'tests': ['dromaeo_dom']}, 01:41:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:41:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:41:38 INFO - 'tests': ['a11yr', 01:41:38 INFO - 'ts_paint', 01:41:38 INFO - 'tpaint', 01:41:38 INFO - 'sessionrestore', 01:41:38 INFO - 'sessionrestore_no_auto_restore', 01:41:38 INFO - 'tabpaint']}, 01:41:38 INFO - 'other-e10s': {'tests': ['a11yr', 01:41:38 INFO - 'ts_paint', 01:41:38 INFO - 'tpaint', 01:41:38 INFO - 'sessionrestore', 01:41:38 INFO - 'sessionrestore_no_auto_restore', 01:41:38 INFO - 'tabpaint']}, 01:41:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:41:38 INFO - 'ts_paint', 01:41:38 INFO - 'tpaint', 01:41:38 INFO - 'sessionrestore', 01:41:38 INFO - 'sessionrestore_no_auto_restore', 01:41:38 INFO - 'tabpaint']}, 01:41:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:41:38 INFO - 'ts_paint', 01:41:38 INFO - 'tpaint', 01:41:38 INFO - 'sessionrestore', 01:41:38 INFO - 'sessionrestore_no_auto_restore', 01:41:38 INFO - 'tabpaint']}, 01:41:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:41:38 INFO - 'tests': ['a11yr', 01:41:38 INFO - 'ts_paint', 01:41:38 INFO - 'tpaint', 01:41:38 INFO - 'sessionrestore', 01:41:38 INFO - 'sessionrestore_no_auto_restore', 01:41:38 INFO - 'tabpaint']}, 01:41:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:41:38 INFO - 'tests': ['a11yr', 01:41:38 INFO - 'ts_paint', 01:41:38 INFO - 'tpaint', 01:41:38 INFO - 'sessionrestore', 01:41:38 INFO - 'sessionrestore_no_auto_restore', 01:41:38 INFO - 'tabpaint']}, 01:41:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:41:38 INFO - 'tests': ['tsvgx', 01:41:38 INFO - 'tsvgr_opacity', 01:41:38 INFO - 'tart', 01:41:38 INFO - 'tscrollx', 01:41:38 INFO - 'cart']}, 01:41:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:41:38 INFO - 'tsvgr_opacity', 01:41:38 INFO - 'tart', 01:41:38 INFO - 'tscrollx', 01:41:38 INFO - 'cart']}, 01:41:38 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:41:38 INFO - 'talos_options': ['--disable-e10s'], 01:41:38 INFO - 'tests': ['tp5o']}, 01:41:38 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:41:38 INFO - 'tests': ['tp5o']}, 01:41:38 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:41:38 INFO - 'talos_options': ['--disable-e10s', 01:41:38 INFO - '--xperf_path', 01:41:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:41:38 INFO - 'tests': ['tp5n']}, 01:41:38 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:41:38 INFO - 'talos_options': ['--xperf_path', 01:41:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:41:38 INFO - 'tests': ['tp5n']}}, 01:41:38 INFO - 'talos.zip': {'path': '', 01:41:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:41:38 INFO - Running post-action listener: _resource_record_post_action 01:41:38 INFO - ##### 01:41:38 INFO - ##### Finished populate-webroot step (success) 01:41:38 INFO - ##### 01:41:38 INFO - ##### 01:41:38 INFO - ##### Running create-virtualenv step. 01:41:38 INFO - ##### 01:41:38 INFO - Running pre-action listener: _resource_record_pre_action 01:41:38 INFO - Running main action method: create_virtualenv 01:41:38 INFO - Creating virtualenv /builds/slave/test/build/venv 01:41:38 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 01:41:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:41:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:41:39 INFO - Using real prefix '/tools/python27' 01:41:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:41:39 INFO - Installing distribute.............................................................................................................................................................................................done. 01:41:42 INFO - Installing pip.................done. 01:41:42 INFO - Return code: 0 01:41:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:41:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:41:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:41:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1099e9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099ef570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe349c89b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10988dd30>, '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 0x1096b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109884b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lRJUWiDs7d/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ln7z5DDoVF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:41:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 01:41:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 01:41:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lRJUWiDs7d/Render', 01:41:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:41:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:41:42 INFO - 'HOME': '/Users/cltbld', 01:41:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:41:42 INFO - 'LOGNAME': 'cltbld', 01:41:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:42 INFO - 'MOZ_NO_REMOTE': '1', 01:41:42 INFO - 'NO_EM_RESTART': '1', 01:41:42 INFO - 'PAGER': '/bin/cat', 01:41:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:41:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:42 INFO - 'PWD': '/builds/slave/test', 01:41:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:41:42 INFO - 'SHELL': '/bin/bash', 01:41:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ln7z5DDoVF/Listeners', 01:41:42 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 01:41:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:41:42 INFO - 'USER': 'cltbld', 01:41:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:41:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:41:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:42 INFO - 'XPC_FLAGS': '0x0', 01:41:42 INFO - 'XPC_SERVICE_NAME': '0', 01:41:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:41:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:42 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 01:41:42 INFO - Cleaning up... 01:41:42 INFO - Return code: 0 01:41:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:41:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:41:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:41:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1099e9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099ef570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe349c89b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10988dd30>, '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 0x1096b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109884b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lRJUWiDs7d/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ln7z5DDoVF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:41:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 01:41:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 01:41:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lRJUWiDs7d/Render', 01:41:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:41:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:41:42 INFO - 'HOME': '/Users/cltbld', 01:41:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:41:42 INFO - 'LOGNAME': 'cltbld', 01:41:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:42 INFO - 'MOZ_NO_REMOTE': '1', 01:41:42 INFO - 'NO_EM_RESTART': '1', 01:41:42 INFO - 'PAGER': '/bin/cat', 01:41:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:41:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:42 INFO - 'PWD': '/builds/slave/test', 01:41:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:41:42 INFO - 'SHELL': '/bin/bash', 01:41:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ln7z5DDoVF/Listeners', 01:41:42 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 01:41:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:41:42 INFO - 'USER': 'cltbld', 01:41:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:41:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:41:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:42 INFO - 'XPC_FLAGS': '0x0', 01:41:42 INFO - 'XPC_SERVICE_NAME': '0', 01:41:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:41:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:43 INFO - Downloading/unpacking psutil>=3.1.1 01:41:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:41:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:41:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:41:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:41:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:41:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:41:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:41:46 INFO - Installing collected packages: psutil 01:41:46 INFO - Running setup.py install for psutil 01:41:46 INFO - building 'psutil._psutil_osx' extension 01:41:46 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 01:41:46 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 01:41:46 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 01:41:46 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 01:41:47 INFO - building 'psutil._psutil_posix' extension 01:41:47 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 01:41:47 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 01:41:47 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 01:41:47 INFO - ^ 01:41:47 INFO - 1 warning generated. 01:41:47 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 01:41:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:41:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:41:47 INFO - Successfully installed psutil 01:41:47 INFO - Cleaning up... 01:41:47 INFO - Return code: 0 01:41:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:41:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:41:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:41:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:47 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 0x1099e9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099ef570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe349c89b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10988dd30>, '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 0x1096b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109884b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lRJUWiDs7d/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ln7z5DDoVF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:41:47 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 01:41:47 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 01:41:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lRJUWiDs7d/Render', 01:41:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:41:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:41:47 INFO - 'HOME': '/Users/cltbld', 01:41:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:41:47 INFO - 'LOGNAME': 'cltbld', 01:41:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:47 INFO - 'MOZ_NO_REMOTE': '1', 01:41:47 INFO - 'NO_EM_RESTART': '1', 01:41:47 INFO - 'PAGER': '/bin/cat', 01:41:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:41:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:47 INFO - 'PWD': '/builds/slave/test', 01:41:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:41:47 INFO - 'SHELL': '/bin/bash', 01:41:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ln7z5DDoVF/Listeners', 01:41:47 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 01:41:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:41:47 INFO - 'USER': 'cltbld', 01:41:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:41:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:41:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:47 INFO - 'XPC_FLAGS': '0x0', 01:41:47 INFO - 'XPC_SERVICE_NAME': '0', 01:41:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:41:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:41:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:41:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:41:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:41:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:41:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:41:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:41:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:41:50 INFO - Installing collected packages: mozsystemmonitor 01:41:50 INFO - Running setup.py install for mozsystemmonitor 01:41:50 INFO - Successfully installed mozsystemmonitor 01:41:50 INFO - Cleaning up... 01:41:50 INFO - Return code: 0 01:41:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:41:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:41:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:41:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1099e9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099ef570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe349c89b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10988dd30>, '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 0x1096b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109884b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lRJUWiDs7d/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ln7z5DDoVF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:41:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 01:41:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 01:41:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lRJUWiDs7d/Render', 01:41:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:41:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:41:50 INFO - 'HOME': '/Users/cltbld', 01:41:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:41:50 INFO - 'LOGNAME': 'cltbld', 01:41:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:50 INFO - 'MOZ_NO_REMOTE': '1', 01:41:50 INFO - 'NO_EM_RESTART': '1', 01:41:50 INFO - 'PAGER': '/bin/cat', 01:41:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:41:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:50 INFO - 'PWD': '/builds/slave/test', 01:41:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:41:50 INFO - 'SHELL': '/bin/bash', 01:41:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ln7z5DDoVF/Listeners', 01:41:50 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 01:41:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:41:50 INFO - 'USER': 'cltbld', 01:41:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:41:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:41:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:50 INFO - 'XPC_FLAGS': '0x0', 01:41:50 INFO - 'XPC_SERVICE_NAME': '0', 01:41:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:41:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:50 INFO - Downloading/unpacking blobuploader==1.2.4 01:41:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:41:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:41:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:41:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:41:53 INFO - Downloading blobuploader-1.2.4.tar.gz 01:41:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:41:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:41:53 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 01:41:53 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 01:41:53 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 01:41:53 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 01:41:53 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 01:41:53 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 01:41:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:41:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:41: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 01:41: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 01:41: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 01:41: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 01:41: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 01:41: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 01:41:54 INFO - Downloading docopt-0.6.1.tar.gz 01:41:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:41:54 INFO - Installing collected packages: blobuploader, requests, docopt 01:41:54 INFO - Running setup.py install for blobuploader 01:41:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:41:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:41:54 INFO - Running setup.py install for requests 01:41:55 INFO - Running setup.py install for docopt 01:41:55 INFO - Successfully installed blobuploader requests docopt 01:41:55 INFO - Cleaning up... 01:41:55 INFO - Return code: 0 01:41:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:41:55 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')]} 01:41:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:55 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')]} 01:41:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:55 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 0x1099e9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099ef570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe349c89b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10988dd30>, '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 0x1096b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109884b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lRJUWiDs7d/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ln7z5DDoVF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:41:55 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 01:41:55 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 01:41:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lRJUWiDs7d/Render', 01:41:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:41:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:41:55 INFO - 'HOME': '/Users/cltbld', 01:41:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:41:55 INFO - 'LOGNAME': 'cltbld', 01:41:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:55 INFO - 'MOZ_NO_REMOTE': '1', 01:41:55 INFO - 'NO_EM_RESTART': '1', 01:41:55 INFO - 'PAGER': '/bin/cat', 01:41:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:41:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:55 INFO - 'PWD': '/builds/slave/test', 01:41:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:41:55 INFO - 'SHELL': '/bin/bash', 01:41:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ln7z5DDoVF/Listeners', 01:41:55 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 01:41:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:41:55 INFO - 'USER': 'cltbld', 01:41:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:41:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:41:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:55 INFO - 'XPC_FLAGS': '0x0', 01:41:55 INFO - 'XPC_SERVICE_NAME': '0', 01:41:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:41:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:41:55 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-HlGQXl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:41:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:41:55 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-m3kKNQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:41:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:41:55 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-DxKweJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:41:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:41:56 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-xJ58XW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:41:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:41:56 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-aymEZm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:41:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:41:56 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-ddbe1U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:41:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:41:56 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-HkZUbY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:41:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:41:56 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-1ZIyKR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:41:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:41:56 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-npnqCV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:41:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:41:56 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-QDgDCP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:41:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:41:56 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-XjMOq7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:41:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:41:56 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-cbNp4i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:41:57 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-NBrBCt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:41:57 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-EtuZLM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:41:57 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-xLbK9C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:41:57 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-PsWdQ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:41:57 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-iQeDpH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:41:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:41:57 INFO - Running setup.py install for manifestparser 01:41:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:41:57 INFO - Running setup.py install for mozcrash 01:41:57 INFO - Running setup.py install for mozdebug 01:41:57 INFO - Running setup.py install for mozdevice 01:41:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:41:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:41:58 INFO - Running setup.py install for mozfile 01:41:58 INFO - Running setup.py install for mozhttpd 01:41:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:41:58 INFO - Running setup.py install for mozinfo 01:41:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:41:58 INFO - Running setup.py install for mozInstall 01:41:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:41:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:41:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:41:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:41:58 INFO - Running setup.py install for mozleak 01:41:58 INFO - Running setup.py install for mozlog 01:41:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:41:59 INFO - Running setup.py install for moznetwork 01:41:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:41:59 INFO - Running setup.py install for mozprocess 01:41:59 INFO - Running setup.py install for mozprofile 01:41:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:41:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:41:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:41:59 INFO - Running setup.py install for mozrunner 01:41:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:41:59 INFO - Running setup.py install for mozscreenshot 01:41:59 INFO - Running setup.py install for moztest 01:41:59 INFO - Running setup.py install for mozversion 01:42:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:42:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:42:00 INFO - Cleaning up... 01:42:00 INFO - Return code: 0 01:42:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:42: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')]} 01:42:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:42:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42: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')]} 01:42:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:42:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:00 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 0x1099e9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099ef570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe349c89b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10988dd30>, '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 0x1096b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109884b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lRJUWiDs7d/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ln7z5DDoVF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:42:00 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 01:42:00 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 01:42:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lRJUWiDs7d/Render', 01:42:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:42:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:42:00 INFO - 'HOME': '/Users/cltbld', 01:42:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:42:00 INFO - 'LOGNAME': 'cltbld', 01:42:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:42:00 INFO - 'MOZ_NO_REMOTE': '1', 01:42:00 INFO - 'NO_EM_RESTART': '1', 01:42:00 INFO - 'PAGER': '/bin/cat', 01:42:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:42:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:42:00 INFO - 'PWD': '/builds/slave/test', 01:42:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:42:00 INFO - 'SHELL': '/bin/bash', 01:42:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ln7z5DDoVF/Listeners', 01:42:00 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 01:42:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:42:00 INFO - 'USER': 'cltbld', 01:42:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:42:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:42:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:42:00 INFO - 'XPC_FLAGS': '0x0', 01:42:00 INFO - 'XPC_SERVICE_NAME': '0', 01:42:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:42:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:42:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:42:00 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-brM1nm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:42:00 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)) 01:42:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:42:00 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-3wqGLu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:42:00 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)) 01:42:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:42:00 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-9NejB9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:42:00 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)) 01:42:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:42:00 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-QvzwEi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:42:00 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)) 01:42:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:42:00 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-vKk_l6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:42:00 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)) 01:42:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:42:00 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-8S69aN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:42:00 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)) 01:42:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:42:00 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-5Ib_ET-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:42:01 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)) 01:42:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:42:01 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-I_jK9u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:42:01 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)) 01:42:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:42:01 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-gzeNfr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:42:01 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)) 01:42:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:42:01 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-PT8VDQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:42:01 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)) 01:42:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:42:01 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-yZt6ED-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:42:01 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)) 01:42:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:42:01 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-bpTS5Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:42:01 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)) 01:42:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:42:01 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-FVgW4h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:42:01 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)) 01:42:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:42:01 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-OGdvPl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:42:01 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)) 01:42:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:42:01 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-MwltqY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:42:01 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)) 01:42:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:42:01 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-TAy88A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:42:02 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)) 01:42:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:42:02 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-zamvEH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:42:02 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)) 01:42:02 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)) 01:42:02 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)) 01:42:02 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)) 01:42:02 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)) 01:42:02 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)) 01:42:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:42:02 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 01:42:02 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 01:42:02 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 01:42:02 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 01:42:02 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 01:42:02 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 01:42:04 INFO - Downloading blessings-1.6.tar.gz 01:42:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:42:04 INFO - Installing collected packages: blessings 01:42:04 INFO - Running setup.py install for blessings 01:42:04 INFO - Successfully installed blessings 01:42:04 INFO - Cleaning up... 01:42:04 INFO - Return code: 0 01:42:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:42:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:42:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:42:05 INFO - Reading from file tmpfile_stdout 01:42:05 INFO - Current package versions: 01:42:05 INFO - blessings == 1.6 01:42:05 INFO - blobuploader == 1.2.4 01:42:05 INFO - docopt == 0.6.1 01:42:05 INFO - manifestparser == 1.1 01:42:05 INFO - mozInstall == 1.12 01:42:05 INFO - mozcrash == 0.17 01:42:05 INFO - mozdebug == 0.1 01:42:05 INFO - mozdevice == 0.48 01:42:05 INFO - mozfile == 1.2 01:42:05 INFO - mozhttpd == 0.7 01:42:05 INFO - mozinfo == 0.9 01:42:05 INFO - mozleak == 0.1 01:42:05 INFO - mozlog == 3.2 01:42:05 INFO - moznetwork == 0.27 01:42:05 INFO - mozprocess == 0.22 01:42:05 INFO - mozprofile == 0.28 01:42:05 INFO - mozrunner == 6.11 01:42:05 INFO - mozscreenshot == 0.1 01:42:05 INFO - mozsystemmonitor == 0.3 01:42:05 INFO - moztest == 0.7 01:42:05 INFO - mozversion == 1.4 01:42:05 INFO - psutil == 3.1.1 01:42:05 INFO - requests == 1.2.3 01:42:05 INFO - wsgiref == 0.1.2 01:42:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:42:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:42:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:42:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:42:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:42:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:05 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 0x1099e9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099ef570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe349c89b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10988dd30>, '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 0x1096b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109884b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lRJUWiDs7d/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ln7z5DDoVF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:42:05 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 01:42:05 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 01:42:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lRJUWiDs7d/Render', 01:42:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:42:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:42:05 INFO - 'HOME': '/Users/cltbld', 01:42:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:42:05 INFO - 'LOGNAME': 'cltbld', 01:42:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:42:05 INFO - 'MOZ_NO_REMOTE': '1', 01:42:05 INFO - 'NO_EM_RESTART': '1', 01:42:05 INFO - 'PAGER': '/bin/cat', 01:42:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:42:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:42:05 INFO - 'PWD': '/builds/slave/test', 01:42:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:42:05 INFO - 'SHELL': '/bin/bash', 01:42:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ln7z5DDoVF/Listeners', 01:42:05 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 01:42:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:42:05 INFO - 'USER': 'cltbld', 01:42:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:42:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:42:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:42:05 INFO - 'XPC_FLAGS': '0x0', 01:42:05 INFO - 'XPC_SERVICE_NAME': '0', 01:42:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:42:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:42:05 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)) 01:42:05 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)) 01:42:05 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)) 01:42:05 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)) 01:42:05 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)) 01:42:05 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)) 01:42:05 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)) 01:42:05 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)) 01:42:05 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)) 01:42:05 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)) 01:42:05 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)) 01:42:05 INFO - Cleaning up... 01:42:05 INFO - Return code: 0 01:42:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:42:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:42:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:42:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:42:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:42:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:05 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 0x1099e9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099ef570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe349c89b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10988dd30>, '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 0x1096b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109884b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lRJUWiDs7d/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ln7z5DDoVF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:42:05 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 01:42:05 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 01:42:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lRJUWiDs7d/Render', 01:42:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:42:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:42:05 INFO - 'HOME': '/Users/cltbld', 01:42:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:42:05 INFO - 'LOGNAME': 'cltbld', 01:42:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:42:05 INFO - 'MOZ_NO_REMOTE': '1', 01:42:05 INFO - 'NO_EM_RESTART': '1', 01:42:05 INFO - 'PAGER': '/bin/cat', 01:42:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:42:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:42:05 INFO - 'PWD': '/builds/slave/test', 01:42:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:42:05 INFO - 'SHELL': '/bin/bash', 01:42:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ln7z5DDoVF/Listeners', 01:42:05 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 01:42:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:42:05 INFO - 'USER': 'cltbld', 01:42:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:42:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:42:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:42:05 INFO - 'XPC_FLAGS': '0x0', 01:42:05 INFO - 'XPC_SERVICE_NAME': '0', 01:42:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:42:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:42:05 INFO - Downloading/unpacking jsonschema 01:42:05 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 01:42:05 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 01:42:05 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 01:42:05 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 01:42:05 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 01:42:05 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 01:42:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:42:08 INFO - Downloading/unpacking functools32 (from jsonschema) 01:42:08 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 01:42:08 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 01:42:08 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 01:42:08 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 01:42:08 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 01:42:08 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 01:42:08 INFO - Downloading functools32-3.2.3-2.tar.gz 01:42:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:42:08 INFO - warning: no files found matching '*.txt' 01:42:08 INFO - no previously-included directories found matching 'build' 01:42:08 INFO - no previously-included directories found matching 'dist' 01:42:08 INFO - no previously-included directories found matching '.git*' 01:42:08 INFO - Installing collected packages: jsonschema, functools32 01:42:08 INFO - Running setup.py install for functools32 01:42:08 INFO - warning: no files found matching '*.txt' 01:42:08 INFO - no previously-included directories found matching 'build' 01:42:08 INFO - no previously-included directories found matching 'dist' 01:42:08 INFO - no previously-included directories found matching '.git*' 01:42:09 INFO - Successfully installed jsonschema functools32 01:42:09 INFO - Cleaning up... 01:42:09 INFO - Return code: 0 01:42:09 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 01:42: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')]} 01:42:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:42:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42: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')]} 01:42:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:42:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42: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 0x1099e9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099ef570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe349c89b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10988dd30>, '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 0x1096b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109884b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lRJUWiDs7d/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ln7z5DDoVF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:42: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 01:42: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 01:42:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lRJUWiDs7d/Render', 01:42:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:42:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:42:09 INFO - 'HOME': '/Users/cltbld', 01:42:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:42:09 INFO - 'LOGNAME': 'cltbld', 01:42:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:42:09 INFO - 'MOZ_NO_REMOTE': '1', 01:42:09 INFO - 'NO_EM_RESTART': '1', 01:42:09 INFO - 'PAGER': '/bin/cat', 01:42:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:42:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:42:09 INFO - 'PWD': '/builds/slave/test', 01:42:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:42:09 INFO - 'SHELL': '/bin/bash', 01:42:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ln7z5DDoVF/Listeners', 01:42:09 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 01:42:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:42:09 INFO - 'USER': 'cltbld', 01:42:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:42:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:42:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:42:09 INFO - 'XPC_FLAGS': '0x0', 01:42:09 INFO - 'XPC_SERVICE_NAME': '0', 01:42:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:42:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:42:09 INFO - Downloading/unpacking flake8 01:42: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 01:42: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 01:42: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 01:42: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 01:42: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 01:42: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 01:42:11 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 01:42:11 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 01:42: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 01:42: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 01:42: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 01:42: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 01:42: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 01:42: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 01:42:12 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 01:42:12 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 01:42: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 01:42: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 01:42: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 01:42: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 01:42: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 01:42: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 01:42:12 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 01:42: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 01:42: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 01:42: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 01:42: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 01:42: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 01:42: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 01:42:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 01:42:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:42:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:42:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:42:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:42:13 INFO - no previously-included directories found matching 'docs/_build' 01:42:13 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 01:42:13 INFO - Running setup.py install for pep8 01:42:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:42:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:42:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:42:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:42:13 INFO - no previously-included directories found matching 'docs/_build' 01:42:13 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 01:42:13 INFO - Successfully installed flake8 mccabe pyflakes pep8 01:42:13 INFO - Cleaning up... 01:42:13 INFO - Return code: 0 01:42:13 INFO - Running post-action listener: _resource_record_post_action 01:42:13 INFO - Running post-action listener: _start_resource_monitoring 01:42:13 INFO - Starting resource monitoring. 01:42:13 INFO - ##### 01:42:13 INFO - ##### Finished create-virtualenv step (success) 01:42:13 INFO - ##### 01:42:13 INFO - ##### 01:42:13 INFO - ##### Running install step. 01:42:13 INFO - ##### 01:42:13 INFO - Running pre-action listener: _resource_record_pre_action 01:42:13 INFO - Running main action method: install 01:42:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:42:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:42:13 INFO - Reading from file tmpfile_stdout 01:42:13 INFO - Detecting whether we're running mozinstall >=1.0... 01:42:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:42:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:42:13 INFO - Reading from file tmpfile_stdout 01:42:13 INFO - Output received: 01:42:13 INFO - Usage: mozinstall [options] installer 01:42:13 INFO - Options: 01:42:13 INFO - -h, --help show this help message and exit 01:42:13 INFO - -d DEST, --destination=DEST 01:42:13 INFO - Directory to install application into. [default: 01:42:13 INFO - "/builds/slave/test"] 01:42:13 INFO - --app=APP Application being installed. [default: firefox] 01:42:13 INFO - mkdir: /builds/slave/test/build/application 01:42:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 01:42:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 01:42:47 INFO - Reading from file tmpfile_stdout 01:42:47 INFO - Output received: 01:42:47 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 01:42:47 INFO - Running post-action listener: _resource_record_post_action 01:42:47 INFO - ##### 01:42:47 INFO - ##### Finished install step (success) 01:42:47 INFO - ##### 01:42:47 INFO - ##### 01:42:47 INFO - ##### Running run-tests step. 01:42:47 INFO - ##### 01:42:47 INFO - Running pre-action listener: _resource_record_pre_action 01:42:47 INFO - Running main action method: run_tests 01:42:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:42:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:42:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:42:47 INFO - 2016-05-24 01:42:47.326 screenresolution[1638:7379] starting screenresolution argv=screenresolution get 01:42:47 INFO - 2016-05-24 01:42:47.329 screenresolution[1638:7379] Display 0: 1600x1200x32@60 01:42:47 INFO - 2016-05-24 01:42:47.335 screenresolution[1639:7381] starting screenresolution argv=screenresolution list 01:42:47 INFO - Available Modes on Display 0 01:42:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:42:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:42:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:42:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:42:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:42:47 INFO - Intel Iris: 01:42:47 INFO - Chipset Model: Intel Iris 01:42:47 INFO - Type: GPU 01:42:47 INFO - Bus: Built-In 01:42:47 INFO - VRAM (Dynamic, Max): 1536 MB 01:42:47 INFO - Vendor: Intel (0x8086) 01:42:47 INFO - Device ID: 0x0a2e 01:42:47 INFO - Revision ID: 0x0009 01:42:47 INFO - Displays: 01:42:47 INFO - Display: 01:42:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:42:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:42:47 INFO - Display Serial Number: 1600x1200 60 01:42:47 INFO - Main Display: Yes 01:42:47 INFO - Mirror: Off 01:42:47 INFO - Online: Yes 01:42:47 INFO - Rotation: Supported 01:42:47 INFO - Return code: 0 01:42:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:42:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:42:47 INFO - Python 2.7.3 01:42:47 INFO - Return code: 0 01:42:47 INFO - grabbing minidump binary from tooltool 01:42:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:42:47 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/macosx64/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 0x10988dd30>, '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 0x1096b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109884b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:42:47 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/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 01:42:47 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/macosx64/releng.manifest -o -c /builds/tooltool_cache 01:42:47 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/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 01:42:47 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:42:47 INFO - Return code: 0 01:42:47 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 01:42:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:42:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:42:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 01:42:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:42:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 01:42:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 01:42:48 INFO - Return code: 0 01:42:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0364', '--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 01:42:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0364 --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 01:42:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lRJUWiDs7d/Render', 01:42:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:42:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:42:48 INFO - 'HOME': '/Users/cltbld', 01:42:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:42:48 INFO - 'LOGNAME': 'cltbld', 01:42:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:42:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 01:42:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:42:48 INFO - 'MOZ_NO_REMOTE': '1', 01:42:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:42:48 INFO - 'NO_EM_RESTART': '1', 01:42:48 INFO - 'PAGER': '/bin/cat', 01:42:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:42:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:42:48 INFO - 'PWD': '/builds/slave/test', 01:42:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:42:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:42:48 INFO - 'SHELL': '/bin/bash', 01:42:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ln7z5DDoVF/Listeners', 01:42:48 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 01:42:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:42:48 INFO - 'USER': 'cltbld', 01:42:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:42:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:42:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:42:48 INFO - 'XPC_FLAGS': '0x0', 01:42:48 INFO - 'XPC_SERVICE_NAME': '0', 01:42:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:42:48 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0364', '--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 01:42:49 INFO - mozversion application_buildid: 20160523171639 01:42:49 INFO - mozversion application_changeset: 46fe2115d46a5bb40523b8466341d8f9a26e1bdf 01:42:49 INFO - mozversion application_display_name: Nightly 01:42:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:42:49 INFO - mozversion application_name: Firefox 01:42:49 INFO - mozversion application_remotingname: firefox 01:42:49 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 01:42:49 INFO - mozversion application_vendor: Mozilla 01:42:49 INFO - mozversion application_version: 49.0a1 01:42:49 INFO - mozversion platform_buildid: 20160523171639 01:42:49 INFO - mozversion platform_changeset: 46fe2115d46a5bb40523b8466341d8f9a26e1bdf 01:42:49 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 01:42:49 INFO - mozversion platform_version: 49.0a1 01:42:49 INFO - using testdate: 1464079369 01:42:49 INFO - actual date: 1464079369 01:42:49 INFO - starting webserver on 'localhost:49278' 01:42:49 INFO - SUITE-START | Running 2 tests 01:42:49 INFO - TEST-START | dromaeo_css 01:42:49 INFO - operating with platform_type : mac_ 01:42:49 INFO - Initialising browser for dromaeo_css test... 01:42:49 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/tmp2vabC3/profile http://localhost:49278/getInfo.html) 01:42:51 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 01:42:51 INFO - PROCESS | 1657 | colorDepth:24 01:42:51 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 01:42:51 INFO - PROCESS | 1657 | __metrics 01:42:51 INFO - PROCESS | 1657 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 01:42:51 ERROR - PROCESS | 1657 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 01:42:52 INFO - TEST-INFO | 1657: exit 0 01:42:52 INFO - Browser initialized. 01:42:52 INFO - Running cycle 1/1 for dromaeo_css test... 01:42:52 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/tmp2vabC3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:43:04 INFO - PROCESS | 1659 | RSS: Main: 239112192 01:43:04 INFO - PROCESS | 1659 | 01:43:55 INFO - PROCESS | 1659 | 1464079435470 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] 01:46:05 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 01:46:06 INFO - PROCESS | 1659 | RSS: Main: 332005376 01:46:06 INFO - PROCESS | 1659 | 01:49:09 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 01:49:09 INFO - PROCESS | 1659 | RSS: Main: 368640000 01:49:09 INFO - PROCESS | 1659 | 01:52:12 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 01:52:12 INFO - PROCESS | 1659 | RSS: Main: 368799744 01:52:12 INFO - PROCESS | 1659 | 01:55:14 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 01:55:15 INFO - PROCESS | 1659 | RSS: Main: 366653440 01:55:15 INFO - PROCESS | 1659 | 01:58:18 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 01:58:19 INFO - PROCESS | 1659 | RSS: Main: 334581760 01:58:19 INFO - PROCESS | 1659 | 02:01:22 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 02:01:22 INFO - PROCESS | 1659 | RSS: Main: 374530048 02:01:22 INFO - PROCESS | 1659 | 02:01:22 INFO - PROCESS | 1659 | __start_tp_report 02:01:22 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 02:01:22 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 02:01:22 INFO - PROCESS | 1659 | |i|pagename|runs| 02:01:22 INFO - PROCESS | 1659 | |0;dojo.html;3478.4608385700226;3484.843182056807;3487.7384196185285;3519.3665140274748;3561.7328700347466;4650.372199753032;5653.773849046043;5676.545876329894;5715.3141623005195;5752.309722833261;5677.602567820254;5686.1186516089965;5705.4579814917515;5808.390119037504;5839.912401313973;5129.461406552315;5158.174692049273;5202.8699282518055;5224.085784987612;5350.170723537858;5313.760880760356;5324.494173053554;5347.812826551071;5378.596605254622;5378.731063446831;5130.153524668444;5183.196604526304;5200.323957885488;5214.895702085956;5228.189630607224;5175.896482070375;5195.714235717034;5261.789528418859;5297.576193904517;5317.9468205317835;5145.691258524463;5193.402758682762;5249.973750131263;5257.658252213594;5270.446603106695;5568.415316391802;5668.603197776158;5689.0328644130595;5730.19777231188;5753.252077229975;6194.473469755077;6213.844653883644;6237.345078766755;6262.624242545417;6264.749410023594;5074.644774865761;6116.548817443993;6228.688565571703;6270.529710271748;6275.058741188813;5833.212516310286;5858.707064646751;5908.911366329508;5919.260092488439;5923.881522369528;6289.842753931097;6293;6310.842228944313;6312.621242725451;6314.273858506331;6048.969755151196;6077.632532680112;6129.540284478682;6147.477464415575;6163.322034576193;6174.598651087665;6178.227721534809;6181.7527298908435;6185.567010309325;6185.969070154711;6073.878522429527;6124.755009799558;6171.259448866164;6176.166217560662;6187.81436556904;5518.33779946409;5934.276613378327;6146.815595532052;6172.598781079305;6190.7523699052435;4943.604511639061;4950.331705219821;4980.327655766498;4991.425986011655;5040.344755181803;4402.889927751768;4418.60232579063;4421.955780442155;4430.202563538594;4456.286994080931;5593.496585307342;5615.550755939515;5633.464820842013;5795.1017592273265;5806.854828629233;6104.246950856165;6172.074188871616;6174.783882564088;6181.721822517998;6206.813795586139;4854.368932038883;5675.829725108253;5685.516731077904;5695.45893140151;5723.914141287884;5328.387235467893;5369.785208591691;5374.48942350476;5396.109641909119;5444.047291723932;6439.323870993572;6445.129907462432;6451.096846441408;6454.419102280817;6468.288488266287;6145.477634401126;6172.629642221481;6196;6202.596831206138;6207.6275423474735;6155.322914479314;6268.153799237045;6321.747130114928;6336.9049464259;6384.489240860627;6108.358622344767;6168.228971378578;6171.47542458896;6172.907406388818;6178;6187.319394866651;6190.566660333733;6195.4424101831055;6213.347598502092;6217.440430361289;5784.4504772046585;5812.82561523155;5833.970830145992;5864.912026319694;6012.759489620366;4757.452892917358;5556.416576259435;5822.359540450631;5844.707764611838;5856.765729370948;5705.885882282414;5711.942880571307;5728.54171666274;5732.914006289992;5897.3807750187325;6100.490342048192;6123.418275263843;6125.173101631401;6155.938440615537;6220.129181914445;5912.024515954819;5963.761449541969;6058.190514149204;6092.664903430354;6137.938620613915 02:01:22 INFO - PROCESS | 1659 | |1;ext.html;484.6897985289413;502.4673845723531;505.8887044850133;509.1802198460478;530.3264853635883;36992.630073699256;40643.390349144785;42815.57184428155;42823.78588107063;44813;43911.78044109775;44028.119437611225;44522;44623.55376446235;44663.77668111655;9603.32776705631;9755.902440975606;9826.754331141707;9832.213422926148;9853.507324633776;9619.80760384792;9754.46350450725;9754.853677194847;9758.756031099208;9775.804483910317;9681.854772178423;9719.514024298793;9766.267529935249;9769.364991275579;9770.218382529416;9703.223742100616;9775.755606109833;9779.559919803625;9783.119519243302;9783.853242201372;9407.20038796703;9515.762105947406;9521.90478095217;9525.476098814563;9558.996305387904;41000.58999410027;41013.283472400755;41290.79354603208;41317.96705082397;41347;2724.441489494649;3178.173674844534;3353.832308384571;3390.2541440882965;3587.4260118381194;28840.567391489145;28860.845566177326;29158.562621560694;29174;29183.56224656632;2686.5835795451735;2717.266338088705;2740.1642499037785;2743.2044707034934;2866.2261189478722;537267.313663437;537746;538106.3094684541;538272;538505;58247;58667.41332586705;58709;58733.70633146892;58767.11849322264;53155;57101.143482847794;58655;62226;63646.36353636497;15829;16158.59603509898;16208.837911620732;16340.428085017205;16438.424655137012;12656.936715316364;12761.808572871414;12767.297798620979;12776.977841772636;12789.232646041268;14012.649683757783;15436.922815386075;15787.28957196929;15787.526374208792;15961.122138282506;5668.659880407189;6639.734410623618;6650.907328092363;6673.232578253563;6676.732930682719;925.4724806860025;934.6401635370431;1002.9749256268651;1008.9142422893989;1070.7911957168278;29210.853945730134;29557.704422955925;29691.443620874023;29725.55411668827;30225.546616800766;1091.3942761767137;1112.1158678850334;1123.168855047259;1136.187625847523;1238.0714464151886;12755.489780408865;14982.475613353481;14994.475193368398;15388.230588470531;15409.768853467207;666930;673496;674823;674898;674930;57811.71094144587;58041.41958580445;59692.40307596955;59811;59953;1306.3141850528548;1351.4729255590328;1407.7958695989025;1439.7624391975207;1458.4895286649587;28869.855650721613;29440.55839162457;30024.399512010074;30789.384212316076;30803.84598076995;54666;56862.7156864213;57067;58005;58040.70979645075;567770;579916;582287;582852;582932;22675.433114171617;24885.50228995446;25526.23421297364;25701.871490643174;25926.22221333363;44719;45156.548434516546;46524.30213546732;46538.76730616325;46977.53022469868;33547.329053419286;36153.819230903675;37667;37792.43311350387;38554.421683674205;36779.63220367762;37458.43812342762;37937;38254.23491530098;38302.80848595851;36992.63007369892;37268.627313726516;37482.437763434114;37845.43231851469;38281.80859095798;31912.202194944384;33068.33863322768;33205.16987075342;33295.66704333023;33521.32957340888;20331;24445.453592762617;24586;24897.25308240756;25967.610485843106 02:01:22 INFO - PROCESS | 1659 | |2;jquery.html;388.0841214733229;388.39022734307133;388.60610806577904;389.5812002097746;405.7606012452652;14085.929570352135;20363;21160.788392116076;21495.892520537414;21529.67705484419;20594.279200227997;20859.582808343825;21116.366509004754;21244.8937755311;21314.04086816093;5665.433456654332;5918.05311150216;5965.313988891289;5968.820935371946;5970.611910225822;5779.711014449282;5961.821145365646;5967.403259674042;5996.490298324626;6001.87996240075;5662.915056274159;5785.045467497858;5841.64950102993;5957.1957785698805;5969.044952807551;5685.800996965087;5805.4194580542035;5850.414958504158;5918.201042859202;5934.436228558302;5116.795328186868;5119.48805119485;5131.230315452674;5133.948660513384;5136.383633963911;24731.010759569777;26300.65255766913;26472.735272647413;26883.865580671973;27071.917123315045;2256.334381357487;2305.204704377004;2440.73151953285;2862.155664079102;2934.9559756603667;14557.98094133411;14825.851741482662;14854.70290594182;15363.615909602238;16084.356625734956;1678.2280151130494;1721;1744.738289256609;1813.8095499972444;1824.562105094781;95292;115635;118548.40725796402;119823;121623.39188304091;10289.588416463257;10747.507060205464;11039.944800275947;11196.160287978271;11372.943135284271;12473.812892806614;12503.437345319482;12928.418221180069;14150;14175.78736318956;8693.565321733888;8748.562571871382;8808.075152109064;8899.065598112235;8940;8838.823223535494;8861.379703420698;8889.244414224733;8894.377393582387;8969.686060987835;6752.932470675329;6794.184697836291;6819.386255236953;6831.795046148623;6868.690908909011;1771.16755125091;1855.7123645834918;1875.3370214295733;1884.321644208084;1886.5189376709127;923.1138107416955;973.2797729679986;1016.913562347194;1032.963846265377;1094.9069329106956;20679.27622533234;21229;21786.455338616663;21814.236501722364;21912.671309930363;959.8416261316944;984.7931934293736;1027.306568066552;1035.803197392508;1039.29328056922;8122.796930076795;11417.657470275904;11420.714482138013;11421.143414243965;11440.370779607201;590962;595698;601603.6666551103;609416;611863;95963.52018239864;104143;106826.9317306817;109146;110480;953.3993584042148;953.6900507335143;984.9113579777854;1003.1122456625939;1005.0100650858898;18401.53996150107;18766.624667506847;18888.433346999613;18997.66899766926;19075.809241907402;43798.34302485402;47415;48667.269990949455;55030.72484637551;55529.16706249329;74850;74935.63304586927;76780;81083;82536.58731706304;11477.253978491372;12312.630621081382;12508.249505029728;12509.687257818627;12635.305058221886;13161.670958226121;13257.270850103338;13447.058705890886;13787.310634468036;13866.861331386557;9858.901410985798;11460.713482163013;11509.942450287695;11538.192326537062;11639.941800290944;11276.830847537129;11368.886311137114;11437.142214333791;11455.828162577402;11771.293722376684;11475.311481311148;11633.47649355764;11763.647090587296;11868.584599538975;11925.045996320447;12097.69755756078;12194.390280486117;12272;12424.875751242373;12461.065420093348;11556.306621602731;11612.593559225386;11795.646130616095;11860.525578976743;11881.940590296994 02:01:22 INFO - PROCESS | 1659 | |3;mootools.html;1412.9717405651882;1568.043493468984;1591.5145880506445;1824.7536582561359;1966.1054220329752;6943.375096241337;7533.4349923755735;8804.031556528776;8807.867881981776;8818.382713210078;8637.697680581181;8658.437201581894;8695.130486951302;8824.514651694155;8855.512946787923;2112.8415368847322;2222.7777222277805;2310.607196776548;2385.677933478984;2390.3665528634924;2391.270662447953;2400.819938504619;2413.987930060347;2417.758224177577;2419.213755529451;2269.4439862233776;2283.805876500491;2290.977090229093;2353.2822489140804;2378.6669866218745;2081.2195426714984;2286.8970896309706;2339.63735620979;2359.457324815285;2386.129062892049;2383.5709572276983;2413.2157048958984;2414.7826695597305;2416.9637455438183;2417.190241269177;8702.738917832476;8749.7812554686;8773.868391974125;8785.604647790864;8795.252403545706;5240.8689782755355;5359.035373632744;5389.407165211824;5404.000259951921;5413.675179489204;3269.395161895033;3272.6072871255365;3427.4687423449404;3427.5544179256785;3447.844846981867;1010.6260683547061;1011.0799172752833;1011.9797604047877;1022.8210063238978;1023.488255872064;11537.250078744855;11773.470193841298;11788;11798;11936.462859171359;4670.953290467051;4784.4497882743235;4804.8798780030775;4852.126617208865;4865.9026819463415;10214.336068155546;10222.744431389126;10236.897631023594;10252.282340236112;10271.486425678686;4638.443386793607;5408.48619381166;5425.593080518976;5429.6199266051;5438.7280635968045;4436;4579.679422440464;4779.832705855348;4817.301491283744;4927.1131196384995;3273.787203831743;3936.547297060875;4048.0487085535465;4048.412980117925;4070.104576993057;1276.8914642255513;1303.2376060004813;1307.5554311534124;1318.630783380655;1321.7026169111873;779.473855147773;885.1900511032334;900.3967341880956;927.166167895756;938.1837801113076;5785.566082543826;5841.4450627190345;5924.555658325557;5934.851628709231;5954.4045595440975;439.4177714528263;446.79894047678675;450.9053098849278;851.4082712514867;988.7280997725683;3888.6111388860886;3977.2244412339896;3993.0616305168264;4048.838046478108;4051.554329023805;9018.549072546346;10489.842652360221;10825.621103261348;10827.783444331228;10853.728656783485;10162.39025658463;10611.946940265248;10657.73355666099;10779.191560632984;10795.190360722978;7774.92225077742;7924.48490848093;8129.512229266263;8243.546604936786;8255.298299644477;4969;5998.610090344114;6018.699065046818;6082.574219804571;6175.876482470415;8197.508149511048;9889.851652225367;9929.106380425796;9973;10139.695809125738;9842.507874606385;10252.077313041862;10267.383956962607;10348;10366.688999330032;7405.2224516425;7585.800117965949;7644.541327520366;7651.885221721567;7663.540187588763;8472.61873215719;8493.980722313361;8552.187542183483;8553.82892342162;8570.185832345916;6074.787382441594;7247.673854676446;7319.194888562148;7377.378619098505;7476.177620461854;7149.285071493017;7359.374453171326;7362.263773622595;7376.077990251219;7412.851742965218;6772.695228714818;6860.48546359035;6865.313468653095;6869.175698916162;6883.380495755406;4790.401199850019;4834.564889159992;4843.927341089816;4902.852914412573;5085.974570127126;4867.561919427269;4905.2151655734915;4933.038057578738;4966;5167.6641018333685 02:01:22 INFO - PROCESS | 1659 | |4;prototype.html;308.6712651026657;312.91394866411736;317.66010368905273;320.42803595581876;322.90635715642463;4391.890202744933;4841.201201801703;4892.1194185046725;5160.354955630546;5197.946811009649;4600.217962946299;4682.648801339896;5164.9741751291285;5205.947940520594;5284.365876094874;3676.5036720042854;3790.886273411802;3804.5814960354337;4001.4998125234347;4032.2741906456827;3310.5034244863223;3935.8819235422984;4015.8594449194284;4040.838366465338;4055.472788537501;3342.548755917944;3958.3270843956598;3993.8602148924792;3994.4807175067344;3999.4200840878057;3804.619538046201;3838.808059597009;3942.980285098585;3996.999999999971;4030.5364883038533;2927.956080658792;3225.8064516129107;3488.476728490721;3499.6850283474355;3508.122969257686;4692.36653051837;4746.928796068062;5011.724355160465;5020.171671674169;5053.873653158664;4200.957990420118;4545.477270113913;4565.904182996091;4576.405067341258;4598.965232822622;5038.546530812245;5213;5314.388845282803;5453.537004002487;5504.752286147133;3330.833458327098;3339.382214290364;3731.421629647409;3769.1896242308035;3781.716371272166;4735.384400028009;5105.055564720538;5157.922631160535;5333.5199832015305;5809.273840769862;3761.269815884669;3766.773993560395;3780.3762379207124;3802.752821066622;3808.8666896658483;3460.0830779843363;3684.281565094781;3829.2532956073296;3831.8004466270977;3848.288066707668;4200.7479551227025;4202.684798640114;4243.130158317537;4466.240739074366;4498.887527811766;4830.734309613005;5403.13549832025;5455.536279416215;5461.869870750411;5476.67139971603;1129.220837622038;1134.8241022641503;1302.5831733845268;1309.3649579953715;1314.6910476038124;692.6155983429145;812.6546217857386;833.3000279765026;847.2544161138159;874.5015341255423;4702.553257440538;5097.898042039213;5113.846584602468;5116.850922612512;5132.741593698761;5123.641345105807;5172.818951336685;5299.894002119936;5520.861978450571;5684.8863022739315;4844.157001792673;5106.795728170831;5108.300363665437;5130.820421285237;5154.407243167084;2696.352875309951;2809.0870467097866;2917.2706823294175;2937.2803663102677;2944.661363943174;5024.698518088926;5137.434882162959;5595;5667.4615911488345;5842.883142337215;5477;5611.298587676541;5768.278965129359;5771;5771.365149833515;4729.847919196826;5489.560835133179;5773.422657734193;5823.79616713413;5825.592208545361;4036.434899114185;5532;5541.2796336476;5551.916721249266;5572.8823047241785;5124.9743751281;5338.54622357104;5611.354694210218;5636.239107720406;5677.120046392815;4975;5043.52086551777;5965.701714914324;5973.178180654768;5993.580449368676;3265.44487437141;3564.322778672044;3822.923541529098;3887.8444862206325;3899.53205615328;5148.918727067208;5616.725226283362;5641.997091032625;5645.548356131581;5660.622538533743;4769.9046019080115;4833.540813622701;5354.732263386893;5515.365732940763;5519.779208831602;4864.173090574682;5181.404138524118;5460.972695136499;5461.208124821799;5528.226048353315;5128.948565544129;5166.354205724285;5190.273361729436;5338.813141540028;5397.730113494388;5271.7578689106695;5301.602379821606;5394.2448057272795;5499.312585926759;5540.944590554042;5400.405955344988;5409.053415652324;5409.486098820606;5414.3232095988005;5426.674399535883 02:01:22 INFO - PROCESS | 1659 | |5;yui.html;619.3496828330253;642.0529718664969;676.4520738202057;701.6667083135515;736.6979538389262;14565.92717036416;14909.105453672788;15210.695786084272;15458.92270538647;15589.220538973063;13781.035327527077;13815.309234538283;14168.929155354235;14265.215413152298;14324.641883952907;718.1454069657118;744.8808190689491;889.3418870036159;905.529124855075;923.5243849417556;898.4788822482944;910.5674804467891;916.2349438219095;922.9400088994194;926.666400095965;970.606904203795;977.2768151567859;977.501474248135;978.5449765858874;979.6718099436697;756.1115689065354;929.665320484625;930.1489137439235;961.4327546747453;969.0358093696817;718.9065421495225;720.2473415280991;720.272524750001;720.4920531025667;721.2751185059035;14922.552323430315;14931.178785166812;14955.327010284454;14956.700865982619;14961.625959350995;12225.327606981613;12272.509099636003;12306.138570300083;12418.928419364149;12522.68693282666;10390.844137337946;10589.205809564237;10699.090577300938;11054.778904421866;11236.381998990053;4004.3392840181336;4009.959900400988;4022;4069.1861627674584;4170.83316667336;78302.60848695834;81291;82532.58733706293;82702;82784;9293;9464;9490.050994900455;9573.87439459478;9767.413955162712;21426.142954281964;21602;21611.891940540198;21665.891670541547;21682.674759878613;7296.8175795604475;7323.901414787718;7360.815979600553;7447.255274472618;7509.3617042552;3499.7725147865817;3959.5248570171766;4014.4781178447197;4041.6362527372676;4048.028473166478;3124.906252812419;3308.387948229585;3438.793672379665;3438.896833095011;3447.620761716209;579.852137704891;620.3238470067557;621.2482895696168;640.9262934762562;641.1824923222928;478.95109709735567;533.9572834173334;560.4535577811682;570.4780126184495;570.771691323474;7109.715611375487;7785.532868027936;7928.347978560144;7971.561564113914;7984.321332686671;516.9224616307508;534.1080395739125;553.9612227144141;568.2726110578466;575.8819442014377;3283.3433313337428;3586.8385922633543;3596.9280614387567;3702.185519185775;3796.1648437343742;68586.65706671435;90784;91109;93157.67105822409;94652;66965;68806.62386752338;73269.63365183247;75560.62219688867;77176.45647087027;71922.64038679878;73079.1730206749;73146;73377;73504.52990940258;7295.452841037049;7300.12398512182;7303.817404564928;7413.332800048021;7442;44322;44896;44917.42789002369;45358.77320613376;45701.54298456973;61749;62400.75198496095;62494.68752656208;62537.68731156315;62628;9683.51582420862;9941.850872236777;10000.899990999997;10043.497825108861;10197.286189966631;8986;10484.26610137283;10711.410872402093;10968.287061341307;11479.966802987772;9889.456079915386;10220.182385409298;10318;10392;10502.527386267484;9644.373115747454;9927.553260103168;9930.950345248228;9970.351927124713;10063.49682515886;9886.648312126512;9964.850527241952;10031.69904902854;10054.29619926598;10181.236407269336;8372.372072094495;8557.700480483154;8562.058173601023;8587;8616.870746938675;8205.589720513832;8597.16834178321;8666.176713212235;8691.913080869363;8890.06654301289 02:01:22 INFO - PROCESS | 1659 | __end_tp_report 02:01:22 INFO - PROCESS | 1659 | __start_cc_report 02:01:22 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,1163 02:01:22 INFO - PROCESS | 1659 | __end_cc_report 02:01:22 INFO - PROCESS | 1659 | __startTimestamp1464080482319__endTimestamp 02:01:22 INFO - PROCESS | 1659 | 02:01:22 INFO - PROCESS | 1659 | ------- Summary: start ------- 02:01:22 INFO - PROCESS | 1659 | Number of tests: 6 02:01:22 INFO - PROCESS | 1659 | 02:01:22 INFO - PROCESS | 1659 | [#0] dojo.html Cycles:165 Average:5706.94 Median:5839.91 stddev:620.78 (10.6%) stddev-sans-first:597.56 02:01:22 INFO - PROCESS | 1659 | Values: 3478.5 3484.8 3487.7 3519.4 3561.7 4650.4 5653.8 5676.5 5715.3 5752.3 5677.6 5686.1 5705.5 5808.4 5839.9 5129.5 5158.2 5202.9 5224.1 5350.2 5313.8 5324.5 5347.8 5378.6 5378.7 5130.2 5183.2 5200.3 5214.9 5228.2 5175.9 5195.7 5261.8 5297.6 5317.9 5145.7 5193.4 5250.0 5257.7 5270.4 5568.4 5668.6 5689.0 5730.2 5753.3 6194.5 6213.8 6237.3 6262.6 6264.7 5074.6 6116.5 6228.7 6270.5 6275.1 5833.2 5858.7 5908.9 5919.3 5923.9 6289.8 6293.0 6310.8 6312.6 6314.3 6049.0 6077.6 6129.5 6147.5 6163.3 6174.6 6178.2 6181.8 6185.6 6186.0 6073.9 6124.8 6171.3 6176.2 6187.8 5518.3 5934.3 6146.8 6172.6 6190.8 4943.6 4950.3 4980.3 4991.4 5040.3 4402.9 4418.6 4422.0 4430.2 4456.3 5593.5 5615.6 5633.5 5795.1 5806.9 6104.2 6172.1 6174.8 6181.7 6206.8 4854.4 5675.8 5685.5 5695.5 5723.9 5328.4 5369.8 5374.5 5396.1 5444.0 6439.3 6445.1 6451.1 6454.4 6468.3 6145.5 6172.6 6196.0 6202.6 6207.6 6155.3 6268.2 6321.7 6336.9 6384.5 6108.4 6168.2 6171.5 6172.9 6178.0 6187.3 6190.6 6195.4 6213.3 6217.4 5784.5 5812.8 5834.0 5864.9 6012.8 4757.5 5556.4 5822.4 5844.7 5856.8 5705.9 5711.9 5728.5 5732.9 5897.4 6100.5 6123.4 6125.2 6155.9 6220.1 5912.0 5963.8 6058.2 6092.7 6137.9 02:01:22 INFO - PROCESS | 1659 | 02:01:22 INFO - PROCESS | 1659 | [#1] ext.html Cycles:180 Average:72324.50 Median:41006.94 stddev:160413.45 (391.2%) stddev-sans-first:160772.76 02:01:22 INFO - PROCESS | 1659 | Values: 484.7 502.5 505.9 509.2 530.3 36992.6 40643.4 42815.6 42823.8 44813.0 43911.8 44028.1 44522.0 44623.6 44663.8 9603.3 9755.9 9826.8 9832.2 9853.5 9619.8 9754.5 9754.9 9758.8 9775.8 9681.9 9719.5 9766.3 9769.4 9770.2 9703.2 9775.8 9779.6 9783.1 9783.9 9407.2 9515.8 9521.9 9525.5 9559.0 41000.6 41013.3 41290.8 41318.0 41347.0 2724.4 3178.2 3353.8 3390.3 3587.4 28840.6 28860.8 29158.6 29174.0 29183.6 2686.6 2717.3 2740.2 2743.2 2866.2 537267.3 537746.0 538106.3 538272.0 538505.0 58247.0 58667.4 58709.0 58733.7 58767.1 53155.0 57101.1 58655.0 62226.0 63646.4 15829.0 16158.6 16208.8 16340.4 16438.4 12656.9 12761.8 12767.3 12777.0 12789.2 14012.6 15436.9 15787.3 15787.5 15961.1 5668.7 6639.7 6650.9 6673.2 6676.7 925.5 934.6 1003.0 1008.9 1070.8 29210.9 29557.7 29691.4 29725.6 30225.5 1091.4 1112.1 1123.2 1136.2 1238.1 12755.5 14982.5 14994.5 15388.2 15409.8 666930.0 673496.0 674823.0 674898.0 674930.0 57811.7 58041.4 59692.4 59811.0 59953.0 1306.3 1351.5 1407.8 1439.8 1458.5 28869.9 29440.6 30024.4 30789.4 30803.8 54666.0 56862.7 57067.0 58005.0 58040.7 567770.0 579916.0 582287.0 582852.0 582932.0 22675.4 24885.5 25526.2 25701.9 25926.2 44719.0 45156.5 46524.3 46538.8 46977.5 33547.3 36153.8 37667.0 37792.4 38554.4 36779.6 37458.4 37937.0 38254.2 38302.8 36992.6 37268.6 37482.4 37845.4 38281.8 31912.2 33068.3 33205.2 33295.7 33521.3 20331.0 24445.5 24586.0 24897.3 25967.6 02:01:22 INFO - PROCESS | 1659 | 02:01:22 INFO - PROCESS | 1659 | [#2] jquery.html Cycles:180 Average:34830.96 Median:21194.89 stddev:99713.46 (470.5%) stddev-sans-first:99959.64 02:01:22 INFO - PROCESS | 1659 | Values: 388.1 388.4 388.6 389.6 405.8 14085.9 20363.0 21160.8 21495.9 21529.7 20594.3 20859.6 21116.4 21244.9 21314.0 5665.4 5918.1 5965.3 5968.8 5970.6 5779.7 5961.8 5967.4 5996.5 6001.9 5662.9 5785.0 5841.6 5957.2 5969.0 5685.8 5805.4 5850.4 5918.2 5934.4 5116.8 5119.5 5131.2 5133.9 5136.4 24731.0 26300.7 26472.7 26883.9 27071.9 2256.3 2305.2 2440.7 2862.2 2935.0 14558.0 14825.9 14854.7 15363.6 16084.4 1678.2 1721.0 1744.7 1813.8 1824.6 95292.0 115635.0 118548.4 119823.0 121623.4 10289.6 10747.5 11039.9 11196.2 11372.9 12473.8 12503.4 12928.4 14150.0 14175.8 8693.6 8748.6 8808.1 8899.1 8940.0 8838.8 8861.4 8889.2 8894.4 8969.7 6752.9 6794.2 6819.4 6831.8 6868.7 1771.2 1855.7 1875.3 1884.3 1886.5 923.1 973.3 1016.9 1033.0 1094.9 20679.3 21229.0 21786.5 21814.2 21912.7 959.8 984.8 1027.3 1035.8 1039.3 8122.8 11417.7 11420.7 11421.1 11440.4 590962.0 595698.0 601603.7 609416.0 611863.0 95963.5 104143.0 106826.9 109146.0 110480.0 953.4 953.7 984.9 1003.1 1005.0 18401.5 18766.6 18888.4 18997.7 19075.8 43798.3 47415.0 48667.3 55030.7 55529.2 74850.0 74935.6 76780.0 81083.0 82536.6 11477.3 12312.6 12508.2 12509.7 12635.3 13161.7 13257.3 13447.1 13787.3 13866.9 9858.9 11460.7 11509.9 11538.2 11639.9 11276.8 11368.9 11437.1 11455.8 11771.3 11475.3 11633.5 11763.6 11868.6 11925.0 12097.7 12194.4 12272.0 12424.9 12461.1 11556.3 11612.6 11795.6 11860.5 11881.9 02:01:22 INFO - PROCESS | 1659 | 02:01:22 INFO - PROCESS | 1659 | [#3] mootools.html Cycles:180 Average:5671.79 Median:4825.93 stddev:3204.30 (66.4%) stddev-sans-first:3197.31 02:01:22 INFO - PROCESS | 1659 | Values: 1413.0 1568.0 1591.5 1824.8 1966.1 6943.4 7533.4 8804.0 8807.9 8818.4 8637.7 8658.4 8695.1 8824.5 8855.5 2112.8 2222.8 2310.6 2385.7 2390.4 2391.3 2400.8 2414.0 2417.8 2419.2 2269.4 2283.8 2291.0 2353.3 2378.7 2081.2 2286.9 2339.6 2359.5 2386.1 2383.6 2413.2 2414.8 2417.0 2417.2 8702.7 8749.8 8773.9 8785.6 8795.3 5240.9 5359.0 5389.4 5404.0 5413.7 3269.4 3272.6 3427.5 3427.6 3447.8 1010.6 1011.1 1012.0 1022.8 1023.5 11537.3 11773.5 11788.0 11798.0 11936.5 4671.0 4784.4 4804.9 4852.1 4865.9 10214.3 10222.7 10236.9 10252.3 10271.5 4638.4 5408.5 5425.6 5429.6 5438.7 4436.0 4579.7 4779.8 4817.3 4927.1 3273.8 3936.5 4048.0 4048.4 4070.1 1276.9 1303.2 1307.6 1318.6 1321.7 779.5 885.2 900.4 927.2 938.2 5785.6 5841.4 5924.6 5934.9 5954.4 439.4 446.8 450.9 851.4 988.7 3888.6 3977.2 3993.1 4048.8 4051.6 9018.5 10489.8 10825.6 10827.8 10853.7 10162.4 10611.9 10657.7 10779.2 10795.2 7774.9 7924.5 8129.5 8243.5 8255.3 4969.0 5998.6 6018.7 6082.6 6175.9 8197.5 9889.9 9929.1 9973.0 10139.7 9842.5 10252.1 10267.4 10348.0 10366.7 7405.2 7585.8 7644.5 7651.9 7663.5 8472.6 8494.0 8552.2 8553.8 8570.2 6074.8 7247.7 7319.2 7377.4 7476.2 7149.3 7359.4 7362.3 7376.1 7412.9 6772.7 6860.5 6865.3 6869.2 6883.4 4790.4 4834.6 4843.9 4902.9 5086.0 4867.6 4905.2 4933.0 4966.0 5167.7 02:01:22 INFO - PROCESS | 1659 | 02:01:22 INFO - PROCESS | 1659 | [#4] prototype.html Cycles:180 Average:4413.04 Median:5041.03 stddev:1357.71 (26.9%) stddev-sans-first:1326.11 02:01:22 INFO - PROCESS | 1659 | Values: 308.7 312.9 317.7 320.4 322.9 4391.9 4841.2 4892.1 5160.4 5197.9 4600.2 4682.6 5165.0 5205.9 5284.4 3676.5 3790.9 3804.6 4001.5 4032.3 3310.5 3935.9 4015.9 4040.8 4055.5 3342.5 3958.3 3993.9 3994.5 3999.4 3804.6 3838.8 3943.0 3997.0 4030.5 2928.0 3225.8 3488.5 3499.7 3508.1 4692.4 4746.9 5011.7 5020.2 5053.9 4201.0 4545.5 4565.9 4576.4 4599.0 5038.5 5213.0 5314.4 5453.5 5504.8 3330.8 3339.4 3731.4 3769.2 3781.7 4735.4 5105.1 5157.9 5333.5 5809.3 3761.3 3766.8 3780.4 3802.8 3808.9 3460.1 3684.3 3829.3 3831.8 3848.3 4200.7 4202.7 4243.1 4466.2 4498.9 4830.7 5403.1 5455.5 5461.9 5476.7 1129.2 1134.8 1302.6 1309.4 1314.7 692.6 812.7 833.3 847.3 874.5 4702.6 5097.9 5113.8 5116.9 5132.7 5123.6 5172.8 5299.9 5520.9 5684.9 4844.2 5106.8 5108.3 5130.8 5154.4 2696.4 2809.1 2917.3 2937.3 2944.7 5024.7 5137.4 5595.0 5667.5 5842.9 5477.0 5611.3 5768.3 5771.0 5771.4 4729.8 5489.6 5773.4 5823.8 5825.6 4036.4 5532.0 5541.3 5551.9 5572.9 5125.0 5338.5 5611.4 5636.2 5677.1 4975.0 5043.5 5965.7 5973.2 5993.6 3265.4 3564.3 3822.9 3887.8 3899.5 5148.9 5616.7 5642.0 5645.5 5660.6 4769.9 4833.5 5354.7 5515.4 5519.8 4864.2 5181.4 5461.0 5461.2 5528.2 5128.9 5166.4 5190.3 5338.8 5397.7 5271.8 5301.6 5394.2 5499.3 5540.9 5400.4 5409.1 5409.5 5414.3 5426.7 02:01:22 INFO - PROCESS | 1659 | 02:01:22 INFO - PROCESS | 1659 | [#5] yui.html Cycles:180 Average:17565.63 Median:641.62 stddev:24894.11 (3879.9%) stddev-sans-first:24931.42 02:01:22 INFO - PROCESS | 1659 | Values: 619.3 642.1 676.5 701.7 736.7 14565.9 14909.1 15210.7 15458.9 15589.2 13781.0 13815.3 14168.9 14265.2 14324.6 718.1 744.9 889.3 905.5 923.5 898.5 910.6 916.2 922.9 926.7 970.6 977.3 977.5 978.5 979.7 756.1 929.7 930.1 961.4 969.0 718.9 720.2 720.3 720.5 721.3 14922.6 14931.2 14955.3 14956.7 14961.6 12225.3 12272.5 12306.1 12418.9 12522.7 10390.8 10589.2 10699.1 11054.8 11236.4 4004.3 4010.0 4022.0 4069.2 4170.8 78302.6 81291.0 82532.6 82702.0 82784.0 9293.0 9464.0 9490.1 9573.9 9767.4 21426.1 21602.0 21611.9 21665.9 21682.7 7296.8 7323.9 7360.8 7447.3 7509.4 3499.8 3959.5 4014.5 4041.6 4048.0 3124.9 3308.4 3438.8 3438.9 3447.6 579.9 620.3 621.2 640.9 641.2 479.0 534.0 560.5 570.5 570.8 7109.7 7785.5 7928.3 7971.6 7984.3 516.9 534.1 554.0 568.3 575.9 3283.3 3586.8 3596.9 3702.2 3796.2 68586.7 90784.0 91109.0 93157.7 94652.0 66965.0 68806.6 73269.6 75560.6 77176.5 71922.6 73079.2 73146.0 73377.0 73504.5 7295.5 7300.1 7303.8 7413.3 7442.0 44322.0 44896.0 44917.4 45358.8 45701.5 61749.0 62400.8 62494.7 62537.7 62628.0 9683.5 9941.9 10000.9 10043.5 10197.3 8986.0 10484.3 10711.4 10968.3 11480.0 9889.5 10220.2 10318.0 10392.0 10502.5 9644.4 9927.6 9931.0 9970.4 10063.5 9886.6 9964.9 10031.7 10054.3 10181.2 8372.4 8557.7 8562.1 8587.0 8616.9 8205.6 8597.2 8666.2 8691.9 8890.1 02:01:22 INFO - PROCESS | 1659 | -------- Summary: end -------- 02:01:22 INFO - PROCESS | 1659 | 02:01:22 INFO - TEST-INFO | 1659: exit 0 02:01:22 INFO - TEST-OK | dromaeo_css | took 1113830ms 02:01:22 INFO - TEST-START | kraken 02:01:22 INFO - operating with platform_type : mac_ 02:01:22 INFO - Initialising browser for kraken test... 02:01:22 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/tmpnnymYk/profile http://localhost:49278/getInfo.html) 02:01:25 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 02:01:25 INFO - PROCESS | 1669 | colorDepth:24 02:01:25 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 02:01:25 INFO - PROCESS | 1669 | __metrics 02:01:25 INFO - PROCESS | 1669 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:01:25 ERROR - PROCESS | 1669 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 02:01:25 INFO - TEST-INFO | 1669: exit 0 02:01:25 INFO - Browser initialized. 02:01:25 INFO - Running cycle 1/1 for kraken test... 02:01:25 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/tmpnnymYk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:01:37 INFO - PROCESS | 1671 | RSS: Main: 239464448 02:01:37 INFO - PROCESS | 1671 | 02:01:40 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 02:01:41 INFO - PROCESS | 1671 | RSS: Main: 287068160 02:01:41 INFO - PROCESS | 1671 | 02:01:44 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 02:01:45 INFO - PROCESS | 1671 | RSS: Main: 296087552 02:01:45 INFO - PROCESS | 1671 | 02:01:48 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 02:01:49 INFO - PROCESS | 1671 | RSS: Main: 383852544 02:01:49 INFO - PROCESS | 1671 | 02:01:51 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 02:01:52 INFO - PROCESS | 1671 | RSS: Main: 283267072 02:01:52 INFO - PROCESS | 1671 | 02:01:54 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:01:54 INFO - PROCESS | 1671 | RSS: Main: 287449088 02:01:54 INFO - PROCESS | 1671 | 02:01:57 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 02:01:58 INFO - PROCESS | 1671 | RSS: Main: 324956160 02:01:58 INFO - PROCESS | 1671 | 02:02:01 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 02:02:02 INFO - PROCESS | 1671 | RSS: Main: 327426048 02:02:02 INFO - PROCESS | 1671 | 02:02:05 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 02:02:05 INFO - PROCESS | 1671 | RSS: Main: 325459968 02:02:05 INFO - PROCESS | 1671 | 02:02:07 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:02:08 INFO - PROCESS | 1671 | RSS: Main: 313679872 02:02:08 INFO - PROCESS | 1671 | 02:02:09 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:02:10 INFO - PROCESS | 1671 | RSS: Main: 320299008 02:02:10 INFO - PROCESS | 1671 | 02:02:12 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:02:13 INFO - PROCESS | 1671 | RSS: Main: 323248128 02:02:13 INFO - PROCESS | 1671 | 02:02:16 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:02:17 INFO - PROCESS | 1671 | RSS: Main: 328011776 02:02:17 INFO - PROCESS | 1671 | 02:02:20 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:02:20 INFO - PROCESS | 1671 | RSS: Main: 325861376 02:02:20 INFO - PROCESS | 1671 | 02:02:22 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 02:02:22 INFO - PROCESS | 1671 | RSS: Main: 372854784 02:02:22 INFO - PROCESS | 1671 | 02:02:22 INFO - PROCESS | 1671 | __start_tp_report 02:02:22 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 02:02:22 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 02:02:22 INFO - PROCESS | 1671 | |i|pagename|runs| 02:02:22 INFO - PROCESS | 1671 | |0;ai-astar;110;103;120;154;104;153;164;97;151;167 02:02:22 INFO - PROCESS | 1671 | |1;audio-beat-detection;154;124;118;199;119;127;163;138;152;117 02:02:22 INFO - PROCESS | 1671 | |2;audio-dft;182;184;182;181;181;184;179;181;181;184 02:02:22 INFO - PROCESS | 1671 | |3;audio-fft;78;57;60;117;58;57;75;80;97;56 02:02:22 INFO - PROCESS | 1671 | |4;audio-oscillator;95;76;139;78;84;75;100;79;76;104 02:02:22 INFO - PROCESS | 1671 | |5;imaging-gaussian-blur;87;85;86;85;87;85;89;85;88;84 02:02:22 INFO - PROCESS | 1671 | |6;imaging-darkroom;93;93;95;93;93;92;94;94;93;93 02:02:22 INFO - PROCESS | 1671 | |7;imaging-desaturate;83;86;85;86;84;83;83;85;87;85 02:02:22 INFO - PROCESS | 1671 | |8;json-parse-financial;56;54;56;57;57;57;56;118;55;54 02:02:22 INFO - PROCESS | 1671 | |9;json-stringify-tinderbox;65;72;72;77;70;68;69;69;70;68 02:02:22 INFO - PROCESS | 1671 | |10;stanford-crypto-aes;83;82;85;82;85;85;85;84;84;86 02:02:22 INFO - PROCESS | 1671 | |11;stanford-crypto-ccm;136;137;136;134;136;133;136;133;217;139 02:02:22 INFO - PROCESS | 1671 | |12;stanford-crypto-pbkdf2;169;230;184;201;218;168;164;164;166;164 02:02:22 INFO - PROCESS | 1671 | |13;stanford-crypto-sha256-iterative;66;65;67;65;66;63;65;65;64;64 02:02:22 INFO - PROCESS | 1671 | __end_tp_report 02:02:22 INFO - PROCESS | 1671 | __start_cc_report 02:02:22 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,2221 02:02:22 INFO - PROCESS | 1671 | __end_cc_report 02:02:22 INFO - PROCESS | 1671 | __startTimestamp1464080542850__endTimestamp 02:02:22 INFO - PROCESS | 1671 | 02:02:22 INFO - PROCESS | 1671 | ------- Summary: start ------- 02:02:22 INFO - PROCESS | 1671 | Number of tests: 14 02:02:22 INFO - PROCESS | 1671 | 02:02:22 INFO - PROCESS | 1671 | [#0] ai-astar Cycles:10 Average:132.30 Median:153.50 stddev:27.91 (18.2%) stddev-sans-first:28.42 02:02:22 INFO - PROCESS | 1671 | Values: 110.0 103.0 120.0 154.0 104.0 153.0 164.0 97.0 151.0 167.0 02:02:22 INFO - PROCESS | 1671 | 02:02:22 INFO - PROCESS | 1671 | [#1] audio-beat-detection Cycles:10 Average:141.10 Median:145.00 stddev:26.29 (18.1%) stddev-sans-first:27.47 02:02:22 INFO - PROCESS | 1671 | Values: 154.0 124.0 118.0 199.0 119.0 127.0 163.0 138.0 152.0 117.0 02:02:22 INFO - PROCESS | 1671 | 02:02:22 INFO - PROCESS | 1671 | [#2] audio-dft Cycles:10 Average:181.90 Median:182.00 stddev:1.66 (0.9%) stddev-sans-first:1.76 02:02:22 INFO - PROCESS | 1671 | Values: 182.0 184.0 182.0 181.0 181.0 184.0 179.0 181.0 181.0 184.0 02:02:22 INFO - PROCESS | 1671 | 02:02:22 INFO - PROCESS | 1671 | [#3] audio-fft Cycles:10 Average:73.50 Median:67.50 stddev:20.50 (30.4%) stddev-sans-first:21.68 02:02:22 INFO - PROCESS | 1671 | Values: 78.0 57.0 60.0 117.0 58.0 57.0 75.0 80.0 97.0 56.0 02:02:22 INFO - PROCESS | 1671 | 02:02:22 INFO - PROCESS | 1671 | [#4] audio-oscillator Cycles:10 Average:90.60 Median:77.00 stddev:20.10 (26.1%) stddev-sans-first:21.26 02:02:22 INFO - PROCESS | 1671 | Values: 95.0 76.0 139.0 78.0 84.0 75.0 100.0 79.0 76.0 104.0 02:02:22 INFO - PROCESS | 1671 | 02:02:22 INFO - PROCESS | 1671 | [#5] imaging-gaussian-blur Cycles:10 Average:86.10 Median:86.50 stddev:1.60 (1.8%) stddev-sans-first:1.66 02:02:22 INFO - PROCESS | 1671 | Values: 87.0 85.0 86.0 85.0 87.0 85.0 89.0 85.0 88.0 84.0 02:02:22 INFO - PROCESS | 1671 | 02:02:22 INFO - PROCESS | 1671 | [#6] imaging-darkroom Cycles:10 Average:93.30 Median:93.00 stddev:0.82 (0.9%) stddev-sans-first:0.87 02:02:22 INFO - PROCESS | 1671 | Values: 93.0 93.0 95.0 93.0 93.0 92.0 94.0 94.0 93.0 93.0 02:02:22 INFO - PROCESS | 1671 | 02:02:22 INFO - PROCESS | 1671 | [#7] imaging-desaturate Cycles:10 Average:84.70 Median:85.00 stddev:1.42 (1.7%) stddev-sans-first:1.36 02:02:22 INFO - PROCESS | 1671 | Values: 83.0 86.0 85.0 86.0 84.0 83.0 83.0 85.0 87.0 85.0 02:02:22 INFO - PROCESS | 1671 | 02:02:22 INFO - PROCESS | 1671 | [#8] json-parse-financial Cycles:10 Average:62.00 Median:56.00 stddev:19.71 (35.2%) stddev-sans-first:20.78 02:02:22 INFO - PROCESS | 1671 | Values: 56.0 54.0 56.0 57.0 57.0 57.0 56.0 118.0 55.0 54.0 02:02:22 INFO - PROCESS | 1671 | 02:02:22 INFO - PROCESS | 1671 | [#9] json-stringify-tinderbox Cycles:10 Average:70.00 Median:70.00 stddev:3.20 (4.6%) stddev-sans-first:2.83 02:02:22 INFO - PROCESS | 1671 | Values: 65.0 72.0 72.0 77.0 70.0 68.0 69.0 69.0 70.0 68.0 02:02:22 INFO - PROCESS | 1671 | 02:02:22 INFO - PROCESS | 1671 | [#10] stanford-crypto-aes Cycles:10 Average:84.10 Median:85.00 stddev:1.37 (1.6%) stddev-sans-first:1.39 02:02:22 INFO - PROCESS | 1671 | Values: 83.0 82.0 85.0 82.0 85.0 85.0 85.0 84.0 84.0 86.0 02:02:22 INFO - PROCESS | 1671 | 02:02:22 INFO - PROCESS | 1671 | [#11] stanford-crypto-ccm Cycles:10 Average:143.70 Median:136.00 stddev:25.82 (19.0%) stddev-sans-first:27.24 02:02:22 INFO - PROCESS | 1671 | Values: 136.0 137.0 136.0 134.0 136.0 133.0 136.0 133.0 217.0 139.0 02:02:22 INFO - PROCESS | 1671 | 02:02:22 INFO - PROCESS | 1671 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.80 Median:176.50 stddev:24.84 (14.1%) stddev-sans-first:25.84 02:02:22 INFO - PROCESS | 1671 | Values: 169.0 230.0 184.0 201.0 218.0 168.0 164.0 164.0 166.0 164.0 02:02:22 INFO - PROCESS | 1671 | 02:02:22 INFO - PROCESS | 1671 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.00 Median:65.00 stddev:1.15 (1.8%) stddev-sans-first:1.17 02:02:22 INFO - PROCESS | 1671 | Values: 66.0 65.0 67.0 65.0 66.0 63.0 65.0 65.0 64.0 64.0 02:02:22 INFO - PROCESS | 1671 | -------- Summary: end -------- 02:02:22 INFO - PROCESS | 1671 | 02:02:23 INFO - TEST-INFO | 1671: exit 0 02:02:23 INFO - TEST-OK | kraken | took 60873ms 02:02:23 INFO - SUITE-END | took 1174s 02:02:24 INFO - Completed test suite (00:19:34) 02:02:24 INFO - javascript benchmark 02:02:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3478.4608385700226, 3484.843182056807, 3487.7384196185285, 3519.3665140274748, 3561.7328700347466, 4650.372199753032, 5653.773849046043, 5676.545876329894, 5715.3141623005195, 5752.309722833261, 5677.602567820254, 5686.1186516089965, 5705.4579814917515, 5808.390119037504, 5839.912401313973, 5129.461406552315, 5158.174692049273, 5202.8699282518055, 5224.085784987612, 5350.170723537858, 5313.760880760356, 5324.494173053554, 5347.812826551071, 5378.596605254622, 5378.731063446831, 5130.153524668444, 5183.196604526304, 5200.323957885488, 5214.895702085956, 5228.189630607224, 5175.896482070375, 5195.714235717034, 5261.789528418859, 5297.576193904517, 5317.9468205317835, 5145.691258524463, 5193.402758682762, 5249.973750131263, 5257.658252213594, 5270.446603106695, 5568.415316391802, 5668.603197776158, 5689.0328644130595, 5730.19777231188, 5753.252077229975, 6194.473469755077, 6213.844653883644, 6237.345078766755, 6262.624242545417, 6264.749410023594, 5074.644774865761, 6116.548817443993, 6228.688565571703, 6270.529710271748, 6275.058741188813, 5833.212516310286, 5858.707064646751, 5908.911366329508, 5919.260092488439, 5923.881522369528, 6289.842753931097, 6293.0, 6310.842228944313, 6312.621242725451, 6314.273858506331, 6048.969755151196, 6077.632532680112, 6129.540284478682, 6147.477464415575, 6163.322034576193, 6174.598651087665, 6178.227721534809, 6181.7527298908435, 6185.567010309325, 6185.969070154711, 6073.878522429527, 6124.755009799558, 6171.259448866164, 6176.166217560662, 6187.81436556904, 5518.33779946409, 5934.276613378327, 6146.815595532052, 6172.598781079305, 6190.7523699052435, 4943.604511639061, 4950.331705219821, 4980.327655766498, 4991.425986011655, 5040.344755181803, 4402.889927751768, 4418.60232579063, 4421.955780442155, 4430.202563538594, 4456.286994080931, 5593.496585307342, 5615.550755939515, 5633.464820842013, 5795.1017592273265, 5806.854828629233, 6104.246950856165, 6172.074188871616, 6174.783882564088, 6181.721822517998, 6206.813795586139, 4854.368932038883, 5675.829725108253, 5685.516731077904, 5695.45893140151, 5723.914141287884, 5328.387235467893, 5369.785208591691, 5374.48942350476, 5396.109641909119, 5444.047291723932, 6439.323870993572, 6445.129907462432, 6451.096846441408, 6454.419102280817, 6468.288488266287, 6145.477634401126, 6172.629642221481, 6196.0, 6202.596831206138, 6207.6275423474735, 6155.322914479314, 6268.153799237045, 6321.747130114928, 6336.9049464259, 6384.489240860627, 6108.358622344767, 6168.228971378578, 6171.47542458896, 6172.907406388818, 6178.0, 6187.319394866651, 6190.566660333733, 6195.4424101831055, 6213.347598502092, 6217.440430361289, 5784.4504772046585, 5812.82561523155, 5833.970830145992, 5864.912026319694, 6012.759489620366, 4757.452892917358, 5556.416576259435, 5822.359540450631, 5844.707764611838, 5856.765729370948, 5705.885882282414, 5711.942880571307, 5728.54171666274, 5732.914006289992, 5897.3807750187325, 6100.490342048192, 6123.418275263843, 6125.173101631401, 6155.938440615537, 6220.129181914445, 5912.024515954819, 5963.761449541969, 6058.190514149204, 6092.664903430354, 6137.938620613915], "value": 5670.394771618595, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [484.6897985289413, 502.4673845723531, 505.8887044850133, 509.1802198460478, 530.3264853635883, 36992.630073699256, 40643.390349144785, 42815.57184428155, 42823.78588107063, 44813.0, 43911.78044109775, 44028.119437611225, 44522.0, 44623.55376446235, 44663.77668111655, 9603.32776705631, 9755.902440975606, 9826.754331141707, 9832.213422926148, 9853.507324633776, 9619.80760384792, 9754.46350450725, 9754.853677194847, 9758.756031099208, 9775.804483910317, 9681.854772178423, 9719.514024298793, 9766.267529935249, 9769.364991275579, 9770.218382529416, 9703.223742100616, 9775.755606109833, 9779.559919803625, 9783.119519243302, 9783.853242201372, 9407.20038796703, 9515.762105947406, 9521.90478095217, 9525.476098814563, 9558.996305387904, 41000.58999410027, 41013.283472400755, 41290.79354603208, 41317.96705082397, 41347.0, 2724.441489494649, 3178.173674844534, 3353.832308384571, 3390.2541440882965, 3587.4260118381194, 28840.567391489145, 28860.845566177326, 29158.562621560694, 29174.0, 29183.56224656632, 2686.5835795451735, 2717.266338088705, 2740.1642499037785, 2743.2044707034934, 2866.2261189478722, 537267.313663437, 537746.0, 538106.3094684541, 538272.0, 538505.0, 58247.0, 58667.41332586705, 58709.0, 58733.70633146892, 58767.11849322264, 53155.0, 57101.143482847794, 58655.0, 62226.0, 63646.36353636497, 15829.0, 16158.59603509898, 16208.837911620732, 16340.428085017205, 16438.424655137012, 12656.936715316364, 12761.808572871414, 12767.297798620979, 12776.977841772636, 12789.232646041268, 14012.649683757783, 15436.922815386075, 15787.28957196929, 15787.526374208792, 15961.122138282506, 5668.659880407189, 6639.734410623618, 6650.907328092363, 6673.232578253563, 6676.732930682719, 925.4724806860025, 934.6401635370431, 1002.9749256268651, 1008.9142422893989, 1070.7911957168278, 29210.853945730134, 29557.704422955925, 29691.443620874023, 29725.55411668827, 30225.546616800766, 1091.3942761767137, 1112.1158678850334, 1123.168855047259, 1136.187625847523, 1238.0714464151886, 12755.489780408865, 14982.475613353481, 14994.475193368398, 15388.230588470531, 15409.768853467207, 666930.0, 673496.0, 674823.0, 674898.0, 674930.0, 57811.71094144587, 58041.41958580445, 59692.40307596955, 59811.0, 59953.0, 1306.3141850528548, 1351.4729255590328, 1407.7958695989025, 1439.7624391975207, 1458.4895286649587, 28869.855650721613, 29440.55839162457, 30024.399512010074, 30789.384212316076, 30803.84598076995, 54666.0, 56862.7156864213, 57067.0, 58005.0, 58040.70979645075, 567770.0, 579916.0, 582287.0, 582852.0, 582932.0, 22675.433114171617, 24885.50228995446, 25526.23421297364, 25701.871490643174, 25926.22221333363, 44719.0, 45156.548434516546, 46524.30213546732, 46538.76730616325, 46977.53022469868, 33547.329053419286, 36153.819230903675, 37667.0, 37792.43311350387, 38554.421683674205, 36779.63220367762, 37458.43812342762, 37937.0, 38254.23491530098, 38302.80848595851, 36992.63007369892, 37268.627313726516, 37482.437763434114, 37845.43231851469, 38281.80859095798, 31912.202194944384, 33068.33863322768, 33205.16987075342, 33295.66704333023, 33521.32957340888, 20331.0, 24445.453592762617, 24586.0, 24897.25308240756, 25967.610485843106], "value": 19730.026881049267, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.0841214733229, 388.39022734307133, 388.60610806577904, 389.5812002097746, 405.7606012452652, 14085.929570352135, 20363.0, 21160.788392116076, 21495.892520537414, 21529.67705484419, 20594.279200227997, 20859.582808343825, 21116.366509004754, 21244.8937755311, 21314.04086816093, 5665.433456654332, 5918.05311150216, 5965.313988891289, 5968.820935371946, 5970.611910225822, 5779.711014449282, 5961.821145365646, 5967.403259674042, 5996.490298324626, 6001.87996240075, 5662.915056274159, 5785.045467497858, 5841.64950102993, 5957.1957785698805, 5969.044952807551, 5685.800996965087, 5805.4194580542035, 5850.414958504158, 5918.201042859202, 5934.436228558302, 5116.795328186868, 5119.48805119485, 5131.230315452674, 5133.948660513384, 5136.383633963911, 24731.010759569777, 26300.65255766913, 26472.735272647413, 26883.865580671973, 27071.917123315045, 2256.334381357487, 2305.204704377004, 2440.73151953285, 2862.155664079102, 2934.9559756603667, 14557.98094133411, 14825.851741482662, 14854.70290594182, 15363.615909602238, 16084.356625734956, 1678.2280151130494, 1721.0, 1744.738289256609, 1813.8095499972444, 1824.562105094781, 95292.0, 115635.0, 118548.40725796402, 119823.0, 121623.39188304091, 10289.588416463257, 10747.507060205464, 11039.944800275947, 11196.160287978271, 11372.943135284271, 12473.812892806614, 12503.437345319482, 12928.418221180069, 14150.0, 14175.78736318956, 8693.565321733888, 8748.562571871382, 8808.075152109064, 8899.065598112235, 8940.0, 8838.823223535494, 8861.379703420698, 8889.244414224733, 8894.377393582387, 8969.686060987835, 6752.932470675329, 6794.184697836291, 6819.386255236953, 6831.795046148623, 6868.690908909011, 1771.16755125091, 1855.7123645834918, 1875.3370214295733, 1884.321644208084, 1886.5189376709127, 923.1138107416955, 973.2797729679986, 1016.913562347194, 1032.963846265377, 1094.9069329106956, 20679.27622533234, 21229.0, 21786.455338616663, 21814.236501722364, 21912.671309930363, 959.8416261316944, 984.7931934293736, 1027.306568066552, 1035.803197392508, 1039.29328056922, 8122.796930076795, 11417.657470275904, 11420.714482138013, 11421.143414243965, 11440.370779607201, 590962.0, 595698.0, 601603.6666551103, 609416.0, 611863.0, 95963.52018239864, 104143.0, 106826.9317306817, 109146.0, 110480.0, 953.3993584042148, 953.6900507335143, 984.9113579777854, 1003.1122456625939, 1005.0100650858898, 18401.53996150107, 18766.624667506847, 18888.433346999613, 18997.66899766926, 19075.809241907402, 43798.34302485402, 47415.0, 48667.269990949455, 55030.72484637551, 55529.16706249329, 74850.0, 74935.63304586927, 76780.0, 81083.0, 82536.58731706304, 11477.253978491372, 12312.630621081382, 12508.249505029728, 12509.687257818627, 12635.305058221886, 13161.670958226121, 13257.270850103338, 13447.058705890886, 13787.310634468036, 13866.861331386557, 9858.901410985798, 11460.713482163013, 11509.942450287695, 11538.192326537062, 11639.941800290944, 11276.830847537129, 11368.886311137114, 11437.142214333791, 11455.828162577402, 11771.293722376684, 11475.311481311148, 11633.47649355764, 11763.647090587296, 11868.584599538975, 11925.045996320447, 12097.69755756078, 12194.390280486117, 12272.0, 12424.875751242373, 12461.065420093348, 11556.306621602731, 11612.593559225386, 11795.646130616095, 11860.525578976743, 11881.940590296994], "value": 10036.61159728829, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1412.9717405651882, 1568.043493468984, 1591.5145880506445, 1824.7536582561359, 1966.1054220329752, 6943.375096241337, 7533.4349923755735, 8804.031556528776, 8807.867881981776, 8818.382713210078, 8637.697680581181, 8658.437201581894, 8695.130486951302, 8824.514651694155, 8855.512946787923, 2112.8415368847322, 2222.7777222277805, 2310.607196776548, 2385.677933478984, 2390.3665528634924, 2391.270662447953, 2400.819938504619, 2413.987930060347, 2417.758224177577, 2419.213755529451, 2269.4439862233776, 2283.805876500491, 2290.977090229093, 2353.2822489140804, 2378.6669866218745, 2081.2195426714984, 2286.8970896309706, 2339.63735620979, 2359.457324815285, 2386.129062892049, 2383.5709572276983, 2413.2157048958984, 2414.7826695597305, 2416.9637455438183, 2417.190241269177, 8702.738917832476, 8749.7812554686, 8773.868391974125, 8785.604647790864, 8795.252403545706, 5240.8689782755355, 5359.035373632744, 5389.407165211824, 5404.000259951921, 5413.675179489204, 3269.395161895033, 3272.6072871255365, 3427.4687423449404, 3427.5544179256785, 3447.844846981867, 1010.6260683547061, 1011.0799172752833, 1011.9797604047877, 1022.8210063238978, 1023.488255872064, 11537.250078744855, 11773.470193841298, 11788.0, 11798.0, 11936.462859171359, 4670.953290467051, 4784.4497882743235, 4804.8798780030775, 4852.126617208865, 4865.9026819463415, 10214.336068155546, 10222.744431389126, 10236.897631023594, 10252.282340236112, 10271.486425678686, 4638.443386793607, 5408.48619381166, 5425.593080518976, 5429.6199266051, 5438.7280635968045, 4436.0, 4579.679422440464, 4779.832705855348, 4817.301491283744, 4927.1131196384995, 3273.787203831743, 3936.547297060875, 4048.0487085535465, 4048.412980117925, 4070.104576993057, 1276.8914642255513, 1303.2376060004813, 1307.5554311534124, 1318.630783380655, 1321.7026169111873, 779.473855147773, 885.1900511032334, 900.3967341880956, 927.166167895756, 938.1837801113076, 5785.566082543826, 5841.4450627190345, 5924.555658325557, 5934.851628709231, 5954.4045595440975, 439.4177714528263, 446.79894047678675, 450.9053098849278, 851.4082712514867, 988.7280997725683, 3888.6111388860886, 3977.2244412339896, 3993.0616305168264, 4048.838046478108, 4051.554329023805, 9018.549072546346, 10489.842652360221, 10825.621103261348, 10827.783444331228, 10853.728656783485, 10162.39025658463, 10611.946940265248, 10657.73355666099, 10779.191560632984, 10795.190360722978, 7774.92225077742, 7924.48490848093, 8129.512229266263, 8243.546604936786, 8255.298299644477, 4969.0, 5998.610090344114, 6018.699065046818, 6082.574219804571, 6175.876482470415, 8197.508149511048, 9889.851652225367, 9929.106380425796, 9973.0, 10139.695809125738, 9842.507874606385, 10252.077313041862, 10267.383956962607, 10348.0, 10366.688999330032, 7405.2224516425, 7585.800117965949, 7644.541327520366, 7651.885221721567, 7663.540187588763, 8472.61873215719, 8493.980722313361, 8552.187542183483, 8553.82892342162, 8570.185832345916, 6074.787382441594, 7247.673854676446, 7319.194888562148, 7377.378619098505, 7476.177620461854, 7149.285071493017, 7359.374453171326, 7362.263773622595, 7376.077990251219, 7412.851742965218, 6772.695228714818, 6860.48546359035, 6865.313468653095, 6869.175698916162, 6883.380495755406, 4790.401199850019, 4834.564889159992, 4843.927341089816, 4902.852914412573, 5085.974570127126, 4867.561919427269, 4905.2151655734915, 4933.038057578738, 4966.0, 5167.6641018333685], "value": 4516.253056786346, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [308.6712651026657, 312.91394866411736, 317.66010368905273, 320.42803595581876, 322.90635715642463, 4391.890202744933, 4841.201201801703, 4892.1194185046725, 5160.354955630546, 5197.946811009649, 4600.217962946299, 4682.648801339896, 5164.9741751291285, 5205.947940520594, 5284.365876094874, 3676.5036720042854, 3790.886273411802, 3804.5814960354337, 4001.4998125234347, 4032.2741906456827, 3310.5034244863223, 3935.8819235422984, 4015.8594449194284, 4040.838366465338, 4055.472788537501, 3342.548755917944, 3958.3270843956598, 3993.8602148924792, 3994.4807175067344, 3999.4200840878057, 3804.619538046201, 3838.808059597009, 3942.980285098585, 3996.999999999971, 4030.5364883038533, 2927.956080658792, 3225.8064516129107, 3488.476728490721, 3499.6850283474355, 3508.122969257686, 4692.36653051837, 4746.928796068062, 5011.724355160465, 5020.171671674169, 5053.873653158664, 4200.957990420118, 4545.477270113913, 4565.904182996091, 4576.405067341258, 4598.965232822622, 5038.546530812245, 5213.0, 5314.388845282803, 5453.537004002487, 5504.752286147133, 3330.833458327098, 3339.382214290364, 3731.421629647409, 3769.1896242308035, 3781.716371272166, 4735.384400028009, 5105.055564720538, 5157.922631160535, 5333.5199832015305, 5809.273840769862, 3761.269815884669, 3766.773993560395, 3780.3762379207124, 3802.752821066622, 3808.8666896658483, 3460.0830779843363, 3684.281565094781, 3829.2532956073296, 3831.8004466270977, 3848.288066707668, 4200.7479551227025, 4202.684798640114, 4243.130158317537, 4466.240739074366, 4498.887527811766, 4830.734309613005, 5403.13549832025, 5455.536279416215, 5461.869870750411, 5476.67139971603, 1129.220837622038, 1134.8241022641503, 1302.5831733845268, 1309.3649579953715, 1314.6910476038124, 692.6155983429145, 812.6546217857386, 833.3000279765026, 847.2544161138159, 874.5015341255423, 4702.553257440538, 5097.898042039213, 5113.846584602468, 5116.850922612512, 5132.741593698761, 5123.641345105807, 5172.818951336685, 5299.894002119936, 5520.861978450571, 5684.8863022739315, 4844.157001792673, 5106.795728170831, 5108.300363665437, 5130.820421285237, 5154.407243167084, 2696.352875309951, 2809.0870467097866, 2917.2706823294175, 2937.2803663102677, 2944.661363943174, 5024.698518088926, 5137.434882162959, 5595.0, 5667.4615911488345, 5842.883142337215, 5477.0, 5611.298587676541, 5768.278965129359, 5771.0, 5771.365149833515, 4729.847919196826, 5489.560835133179, 5773.422657734193, 5823.79616713413, 5825.592208545361, 4036.434899114185, 5532.0, 5541.2796336476, 5551.916721249266, 5572.8823047241785, 5124.9743751281, 5338.54622357104, 5611.354694210218, 5636.239107720406, 5677.120046392815, 4975.0, 5043.52086551777, 5965.701714914324, 5973.178180654768, 5993.580449368676, 3265.44487437141, 3564.322778672044, 3822.923541529098, 3887.8444862206325, 3899.53205615328, 5148.918727067208, 5616.725226283362, 5641.997091032625, 5645.548356131581, 5660.622538533743, 4769.9046019080115, 4833.540813622701, 5354.732263386893, 5515.365732940763, 5519.779208831602, 4864.173090574682, 5181.404138524118, 5460.972695136499, 5461.208124821799, 5528.226048353315, 5128.948565544129, 5166.354205724285, 5190.273361729436, 5338.813141540028, 5397.730113494388, 5271.7578689106695, 5301.602379821606, 5394.2448057272795, 5499.312585926759, 5540.944590554042, 5400.405955344988, 5409.053415652324, 5409.486098820606, 5414.3232095988005, 5426.674399535883], "value": 3978.72526653803, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [619.3496828330253, 642.0529718664969, 676.4520738202057, 701.6667083135515, 736.6979538389262, 14565.92717036416, 14909.105453672788, 15210.695786084272, 15458.92270538647, 15589.220538973063, 13781.035327527077, 13815.309234538283, 14168.929155354235, 14265.215413152298, 14324.641883952907, 718.1454069657118, 744.8808190689491, 889.3418870036159, 905.529124855075, 923.5243849417556, 898.4788822482944, 910.5674804467891, 916.2349438219095, 922.9400088994194, 926.666400095965, 970.606904203795, 977.2768151567859, 977.501474248135, 978.5449765858874, 979.6718099436697, 756.1115689065354, 929.665320484625, 930.1489137439235, 961.4327546747453, 969.0358093696817, 718.9065421495225, 720.2473415280991, 720.272524750001, 720.4920531025667, 721.2751185059035, 14922.552323430315, 14931.178785166812, 14955.327010284454, 14956.700865982619, 14961.625959350995, 12225.327606981613, 12272.509099636003, 12306.138570300083, 12418.928419364149, 12522.68693282666, 10390.844137337946, 10589.205809564237, 10699.090577300938, 11054.778904421866, 11236.381998990053, 4004.3392840181336, 4009.959900400988, 4022.0, 4069.1861627674584, 4170.83316667336, 78302.60848695834, 81291.0, 82532.58733706293, 82702.0, 82784.0, 9293.0, 9464.0, 9490.050994900455, 9573.87439459478, 9767.413955162712, 21426.142954281964, 21602.0, 21611.891940540198, 21665.891670541547, 21682.674759878613, 7296.8175795604475, 7323.901414787718, 7360.815979600553, 7447.255274472618, 7509.3617042552, 3499.7725147865817, 3959.5248570171766, 4014.4781178447197, 4041.6362527372676, 4048.028473166478, 3124.906252812419, 3308.387948229585, 3438.793672379665, 3438.896833095011, 3447.620761716209, 579.852137704891, 620.3238470067557, 621.2482895696168, 640.9262934762562, 641.1824923222928, 478.95109709735567, 533.9572834173334, 560.4535577811682, 570.4780126184495, 570.771691323474, 7109.715611375487, 7785.532868027936, 7928.347978560144, 7971.561564113914, 7984.321332686671, 516.9224616307508, 534.1080395739125, 553.9612227144141, 568.2726110578466, 575.8819442014377, 3283.3433313337428, 3586.8385922633543, 3596.9280614387567, 3702.185519185775, 3796.1648437343742, 68586.65706671435, 90784.0, 91109.0, 93157.67105822409, 94652.0, 66965.0, 68806.62386752338, 73269.63365183247, 75560.62219688867, 77176.45647087027, 71922.64038679878, 73079.1730206749, 73146.0, 73377.0, 73504.52990940258, 7295.452841037049, 7300.12398512182, 7303.817404564928, 7413.332800048021, 7442.0, 44322.0, 44896.0, 44917.42789002369, 45358.77320613376, 45701.54298456973, 61749.0, 62400.75198496095, 62494.68752656208, 62537.68731156315, 62628.0, 9683.51582420862, 9941.850872236777, 10000.899990999997, 10043.497825108861, 10197.286189966631, 8986.0, 10484.26610137283, 10711.410872402093, 10968.287061341307, 11479.966802987772, 9889.456079915386, 10220.182385409298, 10318.0, 10392.0, 10502.527386267484, 9644.373115747454, 9927.553260103168, 9930.950345248228, 9970.351927124713, 10063.49682515886, 9886.648312126512, 9964.850527241952, 10031.69904902854, 10054.29619926598, 10181.236407269336, 8372.372072094495, 8557.700480483154, 8562.058173601023, 8587.0, 8616.870746938675, 8205.589720513832, 8597.16834178321, 8666.176713212235, 8691.913080869363, 8890.06654301289], "value": 6623.532336496837, "unit": "score"}], "name": "dromaeo_css", "value": 7150.417206919404, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 103.0, 120.0, 154.0, 104.0, 153.0, 164.0, 97.0, 151.0, 167.0], "value": 132.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 124.0, 118.0, 199.0, 119.0, 127.0, 163.0, 138.0, 152.0, 117.0], "value": 141.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 184.0, 182.0, 181.0, 181.0, 184.0, 179.0, 181.0, 181.0, 184.0], "value": 181.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 57.0, 60.0, 117.0, 58.0, 57.0, 75.0, 80.0, 97.0, 56.0], "value": 73.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 76.0, 139.0, 78.0, 84.0, 75.0, 100.0, 79.0, 76.0, 104.0], "value": 90.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 86.0, 85.0, 87.0, 85.0, 89.0, 85.0, 88.0, 84.0], "value": 86.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 95.0, 93.0, 93.0, 92.0, 94.0, 94.0, 93.0, 93.0], "value": 93.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 85.0, 86.0, 84.0, 83.0, 83.0, 85.0, 87.0, 85.0], "value": 84.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 56.0, 57.0, 57.0, 57.0, 56.0, 118.0, 55.0, 54.0], "value": 62.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 72.0, 72.0, 77.0, 70.0, 68.0, 69.0, 69.0, 70.0, 68.0], "value": 70.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 85.0, 82.0, 85.0, 85.0, 85.0, 84.0, 84.0, 86.0], "value": 84.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 137.0, 136.0, 134.0, 136.0, 133.0, 136.0, 133.0, 217.0, 139.0], "value": 143.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 230.0, 184.0, 201.0, 218.0, 168.0, 164.0, 164.0, 166.0, 164.0], "value": 182.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 67.0, 65.0, 66.0, 63.0, 65.0, 65.0, 64.0, 64.0], "value": 65.0, "unit": "score"}], "name": "kraken", "value": 1491.1, "alertThreshold": 2.0}]} 02:02:24 INFO - Return code: 0 02:02:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 02:02:24 ERROR - # TBPL SUCCESS # 02:02:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:02:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:02:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:02:24 INFO - 2016-05-24 02:02:24.159 screenresolution[1675:9846] starting screenresolution argv=screenresolution get 02:02:24 INFO - 2016-05-24 02:02:24.162 screenresolution[1675:9846] Display 0: 1600x1200x32@60 02:02:24 INFO - 2016-05-24 02:02:24.168 screenresolution[1676:9849] starting screenresolution argv=screenresolution list 02:02:24 INFO - Available Modes on Display 0 02:02:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:02:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:02:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:02:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:02:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:02:24 INFO - Intel Iris: 02:02:24 INFO - Chipset Model: Intel Iris 02:02:24 INFO - Type: GPU 02:02:24 INFO - Bus: Built-In 02:02:24 INFO - VRAM (Dynamic, Max): 1536 MB 02:02:24 INFO - Vendor: Intel (0x8086) 02:02:24 INFO - Device ID: 0x0a2e 02:02:24 INFO - Revision ID: 0x0009 02:02:24 INFO - Displays: 02:02:24 INFO - Display: 02:02:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:02:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:02:24 INFO - Display Serial Number: 1600x1200 60 02:02:24 INFO - Main Display: Yes 02:02:24 INFO - Mirror: Off 02:02:24 INFO - Online: Yes 02:02:24 INFO - Rotation: Supported 02:02:24 INFO - Return code: 0 02:02:24 INFO - Running post-action listener: _resource_record_post_action 02:02:24 INFO - ##### 02:02:24 INFO - ##### Finished run-tests step (success) 02:02:24 INFO - ##### 02:02:24 INFO - Running post-run listener: _resource_record_post_run 02:02:24 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 36988416; Write bytes: 694650880; Read time: 243; Write time: 1875 02:02:24 INFO - TinderboxPrint: CPU usage
53.8% 02:02:24 INFO - TinderboxPrint: I/O read bytes / time
36,988,416 / 243 02:02:24 INFO - TinderboxPrint: I/O write bytes / time
694,650,880 / 1,875 02:02:24 INFO - TinderboxPrint: CPU idle
2,238.4 (46.2%) 02:02:24 INFO - TinderboxPrint: CPU system
967.7 (20.0%) 02:02:24 INFO - TinderboxPrint: CPU user
1,635.6 (33.8%) 02:02:24 INFO - TinderboxPrint: Swap in / out
433,410,048 / 0 02:02:24 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 379914752; Write bytes: 373343744; Read time: 28170; Write time: 747 02:02:24 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 35931648; Write bytes: 296346112; Read time: 227; Write time: 1065 02:02:24 INFO - Running post-run listener: _upload_blobber_files 02:02:24 INFO - Blob upload gear active. 02:02:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:02:24 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 02:02:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:02:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:02:25 INFO - (blobuploader) - INFO - Open directory for files ... 02:02:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:02:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:02:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:02:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:02:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:02:26 INFO - (blobuploader) - INFO - Done attempting. 02:02:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:02:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:02:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:02:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:02:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:02:27 INFO - (blobuploader) - INFO - Done attempting. 02:02:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:02:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:02:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:02:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:02:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:02:30 INFO - (blobuploader) - INFO - Done attempting. 02:02:30 INFO - (blobuploader) - INFO - Iteration through files over. 02:02:30 INFO - Return code: 0 02:02:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:02:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:02:30 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dcdfa6059c5405b517b80052d27e7377070b373d6856706f28ba4b7a051a2f7817814bbc1602ea28fddf4322892ae2465fd4e9fcbdbcf5ef0a7028661d879855", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/87a6a660504e9b29c5cb8474dd8d9235644a8ec2a4e62fa975a7cca75ea54ad3e3f0bf47aeb7f912c75fdb586c081b45fcf330e3211b2ebd134fb7e51b11b872", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a45736cc1426156afb7dcfdcc8cff395866c1657b21f3c265c221c6aaa5ca0b7ff23c0ddc620dc1f6cc0adb70ccad52572d7f302ebc8d69d6380aaaeac27bacb"} 02:02:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:02:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:02:30 INFO - Contents: 02:02:30 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dcdfa6059c5405b517b80052d27e7377070b373d6856706f28ba4b7a051a2f7817814bbc1602ea28fddf4322892ae2465fd4e9fcbdbcf5ef0a7028661d879855", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/87a6a660504e9b29c5cb8474dd8d9235644a8ec2a4e62fa975a7cca75ea54ad3e3f0bf47aeb7f912c75fdb586c081b45fcf330e3211b2ebd134fb7e51b11b872", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a45736cc1426156afb7dcfdcc8cff395866c1657b21f3c265c221c6aaa5ca0b7ff23c0ddc620dc1f6cc0adb70ccad52572d7f302ebc8d69d6380aaaeac27bacb"} 02:02:30 INFO - Running post-run listener: copy_logs_to_upload_dir 02:02:30 INFO - Copying logs to upload dir... 02:02:30 INFO - mkdir: /builds/slave/test/build/upload/logs 02:02:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.425746 ========= master_lag: 3.20 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-05-24 02:02:34.202365) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 02:02:34.207157) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lRJUWiDs7d/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test/properties RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.Ln7z5DDoVF/Listeners TMPDIR=/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dcdfa6059c5405b517b80052d27e7377070b373d6856706f28ba4b7a051a2f7817814bbc1602ea28fddf4322892ae2465fd4e9fcbdbcf5ef0a7028661d879855", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/87a6a660504e9b29c5cb8474dd8d9235644a8ec2a4e62fa975a7cca75ea54ad3e3f0bf47aeb7f912c75fdb586c081b45fcf330e3211b2ebd134fb7e51b11b872", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a45736cc1426156afb7dcfdcc8cff395866c1657b21f3c265c221c6aaa5ca0b7ff23c0ddc620dc1f6cc0adb70ccad52572d7f302ebc8d69d6380aaaeac27bacb"} build_url:https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010981 build_url: 'https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dcdfa6059c5405b517b80052d27e7377070b373d6856706f28ba4b7a051a2f7817814bbc1602ea28fddf4322892ae2465fd4e9fcbdbcf5ef0a7028661d879855", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/87a6a660504e9b29c5cb8474dd8d9235644a8ec2a4e62fa975a7cca75ea54ad3e3f0bf47aeb7f912c75fdb586c081b45fcf330e3211b2ebd134fb7e51b11b872", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a45736cc1426156afb7dcfdcc8cff395866c1657b21f3c265c221c6aaa5ca0b7ff23c0ddc620dc1f6cc0adb70ccad52572d7f302ebc8d69d6380aaaeac27bacb"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 02:02:34.247610) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 02:02:34.247947) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lRJUWiDs7d/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.Ln7z5DDoVF/Listeners TMPDIR=/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005100 ========= master_lag: 0.20 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 02:02:34.452857) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 02:02:34.453200) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 02:02:34.453721) ========= ========= Total master_lag: 3.60 =========