builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0022 starttime: 1463150367.08 results: success (0) buildid: 20160513054947 builduid: 347f26c9372c46e88e9698777bbaf45c revision: 1f1a8b96d5167153d1f750439ba6a1063155a4bc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 07:39:27.084054) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 07:39:27.084488) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-13 07:39:27.084773) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-13 07:39:27.122450) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 07:39:27.122714) ========= 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.Lh6JWgHh4W/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.ctZMlUYEFI/Listeners TMPDIR=/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/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-13 07:39:27-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 28.6M=0s 2016-05-13 07:39:27 (28.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.124119 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 07:39:27.263383) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 07:39:27.263692) ========= 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.Lh6JWgHh4W/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.ctZMlUYEFI/Listeners TMPDIR=/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/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.019603 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 07:39:27.315629) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-13 07:39:27.315933) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1f1a8b96d5167153d1f750439ba6a1063155a4bc --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 1f1a8b96d5167153d1f750439ba6a1063155a4bc --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Lh6JWgHh4W/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.ctZMlUYEFI/Listeners TMPDIR=/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/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-13 07:39:27,379 truncating revision to first 12 chars 2016-05-13 07:39:27,379 Setting DEBUG logging. 2016-05-13 07:39:27,379 attempt 1/10 2016-05-13 07:39:27,379 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1f1a8b96d516?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-13 07:39:28,487 unpacking tar archive at: mozilla-central-1f1a8b96d516/testing/mozharness/ program finished with exit code 0 elapsedTime=1.358587 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-13 07:39:28.692361) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 07:39:28.692819) ========= script_repo_revision: 1f1a8b96d5167153d1f750439ba6a1063155a4bc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 07:39:28.694071) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 07:39:28.694546) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 07:39:28.708410) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-05-13 07:39:28.709072) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.Lh6JWgHh4W/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.ctZMlUYEFI/Listeners TMPDIR=/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/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 07:39:28 INFO - MultiFileLogger online at 20160513 07:39:28 in /builds/slave/test 07:39:28 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 07:39:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:39:28 INFO - {'append_to_log': False, 07:39:28 INFO - 'base_work_dir': '/builds/slave/test', 07:39:28 INFO - 'blob_upload_branch': 'Firefox', 07:39:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:39:28 INFO - 'branch': 'Firefox', 07:39:28 INFO - 'buildbot_json_path': 'buildprops.json', 07:39:28 INFO - 'config_files': ('talos/mac_config.py',), 07:39:28 INFO - 'default_actions': ('clobber', 07:39:28 INFO - 'read-buildbot-config', 07:39:28 INFO - 'download-and-extract', 07:39:28 INFO - 'populate-webroot', 07:39:28 INFO - 'create-virtualenv', 07:39:28 INFO - 'install', 07:39:28 INFO - 'run-tests'), 07:39:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:39:28 INFO - 'download_minidump_stackwalk': True, 07:39:28 INFO - 'download_symbols': 'ondemand', 07:39:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:39:28 INFO - 'tooltool.py': '/tools/tooltool.py', 07:39:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:39:28 INFO - '/tools/misc-python/virtualenv.py')}, 07:39:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:39:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:39:28 INFO - 'installer_path': 'installer.exe', 07:39:28 INFO - 'log_level': 'info', 07:39:28 INFO - 'log_name': 'talos', 07:39:28 INFO - 'log_to_console': True, 07:39:28 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:39:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:39:28 INFO - 'opt_config_files': (), 07:39:28 INFO - 'pip_index': False, 07:39:28 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:39:28 INFO - 'cmd': ('bash', 07:39:28 INFO - '-c', 07:39:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:39:28 INFO - 'enabled': True, 07:39:28 INFO - 'halt_on_failure': False, 07:39:28 INFO - 'name': 'check_screen_resolution'},), 07:39:28 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:39:28 INFO - 'cmd': ('bash', 07:39:28 INFO - '-c', 07:39:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:39:28 INFO - 'enabled': True, 07:39:28 INFO - 'halt_on_failure': False, 07:39:28 INFO - 'name': 'check_screen_resolution'},), 07:39:28 INFO - 'run_cmd_checks_enabled': True, 07:39:28 INFO - 'sps_profile': False, 07:39:28 INFO - 'sps_profile_interval': 0, 07:39:28 INFO - 'suite': 'dromaeojs-e10s', 07:39:28 INFO - 'system_bits': '32', 07:39:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:39:28 INFO - 'title': 't-yosemite-r7-0022', 07:39:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:39:28 INFO - 'use_talos_json': True, 07:39:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:39:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:39:28 INFO - 'work_dir': 'build'} 07:39:28 INFO - ##### 07:39:28 INFO - ##### Running clobber step. 07:39:28 INFO - ##### 07:39:28 INFO - Running pre-action listener: _resource_record_pre_action 07:39:28 INFO - Running main action method: clobber 07:39:28 INFO - rmtree: /builds/slave/test/build 07:39:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:39:30 INFO - Running post-action listener: _resource_record_post_action 07:39:30 INFO - ##### 07:39:30 INFO - ##### Finished clobber step (success) 07:39:30 INFO - ##### 07:39:30 INFO - ##### 07:39:30 INFO - ##### Running read-buildbot-config step. 07:39:30 INFO - ##### 07:39:30 INFO - Running pre-action listener: _resource_record_pre_action 07:39:30 INFO - Running main action method: read_buildbot_config 07:39:30 INFO - Using buildbot properties: 07:39:30 INFO - { 07:39:30 INFO - "project": "", 07:39:30 INFO - "product": "firefox", 07:39:30 INFO - "script_repo_revision": "production", 07:39:30 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 07:39:30 INFO - "repository": "", 07:39:30 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 07:39:30 INFO - "buildid": "20160513054947", 07:39:30 INFO - "pgo_build": "False", 07:39:30 INFO - "basedir": "/builds/slave/test", 07:39:30 INFO - "buildnumber": 3, 07:39:30 INFO - "slavename": "t-yosemite-r7-0022", 07:39:30 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 07:39:30 INFO - "platform": "yosemite_r7", 07:39:30 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 07:39:30 INFO - "branch": "mozilla-central", 07:39:30 INFO - "repo_path": "mozilla-central", 07:39:30 INFO - "revision": "1f1a8b96d5167153d1f750439ba6a1063155a4bc", 07:39:30 INFO - "stage_platform": "macosx64", 07:39:30 INFO - "builduid": "347f26c9372c46e88e9698777bbaf45c", 07:39:30 INFO - "slavebuilddir": "test" 07:39:30 INFO - } 07:39:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/eSqdiNSAQtGnDp5LAZn1wg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 07:39:30 INFO - Running post-action listener: _resource_record_post_action 07:39:30 INFO - ##### 07:39:30 INFO - ##### Finished read-buildbot-config step (success) 07:39:30 INFO - ##### 07:39:30 INFO - ##### 07:39:30 INFO - ##### Running download-and-extract step. 07:39:30 INFO - ##### 07:39:30 INFO - Running pre-action listener: _resource_record_pre_action 07:39:30 INFO - Running main action method: download_and_extract 07:39:30 INFO - mkdir: /builds/slave/test/build/tests 07:39:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:39:30 INFO - https://queue.taskcluster.net/v1/task/eSqdiNSAQtGnDp5LAZn1wg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 07:39:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eSqdiNSAQtGnDp5LAZn1wg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 07:39:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eSqdiNSAQtGnDp5LAZn1wg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 07:39:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eSqdiNSAQtGnDp5LAZn1wg/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 07:39:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eSqdiNSAQtGnDp5LAZn1wg/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 07:39:31 INFO - Downloaded 1199 bytes. 07:39:31 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 07:39:31 INFO - Using the following test package requirements: 07:39:31 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 07:39:31 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:39:31 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 07:39:31 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:39:31 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 07:39:31 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:39:31 INFO - u'jsshell-mac.zip'], 07:39:31 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:39:31 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 07:39:31 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 07:39:31 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:39:31 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 07:39:31 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:39:31 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 07:39:31 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:39:31 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 07:39:31 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:39:31 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 07:39:31 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:39:31 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')]} 07:39:31 INFO - https://queue.taskcluster.net/v1/task/eSqdiNSAQtGnDp5LAZn1wg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:39:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eSqdiNSAQtGnDp5LAZn1wg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:39:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eSqdiNSAQtGnDp5LAZn1wg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:39:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eSqdiNSAQtGnDp5LAZn1wg/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 07:39:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eSqdiNSAQtGnDp5LAZn1wg/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 07:39:33 INFO - Downloaded 18153848 bytes. 07:39:33 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'] 07:39:33 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 07:39:36 INFO - Return code: 0 07:39:36 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 07:39:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:39:36 INFO - https://queue.taskcluster.net/v1/task/eSqdiNSAQtGnDp5LAZn1wg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:39:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eSqdiNSAQtGnDp5LAZn1wg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:39:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eSqdiNSAQtGnDp5LAZn1wg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:39:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eSqdiNSAQtGnDp5LAZn1wg/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 07:39:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eSqdiNSAQtGnDp5LAZn1wg/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 07:39:40 INFO - Downloaded 18153848 bytes. 07:39:40 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'] 07:39:40 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 07:39:44 INFO - Return code: 0 07:39:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:39:44 INFO - https://queue.taskcluster.net/v1/task/eSqdiNSAQtGnDp5LAZn1wg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 07:39:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eSqdiNSAQtGnDp5LAZn1wg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 07:39:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eSqdiNSAQtGnDp5LAZn1wg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 07:39:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eSqdiNSAQtGnDp5LAZn1wg/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 07:39:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eSqdiNSAQtGnDp5LAZn1wg/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 07:39:45 INFO - Downloaded 10840239 bytes. 07:39:45 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'] 07:39:45 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 07:39:45 INFO - Return code: 0 07:39:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:39:45 INFO - https://queue.taskcluster.net/v1/task/eSqdiNSAQtGnDp5LAZn1wg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 07:39:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eSqdiNSAQtGnDp5LAZn1wg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:39:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eSqdiNSAQtGnDp5LAZn1wg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:39:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eSqdiNSAQtGnDp5LAZn1wg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 07:39:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eSqdiNSAQtGnDp5LAZn1wg/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 07:39:49 INFO - Downloaded 110078162 bytes. 07:39:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eSqdiNSAQtGnDp5LAZn1wg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:39:49 INFO - mkdir: /builds/slave/test/properties 07:39:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:39:49 INFO - Writing to file /builds/slave/test/properties/build_url 07:39:49 INFO - Contents: 07:39:49 INFO - build_url:https://queue.taskcluster.net/v1/task/eSqdiNSAQtGnDp5LAZn1wg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:39:50 INFO - Running post-action listener: _resource_record_post_action 07:39:50 INFO - Running post-action listener: set_extra_try_arguments 07:39:50 INFO - ##### 07:39:50 INFO - ##### Finished download-and-extract step (success) 07:39:50 INFO - ##### 07:39:50 INFO - ##### 07:39:50 INFO - ##### Running populate-webroot step. 07:39:50 INFO - ##### 07:39:50 INFO - Running pre-action listener: _resource_record_pre_action 07:39:50 INFO - Running main action method: populate_webroot 07:39:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:39:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:39:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:39:50 INFO - '--tppagecycles', 07:39:50 INFO - '7'], 07:39:50 INFO - 'tests': ['tsvgm']}}, 07:39:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:39:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:39:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:39:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:39:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:39:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:39:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:39:50 INFO - 'talos_options': ['--disable-e10s'], 07:39:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:39:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:39:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:39:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:39:50 INFO - 'talos_options': ['--disable-e10s'], 07:39:50 INFO - 'tests': ['damp', 'tps']}, 07:39:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:39:50 INFO - 'tests': ['damp', 'tps']}, 07:39:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:39:50 INFO - 'tests': ['dromaeo_dom']}, 07:39:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:39:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:39:50 INFO - 'tests': ['a11yr', 07:39:50 INFO - 'ts_paint', 07:39:50 INFO - 'tpaint', 07:39:50 INFO - 'sessionrestore', 07:39:50 INFO - 'sessionrestore_no_auto_restore', 07:39:50 INFO - 'tabpaint']}, 07:39:50 INFO - 'other-e10s': {'tests': ['a11yr', 07:39:50 INFO - 'ts_paint', 07:39:50 INFO - 'tpaint', 07:39:50 INFO - 'sessionrestore', 07:39:50 INFO - 'sessionrestore_no_auto_restore', 07:39:50 INFO - 'tabpaint']}, 07:39:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:39:50 INFO - 'ts_paint', 07:39:50 INFO - 'tpaint', 07:39:50 INFO - 'sessionrestore', 07:39:50 INFO - 'sessionrestore_no_auto_restore', 07:39:50 INFO - 'tabpaint']}, 07:39:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:39:50 INFO - 'ts_paint', 07:39:50 INFO - 'tpaint', 07:39:50 INFO - 'sessionrestore', 07:39:50 INFO - 'sessionrestore_no_auto_restore', 07:39:50 INFO - 'tabpaint']}, 07:39:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:39:50 INFO - 'tests': ['a11yr', 07:39:50 INFO - 'ts_paint', 07:39:50 INFO - 'tpaint', 07:39:50 INFO - 'sessionrestore', 07:39:50 INFO - 'sessionrestore_no_auto_restore', 07:39:50 INFO - 'tabpaint']}, 07:39:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:39:50 INFO - 'tests': ['a11yr', 07:39:50 INFO - 'ts_paint', 07:39:50 INFO - 'tpaint', 07:39:50 INFO - 'sessionrestore', 07:39:50 INFO - 'sessionrestore_no_auto_restore', 07:39:50 INFO - 'tabpaint']}, 07:39:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:39:50 INFO - 'tests': ['tsvgx', 07:39:50 INFO - 'tsvgr_opacity', 07:39:50 INFO - 'tart', 07:39:50 INFO - 'tscrollx', 07:39:50 INFO - 'cart']}, 07:39:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:39:50 INFO - 'tsvgr_opacity', 07:39:50 INFO - 'tart', 07:39:50 INFO - 'tscrollx', 07:39:50 INFO - 'cart']}, 07:39:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:39:50 INFO - 'talos_options': ['--disable-e10s'], 07:39:50 INFO - 'tests': ['tp5o']}, 07:39:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:39:50 INFO - 'tests': ['tp5o']}, 07:39:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:39:50 INFO - 'talos_options': ['--disable-e10s', 07:39:50 INFO - '--xperf_path', 07:39:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:39:50 INFO - 'tests': ['tp5n']}, 07:39:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:39:50 INFO - 'talos_options': ['--xperf_path', 07:39:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:39:50 INFO - 'tests': ['tp5n']}}, 07:39:50 INFO - 'talos.zip': {'path': '', 07:39:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:39:50 INFO - Running post-action listener: _resource_record_post_action 07:39:50 INFO - ##### 07:39:50 INFO - ##### Finished populate-webroot step (success) 07:39:50 INFO - ##### 07:39:50 INFO - ##### 07:39:50 INFO - ##### Running create-virtualenv step. 07:39:50 INFO - ##### 07:39:50 INFO - Running pre-action listener: _resource_record_pre_action 07:39:50 INFO - Running main action method: create_virtualenv 07:39:50 INFO - Creating virtualenv /builds/slave/test/build/venv 07:39:50 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 07:39:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:39:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:39:51 INFO - Using real prefix '/tools/python27' 07:39:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:39:51 INFO - Installing distribute.............................................................................................................................................................................................done. 07:39:54 INFO - Installing pip.................done. 07:39:54 INFO - Return code: 0 07:39:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:39:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:39:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:39:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:39:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:39:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:39:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:39:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106d98a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d9e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd753dd2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106c3cd30>, '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 0x106a60db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106c33b20>, '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.Lh6JWgHh4W/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.ctZMlUYEFI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/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 07:39:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:39:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:39:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Lh6JWgHh4W/Render', 07:39:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:39:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:39:54 INFO - 'HOME': '/Users/cltbld', 07:39:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:39:54 INFO - 'LOGNAME': 'cltbld', 07:39:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:39:54 INFO - 'MOZ_NO_REMOTE': '1', 07:39:54 INFO - 'NO_EM_RESTART': '1', 07:39:54 INFO - 'PAGER': '/bin/cat', 07:39:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:39:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:39:54 INFO - 'PWD': '/builds/slave/test', 07:39:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:39:54 INFO - 'SHELL': '/bin/bash', 07:39:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ctZMlUYEFI/Listeners', 07:39:54 INFO - 'TMPDIR': '/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/', 07:39:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:39:54 INFO - 'USER': 'cltbld', 07:39:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:39:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:39:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:39:54 INFO - 'XPC_FLAGS': '0x0', 07:39:54 INFO - 'XPC_SERVICE_NAME': '0', 07:39:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:39:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:39:54 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 07:39:54 INFO - Cleaning up... 07:39:54 INFO - Return code: 0 07:39:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:39:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:39:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:39:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:39:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:39:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:39:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:39:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106d98a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d9e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd753dd2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106c3cd30>, '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 0x106a60db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106c33b20>, '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.Lh6JWgHh4W/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.ctZMlUYEFI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/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 07:39:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:39:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:39:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Lh6JWgHh4W/Render', 07:39:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:39:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:39:54 INFO - 'HOME': '/Users/cltbld', 07:39:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:39:54 INFO - 'LOGNAME': 'cltbld', 07:39:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:39:54 INFO - 'MOZ_NO_REMOTE': '1', 07:39:54 INFO - 'NO_EM_RESTART': '1', 07:39:54 INFO - 'PAGER': '/bin/cat', 07:39:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:39:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:39:54 INFO - 'PWD': '/builds/slave/test', 07:39:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:39:54 INFO - 'SHELL': '/bin/bash', 07:39:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ctZMlUYEFI/Listeners', 07:39:54 INFO - 'TMPDIR': '/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/', 07:39:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:39:54 INFO - 'USER': 'cltbld', 07:39:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:39:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:39:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:39:54 INFO - 'XPC_FLAGS': '0x0', 07:39:54 INFO - 'XPC_SERVICE_NAME': '0', 07:39:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:39:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:39:55 INFO - Downloading/unpacking psutil>=3.1.1 07:39:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:39:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:39:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:39:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:39:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:39:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:39:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:39:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:39:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:39:58 INFO - Installing collected packages: psutil 07:39:58 INFO - Running setup.py install for psutil 07:39:58 INFO - building 'psutil._psutil_osx' extension 07:39:58 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 07:39:58 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 07:39:59 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 07:39:59 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 07:39:59 INFO - building 'psutil._psutil_posix' extension 07:39:59 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 07:39:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:39:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:39:59 INFO - ^ 07:39:59 INFO - 1 warning generated. 07:39:59 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 07:39:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:39:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:39:59 INFO - Successfully installed psutil 07:39:59 INFO - Cleaning up... 07:39:59 INFO - Return code: 0 07:39:59 INFO - Installing mozsystemmonitor==0.1 into virtualenv /builds/slave/test/build/venv 07:39:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:39:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:39:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:39:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:39:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:39:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:39:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106d98a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d9e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd753dd2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106c3cd30>, '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 0x106a60db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106c33b20>, '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.Lh6JWgHh4W/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.ctZMlUYEFI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/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 07:39:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.1'] in /builds/slave/test/build 07:39:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.1 07:39:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Lh6JWgHh4W/Render', 07:39:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:39:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:39:59 INFO - 'HOME': '/Users/cltbld', 07:39:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:39:59 INFO - 'LOGNAME': 'cltbld', 07:39:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:39:59 INFO - 'MOZ_NO_REMOTE': '1', 07:39:59 INFO - 'NO_EM_RESTART': '1', 07:39:59 INFO - 'PAGER': '/bin/cat', 07:39:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:39:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:39:59 INFO - 'PWD': '/builds/slave/test', 07:39:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:39:59 INFO - 'SHELL': '/bin/bash', 07:39:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ctZMlUYEFI/Listeners', 07:39:59 INFO - 'TMPDIR': '/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/', 07:39:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:39:59 INFO - 'USER': 'cltbld', 07:39:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:39:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:39:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:39:59 INFO - 'XPC_FLAGS': '0x0', 07:39:59 INFO - 'XPC_SERVICE_NAME': '0', 07:39:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:39:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:39:59 INFO - Downloading/unpacking mozsystemmonitor==0.1 07:39:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:39:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:39:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:39:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:39:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:39:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:40:02 INFO - Downloading mozsystemmonitor-0.1.tar.gz 07:40:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:40:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.1) 07:40:02 INFO - Installing collected packages: mozsystemmonitor 07:40:02 INFO - Running setup.py install for mozsystemmonitor 07:40:02 INFO - Successfully installed mozsystemmonitor 07:40:02 INFO - Cleaning up... 07:40:02 INFO - Return code: 0 07:40:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:40:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:40:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:40:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:40:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:40:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:40:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:40:02 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 0x106d98a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d9e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd753dd2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106c3cd30>, '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 0x106a60db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106c33b20>, '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.Lh6JWgHh4W/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.ctZMlUYEFI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/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 07:40:02 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 07:40:02 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 07:40:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Lh6JWgHh4W/Render', 07:40:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:40:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:40:02 INFO - 'HOME': '/Users/cltbld', 07:40:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:40:02 INFO - 'LOGNAME': 'cltbld', 07:40:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:40:02 INFO - 'MOZ_NO_REMOTE': '1', 07:40:02 INFO - 'NO_EM_RESTART': '1', 07:40:02 INFO - 'PAGER': '/bin/cat', 07:40:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:40:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:40:02 INFO - 'PWD': '/builds/slave/test', 07:40:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:40:02 INFO - 'SHELL': '/bin/bash', 07:40:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ctZMlUYEFI/Listeners', 07:40:02 INFO - 'TMPDIR': '/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/', 07:40:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:40:02 INFO - 'USER': 'cltbld', 07:40:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:40:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:40:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:40:02 INFO - 'XPC_FLAGS': '0x0', 07:40:02 INFO - 'XPC_SERVICE_NAME': '0', 07:40:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:40:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:40:02 INFO - Downloading/unpacking blobuploader==1.2.4 07:40: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 07:40: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 07:40: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 07:40: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 07:40: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 07:40: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 07:40:05 INFO - Downloading blobuploader-1.2.4.tar.gz 07:40:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:40:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:40: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 07:40: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 07:40: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 07:40: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 07:40: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 07:40: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 07:40:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:40:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:40: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 07:40: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 07:40: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 07:40: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 07:40: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 07:40: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 07:40:06 INFO - Downloading docopt-0.6.1.tar.gz 07:40:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:40:06 INFO - Installing collected packages: blobuploader, requests, docopt 07:40:06 INFO - Running setup.py install for blobuploader 07:40:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:40:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:40:06 INFO - Running setup.py install for requests 07:40:06 INFO - Running setup.py install for docopt 07:40:07 INFO - Successfully installed blobuploader requests docopt 07:40:07 INFO - Cleaning up... 07:40:07 INFO - Return code: 0 07:40:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:40:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:40:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:40:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:40:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:40:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:40:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:40:07 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 0x106d98a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d9e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd753dd2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106c3cd30>, '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 0x106a60db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106c33b20>, '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.Lh6JWgHh4W/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.ctZMlUYEFI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/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 07:40:07 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 07:40:07 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 07:40:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Lh6JWgHh4W/Render', 07:40:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:40:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:40:07 INFO - 'HOME': '/Users/cltbld', 07:40:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:40:07 INFO - 'LOGNAME': 'cltbld', 07:40:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:40:07 INFO - 'MOZ_NO_REMOTE': '1', 07:40:07 INFO - 'NO_EM_RESTART': '1', 07:40:07 INFO - 'PAGER': '/bin/cat', 07:40:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:40:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:40:07 INFO - 'PWD': '/builds/slave/test', 07:40:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:40:07 INFO - 'SHELL': '/bin/bash', 07:40:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ctZMlUYEFI/Listeners', 07:40:07 INFO - 'TMPDIR': '/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/', 07:40:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:40:07 INFO - 'USER': 'cltbld', 07:40:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:40:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:40:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:40:07 INFO - 'XPC_FLAGS': '0x0', 07:40:07 INFO - 'XPC_SERVICE_NAME': '0', 07:40:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:40:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:40:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:40:07 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-2dwmDI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:40:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:40:07 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-T0I0NC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:40:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:40:07 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-fUOiyF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:40:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:40:07 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-B5PuZG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:40:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:40:07 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-SkdZJ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:40:08 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-PqYnsa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:40:08 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-YjDnJ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:40:08 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-Ri1Q0g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:40:08 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-llkFNb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:40:08 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-S0lqa5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:40:08 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-zBUpLM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:40:08 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-2rVsSh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:40:08 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-7l4vtD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:40:08 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-vYUKyf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:40:08 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-G3Haas-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:40:09 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-zNJCfR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:40:09 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-RtBzAw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:40:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:40:09 INFO - Running setup.py install for manifestparser 07:40:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:40:09 INFO - Running setup.py install for mozcrash 07:40:09 INFO - Running setup.py install for mozdebug 07:40:09 INFO - Running setup.py install for mozdevice 07:40:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:40:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:40:09 INFO - Running setup.py install for mozfile 07:40:10 INFO - Running setup.py install for mozhttpd 07:40:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:40:10 INFO - Running setup.py install for mozinfo 07:40:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:40:10 INFO - Running setup.py install for mozInstall 07:40:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:40:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:40:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:40:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:40:10 INFO - Running setup.py install for mozleak 07:40:10 INFO - Running setup.py install for mozlog 07:40:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:40:10 INFO - Running setup.py install for moznetwork 07:40:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:40:10 INFO - Running setup.py install for mozprocess 07:40:11 INFO - Running setup.py install for mozprofile 07:40:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:40:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:40:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:40:11 INFO - Running setup.py install for mozrunner 07:40:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:40:11 INFO - Running setup.py install for mozscreenshot 07:40:11 INFO - Running setup.py install for moztest 07:40:11 INFO - Running setup.py install for mozversion 07:40:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:40:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:40:11 INFO - Cleaning up... 07:40:11 INFO - Return code: 0 07:40:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:40:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:40:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:40:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:40:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:40:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:40:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:40:11 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 0x106d98a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d9e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd753dd2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106c3cd30>, '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 0x106a60db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106c33b20>, '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.Lh6JWgHh4W/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.ctZMlUYEFI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/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 07:40:11 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 07:40:11 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 07:40:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Lh6JWgHh4W/Render', 07:40:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:40:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:40:11 INFO - 'HOME': '/Users/cltbld', 07:40:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:40:11 INFO - 'LOGNAME': 'cltbld', 07:40:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:40:11 INFO - 'MOZ_NO_REMOTE': '1', 07:40:11 INFO - 'NO_EM_RESTART': '1', 07:40:11 INFO - 'PAGER': '/bin/cat', 07:40:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:40:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:40:11 INFO - 'PWD': '/builds/slave/test', 07:40:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:40:11 INFO - 'SHELL': '/bin/bash', 07:40:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ctZMlUYEFI/Listeners', 07:40:11 INFO - 'TMPDIR': '/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/', 07:40:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:40:11 INFO - 'USER': 'cltbld', 07:40:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:40:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:40:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:40:11 INFO - 'XPC_FLAGS': '0x0', 07:40:11 INFO - 'XPC_SERVICE_NAME': '0', 07:40:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:40:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:40:12 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-FYYPdh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:40:12 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)) 07:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:40:12 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-4v6ayS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:40:12 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)) 07:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:40:12 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-rew5_O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:40:12 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)) 07:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:40:12 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-G308nI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:40:12 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)) 07:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:40:12 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-eYT7s_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:40:12 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)) 07:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:40:12 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-d7HtPd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:40:12 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)) 07:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:40:12 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-q6Zb4N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:40:12 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)) 07:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:40:12 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-8LEUy1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:40:12 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)) 07:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:40:12 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-_Z8eW7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:40:13 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)) 07:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:40:13 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-KlmsKZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:40:13 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)) 07:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:40:13 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-YhWRjV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:40:13 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)) 07:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:40:13 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-YGPOe_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:40:13 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)) 07:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:40:13 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-HbdExd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:40:13 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)) 07:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:40:13 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-AOjHP6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:40:13 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)) 07:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:40:13 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-6wSYqt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:40:13 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)) 07:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:40:13 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-8RaYnG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:40:13 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)) 07:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:40:13 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-RiGT79-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:40:13 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)) 07:40:13 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)) 07:40:13 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)) 07:40:13 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)) 07:40:13 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)) 07:40:13 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)) 07:40:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:40:13 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 07:40:13 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 07:40:13 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 07:40:13 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 07:40:13 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 07:40:13 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 07:40:16 INFO - Downloading blessings-1.6.tar.gz 07:40:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:40:16 INFO - Installing collected packages: blessings 07:40:16 INFO - Running setup.py install for blessings 07:40:16 INFO - Successfully installed blessings 07:40:16 INFO - Cleaning up... 07:40:16 INFO - Return code: 0 07:40:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:40:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:40:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:40:16 INFO - Reading from file tmpfile_stdout 07:40:16 INFO - Current package versions: 07:40:16 INFO - blessings == 1.6 07:40:16 INFO - blobuploader == 1.2.4 07:40:16 INFO - docopt == 0.6.1 07:40:16 INFO - manifestparser == 1.1 07:40:16 INFO - mozInstall == 1.12 07:40:16 INFO - mozcrash == 0.17 07:40:16 INFO - mozdebug == 0.1 07:40:16 INFO - mozdevice == 0.48 07:40:16 INFO - mozfile == 1.2 07:40:16 INFO - mozhttpd == 0.7 07:40:16 INFO - mozinfo == 0.9 07:40:16 INFO - mozleak == 0.1 07:40:16 INFO - mozlog == 3.2 07:40:16 INFO - moznetwork == 0.27 07:40:16 INFO - mozprocess == 0.22 07:40:16 INFO - mozprofile == 0.28 07:40:16 INFO - mozrunner == 6.11 07:40:16 INFO - mozscreenshot == 0.1 07:40:16 INFO - mozsystemmonitor == 0.1 07:40:16 INFO - moztest == 0.7 07:40:16 INFO - mozversion == 1.4 07:40:16 INFO - psutil == 3.1.1 07:40:16 INFO - requests == 1.2.3 07:40:16 INFO - wsgiref == 0.1.2 07:40:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:40:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:40:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:40:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:40:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:40:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:40:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:40:16 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 0x106d98a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d9e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd753dd2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106c3cd30>, '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 0x106a60db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106c33b20>, '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.Lh6JWgHh4W/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.ctZMlUYEFI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/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 07:40:16 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 07:40:16 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 07:40:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Lh6JWgHh4W/Render', 07:40:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:40:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:40:16 INFO - 'HOME': '/Users/cltbld', 07:40:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:40:16 INFO - 'LOGNAME': 'cltbld', 07:40:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:40:16 INFO - 'MOZ_NO_REMOTE': '1', 07:40:16 INFO - 'NO_EM_RESTART': '1', 07:40:16 INFO - 'PAGER': '/bin/cat', 07:40:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:40:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:40:16 INFO - 'PWD': '/builds/slave/test', 07:40:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:40:16 INFO - 'SHELL': '/bin/bash', 07:40:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ctZMlUYEFI/Listeners', 07:40:16 INFO - 'TMPDIR': '/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/', 07:40:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:40:16 INFO - 'USER': 'cltbld', 07:40:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:40:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:40:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:40:16 INFO - 'XPC_FLAGS': '0x0', 07:40:16 INFO - 'XPC_SERVICE_NAME': '0', 07:40:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:40:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:40:17 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)) 07:40:17 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)) 07:40:17 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)) 07:40:17 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)) 07:40:17 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)) 07:40:17 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)) 07:40:17 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)) 07:40:17 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)) 07:40:17 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)) 07:40:17 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)) 07:40:17 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)) 07:40:17 INFO - Cleaning up... 07:40:17 INFO - Return code: 0 07:40:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:40:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:40:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:40:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:40:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:40:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:40:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:40:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106d98a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d9e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd753dd2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106c3cd30>, '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 0x106a60db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106c33b20>, '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.Lh6JWgHh4W/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.ctZMlUYEFI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/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 07:40:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:40:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:40:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Lh6JWgHh4W/Render', 07:40:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:40:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:40:17 INFO - 'HOME': '/Users/cltbld', 07:40:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:40:17 INFO - 'LOGNAME': 'cltbld', 07:40:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:40:17 INFO - 'MOZ_NO_REMOTE': '1', 07:40:17 INFO - 'NO_EM_RESTART': '1', 07:40:17 INFO - 'PAGER': '/bin/cat', 07:40:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:40:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:40:17 INFO - 'PWD': '/builds/slave/test', 07:40:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:40:17 INFO - 'SHELL': '/bin/bash', 07:40:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ctZMlUYEFI/Listeners', 07:40:17 INFO - 'TMPDIR': '/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/', 07:40:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:40:17 INFO - 'USER': 'cltbld', 07:40:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:40:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:40:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:40:17 INFO - 'XPC_FLAGS': '0x0', 07:40:17 INFO - 'XPC_SERVICE_NAME': '0', 07:40:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:40:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:40:17 INFO - Downloading/unpacking jsonschema 07:40:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:40:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:40:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:40:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:40:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:40:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:40:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:40:19 INFO - Downloading/unpacking functools32 (from jsonschema) 07:40:19 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 07:40:19 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 07:40:19 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 07:40:19 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 07:40:19 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 07:40:19 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 07:40:20 INFO - Downloading functools32-3.2.3-2.tar.gz 07:40:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:40:20 INFO - warning: no files found matching '*.txt' 07:40:20 INFO - no previously-included directories found matching 'build' 07:40:20 INFO - no previously-included directories found matching 'dist' 07:40:20 INFO - no previously-included directories found matching '.git*' 07:40:20 INFO - Installing collected packages: jsonschema, functools32 07:40:20 INFO - Running setup.py install for functools32 07:40:20 INFO - warning: no files found matching '*.txt' 07:40:20 INFO - no previously-included directories found matching 'build' 07:40:20 INFO - no previously-included directories found matching 'dist' 07:40:20 INFO - no previously-included directories found matching '.git*' 07:40:20 INFO - Successfully installed jsonschema functools32 07:40:20 INFO - Cleaning up... 07:40:20 INFO - Return code: 0 07:40:20 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 07:40: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')]} 07:40:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:40:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:40: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')]} 07:40:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:40:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:40:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106d98a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d9e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd753dd2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106c3cd30>, '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 0x106a60db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106c33b20>, '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.Lh6JWgHh4W/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.ctZMlUYEFI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/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 07:40:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 07:40:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 07:40:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Lh6JWgHh4W/Render', 07:40:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:40:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:40:20 INFO - 'HOME': '/Users/cltbld', 07:40:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:40:20 INFO - 'LOGNAME': 'cltbld', 07:40:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:40:20 INFO - 'MOZ_NO_REMOTE': '1', 07:40:20 INFO - 'NO_EM_RESTART': '1', 07:40:20 INFO - 'PAGER': '/bin/cat', 07:40:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:40:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:40:20 INFO - 'PWD': '/builds/slave/test', 07:40:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:40:20 INFO - 'SHELL': '/bin/bash', 07:40:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ctZMlUYEFI/Listeners', 07:40:20 INFO - 'TMPDIR': '/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/', 07:40:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:40:20 INFO - 'USER': 'cltbld', 07:40:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:40:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:40:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:40:20 INFO - 'XPC_FLAGS': '0x0', 07:40:20 INFO - 'XPC_SERVICE_NAME': '0', 07:40:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:40:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:40:21 INFO - Downloading/unpacking flake8 07:40:21 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 07:40:21 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 07:40:21 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 07:40:21 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 07:40:21 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 07:40:21 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 07:40:23 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 07:40:23 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 07:40:23 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 07:40:23 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 07:40:23 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 07:40:23 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 07:40:23 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 07:40:23 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 07:40:23 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 07:40:23 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 07:40:23 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 07:40:23 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 07:40:23 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 07:40:23 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 07:40:23 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 07:40:23 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 07:40:24 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 07:40:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:40:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:40:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:40:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:40:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:40:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:40:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 07:40:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:40:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:40:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:40:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:40:24 INFO - no previously-included directories found matching 'docs/_build' 07:40:24 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 07:40:24 INFO - Running setup.py install for pep8 07:40:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:40:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:40:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:40:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:40:24 INFO - no previously-included directories found matching 'docs/_build' 07:40:24 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 07:40:24 INFO - Successfully installed flake8 mccabe pyflakes pep8 07:40:24 INFO - Cleaning up... 07:40:25 INFO - Return code: 0 07:40:25 INFO - Running post-action listener: _resource_record_post_action 07:40:25 INFO - Running post-action listener: _start_resource_monitoring 07:40:25 INFO - Starting resource monitoring. 07:40:25 INFO - ##### 07:40:25 INFO - ##### Finished create-virtualenv step (success) 07:40:25 INFO - ##### 07:40:25 INFO - ##### 07:40:25 INFO - ##### Running install step. 07:40:25 INFO - ##### 07:40:25 INFO - Running pre-action listener: _resource_record_pre_action 07:40:25 INFO - Running main action method: install 07:40:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:40:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:40:25 INFO - Reading from file tmpfile_stdout 07:40:25 INFO - Detecting whether we're running mozinstall >=1.0... 07:40:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:40:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:40:25 INFO - Reading from file tmpfile_stdout 07:40:25 INFO - Output received: 07:40:25 INFO - Usage: mozinstall [options] installer 07:40:25 INFO - Options: 07:40:25 INFO - -h, --help show this help message and exit 07:40:25 INFO - -d DEST, --destination=DEST 07:40:25 INFO - Directory to install application into. [default: 07:40:25 INFO - "/builds/slave/test"] 07:40:25 INFO - --app=APP Application being installed. [default: firefox] 07:40:25 INFO - mkdir: /builds/slave/test/build/application 07:40:25 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'] 07:40:25 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 07:40:58 INFO - Reading from file tmpfile_stdout 07:40:58 INFO - Output received: 07:40:58 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:40:58 INFO - Running post-action listener: _resource_record_post_action 07:40:58 INFO - ##### 07:40:58 INFO - ##### Finished install step (success) 07:40:58 INFO - ##### 07:40:58 INFO - ##### 07:40:58 INFO - ##### Running run-tests step. 07:40:58 INFO - ##### 07:40:58 INFO - Running pre-action listener: _resource_record_pre_action 07:40:58 INFO - Running main action method: run_tests 07:40:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:40:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:40:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:40:58 INFO - 2016-05-13 07:40:58.761 screenresolution[1930:16197] starting screenresolution argv=screenresolution get 07:40:58 INFO - 2016-05-13 07:40:58.763 screenresolution[1930:16197] Display 0: 1600x1200x32@60 07:40:58 INFO - 2016-05-13 07:40:58.769 screenresolution[1931:16199] starting screenresolution argv=screenresolution list 07:40:58 INFO - Available Modes on Display 0 07:40:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:40:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:40:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:40:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:40:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:40:58 INFO - Intel Iris: 07:40:58 INFO - Chipset Model: Intel Iris 07:40:58 INFO - Type: GPU 07:40:58 INFO - Bus: Built-In 07:40:58 INFO - VRAM (Dynamic, Max): 1536 MB 07:40:58 INFO - Vendor: Intel (0x8086) 07:40:59 INFO - Device ID: 0x0a2e 07:40:59 INFO - Revision ID: 0x0009 07:40:59 INFO - Displays: 07:40:59 INFO - Display: 07:40:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:40:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:40:59 INFO - Display Serial Number: 1600x1200 60 07:40:59 INFO - Main Display: Yes 07:40:59 INFO - Mirror: Off 07:40:59 INFO - Online: Yes 07:40:59 INFO - Rotation: Supported 07:40:59 INFO - Return code: 0 07:40:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:40:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:40:59 INFO - Python 2.7.3 07:40:59 INFO - Return code: 0 07:40:59 INFO - grabbing minidump binary from tooltool 07:40:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:40:59 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 0x106c3cd30>, '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 0x106a60db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106c33b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:40:59 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 07:40:59 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 07:40:59 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 07:40:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:40:59 INFO - Return code: 0 07:40:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:40:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:40:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:40:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:40:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:40:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 07:40:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 07:41:00 INFO - Return code: 0 07:41:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eSqdiNSAQtGnDp5LAZn1wg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0022', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 07:41:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/eSqdiNSAQtGnDp5LAZn1wg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0022 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 07:41:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Lh6JWgHh4W/Render', 07:41:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:41:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:41:00 INFO - 'HOME': '/Users/cltbld', 07:41:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:41:00 INFO - 'LOGNAME': 'cltbld', 07:41:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:41:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:41:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:41:00 INFO - 'MOZ_NO_REMOTE': '1', 07:41:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:41:00 INFO - 'NO_EM_RESTART': '1', 07:41:00 INFO - 'PAGER': '/bin/cat', 07:41:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:41:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:41:00 INFO - 'PWD': '/builds/slave/test', 07:41:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:41:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:41:00 INFO - 'SHELL': '/bin/bash', 07:41:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ctZMlUYEFI/Listeners', 07:41:00 INFO - 'TMPDIR': '/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/', 07:41:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:41:00 INFO - 'USER': 'cltbld', 07:41:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:41:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:41:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:41:00 INFO - 'XPC_FLAGS': '0x0', 07:41:00 INFO - 'XPC_SERVICE_NAME': '0', 07:41:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:41:00 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eSqdiNSAQtGnDp5LAZn1wg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0022', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 07:41:00 INFO - mozversion application_buildid: 20160513054947 07:41:00 INFO - mozversion application_changeset: 1f1a8b96d5167153d1f750439ba6a1063155a4bc 07:41:00 INFO - mozversion application_display_name: Nightly 07:41:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:41:00 INFO - mozversion application_name: Firefox 07:41:00 INFO - mozversion application_remotingname: firefox 07:41:00 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:41:00 INFO - mozversion application_vendor: Mozilla 07:41:00 INFO - mozversion application_version: 49.0a1 07:41:00 INFO - mozversion platform_buildid: 20160513054947 07:41:00 INFO - mozversion platform_changeset: 1f1a8b96d5167153d1f750439ba6a1063155a4bc 07:41:00 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:41:00 INFO - mozversion platform_version: 49.0a1 07:41:00 INFO - using testdate: 1463150460 07:41:00 INFO - actual date: 1463150460 07:41:00 INFO - starting webserver on 'localhost:52228' 07:41:00 INFO - SUITE-START | Running 2 tests 07:41:00 INFO - TEST-START | dromaeo_css 07:41:00 INFO - operating with platform_type : mac_ 07:41:00 INFO - Initialising browser for dromaeo_css test... 07:41:00 INFO - TEST-INFO | started process 1949 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/tmpR17Xxl/profile http://localhost:52228/getInfo.html) 07:41:03 INFO - PROCESS | 1949 | __metrics Screen width/height:1600/1200 07:41:03 INFO - PROCESS | 1949 | colorDepth:24 07:41:03 INFO - PROCESS | 1949 | Browser inner width/height: 1024/689 07:41:03 INFO - PROCESS | 1949 | __metrics 07:41:03 ERROR - PROCESS | 1949 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 07:41:04 INFO - TEST-INFO | 1949: exit 0 07:41:04 INFO - Browser initialized. 07:41:04 INFO - Running cycle 1/1 for dromaeo_css test... 07:41:04 INFO - TEST-INFO | started process 1952 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/tmpR17Xxl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:41:16 INFO - PROCESS | 1952 | RSS: Main: 249675776 07:41:16 INFO - PROCESS | 1952 | 07:42:05 INFO - PROCESS | 1952 | 1463150525691 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] 07:44:16 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:52228/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52228/tests/dromaeo/cssquery-ext.html) 07:44:18 INFO - PROCESS | 1952 | RSS: Main: 260710400 07:44:18 INFO - PROCESS | 1952 | 07:47:20 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:52228/tests/dromaeo/cssquery-ext.html (next: http://localhost:52228/tests/dromaeo/cssquery-jquery.html) 07:47:21 INFO - PROCESS | 1952 | RSS: Main: 273272832 07:47:21 INFO - PROCESS | 1952 | 07:50:22 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:52228/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52228/tests/dromaeo/cssquery-mootools.html) 07:50:23 INFO - PROCESS | 1952 | RSS: Main: 275238912 07:50:23 INFO - PROCESS | 1952 | 07:53:25 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:52228/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52228/tests/dromaeo/cssquery-prototype.html) 07:53:26 INFO - PROCESS | 1952 | RSS: Main: 277020672 07:53:26 INFO - PROCESS | 1952 | 07:56:28 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:52228/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52228/tests/dromaeo/cssquery-yui.html) 07:56:29 INFO - PROCESS | 1952 | RSS: Main: 279875584 07:56:29 INFO - PROCESS | 1952 | 07:59:32 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:52228/tests/dromaeo/cssquery-yui.html (next: http://localhost:52228/tests/dromaeo/cssquery-dojo.html) 07:59:32 INFO - PROCESS | 1952 | RSS: Main: 281006080 07:59:32 INFO - PROCESS | 1952 | 07:59:32 INFO - PROCESS | 1952 | __start_tp_report 07:59:32 INFO - PROCESS | 1952 | _x_x_mozilla_page_load 07:59:32 INFO - PROCESS | 1952 | _x_x_mozilla_page_load_details 07:59:32 INFO - PROCESS | 1952 | |i|pagename|runs| 07:59:32 INFO - PROCESS | 1952 | |0;dojo.html;3465.4628532577453;3466.150793055702;3474.982625086874;3487.703545198658;3507.7895326280427;5740.971295143524;5851.970740146293;5872.970635146829;5890.145928840316;5909.290885093789;4458.353538736881;5717.771289148429;5745.5690823188215;5865.29616446026;5925.259342582177;5198.012377648253;5228.163493840986;5303.390110137326;5371.81198658047;5374.6237763356585;638.0922293503661;5194.194899790519;5250.18622113573;5276.287701160351;5303.204519322094;4362.432883725127;5056.609432406081;5252.816151434681;5269.604779641542;5321.973390133063;5271.762770675329;5307.150855863082;5324.813631522917;5354.223637572531;5360.597955153379;5132.40977287613;5235.58115350771;5270.130428479335;5270.367555893317;5325.707086110262;5439.401665816757;5663.1505274208785;5753.309602847685;5844.386339434383;5855.882882342372;6149.631022138686;6230.096635987757;6233;6251.624902505819;6259.123722678829;5989.622386851049;6160.722767475474;6184.257889053298;6240.656763877985;6288.968555157195;5342.492463215988;6000.909986350208;6011.549133814981;6027.638341699512;6040.7583696651645;6205.6586887721605;6231.2522497300615;6323.146375239376;6357.237131544244;6385.201849768779;6146.801373732169;6152.738688568833;6169.01295792671;6173.382661733791;6209.782657607052;6226.968865155736;6240.875182496416;6265.185525881606;6303.747850086037;6316.905246421307;6211.875762484725;6238.908191066545;6294.559380843297;6326.778562750282;6375.553711240169;6213.596116252519;6225.782097626652;6244.937550624527;6251.874062968515;6257.7184026718905;4938.555530002317;4997.85006449807;4999.100161970808;5018.974905125451;5025.84922452327;3454.7236221102253;4136.896577585584;4428.512863585003;4458.955410445854;4481.991551900796;5080.695158290514;5961.374055724174;5989.760409583655;6002.279726432768;6057.18228039209;6251.060305168349;6290.433860952445;6291.874162516724;6300.9684951574945;6301.023341382093;5915.053591425351;5964.31410387811;5975.731092100866;6023.367546407651;6063.7574497020505;5467.398586155519;5472.699001554953;5476;5494.09347457673;5515.972420137954;6420.325865784119;6441.742330306735;6445.999999999906;6451.2903580607035;6456.838579035561;6153.13856060143;6211.2857021443115;6224.284207316216;6231.626102433868;6283.963146081029;6461.61230326182;6483.448906842971;6494.0258961156205;6495.285518593046;6509.316521765147;6144.586745048575;6228.532860035606;6229.2524897010835;6231.875362492816;6255.0617407387435;5726.9691455538405;5948.672822994604;6172.537059720449;6181.289151747606;6280.560360774884;6227.283862355887;6243.575285182545;6265.27949285838;6290.182276304051;6302.086197501429;5257.658252213594;6142.907856382247;6161.907571386343;6204.410580994979;6251;5885.882282354414;5987.520998320037;6108.493847774579;6108.5723999319625;6127.525510585499;5070.467600901852;6262.248530176408;6266;6279.403456671609;6296.307406185408;5944.851378715393;6005.609635373687;6081.939180608138;6147.354527774519;6173.506119510339 07:59:32 INFO - PROCESS | 1952 | |1;ext.html;524.3340956984632;530.1755769777999;530.2655821686963;542.9104197807362;636.8312397214737;39443.60556394435;41609.75170744875;41633.08568400206;41634;41678.16643667125;41656.37515437263;42028.73913782584;42264.57735422645;42288.788556057254;42300.788496057554;9458.243340532757;9577.233821294314;9688.612455501807;9692.418454892693;9716.708498745049;9627.3260871739;9678.758031049245;9700.805983880318;9710.12608865202;9712;9702.563384647709;9705.078017588317;9721.756956076084;9739.26955478342;9739.707808765748;9657.082577155159;9681.564329605115;9696.709098727084;9704.660336888246;9736.805263894754;9518.333716639838;9614.08666176719;9626.229901607854;9635.325527213028;9637.759056023655;38802.805985970175;39032.21935561273;39097.827065187805;39168.60831391678;39239.62661306869;2564.4614630927526;2808.43831233752;3022.425739109562;3266.5590145330316;3362.8318584070944;28014.579781303295;28824.135275941546;29317.97387091463;29907.401851962837;30017.549736753746;2715.2261605442354;2717.8369297841996;2757.9448411031667;2794.455081259135;2870;530611;531154;541038.2948085234;541378;542540.2872985649;64587;64948;65210.34789652138;65232.02151967724;65313.02030469547;36291.81854090712;36407.81796091056;37009;37051.81474092666;68836.6558167206;14135.858641413453;16205;16669.83330166683;16941.91529042347;16950.830491694924;11735.23720958135;12438.134279857913;13051.543195988095;13165.934170329087;13174.802377964339;19227.11608177389;19787.01064946747;19840.60318793616;19947.102380392917;20005.599888002158;8481.83036339282;8482.151784821468;8527.275181609633;8528.378000559207;8546.31629469641;948.3788118782209;957.6456711016829;1015.3958394755109;1100.6587957732968;1118.4128332625273;24820;25240.495190096095;29144.7085529142;29768.40463190724;30342.393152137276;962.7304354780532;1107.7562936154034;1108.578740078765;1125.487903004131;1200.4717924113363;14275.42898284057;15332.616684582752;15342.463013794686;15420.61448463775;15683.607909802118;663162;689286;697101;704522;706458;59926.40073599208;60349.39650603526;60836.69581652063;60874.08688869671;60978;1450.260367212708;1456.2281990545027;1512.5916002679214;1526.3131590784192;1534.5242974677885;24146.034158633458;29544;29606.259843504085;30209;30311;51348.48651513587;51673.74163129311;51751.22373164481;51825;51832.740836295576;420916;421599;423042;618259;620707;25623.87188064122;26594.468110637292;26908.327291817077;26912.86543567348;26913;45138.548614513435;45633.26200427985;46139.538604613525;46448.30327545022;47243.52756472391;36884.262314754094;38094.61905380911;38439.392731909604;38455.61544384632;39020;30085.548716769703;37796;37933;38493.61506385012;38534.61465385422;32067;36884;37274.813625931696;37498.8125059373;37928.62071379251;27266.727332727212;31817.409129542968;33374.49938250977;34347;34506.7922622707;20776;24729;24867.87566062158;25604.30079642579;26226.213213603623 07:59:32 INFO - PROCESS | 1952 | |2;jquery.html;383.9347310957137;385.8765195137555;389.2604052300629;394.2848412264227;407.69830325559076;14567.125972441634;20273;20292.59414811705;21597;21615.3515394538;20760;20780.68828967563;20862.16551337945;21434.24980125696;21543.56912861742;4941.8023279068975;5653.604247702652;5801.07182850744;6027;6113.083037544359;5807.796727114531;6039.879202415949;6088.939110608903;6094.695265236743;6095.847603809896;5287.471252874701;6085.300190478085;6089.725962331706;6092.299385570672;6112.969435152818;5763.308402991626;6001.459868611824;6008.489278411318;6075.726592303357;6095.420935011189;4564.497905230422;5269.1832765921345;5270.288511050997;5295.0468915595175;5304.151335786256;24942.37644058913;25693.74306256951;26443.73556264432;26449;26815.329616759544;2072.782357852434;2135.925242616501;2203.6914831923546;2471.765182307696;2643.4845205184997;12165.878341216563;14490.275486225646;14876.702465950728;15045.699086018329;15516;1681.2686481380638;1718.3298513579714;1772.6631939931372;1821.2350812658715;1841.2634946021565;110603;116799.41600292029;117639;117872.41063794712;119631;10824.24230303871;11294.887051129548;11297.152713546353;11464.248580340658;11550.480228389742;14142.646433839236;14144.22206778637;14162.150270983773;14175.929120354333;14176.574702758933;8767.12328767131;8783.604737786814;8849.911500885037;8863.911360886308;8993.14565116313;8872.423292485968;8981.236594889377;8985.236254918274;9036.683716069905;9085.591148398338;6802.537454447318;6907.792766216921;6948.687309071104;6963.825904352431;6972.79081627552;1929.7201905723591;1942.1454559993558;1954.491832123658;1958.167778694078;1961.9509512262307;940.3088729783603;950.5769932380163;1021.667957913681;1056.2236755984345;1110.3282514078867;20971;21340.466488337916;21521.784782152292;21818.236361727264;21978.724786273386;993.7541156985266;1005.5676059294574;1017.6082208349691;1093.7429704019548;1117.4692021289823;9079.306114948939;11459.369734664508;11554.595589154507;11651.825222621668;11653.18427710069;433477;530730;591987;599906.0004699948;602137;86004;96599;113122;115792;116233;1036.5750042482546;1054.6994106679713;1072.6567498400436;1074.5272080284649;1076.262760009396;14839.10965342083;17882.5529361767;18881.527961801065;19328.613427731085;19467.870234146845;37790.811045945695;49788;49806.75096624494;50691.49308506868;51539.96920061653;67134.66432667806;70166.94749578863;70750.64624676843;72571;73059;7098.219195888345;7464.552126872405;7594.582297973665;7638.923610764044;7731.652075656497;12434.875651243372;12861.614151575468;12892.742145157232;13027.413766380347;13241;11173.944130279297;11209.943950280196;11219.270747401393;11250.437478125898;11403.315801051964;9073.59168837417;11049.723756906142;11211.215214934877;11231.943840280746;11528.538858445903;11389.487473063899;11493.482793274155;11580.6525804226;11823.586174483851;11834.704132396759;7393.149787774475;7627;7660.310572048543;7662.0422447194105;7810.336121429742;9621.27840411958;11274.647042354642;11338.206325557132;11380;11438.027767639844 07:59:32 INFO - PROCESS | 1952 | |3;mootools.html;1627.690738759636;1657.5938894970734;1921.7213504041915;1973.3981135753597;2017.7881322461133;8340;9078.409903356278;9096.499692516909;9583.856242156364;9646.324757266993;9213.401128926633;9258.490783006931;9397.671081512148;9416.482093484856;9423.34036617437;2462.261321603516;2487.800975921931;2494.7006359236825;2495.1890635543523;2495.950080998379;2438.512297540499;2467.728549859523;2475.2203056037292;2486.8632225227607;2487.2786891801356;2427.4416884116663;2443.1326878957893;2461.3231361375674;2466.334089795754;2471.641611966264;2418.709754829414;2430.720467146274;2441.694788151481;2471.6910386201725;2472.814538909589;2465.938351541208;2481.9131330403347;2496.3010357100043;2506.4360518883104;2515.421453065787;9371.109744574254;9398.95300523493;9459.810803783887;9467.816522934632;9507.19188868947;5569.470900264469;5661.46216109469;5748.626339287975;5749.252597162384;5754.338251101135;3594;3642.25333806569;3669.2478042001326;3701.9259614807825;3710.2950439416422;1039.1582817917435;1053.7945100705365;1056.889026652198;1061.6708820265742;1064.8243039898407;12314.753704925852;12456.75086498274;12484.375781210994;12491.875081249253;12500.374981250901;3818.121831978605;4692.718436893797;4802.327674125625;4955.4548999610015;5103.795848166106;10310;10539.42033188182;10569.418681972411;10599;10611.575536978435;5529.087700529368;5587.161925711176;5654.943450565524;5664.433556644302;5740.425957404226;4560.908781824412;4880.511948805162;5054.166062599704;5065.138926382524;5092.567131793839;3600.873969411058;4188.937165942513;4212.85255016073;4267;4296.334068219431;1363.8908887288992;1375.40170026037;1378.8827949624394;1378.9379477923317;1385.6466601016675;855.5893171277821;920.1396694090912;948.2650945517279;956.3879117364892;980.3039841712322;6130.754769809158;6137.263528376623;6152.846178845564;6320.905186422207;6346.524010699216;463.0992719161199;463.62214794942014;468.6742713813867;472.98344557940305;480.7860502076542;3984.1633257015774;4125.628693417607;4132.586741325843;4167.645750111214;4218.514870789899;11078.667639970814;11344.659660210207;11395.145364097725;11559.30644161353;11583.07335413165;11345.319280843176;11387.715307117223;11508.712282193012;11519.596814111466;11537;7128.966299886488;8282.461639993382;8679.17547832955;8685.739427817176;8686.739397818075;5146.202338637517;5208.427073021889;5679.864027194495;5772.307323121252;6100.938990610037;8888.6000129993;10101.14140298083;10660.520276587727;10701;10710.410927399067;10445.9477702611;10746.623868164577;10831;10874.401907895142;10901.964313390215;6562.967185164043;7289.088863892013;7723;7972.561509117054;7974.760757177294;8790.208881200468;8865.601047952987;8897.243734282658;8948.955255223942;8979.640814367353;7740.922590774246;7819.178986206366;7954.482958607672;8068.677252909818;8089.433739638169;7775.22247775218;7871;7882.921170788219;7931.484453510503;8041.959790201012;7170.856582868209;7381.04046473955;7414.851702965802;7450.180480147288;7459.254074592497;4401.2297847877135;5110.310108135503;5159.226116082617;5237.895242095061;5276.234945932895;5200.843974680765;5263.6052296078715;5313.681179129265;5424.484673955968;5459.372172200089 07:59:32 INFO - PROCESS | 1952 | |4;prototype.html;314.47953636731205;318.21083712393266;319.95056215612783;321.4615519005665;325.1448689945443;5404.32445944257;5474.0019892739165;5504.889902201953;5514.3658479274845;5526.198701188326;4697.882552936178;5149.742512874351;5288.5504732097725;5366.738816378148;5379.35447746271;3734.6078661740535;3894.922101557967;3956.0417593291363;4057.5333836608875;4123.711340206171;3637.5453068366455;3715;3749.362608356586;3993.1015521507575;4110.917781644365;3723.4414837774416;4051.8784436466804;4053.9594604054023;4070.470838790953;4118.361653943629;3680.4847321375028;3947.881563553098;3975.5428125765616;4078.490188726409;4096.283150448675;2814.802963792535;2968.9703102969124;3392.915177120567;3408.2672225471642;3410.9658903410896;4390.560943905616;5090.796368145269;5099.745012749348;5129.204973229155;5200.531952124289;4225.471816022997;4341;4448.398576512453;4568.291914753218;4570.223062079454;4325.740455572675;4702.3416721659;4837.758112094416;5398.676079435247;5592.804251851207;3731.5522137343423;3749.7000239981007;3770.698344132463;3782.508273924372;3795;5123.897522049539;5241.633085684004;5732.770689172428;5811.767529298865;5935.347111817697;3431.605365382963;3922.490076290064;3949.19041596472;3955.228730397545;3971.517801374141;3196.8241746704152;3837.501124853751;3999.420084087791;4007.4189242559664;4008.759474431485;4635.3742244797195;4648.5119062498625;4649.4420669519195;4654.394928659321;4659.930101048487;4928.728919909439;5400.027994960867;5487.533559647395;5706.229658995982;5788.581797459649;1313.7766579681286;1334.4928927007868;1340.9406568810725;1342.2483658825327;1348.1035111650642;814.686345756876;822.296936119621;839.6179738219096;851.3274513134554;877.9517126557973;4334.674899382559;4579.313103034571;5267.15649522664;5271.551918086929;5280.234366016906;4816.325714399987;5346.957343318093;5408.810691625774;5743.597948143673;5793.8841223175305;4486.573775491323;4886.364772579542;5058.4435712071645;5175.990681817085;5281.973590132025;2815.142006275848;2839.772818174541;3012.186709588399;3042.9695703042685;3064.8161110333454;6013;6026.397360264026;6040.818775436744;6050.757969681164;6077.20996270482;5071.822486213038;5711.771529138871;5907.527397808165;5955.821325360246;5993.430624090704;4665.673402861835;5842.561807864512;6039.969800150971;6042.335343112342;6054.516643422389;4634.169503046211;4896.583790377857;5346.465353464622;5413.458654134555;5497.47773961473;4654.743989080498;5241.580673546184;5444.673319600836;5608.728464971693;5656.387929440254;6008.969955150196;6018.682807119203;6023.608465449907;6026.186464827192;6054.455099040932;3756.530433695788;3883.165119499321;3901.51231096113;4064.841520166738;4078.53096893861;5815.709214539172;5826.41735826414;5831.446012628793;5857;5870.7945221917025;4829.637777166657;5092.567131793839;5630.60585758993;5717.8570535736935;5725.112607545837;5132.10188217053;5252.133397989289;5497.367802702581;5613.494785469327;5688.857778555569;5306.567226848806;5363.678179309254;5450.427705090909;5531.091773338192;5572.801847602785;4615.515370886143;4853.805847766191;5479.849231661396;5572.60991730575;5753.90675771602;5627.155926611041;5633.718314084362;5636.351819540641;5655.264815573949;5684.8294551163535 07:59:32 INFO - PROCESS | 1952 | |5;yui.html;642.2068745099804;661.4443867151592;734.3611058379211;740.785172300033;757.3486801350841;14912.17983010934;15234;15273.083614983096;15329.923350383233;15463.22683865805;14179.503717369867;14235;14283.857161428383;14286.785698214535;14327.212003339811;722.3679280629449;906.188960880012;918.3158546882565;933.1088810186255;937.9062093790601;913.6482454255126;918.2699753695828;918.8346097702411;919.9540022998857;922.3866129024191;945.394947233771;958.3339578992603;971.1890571372912;972.9756756081084;973.7224890906093;804.7625950344662;804.8430556041574;809.3039985612369;935.7333160049421;961.6586111930234;707.7133760826873;711.903892974447;714.2143641993765;715.6994062493713;716.2729829223342;12304.384780760925;14832.406703731838;14849.183294918776;14868.776968345483;14923;11148.05241554469;12413.255204687748;12570.622881313575;12597.433115509824;12637.368131593383;9787.62787613031;10107.140893024101;10115.7471063224;10137;10634.787304253949;3417.820851806123;3466.757326987112;3470.6182319944787;3506.7194624429985;3584.8207589620415;58580.70709646467;69694.65152674254;82329;82729.345413092;87738.56130719306;9277.67528136512;9413.670521531714;9539.427634341962;9543.33196676226;9721.46531940736;21330.78669213319;21346.146154153972;21375.717456952632;21557.78442215558;21675.891620541795;7581.658825352873;7620.642833780976;7648.08223013242;7653.4642575020325;7867.6459559319965;4027.7986100695434;4085.9387109193385;4086.6117718816663;4088.7546747195265;4095.140020595649;2997.385535965122;3591.9102022449124;3673.3571624965525;3692.5015122958594;3703.333399988028;608.1880689279773;640.9871802563922;641.3906788550895;659.5251418978328;710.6944014073895;517.2654830141133;527.8442859356499;587.717895410197;592.3157957504317;607.872545416625;6871.622060786704;7887.921120788834;8109.229623185788;8324.334053275723;8327.95836020816;522.4984015345235;597.596622279968;603.1585937617;622.7260005597614;638.9776357827438;3630.40098383769;3799.6960243181034;3985.342418500915;4002.899927501836;4010.258102251093;47604.7619761899;72715;75452.62949096164;93774.5311273453;95657.04342956482;85088.57455712682;85454;85500.57249713711;87400;87938.24123517622;77276.61361693268;77779;78294;78326.21673783303;78684.6065769679;7212.278772122746;7757.534547927143;7767.650455729393;7772.891877909958;7887.290143887078;44986.77506612446;45856.31215531837;46695.29957050579;47019;47500.52499474961;62914.685426574404;64427;65030.674846625465;65419.34580654323;66111;9750.609975600897;10119.79760404773;10213.795724085328;10241.539130739284;10414.791704166026;10710;10752.569897204025;10846.349219046882;11043.889561104286;11485.827712584485;10637.734056648645;10656.307340022873;10759.139268858316;10767.569297228023;10783.784324313627;9230.723078307661;10549.208809339178;10648.254622176373;10686.465676716289;10724.088452481627;8834.558272086499;10194.337368071052;10575.36547807134;10640;10713.839292410776;8499.277561407102;8834.249088827522;9041.457512549268;9077.273818094669;9278;8230.958845205736;8467.49195048299;8754.168354006359;9115.815444371357;9152.405093668891 07:59:32 INFO - PROCESS | 1952 | __end_tp_report 07:59:32 INFO - PROCESS | 1952 | __start_cc_report 07:59:32 INFO - PROCESS | 1952 | _x_x_mozilla_cycle_collect,418 07:59:32 INFO - PROCESS | 1952 | __end_cc_report 07:59:32 INFO - PROCESS | 1952 | __startTimestamp1463151572583__endTimestamp 07:59:32 INFO - PROCESS | 1952 | 07:59:32 INFO - PROCESS | 1952 | ------- Summary: start ------- 07:59:32 INFO - PROCESS | 1952 | Number of tests: 6 07:59:32 INFO - PROCESS | 1952 | 07:59:32 INFO - PROCESS | 1952 | [#0] dojo.html Cycles:165 Average:5757.83 Median:6063.76 stddev:787.18 (13.0%) stddev-sans-first:768.78 07:59:32 INFO - PROCESS | 1952 | Values: 3465.5 3466.2 3475.0 3487.7 3507.8 5741.0 5852.0 5873.0 5890.1 5909.3 4458.4 5717.8 5745.6 5865.3 5925.3 5198.0 5228.2 5303.4 5371.8 5374.6 638.1 5194.2 5250.2 5276.3 5303.2 4362.4 5056.6 5252.8 5269.6 5322.0 5271.8 5307.2 5324.8 5354.2 5360.6 5132.4 5235.6 5270.1 5270.4 5325.7 5439.4 5663.2 5753.3 5844.4 5855.9 6149.6 6230.1 6233.0 6251.6 6259.1 5989.6 6160.7 6184.3 6240.7 6289.0 5342.5 6000.9 6011.5 6027.6 6040.8 6205.7 6231.3 6323.1 6357.2 6385.2 6146.8 6152.7 6169.0 6173.4 6209.8 6227.0 6240.9 6265.2 6303.7 6316.9 6211.9 6238.9 6294.6 6326.8 6375.6 6213.6 6225.8 6244.9 6251.9 6257.7 4938.6 4997.9 4999.1 5019.0 5025.8 3454.7 4136.9 4428.5 4459.0 4482.0 5080.7 5961.4 5989.8 6002.3 6057.2 6251.1 6290.4 6291.9 6301.0 6301.0 5915.1 5964.3 5975.7 6023.4 6063.8 5467.4 5472.7 5476.0 5494.1 5516.0 6420.3 6441.7 6446.0 6451.3 6456.8 6153.1 6211.3 6224.3 6231.6 6284.0 6461.6 6483.4 6494.0 6495.3 6509.3 6144.6 6228.5 6229.3 6231.9 6255.1 5727.0 5948.7 6172.5 6181.3 6280.6 6227.3 6243.6 6265.3 6290.2 6302.1 5257.7 6142.9 6161.9 6204.4 6251.0 5885.9 5987.5 6108.5 6108.6 6127.5 5070.5 6262.2 6266.0 6279.4 6296.3 5944.9 6005.6 6081.9 6147.4 6173.5 07:59:32 INFO - PROCESS | 1952 | 07:59:32 INFO - PROCESS | 1952 | [#1] ext.html Cycles:180 Average:70283.10 Median:38911.40 stddev:156851.37 (403.1%) stddev-sans-first:157203.93 07:59:32 INFO - PROCESS | 1952 | Values: 524.3 530.2 530.3 542.9 636.8 39443.6 41609.8 41633.1 41634.0 41678.2 41656.4 42028.7 42264.6 42288.8 42300.8 9458.2 9577.2 9688.6 9692.4 9716.7 9627.3 9678.8 9700.8 9710.1 9712.0 9702.6 9705.1 9721.8 9739.3 9739.7 9657.1 9681.6 9696.7 9704.7 9736.8 9518.3 9614.1 9626.2 9635.3 9637.8 38802.8 39032.2 39097.8 39168.6 39239.6 2564.5 2808.4 3022.4 3266.6 3362.8 28014.6 28824.1 29318.0 29907.4 30017.5 2715.2 2717.8 2757.9 2794.5 2870.0 530611.0 531154.0 541038.3 541378.0 542540.3 64587.0 64948.0 65210.3 65232.0 65313.0 36291.8 36407.8 37009.0 37051.8 68836.7 14135.9 16205.0 16669.8 16941.9 16950.8 11735.2 12438.1 13051.5 13165.9 13174.8 19227.1 19787.0 19840.6 19947.1 20005.6 8481.8 8482.2 8527.3 8528.4 8546.3 948.4 957.6 1015.4 1100.7 1118.4 24820.0 25240.5 29144.7 29768.4 30342.4 962.7 1107.8 1108.6 1125.5 1200.5 14275.4 15332.6 15342.5 15420.6 15683.6 663162.0 689286.0 697101.0 704522.0 706458.0 59926.4 60349.4 60836.7 60874.1 60978.0 1450.3 1456.2 1512.6 1526.3 1534.5 24146.0 29544.0 29606.3 30209.0 30311.0 51348.5 51673.7 51751.2 51825.0 51832.7 420916.0 421599.0 423042.0 618259.0 620707.0 25623.9 26594.5 26908.3 26912.9 26913.0 45138.5 45633.3 46139.5 46448.3 47243.5 36884.3 38094.6 38439.4 38455.6 39020.0 30085.5 37796.0 37933.0 38493.6 38534.6 32067.0 36884.0 37274.8 37498.8 37928.6 27266.7 31817.4 33374.5 34347.0 34506.8 20776.0 24729.0 24867.9 25604.3 26226.2 07:59:32 INFO - PROCESS | 1952 | 07:59:32 INFO - PROCESS | 1952 | [#2] jquery.html Cycles:180 Average:32932.26 Median:21606.18 stddev:92422.47 (427.8%) stddev-sans-first:92649.42 07:59:32 INFO - PROCESS | 1952 | Values: 383.9 385.9 389.3 394.3 407.7 14567.1 20273.0 20292.6 21597.0 21615.4 20760.0 20780.7 20862.2 21434.2 21543.6 4941.8 5653.6 5801.1 6027.0 6113.1 5807.8 6039.9 6088.9 6094.7 6095.8 5287.5 6085.3 6089.7 6092.3 6113.0 5763.3 6001.5 6008.5 6075.7 6095.4 4564.5 5269.2 5270.3 5295.0 5304.2 24942.4 25693.7 26443.7 26449.0 26815.3 2072.8 2135.9 2203.7 2471.8 2643.5 12165.9 14490.3 14876.7 15045.7 15516.0 1681.3 1718.3 1772.7 1821.2 1841.3 110603.0 116799.4 117639.0 117872.4 119631.0 10824.2 11294.9 11297.2 11464.2 11550.5 14142.6 14144.2 14162.2 14175.9 14176.6 8767.1 8783.6 8849.9 8863.9 8993.1 8872.4 8981.2 8985.2 9036.7 9085.6 6802.5 6907.8 6948.7 6963.8 6972.8 1929.7 1942.1 1954.5 1958.2 1962.0 940.3 950.6 1021.7 1056.2 1110.3 20971.0 21340.5 21521.8 21818.2 21978.7 993.8 1005.6 1017.6 1093.7 1117.5 9079.3 11459.4 11554.6 11651.8 11653.2 433477.0 530730.0 591987.0 599906.0 602137.0 86004.0 96599.0 113122.0 115792.0 116233.0 1036.6 1054.7 1072.7 1074.5 1076.3 14839.1 17882.6 18881.5 19328.6 19467.9 37790.8 49788.0 49806.8 50691.5 51540.0 67134.7 70166.9 70750.6 72571.0 73059.0 7098.2 7464.6 7594.6 7638.9 7731.7 12434.9 12861.6 12892.7 13027.4 13241.0 11173.9 11209.9 11219.3 11250.4 11403.3 9073.6 11049.7 11211.2 11231.9 11528.5 11389.5 11493.5 11580.7 11823.6 11834.7 7393.1 7627.0 7660.3 7662.0 7810.3 9621.3 11274.6 11338.2 11380.0 11438.0 07:59:32 INFO - PROCESS | 1952 | 07:59:32 INFO - PROCESS | 1952 | [#3] mootools.html Cycles:180 Average:5990.81 Median:2582.85 stddev:3427.16 (132.7%) stddev-sans-first:3421.09 07:59:32 INFO - PROCESS | 1952 | Values: 1627.7 1657.6 1921.7 1973.4 2017.8 8340.0 9078.4 9096.5 9583.9 9646.3 9213.4 9258.5 9397.7 9416.5 9423.3 2462.3 2487.8 2494.7 2495.2 2496.0 2438.5 2467.7 2475.2 2486.9 2487.3 2427.4 2443.1 2461.3 2466.3 2471.6 2418.7 2430.7 2441.7 2471.7 2472.8 2465.9 2481.9 2496.3 2506.4 2515.4 9371.1 9399.0 9459.8 9467.8 9507.2 5569.5 5661.5 5748.6 5749.3 5754.3 3594.0 3642.3 3669.2 3701.9 3710.3 1039.2 1053.8 1056.9 1061.7 1064.8 12314.8 12456.8 12484.4 12491.9 12500.4 3818.1 4692.7 4802.3 4955.5 5103.8 10310.0 10539.4 10569.4 10599.0 10611.6 5529.1 5587.2 5654.9 5664.4 5740.4 4560.9 4880.5 5054.2 5065.1 5092.6 3600.9 4188.9 4212.9 4267.0 4296.3 1363.9 1375.4 1378.9 1378.9 1385.6 855.6 920.1 948.3 956.4 980.3 6130.8 6137.3 6152.8 6320.9 6346.5 463.1 463.6 468.7 473.0 480.8 3984.2 4125.6 4132.6 4167.6 4218.5 11078.7 11344.7 11395.1 11559.3 11583.1 11345.3 11387.7 11508.7 11519.6 11537.0 7129.0 8282.5 8679.2 8685.7 8686.7 5146.2 5208.4 5679.9 5772.3 6100.9 8888.6 10101.1 10660.5 10701.0 10710.4 10445.9 10746.6 10831.0 10874.4 10902.0 6563.0 7289.1 7723.0 7972.6 7974.8 8790.2 8865.6 8897.2 8949.0 8979.6 7740.9 7819.2 7954.5 8068.7 8089.4 7775.2 7871.0 7882.9 7931.5 8042.0 7170.9 7381.0 7414.9 7450.2 7459.3 4401.2 5110.3 5159.2 5237.9 5276.2 5200.8 5263.6 5313.7 5424.5 5459.4 07:59:32 INFO - PROCESS | 1952 | 07:59:32 INFO - PROCESS | 1952 | [#4] prototype.html Cycles:180 Average:4524.01 Median:5091.68 stddev:1404.59 (27.6%) stddev-sans-first:1372.54 07:59:32 INFO - PROCESS | 1952 | Values: 314.5 318.2 320.0 321.5 325.1 5404.3 5474.0 5504.9 5514.4 5526.2 4697.9 5149.7 5288.6 5366.7 5379.4 3734.6 3894.9 3956.0 4057.5 4123.7 3637.5 3715.0 3749.4 3993.1 4110.9 3723.4 4051.9 4054.0 4070.5 4118.4 3680.5 3947.9 3975.5 4078.5 4096.3 2814.8 2969.0 3392.9 3408.3 3411.0 4390.6 5090.8 5099.7 5129.2 5200.5 4225.5 4341.0 4448.4 4568.3 4570.2 4325.7 4702.3 4837.8 5398.7 5592.8 3731.6 3749.7 3770.7 3782.5 3795.0 5123.9 5241.6 5732.8 5811.8 5935.3 3431.6 3922.5 3949.2 3955.2 3971.5 3196.8 3837.5 3999.4 4007.4 4008.8 4635.4 4648.5 4649.4 4654.4 4659.9 4928.7 5400.0 5487.5 5706.2 5788.6 1313.8 1334.5 1340.9 1342.2 1348.1 814.7 822.3 839.6 851.3 878.0 4334.7 4579.3 5267.2 5271.6 5280.2 4816.3 5347.0 5408.8 5743.6 5793.9 4486.6 4886.4 5058.4 5176.0 5282.0 2815.1 2839.8 3012.2 3043.0 3064.8 6013.0 6026.4 6040.8 6050.8 6077.2 5071.8 5711.8 5907.5 5955.8 5993.4 4665.7 5842.6 6040.0 6042.3 6054.5 4634.2 4896.6 5346.5 5413.5 5497.5 4654.7 5241.6 5444.7 5608.7 5656.4 6009.0 6018.7 6023.6 6026.2 6054.5 3756.5 3883.2 3901.5 4064.8 4078.5 5815.7 5826.4 5831.4 5857.0 5870.8 4829.6 5092.6 5630.6 5717.9 5725.1 5132.1 5252.1 5497.4 5613.5 5688.9 5306.6 5363.7 5450.4 5531.1 5572.8 4615.5 4853.8 5479.8 5572.6 5753.9 5627.2 5633.7 5636.4 5655.3 5684.8 07:59:32 INFO - PROCESS | 1952 | 07:59:32 INFO - PROCESS | 1952 | [#5] yui.html Cycles:180 Average:17812.12 Median:641.19 stddev:25362.61 (3955.6%) stddev-sans-first:25401.00 07:59:32 INFO - PROCESS | 1952 | Values: 642.2 661.4 734.4 740.8 757.3 14912.2 15234.0 15273.1 15329.9 15463.2 14179.5 14235.0 14283.9 14286.8 14327.2 722.4 906.2 918.3 933.1 937.9 913.6 918.3 918.8 920.0 922.4 945.4 958.3 971.2 973.0 973.7 804.8 804.8 809.3 935.7 961.7 707.7 711.9 714.2 715.7 716.3 12304.4 14832.4 14849.2 14868.8 14923.0 11148.1 12413.3 12570.6 12597.4 12637.4 9787.6 10107.1 10115.7 10137.0 10634.8 3417.8 3466.8 3470.6 3506.7 3584.8 58580.7 69694.7 82329.0 82729.3 87738.6 9277.7 9413.7 9539.4 9543.3 9721.5 21330.8 21346.1 21375.7 21557.8 21675.9 7581.7 7620.6 7648.1 7653.5 7867.6 4027.8 4085.9 4086.6 4088.8 4095.1 2997.4 3591.9 3673.4 3692.5 3703.3 608.2 641.0 641.4 659.5 710.7 517.3 527.8 587.7 592.3 607.9 6871.6 7887.9 8109.2 8324.3 8328.0 522.5 597.6 603.2 622.7 639.0 3630.4 3799.7 3985.3 4002.9 4010.3 47604.8 72715.0 75452.6 93774.5 95657.0 85088.6 85454.0 85500.6 87400.0 87938.2 77276.6 77779.0 78294.0 78326.2 78684.6 7212.3 7757.5 7767.7 7772.9 7887.3 44986.8 45856.3 46695.3 47019.0 47500.5 62914.7 64427.0 65030.7 65419.3 66111.0 9750.6 10119.8 10213.8 10241.5 10414.8 10710.0 10752.6 10846.3 11043.9 11485.8 10637.7 10656.3 10759.1 10767.6 10783.8 9230.7 10549.2 10648.3 10686.5 10724.1 8834.6 10194.3 10575.4 10640.0 10713.8 8499.3 8834.2 9041.5 9077.3 9278.0 8231.0 8467.5 8754.2 9115.8 9152.4 07:59:32 INFO - PROCESS | 1952 | -------- Summary: end -------- 07:59:32 INFO - PROCESS | 1952 | 07:59:33 INFO - TEST-INFO | 1952: exit 0 07:59:33 INFO - TEST-OK | dromaeo_css | took 1112691ms 07:59:33 INFO - TEST-START | kraken 07:59:33 INFO - operating with platform_type : mac_ 07:59:33 INFO - Initialising browser for kraken test... 07:59:33 INFO - TEST-INFO | started process 1958 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/tmpTnPn_O/profile http://localhost:52228/getInfo.html) 07:59:36 INFO - PROCESS | 1958 | __metrics Screen width/height:1600/1200 07:59:36 INFO - PROCESS | 1958 | colorDepth:24 07:59:36 INFO - PROCESS | 1958 | Browser inner width/height: 1024/689 07:59:36 INFO - PROCESS | 1958 | __metrics 07:59:36 ERROR - PROCESS | 1958 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 07:59:36 INFO - TEST-INFO | 1958: exit 0 07:59:36 INFO - Browser initialized. 07:59:36 INFO - Running cycle 1/1 for kraken test... 07:59:36 INFO - TEST-INFO | started process 1961 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/tmpTnPn_O/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:59:48 INFO - PROCESS | 1961 | RSS: Main: 249729024 07:59:48 INFO - PROCESS | 1961 | 07:59:51 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:52228/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52228/tests/kraken/driver.html?testName=audio-beat-detection) 07:59:52 INFO - PROCESS | 1961 | RSS: Main: 261697536 07:59:52 INFO - PROCESS | 1961 | 07:59:55 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:52228/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52228/tests/kraken/driver.html?testName=audio-dft) 07:59:55 INFO - PROCESS | 1961 | RSS: Main: 266391552 07:59:55 INFO - PROCESS | 1961 | 07:59:59 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:52228/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52228/tests/kraken/driver.html?testName=audio-fft) 08:00:00 INFO - PROCESS | 1961 | RSS: Main: 267214848 08:00:00 INFO - PROCESS | 1961 | 08:00:02 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:52228/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52228/tests/kraken/driver.html?testName=audio-oscillator) 08:00:02 INFO - PROCESS | 1961 | RSS: Main: 267726848 08:00:02 INFO - PROCESS | 1961 | 08:00:04 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:52228/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52228/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:00:05 INFO - PROCESS | 1961 | RSS: Main: 267800576 08:00:05 INFO - PROCESS | 1961 | 08:00:08 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:52228/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52228/tests/kraken/driver.html?testName=imaging-darkroom) 08:00:09 INFO - PROCESS | 1961 | RSS: Main: 267706368 08:00:09 INFO - PROCESS | 1961 | 08:00:12 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:52228/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52228/tests/kraken/driver.html?testName=imaging-desaturate) 08:00:12 INFO - PROCESS | 1961 | RSS: Main: 267964416 08:00:12 INFO - PROCESS | 1961 | 08:00:16 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:52228/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52228/tests/kraken/driver.html?testName=json-parse-financial) 08:00:16 INFO - PROCESS | 1961 | RSS: Main: 268111872 08:00:16 INFO - PROCESS | 1961 | 08:00:18 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:52228/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52228/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:00:19 INFO - PROCESS | 1961 | RSS: Main: 268075008 08:00:19 INFO - PROCESS | 1961 | 08:00:21 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:52228/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52228/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:00:21 INFO - PROCESS | 1961 | RSS: Main: 268029952 08:00:21 INFO - PROCESS | 1961 | 08:00:24 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:52228/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52228/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:00:24 INFO - PROCESS | 1961 | RSS: Main: 268210176 08:00:24 INFO - PROCESS | 1961 | 08:00:27 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:52228/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:00:28 INFO - PROCESS | 1961 | RSS: Main: 268300288 08:00:28 INFO - PROCESS | 1961 | 08:00:31 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:52228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:00:32 INFO - PROCESS | 1961 | RSS: Main: 268263424 08:00:32 INFO - PROCESS | 1961 | 08:00:34 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:52228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52228/tests/kraken/driver.html?testName=ai-astar) 08:00:34 INFO - PROCESS | 1961 | RSS: Main: 271007744 08:00:34 INFO - PROCESS | 1961 | 08:00:34 INFO - PROCESS | 1961 | __start_tp_report 08:00:34 INFO - PROCESS | 1961 | _x_x_mozilla_page_load 08:00:34 INFO - PROCESS | 1961 | _x_x_mozilla_page_load_details 08:00:34 INFO - PROCESS | 1961 | |i|pagename|runs| 08:00:34 INFO - PROCESS | 1961 | |0;ai-astar;114;116;108;117;115;116;108;115;117;115 08:00:34 INFO - PROCESS | 1961 | |1;audio-beat-detection;160;142;137;259;157;172;152;123;169;140 08:00:34 INFO - PROCESS | 1961 | |2;audio-dft;195;203;253;217;241;201;199;201;199;196 08:00:34 INFO - PROCESS | 1961 | |3;audio-fft;74;78;95;204;84;78;60;65;131;71 08:00:34 INFO - PROCESS | 1961 | |4;audio-oscillator;82;84;150;78;81;85;124;81;80;129 08:00:34 INFO - PROCESS | 1961 | |5;imaging-gaussian-blur;88;88;92;77;135;113;91;86;89;86 08:00:34 INFO - PROCESS | 1961 | |6;imaging-darkroom;94;96;95;95;93;104;95;95;96;93 08:00:34 INFO - PROCESS | 1961 | |7;imaging-desaturate;93;129;142;128;90;117;95;75;145;145 08:00:34 INFO - PROCESS | 1961 | |8;json-parse-financial;60;59;60;56;60;59;59;126;84;91 08:00:34 INFO - PROCESS | 1961 | |9;json-stringify-tinderbox;68;69;72;68;72;73;70;69;68;66 08:00:34 INFO - PROCESS | 1961 | |10;stanford-crypto-aes;102;111;114;110;111;87;85;87;90;88 08:00:34 INFO - PROCESS | 1961 | |11;stanford-crypto-ccm;148;159;130;138;138;138;136;141;247;152 08:00:34 INFO - PROCESS | 1961 | |12;stanford-crypto-pbkdf2;173;228;215;166;165;171;171;226;172;168 08:00:34 INFO - PROCESS | 1961 | |13;stanford-crypto-sha256-iterative;67;67;71;78;88;85;84;88;88;79 08:00:34 INFO - PROCESS | 1961 | __end_tp_report 08:00:34 INFO - PROCESS | 1961 | __start_cc_report 08:00:34 INFO - PROCESS | 1961 | _x_x_mozilla_cycle_collect,1088 08:00:34 INFO - PROCESS | 1961 | __end_cc_report 08:00:34 INFO - PROCESS | 1961 | __startTimestamp1463151634400__endTimestamp 08:00:34 INFO - PROCESS | 1961 | 08:00:34 INFO - PROCESS | 1961 | ------- Summary: start ------- 08:00:34 INFO - PROCESS | 1961 | Number of tests: 14 08:00:34 INFO - PROCESS | 1961 | 08:00:34 INFO - PROCESS | 1961 | [#0] ai-astar Cycles:10 Average:114.10 Median:115.50 stddev:3.35 (2.9%) stddev-sans-first:3.55 08:00:34 INFO - PROCESS | 1961 | Values: 114.0 116.0 108.0 117.0 115.0 116.0 108.0 115.0 117.0 115.0 08:00:34 INFO - PROCESS | 1961 | 08:00:34 INFO - PROCESS | 1961 | [#1] audio-beat-detection Cycles:10 Average:161.10 Median:158.50 stddev:37.58 (23.7%) stddev-sans-first:39.86 08:00:34 INFO - PROCESS | 1961 | Values: 160.0 142.0 137.0 259.0 157.0 172.0 152.0 123.0 169.0 140.0 08:00:34 INFO - PROCESS | 1961 | 08:00:34 INFO - PROCESS | 1961 | [#2] audio-dft Cycles:10 Average:210.50 Median:202.00 stddev:20.36 (10.1%) stddev-sans-first:20.81 08:00:34 INFO - PROCESS | 1961 | Values: 195.0 203.0 253.0 217.0 241.0 201.0 199.0 201.0 199.0 196.0 08:00:34 INFO - PROCESS | 1961 | 08:00:34 INFO - PROCESS | 1961 | [#3] audio-fft Cycles:10 Average:94.00 Median:76.00 stddev:43.47 (57.2%) stddev-sans-first:45.50 08:00:34 INFO - PROCESS | 1961 | Values: 74.0 78.0 95.0 204.0 84.0 78.0 60.0 65.0 131.0 71.0 08:00:34 INFO - PROCESS | 1961 | 08:00:34 INFO - PROCESS | 1961 | [#4] audio-oscillator Cycles:10 Average:97.40 Median:81.00 stddev:26.37 (32.6%) stddev-sans-first:27.38 08:00:34 INFO - PROCESS | 1961 | Values: 82.0 84.0 150.0 78.0 81.0 85.0 124.0 81.0 80.0 129.0 08:00:34 INFO - PROCESS | 1961 | 08:00:34 INFO - PROCESS | 1961 | [#5] imaging-gaussian-blur Cycles:10 Average:94.50 Median:88.00 stddev:16.89 (19.2%) stddev-sans-first:17.75 08:00:34 INFO - PROCESS | 1961 | Values: 88.0 88.0 92.0 77.0 135.0 113.0 91.0 86.0 89.0 86.0 08:00:34 INFO - PROCESS | 1961 | 08:00:34 INFO - PROCESS | 1961 | [#6] imaging-darkroom Cycles:10 Average:95.60 Median:95.00 stddev:3.13 (3.3%) stddev-sans-first:3.27 08:00:34 INFO - PROCESS | 1961 | Values: 94.0 96.0 95.0 95.0 93.0 104.0 95.0 95.0 96.0 93.0 08:00:34 INFO - PROCESS | 1961 | 08:00:34 INFO - PROCESS | 1961 | [#7] imaging-desaturate Cycles:10 Average:115.90 Median:110.00 stddev:25.82 (23.5%) stddev-sans-first:26.02 08:00:34 INFO - PROCESS | 1961 | Values: 93.0 129.0 142.0 128.0 90.0 117.0 95.0 75.0 145.0 145.0 08:00:34 INFO - PROCESS | 1961 | 08:00:34 INFO - PROCESS | 1961 | [#8] json-parse-financial Cycles:10 Average:71.40 Median:60.00 stddev:22.64 (37.7%) stddev-sans-first:23.63 08:00:34 INFO - PROCESS | 1961 | Values: 60.0 59.0 60.0 56.0 60.0 59.0 59.0 126.0 84.0 91.0 08:00:34 INFO - PROCESS | 1961 | 08:00:34 INFO - PROCESS | 1961 | [#9] json-stringify-tinderbox Cycles:10 Average:69.50 Median:69.50 stddev:2.22 (3.2%) stddev-sans-first:2.29 08:00:34 INFO - PROCESS | 1961 | Values: 68.0 69.0 72.0 68.0 72.0 73.0 70.0 69.0 68.0 66.0 08:00:34 INFO - PROCESS | 1961 | 08:00:34 INFO - PROCESS | 1961 | [#10] stanford-crypto-aes Cycles:10 Average:98.50 Median:86.00 stddev:12.14 (14.1%) stddev-sans-first:12.81 08:00:34 INFO - PROCESS | 1961 | Values: 102.0 111.0 114.0 110.0 111.0 87.0 85.0 87.0 90.0 88.0 08:00:34 INFO - PROCESS | 1961 | 08:00:34 INFO - PROCESS | 1961 | [#11] stanford-crypto-ccm Cycles:10 Average:152.70 Median:144.50 stddev:34.21 (23.7%) stddev-sans-first:36.24 08:00:34 INFO - PROCESS | 1961 | Values: 148.0 159.0 130.0 138.0 138.0 138.0 136.0 141.0 247.0 152.0 08:00:34 INFO - PROCESS | 1961 | 08:00:34 INFO - PROCESS | 1961 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.50 Median:172.50 stddev:26.21 (15.2%) stddev-sans-first:27.41 08:00:34 INFO - PROCESS | 1961 | Values: 173.0 228.0 215.0 166.0 165.0 171.0 171.0 226.0 172.0 168.0 08:00:34 INFO - PROCESS | 1961 | 08:00:34 INFO - PROCESS | 1961 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:79.50 Median:84.50 stddev:8.53 (10.1%) stddev-sans-first:7.75 08:00:34 INFO - PROCESS | 1961 | Values: 67.0 67.0 71.0 78.0 88.0 85.0 84.0 88.0 88.0 79.0 08:00:34 INFO - PROCESS | 1961 | -------- Summary: end -------- 08:00:34 INFO - PROCESS | 1961 | 08:00:35 INFO - TEST-INFO | 1961: exit 0 08:00:35 INFO - TEST-OK | kraken | took 62043ms 08:00:35 INFO - SUITE-END | took 1174s 08:00:35 INFO - Completed test suite (00:19:35) 08:00:35 INFO - javascript benchmark 08:00:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7221.850758264619, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3465.4628532577453, 3466.150793055702, 3474.982625086874, 3487.703545198658, 3507.7895326280427, 5740.971295143524, 5851.970740146293, 5872.970635146829, 5890.145928840316, 5909.290885093789, 4458.353538736881, 5717.771289148429, 5745.5690823188215, 5865.29616446026, 5925.259342582177, 5198.012377648253, 5228.163493840986, 5303.390110137326, 5371.81198658047, 5374.6237763356585, 638.0922293503661, 5194.194899790519, 5250.18622113573, 5276.287701160351, 5303.204519322094, 4362.432883725127, 5056.609432406081, 5252.816151434681, 5269.604779641542, 5321.973390133063, 5271.762770675329, 5307.150855863082, 5324.813631522917, 5354.223637572531, 5360.597955153379, 5132.40977287613, 5235.58115350771, 5270.130428479335, 5270.367555893317, 5325.707086110262, 5439.401665816757, 5663.1505274208785, 5753.309602847685, 5844.386339434383, 5855.882882342372, 6149.631022138686, 6230.096635987757, 6233.0, 6251.624902505819, 6259.123722678829, 5989.622386851049, 6160.722767475474, 6184.257889053298, 6240.656763877985, 6288.968555157195, 5342.492463215988, 6000.909986350208, 6011.549133814981, 6027.638341699512, 6040.7583696651645, 6205.6586887721605, 6231.2522497300615, 6323.146375239376, 6357.237131544244, 6385.201849768779, 6146.801373732169, 6152.738688568833, 6169.01295792671, 6173.382661733791, 6209.782657607052, 6226.968865155736, 6240.875182496416, 6265.185525881606, 6303.747850086037, 6316.905246421307, 6211.875762484725, 6238.908191066545, 6294.559380843297, 6326.778562750282, 6375.553711240169, 6213.596116252519, 6225.782097626652, 6244.937550624527, 6251.874062968515, 6257.7184026718905, 4938.555530002317, 4997.85006449807, 4999.100161970808, 5018.974905125451, 5025.84922452327, 3454.7236221102253, 4136.896577585584, 4428.512863585003, 4458.955410445854, 4481.991551900796, 5080.695158290514, 5961.374055724174, 5989.760409583655, 6002.279726432768, 6057.18228039209, 6251.060305168349, 6290.433860952445, 6291.874162516724, 6300.9684951574945, 6301.023341382093, 5915.053591425351, 5964.31410387811, 5975.731092100866, 6023.367546407651, 6063.7574497020505, 5467.398586155519, 5472.699001554953, 5476.0, 5494.09347457673, 5515.972420137954, 6420.325865784119, 6441.742330306735, 6445.999999999906, 6451.2903580607035, 6456.838579035561, 6153.13856060143, 6211.2857021443115, 6224.284207316216, 6231.626102433868, 6283.963146081029, 6461.61230326182, 6483.448906842971, 6494.0258961156205, 6495.285518593046, 6509.316521765147, 6144.586745048575, 6228.532860035606, 6229.2524897010835, 6231.875362492816, 6255.0617407387435, 5726.9691455538405, 5948.672822994604, 6172.537059720449, 6181.289151747606, 6280.560360774884, 6227.283862355887, 6243.575285182545, 6265.27949285838, 6290.182276304051, 6302.086197501429, 5257.658252213594, 6142.907856382247, 6161.907571386343, 6204.410580994979, 6251.0, 5885.882282354414, 5987.520998320037, 6108.493847774579, 6108.5723999319625, 6127.525510585499, 5070.467600901852, 6262.248530176408, 6266.0, 6279.403456671609, 6296.307406185408, 5944.851378715393, 6005.609635373687, 6081.939180608138, 6147.354527774519, 6173.506119510339], "value": 5709.420266800707, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [524.3340956984632, 530.1755769777999, 530.2655821686963, 542.9104197807362, 636.8312397214737, 39443.60556394435, 41609.75170744875, 41633.08568400206, 41634.0, 41678.16643667125, 41656.37515437263, 42028.73913782584, 42264.57735422645, 42288.788556057254, 42300.788496057554, 9458.243340532757, 9577.233821294314, 9688.612455501807, 9692.418454892693, 9716.708498745049, 9627.3260871739, 9678.758031049245, 9700.805983880318, 9710.12608865202, 9712.0, 9702.563384647709, 9705.078017588317, 9721.756956076084, 9739.26955478342, 9739.707808765748, 9657.082577155159, 9681.564329605115, 9696.709098727084, 9704.660336888246, 9736.805263894754, 9518.333716639838, 9614.08666176719, 9626.229901607854, 9635.325527213028, 9637.759056023655, 38802.805985970175, 39032.21935561273, 39097.827065187805, 39168.60831391678, 39239.62661306869, 2564.4614630927526, 2808.43831233752, 3022.425739109562, 3266.5590145330316, 3362.8318584070944, 28014.579781303295, 28824.135275941546, 29317.97387091463, 29907.401851962837, 30017.549736753746, 2715.2261605442354, 2717.8369297841996, 2757.9448411031667, 2794.455081259135, 2870.0, 530611.0, 531154.0, 541038.2948085234, 541378.0, 542540.2872985649, 64587.0, 64948.0, 65210.34789652138, 65232.02151967724, 65313.02030469547, 36291.81854090712, 36407.81796091056, 37009.0, 37051.81474092666, 68836.6558167206, 14135.858641413453, 16205.0, 16669.83330166683, 16941.91529042347, 16950.830491694924, 11735.23720958135, 12438.134279857913, 13051.543195988095, 13165.934170329087, 13174.802377964339, 19227.11608177389, 19787.01064946747, 19840.60318793616, 19947.102380392917, 20005.599888002158, 8481.83036339282, 8482.151784821468, 8527.275181609633, 8528.378000559207, 8546.31629469641, 948.3788118782209, 957.6456711016829, 1015.3958394755109, 1100.6587957732968, 1118.4128332625273, 24820.0, 25240.495190096095, 29144.7085529142, 29768.40463190724, 30342.393152137276, 962.7304354780532, 1107.7562936154034, 1108.578740078765, 1125.487903004131, 1200.4717924113363, 14275.42898284057, 15332.616684582752, 15342.463013794686, 15420.61448463775, 15683.607909802118, 663162.0, 689286.0, 697101.0, 704522.0, 706458.0, 59926.40073599208, 60349.39650603526, 60836.69581652063, 60874.08688869671, 60978.0, 1450.260367212708, 1456.2281990545027, 1512.5916002679214, 1526.3131590784192, 1534.5242974677885, 24146.034158633458, 29544.0, 29606.259843504085, 30209.0, 30311.0, 51348.48651513587, 51673.74163129311, 51751.22373164481, 51825.0, 51832.740836295576, 420916.0, 421599.0, 423042.0, 618259.0, 620707.0, 25623.87188064122, 26594.468110637292, 26908.327291817077, 26912.86543567348, 26913.0, 45138.548614513435, 45633.26200427985, 46139.538604613525, 46448.30327545022, 47243.52756472391, 36884.262314754094, 38094.61905380911, 38439.392731909604, 38455.61544384632, 39020.0, 30085.548716769703, 37796.0, 37933.0, 38493.61506385012, 38534.61465385422, 32067.0, 36884.0, 37274.813625931696, 37498.8125059373, 37928.62071379251, 27266.727332727212, 31817.409129542968, 33374.49938250977, 34347.0, 34506.7922622707, 20776.0, 24729.0, 24867.87566062158, 25604.30079642579, 26226.213213603623], "value": 19758.110396588996, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.9347310957137, 385.8765195137555, 389.2604052300629, 394.2848412264227, 407.69830325559076, 14567.125972441634, 20273.0, 20292.59414811705, 21597.0, 21615.3515394538, 20760.0, 20780.68828967563, 20862.16551337945, 21434.24980125696, 21543.56912861742, 4941.8023279068975, 5653.604247702652, 5801.07182850744, 6027.0, 6113.083037544359, 5807.796727114531, 6039.879202415949, 6088.939110608903, 6094.695265236743, 6095.847603809896, 5287.471252874701, 6085.300190478085, 6089.725962331706, 6092.299385570672, 6112.969435152818, 5763.308402991626, 6001.459868611824, 6008.489278411318, 6075.726592303357, 6095.420935011189, 4564.497905230422, 5269.1832765921345, 5270.288511050997, 5295.0468915595175, 5304.151335786256, 24942.37644058913, 25693.74306256951, 26443.73556264432, 26449.0, 26815.329616759544, 2072.782357852434, 2135.925242616501, 2203.6914831923546, 2471.765182307696, 2643.4845205184997, 12165.878341216563, 14490.275486225646, 14876.702465950728, 15045.699086018329, 15516.0, 1681.2686481380638, 1718.3298513579714, 1772.6631939931372, 1821.2350812658715, 1841.2634946021565, 110603.0, 116799.41600292029, 117639.0, 117872.41063794712, 119631.0, 10824.24230303871, 11294.887051129548, 11297.152713546353, 11464.248580340658, 11550.480228389742, 14142.646433839236, 14144.22206778637, 14162.150270983773, 14175.929120354333, 14176.574702758933, 8767.12328767131, 8783.604737786814, 8849.911500885037, 8863.911360886308, 8993.14565116313, 8872.423292485968, 8981.236594889377, 8985.236254918274, 9036.683716069905, 9085.591148398338, 6802.537454447318, 6907.792766216921, 6948.687309071104, 6963.825904352431, 6972.79081627552, 1929.7201905723591, 1942.1454559993558, 1954.491832123658, 1958.167778694078, 1961.9509512262307, 940.3088729783603, 950.5769932380163, 1021.667957913681, 1056.2236755984345, 1110.3282514078867, 20971.0, 21340.466488337916, 21521.784782152292, 21818.236361727264, 21978.724786273386, 993.7541156985266, 1005.5676059294574, 1017.6082208349691, 1093.7429704019548, 1117.4692021289823, 9079.306114948939, 11459.369734664508, 11554.595589154507, 11651.825222621668, 11653.18427710069, 433477.0, 530730.0, 591987.0, 599906.0004699948, 602137.0, 86004.0, 96599.0, 113122.0, 115792.0, 116233.0, 1036.5750042482546, 1054.6994106679713, 1072.6567498400436, 1074.5272080284649, 1076.262760009396, 14839.10965342083, 17882.5529361767, 18881.527961801065, 19328.613427731085, 19467.870234146845, 37790.811045945695, 49788.0, 49806.75096624494, 50691.49308506868, 51539.96920061653, 67134.66432667806, 70166.94749578863, 70750.64624676843, 72571.0, 73059.0, 7098.219195888345, 7464.552126872405, 7594.582297973665, 7638.923610764044, 7731.652075656497, 12434.875651243372, 12861.614151575468, 12892.742145157232, 13027.413766380347, 13241.0, 11173.944130279297, 11209.943950280196, 11219.270747401393, 11250.437478125898, 11403.315801051964, 9073.59168837417, 11049.723756906142, 11211.215214934877, 11231.943840280746, 11528.538858445903, 11389.487473063899, 11493.482793274155, 11580.6525804226, 11823.586174483851, 11834.704132396759, 7393.149787774475, 7627.0, 7660.310572048543, 7662.0422447194105, 7810.336121429742, 9621.27840411958, 11274.647042354642, 11338.206325557132, 11380.0, 11438.027767639844], "value": 9710.72727875701, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1627.690738759636, 1657.5938894970734, 1921.7213504041915, 1973.3981135753597, 2017.7881322461133, 8340.0, 9078.409903356278, 9096.499692516909, 9583.856242156364, 9646.324757266993, 9213.401128926633, 9258.490783006931, 9397.671081512148, 9416.482093484856, 9423.34036617437, 2462.261321603516, 2487.800975921931, 2494.7006359236825, 2495.1890635543523, 2495.950080998379, 2438.512297540499, 2467.728549859523, 2475.2203056037292, 2486.8632225227607, 2487.2786891801356, 2427.4416884116663, 2443.1326878957893, 2461.3231361375674, 2466.334089795754, 2471.641611966264, 2418.709754829414, 2430.720467146274, 2441.694788151481, 2471.6910386201725, 2472.814538909589, 2465.938351541208, 2481.9131330403347, 2496.3010357100043, 2506.4360518883104, 2515.421453065787, 9371.109744574254, 9398.95300523493, 9459.810803783887, 9467.816522934632, 9507.19188868947, 5569.470900264469, 5661.46216109469, 5748.626339287975, 5749.252597162384, 5754.338251101135, 3594.0, 3642.25333806569, 3669.2478042001326, 3701.9259614807825, 3710.2950439416422, 1039.1582817917435, 1053.7945100705365, 1056.889026652198, 1061.6708820265742, 1064.8243039898407, 12314.753704925852, 12456.75086498274, 12484.375781210994, 12491.875081249253, 12500.374981250901, 3818.121831978605, 4692.718436893797, 4802.327674125625, 4955.4548999610015, 5103.795848166106, 10310.0, 10539.42033188182, 10569.418681972411, 10599.0, 10611.575536978435, 5529.087700529368, 5587.161925711176, 5654.943450565524, 5664.433556644302, 5740.425957404226, 4560.908781824412, 4880.511948805162, 5054.166062599704, 5065.138926382524, 5092.567131793839, 3600.873969411058, 4188.937165942513, 4212.85255016073, 4267.0, 4296.334068219431, 1363.8908887288992, 1375.40170026037, 1378.8827949624394, 1378.9379477923317, 1385.6466601016675, 855.5893171277821, 920.1396694090912, 948.2650945517279, 956.3879117364892, 980.3039841712322, 6130.754769809158, 6137.263528376623, 6152.846178845564, 6320.905186422207, 6346.524010699216, 463.0992719161199, 463.62214794942014, 468.6742713813867, 472.98344557940305, 480.7860502076542, 3984.1633257015774, 4125.628693417607, 4132.586741325843, 4167.645750111214, 4218.514870789899, 11078.667639970814, 11344.659660210207, 11395.145364097725, 11559.30644161353, 11583.07335413165, 11345.319280843176, 11387.715307117223, 11508.712282193012, 11519.596814111466, 11537.0, 7128.966299886488, 8282.461639993382, 8679.17547832955, 8685.739427817176, 8686.739397818075, 5146.202338637517, 5208.427073021889, 5679.864027194495, 5772.307323121252, 6100.938990610037, 8888.6000129993, 10101.14140298083, 10660.520276587727, 10701.0, 10710.410927399067, 10445.9477702611, 10746.623868164577, 10831.0, 10874.401907895142, 10901.964313390215, 6562.967185164043, 7289.088863892013, 7723.0, 7972.561509117054, 7974.760757177294, 8790.208881200468, 8865.601047952987, 8897.243734282658, 8948.955255223942, 8979.640814367353, 7740.922590774246, 7819.178986206366, 7954.482958607672, 8068.677252909818, 8089.433739638169, 7775.22247775218, 7871.0, 7882.921170788219, 7931.484453510503, 8041.959790201012, 7170.856582868209, 7381.04046473955, 7414.851702965802, 7450.180480147288, 7459.254074592497, 4401.2297847877135, 5110.310108135503, 5159.226116082617, 5237.895242095061, 5276.234945932895, 5200.843974680765, 5263.6052296078715, 5313.681179129265, 5424.484673955968, 5459.372172200089], "value": 4717.958202927081, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [314.47953636731205, 318.21083712393266, 319.95056215612783, 321.4615519005665, 325.1448689945443, 5404.32445944257, 5474.0019892739165, 5504.889902201953, 5514.3658479274845, 5526.198701188326, 4697.882552936178, 5149.742512874351, 5288.5504732097725, 5366.738816378148, 5379.35447746271, 3734.6078661740535, 3894.922101557967, 3956.0417593291363, 4057.5333836608875, 4123.711340206171, 3637.5453068366455, 3715.0, 3749.362608356586, 3993.1015521507575, 4110.917781644365, 3723.4414837774416, 4051.8784436466804, 4053.9594604054023, 4070.470838790953, 4118.361653943629, 3680.4847321375028, 3947.881563553098, 3975.5428125765616, 4078.490188726409, 4096.283150448675, 2814.802963792535, 2968.9703102969124, 3392.915177120567, 3408.2672225471642, 3410.9658903410896, 4390.560943905616, 5090.796368145269, 5099.745012749348, 5129.204973229155, 5200.531952124289, 4225.471816022997, 4341.0, 4448.398576512453, 4568.291914753218, 4570.223062079454, 4325.740455572675, 4702.3416721659, 4837.758112094416, 5398.676079435247, 5592.804251851207, 3731.5522137343423, 3749.7000239981007, 3770.698344132463, 3782.508273924372, 3795.0, 5123.897522049539, 5241.633085684004, 5732.770689172428, 5811.767529298865, 5935.347111817697, 3431.605365382963, 3922.490076290064, 3949.19041596472, 3955.228730397545, 3971.517801374141, 3196.8241746704152, 3837.501124853751, 3999.420084087791, 4007.4189242559664, 4008.759474431485, 4635.3742244797195, 4648.5119062498625, 4649.4420669519195, 4654.394928659321, 4659.930101048487, 4928.728919909439, 5400.027994960867, 5487.533559647395, 5706.229658995982, 5788.581797459649, 1313.7766579681286, 1334.4928927007868, 1340.9406568810725, 1342.2483658825327, 1348.1035111650642, 814.686345756876, 822.296936119621, 839.6179738219096, 851.3274513134554, 877.9517126557973, 4334.674899382559, 4579.313103034571, 5267.15649522664, 5271.551918086929, 5280.234366016906, 4816.325714399987, 5346.957343318093, 5408.810691625774, 5743.597948143673, 5793.8841223175305, 4486.573775491323, 4886.364772579542, 5058.4435712071645, 5175.990681817085, 5281.973590132025, 2815.142006275848, 2839.772818174541, 3012.186709588399, 3042.9695703042685, 3064.8161110333454, 6013.0, 6026.397360264026, 6040.818775436744, 6050.757969681164, 6077.20996270482, 5071.822486213038, 5711.771529138871, 5907.527397808165, 5955.821325360246, 5993.430624090704, 4665.673402861835, 5842.561807864512, 6039.969800150971, 6042.335343112342, 6054.516643422389, 4634.169503046211, 4896.583790377857, 5346.465353464622, 5413.458654134555, 5497.47773961473, 4654.743989080498, 5241.580673546184, 5444.673319600836, 5608.728464971693, 5656.387929440254, 6008.969955150196, 6018.682807119203, 6023.608465449907, 6026.186464827192, 6054.455099040932, 3756.530433695788, 3883.165119499321, 3901.51231096113, 4064.841520166738, 4078.53096893861, 5815.709214539172, 5826.41735826414, 5831.446012628793, 5857.0, 5870.7945221917025, 4829.637777166657, 5092.567131793839, 5630.60585758993, 5717.8570535736935, 5725.112607545837, 5132.10188217053, 5252.133397989289, 5497.367802702581, 5613.494785469327, 5688.857778555569, 5306.567226848806, 5363.678179309254, 5450.427705090909, 5531.091773338192, 5572.801847602785, 4615.515370886143, 4853.805847766191, 5479.849231661396, 5572.60991730575, 5753.90675771602, 5627.155926611041, 5633.718314084362, 5636.351819540641, 5655.264815573949, 5684.8294551163535], "value": 4078.654330963332, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [642.2068745099804, 661.4443867151592, 734.3611058379211, 740.785172300033, 757.3486801350841, 14912.17983010934, 15234.0, 15273.083614983096, 15329.923350383233, 15463.22683865805, 14179.503717369867, 14235.0, 14283.857161428383, 14286.785698214535, 14327.212003339811, 722.3679280629449, 906.188960880012, 918.3158546882565, 933.1088810186255, 937.9062093790601, 913.6482454255126, 918.2699753695828, 918.8346097702411, 919.9540022998857, 922.3866129024191, 945.394947233771, 958.3339578992603, 971.1890571372912, 972.9756756081084, 973.7224890906093, 804.7625950344662, 804.8430556041574, 809.3039985612369, 935.7333160049421, 961.6586111930234, 707.7133760826873, 711.903892974447, 714.2143641993765, 715.6994062493713, 716.2729829223342, 12304.384780760925, 14832.406703731838, 14849.183294918776, 14868.776968345483, 14923.0, 11148.05241554469, 12413.255204687748, 12570.622881313575, 12597.433115509824, 12637.368131593383, 9787.62787613031, 10107.140893024101, 10115.7471063224, 10137.0, 10634.787304253949, 3417.820851806123, 3466.757326987112, 3470.6182319944787, 3506.7194624429985, 3584.8207589620415, 58580.70709646467, 69694.65152674254, 82329.0, 82729.345413092, 87738.56130719306, 9277.67528136512, 9413.670521531714, 9539.427634341962, 9543.33196676226, 9721.46531940736, 21330.78669213319, 21346.146154153972, 21375.717456952632, 21557.78442215558, 21675.891620541795, 7581.658825352873, 7620.642833780976, 7648.08223013242, 7653.4642575020325, 7867.6459559319965, 4027.7986100695434, 4085.9387109193385, 4086.6117718816663, 4088.7546747195265, 4095.140020595649, 2997.385535965122, 3591.9102022449124, 3673.3571624965525, 3692.5015122958594, 3703.333399988028, 608.1880689279773, 640.9871802563922, 641.3906788550895, 659.5251418978328, 710.6944014073895, 517.2654830141133, 527.8442859356499, 587.717895410197, 592.3157957504317, 607.872545416625, 6871.622060786704, 7887.921120788834, 8109.229623185788, 8324.334053275723, 8327.95836020816, 522.4984015345235, 597.596622279968, 603.1585937617, 622.7260005597614, 638.9776357827438, 3630.40098383769, 3799.6960243181034, 3985.342418500915, 4002.899927501836, 4010.258102251093, 47604.7619761899, 72715.0, 75452.62949096164, 93774.5311273453, 95657.04342956482, 85088.57455712682, 85454.0, 85500.57249713711, 87400.0, 87938.24123517622, 77276.61361693268, 77779.0, 78294.0, 78326.21673783303, 78684.6065769679, 7212.278772122746, 7757.534547927143, 7767.650455729393, 7772.891877909958, 7887.290143887078, 44986.77506612446, 45856.31215531837, 46695.29957050579, 47019.0, 47500.52499474961, 62914.685426574404, 64427.0, 65030.674846625465, 65419.34580654323, 66111.0, 9750.609975600897, 10119.79760404773, 10213.795724085328, 10241.539130739284, 10414.791704166026, 10710.0, 10752.569897204025, 10846.349219046882, 11043.889561104286, 11485.827712584485, 10637.734056648645, 10656.307340022873, 10759.139268858316, 10767.569297228023, 10783.784324313627, 9230.723078307661, 10549.208809339178, 10648.254622176373, 10686.465676716289, 10724.088452481627, 8834.558272086499, 10194.337368071052, 10575.36547807134, 10640.0, 10713.839292410776, 8499.277561407102, 8834.249088827522, 9041.457512549268, 9077.273818094669, 9278.0, 8230.958845205736, 8467.49195048299, 8754.168354006359, 9115.815444371357, 9152.405093668891], "value": 6729.547797398789, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1640.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 116.0, 108.0, 117.0, 115.0, 116.0, 108.0, 115.0, 117.0, 115.0], "value": 114.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 142.0, 137.0, 259.0, 157.0, 172.0, 152.0, 123.0, 169.0, 140.0], "value": 161.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 203.0, 253.0, 217.0, 241.0, 201.0, 199.0, 201.0, 199.0, 196.0], "value": 210.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 78.0, 95.0, 204.0, 84.0, 78.0, 60.0, 65.0, 131.0, 71.0], "value": 94.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 150.0, 78.0, 81.0, 85.0, 124.0, 81.0, 80.0, 129.0], "value": 97.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 92.0, 77.0, 135.0, 113.0, 91.0, 86.0, 89.0, 86.0], "value": 94.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 95.0, 95.0, 93.0, 104.0, 95.0, 95.0, 96.0, 93.0], "value": 95.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 129.0, 142.0, 128.0, 90.0, 117.0, 95.0, 75.0, 145.0, 145.0], "value": 115.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 60.0, 56.0, 60.0, 59.0, 59.0, 126.0, 84.0, 91.0], "value": 71.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 72.0, 68.0, 72.0, 73.0, 70.0, 69.0, 68.0, 66.0], "value": 69.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 111.0, 114.0, 110.0, 111.0, 87.0, 85.0, 87.0, 90.0, 88.0], "value": 98.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 159.0, 130.0, 138.0, 138.0, 138.0, 136.0, 141.0, 247.0, 152.0], "value": 152.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 228.0, 215.0, 166.0, 165.0, 171.0, 171.0, 226.0, 172.0, 168.0], "value": 185.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 71.0, 78.0, 88.0, 85.0, 84.0, 88.0, 88.0, 79.0], "value": 79.5, "unit": "score"}]}]} 08:00:35 INFO - Return code: 0 08:00:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 08:00:35 ERROR - # TBPL SUCCESS # 08:00:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:00:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:00:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:00:35 INFO - 2016-05-13 08:00:35.690 screenresolution[1966:18873] starting screenresolution argv=screenresolution get 08:00:35 INFO - 2016-05-13 08:00:35.693 screenresolution[1966:18873] Display 0: 1600x1200x32@60 08:00:35 INFO - 2016-05-13 08:00:35.699 screenresolution[1967:18875] starting screenresolution argv=screenresolution list 08:00:35 INFO - Available Modes on Display 0 08:00:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:00:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:00:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:00:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:00:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:00:35 INFO - Intel Iris: 08:00:35 INFO - Chipset Model: Intel Iris 08:00:35 INFO - Type: GPU 08:00:35 INFO - Bus: Built-In 08:00:35 INFO - VRAM (Dynamic, Max): 1536 MB 08:00:35 INFO - Vendor: Intel (0x8086) 08:00:35 INFO - Device ID: 0x0a2e 08:00:35 INFO - Revision ID: 0x0009 08:00:35 INFO - Displays: 08:00:35 INFO - Display: 08:00:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:00:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:00:35 INFO - Display Serial Number: 1600x1200 60 08:00:35 INFO - Main Display: Yes 08:00:35 INFO - Mirror: Off 08:00:35 INFO - Online: Yes 08:00:35 INFO - Rotation: Supported 08:00:35 INFO - Return code: 0 08:00:35 INFO - Running post-action listener: _resource_record_post_action 08:00:35 INFO - ##### 08:00:35 INFO - ##### Finished run-tests step (success) 08:00:35 INFO - ##### 08:00:35 INFO - Running post-run listener: _resource_record_post_run 08:00:36 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 319488; Write bytes: 697078272; Read time: 4; Write time: 2130 08:00:36 INFO - TinderboxPrint: CPU usage
54.5% 08:00:36 INFO - TinderboxPrint: I/O read bytes / time
319,488 / 4 08:00:36 INFO - TinderboxPrint: I/O write bytes / time
697,078,272 / 2,130 08:00:36 INFO - TinderboxPrint: CPU idle
2,203.4 (45.5%) 08:00:36 INFO - TinderboxPrint: CPU system
976.1 (20.2%) 08:00:36 INFO - TinderboxPrint: CPU user
1,662.1 (34.3%) 08:00:36 INFO - TinderboxPrint: Swap in / out
394,694,656 / 0 08:00:36 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 382280192; Write bytes: 377816064; Read time: 28376; Write time: 682 08:00:36 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 319488; Write bytes: 297762304; Read time: 4; Write time: 1399 08:00:36 INFO - Running post-run listener: _upload_blobber_files 08:00:36 INFO - Blob upload gear active. 08:00:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:00:36 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 08:00:36 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'] 08:00:36 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 08:00:36 INFO - (blobuploader) - INFO - Open directory for files ... 08:00:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:00:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:00:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:00:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:00:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:00:38 INFO - (blobuploader) - INFO - Done attempting. 08:00:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:00:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:00:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:00:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:00:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:00:39 INFO - (blobuploader) - INFO - Done attempting. 08:00:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:00:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:00:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:00:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:00:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:00:41 INFO - (blobuploader) - INFO - Done attempting. 08:00:41 INFO - (blobuploader) - INFO - Iteration through files over. 08:00:41 INFO - Return code: 0 08:00:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:00:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:00:41 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55b61291c43cbe2227013ad295b6cf875fddf814404eb16defcca4a69b5da6642b20a4ff3babfcd7a17197280addfaa151efbe0c6223538a77efaa4387305842", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ab3941fcd0531f4bdbb011b5ae41d250df49d6abad8a3069d39da5d11d53b36f01676e85948b0f5e4c9786df2c19f854fc1ad7e5d5b0e665a0d60c9fb9d9d34", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/de0cdb5573b96be2e58bbda41694f0eee7cbd8877e0103506ce9335ca4888803f7561130fe8caba679f42a8469b633aae3fec0abd001a2e15c8075e23f74ea44"} 08:00:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:00:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:00:41 INFO - Contents: 08:00:41 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55b61291c43cbe2227013ad295b6cf875fddf814404eb16defcca4a69b5da6642b20a4ff3babfcd7a17197280addfaa151efbe0c6223538a77efaa4387305842", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ab3941fcd0531f4bdbb011b5ae41d250df49d6abad8a3069d39da5d11d53b36f01676e85948b0f5e4c9786df2c19f854fc1ad7e5d5b0e665a0d60c9fb9d9d34", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/de0cdb5573b96be2e58bbda41694f0eee7cbd8877e0103506ce9335ca4888803f7561130fe8caba679f42a8469b633aae3fec0abd001a2e15c8075e23f74ea44"} 08:00:41 INFO - Running post-run listener: copy_logs_to_upload_dir 08:00:41 INFO - Copying logs to upload dir... 08:00:41 INFO - mkdir: /builds/slave/test/build/upload/logs 08:00:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.113660 ========= master_lag: 1.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-05-13 08:00:42.889086) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 5 secs) (at 2016-05-13 08:00:42.892410) ========= 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.Lh6JWgHh4W/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.ctZMlUYEFI/Listeners TMPDIR=/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55b61291c43cbe2227013ad295b6cf875fddf814404eb16defcca4a69b5da6642b20a4ff3babfcd7a17197280addfaa151efbe0c6223538a77efaa4387305842", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ab3941fcd0531f4bdbb011b5ae41d250df49d6abad8a3069d39da5d11d53b36f01676e85948b0f5e4c9786df2c19f854fc1ad7e5d5b0e665a0d60c9fb9d9d34", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/de0cdb5573b96be2e58bbda41694f0eee7cbd8877e0103506ce9335ca4888803f7561130fe8caba679f42a8469b633aae3fec0abd001a2e15c8075e23f74ea44"} build_url:https://queue.taskcluster.net/v1/task/eSqdiNSAQtGnDp5LAZn1wg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011535 build_url: 'https://queue.taskcluster.net/v1/task/eSqdiNSAQtGnDp5LAZn1wg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55b61291c43cbe2227013ad295b6cf875fddf814404eb16defcca4a69b5da6642b20a4ff3babfcd7a17197280addfaa151efbe0c6223538a77efaa4387305842", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ab3941fcd0531f4bdbb011b5ae41d250df49d6abad8a3069d39da5d11d53b36f01676e85948b0f5e4c9786df2c19f854fc1ad7e5d5b0e665a0d60c9fb9d9d34", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/de0cdb5573b96be2e58bbda41694f0eee7cbd8877e0103506ce9335ca4888803f7561130fe8caba679f42a8469b633aae3fec0abd001a2e15c8075e23f74ea44"}' ========= master_lag: 5.57 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 5 secs) (at 2016-05-13 08:00:48.475547) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-05-13 08:00:48.475873) ========= 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.Lh6JWgHh4W/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.ctZMlUYEFI/Listeners TMPDIR=/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/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.005265 ========= master_lag: 3.77 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-05-13 08:00:52.247587) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 08:00:52.247947) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 08:00:52.251542) ========= ========= Total master_lag: 10.47 =========