builder: larch_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0318 starttime: 1464133809.46 results: success (0) buildid: 20160524144846 builduid: 43a836b0f7254a4393487341ab8a9fcb revision: 96e80e3b85ff1f96f67991a0a167cfc7a66b9743 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 16:50:09.458337) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 16:50:09.458793) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-24 16:50:09.459120) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-24 16:50:09.541154) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 16:50:09.541510) ========= 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.UEqlyLYoYl/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.827T5vvaGV/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-24 16:50:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.6M=0.001s 2016-05-24 16:50:09 (18.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.144586 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 16:50:09.708115) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 16:50:09.708479) ========= 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.UEqlyLYoYl/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.827T5vvaGV/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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.026143 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 16:50:09.761528) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-24 16:50:09.761840) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev 96e80e3b85ff1f96f67991a0a167cfc7a66b9743 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev 96e80e3b85ff1f96f67991a0a167cfc7a66b9743 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.UEqlyLYoYl/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.827T5vvaGV/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-24 16:50:09,843 truncating revision to first 12 chars 2016-05-24 16:50:09,844 Setting DEBUG logging. 2016-05-24 16:50:09,844 attempt 1/10 2016-05-24 16:50:09,844 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/96e80e3b85ff?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-24 16:50:11,195 unpacking tar archive at: larch-96e80e3b85ff/testing/mozharness/ program finished with exit code 0 elapsedTime=1.594773 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-24 16:50:11.374844) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 16:50:11.375116) ========= script_repo_revision: 96e80e3b85ff1f96f67991a0a167cfc7a66b9743 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 16:50:11.375478) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 16:50:11.375759) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 16:50:11.392717) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-05-24 16:50:11.393002) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 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', 'Larch', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.UEqlyLYoYl/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.827T5vvaGV/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 16:50:11 INFO - MultiFileLogger online at 20160524 16:50:11 in /builds/slave/test 16:50:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 16:50:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:50:11 INFO - {'append_to_log': False, 16:50:11 INFO - 'base_work_dir': '/builds/slave/test', 16:50:11 INFO - 'blob_upload_branch': 'Larch', 16:50:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:50:11 INFO - 'branch': 'Larch', 16:50:11 INFO - 'buildbot_json_path': 'buildprops.json', 16:50:11 INFO - 'config_files': ('talos/mac_config.py',), 16:50:11 INFO - 'default_actions': ('clobber', 16:50:11 INFO - 'read-buildbot-config', 16:50:11 INFO - 'download-and-extract', 16:50:11 INFO - 'populate-webroot', 16:50:11 INFO - 'create-virtualenv', 16:50:11 INFO - 'install', 16:50:11 INFO - 'run-tests'), 16:50:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:50:11 INFO - 'download_minidump_stackwalk': True, 16:50:11 INFO - 'download_symbols': 'ondemand', 16:50:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:50:11 INFO - 'tooltool.py': '/tools/tooltool.py', 16:50:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:50:11 INFO - '/tools/misc-python/virtualenv.py')}, 16:50:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:50:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:50:11 INFO - 'installer_path': 'installer.exe', 16:50:11 INFO - 'log_level': 'info', 16:50:11 INFO - 'log_name': 'talos', 16:50:11 INFO - 'log_to_console': True, 16:50:11 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:50:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:50:11 INFO - 'opt_config_files': (), 16:50:11 INFO - 'pip_index': False, 16:50:11 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:50:11 INFO - 'cmd': ('bash', 16:50:11 INFO - '-c', 16:50:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:50:11 INFO - 'enabled': True, 16:50:11 INFO - 'halt_on_failure': False, 16:50:11 INFO - 'name': 'check_screen_resolution'},), 16:50:11 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:50:11 INFO - 'cmd': ('bash', 16:50:11 INFO - '-c', 16:50:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:50:11 INFO - 'enabled': True, 16:50:11 INFO - 'halt_on_failure': False, 16:50:11 INFO - 'name': 'check_screen_resolution'},), 16:50:11 INFO - 'run_cmd_checks_enabled': True, 16:50:11 INFO - 'sps_profile': False, 16:50:11 INFO - 'sps_profile_interval': 0, 16:50:11 INFO - 'suite': 'dromaeojs-e10s', 16:50:11 INFO - 'system_bits': '32', 16:50:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:50:11 INFO - 'title': 't-yosemite-r7-0318', 16:50:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:50:11 INFO - 'use_talos_json': True, 16:50:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:50:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:50:11 INFO - 'work_dir': 'build'} 16:50:11 INFO - ##### 16:50:11 INFO - ##### Running clobber step. 16:50:11 INFO - ##### 16:50:11 INFO - Running pre-action listener: _resource_record_pre_action 16:50:11 INFO - Running main action method: clobber 16:50:11 INFO - rmtree: /builds/slave/test/build 16:50:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:50:13 INFO - Running post-action listener: _resource_record_post_action 16:50:13 INFO - ##### 16:50:13 INFO - ##### Finished clobber step (success) 16:50:13 INFO - ##### 16:50:13 INFO - ##### 16:50:13 INFO - ##### Running read-buildbot-config step. 16:50:13 INFO - ##### 16:50:13 INFO - Running pre-action listener: _resource_record_pre_action 16:50:13 INFO - Running main action method: read_buildbot_config 16:50:13 INFO - Using buildbot properties: 16:50:13 INFO - { 16:50:13 INFO - "project": "", 16:50:13 INFO - "product": "firefox", 16:50:13 INFO - "script_repo_revision": "production", 16:50:13 INFO - "builddir": "larch_yosemite_r7_test-dromaeojs-e10s", 16:50:13 INFO - "repository": "", 16:50:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 larch talos dromaeojs-e10s", 16:50:13 INFO - "buildid": "20160524144846", 16:50:13 INFO - "pgo_build": "False", 16:50:13 INFO - "basedir": "/builds/slave/test", 16:50:13 INFO - "buildnumber": 0, 16:50:13 INFO - "slavename": "t-yosemite-r7-0318", 16:50:13 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 16:50:13 INFO - "platform": "yosemite_r7", 16:50:13 INFO - "scheduler": "tests-larch-macosx64-talos", 16:50:13 INFO - "branch": "larch", 16:50:13 INFO - "repo_path": "projects/larch", 16:50:13 INFO - "revision": "96e80e3b85ff1f96f67991a0a167cfc7a66b9743", 16:50:13 INFO - "stage_platform": "macosx64", 16:50:13 INFO - "builduid": "43a836b0f7254a4393487341ab8a9fcb", 16:50:13 INFO - "slavebuilddir": "test" 16:50:13 INFO - } 16:50:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/dBlJAsFsQcegm3CAmX3zAg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 16:50:13 INFO - Running post-action listener: _resource_record_post_action 16:50:13 INFO - ##### 16:50:13 INFO - ##### Finished read-buildbot-config step (success) 16:50:13 INFO - ##### 16:50:13 INFO - ##### 16:50:13 INFO - ##### Running download-and-extract step. 16:50:13 INFO - ##### 16:50:13 INFO - Running pre-action listener: _resource_record_pre_action 16:50:13 INFO - Running main action method: download_and_extract 16:50:13 INFO - mkdir: /builds/slave/test/build/tests 16:50:13 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')]} 16:50:13 INFO - https://queue.taskcluster.net/v1/task/dBlJAsFsQcegm3CAmX3zAg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 16:50:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dBlJAsFsQcegm3CAmX3zAg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 16:50:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dBlJAsFsQcegm3CAmX3zAg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 16:50:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dBlJAsFsQcegm3CAmX3zAg/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 16:50:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dBlJAsFsQcegm3CAmX3zAg/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 16:50:15 INFO - Downloaded 1199 bytes. 16:50:15 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 16:50:15 INFO - Using the following test package requirements: 16:50:15 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 16:50:15 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:50:15 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 16:50:15 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:50:15 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 16:50:15 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:50:15 INFO - u'jsshell-mac.zip'], 16:50:15 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:50:15 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 16:50:15 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 16:50:15 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:50:15 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 16:50:15 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:50:15 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 16:50:15 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:50:15 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 16:50:15 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:50:15 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 16:50:15 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:50:15 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')]} 16:50:15 INFO - https://queue.taskcluster.net/v1/task/dBlJAsFsQcegm3CAmX3zAg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:50:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dBlJAsFsQcegm3CAmX3zAg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:50:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dBlJAsFsQcegm3CAmX3zAg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:50:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dBlJAsFsQcegm3CAmX3zAg/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 16:50:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dBlJAsFsQcegm3CAmX3zAg/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 16:50:17 INFO - Downloaded 18150514 bytes. 16:50:17 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'] 16:50:17 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 16:50:20 INFO - Return code: 0 16:50:20 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 16:50: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')]} 16:50:20 INFO - https://queue.taskcluster.net/v1/task/dBlJAsFsQcegm3CAmX3zAg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:50:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dBlJAsFsQcegm3CAmX3zAg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:50:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dBlJAsFsQcegm3CAmX3zAg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:50:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dBlJAsFsQcegm3CAmX3zAg/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 16:50:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dBlJAsFsQcegm3CAmX3zAg/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 16:50:23 INFO - Downloaded 18150514 bytes. 16:50:23 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'] 16:50:23 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 16:50:26 INFO - Return code: 0 16:50:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:50:26 INFO - https://queue.taskcluster.net/v1/task/dBlJAsFsQcegm3CAmX3zAg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 16:50:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dBlJAsFsQcegm3CAmX3zAg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 16:50:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dBlJAsFsQcegm3CAmX3zAg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 16:50:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dBlJAsFsQcegm3CAmX3zAg/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 16:50:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dBlJAsFsQcegm3CAmX3zAg/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 16:50:27 INFO - Downloaded 10840160 bytes. 16:50:27 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'] 16:50:27 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 16:50:28 INFO - Return code: 0 16:50:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:50:28 INFO - https://queue.taskcluster.net/v1/task/dBlJAsFsQcegm3CAmX3zAg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 16:50:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dBlJAsFsQcegm3CAmX3zAg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:50:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dBlJAsFsQcegm3CAmX3zAg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:50:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dBlJAsFsQcegm3CAmX3zAg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 16:50:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dBlJAsFsQcegm3CAmX3zAg/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 16:50:31 INFO - Downloaded 110056100 bytes. 16:50:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dBlJAsFsQcegm3CAmX3zAg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:50:31 INFO - mkdir: /builds/slave/test/properties 16:50:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:50:31 INFO - Writing to file /builds/slave/test/properties/build_url 16:50:31 INFO - Contents: 16:50:31 INFO - build_url:https://queue.taskcluster.net/v1/task/dBlJAsFsQcegm3CAmX3zAg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:50:32 INFO - Running post-action listener: _resource_record_post_action 16:50:32 INFO - Running post-action listener: set_extra_try_arguments 16:50:32 INFO - ##### 16:50:32 INFO - ##### Finished download-and-extract step (success) 16:50:32 INFO - ##### 16:50:32 INFO - ##### 16:50:32 INFO - ##### Running populate-webroot step. 16:50:32 INFO - ##### 16:50:32 INFO - Running pre-action listener: _resource_record_pre_action 16:50:32 INFO - Running main action method: populate_webroot 16:50:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:50:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:50:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:50:32 INFO - '--tppagecycles', 16:50:32 INFO - '7'], 16:50:32 INFO - 'tests': ['tsvgm']}}, 16:50:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:50:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:50:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:50:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:50:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:50:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:50:32 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:50:32 INFO - 'talos_options': ['--disable-e10s'], 16:50:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:50:32 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:50:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:50:32 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:50:32 INFO - 'talos_options': ['--disable-e10s'], 16:50:32 INFO - 'tests': ['damp', 'tps']}, 16:50:32 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:50:32 INFO - 'tests': ['damp', 'tps']}, 16:50:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:50:32 INFO - 'tests': ['dromaeo_dom']}, 16:50:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:50:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:50:32 INFO - 'tests': ['a11yr', 16:50:32 INFO - 'ts_paint', 16:50:32 INFO - 'tpaint', 16:50:32 INFO - 'sessionrestore', 16:50:32 INFO - 'sessionrestore_no_auto_restore', 16:50:32 INFO - 'tabpaint']}, 16:50:32 INFO - 'other-e10s': {'tests': ['a11yr', 16:50:32 INFO - 'ts_paint', 16:50:32 INFO - 'tpaint', 16:50:32 INFO - 'sessionrestore', 16:50:32 INFO - 'sessionrestore_no_auto_restore', 16:50:32 INFO - 'tabpaint']}, 16:50:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:50:32 INFO - 'ts_paint', 16:50:32 INFO - 'tpaint', 16:50:32 INFO - 'sessionrestore', 16:50:32 INFO - 'sessionrestore_no_auto_restore', 16:50:32 INFO - 'tabpaint']}, 16:50:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:50:32 INFO - 'ts_paint', 16:50:32 INFO - 'tpaint', 16:50:32 INFO - 'sessionrestore', 16:50:32 INFO - 'sessionrestore_no_auto_restore', 16:50:32 INFO - 'tabpaint']}, 16:50:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:50:32 INFO - 'tests': ['a11yr', 16:50:32 INFO - 'ts_paint', 16:50:32 INFO - 'tpaint', 16:50:32 INFO - 'sessionrestore', 16:50:32 INFO - 'sessionrestore_no_auto_restore', 16:50:32 INFO - 'tabpaint']}, 16:50:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:50:32 INFO - 'tests': ['a11yr', 16:50:32 INFO - 'ts_paint', 16:50:32 INFO - 'tpaint', 16:50:32 INFO - 'sessionrestore', 16:50:32 INFO - 'sessionrestore_no_auto_restore', 16:50:32 INFO - 'tabpaint']}, 16:50:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:50:32 INFO - 'tests': ['tsvgx', 16:50:32 INFO - 'tsvgr_opacity', 16:50:32 INFO - 'tart', 16:50:32 INFO - 'tscrollx', 16:50:32 INFO - 'cart']}, 16:50:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:50:32 INFO - 'tsvgr_opacity', 16:50:32 INFO - 'tart', 16:50:32 INFO - 'tscrollx', 16:50:32 INFO - 'cart']}, 16:50:32 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:50:32 INFO - 'talos_options': ['--disable-e10s'], 16:50:32 INFO - 'tests': ['tp5o']}, 16:50:32 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:50:32 INFO - 'tests': ['tp5o']}, 16:50:32 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:50:32 INFO - 'talos_options': ['--disable-e10s', 16:50:32 INFO - '--xperf_path', 16:50:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:50:32 INFO - 'tests': ['tp5n']}, 16:50:32 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:50:32 INFO - 'talos_options': ['--xperf_path', 16:50:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:50:32 INFO - 'tests': ['tp5n']}}, 16:50:32 INFO - 'talos.zip': {'path': '', 16:50:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:50:32 INFO - Running post-action listener: _resource_record_post_action 16:50:32 INFO - ##### 16:50:32 INFO - ##### Finished populate-webroot step (success) 16:50:32 INFO - ##### 16:50:32 INFO - ##### 16:50:32 INFO - ##### Running create-virtualenv step. 16:50:32 INFO - ##### 16:50:32 INFO - Running pre-action listener: _resource_record_pre_action 16:50:32 INFO - Running main action method: create_virtualenv 16:50:32 INFO - Creating virtualenv /builds/slave/test/build/venv 16:50:32 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:50:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:50:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:50:33 INFO - Using real prefix '/tools/python27' 16:50:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:50:33 INFO - Installing distribute.............................................................................................................................................................................................done. 16:50:36 INFO - Installing pip.................done. 16:50:36 INFO - Return code: 0 16:50:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:50: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')]} 16:50:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:50:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:50: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')]} 16:50:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:50:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:50:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101f4ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f52570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6d04b79e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101df0d30>, '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 0x101c14db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101de7b20>, '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.UEqlyLYoYl/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.827T5vvaGV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 16:50:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:50:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:50:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UEqlyLYoYl/Render', 16:50:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:50:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:50:36 INFO - 'HOME': '/Users/cltbld', 16:50:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:50:36 INFO - 'LOGNAME': 'cltbld', 16:50:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:50:36 INFO - 'MOZ_NO_REMOTE': '1', 16:50:36 INFO - 'NO_EM_RESTART': '1', 16:50:36 INFO - 'PAGER': '/bin/cat', 16:50:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:50:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:50:36 INFO - 'PWD': '/builds/slave/test', 16:50:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:50:36 INFO - 'SHELL': '/bin/bash', 16:50:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.827T5vvaGV/Listeners', 16:50:36 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 16:50:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:50:36 INFO - 'USER': 'cltbld', 16:50:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:50:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:50:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:50:36 INFO - 'XPC_FLAGS': '0x0', 16:50:36 INFO - 'XPC_SERVICE_NAME': '0', 16:50:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:50:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:50:36 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 16:50:36 INFO - Cleaning up... 16:50:36 INFO - Return code: 0 16:50:36 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 16:50: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')]} 16:50:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:50:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:50: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')]} 16:50:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:50:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:50:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101f4ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f52570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6d04b79e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101df0d30>, '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 0x101c14db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101de7b20>, '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.UEqlyLYoYl/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.827T5vvaGV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 16:50:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 16:50:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 16:50:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UEqlyLYoYl/Render', 16:50:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:50:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:50:36 INFO - 'HOME': '/Users/cltbld', 16:50:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:50:36 INFO - 'LOGNAME': 'cltbld', 16:50:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:50:36 INFO - 'MOZ_NO_REMOTE': '1', 16:50:36 INFO - 'NO_EM_RESTART': '1', 16:50:36 INFO - 'PAGER': '/bin/cat', 16:50:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:50:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:50:36 INFO - 'PWD': '/builds/slave/test', 16:50:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:50:36 INFO - 'SHELL': '/bin/bash', 16:50:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.827T5vvaGV/Listeners', 16:50:36 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 16:50:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:50:36 INFO - 'USER': 'cltbld', 16:50:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:50:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:50:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:50:36 INFO - 'XPC_FLAGS': '0x0', 16:50:36 INFO - 'XPC_SERVICE_NAME': '0', 16:50:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:50:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:50:37 INFO - Downloading/unpacking psutil>=0.7.1 16:50:37 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 16:50:37 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 16:50:37 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 16:50:37 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 16:50:37 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 16:50:37 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 16:50:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:50:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:50:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:50:39 INFO - Installing collected packages: psutil 16:50:39 INFO - Running setup.py install for psutil 16:50:39 INFO - building 'psutil._psutil_osx' extension 16:50:39 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 16:50:40 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 16:50:40 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 16:50:40 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 16:50:40 INFO - building 'psutil._psutil_posix' extension 16:50:40 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 16:50:40 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:50:40 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:50:40 INFO - ^ 16:50:40 INFO - 1 warning generated. 16:50:40 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 16:50:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:50:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:50:40 INFO - Successfully installed psutil 16:50:40 INFO - Cleaning up... 16:50:40 INFO - Return code: 0 16:50:40 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 16:50:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:50:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:50:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:50:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:50:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:50:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:50:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101f4ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f52570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6d04b79e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101df0d30>, '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 0x101c14db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101de7b20>, '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.UEqlyLYoYl/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.827T5vvaGV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 16:50:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 16:50:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 16:50:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UEqlyLYoYl/Render', 16:50:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:50:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:50:40 INFO - 'HOME': '/Users/cltbld', 16:50:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:50:40 INFO - 'LOGNAME': 'cltbld', 16:50:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:50:40 INFO - 'MOZ_NO_REMOTE': '1', 16:50:40 INFO - 'NO_EM_RESTART': '1', 16:50:40 INFO - 'PAGER': '/bin/cat', 16:50:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:50:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:50:40 INFO - 'PWD': '/builds/slave/test', 16:50:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:50:40 INFO - 'SHELL': '/bin/bash', 16:50:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.827T5vvaGV/Listeners', 16:50:40 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 16:50:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:50:40 INFO - 'USER': 'cltbld', 16:50:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:50:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:50:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:50:40 INFO - 'XPC_FLAGS': '0x0', 16:50:40 INFO - 'XPC_SERVICE_NAME': '0', 16:50:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:50:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:50:41 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 16:50:41 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 16:50:41 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 16:50:41 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 16:50:41 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 16:50:41 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 16:50:41 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 16:50:43 INFO - Downloading mozsystemmonitor-0.0.tar.gz 16:50:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:50:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 16:50:43 INFO - Installing collected packages: mozsystemmonitor 16:50:43 INFO - Running setup.py install for mozsystemmonitor 16:50:43 INFO - Successfully installed mozsystemmonitor 16:50:43 INFO - Cleaning up... 16:50:43 INFO - Return code: 0 16:50:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:50:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:50:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:50:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:50:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:50:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:50:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:50:43 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 0x101f4ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f52570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6d04b79e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101df0d30>, '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 0x101c14db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101de7b20>, '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.UEqlyLYoYl/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.827T5vvaGV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 16:50:43 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 16:50:43 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 16:50:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UEqlyLYoYl/Render', 16:50:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:50:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:50:43 INFO - 'HOME': '/Users/cltbld', 16:50:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:50:43 INFO - 'LOGNAME': 'cltbld', 16:50:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:50:43 INFO - 'MOZ_NO_REMOTE': '1', 16:50:43 INFO - 'NO_EM_RESTART': '1', 16:50:43 INFO - 'PAGER': '/bin/cat', 16:50:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:50:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:50:43 INFO - 'PWD': '/builds/slave/test', 16:50:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:50:43 INFO - 'SHELL': '/bin/bash', 16:50:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.827T5vvaGV/Listeners', 16:50:43 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 16:50:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:50:43 INFO - 'USER': 'cltbld', 16:50:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:50:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:50:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:50:43 INFO - 'XPC_FLAGS': '0x0', 16:50:43 INFO - 'XPC_SERVICE_NAME': '0', 16:50:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:50:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:50:44 INFO - Downloading/unpacking blobuploader==1.2.4 16:50:44 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 16:50:44 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 16:50:44 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 16:50:44 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 16:50:44 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 16:50:44 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 16:50:46 INFO - Downloading blobuploader-1.2.4.tar.gz 16:50:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:50:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:50:46 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 16:50:46 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 16:50:46 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 16:50:46 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 16:50:46 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 16:50:46 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 16:50:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:50:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:50:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:50:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:50:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:50:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:50:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:50:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:50:47 INFO - Downloading docopt-0.6.1.tar.gz 16:50:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:50:47 INFO - Installing collected packages: blobuploader, requests, docopt 16:50:47 INFO - Running setup.py install for blobuploader 16:50:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:50:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:50:48 INFO - Running setup.py install for requests 16:50:48 INFO - Running setup.py install for docopt 16:50:48 INFO - Successfully installed blobuploader requests docopt 16:50:48 INFO - Cleaning up... 16:50:48 INFO - Return code: 0 16:50:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:50:48 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')]} 16:50:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:50:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:50:48 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')]} 16:50:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:50:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:50:48 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 0x101f4ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f52570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6d04b79e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101df0d30>, '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 0x101c14db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101de7b20>, '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.UEqlyLYoYl/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.827T5vvaGV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 16:50:48 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 16:50:48 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 16:50:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UEqlyLYoYl/Render', 16:50:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:50:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:50:48 INFO - 'HOME': '/Users/cltbld', 16:50:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:50:48 INFO - 'LOGNAME': 'cltbld', 16:50:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:50:48 INFO - 'MOZ_NO_REMOTE': '1', 16:50:48 INFO - 'NO_EM_RESTART': '1', 16:50:48 INFO - 'PAGER': '/bin/cat', 16:50:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:50:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:50:48 INFO - 'PWD': '/builds/slave/test', 16:50:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:50:48 INFO - 'SHELL': '/bin/bash', 16:50:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.827T5vvaGV/Listeners', 16:50:48 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 16:50:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:50:48 INFO - 'USER': 'cltbld', 16:50:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:50:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:50:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:50:48 INFO - 'XPC_FLAGS': '0x0', 16:50:48 INFO - 'XPC_SERVICE_NAME': '0', 16:50:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:50:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:50:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:50:49 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-hbh0ZJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:50:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:50:49 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-OWTTmA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:50:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:50:49 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-Y_p9CD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:50:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:50:49 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-wkqcgm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:50:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:50:49 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-78tuD7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:50:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:50:49 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-q3zPoA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:50:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:50:49 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-Cw5ZC1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:50:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:50:49 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-3mEnHo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:50:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:50:49 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-qnXIib-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:50:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:50:49 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-js6GvB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:50:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:50:50 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-7lNRJ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:50:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:50:50 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-ZrsYIj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:50:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:50:50 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-alWzvB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:50:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:50:50 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-naQBGu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:50:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:50:50 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-vcDbVI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:50:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:50:50 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-cqP1yK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:50:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:50:50 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-nCZPcD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:50:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:50:50 INFO - Running setup.py install for manifestparser 16:50:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:50:50 INFO - Running setup.py install for mozcrash 16:50:51 INFO - Running setup.py install for mozdebug 16:50:51 INFO - Running setup.py install for mozdevice 16:50:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:50:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:50:51 INFO - Running setup.py install for mozfile 16:50:51 INFO - Running setup.py install for mozhttpd 16:50:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:50:51 INFO - Running setup.py install for mozinfo 16:50:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:50:51 INFO - Running setup.py install for mozInstall 16:50:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:50:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:50:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:50:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:50:51 INFO - Running setup.py install for mozleak 16:50:52 INFO - Running setup.py install for mozlog 16:50:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:50:52 INFO - Running setup.py install for moznetwork 16:50:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:50:52 INFO - Running setup.py install for mozprocess 16:50:52 INFO - Running setup.py install for mozprofile 16:50:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:50:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:50:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:50:52 INFO - Running setup.py install for mozrunner 16:50:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:50:52 INFO - Running setup.py install for mozscreenshot 16:50:53 INFO - Running setup.py install for moztest 16:50:53 INFO - Running setup.py install for mozversion 16:50:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:50:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:50:53 INFO - Cleaning up... 16:50:53 INFO - Return code: 0 16:50:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:50:53 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')]} 16:50:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:50:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:50:53 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')]} 16:50:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:50:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:50:53 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 0x101f4ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f52570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6d04b79e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101df0d30>, '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 0x101c14db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101de7b20>, '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.UEqlyLYoYl/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.827T5vvaGV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 16:50:53 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 16:50:53 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 16:50:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UEqlyLYoYl/Render', 16:50:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:50:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:50:53 INFO - 'HOME': '/Users/cltbld', 16:50:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:50:53 INFO - 'LOGNAME': 'cltbld', 16:50:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:50:53 INFO - 'MOZ_NO_REMOTE': '1', 16:50:53 INFO - 'NO_EM_RESTART': '1', 16:50:53 INFO - 'PAGER': '/bin/cat', 16:50:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:50:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:50:53 INFO - 'PWD': '/builds/slave/test', 16:50:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:50:53 INFO - 'SHELL': '/bin/bash', 16:50:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.827T5vvaGV/Listeners', 16:50:53 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 16:50:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:50:53 INFO - 'USER': 'cltbld', 16:50:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:50:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:50:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:50:53 INFO - 'XPC_FLAGS': '0x0', 16:50:53 INFO - 'XPC_SERVICE_NAME': '0', 16:50:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:50:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:50:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:50:53 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-TY55GZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:50:53 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)) 16:50:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:50:53 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-vxsXBh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:50:53 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)) 16:50:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:50:53 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-fHPJyi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:50:53 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)) 16:50:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:50:53 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-NAppAT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:50:54 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)) 16:50:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:50:54 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-4Xod6p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:50:54 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)) 16:50:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:50:54 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-uiB1eU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:50:54 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)) 16:50:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:50:54 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-S1ZSPZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:50:54 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)) 16:50:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:50:54 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-U7wmu8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:50:54 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)) 16:50:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:50:54 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-zduO1E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:50:54 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)) 16:50:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:50:54 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-LaFcM3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:50:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 16:50:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:50:54 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-KmKkKZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:50:54 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)) 16:50:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:50:54 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-HdAChF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:50:54 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)) 16:50:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:50:54 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-E6atnA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:50:55 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)) 16:50:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:50:55 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-_Ghp88-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:50:55 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)) 16:50:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:50:55 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-xgiDsI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:50:55 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)) 16:50:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:50:55 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-qS1ndV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:50:55 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)) 16:50:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:50:55 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-nhCWoy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:50:55 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)) 16:50:55 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)) 16:50:55 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)) 16:50:55 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)) 16:50:55 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)) 16:50:55 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)) 16:50:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:50: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 16:50: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 16:50: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 16:50: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 16:50: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 16:50: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 16:50:57 INFO - Downloading blessings-1.6.tar.gz 16:50:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:50:58 INFO - Installing collected packages: blessings 16:50:58 INFO - Running setup.py install for blessings 16:50:58 INFO - Successfully installed blessings 16:50:58 INFO - Cleaning up... 16:50:58 INFO - Return code: 0 16:50:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:50:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:50:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:50:58 INFO - Reading from file tmpfile_stdout 16:50:58 INFO - Current package versions: 16:50:58 INFO - blessings == 1.6 16:50:58 INFO - blobuploader == 1.2.4 16:50:58 INFO - docopt == 0.6.1 16:50:58 INFO - manifestparser == 1.1 16:50:58 INFO - mozInstall == 1.12 16:50:58 INFO - mozcrash == 0.17 16:50:58 INFO - mozdebug == 0.1 16:50:58 INFO - mozdevice == 0.48 16:50:58 INFO - mozfile == 1.2 16:50:58 INFO - mozhttpd == 0.7 16:50:58 INFO - mozinfo == 0.9 16:50:58 INFO - mozleak == 0.1 16:50:58 INFO - mozlog == 3.1 16:50:58 INFO - moznetwork == 0.27 16:50:58 INFO - mozprocess == 0.22 16:50:58 INFO - mozprofile == 0.28 16:50:58 INFO - mozrunner == 6.11 16:50:58 INFO - mozscreenshot == 0.1 16:50:58 INFO - mozsystemmonitor == 0.0 16:50:58 INFO - moztest == 0.7 16:50:58 INFO - mozversion == 1.4 16:50:58 INFO - psutil == 3.1.1 16:50:58 INFO - requests == 1.2.3 16:50:58 INFO - wsgiref == 0.1.2 16:50:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:50:58 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')]} 16:50:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:50:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:50:58 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')]} 16:50:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:50:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:50:58 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 0x101f4ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f52570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6d04b79e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101df0d30>, '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 0x101c14db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101de7b20>, '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.UEqlyLYoYl/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.827T5vvaGV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 16:50:58 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 16:50:58 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 16:50:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UEqlyLYoYl/Render', 16:50:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:50:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:50:58 INFO - 'HOME': '/Users/cltbld', 16:50:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:50:58 INFO - 'LOGNAME': 'cltbld', 16:50:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:50:58 INFO - 'MOZ_NO_REMOTE': '1', 16:50:58 INFO - 'NO_EM_RESTART': '1', 16:50:58 INFO - 'PAGER': '/bin/cat', 16:50:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:50:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:50:58 INFO - 'PWD': '/builds/slave/test', 16:50:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:50:58 INFO - 'SHELL': '/bin/bash', 16:50:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.827T5vvaGV/Listeners', 16:50:58 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 16:50:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:50:58 INFO - 'USER': 'cltbld', 16:50:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:50:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:50:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:50:58 INFO - 'XPC_FLAGS': '0x0', 16:50:58 INFO - 'XPC_SERVICE_NAME': '0', 16:50:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:50:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:50:58 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)) 16:50:58 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)) 16:50:58 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)) 16:50:58 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)) 16:50:58 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)) 16:50:58 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)) 16:50:58 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)) 16:50:58 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)) 16:50:58 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)) 16:50:58 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)) 16:50:58 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)) 16:50:58 INFO - Cleaning up... 16:50:58 INFO - Return code: 0 16:50:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:50:58 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')]} 16:50:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:50:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:50:58 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')]} 16:50:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:50:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:50:58 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 0x101f4ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f52570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6d04b79e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101df0d30>, '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 0x101c14db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101de7b20>, '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.UEqlyLYoYl/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.827T5vvaGV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 16:50:58 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 16:50:58 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 16:50:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UEqlyLYoYl/Render', 16:50:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:50:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:50:58 INFO - 'HOME': '/Users/cltbld', 16:50:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:50:58 INFO - 'LOGNAME': 'cltbld', 16:50:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:50:58 INFO - 'MOZ_NO_REMOTE': '1', 16:50:58 INFO - 'NO_EM_RESTART': '1', 16:50:58 INFO - 'PAGER': '/bin/cat', 16:50:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:50:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:50:58 INFO - 'PWD': '/builds/slave/test', 16:50:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:50:58 INFO - 'SHELL': '/bin/bash', 16:50:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.827T5vvaGV/Listeners', 16:50:58 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 16:50:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:50:58 INFO - 'USER': 'cltbld', 16:50:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:50:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:50:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:50:58 INFO - 'XPC_FLAGS': '0x0', 16:50:58 INFO - 'XPC_SERVICE_NAME': '0', 16:50:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:50:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:50:59 INFO - Downloading/unpacking jsonschema 16:50: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 16:50: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 16:50: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 16:50: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 16:50: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 16:50: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 16:51:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:51:01 INFO - Downloading/unpacking functools32 (from jsonschema) 16:51:01 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 16:51:01 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 16:51:01 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 16:51:01 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 16:51:01 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 16:51:01 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 16:51:01 INFO - Downloading functools32-3.2.3-2.tar.gz 16:51:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:51:01 INFO - warning: no files found matching '*.txt' 16:51:01 INFO - no previously-included directories found matching 'build' 16:51:01 INFO - no previously-included directories found matching 'dist' 16:51:01 INFO - no previously-included directories found matching '.git*' 16:51:01 INFO - Installing collected packages: jsonschema, functools32 16:51:02 INFO - Running setup.py install for functools32 16:51:02 INFO - warning: no files found matching '*.txt' 16:51:02 INFO - no previously-included directories found matching 'build' 16:51:02 INFO - no previously-included directories found matching 'dist' 16:51:02 INFO - no previously-included directories found matching '.git*' 16:51:02 INFO - Successfully installed jsonschema functools32 16:51:02 INFO - Cleaning up... 16:51:02 INFO - Return code: 0 16:51:02 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 16:51: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')]} 16:51:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:51:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:51: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')]} 16:51:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:51:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:51: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101f4ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f52570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6d04b79e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101df0d30>, '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 0x101c14db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101de7b20>, '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.UEqlyLYoYl/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.827T5vvaGV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 16:51: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', 'flake8'] in /builds/slave/test/build 16:51: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 flake8 16:51:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UEqlyLYoYl/Render', 16:51:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:51:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:51:02 INFO - 'HOME': '/Users/cltbld', 16:51:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:51:02 INFO - 'LOGNAME': 'cltbld', 16:51:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:51:02 INFO - 'MOZ_NO_REMOTE': '1', 16:51:02 INFO - 'NO_EM_RESTART': '1', 16:51:02 INFO - 'PAGER': '/bin/cat', 16:51:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:51:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:51:02 INFO - 'PWD': '/builds/slave/test', 16:51:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:51:02 INFO - 'SHELL': '/bin/bash', 16:51:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.827T5vvaGV/Listeners', 16:51:02 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 16:51:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:51:02 INFO - 'USER': 'cltbld', 16:51:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:51:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:51:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:51:02 INFO - 'XPC_FLAGS': '0x0', 16:51:02 INFO - 'XPC_SERVICE_NAME': '0', 16:51:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:51:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:51:02 INFO - Downloading/unpacking flake8 16:51: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 16:51: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 16:51: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 16:51: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 16:51: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 16:51: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 16:51:04 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:51:05 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:51: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 16:51: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 16:51: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 16:51: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 16:51: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 16:51: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 16:51:05 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:51:05 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:51: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 16:51: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 16:51: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 16:51: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 16:51: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 16:51: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 16:51:05 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:51: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 16:51: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 16:51: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 16:51: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 16:51: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 16:51: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 16:51:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 16:51:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:51:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:51:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:51:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:51:06 INFO - no previously-included directories found matching 'docs/_build' 16:51:06 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:51:06 INFO - Running setup.py install for pep8 16:51:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:51:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:51:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:51:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:51:06 INFO - no previously-included directories found matching 'docs/_build' 16:51:06 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 16:51:06 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:51:06 INFO - Cleaning up... 16:51:06 INFO - Return code: 0 16:51:06 INFO - Running post-action listener: _resource_record_post_action 16:51:06 INFO - Running post-action listener: _start_resource_monitoring 16:51:06 INFO - Starting resource monitoring. 16:51:06 INFO - ##### 16:51:06 INFO - ##### Finished create-virtualenv step (success) 16:51:06 INFO - ##### 16:51:06 INFO - ##### 16:51:06 INFO - ##### Running install step. 16:51:06 INFO - ##### 16:51:06 INFO - Running pre-action listener: _resource_record_pre_action 16:51:06 INFO - Running main action method: install 16:51:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:51:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:51:06 INFO - Reading from file tmpfile_stdout 16:51:06 INFO - Detecting whether we're running mozinstall >=1.0... 16:51:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:51:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:51:07 INFO - Reading from file tmpfile_stdout 16:51:07 INFO - Output received: 16:51:07 INFO - Usage: mozinstall [options] installer 16:51:07 INFO - Options: 16:51:07 INFO - -h, --help show this help message and exit 16:51:07 INFO - -d DEST, --destination=DEST 16:51:07 INFO - Directory to install application into. [default: 16:51:07 INFO - "/builds/slave/test"] 16:51:07 INFO - --app=APP Application being installed. [default: firefox] 16:51:07 INFO - mkdir: /builds/slave/test/build/application 16:51:07 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'] 16:51:07 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 16:51:40 INFO - Reading from file tmpfile_stdout 16:51:40 INFO - Output received: 16:51:40 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:51:40 INFO - Running post-action listener: _resource_record_post_action 16:51:40 INFO - ##### 16:51:40 INFO - ##### Finished install step (success) 16:51:40 INFO - ##### 16:51:40 INFO - ##### 16:51:40 INFO - ##### Running run-tests step. 16:51:40 INFO - ##### 16:51:40 INFO - Running pre-action listener: _resource_record_pre_action 16:51:40 INFO - Running main action method: run_tests 16:51:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:51:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:51:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:51:40 INFO - 2016-05-24 16:51:40.404 screenresolution[1644:8058] starting screenresolution argv=screenresolution get 16:51:40 INFO - 2016-05-24 16:51:40.406 screenresolution[1644:8058] Display 0: 1600x1200x32@60 16:51:40 INFO - 2016-05-24 16:51:40.412 screenresolution[1645:8060] starting screenresolution argv=screenresolution list 16:51:40 INFO - Available Modes on Display 0 16:51:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:51:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:51:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:51:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:51:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:51:40 INFO - Intel Iris: 16:51:40 INFO - Chipset Model: Intel Iris 16:51:40 INFO - Type: GPU 16:51:40 INFO - Bus: Built-In 16:51:40 INFO - VRAM (Dynamic, Max): 1536 MB 16:51:40 INFO - Vendor: Intel (0x8086) 16:51:40 INFO - Device ID: 0x0a2e 16:51:40 INFO - Revision ID: 0x0009 16:51:40 INFO - Displays: 16:51:40 INFO - Display: 16:51:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:51:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:51:40 INFO - Display Serial Number: 1600x1200 60 16:51:40 INFO - Main Display: Yes 16:51:40 INFO - Mirror: Off 16:51:40 INFO - Online: Yes 16:51:40 INFO - Rotation: Supported 16:51:40 INFO - Return code: 0 16:51:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:51:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:51:40 INFO - Python 2.7.3 16:51:40 INFO - Return code: 0 16:51:40 INFO - grabbing minidump binary from tooltool 16:51:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:51:40 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 0x101df0d30>, '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 0x101c14db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101de7b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:51:40 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 16:51:40 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 16:51:40 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 16:51:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:51:40 INFO - Return code: 0 16:51:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:51:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:51:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:51:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:51:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:51:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 16:51:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 16:51:41 INFO - Return code: 0 16:51:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dBlJAsFsQcegm3CAmX3zAg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0318', '--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 16:51:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dBlJAsFsQcegm3CAmX3zAg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0318 --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 16:51:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UEqlyLYoYl/Render', 16:51:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:51:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:51:41 INFO - 'HOME': '/Users/cltbld', 16:51:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:51:41 INFO - 'LOGNAME': 'cltbld', 16:51:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:51:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:51:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:51:41 INFO - 'MOZ_NO_REMOTE': '1', 16:51:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:51:41 INFO - 'NO_EM_RESTART': '1', 16:51:41 INFO - 'PAGER': '/bin/cat', 16:51:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:51:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:51:41 INFO - 'PWD': '/builds/slave/test', 16:51:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:51:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:51:41 INFO - 'SHELL': '/bin/bash', 16:51:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.827T5vvaGV/Listeners', 16:51:41 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 16:51:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:51:41 INFO - 'USER': 'cltbld', 16:51:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:51:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:51:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:51:41 INFO - 'XPC_FLAGS': '0x0', 16:51:41 INFO - 'XPC_SERVICE_NAME': '0', 16:51:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:51:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dBlJAsFsQcegm3CAmX3zAg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0318', '--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 16:51:42 INFO - mozversion application_buildid: 20160524144846 16:51:42 INFO - mozversion application_changeset: 96e80e3b85ff1f96f67991a0a167cfc7a66b9743 16:51:42 INFO - mozversion application_display_name: Nightly 16:51:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:51:42 INFO - mozversion application_name: Firefox 16:51:42 INFO - mozversion application_remotingname: firefox 16:51:42 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 16:51:42 INFO - mozversion application_vendor: Mozilla 16:51:42 INFO - mozversion application_version: 49.0a1 16:51:42 INFO - mozversion platform_buildid: 20160524144846 16:51:42 INFO - mozversion platform_changeset: 96e80e3b85ff1f96f67991a0a167cfc7a66b9743 16:51:42 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 16:51:42 INFO - mozversion platform_version: 49.0a1 16:51:42 INFO - using testdate: 1464133902 16:51:42 INFO - actual date: 1464133902 16:51:42 INFO - starting webserver on 'localhost:49277' 16:51:42 INFO - SUITE-START | Running 2 tests 16:51:42 INFO - TEST-START | dromaeo_css 16:51:42 INFO - operating with platform_type : mac_ 16:51:42 INFO - Initialising browser for dromaeo_css test... 16:51:42 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/tmppGEZCT/profile http://localhost:49277/getInfo.html) 16:51:44 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 16:51:44 INFO - PROCESS | 1663 | colorDepth:24 16:51:44 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 16:51:44 INFO - PROCESS | 1663 | __metrics 16:51:45 ERROR - PROCESS | 1663 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 16:51:45 INFO - TEST-INFO | 1663: exit 0 16:51:45 INFO - Browser initialized. 16:51:45 INFO - Running cycle 1/1 for dromaeo_css test... 16:51:45 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/tmppGEZCT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:51:57 INFO - PROCESS | 1667 | RSS: Main: 249843712 16:51:57 INFO - PROCESS | 1667 | 16:52:47 INFO - PROCESS | 1667 | 1464133967309 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] 16:54:58 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 16:54:59 INFO - PROCESS | 1667 | RSS: Main: 261361664 16:54:59 INFO - PROCESS | 1667 | 16:58:01 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 16:58:02 INFO - PROCESS | 1667 | RSS: Main: 273453056 16:58:02 INFO - PROCESS | 1667 | 17:01:03 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 17:01:04 INFO - PROCESS | 1667 | RSS: Main: 274604032 17:01:04 INFO - PROCESS | 1667 | 17:04:06 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 17:04:06 INFO - PROCESS | 1667 | RSS: Main: 278626304 17:04:06 INFO - PROCESS | 1667 | 17:07:09 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 17:07:10 INFO - PROCESS | 1667 | RSS: Main: 278138880 17:07:10 INFO - PROCESS | 1667 | 17:10:13 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 17:10:13 INFO - PROCESS | 1667 | RSS: Main: 280489984 17:10:13 INFO - PROCESS | 1667 | 17:10:13 INFO - PROCESS | 1667 | __start_tp_report 17:10:13 INFO - PROCESS | 1667 | _x_x_mozilla_page_load 17:10:13 INFO - PROCESS | 1667 | _x_x_mozilla_page_load_details 17:10:13 INFO - PROCESS | 1667 | |i|pagename|runs| 17:10:13 INFO - PROCESS | 1667 | |0;dojo.html;2916.5041942869725;3135.2945587242875;3543.255916257586;3547.2905418916225;3563.127033876701;5138.511841375063;5313.20301954707;5793.652380857146;5795.536357091433;5925.111233314994;4747.8575642730775;5678.063119585274;5755.597108202427;5784.1902133701215;5958.672273024982;5194.71429071401;5233.058049551079;5265.999460102587;5363.731813409334;5371.113766228567;398.26492990092123;5320.600954928376;5324;5325.893482130355;5376.435474275204;5285.260063591119;5291.788328466876;5292.259083728242;5311.548518375943;5320.361556613192;5216.5305122538775;5322.4411436799355;5332.986732520809;5362.812301569426;5373.355197376301;5194.298769666122;5202.92195617066;5263.44733802949;5281.867953301198;5318.601104917108;5668.801591944262;5769.336526299488;5772.769089236425;5785;5843.941560584425;6121.204243448367;6254.437100660963;6347.682615869188;6363.172787537636;6415.518836087312;6273.686315684197;6333.366663333676;6341;6374.713137908805;6394.904076438857;5933.169356290123;5949.791757288517;5956.273038016751;5979.730912108966;5988.431099045627;6242.781502647476;6262.530310226751;6278.497720182466;6359.459445947054;6392.137061496732;6246;6281.748730050746;6285.9057114143325;6295.6537390443045;6318.304986451487;6272.937270627326;6337.397947195009;6365.554411191265;6370.77702280418;6375;5253.789848406021;6123.740204357194;6224.937750622526;6259.311475737665;6324.367563243731;6161.476274516626;6235.314115447297;6313;6322.589031712924;6326.082718005955;4983.8504844854715;5027.798888044437;5028.446870844205;5030.647854650212;5041.899162016739;4476.888077798016;4486.439195100613;4509.616682582017;4564.109998550317;4580.4045474088825;4606.677532572755;5806.44838740319;5855.355910849803;5872.383399743051;5884.588078834526;6302.621842689453;6342.714577844008;6344.984802431589;6347.39699728525;6349.301576826545;5599.860003499945;5613.7193140342815;5638.295213098363;5677.687727174963;5717.113847353667;5295.655782374133;5305.867353316121;5309.761060752276;5330.706811125426;5336.653117547347;5000.724960127228;5945.702714864239;6191.288001879841;6235.533562135802;6345.07996340528;6236.345183755821;6255.186825712628;6312.652804095819;6323.557350985387;6373.330800266091;6390;6405.775797847239;6406.839829004312;6432.453241474527;6439.645819479786;6151;6181.35095814933;6297.118403423433;6335.208098987627;6340.968295158495;6238.906416403667;6341.682915854097;6352.809415717536;6356.396142366468;6368.108464814837;6224.813255602339;6235.3452887446165;6236.438720515175;6241.906371404523;6266.561340706106;5218.112920803548;6105.186826067751;6126.081087836682;6223.37766223374;6226.968865155645;5526.336839579116;5617.241672374177;5633.2113504110275;5688.40271771458;5943.673097979479;5798.449147330997;5803.332616749128;5809.680467574155;5819.970900145472;5840.474357307863;5716.342620598684;5717.828465146053;5925.674087925033;6037.335893051673;6083.69581520931 17:10:13 INFO - PROCESS | 1667 | |1;ext.html;521.9373675158979;522.7516929458506;538.2572050570213;564.7096384760821;569.9996506131738;36326.910192694195;43461.99999999996;43528.78235608817;43534.78232608837;43538.782306088426;42203.15593688132;43693.34459983103;44156.558434415645;44244.55755442445;44255.55744442555;9292;9863.358881672704;9904.356216845918;9922.107010369065;9940.403575785476;9800.215982721365;9846;9888.90111098887;9895.158911492495;9897.703068907907;9896.851547226797;9931.652392166276;9937.652182173626;9943;9944.701658950207;9795.706128816148;9820.65627703027;9843.557039933185;9887.307888447811;9896.901030989706;9753.512324383822;9760.560774765152;9769.853452198151;9777.657781977596;9851.655192068243;40569.797151014354;41256;41294.967625809295;41345.1730965382;41426.37860432096;3154.7318477929402;3183.2519357950864;3375.476801095834;3581.8925432237074;3781.2059467511854;29233.123006309845;29509.8196072159;29685.554716679268;30048;30137;2764.378014946641;2795.65054368204;2802.63565736455;2883.1494709060876;2911.5923770672125;467103;467986;474473;474772;475364;35910.46134307988;66243.66878165578;67308;67335;67340.6632966832;31503.36993260122;32722.509162362585;36700;37833.24333513314;38126.80936595299;15484.993475424062;15671.294791734399;15701.371945122295;15708.21458927072;15714.528564143095;11183.32900026001;12753;13015.414306356237;13117.475300988044;13120.015998800129;8347.916520834835;8357.414980951397;8366.456180348257;8382.161783821692;8417.957910210533;3143.764217683683;3336.8164750938445;3428.177237462991;3440.535527703778;3442.1394651337164;1014.4420568687193;1027.866377370937;1059.9523021464054;1097.91765617579;1113.3208742666966;25250.747492525206;30669.69330306713;30742.07773766746;31123.377532449224;31522.684773151974;1074.623881641435;1086.364476781092;1089.3844977587637;1092.7541303206715;1112.4826955465746;14626.488072917396;15874.920625396799;16084.839151608568;16224;16253.593660158354;654330;661253;661662;667464;668762;43689;43914.56085439105;44322;44787.77606111948;44985.77507112444;1439.8344190418027;1453.7528620134603;1479.0534058202975;1483.169425121935;1483.1842486632308;30447.543286850272;30478.847605761828;30733.53899691551;30956.69043309538;31105.8444707775;56599.43400566106;57020.144697828735;57156;57334.7133264331;57732.84534309206;499564;500028;500204;500689;501204;26976.73023269742;27922.860385697943;28032.57951130694;28119.859400702866;28291;48635.27047094367;48832.02335953332;49077.01845962989;49407.50592494029;50172.74913625408;38943.221135577696;40322.395164071975;40547.1890562193;40808;41039.17921641491;39439;39574;40347.59652403439;40496.392554111124;40611.390829136995;33581.49627755635;39151.804240978614;39338.016549586486;39980.800575982765;40955;33398.30092392914;34628.65371346355;35070;35403.29193416169;35413.645863542064;23873.16443924454;24134;25662.743372566783;26205;26429 17:10:13 INFO - PROCESS | 1667 | |2;jquery.html;392.63288824948694;395.2588895820337;397.96418322350985;401.08552500299294;419.1533103131674;14756.483523076695;19488.02559872006;20802.583948321062;20816.791832081675;20862;19102;19974.20103195874;20345.796542034575;20738.274160404388;21517.56964860702;5637.436256374351;5856.150858125569;6009.5793294469295;6013.248343957005;6053.394660533933;5858.443447872445;5977.88044239115;6017.247844019497;6154.661493617849;6166.722497487602;6075.969620151893;6125.295591007024;6161.045038019114;6172.9074063889075;6185.938140618604;5275.7338238822595;5756.481916627503;6039.547033972426;6127.479164271064;6137.9079313810325;5043.798248070073;5338.386085600167;5411.56707463402;5841.678707671076;5994.460498555108;12720.936395317964;13512.59462216135;13728.382222799997;21147.682784758243;25315.240542783748;2057.434205593459;2073.9377818665466;2094.717213176217;2123.097683484513;2255.8195344372575;7916.802079948047;8391.580420978926;9789.118979291898;10225.846612300822;10514.421706806123;1697.507722760398;1702.7616133741285;1728.930842766288;1746.1269365317341;1776.7157254839165;114548.42725786401;119419;120167.3991630045;120691.79308206894;120701;11781.116416268815;11830.763384732258;11834.408279585987;11915.076474144596;11984.340861252715;14631.122132672073;14641.34113964874;14924.552263432115;14994.025388349723;15062.171580563174;8719.215270625675;8768.035516093225;8783.736487905373;8795.91204087951;8886.511241881759;7266.382357499566;8730.21428071464;8824.647014119364;8835.823283534293;8838.646454141892;4560.201964656172;4606.723596584216;4690.64820138491;4694.2723877799;4806.831760888422;1937.6996565532365;1999.1403696410682;2000.5298754792611;2029.046348216336;2039.8878061706748;938.990610093904;988.3476905242505;992.4194346307347;1017.730301470111;1038.5586125896464;18574.53563660892;20180;20471.28350507755;20809.1676332945;20905.954702264826;921.7924518880288;976.0678551982982;983.9950800245953;988.6984469736799;992.2954702161402;10690.305130166515;10889.727756806144;10890.72773180661;10892.128629709605;10909.23635345534;565672.1716391392;601245;604174;604688;609832;114379.42810286062;126186;127824.36087819502;141224;149145;920.604140219712;1045.869266341692;1051.4952822645173;1120.9607663731892;1122.511707407264;18020.36928707489;18401.263949441873;18411.26354945787;18435.26258949627;18598.628027439107;40343.596564033985;41849.48058095239;54606.72696636491;55303.34089977307;55525.7223713895;61433.38566614455;80531.59734201292;81819.36361272621;82223.17776822395;82479.76280355919;5842.094475356326;7395.112586489656;7412;7488.363489103487;8170.836583268182;9320.207782338577;11157;12333.136680432284;12351.073669474647;12549.435275412447;10341.224408169568;10722.303050301705;10844.45777711127;11062.944685276521;11101.444927753419;10327.277090603586;10738.73153171177;10933.125349971824;11051.944740276569;11202.327860328407;9059.68291109808;10740.872208418315;11225.382603956861;11278.887211127783;11407.372594507382;8121.918780812352;8129.146439623754;8131.593420329078;8334.416590838826;8472.576371181292;9114;10506.369617822955;10937.890621093688;11008.669739907815;11210.887891120985 17:10:13 INFO - PROCESS | 1667 | |3;mootools.html;1559.8908076434648;1627.5198816349175;1900.8574356923234;1943.8250557449826;2008.8995550222494;7741.264579864911;7986.520808751479;8557.529335886524;8587.527685977277;9106.635734570618;8611.966564012311;8699.434536755107;8725.301975841934;8820.20618144367;8826.470411775297;2406.4946361264156;2416.214730212679;2436.8172387070954;2442;2449.865257410842;2375.3824005758456;2420.2860156253946;2440.987795061022;2442.0842184180933;2458.0782206672498;2409.0484258717797;2423.357810180295;2435.257246539805;2435.43984883476;2438.426969662128;2404.5551572959057;2418.9516209675794;2425.769551892576;2426.4055306449854;2432;2381.7141942966955;2410.2166795791263;2410.614301711735;2412.264259400882;2421.2978236311446;8723.345749068845;8757.605907734167;8802.075782042857;8808.163224493663;8838.558072096434;5157.174852023696;5314.681119132864;5338.332708411449;5339.09235429974;5353.411124776271;2879.280179955011;3392.8812491562676;3458.913527161841;3500.8599656013985;3517.0152357339985;1050.9159267258601;1056.2395075545594;1059.3961441978076;1061.723951772537;1065.7548763784296;10396.272260941738;10575.36547807134;11812.409379531075;11876.228045177122;11882.168248222628;4760.523947605212;4786.928196077061;4820.6143508519235;4875.5124487551675;4877.268409738567;9846.409215446953;9951.651692190739;10010.249231307682;10039.69880903574;10059.44673042975;5141.71720555373;5208.218767184952;5248.396434410015;5264;5268.578513718893;4284.785760711952;4636.211843986531;4706.458757242961;4797.304390863375;4809.326694262806;3355.614104377979;4043.9890027493125;4104.91790164195;4123.773192474383;4185.769782661922;1341.53716967646;1342.2617560341773;1342.3020029584561;1351.6823546466574;1357.4230951845623;850.013983778826;868.3617541107408;874.0254616102999;908.5911339897073;910.2626872233512;5760.279965004374;5764.164196191528;5974.731137098841;6060.454559089703;6089.695515224221;911.3802614222392;941.9623215071321;975.2295686407658;980.3382716666364;984.3798407003685;3889.2804831106337;3932.2135572884963;3958.386450100239;3992.3412636915164;4010.8997275067773;10527.526261318259;10996.945015274872;11009.889901101047;11072.446377681244;11076.280041797258;9147.67983120601;10748.838767418494;10764.838527421938;10919.235653504338;10924.2899211551;6443.96778016107;7239.9276007240305;8371.995360556772;8415.368847336475;8441.873371899426;5594.300712410949;5797.449242321973;5880.970830104629;5961.314448838439;5961.463468287875;8300.626471808662;8489.83020339602;9947.651832185838;10164.796704066026;10229.948850255701;9494.145526902612;10004.749881253027;10009.449480278654;10143;10263.384196948207;6149.893019256577;6909.619970901648;6998.475114366341;7240.637968101679;7554.244575542401;8246.793830154294;8366.539840308842;8373.623186956724;8413.747587572383;8450.366222533212;7289.635518224173;7350.08123984502;7457.888131677921;7579.090509138939;7604.9733286005685;7280.3083707047745;7323.450741194325;7487.8876816846705;7516.135644400964;7552.244775522624;7080.114985626797;7097.822554435974;7154.427645788221;7170.641467926478;7201.4958952874895;4833.202521583899;4837.153498137742;4944.233643785219;5009.649324547392;5141.151709967813;4514.0069184779295;4868.245421959602;4920.8682003138765;4985.9501404985485;5228.686278823283 17:10:13 INFO - PROCESS | 1667 | |4;prototype.html;254.81907845429743;315.68431568431566;318.1188108938241;319.9952000719989;320.9406259841931;4794.017226468565;4831.391048833626;5427.209021023063;5466.453354664532;5491.533219676322;4763.4998325175875;5176.705823544114;5364.356277246736;5364.417496838432;5372.952274306502;3828.9808550957277;3871.7676939383587;3913.373860182357;4084.4894388201474;4090.3046482097966;3378.712809411203;3980.701447391443;4049.514058312992;4053.5338436079933;4074.775887326196;3823.5602905665787;4009.7393669411395;4027.556968733437;4057.5333836608725;4071.531773846016;3710.406334986389;4002.259017327472;4026.6979976501734;4027.536833264183;4059.8376064957365;3242.513622956552;3455.94816077759;3474.5483087198504;3486.9825650871835;3516.7714098583765;4377.037051848588;4807.591354734816;4924.990376972712;5066.316047333599;5189.455107213764;3860.903477413059;4124.793760311973;4596.264597664391;4644.419447569054;4660.440747110369;4730.793647619835;5113.897722045538;5141.562967147797;5557.722113894289;5636.267285252932;3153.1486498645468;3172.0800967719333;3300.9834950825334;3769.963260103466;3939.7439166454087;4590.472095708969;4884.975575122137;5363.115086010804;5917.349091599921;5921.378255283219;3332.800031998088;3573.4103872860687;3617.276544691072;3762.811859407019;3927.273454410943;3258.9511157332186;3812.5806161322234;3839.9248210501105;3939.8574413419788;3990.06233535119;4263.978680106642;4370.366296886998;4492.3710680504755;4565.452145742533;4652.185867473247;5188.92216616751;5651.519620832275;5656.377798442168;5726.026575482178;5742.0953751449415;1291.6770807298176;1332.6868185976289;1353.5668586052368;1358.9252591107386;1361.6995769040543;828.4035494443991;844.5734903873505;847.2247893177673;851.1148405658047;895.5656506594297;5186.429492755794;5200.141976573823;5213.452587478336;5220.738963051832;5256.894862102737;5308.548773354308;5761.971190144022;5762.337331206881;5776.249087618583;5780.034788172042;4877.951220487821;4953.603711703127;5160.380754309507;5165.612579056586;5182.766775495036;2777.8471934147506;2942.985285073561;3027.516516906744;3040.9087727368214;3048.2531779714113;4708.987567672967;4846.927296090562;5304.416514183436;5614.277075618322;5992.40075992406;5090.414602320707;5127.487251274917;5146.742662866842;5270.710110943858;5883.646321596649;5971;6012.278526576839;6040.124181993674;6049.667268300285;6073.635581865102;4712.740799256074;4925.45819959797;5755.309362876482;5773.336066352424;5829.76680932758;5185.351831021122;5468.863278418072;5598.664080155204;5606.971965140148;5721.656700597978;5730.914036289542;5999.230227083108;6013.548983826143;6018.127371531016;6035.637861728311;3469.670381313771;3473.617902030724;3616.4756110363323;3930.194310166352;3948.368261078214;5681.914771278517;5806.622569532967;5863.648181109147;5881.411858814085;5910.611006413404;5057.974710126426;5079.847604571869;5290.973545132404;5555.555555555627;5756;4818.662693611554;5289.867753306198;5329.7475093353805;5667.3765885753355;5679.375268720355;4429.734215947054;5503.064479038493;5529.281193444826;5575.916361254665;5582.190582365615;5420.268263784496;5646.689432081275;5648.63283886546;5657.179708942263;5680.801171958961;4929.9014019720125;4955.40535135786;4994.001199760135;5381.461853814744;5606.943930560642 17:10:13 INFO - PROCESS | 1667 | |5;yui.html;564.9576531299753;672.559473544828;687.165094410292;687.6905392573343;719.9568025918444;13855.445782168714;14121.293935303233;14125.505607303747;14273.785893211598;14297.571072867804;13047.825695687387;15342.463013794519;15637.452689155882;16094.436694715687;16131.274092665828;918.2516249256868;922.2852289475677;926.157196950775;931.7344556801313;931.9254459643246;732.6849454734462;925.5788616179622;943.6084025129563;944.4994153098869;956.3018996132827;951.4434056077204;976.3068221562698;991.5092029445435;993.696922438656;996.9551370188359;929.7210836748982;963.648268382039;966.0339660339661;968.675493709601;972.3873959405599;702.6170736948325;714.4748609771814;721.0230138162766;725.8947452619393;727.2836256287552;13459.461621535127;13532.32338383077;13583.252921089335;13759.10565813219;13827.101238419571;12686.682832929158;12789.872101278961;12807.167534110253;12811.161254048906;12847.87152128476;9938.15525680318;9940.701778946643;10119.544620492023;10218;10537.578496860117;3504.754667173299;3533.94699079514;3536.840842162109;3550.449680299532;3571.285742851414;78276.60861695655;78984;79571;79600;80167;8152.592370381457;9406.717798466058;9454.43273403598;9614.97149879858;9768.21854251658;19381.4185574433;21324.78675213228;22525.43686407853;22580.774192258195;22615.773842261366;6444.420002199825;7429.837076353532;7445.516041457288;7474.925250747532;7493.962530187423;3980.24375368679;4062;4062.2281766464866;4069.1251380953236;4077;3498.160441494023;3580.480830279595;3582.9283414331567;3612.8554857805393;3637.799921004317;670.3865962644126;698.5703792167882;699.8145491444772;707.3669066185735;711.5588335232189;570.2729020498814;578.2396149063967;578.9392113828071;611.3092205807409;626.2766504687094;8308.335333173332;8366.414350995494;8419.07390187079;8444;8482.448640838325;506.2153661824231;549.9560035197175;559.1291439881247;620.4190567240258;637.8692368064536;3991.4212439196153;4018.457508236409;4057.9797101014306;4069.04377471294;4123.97938010308;71676;93976.59035114478;94927;95818;97939.51030244803;74887.37894173787;76729;77616.22383776202;79734.601326993;80937.59531202423;55399.72300138393;69399;70216.29783702097;71956.84129476124;73508.26491735122;6440.8389790255615;6945.965270173617;7690.961720167706;8358.83282334362;8512.957435212784;29740.702592973794;34571.30857382788;40248.798756006036;41370.793146035285;41973.79013104915;60864;61133.694331529834;61310.69344653248;61381;61564.69217654062;9306.767330816783;10248.923862994576;10740.720461525492;11112.166587506132;11326.490307935997;12057.216280941711;12058.939705301418;12060.577879774166;12338.75322493563;12387.442565084413;11192.608258710907;11366.261193022427;11638.35989020612;11743.412829358327;11791.646250612495;10963.506642201279;11250.549978000789;11317.660470185907;11504.36725980079;11836.644900652995;11092.168087393316;11093.889061109285;11384.772304554028;11582.6525204244;11707.180497365103;9373.297002724687;9505;9520.904790952001;9716.174125199437;9767.462789546644;8498.70254541088;9178.54107294646;9457.007014263678;10125.949370253102;10192 17:10:13 INFO - PROCESS | 1667 | __end_tp_report 17:10:13 INFO - PROCESS | 1667 | __start_cc_report 17:10:13 INFO - PROCESS | 1667 | _x_x_mozilla_cycle_collect,407 17:10:13 INFO - PROCESS | 1667 | __end_cc_report 17:10:13 INFO - PROCESS | 1667 | __startTimestamp1464135013376__endTimestamp 17:10:13 INFO - PROCESS | 1667 | 17:10:13 INFO - PROCESS | 1667 | ------- Summary: start ------- 17:10:13 INFO - PROCESS | 1667 | Number of tests: 6 17:10:13 INFO - PROCESS | 1667 | 17:10:13 INFO - PROCESS | 1667 | [#0] dojo.html Cycles:165 Average:5726.23 Median:5925.11 stddev:782.83 (13.2%) stddev-sans-first:753.56 17:10:13 INFO - PROCESS | 1667 | Values: 2916.5 3135.3 3543.3 3547.3 3563.1 5138.5 5313.2 5793.7 5795.5 5925.1 4747.9 5678.1 5755.6 5784.2 5958.7 5194.7 5233.1 5266.0 5363.7 5371.1 398.3 5320.6 5324.0 5325.9 5376.4 5285.3 5291.8 5292.3 5311.5 5320.4 5216.5 5322.4 5333.0 5362.8 5373.4 5194.3 5202.9 5263.4 5281.9 5318.6 5668.8 5769.3 5772.8 5785.0 5843.9 6121.2 6254.4 6347.7 6363.2 6415.5 6273.7 6333.4 6341.0 6374.7 6394.9 5933.2 5949.8 5956.3 5979.7 5988.4 6242.8 6262.5 6278.5 6359.5 6392.1 6246.0 6281.7 6285.9 6295.7 6318.3 6272.9 6337.4 6365.6 6370.8 6375.0 5253.8 6123.7 6224.9 6259.3 6324.4 6161.5 6235.3 6313.0 6322.6 6326.1 4983.9 5027.8 5028.4 5030.6 5041.9 4476.9 4486.4 4509.6 4564.1 4580.4 4606.7 5806.4 5855.4 5872.4 5884.6 6302.6 6342.7 6345.0 6347.4 6349.3 5599.9 5613.7 5638.3 5677.7 5717.1 5295.7 5305.9 5309.8 5330.7 5336.7 5000.7 5945.7 6191.3 6235.5 6345.1 6236.3 6255.2 6312.7 6323.6 6373.3 6390.0 6405.8 6406.8 6432.5 6439.6 6151.0 6181.4 6297.1 6335.2 6341.0 6238.9 6341.7 6352.8 6356.4 6368.1 6224.8 6235.3 6236.4 6241.9 6266.6 5218.1 6105.2 6126.1 6223.4 6227.0 5526.3 5617.2 5633.2 5688.4 5943.7 5798.4 5803.3 5809.7 5820.0 5840.5 5716.3 5717.8 5925.7 6037.3 6083.7 17:10:13 INFO - PROCESS | 1667 | 17:10:13 INFO - PROCESS | 1667 | [#1] ext.html Cycles:180 Average:67266.91 Median:40421.99 stddev:147498.97 (364.9%) stddev-sans-first:147827.62 17:10:13 INFO - PROCESS | 1667 | Values: 521.9 522.8 538.3 564.7 570.0 36326.9 43462.0 43528.8 43534.8 43538.8 42203.2 43693.3 44156.6 44244.6 44255.6 9292.0 9863.4 9904.4 9922.1 9940.4 9800.2 9846.0 9888.9 9895.2 9897.7 9896.9 9931.7 9937.7 9943.0 9944.7 9795.7 9820.7 9843.6 9887.3 9896.9 9753.5 9760.6 9769.9 9777.7 9851.7 40569.8 41256.0 41295.0 41345.2 41426.4 3154.7 3183.3 3375.5 3581.9 3781.2 29233.1 29509.8 29685.6 30048.0 30137.0 2764.4 2795.7 2802.6 2883.1 2911.6 467103.0 467986.0 474473.0 474772.0 475364.0 35910.5 66243.7 67308.0 67335.0 67340.7 31503.4 32722.5 36700.0 37833.2 38126.8 15485.0 15671.3 15701.4 15708.2 15714.5 11183.3 12753.0 13015.4 13117.5 13120.0 8347.9 8357.4 8366.5 8382.2 8418.0 3143.8 3336.8 3428.2 3440.5 3442.1 1014.4 1027.9 1060.0 1097.9 1113.3 25250.7 30669.7 30742.1 31123.4 31522.7 1074.6 1086.4 1089.4 1092.8 1112.5 14626.5 15874.9 16084.8 16224.0 16253.6 654330.0 661253.0 661662.0 667464.0 668762.0 43689.0 43914.6 44322.0 44787.8 44985.8 1439.8 1453.8 1479.1 1483.2 1483.2 30447.5 30478.8 30733.5 30956.7 31105.8 56599.4 57020.1 57156.0 57334.7 57732.8 499564.0 500028.0 500204.0 500689.0 501204.0 26976.7 27922.9 28032.6 28119.9 28291.0 48635.3 48832.0 49077.0 49407.5 50172.7 38943.2 40322.4 40547.2 40808.0 41039.2 39439.0 39574.0 40347.6 40496.4 40611.4 33581.5 39151.8 39338.0 39980.8 40955.0 33398.3 34628.7 35070.0 35403.3 35413.6 23873.2 24134.0 25662.7 26205.0 26429.0 17:10:13 INFO - PROCESS | 1667 | 17:10:13 INFO - PROCESS | 1667 | [#2] jquery.html Cycles:180 Average:34677.74 Median:11635.39 stddev:99906.69 (858.6%) stddev-sans-first:100153.79 17:10:13 INFO - PROCESS | 1667 | Values: 392.6 395.3 398.0 401.1 419.2 14756.5 19488.0 20802.6 20816.8 20862.0 19102.0 19974.2 20345.8 20738.3 21517.6 5637.4 5856.2 6009.6 6013.2 6053.4 5858.4 5977.9 6017.2 6154.7 6166.7 6076.0 6125.3 6161.0 6172.9 6185.9 5275.7 5756.5 6039.5 6127.5 6137.9 5043.8 5338.4 5411.6 5841.7 5994.5 12720.9 13512.6 13728.4 21147.7 25315.2 2057.4 2073.9 2094.7 2123.1 2255.8 7916.8 8391.6 9789.1 10225.8 10514.4 1697.5 1702.8 1728.9 1746.1 1776.7 114548.4 119419.0 120167.4 120691.8 120701.0 11781.1 11830.8 11834.4 11915.1 11984.3 14631.1 14641.3 14924.6 14994.0 15062.2 8719.2 8768.0 8783.7 8795.9 8886.5 7266.4 8730.2 8824.6 8835.8 8838.6 4560.2 4606.7 4690.6 4694.3 4806.8 1937.7 1999.1 2000.5 2029.0 2039.9 939.0 988.3 992.4 1017.7 1038.6 18574.5 20180.0 20471.3 20809.2 20906.0 921.8 976.1 984.0 988.7 992.3 10690.3 10889.7 10890.7 10892.1 10909.2 565672.2 601245.0 604174.0 604688.0 609832.0 114379.4 126186.0 127824.4 141224.0 149145.0 920.6 1045.9 1051.5 1121.0 1122.5 18020.4 18401.3 18411.3 18435.3 18598.6 40343.6 41849.5 54606.7 55303.3 55525.7 61433.4 80531.6 81819.4 82223.2 82479.8 5842.1 7395.1 7412.0 7488.4 8170.8 9320.2 11157.0 12333.1 12351.1 12549.4 10341.2 10722.3 10844.5 11062.9 11101.4 10327.3 10738.7 10933.1 11051.9 11202.3 9059.7 10740.9 11225.4 11278.9 11407.4 8121.9 8129.1 8131.6 8334.4 8472.6 9114.0 10506.4 10937.9 11008.7 11210.9 17:10:13 INFO - PROCESS | 1667 | 17:10:13 INFO - PROCESS | 1667 | [#3] mootools.html Cycles:180 Average:5674.18 Median:4876.39 stddev:3140.52 (64.4%) stddev-sans-first:3134.11 17:10:13 INFO - PROCESS | 1667 | Values: 1559.9 1627.5 1900.9 1943.8 2008.9 7741.3 7986.5 8557.5 8587.5 9106.6 8612.0 8699.4 8725.3 8820.2 8826.5 2406.5 2416.2 2436.8 2442.0 2449.9 2375.4 2420.3 2441.0 2442.1 2458.1 2409.0 2423.4 2435.3 2435.4 2438.4 2404.6 2419.0 2425.8 2426.4 2432.0 2381.7 2410.2 2410.6 2412.3 2421.3 8723.3 8757.6 8802.1 8808.2 8838.6 5157.2 5314.7 5338.3 5339.1 5353.4 2879.3 3392.9 3458.9 3500.9 3517.0 1050.9 1056.2 1059.4 1061.7 1065.8 10396.3 10575.4 11812.4 11876.2 11882.2 4760.5 4786.9 4820.6 4875.5 4877.3 9846.4 9951.7 10010.2 10039.7 10059.4 5141.7 5208.2 5248.4 5264.0 5268.6 4284.8 4636.2 4706.5 4797.3 4809.3 3355.6 4044.0 4104.9 4123.8 4185.8 1341.5 1342.3 1342.3 1351.7 1357.4 850.0 868.4 874.0 908.6 910.3 5760.3 5764.2 5974.7 6060.5 6089.7 911.4 942.0 975.2 980.3 984.4 3889.3 3932.2 3958.4 3992.3 4010.9 10527.5 10996.9 11009.9 11072.4 11076.3 9147.7 10748.8 10764.8 10919.2 10924.3 6444.0 7239.9 8372.0 8415.4 8441.9 5594.3 5797.4 5881.0 5961.3 5961.5 8300.6 8489.8 9947.7 10164.8 10229.9 9494.1 10004.7 10009.4 10143.0 10263.4 6149.9 6909.6 6998.5 7240.6 7554.2 8246.8 8366.5 8373.6 8413.7 8450.4 7289.6 7350.1 7457.9 7579.1 7605.0 7280.3 7323.5 7487.9 7516.1 7552.2 7080.1 7097.8 7154.4 7170.6 7201.5 4833.2 4837.2 4944.2 5009.6 5141.2 4514.0 4868.2 4920.9 4986.0 5228.7 17:10:13 INFO - PROCESS | 1667 | 17:10:13 INFO - PROCESS | 1667 | [#4] prototype.html Cycles:180 Average:4491.55 Median:5062.15 stddev:1396.39 (27.6%) stddev-sans-first:1363.62 17:10:13 INFO - PROCESS | 1667 | Values: 254.8 315.7 318.1 320.0 320.9 4794.0 4831.4 5427.2 5466.5 5491.5 4763.5 5176.7 5364.4 5364.4 5373.0 3829.0 3871.8 3913.4 4084.5 4090.3 3378.7 3980.7 4049.5 4053.5 4074.8 3823.6 4009.7 4027.6 4057.5 4071.5 3710.4 4002.3 4026.7 4027.5 4059.8 3242.5 3455.9 3474.5 3487.0 3516.8 4377.0 4807.6 4925.0 5066.3 5189.5 3860.9 4124.8 4596.3 4644.4 4660.4 4730.8 5113.9 5141.6 5557.7 5636.3 3153.1 3172.1 3301.0 3770.0 3939.7 4590.5 4885.0 5363.1 5917.3 5921.4 3332.8 3573.4 3617.3 3762.8 3927.3 3259.0 3812.6 3839.9 3939.9 3990.1 4264.0 4370.4 4492.4 4565.5 4652.2 5188.9 5651.5 5656.4 5726.0 5742.1 1291.7 1332.7 1353.6 1358.9 1361.7 828.4 844.6 847.2 851.1 895.6 5186.4 5200.1 5213.5 5220.7 5256.9 5308.5 5762.0 5762.3 5776.2 5780.0 4878.0 4953.6 5160.4 5165.6 5182.8 2777.8 2943.0 3027.5 3040.9 3048.3 4709.0 4846.9 5304.4 5614.3 5992.4 5090.4 5127.5 5146.7 5270.7 5883.6 5971.0 6012.3 6040.1 6049.7 6073.6 4712.7 4925.5 5755.3 5773.3 5829.8 5185.4 5468.9 5598.7 5607.0 5721.7 5730.9 5999.2 6013.5 6018.1 6035.6 3469.7 3473.6 3616.5 3930.2 3948.4 5681.9 5806.6 5863.6 5881.4 5910.6 5058.0 5079.8 5291.0 5555.6 5756.0 4818.7 5289.9 5329.7 5667.4 5679.4 4429.7 5503.1 5529.3 5575.9 5582.2 5420.3 5646.7 5648.6 5657.2 5680.8 4929.9 4955.4 4994.0 5381.5 5606.9 17:10:13 INFO - PROCESS | 1667 | 17:10:13 INFO - PROCESS | 1667 | [#5] yui.html Cycles:180 Average:17600.01 Median:61345.85 stddev:24771.20 (40.4%) stddev-sans-first:24807.67 17:10:13 INFO - PROCESS | 1667 | Values: 565.0 672.6 687.2 687.7 720.0 13855.4 14121.3 14125.5 14273.8 14297.6 13047.8 15342.5 15637.5 16094.4 16131.3 918.3 922.3 926.2 931.7 931.9 732.7 925.6 943.6 944.5 956.3 951.4 976.3 991.5 993.7 997.0 929.7 963.6 966.0 968.7 972.4 702.6 714.5 721.0 725.9 727.3 13459.5 13532.3 13583.3 13759.1 13827.1 12686.7 12789.9 12807.2 12811.2 12847.9 9938.2 9940.7 10119.5 10218.0 10537.6 3504.8 3533.9 3536.8 3550.4 3571.3 78276.6 78984.0 79571.0 79600.0 80167.0 8152.6 9406.7 9454.4 9615.0 9768.2 19381.4 21324.8 22525.4 22580.8 22615.8 6444.4 7429.8 7445.5 7474.9 7494.0 3980.2 4062.0 4062.2 4069.1 4077.0 3498.2 3580.5 3582.9 3612.9 3637.8 670.4 698.6 699.8 707.4 711.6 570.3 578.2 578.9 611.3 626.3 8308.3 8366.4 8419.1 8444.0 8482.4 506.2 550.0 559.1 620.4 637.9 3991.4 4018.5 4058.0 4069.0 4124.0 71676.0 93976.6 94927.0 95818.0 97939.5 74887.4 76729.0 77616.2 79734.6 80937.6 55399.7 69399.0 70216.3 71956.8 73508.3 6440.8 6946.0 7691.0 8358.8 8513.0 29740.7 34571.3 40248.8 41370.8 41973.8 60864.0 61133.7 61310.7 61381.0 61564.7 9306.8 10248.9 10740.7 11112.2 11326.5 12057.2 12058.9 12060.6 12338.8 12387.4 11192.6 11366.3 11638.4 11743.4 11791.6 10963.5 11250.5 11317.7 11504.4 11836.6 11092.2 11093.9 11384.8 11582.7 11707.2 9373.3 9505.0 9520.9 9716.2 9767.5 8498.7 9178.5 9457.0 10125.9 10192.0 17:10:13 INFO - PROCESS | 1667 | -------- Summary: end -------- 17:10:13 INFO - PROCESS | 1667 | 17:10:13 INFO - TEST-INFO | 1667: exit 0 17:10:13 INFO - TEST-OK | dromaeo_css | took 1111819ms 17:10:13 INFO - TEST-START | kraken 17:10:13 INFO - operating with platform_type : mac_ 17:10:13 INFO - Initialising browser for kraken test... 17:10:13 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/tmpGj3Iog/profile http://localhost:49277/getInfo.html) 17:10:17 INFO - PROCESS | 1674 | __metrics Screen width/height:1600/1200 17:10:17 INFO - PROCESS | 1674 | colorDepth:24 17:10:17 INFO - PROCESS | 1674 | Browser inner width/height: 1024/689 17:10:17 INFO - PROCESS | 1674 | __metrics 17:10:17 ERROR - PROCESS | 1674 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 17:10:17 INFO - TEST-INFO | 1674: exit 0 17:10:17 INFO - Browser initialized. 17:10:17 INFO - Running cycle 1/1 for kraken test... 17:10:17 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/tmpGj3Iog/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:10:29 INFO - PROCESS | 1677 | RSS: Main: 249896960 17:10:29 INFO - PROCESS | 1677 | 17:10:32 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 17:10:33 INFO - PROCESS | 1677 | RSS: Main: 261619712 17:10:33 INFO - PROCESS | 1677 | 17:10:36 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 17:10:36 INFO - PROCESS | 1677 | RSS: Main: 266326016 17:10:36 INFO - PROCESS | 1677 | 17:10:40 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 17:10:41 INFO - PROCESS | 1677 | RSS: Main: 266637312 17:10:41 INFO - PROCESS | 1677 | 17:10:43 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 17:10:43 INFO - PROCESS | 1677 | RSS: Main: 267321344 17:10:43 INFO - PROCESS | 1677 | 17:10:46 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:10:46 INFO - PROCESS | 1677 | RSS: Main: 267960320 17:10:46 INFO - PROCESS | 1677 | 17:10:49 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 17:10:50 INFO - PROCESS | 1677 | RSS: Main: 268804096 17:10:50 INFO - PROCESS | 1677 | 17:10:53 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 17:10:54 INFO - PROCESS | 1677 | RSS: Main: 269062144 17:10:54 INFO - PROCESS | 1677 | 17:10:57 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 17:10:58 INFO - PROCESS | 1677 | RSS: Main: 269672448 17:10:58 INFO - PROCESS | 1677 | 17:10:59 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:11:00 INFO - PROCESS | 1677 | RSS: Main: 269910016 17:11:00 INFO - PROCESS | 1677 | 17:11:02 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:11:03 INFO - PROCESS | 1677 | RSS: Main: 269942784 17:11:03 INFO - PROCESS | 1677 | 17:11:05 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:11:06 INFO - PROCESS | 1677 | RSS: Main: 270381056 17:11:06 INFO - PROCESS | 1677 | 17:11:09 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:11:09 INFO - PROCESS | 1677 | RSS: Main: 270499840 17:11:09 INFO - PROCESS | 1677 | 17:11:12 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:11:13 INFO - PROCESS | 1677 | RSS: Main: 270487552 17:11:13 INFO - PROCESS | 1677 | 17:11:15 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 17:11:15 INFO - PROCESS | 1677 | RSS: Main: 272838656 17:11:15 INFO - PROCESS | 1677 | 17:11:15 INFO - PROCESS | 1677 | __start_tp_report 17:11:15 INFO - PROCESS | 1677 | _x_x_mozilla_page_load 17:11:15 INFO - PROCESS | 1677 | _x_x_mozilla_page_load_details 17:11:15 INFO - PROCESS | 1677 | |i|pagename|runs| 17:11:15 INFO - PROCESS | 1677 | |0;ai-astar;115;116;110;115;116;113;107;117;116;127 17:11:15 INFO - PROCESS | 1677 | |1;audio-beat-detection;156;121;145;198;126;145;140;121;170;130 17:11:15 INFO - PROCESS | 1677 | |2;audio-dft;227;277;223;243;267;196;195;199;246;253 17:11:15 INFO - PROCESS | 1677 | |3;audio-fft;68;83;74;182;69;79;89;59;148;85 17:11:15 INFO - PROCESS | 1677 | |4;audio-oscillator;81;84;142;83;116;119;132;81;82;138 17:11:15 INFO - PROCESS | 1677 | |5;imaging-gaussian-blur;89;89;90;87;89;87;92;86;89;103 17:11:15 INFO - PROCESS | 1677 | |6;imaging-darkroom;89;96;151;134;95;94;104;153;109;95 17:11:15 INFO - PROCESS | 1677 | |7;imaging-desaturate;90;93;92;126;107;90;94;90;91;94 17:11:15 INFO - PROCESS | 1677 | |8;json-parse-financial;59;60;59;62;63;62;61;121;63;58 17:11:15 INFO - PROCESS | 1677 | |9;json-stringify-tinderbox;65;83;92;104;62;102;102;98;82;68 17:11:15 INFO - PROCESS | 1677 | |10;stanford-crypto-aes;88;88;91;88;85;84;87;80;86;72 17:11:15 INFO - PROCESS | 1677 | |11;stanford-crypto-ccm;139;140;140;158;154;155;136;136;255;144 17:11:15 INFO - PROCESS | 1677 | |12;stanford-crypto-pbkdf2;167;166;169;154;161;225;223;170;225;222 17:11:15 INFO - PROCESS | 1677 | |13;stanford-crypto-sha256-iterative;67;66;69;66;68;66;65;66;66;69 17:11:15 INFO - PROCESS | 1677 | __end_tp_report 17:11:15 INFO - PROCESS | 1677 | __start_cc_report 17:11:15 INFO - PROCESS | 1677 | _x_x_mozilla_cycle_collect,1087 17:11:15 INFO - PROCESS | 1677 | __end_cc_report 17:11:15 INFO - PROCESS | 1677 | __startTimestamp1464135075372__endTimestamp 17:11:15 INFO - PROCESS | 1677 | 17:11:15 INFO - PROCESS | 1677 | ------- Summary: start ------- 17:11:15 INFO - PROCESS | 1677 | Number of tests: 14 17:11:15 INFO - PROCESS | 1677 | 17:11:15 INFO - PROCESS | 1677 | [#0] ai-astar Cycles:10 Average:115.20 Median:116.00 stddev:5.20 (4.5%) stddev-sans-first:5.52 17:11:15 INFO - PROCESS | 1677 | Values: 115.0 116.0 110.0 115.0 116.0 113.0 107.0 117.0 116.0 127.0 17:11:15 INFO - PROCESS | 1677 | 17:11:15 INFO - PROCESS | 1677 | [#1] audio-beat-detection Cycles:10 Average:145.20 Median:145.00 stddev:24.31 (16.8%) stddev-sans-first:25.47 17:11:15 INFO - PROCESS | 1677 | Values: 156.0 121.0 145.0 198.0 126.0 145.0 140.0 121.0 170.0 130.0 17:11:15 INFO - PROCESS | 1677 | 17:11:15 INFO - PROCESS | 1677 | [#2] audio-dft Cycles:10 Average:232.60 Median:244.50 stddev:29.56 (12.1%) stddev-sans-first:31.28 17:11:15 INFO - PROCESS | 1677 | Values: 227.0 277.0 223.0 243.0 267.0 196.0 195.0 199.0 246.0 253.0 17:11:15 INFO - PROCESS | 1677 | 17:11:15 INFO - PROCESS | 1677 | [#3] audio-fft Cycles:10 Average:93.60 Median:76.50 stddev:39.49 (51.6%) stddev-sans-first:40.79 17:11:15 INFO - PROCESS | 1677 | Values: 68.0 83.0 74.0 182.0 69.0 79.0 89.0 59.0 148.0 85.0 17:11:15 INFO - PROCESS | 1677 | 17:11:15 INFO - PROCESS | 1677 | [#4] audio-oscillator Cycles:10 Average:105.80 Median:81.00 stddev:26.04 (32.2%) stddev-sans-first:26.03 17:11:15 INFO - PROCESS | 1677 | Values: 81.0 84.0 142.0 83.0 116.0 119.0 132.0 81.0 82.0 138.0 17:11:15 INFO - PROCESS | 1677 | 17:11:15 INFO - PROCESS | 1677 | [#5] imaging-gaussian-blur Cycles:10 Average:90.10 Median:89.00 stddev:4.84 (5.4%) stddev-sans-first:5.12 17:11:15 INFO - PROCESS | 1677 | Values: 89.0 89.0 90.0 87.0 89.0 87.0 92.0 86.0 89.0 103.0 17:11:15 INFO - PROCESS | 1677 | 17:11:15 INFO - PROCESS | 1677 | [#6] imaging-darkroom Cycles:10 Average:112.00 Median:91.50 stddev:24.60 (26.9%) stddev-sans-first:24.64 17:11:15 INFO - PROCESS | 1677 | Values: 89.0 96.0 151.0 134.0 95.0 94.0 104.0 153.0 109.0 95.0 17:11:15 INFO - PROCESS | 1677 | 17:11:15 INFO - PROCESS | 1677 | [#7] imaging-desaturate Cycles:10 Average:96.70 Median:91.50 stddev:11.46 (12.5%) stddev-sans-first:11.90 17:11:15 INFO - PROCESS | 1677 | Values: 90.0 93.0 92.0 126.0 107.0 90.0 94.0 90.0 91.0 94.0 17:11:15 INFO - PROCESS | 1677 | 17:11:15 INFO - PROCESS | 1677 | [#8] json-parse-financial Cycles:10 Average:66.80 Median:61.50 stddev:19.12 (31.1%) stddev-sans-first:20.07 17:11:15 INFO - PROCESS | 1677 | Values: 59.0 60.0 59.0 62.0 63.0 62.0 61.0 121.0 63.0 58.0 17:11:15 INFO - PROCESS | 1677 | 17:11:15 INFO - PROCESS | 1677 | [#9] json-stringify-tinderbox Cycles:10 Average:85.80 Median:75.00 stddev:16.27 (21.7%) stddev-sans-first:15.41 17:11:15 INFO - PROCESS | 1677 | Values: 65.0 83.0 92.0 104.0 62.0 102.0 102.0 98.0 82.0 68.0 17:11:15 INFO - PROCESS | 1677 | 17:11:15 INFO - PROCESS | 1677 | [#10] stanford-crypto-aes Cycles:10 Average:84.90 Median:87.50 stddev:5.40 (6.2%) stddev-sans-first:5.61 17:11:15 INFO - PROCESS | 1677 | Values: 88.0 88.0 91.0 88.0 85.0 84.0 87.0 80.0 86.0 72.0 17:11:15 INFO - PROCESS | 1677 | 17:11:15 INFO - PROCESS | 1677 | [#11] stanford-crypto-ccm Cycles:10 Average:155.70 Median:149.00 stddev:35.83 (24.0%) stddev-sans-first:37.49 17:11:15 INFO - PROCESS | 1677 | Values: 139.0 140.0 140.0 158.0 154.0 155.0 136.0 136.0 255.0 144.0 17:11:15 INFO - PROCESS | 1677 | 17:11:15 INFO - PROCESS | 1677 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:188.20 Median:196.00 stddev:30.94 (15.8%) stddev-sans-first:31.85 17:11:15 INFO - PROCESS | 1677 | Values: 167.0 166.0 169.0 154.0 161.0 225.0 223.0 170.0 225.0 222.0 17:11:15 INFO - PROCESS | 1677 | 17:11:15 INFO - PROCESS | 1677 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:66.50 stddev:1.40 (2.1%) stddev-sans-first:1.48 17:11:15 INFO - PROCESS | 1677 | Values: 67.0 66.0 69.0 66.0 68.0 66.0 65.0 66.0 66.0 69.0 17:11:15 INFO - PROCESS | 1677 | -------- Summary: end -------- 17:11:15 INFO - PROCESS | 1677 | 17:11:16 INFO - TEST-INFO | 1677: exit 0 17:11:16 INFO - TEST-OK | kraken | took 62457ms 17:11:16 INFO - SUITE-END | took 1174s 17:11:16 INFO - Completed test suite (00:19:34) 17:11:16 INFO - javascript benchmark 17:11:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2916.5041942869725, 3135.2945587242875, 3543.255916257586, 3547.2905418916225, 3563.127033876701, 5138.511841375063, 5313.20301954707, 5793.652380857146, 5795.536357091433, 5925.111233314994, 4747.8575642730775, 5678.063119585274, 5755.597108202427, 5784.1902133701215, 5958.672273024982, 5194.71429071401, 5233.058049551079, 5265.999460102587, 5363.731813409334, 5371.113766228567, 398.26492990092123, 5320.600954928376, 5324.0, 5325.893482130355, 5376.435474275204, 5285.260063591119, 5291.788328466876, 5292.259083728242, 5311.548518375943, 5320.361556613192, 5216.5305122538775, 5322.4411436799355, 5332.986732520809, 5362.812301569426, 5373.355197376301, 5194.298769666122, 5202.92195617066, 5263.44733802949, 5281.867953301198, 5318.601104917108, 5668.801591944262, 5769.336526299488, 5772.769089236425, 5785.0, 5843.941560584425, 6121.204243448367, 6254.437100660963, 6347.682615869188, 6363.172787537636, 6415.518836087312, 6273.686315684197, 6333.366663333676, 6341.0, 6374.713137908805, 6394.904076438857, 5933.169356290123, 5949.791757288517, 5956.273038016751, 5979.730912108966, 5988.431099045627, 6242.781502647476, 6262.530310226751, 6278.497720182466, 6359.459445947054, 6392.137061496732, 6246.0, 6281.748730050746, 6285.9057114143325, 6295.6537390443045, 6318.304986451487, 6272.937270627326, 6337.397947195009, 6365.554411191265, 6370.77702280418, 6375.0, 5253.789848406021, 6123.740204357194, 6224.937750622526, 6259.311475737665, 6324.367563243731, 6161.476274516626, 6235.314115447297, 6313.0, 6322.589031712924, 6326.082718005955, 4983.8504844854715, 5027.798888044437, 5028.446870844205, 5030.647854650212, 5041.899162016739, 4476.888077798016, 4486.439195100613, 4509.616682582017, 4564.109998550317, 4580.4045474088825, 4606.677532572755, 5806.44838740319, 5855.355910849803, 5872.383399743051, 5884.588078834526, 6302.621842689453, 6342.714577844008, 6344.984802431589, 6347.39699728525, 6349.301576826545, 5599.860003499945, 5613.7193140342815, 5638.295213098363, 5677.687727174963, 5717.113847353667, 5295.655782374133, 5305.867353316121, 5309.761060752276, 5330.706811125426, 5336.653117547347, 5000.724960127228, 5945.702714864239, 6191.288001879841, 6235.533562135802, 6345.07996340528, 6236.345183755821, 6255.186825712628, 6312.652804095819, 6323.557350985387, 6373.330800266091, 6390.0, 6405.775797847239, 6406.839829004312, 6432.453241474527, 6439.645819479786, 6151.0, 6181.35095814933, 6297.118403423433, 6335.208098987627, 6340.968295158495, 6238.906416403667, 6341.682915854097, 6352.809415717536, 6356.396142366468, 6368.108464814837, 6224.813255602339, 6235.3452887446165, 6236.438720515175, 6241.906371404523, 6266.561340706106, 5218.112920803548, 6105.186826067751, 6126.081087836682, 6223.37766223374, 6226.968865155645, 5526.336839579116, 5617.241672374177, 5633.2113504110275, 5688.40271771458, 5943.673097979479, 5798.449147330997, 5803.332616749128, 5809.680467574155, 5819.970900145472, 5840.474357307863, 5716.342620598684, 5717.828465146053, 5925.674087925033, 6037.335893051673, 6083.69581520931], "value": 5679.547014304103, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [521.9373675158979, 522.7516929458506, 538.2572050570213, 564.7096384760821, 569.9996506131738, 36326.910192694195, 43461.99999999996, 43528.78235608817, 43534.78232608837, 43538.782306088426, 42203.15593688132, 43693.34459983103, 44156.558434415645, 44244.55755442445, 44255.55744442555, 9292.0, 9863.358881672704, 9904.356216845918, 9922.107010369065, 9940.403575785476, 9800.215982721365, 9846.0, 9888.90111098887, 9895.158911492495, 9897.703068907907, 9896.851547226797, 9931.652392166276, 9937.652182173626, 9943.0, 9944.701658950207, 9795.706128816148, 9820.65627703027, 9843.557039933185, 9887.307888447811, 9896.901030989706, 9753.512324383822, 9760.560774765152, 9769.853452198151, 9777.657781977596, 9851.655192068243, 40569.797151014354, 41256.0, 41294.967625809295, 41345.1730965382, 41426.37860432096, 3154.7318477929402, 3183.2519357950864, 3375.476801095834, 3581.8925432237074, 3781.2059467511854, 29233.123006309845, 29509.8196072159, 29685.554716679268, 30048.0, 30137.0, 2764.378014946641, 2795.65054368204, 2802.63565736455, 2883.1494709060876, 2911.5923770672125, 467103.0, 467986.0, 474473.0, 474772.0, 475364.0, 35910.46134307988, 66243.66878165578, 67308.0, 67335.0, 67340.6632966832, 31503.36993260122, 32722.509162362585, 36700.0, 37833.24333513314, 38126.80936595299, 15484.993475424062, 15671.294791734399, 15701.371945122295, 15708.21458927072, 15714.528564143095, 11183.32900026001, 12753.0, 13015.414306356237, 13117.475300988044, 13120.015998800129, 8347.916520834835, 8357.414980951397, 8366.456180348257, 8382.161783821692, 8417.957910210533, 3143.764217683683, 3336.8164750938445, 3428.177237462991, 3440.535527703778, 3442.1394651337164, 1014.4420568687193, 1027.866377370937, 1059.9523021464054, 1097.91765617579, 1113.3208742666966, 25250.747492525206, 30669.69330306713, 30742.07773766746, 31123.377532449224, 31522.684773151974, 1074.623881641435, 1086.364476781092, 1089.3844977587637, 1092.7541303206715, 1112.4826955465746, 14626.488072917396, 15874.920625396799, 16084.839151608568, 16224.0, 16253.593660158354, 654330.0, 661253.0, 661662.0, 667464.0, 668762.0, 43689.0, 43914.56085439105, 44322.0, 44787.77606111948, 44985.77507112444, 1439.8344190418027, 1453.7528620134603, 1479.0534058202975, 1483.169425121935, 1483.1842486632308, 30447.543286850272, 30478.847605761828, 30733.53899691551, 30956.69043309538, 31105.8444707775, 56599.43400566106, 57020.144697828735, 57156.0, 57334.7133264331, 57732.84534309206, 499564.0, 500028.0, 500204.0, 500689.0, 501204.0, 26976.73023269742, 27922.860385697943, 28032.57951130694, 28119.859400702866, 28291.0, 48635.27047094367, 48832.02335953332, 49077.01845962989, 49407.50592494029, 50172.74913625408, 38943.221135577696, 40322.395164071975, 40547.1890562193, 40808.0, 41039.17921641491, 39439.0, 39574.0, 40347.59652403439, 40496.392554111124, 40611.390829136995, 33581.49627755635, 39151.804240978614, 39338.016549586486, 39980.800575982765, 40955.0, 33398.30092392914, 34628.65371346355, 35070.0, 35403.29193416169, 35413.645863542064, 23873.16443924454, 24134.0, 25662.743372566783, 26205.0, 26429.0], "value": 18941.052260787605, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.63288824948694, 395.2588895820337, 397.96418322350985, 401.08552500299294, 419.1533103131674, 14756.483523076695, 19488.02559872006, 20802.583948321062, 20816.791832081675, 20862.0, 19102.0, 19974.20103195874, 20345.796542034575, 20738.274160404388, 21517.56964860702, 5637.436256374351, 5856.150858125569, 6009.5793294469295, 6013.248343957005, 6053.394660533933, 5858.443447872445, 5977.88044239115, 6017.247844019497, 6154.661493617849, 6166.722497487602, 6075.969620151893, 6125.295591007024, 6161.045038019114, 6172.9074063889075, 6185.938140618604, 5275.7338238822595, 5756.481916627503, 6039.547033972426, 6127.479164271064, 6137.9079313810325, 5043.798248070073, 5338.386085600167, 5411.56707463402, 5841.678707671076, 5994.460498555108, 12720.936395317964, 13512.59462216135, 13728.382222799997, 21147.682784758243, 25315.240542783748, 2057.434205593459, 2073.9377818665466, 2094.717213176217, 2123.097683484513, 2255.8195344372575, 7916.802079948047, 8391.580420978926, 9789.118979291898, 10225.846612300822, 10514.421706806123, 1697.507722760398, 1702.7616133741285, 1728.930842766288, 1746.1269365317341, 1776.7157254839165, 114548.42725786401, 119419.0, 120167.3991630045, 120691.79308206894, 120701.0, 11781.116416268815, 11830.763384732258, 11834.408279585987, 11915.076474144596, 11984.340861252715, 14631.122132672073, 14641.34113964874, 14924.552263432115, 14994.025388349723, 15062.171580563174, 8719.215270625675, 8768.035516093225, 8783.736487905373, 8795.91204087951, 8886.511241881759, 7266.382357499566, 8730.21428071464, 8824.647014119364, 8835.823283534293, 8838.646454141892, 4560.201964656172, 4606.723596584216, 4690.64820138491, 4694.2723877799, 4806.831760888422, 1937.6996565532365, 1999.1403696410682, 2000.5298754792611, 2029.046348216336, 2039.8878061706748, 938.990610093904, 988.3476905242505, 992.4194346307347, 1017.730301470111, 1038.5586125896464, 18574.53563660892, 20180.0, 20471.28350507755, 20809.1676332945, 20905.954702264826, 921.7924518880288, 976.0678551982982, 983.9950800245953, 988.6984469736799, 992.2954702161402, 10690.305130166515, 10889.727756806144, 10890.72773180661, 10892.128629709605, 10909.23635345534, 565672.1716391392, 601245.0, 604174.0, 604688.0, 609832.0, 114379.42810286062, 126186.0, 127824.36087819502, 141224.0, 149145.0, 920.604140219712, 1045.869266341692, 1051.4952822645173, 1120.9607663731892, 1122.511707407264, 18020.36928707489, 18401.263949441873, 18411.26354945787, 18435.26258949627, 18598.628027439107, 40343.596564033985, 41849.48058095239, 54606.72696636491, 55303.34089977307, 55525.7223713895, 61433.38566614455, 80531.59734201292, 81819.36361272621, 82223.17776822395, 82479.76280355919, 5842.094475356326, 7395.112586489656, 7412.0, 7488.363489103487, 8170.836583268182, 9320.207782338577, 11157.0, 12333.136680432284, 12351.073669474647, 12549.435275412447, 10341.224408169568, 10722.303050301705, 10844.45777711127, 11062.944685276521, 11101.444927753419, 10327.277090603586, 10738.73153171177, 10933.125349971824, 11051.944740276569, 11202.327860328407, 9059.68291109808, 10740.872208418315, 11225.382603956861, 11278.887211127783, 11407.372594507382, 8121.918780812352, 8129.146439623754, 8131.593420329078, 8334.416590838826, 8472.576371181292, 9114.0, 10506.369617822955, 10937.890621093688, 11008.669739907815, 11210.887891120985], "value": 9410.471801651589, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1559.8908076434648, 1627.5198816349175, 1900.8574356923234, 1943.8250557449826, 2008.8995550222494, 7741.264579864911, 7986.520808751479, 8557.529335886524, 8587.527685977277, 9106.635734570618, 8611.966564012311, 8699.434536755107, 8725.301975841934, 8820.20618144367, 8826.470411775297, 2406.4946361264156, 2416.214730212679, 2436.8172387070954, 2442.0, 2449.865257410842, 2375.3824005758456, 2420.2860156253946, 2440.987795061022, 2442.0842184180933, 2458.0782206672498, 2409.0484258717797, 2423.357810180295, 2435.257246539805, 2435.43984883476, 2438.426969662128, 2404.5551572959057, 2418.9516209675794, 2425.769551892576, 2426.4055306449854, 2432.0, 2381.7141942966955, 2410.2166795791263, 2410.614301711735, 2412.264259400882, 2421.2978236311446, 8723.345749068845, 8757.605907734167, 8802.075782042857, 8808.163224493663, 8838.558072096434, 5157.174852023696, 5314.681119132864, 5338.332708411449, 5339.09235429974, 5353.411124776271, 2879.280179955011, 3392.8812491562676, 3458.913527161841, 3500.8599656013985, 3517.0152357339985, 1050.9159267258601, 1056.2395075545594, 1059.3961441978076, 1061.723951772537, 1065.7548763784296, 10396.272260941738, 10575.36547807134, 11812.409379531075, 11876.228045177122, 11882.168248222628, 4760.523947605212, 4786.928196077061, 4820.6143508519235, 4875.5124487551675, 4877.268409738567, 9846.409215446953, 9951.651692190739, 10010.249231307682, 10039.69880903574, 10059.44673042975, 5141.71720555373, 5208.218767184952, 5248.396434410015, 5264.0, 5268.578513718893, 4284.785760711952, 4636.211843986531, 4706.458757242961, 4797.304390863375, 4809.326694262806, 3355.614104377979, 4043.9890027493125, 4104.91790164195, 4123.773192474383, 4185.769782661922, 1341.53716967646, 1342.2617560341773, 1342.3020029584561, 1351.6823546466574, 1357.4230951845623, 850.013983778826, 868.3617541107408, 874.0254616102999, 908.5911339897073, 910.2626872233512, 5760.279965004374, 5764.164196191528, 5974.731137098841, 6060.454559089703, 6089.695515224221, 911.3802614222392, 941.9623215071321, 975.2295686407658, 980.3382716666364, 984.3798407003685, 3889.2804831106337, 3932.2135572884963, 3958.386450100239, 3992.3412636915164, 4010.8997275067773, 10527.526261318259, 10996.945015274872, 11009.889901101047, 11072.446377681244, 11076.280041797258, 9147.67983120601, 10748.838767418494, 10764.838527421938, 10919.235653504338, 10924.2899211551, 6443.96778016107, 7239.9276007240305, 8371.995360556772, 8415.368847336475, 8441.873371899426, 5594.300712410949, 5797.449242321973, 5880.970830104629, 5961.314448838439, 5961.463468287875, 8300.626471808662, 8489.83020339602, 9947.651832185838, 10164.796704066026, 10229.948850255701, 9494.145526902612, 10004.749881253027, 10009.449480278654, 10143.0, 10263.384196948207, 6149.893019256577, 6909.619970901648, 6998.475114366341, 7240.637968101679, 7554.244575542401, 8246.793830154294, 8366.539840308842, 8373.623186956724, 8413.747587572383, 8450.366222533212, 7289.635518224173, 7350.08123984502, 7457.888131677921, 7579.090509138939, 7604.9733286005685, 7280.3083707047745, 7323.450741194325, 7487.8876816846705, 7516.135644400964, 7552.244775522624, 7080.114985626797, 7097.822554435974, 7154.427645788221, 7170.641467926478, 7201.4958952874895, 4833.202521583899, 4837.153498137742, 4944.233643785219, 5009.649324547392, 5141.151709967813, 4514.0069184779295, 4868.245421959602, 4920.8682003138765, 4985.9501404985485, 5228.686278823283], "value": 4597.220943461871, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [254.81907845429743, 315.68431568431566, 318.1188108938241, 319.9952000719989, 320.9406259841931, 4794.017226468565, 4831.391048833626, 5427.209021023063, 5466.453354664532, 5491.533219676322, 4763.4998325175875, 5176.705823544114, 5364.356277246736, 5364.417496838432, 5372.952274306502, 3828.9808550957277, 3871.7676939383587, 3913.373860182357, 4084.4894388201474, 4090.3046482097966, 3378.712809411203, 3980.701447391443, 4049.514058312992, 4053.5338436079933, 4074.775887326196, 3823.5602905665787, 4009.7393669411395, 4027.556968733437, 4057.5333836608725, 4071.531773846016, 3710.406334986389, 4002.259017327472, 4026.6979976501734, 4027.536833264183, 4059.8376064957365, 3242.513622956552, 3455.94816077759, 3474.5483087198504, 3486.9825650871835, 3516.7714098583765, 4377.037051848588, 4807.591354734816, 4924.990376972712, 5066.316047333599, 5189.455107213764, 3860.903477413059, 4124.793760311973, 4596.264597664391, 4644.419447569054, 4660.440747110369, 4730.793647619835, 5113.897722045538, 5141.562967147797, 5557.722113894289, 5636.267285252932, 3153.1486498645468, 3172.0800967719333, 3300.9834950825334, 3769.963260103466, 3939.7439166454087, 4590.472095708969, 4884.975575122137, 5363.115086010804, 5917.349091599921, 5921.378255283219, 3332.800031998088, 3573.4103872860687, 3617.276544691072, 3762.811859407019, 3927.273454410943, 3258.9511157332186, 3812.5806161322234, 3839.9248210501105, 3939.8574413419788, 3990.06233535119, 4263.978680106642, 4370.366296886998, 4492.3710680504755, 4565.452145742533, 4652.185867473247, 5188.92216616751, 5651.519620832275, 5656.377798442168, 5726.026575482178, 5742.0953751449415, 1291.6770807298176, 1332.6868185976289, 1353.5668586052368, 1358.9252591107386, 1361.6995769040543, 828.4035494443991, 844.5734903873505, 847.2247893177673, 851.1148405658047, 895.5656506594297, 5186.429492755794, 5200.141976573823, 5213.452587478336, 5220.738963051832, 5256.894862102737, 5308.548773354308, 5761.971190144022, 5762.337331206881, 5776.249087618583, 5780.034788172042, 4877.951220487821, 4953.603711703127, 5160.380754309507, 5165.612579056586, 5182.766775495036, 2777.8471934147506, 2942.985285073561, 3027.516516906744, 3040.9087727368214, 3048.2531779714113, 4708.987567672967, 4846.927296090562, 5304.416514183436, 5614.277075618322, 5992.40075992406, 5090.414602320707, 5127.487251274917, 5146.742662866842, 5270.710110943858, 5883.646321596649, 5971.0, 6012.278526576839, 6040.124181993674, 6049.667268300285, 6073.635581865102, 4712.740799256074, 4925.45819959797, 5755.309362876482, 5773.336066352424, 5829.76680932758, 5185.351831021122, 5468.863278418072, 5598.664080155204, 5606.971965140148, 5721.656700597978, 5730.914036289542, 5999.230227083108, 6013.548983826143, 6018.127371531016, 6035.637861728311, 3469.670381313771, 3473.617902030724, 3616.4756110363323, 3930.194310166352, 3948.368261078214, 5681.914771278517, 5806.622569532967, 5863.648181109147, 5881.411858814085, 5910.611006413404, 5057.974710126426, 5079.847604571869, 5290.973545132404, 5555.555555555627, 5756.0, 4818.662693611554, 5289.867753306198, 5329.7475093353805, 5667.3765885753355, 5679.375268720355, 4429.734215947054, 5503.064479038493, 5529.281193444826, 5575.916361254665, 5582.190582365615, 5420.268263784496, 5646.689432081275, 5648.63283886546, 5657.179708942263, 5680.801171958961, 4929.9014019720125, 4955.40535135786, 4994.001199760135, 5381.461853814744, 5606.943930560642], "value": 4047.6004459925566, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [564.9576531299753, 672.559473544828, 687.165094410292, 687.6905392573343, 719.9568025918444, 13855.445782168714, 14121.293935303233, 14125.505607303747, 14273.785893211598, 14297.571072867804, 13047.825695687387, 15342.463013794519, 15637.452689155882, 16094.436694715687, 16131.274092665828, 918.2516249256868, 922.2852289475677, 926.157196950775, 931.7344556801313, 931.9254459643246, 732.6849454734462, 925.5788616179622, 943.6084025129563, 944.4994153098869, 956.3018996132827, 951.4434056077204, 976.3068221562698, 991.5092029445435, 993.696922438656, 996.9551370188359, 929.7210836748982, 963.648268382039, 966.0339660339661, 968.675493709601, 972.3873959405599, 702.6170736948325, 714.4748609771814, 721.0230138162766, 725.8947452619393, 727.2836256287552, 13459.461621535127, 13532.32338383077, 13583.252921089335, 13759.10565813219, 13827.101238419571, 12686.682832929158, 12789.872101278961, 12807.167534110253, 12811.161254048906, 12847.87152128476, 9938.15525680318, 9940.701778946643, 10119.544620492023, 10218.0, 10537.578496860117, 3504.754667173299, 3533.94699079514, 3536.840842162109, 3550.449680299532, 3571.285742851414, 78276.60861695655, 78984.0, 79571.0, 79600.0, 80167.0, 8152.592370381457, 9406.717798466058, 9454.43273403598, 9614.97149879858, 9768.21854251658, 19381.4185574433, 21324.78675213228, 22525.43686407853, 22580.774192258195, 22615.773842261366, 6444.420002199825, 7429.837076353532, 7445.516041457288, 7474.925250747532, 7493.962530187423, 3980.24375368679, 4062.0, 4062.2281766464866, 4069.1251380953236, 4077.0, 3498.160441494023, 3580.480830279595, 3582.9283414331567, 3612.8554857805393, 3637.799921004317, 670.3865962644126, 698.5703792167882, 699.8145491444772, 707.3669066185735, 711.5588335232189, 570.2729020498814, 578.2396149063967, 578.9392113828071, 611.3092205807409, 626.2766504687094, 8308.335333173332, 8366.414350995494, 8419.07390187079, 8444.0, 8482.448640838325, 506.2153661824231, 549.9560035197175, 559.1291439881247, 620.4190567240258, 637.8692368064536, 3991.4212439196153, 4018.457508236409, 4057.9797101014306, 4069.04377471294, 4123.97938010308, 71676.0, 93976.59035114478, 94927.0, 95818.0, 97939.51030244803, 74887.37894173787, 76729.0, 77616.22383776202, 79734.601326993, 80937.59531202423, 55399.72300138393, 69399.0, 70216.29783702097, 71956.84129476124, 73508.26491735122, 6440.8389790255615, 6945.965270173617, 7690.961720167706, 8358.83282334362, 8512.957435212784, 29740.702592973794, 34571.30857382788, 40248.798756006036, 41370.793146035285, 41973.79013104915, 60864.0, 61133.694331529834, 61310.69344653248, 61381.0, 61564.69217654062, 9306.767330816783, 10248.923862994576, 10740.720461525492, 11112.166587506132, 11326.490307935997, 12057.216280941711, 12058.939705301418, 12060.577879774166, 12338.75322493563, 12387.442565084413, 11192.608258710907, 11366.261193022427, 11638.35989020612, 11743.412829358327, 11791.646250612495, 10963.506642201279, 11250.549978000789, 11317.660470185907, 11504.36725980079, 11836.644900652995, 11092.168087393316, 11093.889061109285, 11384.772304554028, 11582.6525204244, 11707.180497365103, 9373.297002724687, 9505.0, 9520.904790952001, 9716.174125199437, 9767.462789546644, 8498.70254541088, 9178.54107294646, 9457.007014263678, 10125.949370253102, 10192.0], "value": 6817.829082801189, "unit": "score"}], "name": "dromaeo_css", "value": 7103.167875243964, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 116.0, 110.0, 115.0, 116.0, 113.0, 107.0, 117.0, 116.0, 127.0], "value": 115.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 121.0, 145.0, 198.0, 126.0, 145.0, 140.0, 121.0, 170.0, 130.0], "value": 145.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [227.0, 277.0, 223.0, 243.0, 267.0, 196.0, 195.0, 199.0, 246.0, 253.0], "value": 232.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 83.0, 74.0, 182.0, 69.0, 79.0, 89.0, 59.0, 148.0, 85.0], "value": 93.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 84.0, 142.0, 83.0, 116.0, 119.0, 132.0, 81.0, 82.0, 138.0], "value": 105.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 90.0, 87.0, 89.0, 87.0, 92.0, 86.0, 89.0, 103.0], "value": 90.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 96.0, 151.0, 134.0, 95.0, 94.0, 104.0, 153.0, 109.0, 95.0], "value": 112.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 93.0, 92.0, 126.0, 107.0, 90.0, 94.0, 90.0, 91.0, 94.0], "value": 96.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 59.0, 62.0, 63.0, 62.0, 61.0, 121.0, 63.0, 58.0], "value": 66.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 83.0, 92.0, 104.0, 62.0, 102.0, 102.0, 98.0, 82.0, 68.0], "value": 85.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 91.0, 88.0, 85.0, 84.0, 87.0, 80.0, 86.0, 72.0], "value": 84.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 140.0, 140.0, 158.0, 154.0, 155.0, 136.0, 136.0, 255.0, 144.0], "value": 155.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 166.0, 169.0, 154.0, 161.0, 225.0, 223.0, 170.0, 225.0, 222.0], "value": 188.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 69.0, 66.0, 68.0, 66.0, 65.0, 66.0, 66.0, 69.0], "value": 66.8, "unit": "score"}], "name": "kraken", "value": 1639.4, "alertThreshold": 2.0}]} 17:11:16 INFO - Return code: 0 17:11:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 17:11:16 ERROR - # TBPL SUCCESS # 17:11:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:11:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:11:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:11:16 INFO - 2016-05-24 17:11:16.685 screenresolution[1682:10699] starting screenresolution argv=screenresolution get 17:11:16 INFO - 2016-05-24 17:11:16.688 screenresolution[1682:10699] Display 0: 1600x1200x32@60 17:11:16 INFO - 2016-05-24 17:11:16.694 screenresolution[1683:10702] starting screenresolution argv=screenresolution list 17:11:16 INFO - Available Modes on Display 0 17:11:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:11:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:11:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:11:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:11:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:11:16 INFO - Intel Iris: 17:11:16 INFO - Chipset Model: Intel Iris 17:11:16 INFO - Type: GPU 17:11:16 INFO - Bus: Built-In 17:11:16 INFO - VRAM (Dynamic, Max): 1536 MB 17:11:16 INFO - Vendor: Intel (0x8086) 17:11:16 INFO - Device ID: 0x0a2e 17:11:16 INFO - Revision ID: 0x0009 17:11:16 INFO - Displays: 17:11:16 INFO - Display: 17:11:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:11:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:11:16 INFO - Display Serial Number: 1600x1200 60 17:11:16 INFO - Main Display: Yes 17:11:16 INFO - Mirror: Off 17:11:16 INFO - Online: Yes 17:11:16 INFO - Rotation: Supported 17:11:16 INFO - Return code: 0 17:11:16 INFO - Running post-action listener: _resource_record_post_action 17:11:16 INFO - ##### 17:11:16 INFO - ##### Finished run-tests step (success) 17:11:16 INFO - ##### 17:11:16 INFO - Running post-run listener: _resource_record_post_run 17:11:17 INFO - Total resource usage - Wall time: 1209s; CPU: 55.0%; Read bytes: 31116800; Write bytes: 695413248; Read time: 197; Write time: 1914 17:11:17 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 380373504; Write bytes: 374687232; Read time: 28348; Write time: 767 17:11:18 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 30191104; Write bytes: 297972736; Read time: 185; Write time: 1090 17:11:18 INFO - Running post-run listener: _upload_blobber_files 17:11:18 INFO - Blob upload gear active. 17:11:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:11:18 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 17:11:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Larch', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:11:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Larch -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:11:18 INFO - (blobuploader) - INFO - Open directory for files ... 17:11:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:11:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:11:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:11:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:11:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:11:19 INFO - (blobuploader) - INFO - Done attempting. 17:11:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:11:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:11:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:11:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:11:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:11:20 INFO - (blobuploader) - INFO - Done attempting. 17:11:20 INFO - (blobuploader) - INFO - Iteration through files over. 17:11:20 INFO - Return code: 0 17:11:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:11:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:11:20 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/3f7ab62a5a55c20cbf3f66692779a05e4c2fa802902f3235b752d83adb1a0e9ce03c86d1a0e53f5c0f5d0720140b99e383058ff3e2cd727cc949988f2ad0c4ee", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/e5c4e82a147a890ff2bf5866cfd49ea4e83e6f9e61883c0779245d31c30b2d5903a1d705605752ddafd9fd469c60c1d8b589f832dd02728b75ea69f5b6dcb183"} 17:11:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:11:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:11:20 INFO - Contents: 17:11:20 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/3f7ab62a5a55c20cbf3f66692779a05e4c2fa802902f3235b752d83adb1a0e9ce03c86d1a0e53f5c0f5d0720140b99e383058ff3e2cd727cc949988f2ad0c4ee", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/e5c4e82a147a890ff2bf5866cfd49ea4e83e6f9e61883c0779245d31c30b2d5903a1d705605752ddafd9fd469c60c1d8b589f832dd02728b75ea69f5b6dcb183"} 17:11:20 INFO - Running post-run listener: copy_logs_to_upload_dir 17:11:20 INFO - Copying logs to upload dir... 17:11:20 INFO - mkdir: /builds/slave/test/build/upload/logs 17:11:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.691610 ========= master_lag: 0.24 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-05-24 17:11:20.326718) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 17:11:20.334037) ========= 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.UEqlyLYoYl/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.827T5vvaGV/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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/Larch/sha512/3f7ab62a5a55c20cbf3f66692779a05e4c2fa802902f3235b752d83adb1a0e9ce03c86d1a0e53f5c0f5d0720140b99e383058ff3e2cd727cc949988f2ad0c4ee", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/e5c4e82a147a890ff2bf5866cfd49ea4e83e6f9e61883c0779245d31c30b2d5903a1d705605752ddafd9fd469c60c1d8b589f832dd02728b75ea69f5b6dcb183"} build_url:https://queue.taskcluster.net/v1/task/dBlJAsFsQcegm3CAmX3zAg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010960 build_url: 'https://queue.taskcluster.net/v1/task/dBlJAsFsQcegm3CAmX3zAg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/3f7ab62a5a55c20cbf3f66692779a05e4c2fa802902f3235b752d83adb1a0e9ce03c86d1a0e53f5c0f5d0720140b99e383058ff3e2cd727cc949988f2ad0c4ee", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/e5c4e82a147a890ff2bf5866cfd49ea4e83e6f9e61883c0779245d31c30b2d5903a1d705605752ddafd9fd469c60c1d8b589f832dd02728b75ea69f5b6dcb183"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 17:11:20.370300) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 17:11:20.370712) ========= 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.UEqlyLYoYl/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.827T5vvaGV/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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.005159 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 17:11:20.419268) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 17:11:20.422683) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 17:11:20.423171) ========= ========= Total master_lag: 0.38 =========