builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0243 starttime: 1465118410.8 results: success (0) buildid: 20160605004013 builduid: f2e7e1e69a8447ca99b55e3f1fac1182 revision: dc972d877cd697c2c8f6248534f21bb5e1dba9ae ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-05 02:20:10.804752) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-05 02:20:10.805270) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-05 02:20:10.805697) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-05 02:20:11.001929) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-05 02:20:11.002278) ========= 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.vW4o3gwK5I/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.IdP3E3HlJY/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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-06-05 02:20:11-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.4M=0.001s 2016-06-05 02:20:11 (19.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.548483 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-05 02:20:11.587071) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-05 02:20:11.587376) ========= 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.vW4o3gwK5I/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.IdP3E3HlJY/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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.025434 ========= master_lag: 0.08 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-05 02:20:11.693035) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-05 02:20:11.693377) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev dc972d877cd697c2c8f6248534f21bb5e1dba9ae --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev dc972d877cd697c2c8f6248534f21bb5e1dba9ae --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vW4o3gwK5I/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.IdP3E3HlJY/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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-06-05 02:20:11,788 truncating revision to first 12 chars 2016-06-05 02:20:11,788 Setting DEBUG logging. 2016-06-05 02:20:11,788 attempt 1/10 2016-06-05 02:20:11,788 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/dc972d877cd6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-05 02:20:13,084 unpacking tar archive at: mozilla-aurora-dc972d877cd6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.550437 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-05 02:20:13.285405) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-05 02:20:13.285743) ========= script_repo_revision: dc972d877cd697c2c8f6248534f21bb5e1dba9ae ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-05 02:20:13.286149) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-05 02:20:13.286446) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-05 02:20:13.315306) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 37 secs) (at 2016-06-05 02:20:13.315947) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vW4o3gwK5I/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.IdP3E3HlJY/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 02:20:13 INFO - MultiFileLogger online at 20160605 02:20:13 in /builds/slave/test 02:20:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:20:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:20:13 INFO - {'append_to_log': False, 02:20:13 INFO - 'base_work_dir': '/builds/slave/test', 02:20:13 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:20:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:20:13 INFO - 'branch': 'Mozilla-Aurora', 02:20:13 INFO - 'buildbot_json_path': 'buildprops.json', 02:20:13 INFO - 'config_files': ('talos/mac_config.py',), 02:20:13 INFO - 'default_actions': ('clobber', 02:20:13 INFO - 'read-buildbot-config', 02:20:13 INFO - 'download-and-extract', 02:20:13 INFO - 'populate-webroot', 02:20:13 INFO - 'create-virtualenv', 02:20:13 INFO - 'install', 02:20:13 INFO - 'run-tests'), 02:20:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:20:13 INFO - 'download_minidump_stackwalk': True, 02:20:13 INFO - 'download_symbols': 'ondemand', 02:20:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:20:13 INFO - 'tooltool.py': '/tools/tooltool.py', 02:20:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:20:13 INFO - '/tools/misc-python/virtualenv.py')}, 02:20:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:20:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:20:13 INFO - 'installer_path': 'installer.exe', 02:20:13 INFO - 'log_level': 'info', 02:20:13 INFO - 'log_name': 'talos', 02:20:13 INFO - 'log_to_console': True, 02:20:13 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:20:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:20:13 INFO - 'opt_config_files': (), 02:20:13 INFO - 'pip_index': False, 02:20:13 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:20:13 INFO - 'cmd': ('bash', 02:20:13 INFO - '-c', 02:20:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:20:13 INFO - 'enabled': True, 02:20:13 INFO - 'halt_on_failure': False, 02:20:13 INFO - 'name': 'check_screen_resolution'},), 02:20:13 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:20:13 INFO - 'cmd': ('bash', 02:20:13 INFO - '-c', 02:20:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:20:13 INFO - 'enabled': True, 02:20:13 INFO - 'halt_on_failure': False, 02:20:13 INFO - 'name': 'check_screen_resolution'},), 02:20:13 INFO - 'run_cmd_checks_enabled': True, 02:20:13 INFO - 'sps_profile': False, 02:20:13 INFO - 'sps_profile_interval': 0, 02:20:13 INFO - 'suite': 'dromaeojs', 02:20:13 INFO - 'system_bits': '32', 02:20:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:20:13 INFO - 'title': 't-yosemite-r7-0243', 02:20:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:20:13 INFO - 'use_talos_json': True, 02:20:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:20:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:20:13 INFO - 'work_dir': 'build'} 02:20:13 INFO - ##### 02:20:13 INFO - ##### Running clobber step. 02:20:13 INFO - ##### 02:20:13 INFO - Running pre-action listener: _resource_record_pre_action 02:20:13 INFO - Running main action method: clobber 02:20:13 INFO - rmtree: /builds/slave/test/build 02:20:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:20:15 INFO - Running post-action listener: _resource_record_post_action 02:20:15 INFO - ##### 02:20:15 INFO - ##### Running read-buildbot-config step. 02:20:15 INFO - ##### 02:20:15 INFO - Running pre-action listener: _resource_record_pre_action 02:20:15 INFO - Running main action method: read_buildbot_config 02:20:15 INFO - Using buildbot properties: 02:20:15 INFO - { 02:20:15 INFO - "project": "", 02:20:15 INFO - "product": "firefox", 02:20:15 INFO - "script_repo_revision": "production", 02:20:15 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 02:20:15 INFO - "repository": "", 02:20:15 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 02:20:15 INFO - "buildid": "20160605004013", 02:20:15 INFO - "stage_platform": "macosx64", 02:20:15 INFO - "basedir": "/builds/slave/test", 02:20:15 INFO - "nightly_build": "True", 02:20:15 INFO - "buildnumber": 23, 02:20:15 INFO - "slavename": "t-yosemite-r7-0243", 02:20:15 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 02:20:15 INFO - "platform": "yosemite_r7", 02:20:15 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:20:15 INFO - "branch": "mozilla-aurora", 02:20:15 INFO - "repo_path": "releases/mozilla-aurora", 02:20:15 INFO - "revision": "dc972d877cd697c2c8f6248534f21bb5e1dba9ae", 02:20:15 INFO - "pgo_build": "False", 02:20:15 INFO - "builduid": "f2e7e1e69a8447ca99b55e3f1fac1182", 02:20:15 INFO - "slavebuilddir": "test" 02:20:15 INFO - } 02:20:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 02:20:15 INFO - Running post-action listener: _resource_record_post_action 02:20:15 INFO - ##### 02:20:15 INFO - ##### Running download-and-extract step. 02:20:15 INFO - ##### 02:20:15 INFO - Running pre-action listener: _resource_record_pre_action 02:20:15 INFO - Running main action method: download_and_extract 02:20:15 INFO - mkdir: /builds/slave/test/build/tests 02:20: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')]} 02:20:15 INFO - https://queue.taskcluster.net/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 02:20:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:20:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:20:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 02:20:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 02:20:45 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json: timed out 02:20:45 INFO - retry: attempt #1 caught exception: timed out 02:20:45 INFO - retry: Failed, sleeping 30 seconds before retrying 02:21:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #2 02:21:43 INFO - Downloaded 1199 bytes. 02:21:43 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 02:21:43 INFO - Using the following test package requirements: 02:21:43 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:21:43 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:21:43 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 02:21:43 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:21:43 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 02:21:43 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:21:43 INFO - u'jsshell-mac.zip'], 02:21:43 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:21:43 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 02:21:43 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:21:43 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:21:43 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 02:21:43 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:21:43 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 02:21:43 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:21:43 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 02:21:43 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:21:43 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 02:21:43 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:21: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')]} 02:21:43 INFO - https://queue.taskcluster.net/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:21:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:21:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:21:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:21:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:21:47 INFO - Downloaded 18466847 bytes. 02:21:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:21:47 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:21:50 INFO - Return code: 0 02:21:50 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:21:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:21:50 INFO - https://queue.taskcluster.net/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:21:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:21:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:21:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:21:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:21:51 INFO - Downloaded 18466847 bytes. 02:21:51 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:21:51 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:21:55 INFO - Return code: 0 02:21:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:21:55 INFO - https://queue.taskcluster.net/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 02:21:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:21:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:21:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:21:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:21:56 INFO - Downloaded 10840145 bytes. 02:21:56 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:21:56 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 02:21:57 INFO - Return code: 0 02:21:57 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')]} 02:21:57 INFO - https://queue.taskcluster.net/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 02:21:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:21:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:21:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 02:21:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 02:22:02 INFO - Downloaded 114222919 bytes. 02:22:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:22:02 INFO - mkdir: /builds/slave/test/properties 02:22:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:22:02 INFO - Writing to file /builds/slave/test/properties/build_url 02:22:02 INFO - Contents: 02:22:02 INFO - build_url:https://queue.taskcluster.net/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:22:03 INFO - Running post-action listener: _resource_record_post_action 02:22:03 INFO - Running post-action listener: set_extra_try_arguments 02:22:03 INFO - ##### 02:22:03 INFO - ##### Running populate-webroot step. 02:22:03 INFO - ##### 02:22:03 INFO - Running pre-action listener: _resource_record_pre_action 02:22:03 INFO - Running main action method: populate_webroot 02:22:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:22:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:22:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:22:03 INFO - '--tppagecycles', 02:22:03 INFO - '7'], 02:22:03 INFO - 'tests': ['tsvgm']}}, 02:22:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:22:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:22:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:22:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:22:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:22:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:22:03 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:22:03 INFO - 'talos_options': ['--disable-e10s'], 02:22:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:22:03 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:22:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:22:03 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:22:03 INFO - 'talos_options': ['--disable-e10s'], 02:22:03 INFO - 'tests': ['damp', 'tps']}, 02:22:03 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:22:03 INFO - 'tests': ['damp', 'tps']}, 02:22:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:22:03 INFO - 'tests': ['dromaeo_dom']}, 02:22:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:22:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:22:03 INFO - 'tests': ['a11yr', 02:22:03 INFO - 'ts_paint', 02:22:03 INFO - 'tpaint', 02:22:03 INFO - 'sessionrestore', 02:22:03 INFO - 'sessionrestore_no_auto_restore', 02:22:03 INFO - 'tabpaint']}, 02:22:03 INFO - 'other-e10s': {'tests': ['a11yr', 02:22:03 INFO - 'ts_paint', 02:22:03 INFO - 'tpaint', 02:22:03 INFO - 'sessionrestore', 02:22:03 INFO - 'sessionrestore_no_auto_restore', 02:22:03 INFO - 'tabpaint']}, 02:22:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:22:03 INFO - 'ts_paint', 02:22:03 INFO - 'tpaint', 02:22:03 INFO - 'sessionrestore', 02:22:03 INFO - 'sessionrestore_no_auto_restore', 02:22:03 INFO - 'tabpaint']}, 02:22:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:22:03 INFO - 'ts_paint', 02:22:03 INFO - 'tpaint', 02:22:03 INFO - 'sessionrestore', 02:22:03 INFO - 'sessionrestore_no_auto_restore', 02:22:03 INFO - 'tabpaint']}, 02:22:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:22:03 INFO - 'tests': ['a11yr', 02:22:03 INFO - 'ts_paint', 02:22:03 INFO - 'tpaint', 02:22:03 INFO - 'sessionrestore', 02:22:03 INFO - 'sessionrestore_no_auto_restore', 02:22:03 INFO - 'tabpaint']}, 02:22:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:22:03 INFO - 'tests': ['a11yr', 02:22:03 INFO - 'ts_paint', 02:22:03 INFO - 'tpaint', 02:22:03 INFO - 'sessionrestore', 02:22:03 INFO - 'sessionrestore_no_auto_restore', 02:22:03 INFO - 'tabpaint']}, 02:22:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:22:03 INFO - 'tests': ['tsvgx', 02:22:03 INFO - 'tsvgr_opacity', 02:22:03 INFO - 'tart', 02:22:03 INFO - 'tscrollx', 02:22:03 INFO - 'cart']}, 02:22:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:22:03 INFO - 'tsvgr_opacity', 02:22:03 INFO - 'tart', 02:22:03 INFO - 'tscrollx', 02:22:03 INFO - 'cart']}, 02:22:03 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:22:03 INFO - 'talos_options': ['--disable-e10s'], 02:22:03 INFO - 'tests': ['tp5o']}, 02:22:03 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:22:03 INFO - 'tests': ['tp5o']}, 02:22:03 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:22:03 INFO - 'talos_options': ['--disable-e10s', 02:22:03 INFO - '--xperf_path', 02:22:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:22:03 INFO - 'tests': ['tp5n']}, 02:22:03 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:22:03 INFO - 'talos_options': ['--xperf_path', 02:22:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:22:03 INFO - 'tests': ['tp5n']}}, 02:22:03 INFO - 'talos.zip': {'path': '', 02:22:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:22:03 INFO - Running post-action listener: _resource_record_post_action 02:22:03 INFO - ##### 02:22:03 INFO - ##### Running create-virtualenv step. 02:22:03 INFO - ##### 02:22:03 INFO - Running pre-action listener: _resource_record_pre_action 02:22:03 INFO - Running main action method: create_virtualenv 02:22:03 INFO - Creating virtualenv /builds/slave/test/build/venv 02:22:03 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 02:22:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:22:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:22:03 INFO - Using real prefix '/tools/python27' 02:22:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:22:04 INFO - Installing distribute.............................................................................................................................................................................................done. 02:22:06 INFO - Installing pip.................done. 02:22:06 INFO - Return code: 0 02:22:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:22:06 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')]} 02:22:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:22:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:06 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')]} 02:22:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:22:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:06 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 0x102366a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102376420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84784c9c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102205e00>, '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 0x10206edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102200b20>, '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.vW4o3gwK5I/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.IdP3E3HlJY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 02:22:06 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 02:22:06 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 02:22:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vW4o3gwK5I/Render', 02:22:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:22:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:22:06 INFO - 'HOME': '/Users/cltbld', 02:22:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:22:06 INFO - 'LOGNAME': 'cltbld', 02:22:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:22:06 INFO - 'MOZ_NO_REMOTE': '1', 02:22:06 INFO - 'NO_EM_RESTART': '1', 02:22:06 INFO - 'PAGER': '/bin/cat', 02:22:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:22:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:22:06 INFO - 'PWD': '/builds/slave/test', 02:22:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:22:06 INFO - 'SHELL': '/bin/bash', 02:22:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IdP3E3HlJY/Listeners', 02:22:06 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 02:22:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:22:06 INFO - 'USER': 'cltbld', 02:22:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:22:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:22:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:22:06 INFO - 'XPC_FLAGS': '0x0', 02:22:06 INFO - 'XPC_SERVICE_NAME': '0', 02:22:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:22:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:22:07 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 02:22:07 INFO - Cleaning up... 02:22:07 INFO - Return code: 0 02:22:07 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 02:22:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:22:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:22:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:22:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:22:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x102366a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102376420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84784c9c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102205e00>, '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 0x10206edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102200b20>, '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.vW4o3gwK5I/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.IdP3E3HlJY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 02:22:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 02:22:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 02:22:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vW4o3gwK5I/Render', 02:22:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:22:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:22:07 INFO - 'HOME': '/Users/cltbld', 02:22:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:22:07 INFO - 'LOGNAME': 'cltbld', 02:22:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:22:07 INFO - 'MOZ_NO_REMOTE': '1', 02:22:07 INFO - 'NO_EM_RESTART': '1', 02:22:07 INFO - 'PAGER': '/bin/cat', 02:22:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:22:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:22:07 INFO - 'PWD': '/builds/slave/test', 02:22:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:22:07 INFO - 'SHELL': '/bin/bash', 02:22:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IdP3E3HlJY/Listeners', 02:22:07 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 02:22:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:22:07 INFO - 'USER': 'cltbld', 02:22:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:22:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:22:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:22:07 INFO - 'XPC_FLAGS': '0x0', 02:22:07 INFO - 'XPC_SERVICE_NAME': '0', 02:22:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:22:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:22:07 INFO - Downloading/unpacking psutil>=0.7.1 02:22:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:22:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:22:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:22:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:22:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:22:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:22:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:22:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:22:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:22:10 INFO - Installing collected packages: psutil 02:22:10 INFO - Running setup.py install for psutil 02:22:10 INFO - building 'psutil._psutil_osx' extension 02:22:10 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 02:22:11 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 02:22:11 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 02:22:11 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 02:22:11 INFO - building 'psutil._psutil_posix' extension 02:22:11 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 02:22:11 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:22:11 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:22:11 INFO - ^ 02:22:11 INFO - 1 warning generated. 02:22:11 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 02:22:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:22:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:22:11 INFO - Successfully installed psutil 02:22:11 INFO - Cleaning up... 02:22:12 INFO - Return code: 0 02:22:12 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 02:22:12 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')]} 02:22:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:22:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:12 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')]} 02:22:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:22:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:12 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 0x102366a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102376420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84784c9c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102205e00>, '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 0x10206edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102200b20>, '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.vW4o3gwK5I/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.IdP3E3HlJY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 02:22:12 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 02:22:12 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 02:22:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vW4o3gwK5I/Render', 02:22:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:22:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:22:12 INFO - 'HOME': '/Users/cltbld', 02:22:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:22:12 INFO - 'LOGNAME': 'cltbld', 02:22:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:22:12 INFO - 'MOZ_NO_REMOTE': '1', 02:22:12 INFO - 'NO_EM_RESTART': '1', 02:22:12 INFO - 'PAGER': '/bin/cat', 02:22:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:22:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:22:12 INFO - 'PWD': '/builds/slave/test', 02:22:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:22:12 INFO - 'SHELL': '/bin/bash', 02:22:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IdP3E3HlJY/Listeners', 02:22:12 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 02:22:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:22:12 INFO - 'USER': 'cltbld', 02:22:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:22:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:22:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:22:12 INFO - 'XPC_FLAGS': '0x0', 02:22:12 INFO - 'XPC_SERVICE_NAME': '0', 02:22:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:22:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:22:12 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 02:22:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:22:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:22:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:22:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:22:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:22:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:22:14 INFO - Downloading mozsystemmonitor-0.0.tar.gz 02:22:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:22:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 02:22:14 INFO - Installing collected packages: mozsystemmonitor 02:22:14 INFO - Running setup.py install for mozsystemmonitor 02:22:14 INFO - Successfully installed mozsystemmonitor 02:22:14 INFO - Cleaning up... 02:22:15 INFO - Return code: 0 02:22:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:22: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')]} 02:22:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:22:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22: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')]} 02:22:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:22:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:15 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 0x102366a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102376420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84784c9c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102205e00>, '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 0x10206edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102200b20>, '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.vW4o3gwK5I/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.IdP3E3HlJY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 02:22:15 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 02:22:15 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 02:22:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vW4o3gwK5I/Render', 02:22:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:22:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:22:15 INFO - 'HOME': '/Users/cltbld', 02:22:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:22:15 INFO - 'LOGNAME': 'cltbld', 02:22:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:22:15 INFO - 'MOZ_NO_REMOTE': '1', 02:22:15 INFO - 'NO_EM_RESTART': '1', 02:22:15 INFO - 'PAGER': '/bin/cat', 02:22:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:22:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:22:15 INFO - 'PWD': '/builds/slave/test', 02:22:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:22:15 INFO - 'SHELL': '/bin/bash', 02:22:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IdP3E3HlJY/Listeners', 02:22:15 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 02:22:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:22:15 INFO - 'USER': 'cltbld', 02:22:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:22:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:22:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:22:15 INFO - 'XPC_FLAGS': '0x0', 02:22:15 INFO - 'XPC_SERVICE_NAME': '0', 02:22:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:22:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:22:15 INFO - Downloading/unpacking blobuploader==1.2.4 02:22:15 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 02:22:15 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 02:22:15 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 02:22:15 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 02:22:15 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 02:22:15 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 02:22:17 INFO - Downloading blobuploader-1.2.4.tar.gz 02:22:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:22:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:22:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:22:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:22:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:22:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:22:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:22:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:22:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:22:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:22:18 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 02:22:18 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 02:22:18 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 02:22:18 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 02:22:18 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 02:22:18 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 02:22:18 INFO - Downloading docopt-0.6.1.tar.gz 02:22:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:22:18 INFO - Installing collected packages: blobuploader, requests, docopt 02:22:18 INFO - Running setup.py install for blobuploader 02:22:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:22:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:22:19 INFO - Running setup.py install for requests 02:22:19 INFO - Running setup.py install for docopt 02:22:19 INFO - Successfully installed blobuploader requests docopt 02:22:19 INFO - Cleaning up... 02:22:19 INFO - Return code: 0 02:22:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:22:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:22:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:22:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:22:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:22:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:19 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 0x102366a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102376420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84784c9c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102205e00>, '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 0x10206edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102200b20>, '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.vW4o3gwK5I/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.IdP3E3HlJY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 02:22:19 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 02:22:19 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 02:22:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vW4o3gwK5I/Render', 02:22:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:22:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:22:19 INFO - 'HOME': '/Users/cltbld', 02:22:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:22:19 INFO - 'LOGNAME': 'cltbld', 02:22:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:22:19 INFO - 'MOZ_NO_REMOTE': '1', 02:22:19 INFO - 'NO_EM_RESTART': '1', 02:22:19 INFO - 'PAGER': '/bin/cat', 02:22:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:22:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:22:19 INFO - 'PWD': '/builds/slave/test', 02:22:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:22:19 INFO - 'SHELL': '/bin/bash', 02:22:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IdP3E3HlJY/Listeners', 02:22:19 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 02:22:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:22:19 INFO - 'USER': 'cltbld', 02:22:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:22:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:22:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:22:19 INFO - 'XPC_FLAGS': '0x0', 02:22:19 INFO - 'XPC_SERVICE_NAME': '0', 02:22:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:22:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:22:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:22:20 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-aVEXTu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:22:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:22:20 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-Njosng-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:22:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:22:20 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-avl5ET-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:22:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:22:20 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-CF07tu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:22:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:22:20 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-oywpnM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:22:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:22:20 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-P2wiOw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:22:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:22:20 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-O7TrPQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:22:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:22:20 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-HhUviH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:22:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:22:20 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-nZAxx3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:22:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:22:20 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-XPUp6W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:22:21 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-zGP6uN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:22:21 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-Fb7x_K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:22:21 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-t5SUdD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:22:21 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-en7AIp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:22:21 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-lOyekG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:22:21 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-OLQhgO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:22:21 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-JD05vt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:22:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:22:21 INFO - Running setup.py install for manifestparser 02:22:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:22:21 INFO - Running setup.py install for mozcrash 02:22:22 INFO - Running setup.py install for mozdebug 02:22:22 INFO - Running setup.py install for mozdevice 02:22:22 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:22:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:22:22 INFO - Running setup.py install for mozfile 02:22:22 INFO - Running setup.py install for mozhttpd 02:22:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:22:22 INFO - Running setup.py install for mozinfo 02:22:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:22:22 INFO - Running setup.py install for mozInstall 02:22:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:22:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:22:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:22:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:22:22 INFO - Running setup.py install for mozleak 02:22:23 INFO - Running setup.py install for mozlog 02:22:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:22:23 INFO - Running setup.py install for moznetwork 02:22:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:22:23 INFO - Running setup.py install for mozprocess 02:22:23 INFO - Running setup.py install for mozprofile 02:22:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:22:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:22:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:22:23 INFO - Running setup.py install for mozrunner 02:22:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:22:23 INFO - Running setup.py install for mozscreenshot 02:22:24 INFO - Running setup.py install for moztest 02:22:24 INFO - Running setup.py install for mozversion 02:22:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:22:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:22:24 INFO - Cleaning up... 02:22:24 INFO - Return code: 0 02:22:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:22:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:22:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:22:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:22:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:22:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:24 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 0x102366a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102376420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84784c9c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102205e00>, '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 0x10206edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102200b20>, '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.vW4o3gwK5I/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.IdP3E3HlJY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 02:22:24 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 02:22:24 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 02:22:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vW4o3gwK5I/Render', 02:22:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:22:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:22:24 INFO - 'HOME': '/Users/cltbld', 02:22:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:22:24 INFO - 'LOGNAME': 'cltbld', 02:22:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:22:24 INFO - 'MOZ_NO_REMOTE': '1', 02:22:24 INFO - 'NO_EM_RESTART': '1', 02:22:24 INFO - 'PAGER': '/bin/cat', 02:22:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:22:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:22:24 INFO - 'PWD': '/builds/slave/test', 02:22:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:22:24 INFO - 'SHELL': '/bin/bash', 02:22:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IdP3E3HlJY/Listeners', 02:22:24 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 02:22:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:22:24 INFO - 'USER': 'cltbld', 02:22:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:22:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:22:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:22:24 INFO - 'XPC_FLAGS': '0x0', 02:22:24 INFO - 'XPC_SERVICE_NAME': '0', 02:22:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:22:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:22:24 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-bqoCUH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:22:24 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)) 02:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:22:24 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-9OPNFF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:22:24 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)) 02:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:22:24 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-0LvZvw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:22:24 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)) 02:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:22:24 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-1XTh0E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:22:25 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)) 02:22:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:22:25 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-riYdfj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:22:25 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)) 02:22:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:22:25 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-KdlAhh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:22:25 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)) 02:22:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:22:25 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-Tv6QHZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:22:25 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)) 02:22:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:22:25 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-7ld5n8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:22:25 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)) 02:22:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:22:25 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-WtIJx7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:22:25 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)) 02:22:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:22:25 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-CmuUaQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:22:25 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)) 02:22:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:22:25 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-WBjnwM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:22:25 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)) 02:22:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:22:25 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-42U7qx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:22:25 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)) 02:22:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:22:25 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-W5VbYp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:22:26 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)) 02:22:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:22:26 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-49dsV4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:22:26 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)) 02:22:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:22:26 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-DTSB2X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:22:26 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)) 02:22:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:22:26 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-vRCJOd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:22:26 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)) 02:22:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:22:26 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-55ryif-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:22:26 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)) 02:22:26 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)) 02:22:26 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)) 02:22:26 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)) 02:22:26 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)) 02:22:26 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)) 02:22:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:22:26 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 02:22:26 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 02:22:26 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 02:22:26 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 02:22:26 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 02:22:26 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 02:22:28 INFO - Downloading blessings-1.6.tar.gz 02:22:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:22:29 INFO - Installing collected packages: blessings 02:22:29 INFO - Running setup.py install for blessings 02:22:29 INFO - Successfully installed blessings 02:22:29 INFO - Cleaning up... 02:22:29 INFO - Return code: 0 02:22:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:22:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:22:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:22:29 INFO - Reading from file tmpfile_stdout 02:22:29 INFO - Current package versions: 02:22:29 INFO - blessings == 1.6 02:22:29 INFO - blobuploader == 1.2.4 02:22:29 INFO - docopt == 0.6.1 02:22:29 INFO - manifestparser == 1.1 02:22:29 INFO - mozInstall == 1.12 02:22:29 INFO - mozcrash == 0.17 02:22:29 INFO - mozdebug == 0.1 02:22:29 INFO - mozdevice == 0.48 02:22:29 INFO - mozfile == 1.2 02:22:29 INFO - mozhttpd == 0.7 02:22:29 INFO - mozinfo == 0.9 02:22:29 INFO - mozleak == 0.1 02:22:29 INFO - mozlog == 3.1 02:22:29 INFO - moznetwork == 0.27 02:22:29 INFO - mozprocess == 0.22 02:22:29 INFO - mozprofile == 0.28 02:22:29 INFO - mozrunner == 6.11 02:22:29 INFO - mozscreenshot == 0.1 02:22:29 INFO - mozsystemmonitor == 0.0 02:22:29 INFO - moztest == 0.7 02:22:29 INFO - mozversion == 1.4 02:22:29 INFO - psutil == 3.1.1 02:22:29 INFO - requests == 1.2.3 02:22:29 INFO - wsgiref == 0.1.2 02:22:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:22:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:22:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:22:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:22:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:22:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:29 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 0x102366a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102376420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84784c9c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102205e00>, '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 0x10206edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102200b20>, '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.vW4o3gwK5I/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.IdP3E3HlJY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 02:22:29 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 02:22:29 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 02:22:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vW4o3gwK5I/Render', 02:22:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:22:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:22:29 INFO - 'HOME': '/Users/cltbld', 02:22:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:22:29 INFO - 'LOGNAME': 'cltbld', 02:22:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:22:29 INFO - 'MOZ_NO_REMOTE': '1', 02:22:29 INFO - 'NO_EM_RESTART': '1', 02:22:29 INFO - 'PAGER': '/bin/cat', 02:22:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:22:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:22:29 INFO - 'PWD': '/builds/slave/test', 02:22:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:22:29 INFO - 'SHELL': '/bin/bash', 02:22:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IdP3E3HlJY/Listeners', 02:22:29 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 02:22:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:22:29 INFO - 'USER': 'cltbld', 02:22:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:22:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:22:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:22:29 INFO - 'XPC_FLAGS': '0x0', 02:22:29 INFO - 'XPC_SERVICE_NAME': '0', 02:22:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:22:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:22:29 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)) 02:22:29 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)) 02:22:29 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)) 02:22:29 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)) 02:22:29 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)) 02:22:29 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)) 02:22:29 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)) 02:22:29 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)) 02:22:29 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)) 02:22:29 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)) 02:22:29 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)) 02:22:29 INFO - Cleaning up... 02:22:29 INFO - Return code: 0 02:22:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:22:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:22:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:22:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:22:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:22:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:29 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 0x102366a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102376420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84784c9c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102205e00>, '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 0x10206edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102200b20>, '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.vW4o3gwK5I/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.IdP3E3HlJY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 02:22:29 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 02:22:29 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 02:22:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vW4o3gwK5I/Render', 02:22:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:22:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:22:29 INFO - 'HOME': '/Users/cltbld', 02:22:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:22:29 INFO - 'LOGNAME': 'cltbld', 02:22:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:22:29 INFO - 'MOZ_NO_REMOTE': '1', 02:22:29 INFO - 'NO_EM_RESTART': '1', 02:22:29 INFO - 'PAGER': '/bin/cat', 02:22:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:22:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:22:29 INFO - 'PWD': '/builds/slave/test', 02:22:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:22:29 INFO - 'SHELL': '/bin/bash', 02:22:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IdP3E3HlJY/Listeners', 02:22:29 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 02:22:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:22:29 INFO - 'USER': 'cltbld', 02:22:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:22:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:22:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:22:29 INFO - 'XPC_FLAGS': '0x0', 02:22:29 INFO - 'XPC_SERVICE_NAME': '0', 02:22:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:22:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:22:30 INFO - Downloading/unpacking jsonschema 02:22:30 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 02:22:30 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 02:22:30 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 02:22:30 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 02:22:30 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 02:22:30 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 02:22:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:22:32 INFO - Downloading/unpacking functools32 (from jsonschema) 02:22:32 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 02:22:32 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 02:22:32 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 02:22:32 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 02:22:32 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 02:22:32 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 02:22:32 INFO - Downloading functools32-3.2.3-2.tar.gz 02:22:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:22:33 INFO - warning: no files found matching '*.txt' 02:22:33 INFO - no previously-included directories found matching 'build' 02:22:33 INFO - no previously-included directories found matching 'dist' 02:22:33 INFO - no previously-included directories found matching '.git*' 02:22:33 INFO - Installing collected packages: jsonschema, functools32 02:22:33 INFO - Running setup.py install for functools32 02:22:33 INFO - warning: no files found matching '*.txt' 02:22:33 INFO - no previously-included directories found matching 'build' 02:22:33 INFO - no previously-included directories found matching 'dist' 02:22:33 INFO - no previously-included directories found matching '.git*' 02:22:33 INFO - Successfully installed jsonschema functools32 02:22:33 INFO - Cleaning up... 02:22:33 INFO - Return code: 0 02:22:33 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 02:22:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:22:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:22:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:22:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:22:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:33 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 0x102366a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102376420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84784c9c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102205e00>, '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 0x10206edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102200b20>, '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.vW4o3gwK5I/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.IdP3E3HlJY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 02:22:33 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 02:22:33 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 02:22:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vW4o3gwK5I/Render', 02:22:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:22:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:22:33 INFO - 'HOME': '/Users/cltbld', 02:22:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:22:33 INFO - 'LOGNAME': 'cltbld', 02:22:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:22:33 INFO - 'MOZ_NO_REMOTE': '1', 02:22:33 INFO - 'NO_EM_RESTART': '1', 02:22:33 INFO - 'PAGER': '/bin/cat', 02:22:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:22:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:22:33 INFO - 'PWD': '/builds/slave/test', 02:22:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:22:33 INFO - 'SHELL': '/bin/bash', 02:22:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IdP3E3HlJY/Listeners', 02:22:33 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 02:22:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:22:33 INFO - 'USER': 'cltbld', 02:22:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:22:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:22:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:22:33 INFO - 'XPC_FLAGS': '0x0', 02:22:33 INFO - 'XPC_SERVICE_NAME': '0', 02:22:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:22:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:22:33 INFO - Downloading/unpacking flake8 02:22:33 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 02:22:33 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 02:22:33 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 02:22:33 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 02:22:33 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 02:22:33 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 02:22:36 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 02:22:36 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 02:22:36 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 02:22:36 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 02:22:36 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 02:22:36 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 02:22:36 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 02:22:36 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 02:22:36 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 02:22:36 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 02:22:36 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 02:22:36 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 02:22:36 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 02:22:36 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 02:22:36 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 02:22:36 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 02:22:36 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 02:22:36 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 02:22:36 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 02:22:36 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 02:22:36 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 02:22:36 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 02:22:36 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 02:22:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 02:22:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:22:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:22:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:22:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:22:37 INFO - no previously-included directories found matching 'docs/_build' 02:22:37 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 02:22:37 INFO - Running setup.py install for pep8 02:22:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:22:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:22:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:22:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:22:37 INFO - no previously-included directories found matching 'docs/_build' 02:22:37 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 02:22:37 INFO - Successfully installed flake8 mccabe pyflakes pep8 02:22:37 INFO - Cleaning up... 02:22:37 INFO - Return code: 0 02:22:37 INFO - Running post-action listener: _resource_record_post_action 02:22:37 INFO - Running post-action listener: _start_resource_monitoring 02:22:37 INFO - Starting resource monitoring. 02:22:37 INFO - ##### 02:22:37 INFO - ##### Running install step. 02:22:37 INFO - ##### 02:22:37 INFO - Running pre-action listener: _resource_record_pre_action 02:22:37 INFO - Running main action method: install 02:22:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:22:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:22:38 INFO - Reading from file tmpfile_stdout 02:22:38 INFO - Detecting whether we're running mozinstall >=1.0... 02:22:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:22:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:22:38 INFO - Reading from file tmpfile_stdout 02:22:38 INFO - Output received: 02:22:38 INFO - Usage: mozinstall [options] installer 02:22:38 INFO - Options: 02:22:38 INFO - -h, --help show this help message and exit 02:22:38 INFO - -d DEST, --destination=DEST 02:22:38 INFO - Directory to install application into. [default: 02:22:38 INFO - "/builds/slave/test"] 02:22:38 INFO - --app=APP Application being installed. [default: firefox] 02:22:38 INFO - mkdir: /builds/slave/test/build/application 02:22:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:22:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:23:10 INFO - Reading from file tmpfile_stdout 02:23:10 INFO - Output received: 02:23:10 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:23:10 INFO - Running post-action listener: _resource_record_post_action 02:23:10 INFO - ##### 02:23:10 INFO - ##### Running run-tests step. 02:23:10 INFO - ##### 02:23:10 INFO - Running pre-action listener: _resource_record_pre_action 02:23:10 INFO - Running main action method: run_tests 02:23:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:23:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:23:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:23:10 INFO - 2016-06-05 02:23:10.245 screenresolution[1652:8037] starting screenresolution argv=screenresolution get 02:23:10 INFO - 2016-06-05 02:23:10.247 screenresolution[1652:8037] Display 0: 1600x1200x32@60 02:23:10 INFO - 2016-06-05 02:23:10.253 screenresolution[1653:8039] starting screenresolution argv=screenresolution list 02:23:10 INFO - Available Modes on Display 0 02:23:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:23:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:23:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:23:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:23:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:23:10 INFO - Intel Iris: 02:23:10 INFO - Chipset Model: Intel Iris 02:23:10 INFO - Type: GPU 02:23:10 INFO - Bus: Built-In 02:23:10 INFO - VRAM (Dynamic, Max): 1536 MB 02:23:10 INFO - Vendor: Intel (0x8086) 02:23:10 INFO - Device ID: 0x0a2e 02:23:10 INFO - Revision ID: 0x0009 02:23:10 INFO - Displays: 02:23:10 INFO - Display: 02:23:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:23:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:23:10 INFO - Display Serial Number: 1600x1200 60 02:23:10 INFO - Main Display: Yes 02:23:10 INFO - Mirror: Off 02:23:10 INFO - Online: Yes 02:23:10 INFO - Rotation: Supported 02:23:10 INFO - Return code: 0 02:23:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:23:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:23:10 INFO - Python 2.7.3 02:23:10 INFO - Return code: 0 02:23:10 INFO - grabbing minidump binary from tooltool 02:23:10 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')]} 02:23:10 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102205e00>, '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 0x10206edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102200b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:23:10 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 02:23:10 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 02:23:10 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:23:10 INFO - Return code: 0 02:23:10 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:23:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:23:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:23:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:23:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:23:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 02:23:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 02:23:11 INFO - Return code: 0 02:23:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0243', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 02:23:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0243 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 02:23:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vW4o3gwK5I/Render', 02:23:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:23:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:23:11 INFO - 'HOME': '/Users/cltbld', 02:23:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:23:11 INFO - 'LOGNAME': 'cltbld', 02:23:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:23:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:23:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:23:11 INFO - 'MOZ_NO_REMOTE': '1', 02:23:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:23:11 INFO - 'NO_EM_RESTART': '1', 02:23:11 INFO - 'PAGER': '/bin/cat', 02:23:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:23:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:23:11 INFO - 'PWD': '/builds/slave/test', 02:23:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:23:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:23:11 INFO - 'SHELL': '/bin/bash', 02:23:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IdP3E3HlJY/Listeners', 02:23:11 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 02:23:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:23:11 INFO - 'USER': 'cltbld', 02:23:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:23:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:23:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:23:11 INFO - 'XPC_FLAGS': '0x0', 02:23:11 INFO - 'XPC_SERVICE_NAME': '0', 02:23:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:23:11 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0243', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 02:23:11 INFO - mozversion application_buildid: 20160605004013 02:23:11 INFO - mozversion application_changeset: dc972d877cd697c2c8f6248534f21bb5e1dba9ae 02:23:11 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:23:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:23:11 INFO - mozversion application_name: Firefox 02:23:11 INFO - mozversion application_remotingname: firefox-dev 02:23:11 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:23:11 INFO - mozversion application_vendor: Mozilla 02:23:11 INFO - mozversion application_version: 48.0a2 02:23:11 INFO - mozversion platform_buildid: 20160605004013 02:23:11 INFO - mozversion platform_changeset: dc972d877cd697c2c8f6248534f21bb5e1dba9ae 02:23:11 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:23:11 INFO - mozversion platform_version: 48.0a2 02:23:11 INFO - using testdate: 1465118591 02:23:11 INFO - actual date: 1465118591 02:23:11 INFO - starting webserver on 'localhost:49276' 02:23:11 INFO - SUITE-START | Running 2 tests 02:23:11 INFO - TEST-START | dromaeo_css 02:23:11 INFO - operating with platform_type : mac_ 02:23:11 INFO - Initialising browser for dromaeo_css test... 02:23:11 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/tmpkfykJD/profile http://localhost:49276/getInfo.html) 02:23:14 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 02:23:14 INFO - PROCESS | 1671 | colorDepth:24 02:23:14 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 02:23:14 INFO - PROCESS | 1671 | __metrics 02:23:14 INFO - PROCESS | 1671 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:23:14 ERROR - PROCESS | 1671 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 02:23:15 INFO - TEST-INFO | 1671: exit 0 02:23:15 INFO - Browser initialized. 02:23:15 INFO - Running cycle 1/1 for dromaeo_css test... 02:23:15 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/tmpkfykJD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:23:26 INFO - PROCESS | 1675 | RSS: Main: 211062784 02:23:26 INFO - PROCESS | 1675 | 02:24:17 INFO - PROCESS | 1675 | 1465118657651 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] 02:26:29 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49276/tests/dromaeo/cssquery-ext.html) 02:26:31 INFO - PROCESS | 1675 | RSS: Main: 395837440 02:26:31 INFO - PROCESS | 1675 | 02:29:34 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-ext.html (next: http://localhost:49276/tests/dromaeo/cssquery-jquery.html) 02:29:34 INFO - PROCESS | 1675 | RSS: Main: 358481920 02:29:34 INFO - PROCESS | 1675 | 02:32:36 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49276/tests/dromaeo/cssquery-mootools.html) 02:32:37 INFO - PROCESS | 1675 | RSS: Main: 362266624 02:32:37 INFO - PROCESS | 1675 | 02:35:39 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49276/tests/dromaeo/cssquery-prototype.html) 02:35:40 INFO - PROCESS | 1675 | RSS: Main: 363012096 02:35:40 INFO - PROCESS | 1675 | 02:38:43 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49276/tests/dromaeo/cssquery-yui.html) 02:38:43 INFO - PROCESS | 1675 | RSS: Main: 329793536 02:38:43 INFO - PROCESS | 1675 | 02:41:46 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-yui.html (next: http://localhost:49276/tests/dromaeo/cssquery-dojo.html) 02:41:46 INFO - PROCESS | 1675 | RSS: Main: 369414144 02:41:46 INFO - PROCESS | 1675 | 02:41:46 INFO - PROCESS | 1675 | __start_tp_report 02:41:46 INFO - PROCESS | 1675 | _x_x_mozilla_page_load 02:41:46 INFO - PROCESS | 1675 | _x_x_mozilla_page_load_details 02:41:46 INFO - PROCESS | 1675 | |i|pagename|runs| 02:41:46 INFO - PROCESS | 1675 | |0;dojo.html;3314.585676790401;3382.2220889195482;3392.474166504193;3414.9317013659743;3440.088376580205;5606.971965140179;5619.129034999576;5628.38087810341;5638.887222255553;5645.633033852797;5599.664020158793;5617.4382561743805;5630.746616402272;5642.069058605335;5643.6049476536655;4976.825811096613;5006.374203224597;5104.8979020419565;5134.1015322318635;5175.585953123741;5121.436641969381;5124.436312005676;5131.102057139986;5131.3842338919385;5144.09978253806;5072.72100034498;5129.666571672848;5133.743312834362;5137.614678899079;5158.716270605115;5079.746012699387;5119.56483698886;5124.600575271323;5134.743262836881;5177.275181474596;4991.575716064139;5012.8997420051755;5070.518300761421;5077.162268225738;5089.847304580869;5469.015577196104;5495.313085864267;5508.063629183049;5509.063459211943;5512.724363781835;6099.085137229406;6117.541184411187;6124.142620033199;6138;6138.815835524942;5994.13085102662;6136.294326152505;6137.202163718733;6205.2553693557065;6239.563230573862;5669.177969194526;5684.687342196219;5697;5697.886042279131;5732;6257.74969001244;6303.936960630335;6318.241810982711;6328.525360597973;6331.176946996861;5808.7095645218415;5924.851878703067;5934.673592952343;6007.038873780261;6016.789412370634;6101.60339577926;6121.969390153021;6122.367660829195;6130.724117414727;6133.509319254538;6010.519158467312;6107.083937409336;6123.295820980555;6127.234095738033;6137.478314343331;6077.17958075663;6100.877982440326;6117.959946809053;6126.172966649446;6143.877122457526;4806.855794326176;4822.348982887265;4828.95171048285;4837.612990960714;4866.780994855169;4212.052288235184;4298.871033868989;4336.566343365638;4372.431583894074;4410.448693913261;5556.416576259516;5584.441555844383;5591.692456914909;5595.440455954372;5637.830865074055;5996.220491336186;6147.963375106421;6172.043333283348;6189.319174890758;6206.813795586139;5499.807506737245;5517.36550296713;5574.916376254359;5577.6932268724795;5588;5269.920951185659;5283.762230699628;5286.762095705702;5288.391834938954;5292.417834038252;6219.004959206505;6287.528435367274;6292.339304373067;6297.370262973666;6304.779332723333;6124.938750612437;6178.691065446799;6188.690565471798;6197.1324014638885;6203.503719702503;6270.874582508233;6285.120083188449;6302.432781049728;6306.558540902093;6308.053791931247;5886.323072846678;5940.435658612425;5984.072468767348;6047.637141771508;6060;6043.909341359971;6055.2430946131735;6067.787627433018;6078.179445774764;6082.878342433215;5867.149263356875;5872.119182122716;5895.616784908967;5969.910451343147;5977.163197152489;5165.793368265227;5953.7023148843255;5957.970210148921;5959.642421454727;5976.043832986701;5754.482096611325;5788.855278618068;5788.884222315446;5812.883742325045;5818.214541037075;5770.942290577041;5869;5900.675462849413;5968.731407086779;5974.9402505974385;5838.970805145947;5848.970755146197;5858.765649373977;5861.736221869941;5862.944669959448 02:41:46 INFO - PROCESS | 1675 | |1;ext.html;525.818592585558;538.8733647592815;539.8704310965368;549.7031602934416;582.2488989203927;27572.58641120382;31720;31764.04707858762;38500.42249366262;39371;42197.367039494435;42561.57438425622;42617.147657046844;42794.572054279444;42929;9413.57638906251;9638.421694698305;9642.75893102671;9646.903530964706;9655.22758179344;8554.52950087745;8720.34597405194;9459.716208513755;11415.48630311638;11612.36132012739;11411.48648310828;11470.942645286761;11505.137114716388;11507.194496385257;11512.481938312796;11307;11406.600768973047;11415.942920285346;11421.143414243965;11427.257228280218;8539.701110461167;8740.956295218546;8746.34402419821;8750.693725719633;11327.490262938187;38975.80512097421;39407.43851649634;39422.21155576872;39450.21099578021;39509;3220.22714548509;3253.544503769474;3279.8852040178717;3315.8507867146036;3337.766356355056;27034;28314.71685283162;28319.150425487267;28640;28677.713222867715;2338.286822519131;2712.715164907676;2762.1575419497044;2766.5711814668757;2796.398774263543;484701;485505;486063;486528;487791;64962.0255696165;65736.67131664311;65895;65989.70051497502;66053.33946660568;69907.60184796275;70070;70188;70252;70365.64817175882;17294;18075.36736214226;18075.54811129732;18141.820781649414;18210.817891820912;12496.687582810502;12763.553275635308;13783.862161378458;13867.86132138686;13914.304284785923;18158.90920545389;18273.725894111598;18304.725429118575;18322.541936451697;18334.908325458553;5288.915772266676;6645.700943457556;6693.732250709917;6775.881979473332;6917.654117294115;847.4152834544145;939.1641439119189;949.2202121023082;1028.3572767020612;1107.6234080412537;29231.853840731084;29455.263618409284;29835.850820746193;29905.700942990727;30614.38771224563;950.9144177024101;1095.4030053620795;1100.4057808783327;1184.7748927703708;1224.7672942140964;15159.696806063817;15359.84801139919;15442.922785386001;15445.613859653598;15877.444289449813;567892;571125;572320;572367;574509;59633;61650.38349616536;61792;61823;61887;1362.8977826662842;1391.4851504943235;1399.510171439988;1489.9180545069908;1512.7201467728505;28493.572596410657;28838.567421489115;30301;30335.544966825073;31130.844345778125;43768.90577735455;51503.74248128735;52074;52150.478495214564;52234.69413264699;436319;438740;440837;443231;457211;37535.81232093822;39011.219775603764;39927;39930.80034599808;39975;38468;44508.66474005785;45582.54417455916;45587;47015.529844701116;37309.25381492301;37852;38174.37343452367;38558.80720596379;39318.2136357277;35499;36107.63892361043;36386.90839274826;36906.81546592357;37231.88304350874;35404.822975884956;35969.28061438809;36349.27301453903;36649.63350366569;36654;31567.84216078905;32130.678693213704;32163.356732864744;33877.322453551285;34854;24175.879120604284;24190.75809241885;24583.877080614482;24654.63018054695;24674 02:41:46 INFO - PROCESS | 1675 | |2;jquery.html;381.77856843031043;381.91406933439964;403.03473181729737;404.3994667918142;404.6418919256459;19511.707324390147;19902.701459478103;20076.69884951727;20508.87201203931;20540.38378848635;16831.91584042078;20444.18223271071;20939.581208375825;21718.348449546498;21754.912254387298;5747.913781293263;5835.79574714884;5998.040313549833;6041.395860413967;6082.878342433149;5971.940280597182;5992.400759924017;6009.399060094;6016.939830601682;6025.969870150665;5565.2486914266465;5870.618409803371;5874.412558744113;5893.38119497453;5927.555433342495;5816.709164541777;5851.76592936282;5871.9119213211625;5884.823455296305;5885.117232415129;5574.052411090124;5618.690971996539;5704.401037891044;5711.600187986843;5718.5711071669375;13273;13716.314184290846;13863.306834658226;13905.79141312881;13937.303134843218;2132.3070002249174;2148.237375731624;2188.6060509108356;2397.8681172535503;2566.1788227767124;9970.9501452493;10059.730587559072;10156.238282128796;10358.741031474197;14180.574582762534;1716.373523663866;1791.167107295114;1793.6861049316449;1821.9362322318655;1889.2915156816193;110400;116873.99999999916;119619.40190298992;120453.59092818094;122079;9687.951560242154;11533.538658453568;11748.412579371168;11836.585719499777;11937.343446110548;15191.392344306325;15306.158161301013;15353.850460935479;15387.692246155015;15418.691626167638;8866.73399798007;8880.955595222113;8904.8664270036;8914.465132091966;9059.68291109808;8937;8952.283817294601;8954.731358059269;9033.457992520469;9047.954760226157;4673.906521869543;4678.929816052762;4682.929756053662;4695.154872122982;4749.881252968704;1913.866029377958;1986.0864002558696;2007.708882212071;2015.6472617292209;2039.3677959832498;772.7720322504874;908.4276905549461;1059.523214553454;1071.844582535528;1080.6487891435158;19876.60246795056;20122.597548048958;20219.696704549442;20302.898485507772;20460.897695511427;925.8194652042922;940.7836197674574;961.6009356117273;962.6341990043738;993.6969224386631;10347.189241882641;10725.78548429027;10726;10807;10923.781524369628;591321;592207;598601;612372;613829;121540.39229803794;125244.12133818;125266;126111.10833337507;126295.73704263024;1006.0291818395284;1006.5621454667244;1014.8274793285159;1027.024326889458;1028.6708253359002;17585.560360991076;17788.644227115645;17822.821771782117;18191.818081819543;18339.71621986448;53246;53320.73339633432;53846;54891.725541372034;56204;77954.22045779697;78269.60865195637;78586;79209.60395197987;79439.60280198562;8035.397345199251;8078.959605202172;8104.756857294291;8291.046529649167;8497.065322814362;10463.68608941733;12168.45241964096;12393.132480726263;12399.690007749878;12537.937310313755;10507.947460262956;10773.407462589634;10953.908256147537;10983.066439352744;11082.279651822606;10175.643852465424;10233.539490722786;10452.634157804441;10637.095846853104;10991.780164396507;10843.783124337628;11050.613228536962;11099.278546894426;11113.277636953571;11344.659660210207;7977.960110199412;7986.600669966362;8055.597220138852;8246;8256.752297431087;8093.757187284391;9915.058069483388;10337.397703355991;11064.059554937598;11066.225364224749 02:41:46 INFO - PROCESS | 1675 | |3;mootools.html;1358.2325985818018;1554.1063888264248;1567.1850637668413;1783.9197236124373;1927.9228830846757;7909.644066017028;8616.483011019336;8747.169018943207;8754;8782.25350845179;7481.5885126318035;8512;8734.475931444118;8746.737597872074;8785.692500762474;2159.4277516458073;2403.531311394279;2429.125514814665;2466.3587467258462;2467.6915385576804;2484.5527804995095;2492.7881130103965;2494.6008638617823;2495.7129930058004;2496.151308555091;2390.916317928873;2412.662227288181;2429.6841410616594;2429.6962879640046;2434.7078350597953;2131.509752756859;2384.141708984764;2429.866357350345;2434.9391265218424;2435.537247922889;2424.7211570669424;2447.596146635803;2451.399407145243;2453.766892145243;2455.484348286862;8665.263452606536;8723.60743766532;8742.86885696715;8752.562371881444;8759.2992560595;5137.691738495702;5306.283651707009;5306.867328316785;5344.331958505187;5371.409144994048;3059.4492991261563;3566.2154326048226;3613.295407395559;3629.383004889175;3660.084978755311;1046.6964580271713;1051.2588625019343;1064.2390690656218;1605.8474444927713;1654.8096968848615;9804.46075465849;10701.625443109453;10855.457227138611;11666.708332291675;11672.182947193785;4466.798994045275;4558.407407037064;4642.489326174118;4673.906521869543;4678.157931572349;9179.862302065474;9199;9213.677521286723;9225;9235.12266334697;5322.2282768998275;5333.893322133536;5363.758630861621;5373.35519737634;5389.9759045781175;4069.409935559327;4401.889952751143;4479.888002799956;4868.9269660955115;4879.4876537963;3276;3762.774233545996;3815.2369526094335;3862.1310205203604;3895.6883449323986;1044.968650940473;1188.6493484422117;1191;1194.235689158939;1204.5422739359162;842.8145807922248;891.7993451473496;902.2962089570145;910.4537277633499;922.8938672052666;5078.187490001582;5789.942100579025;5963.98612235921;5970.791022314198;6120.622859856497;468.62978247185026;472.5534370020298;472.75889296458365;1006.8187726209353;1026.779242462874;3618.782873027627;3662.61542538035;3706.592274849764;3719.1817800083936;3723.478712980185;10466.790664186674;10773.676789696321;10870.673879783619;10878.075363594025;10886.564537418415;10631.989960953852;10697.83953240702;10708.12607793633;10710.839337409945;10717.571297148026;8055;8211.671533138728;8486.533240671823;8535.445196062235;8550.529720865288;5736.4550367715;5838.678872662044;5851.64890106595;5855.23881895368;5880.14737863016;9403.717888463358;9550.997145299922;9702.369345992487;9708.320417570701;9820.852687209555;9505.762355941157;9788.510574471391;9934.056264654846;9955.900440995498;10063.295569310078;5822.272215973004;6398.168238129014;6597.769078082244;7024.8595028098125;7135.500514963903;8235.13531079228;8284.710035148983;8350.206730360605;8350.415470916978;8351.95824020876;7300.087489063867;7429.442791790745;7453.329200371993;7536.397088233038;7592.506487078323;7328.670209840463;7368.557886526825;7384.52000619958;7434.256574342522;7453.142888567884;7024.297570242935;7043;7069.399101076466;7075.646217689198;7089.964550177217;4757.738324392191;4790.473047964651;4826.782794774173;4898.2652602109965;4902.7058376497525;4831.275308703723;4902.9019419610695;4926.605871530341;4952.8761780955765;5080.796768129233 02:41:46 INFO - PROCESS | 1675 | |4;prototype.html;314.11106568411395;320.1563879178366;323.59388966846603;328.91119397762606;330.57686161713013;6638.23660279068;6684.899726504106;6695.096162018126;6720.327967203277;6733.293004234552;6619.7683081092055;6626.834329141774;6626.867462650756;6649.002649602562;6654.800355989328;4638.559336863001;4746;4758.262469317261;4758.381410416641;4761.976190119045;4739.549742774431;4775.044991001813;4817.181079216542;4839.080574690815;4874.610031197496;4730.479647238801;4797.304390863323;4799.13615549201;4821.059893320804;4828.589569886564;4633.050224703928;4822.445418776851;4828.203346447832;4831.178699621073;4837.153498137812;4054.2702313583545;4075.6128167824304;4121.567235440296;4136.276151673445;4148.688848336386;6316.147320111772;6326.778562750282;6331.493480521547;6358.109864618957;6384.297727249998;5748.97125514374;5768.134779783024;5772.07646776518;5777.913331300034;5800.158976948319;5432.483914028161;6017.789377371771;7067.363937245621;7075.681594328216;7091.6454177290925;3885.3783394657003;4361.542038085987;4670.229412146991;4695.530446955311;4710.599599034086;5954.851128721773;7343.559386436831;7454.776356709307;7455.664495097733;7462.291082347223;4646.930296045494;4660.1145782301255;4662.787675204471;4671;4674.719516829001;4737.644676649265;4738.147133515931;4741.881452963704;4741.905161896742;4747.620190384761;5671.177679236487;5675.1770993205755;5678.205051292823;5685.5167310778215;5689.544836413077;6856.657167141623;7064.50548461613;7069.257727938492;7069.82325441868;7087.574745515182;1618.9271482783304;1637.3696126991194;1648.2912347690608;1651.3229575873834;1654.1894471708777;1023.7747695506977;1026.557686450538;1046.016744260393;1047.0681093826502;1049.396596956753;5875.23621929155;6215.627062376272;6319.778807741707;6479.22249330074;6573.342665733484;7388.593627350548;7393.667284972189;7395.334419902127;7396.371308438843;7411;6623.966880165568;6630.834229144213;6633.336666333425;6648.601083934883;6651.135352404156;3834.405667121601;3881.4760007399204;3884.2231553689376;3897.493325867619;3899.883003509899;6195.535334849904;6278.6860656966965;7375.741849035258;7704.191059938737;7745.457817952689;7571.772846814604;7587.317141457295;7591.924080759121;7599.886001709979;7613.314801667876;7664;7690;7690.307872291297;7694.115176754744;7705.691772329268;6004.939950600438;7362.263773622595;7373.18894921569;7374.373178279906;7382.704691812267;6952.235254121942;7208.531445456028;7225.494215404871;7238.710451581877;7268.309510596485;7640.312371886557;7693.769186924401;7695.422843286664;7704.845903081795;7718.459707820399;4657.534246575316;4665.300204969146;4668.416447944007;4669.39297891272;4676.462206846256;6373.235211774431;6984.3364880336285;7342.6695798688115;7348.77953661391;7387.667554959932;6322.083297921684;6569.310222426577;6854.897176542256;7079.9646001771725;7102.18324892644;7039.788806335818;7057.788266352018;7079.539829911039;7083.008378826866;7083.327083927019;6905.792826215222;6913.446924246149;6919.169699636076;6932.61870597122;6951.540176562979;5968.4926781224085;6934.202566704894;7085.929140708733;7097.929020709933;7099.929000709927;6619.66901654925;6641.402273795382;6936.653167341714;6941.271166527442;6972.825679358057 02:41:46 INFO - PROCESS | 1675 | |5;yui.html;692.141744237146;739.2053542441877;747.6075060593191;750.5923109084755;796.9003874515686;14770.630734231623;14910.030847994887;15265.771013434793;15317.693646127085;15358.616034599141;13855.515056973009;13952.44190232392;13958;13975.580732578013;13996.230207338593;731.3162193349209;748.5994992678918;861.5046348349692;909.5997760985179;939.0374865762614;923.8706581078654;927.9721608351726;934.8971613122551;935.434062392253;935.6163972771166;980.7989362180735;985.3102828020378;985.9309848310621;987.0918754745651;988.9912289464742;761.3224230434889;780.8165081205912;780.8321210939675;781.917898620648;951.7430293820698;710.4813486155084;713.2012146396017;713.350850725837;713.7216485570632;714.2821464428216;14775.556733298019;14852.925735371362;14861.92569037148;14879;14896.478623248135;11051.502682379312;12112.39438028095;12591.118621696485;12733.172343797623;12905.419256133402;10049.246306526966;10193.133583645398;10305.278630495868;10345.63790267337;11151.38667373294;3473.722102231841;3506.6318036606044;3550.5384300041087;3617.8733744319084;3630.963690363089;76306;77048.61475692586;77216.22783772147;80899.5955020227;81325;9443.527823608792;9641.758956026015;9651.758706032266;9774.657886973922;9864.950675246579;22623.773762262168;22673.659895101588;22742.65886011711;22872.199473018365;22908.88545557295;7229.132504099542;7470.2903224193615;7510.4742668013805;7586.848263034708;7604.429667774939;4021.2962731521866;4029.81865816039;4032.1330913853076;4035.3745169498775;4036.0918793271867;3490.110021944388;3545.858165673344;3573.3746594346408;3573.428251479803;3579;637.5377851057946;642.5502148496072;647.4043879630751;659.9109120268798;670.3598063849013;522.5323335614604;541.8374487653688;604.9364816694272;606.4117805728437;613.0773186354508;7731.458797884206;7848.293653571206;7934.682612695427;8088;8224.300934420522;582.0628787651877;587.6767777722321;588.2470437839572;589.675678376891;608.8660494691161;3660.194757153422;3782.6028267031566;3800.315943130208;3820.8853734388013;3848.8845334640005;83603;85012;86305.70541441883;86518.13481865101;86752;52123.47876521186;63249;65297.673511632136;66778.6644267112;67623.32376676268;59512.70243648754;59547;59801.40198597958;60030.99999999913;60050.59797608047;7460.368974211835;7583.696652133853;7587.544747315179;7628;7643.694252229849;46136.76931615321;46274.768626156656;46449.76775116238;46831.48763842657;47128.52871471241;60099;63780.681096596076;63865.7226855451;64839.67580162069;65026;9993.700188994342;10193.235507337127;10210.948945255226;10211.540480678239;10531.21015923824;10433.53049112807;10516.631917882838;10709.625163119254;11038.392888391216;11150.888491115309;10247.282690211614;10739.624113156002;11093.778124437627;11245.550177992789;11437.970582647602;10647.148228141576;10745.925407459505;11157.609483668033;11158.218924675195;11488.19582629208;10646.254762166884;10770.784584308114;11162.49768760424;11196.104311654884;11209.215354925076;8862.778430539289;9332.81334373295;9412.764680883032;9616.51917404113;9749.610015599581;9053.77365565866;9458.574364153767;9495.24038076966;9559.091886270793;9579.425234485952 02:41:46 INFO - PROCESS | 1675 | __end_tp_report 02:41:46 INFO - PROCESS | 1675 | __start_cc_report 02:41:46 INFO - PROCESS | 1675 | _x_x_mozilla_cycle_collect,1853 02:41:46 INFO - PROCESS | 1675 | __end_cc_report 02:41:46 INFO - PROCESS | 1675 | __startTimestamp1465119706916__endTimestamp 02:41:46 INFO - PROCESS | 1675 | 02:41:46 INFO - PROCESS | 1675 | ------- Summary: start ------- 02:41:46 INFO - PROCESS | 1675 | Number of tests: 6 02:41:46 INFO - PROCESS | 1675 | 02:41:46 INFO - PROCESS | 1675 | [#0] dojo.html Cycles:165 Average:5636.20 Median:5818.21 stddev:625.23 (10.7%) stddev-sans-first:600.04 02:41:46 INFO - PROCESS | 1675 | Values: 3314.6 3382.2 3392.5 3414.9 3440.1 5607.0 5619.1 5628.4 5638.9 5645.6 5599.7 5617.4 5630.7 5642.1 5643.6 4976.8 5006.4 5104.9 5134.1 5175.6 5121.4 5124.4 5131.1 5131.4 5144.1 5072.7 5129.7 5133.7 5137.6 5158.7 5079.7 5119.6 5124.6 5134.7 5177.3 4991.6 5012.9 5070.5 5077.2 5089.8 5469.0 5495.3 5508.1 5509.1 5512.7 6099.1 6117.5 6124.1 6138.0 6138.8 5994.1 6136.3 6137.2 6205.3 6239.6 5669.2 5684.7 5697.0 5697.9 5732.0 6257.7 6303.9 6318.2 6328.5 6331.2 5808.7 5924.9 5934.7 6007.0 6016.8 6101.6 6122.0 6122.4 6130.7 6133.5 6010.5 6107.1 6123.3 6127.2 6137.5 6077.2 6100.9 6118.0 6126.2 6143.9 4806.9 4822.3 4829.0 4837.6 4866.8 4212.1 4298.9 4336.6 4372.4 4410.4 5556.4 5584.4 5591.7 5595.4 5637.8 5996.2 6148.0 6172.0 6189.3 6206.8 5499.8 5517.4 5574.9 5577.7 5588.0 5269.9 5283.8 5286.8 5288.4 5292.4 6219.0 6287.5 6292.3 6297.4 6304.8 6124.9 6178.7 6188.7 6197.1 6203.5 6270.9 6285.1 6302.4 6306.6 6308.1 5886.3 5940.4 5984.1 6047.6 6060.0 6043.9 6055.2 6067.8 6078.2 6082.9 5867.1 5872.1 5895.6 5969.9 5977.2 5165.8 5953.7 5958.0 5959.6 5976.0 5754.5 5788.9 5788.9 5812.9 5818.2 5770.9 5869.0 5900.7 5968.7 5974.9 5839.0 5849.0 5858.8 5861.7 5862.9 02:41:46 INFO - PROCESS | 1675 | 02:41:46 INFO - PROCESS | 1675 | [#1] ext.html Cycles:180 Average:64920.90 Median:37069.35 stddev:133864.92 (361.1%) stddev-sans-first:134153.13 02:41:46 INFO - PROCESS | 1675 | Values: 525.8 538.9 539.9 549.7 582.2 27572.6 31720.0 31764.0 38500.4 39371.0 42197.4 42561.6 42617.1 42794.6 42929.0 9413.6 9638.4 9642.8 9646.9 9655.2 8554.5 8720.3 9459.7 11415.5 11612.4 11411.5 11470.9 11505.1 11507.2 11512.5 11307.0 11406.6 11415.9 11421.1 11427.3 8539.7 8741.0 8746.3 8750.7 11327.5 38975.8 39407.4 39422.2 39450.2 39509.0 3220.2 3253.5 3279.9 3315.9 3337.8 27034.0 28314.7 28319.2 28640.0 28677.7 2338.3 2712.7 2762.2 2766.6 2796.4 484701.0 485505.0 486063.0 486528.0 487791.0 64962.0 65736.7 65895.0 65989.7 66053.3 69907.6 70070.0 70188.0 70252.0 70365.6 17294.0 18075.4 18075.5 18141.8 18210.8 12496.7 12763.6 13783.9 13867.9 13914.3 18158.9 18273.7 18304.7 18322.5 18334.9 5288.9 6645.7 6693.7 6775.9 6917.7 847.4 939.2 949.2 1028.4 1107.6 29231.9 29455.3 29835.9 29905.7 30614.4 950.9 1095.4 1100.4 1184.8 1224.8 15159.7 15359.8 15442.9 15445.6 15877.4 567892.0 571125.0 572320.0 572367.0 574509.0 59633.0 61650.4 61792.0 61823.0 61887.0 1362.9 1391.5 1399.5 1489.9 1512.7 28493.6 28838.6 30301.0 30335.5 31130.8 43768.9 51503.7 52074.0 52150.5 52234.7 436319.0 438740.0 440837.0 443231.0 457211.0 37535.8 39011.2 39927.0 39930.8 39975.0 38468.0 44508.7 45582.5 45587.0 47015.5 37309.3 37852.0 38174.4 38558.8 39318.2 35499.0 36107.6 36386.9 36906.8 37231.9 35404.8 35969.3 36349.3 36649.6 36654.0 31567.8 32130.7 32163.4 33877.3 34854.0 24175.9 24190.8 24583.9 24654.6 24674.0 02:41:46 INFO - PROCESS | 1675 | 02:41:46 INFO - PROCESS | 1675 | [#2] jquery.html Cycles:180 Average:34729.76 Median:20739.98 stddev:100386.81 (484.0%) stddev-sans-first:100635.29 02:41:46 INFO - PROCESS | 1675 | Values: 381.8 381.9 403.0 404.4 404.6 19511.7 19902.7 20076.7 20508.9 20540.4 16831.9 20444.2 20939.6 21718.3 21754.9 5747.9 5835.8 5998.0 6041.4 6082.9 5971.9 5992.4 6009.4 6016.9 6026.0 5565.2 5870.6 5874.4 5893.4 5927.6 5816.7 5851.8 5871.9 5884.8 5885.1 5574.1 5618.7 5704.4 5711.6 5718.6 13273.0 13716.3 13863.3 13905.8 13937.3 2132.3 2148.2 2188.6 2397.9 2566.2 9971.0 10059.7 10156.2 10358.7 14180.6 1716.4 1791.2 1793.7 1821.9 1889.3 110400.0 116874.0 119619.4 120453.6 122079.0 9688.0 11533.5 11748.4 11836.6 11937.3 15191.4 15306.2 15353.9 15387.7 15418.7 8866.7 8881.0 8904.9 8914.5 9059.7 8937.0 8952.3 8954.7 9033.5 9048.0 4673.9 4678.9 4682.9 4695.2 4749.9 1913.9 1986.1 2007.7 2015.6 2039.4 772.8 908.4 1059.5 1071.8 1080.6 19876.6 20122.6 20219.7 20302.9 20460.9 925.8 940.8 961.6 962.6 993.7 10347.2 10725.8 10726.0 10807.0 10923.8 591321.0 592207.0 598601.0 612372.0 613829.0 121540.4 125244.1 125266.0 126111.1 126295.7 1006.0 1006.6 1014.8 1027.0 1028.7 17585.6 17788.6 17822.8 18191.8 18339.7 53246.0 53320.7 53846.0 54891.7 56204.0 77954.2 78269.6 78586.0 79209.6 79439.6 8035.4 8079.0 8104.8 8291.0 8497.1 10463.7 12168.5 12393.1 12399.7 12537.9 10507.9 10773.4 10953.9 10983.1 11082.3 10175.6 10233.5 10452.6 10637.1 10991.8 10843.8 11050.6 11099.3 11113.3 11344.7 7978.0 7986.6 8055.6 8246.0 8256.8 8093.8 9915.1 10337.4 11064.1 11066.2 02:41:46 INFO - PROCESS | 1675 | 02:41:46 INFO - PROCESS | 1675 | [#3] mootools.html Cycles:180 Average:5608.91 Median:4902.80 stddev:3116.36 (63.6%) stddev-sans-first:3108.73 02:41:46 INFO - PROCESS | 1675 | Values: 1358.2 1554.1 1567.2 1783.9 1927.9 7909.6 8616.5 8747.2 8754.0 8782.3 7481.6 8512.0 8734.5 8746.7 8785.7 2159.4 2403.5 2429.1 2466.4 2467.7 2484.6 2492.8 2494.6 2495.7 2496.2 2390.9 2412.7 2429.7 2429.7 2434.7 2131.5 2384.1 2429.9 2434.9 2435.5 2424.7 2447.6 2451.4 2453.8 2455.5 8665.3 8723.6 8742.9 8752.6 8759.3 5137.7 5306.3 5306.9 5344.3 5371.4 3059.4 3566.2 3613.3 3629.4 3660.1 1046.7 1051.3 1064.2 1605.8 1654.8 9804.5 10701.6 10855.5 11666.7 11672.2 4466.8 4558.4 4642.5 4673.9 4678.2 9179.9 9199.0 9213.7 9225.0 9235.1 5322.2 5333.9 5363.8 5373.4 5390.0 4069.4 4401.9 4479.9 4868.9 4879.5 3276.0 3762.8 3815.2 3862.1 3895.7 1045.0 1188.6 1191.0 1194.2 1204.5 842.8 891.8 902.3 910.5 922.9 5078.2 5789.9 5964.0 5970.8 6120.6 468.6 472.6 472.8 1006.8 1026.8 3618.8 3662.6 3706.6 3719.2 3723.5 10466.8 10773.7 10870.7 10878.1 10886.6 10632.0 10697.8 10708.1 10710.8 10717.6 8055.0 8211.7 8486.5 8535.4 8550.5 5736.5 5838.7 5851.6 5855.2 5880.1 9403.7 9551.0 9702.4 9708.3 9820.9 9505.8 9788.5 9934.1 9955.9 10063.3 5822.3 6398.2 6597.8 7024.9 7135.5 8235.1 8284.7 8350.2 8350.4 8352.0 7300.1 7429.4 7453.3 7536.4 7592.5 7328.7 7368.6 7384.5 7434.3 7453.1 7024.3 7043.0 7069.4 7075.6 7090.0 4757.7 4790.5 4826.8 4898.3 4902.7 4831.3 4902.9 4926.6 4952.9 5080.8 02:41:46 INFO - PROCESS | 1675 | 02:41:46 INFO - PROCESS | 1675 | [#4] prototype.html Cycles:180 Average:5740.16 Median:6378.77 stddev:1835.01 (28.8%) stddev-sans-first:1794.39 02:41:46 INFO - PROCESS | 1675 | Values: 314.1 320.2 323.6 328.9 330.6 6638.2 6684.9 6695.1 6720.3 6733.3 6619.8 6626.8 6626.9 6649.0 6654.8 4638.6 4746.0 4758.3 4758.4 4762.0 4739.5 4775.0 4817.2 4839.1 4874.6 4730.5 4797.3 4799.1 4821.1 4828.6 4633.1 4822.4 4828.2 4831.2 4837.2 4054.3 4075.6 4121.6 4136.3 4148.7 6316.1 6326.8 6331.5 6358.1 6384.3 5749.0 5768.1 5772.1 5777.9 5800.2 5432.5 6017.8 7067.4 7075.7 7091.6 3885.4 4361.5 4670.2 4695.5 4710.6 5954.9 7343.6 7454.8 7455.7 7462.3 4646.9 4660.1 4662.8 4671.0 4674.7 4737.6 4738.1 4741.9 4741.9 4747.6 5671.2 5675.2 5678.2 5685.5 5689.5 6856.7 7064.5 7069.3 7069.8 7087.6 1618.9 1637.4 1648.3 1651.3 1654.2 1023.8 1026.6 1046.0 1047.1 1049.4 5875.2 6215.6 6319.8 6479.2 6573.3 7388.6 7393.7 7395.3 7396.4 7411.0 6624.0 6630.8 6633.3 6648.6 6651.1 3834.4 3881.5 3884.2 3897.5 3899.9 6195.5 6278.7 7375.7 7704.2 7745.5 7571.8 7587.3 7591.9 7599.9 7613.3 7664.0 7690.0 7690.3 7694.1 7705.7 6004.9 7362.3 7373.2 7374.4 7382.7 6952.2 7208.5 7225.5 7238.7 7268.3 7640.3 7693.8 7695.4 7704.8 7718.5 4657.5 4665.3 4668.4 4669.4 4676.5 6373.2 6984.3 7342.7 7348.8 7387.7 6322.1 6569.3 6854.9 7080.0 7102.2 7039.8 7057.8 7079.5 7083.0 7083.3 6905.8 6913.4 6919.2 6932.6 6951.5 5968.5 6934.2 7085.9 7097.9 7099.9 6619.7 6641.4 6936.7 6941.3 6972.8 02:41:46 INFO - PROCESS | 1675 | 02:41:46 INFO - PROCESS | 1675 | [#5] yui.html Cycles:180 Average:17048.44 Median:60074.80 stddev:23246.73 (38.7%) stddev-sans-first:23279.50 02:41:46 INFO - PROCESS | 1675 | Values: 692.1 739.2 747.6 750.6 796.9 14770.6 14910.0 15265.8 15317.7 15358.6 13855.5 13952.4 13958.0 13975.6 13996.2 731.3 748.6 861.5 909.6 939.0 923.9 928.0 934.9 935.4 935.6 980.8 985.3 985.9 987.1 989.0 761.3 780.8 780.8 781.9 951.7 710.5 713.2 713.4 713.7 714.3 14775.6 14852.9 14861.9 14879.0 14896.5 11051.5 12112.4 12591.1 12733.2 12905.4 10049.2 10193.1 10305.3 10345.6 11151.4 3473.7 3506.6 3550.5 3617.9 3631.0 76306.0 77048.6 77216.2 80899.6 81325.0 9443.5 9641.8 9651.8 9774.7 9865.0 22623.8 22673.7 22742.7 22872.2 22908.9 7229.1 7470.3 7510.5 7586.8 7604.4 4021.3 4029.8 4032.1 4035.4 4036.1 3490.1 3545.9 3573.4 3573.4 3579.0 637.5 642.6 647.4 659.9 670.4 522.5 541.8 604.9 606.4 613.1 7731.5 7848.3 7934.7 8088.0 8224.3 582.1 587.7 588.2 589.7 608.9 3660.2 3782.6 3800.3 3820.9 3848.9 83603.0 85012.0 86305.7 86518.1 86752.0 52123.5 63249.0 65297.7 66778.7 67623.3 59512.7 59547.0 59801.4 60031.0 60050.6 7460.4 7583.7 7587.5 7628.0 7643.7 46136.8 46274.8 46449.8 46831.5 47128.5 60099.0 63780.7 63865.7 64839.7 65026.0 9993.7 10193.2 10210.9 10211.5 10531.2 10433.5 10516.6 10709.6 11038.4 11150.9 10247.3 10739.6 11093.8 11245.6 11438.0 10647.1 10745.9 11157.6 11158.2 11488.2 10646.3 10770.8 11162.5 11196.1 11209.2 8862.8 9332.8 9412.8 9616.5 9749.6 9053.8 9458.6 9495.2 9559.1 9579.4 02:41:46 INFO - PROCESS | 1675 | -------- Summary: end -------- 02:41:46 INFO - PROCESS | 1675 | 02:41:47 INFO - TEST-INFO | 1675: exit 0 02:41:47 INFO - TEST-OK | dromaeo_css | took 1115563ms 02:41:47 INFO - TEST-START | kraken 02:41:47 INFO - operating with platform_type : mac_ 02:41:47 INFO - Initialising browser for kraken test... 02:41:47 INFO - TEST-INFO | started process 1681 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/tmpmrhdQU/profile http://localhost:49276/getInfo.html) 02:41:49 INFO - PROCESS | 1681 | __metrics Screen width/height:1600/1200 02:41:49 INFO - PROCESS | 1681 | colorDepth:24 02:41:49 INFO - PROCESS | 1681 | Browser inner width/height: 1024/689 02:41:49 INFO - PROCESS | 1681 | __metrics 02:41:49 INFO - PROCESS | 1681 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:41:49 ERROR - PROCESS | 1681 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 02:41:50 INFO - TEST-INFO | 1681: exit 0 02:41:50 INFO - Browser initialized. 02:41:50 INFO - Running cycle 1/1 for kraken test... 02:41:50 INFO - TEST-INFO | started process 1684 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/tmpmrhdQU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:42:02 INFO - PROCESS | 1684 | RSS: Main: 211046400 02:42:02 INFO - PROCESS | 1684 | 02:42:05 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection) 02:42:06 INFO - PROCESS | 1684 | RSS: Main: 271446016 02:42:06 INFO - PROCESS | 1684 | 02:42:08 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-dft) 02:42:09 INFO - PROCESS | 1684 | RSS: Main: 285601792 02:42:09 INFO - PROCESS | 1684 | 02:42:12 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-fft) 02:42:13 INFO - PROCESS | 1684 | RSS: Main: 373338112 02:42:13 INFO - PROCESS | 1684 | 02:42:15 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator) 02:42:15 INFO - PROCESS | 1684 | RSS: Main: 280272896 02:42:15 INFO - PROCESS | 1684 | 02:42:17 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:42:18 INFO - PROCESS | 1684 | RSS: Main: 279674880 02:42:18 INFO - PROCESS | 1684 | 02:42:21 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom) 02:42:21 INFO - PROCESS | 1684 | RSS: Main: 318562304 02:42:21 INFO - PROCESS | 1684 | 02:42:24 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate) 02:42:25 INFO - PROCESS | 1684 | RSS: Main: 318386176 02:42:25 INFO - PROCESS | 1684 | 02:42:28 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial) 02:42:28 INFO - PROCESS | 1684 | RSS: Main: 317947904 02:42:28 INFO - PROCESS | 1684 | 02:42:30 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:42:31 INFO - PROCESS | 1684 | RSS: Main: 308903936 02:42:31 INFO - PROCESS | 1684 | 02:42:32 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:42:33 INFO - PROCESS | 1684 | RSS: Main: 316317696 02:42:33 INFO - PROCESS | 1684 | 02:42:35 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:42:36 INFO - PROCESS | 1684 | RSS: Main: 318382080 02:42:36 INFO - PROCESS | 1684 | 02:42:39 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:42:39 INFO - PROCESS | 1684 | RSS: Main: 323948544 02:42:39 INFO - PROCESS | 1684 | 02:42:42 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:42:43 INFO - PROCESS | 1684 | RSS: Main: 321904640 02:42:43 INFO - PROCESS | 1684 | 02:42:45 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49276/tests/kraken/driver.html?testName=ai-astar) 02:42:45 INFO - PROCESS | 1684 | RSS: Main: 385806336 02:42:45 INFO - PROCESS | 1684 | 02:42:45 INFO - PROCESS | 1684 | __start_tp_report 02:42:45 INFO - PROCESS | 1684 | _x_x_mozilla_page_load 02:42:45 INFO - PROCESS | 1684 | _x_x_mozilla_page_load_details 02:42:45 INFO - PROCESS | 1684 | |i|pagename|runs| 02:42:45 INFO - PROCESS | 1684 | |0;ai-astar;113;113;119;112;112;111;114;113;113;135 02:42:45 INFO - PROCESS | 1684 | |1;audio-beat-detection;156;121;165;181;146;143;118;152;158;117 02:42:45 INFO - PROCESS | 1684 | |2;audio-dft;184;188;187;185;185;185;183;185;183;183 02:42:45 INFO - PROCESS | 1684 | |3;audio-fft;70;75;70;154;55;56;58;64;131;54 02:42:45 INFO - PROCESS | 1684 | |4;audio-oscillator;91;89;138;76;79;78;125;78;76;124 02:42:45 INFO - PROCESS | 1684 | |5;imaging-gaussian-blur;89;86;91;87;89;86;91;87;75;115 02:42:45 INFO - PROCESS | 1684 | |6;imaging-darkroom;94;95;96;96;102;138;96;96;94;94 02:42:45 INFO - PROCESS | 1684 | |7;imaging-desaturate;84;84;85;86;85;85;85;85;86;85 02:42:45 INFO - PROCESS | 1684 | |8;json-parse-financial;60;64;64;64;65;64;65;84;58;57 02:42:45 INFO - PROCESS | 1684 | |9;json-stringify-tinderbox;63;64;71;73;76;72;66;67;69;66 02:42:45 INFO - PROCESS | 1684 | |10;stanford-crypto-aes;81;80;81;79;79;80;77;80;82;85 02:42:45 INFO - PROCESS | 1684 | |11;stanford-crypto-ccm;145;108;142;140;113;142;142;138;156;153 02:42:45 INFO - PROCESS | 1684 | |12;stanford-crypto-pbkdf2;167;166;167;164;174;167;164;164;165;210 02:42:45 INFO - PROCESS | 1684 | |13;stanford-crypto-sha256-iterative;67;65;67;71;75;92;91;92;77;66 02:42:45 INFO - PROCESS | 1684 | __end_tp_report 02:42:45 INFO - PROCESS | 1684 | __start_cc_report 02:42:45 INFO - PROCESS | 1684 | _x_x_mozilla_cycle_collect,1993 02:42:45 INFO - PROCESS | 1684 | __end_cc_report 02:42:45 INFO - PROCESS | 1684 | __startTimestamp1465119765338__endTimestamp 02:42:45 INFO - PROCESS | 1684 | 02:42:45 INFO - PROCESS | 1684 | ------- Summary: start ------- 02:42:45 INFO - PROCESS | 1684 | Number of tests: 14 02:42:45 INFO - PROCESS | 1684 | 02:42:45 INFO - PROCESS | 1684 | [#0] ai-astar Cycles:10 Average:115.50 Median:113.00 stddev:7.18 (6.4%) stddev-sans-first:7.56 02:42:45 INFO - PROCESS | 1684 | Values: 113.0 113.0 119.0 112.0 112.0 111.0 114.0 113.0 113.0 135.0 02:42:45 INFO - PROCESS | 1684 | 02:42:45 INFO - PROCESS | 1684 | [#1] audio-beat-detection Cycles:10 Average:145.70 Median:154.00 stddev:21.41 (13.9%) stddev-sans-first:22.38 02:42:45 INFO - PROCESS | 1684 | Values: 156.0 121.0 165.0 181.0 146.0 143.0 118.0 152.0 158.0 117.0 02:42:45 INFO - PROCESS | 1684 | 02:42:45 INFO - PROCESS | 1684 | [#2] audio-dft Cycles:10 Average:184.80 Median:185.00 stddev:1.69 (0.9%) stddev-sans-first:1.76 02:42:45 INFO - PROCESS | 1684 | Values: 184.0 188.0 187.0 185.0 185.0 185.0 183.0 185.0 183.0 183.0 02:42:45 INFO - PROCESS | 1684 | 02:42:45 INFO - PROCESS | 1684 | [#3] audio-fft Cycles:10 Average:78.70 Median:61.00 stddev:34.80 (57.1%) stddev-sans-first:36.77 02:42:45 INFO - PROCESS | 1684 | Values: 70.0 75.0 70.0 154.0 55.0 56.0 58.0 64.0 131.0 54.0 02:42:45 INFO - PROCESS | 1684 | 02:42:45 INFO - PROCESS | 1684 | [#4] audio-oscillator Cycles:10 Average:95.40 Median:78.00 stddev:24.03 (30.8%) stddev-sans-first:25.43 02:42:45 INFO - PROCESS | 1684 | Values: 91.0 89.0 138.0 76.0 79.0 78.0 125.0 78.0 76.0 124.0 02:42:45 INFO - PROCESS | 1684 | 02:42:45 INFO - PROCESS | 1684 | [#5] imaging-gaussian-blur Cycles:10 Average:89.60 Median:88.00 stddev:10.01 (11.4%) stddev-sans-first:10.62 02:42:45 INFO - PROCESS | 1684 | Values: 89.0 86.0 91.0 87.0 89.0 86.0 91.0 87.0 75.0 115.0 02:42:45 INFO - PROCESS | 1684 | 02:42:45 INFO - PROCESS | 1684 | [#6] imaging-darkroom Cycles:10 Average:100.10 Median:95.50 stddev:13.52 (14.2%) stddev-sans-first:14.16 02:42:45 INFO - PROCESS | 1684 | Values: 94.0 95.0 96.0 96.0 102.0 138.0 96.0 96.0 94.0 94.0 02:42:45 INFO - PROCESS | 1684 | 02:42:45 INFO - PROCESS | 1684 | [#7] imaging-desaturate Cycles:10 Average:85.00 Median:85.00 stddev:0.67 (0.8%) stddev-sans-first:0.60 02:42:45 INFO - PROCESS | 1684 | Values: 84.0 84.0 85.0 86.0 85.0 85.0 85.0 85.0 86.0 85.0 02:42:45 INFO - PROCESS | 1684 | 02:42:45 INFO - PROCESS | 1684 | [#8] json-parse-financial Cycles:10 Average:64.50 Median:64.00 stddev:7.46 (11.7%) stddev-sans-first:7.73 02:42:45 INFO - PROCESS | 1684 | Values: 60.0 64.0 64.0 64.0 65.0 64.0 65.0 84.0 58.0 57.0 02:42:45 INFO - PROCESS | 1684 | 02:42:45 INFO - PROCESS | 1684 | [#9] json-stringify-tinderbox Cycles:10 Average:68.70 Median:70.00 stddev:4.22 (6.0%) stddev-sans-first:3.94 02:42:45 INFO - PROCESS | 1684 | Values: 63.0 64.0 71.0 73.0 76.0 72.0 66.0 67.0 69.0 66.0 02:42:45 INFO - PROCESS | 1684 | 02:42:45 INFO - PROCESS | 1684 | [#10] stanford-crypto-aes Cycles:10 Average:80.40 Median:80.50 stddev:2.12 (2.6%) stddev-sans-first:2.24 02:42:45 INFO - PROCESS | 1684 | Values: 81.0 80.0 81.0 79.0 79.0 80.0 77.0 80.0 82.0 85.0 02:42:45 INFO - PROCESS | 1684 | 02:42:45 INFO - PROCESS | 1684 | [#11] stanford-crypto-ccm Cycles:10 Average:137.90 Median:142.00 stddev:15.55 (10.9%) stddev-sans-first:16.27 02:42:45 INFO - PROCESS | 1684 | Values: 145.0 108.0 142.0 140.0 113.0 142.0 142.0 138.0 156.0 153.0 02:42:45 INFO - PROCESS | 1684 | 02:42:45 INFO - PROCESS | 1684 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.80 Median:167.00 stddev:14.09 (8.4%) stddev-sans-first:14.87 02:42:45 INFO - PROCESS | 1684 | Values: 167.0 166.0 167.0 164.0 174.0 167.0 164.0 164.0 165.0 210.0 02:42:45 INFO - PROCESS | 1684 | 02:42:45 INFO - PROCESS | 1684 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.30 Median:76.00 stddev:11.28 (14.8%) stddev-sans-first:11.46 02:42:45 INFO - PROCESS | 1684 | Values: 67.0 65.0 67.0 71.0 75.0 92.0 91.0 92.0 77.0 66.0 02:42:45 INFO - PROCESS | 1684 | -------- Summary: end -------- 02:42:45 INFO - PROCESS | 1684 | 02:42:46 INFO - TEST-INFO | 1684: exit 0 02:42:46 INFO - TEST-OK | kraken | took 58672ms 02:42:46 INFO - SUITE-END | took 1174s 02:42:46 INFO - Completed test suite (00:19:34) 02:42:46 INFO - javascript benchmark 02:42:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3314.585676790401, 3382.2220889195482, 3392.474166504193, 3414.9317013659743, 3440.088376580205, 5606.971965140179, 5619.129034999576, 5628.38087810341, 5638.887222255553, 5645.633033852797, 5599.664020158793, 5617.4382561743805, 5630.746616402272, 5642.069058605335, 5643.6049476536655, 4976.825811096613, 5006.374203224597, 5104.8979020419565, 5134.1015322318635, 5175.585953123741, 5121.436641969381, 5124.436312005676, 5131.102057139986, 5131.3842338919385, 5144.09978253806, 5072.72100034498, 5129.666571672848, 5133.743312834362, 5137.614678899079, 5158.716270605115, 5079.746012699387, 5119.56483698886, 5124.600575271323, 5134.743262836881, 5177.275181474596, 4991.575716064139, 5012.8997420051755, 5070.518300761421, 5077.162268225738, 5089.847304580869, 5469.015577196104, 5495.313085864267, 5508.063629183049, 5509.063459211943, 5512.724363781835, 6099.085137229406, 6117.541184411187, 6124.142620033199, 6138.0, 6138.815835524942, 5994.13085102662, 6136.294326152505, 6137.202163718733, 6205.2553693557065, 6239.563230573862, 5669.177969194526, 5684.687342196219, 5697.0, 5697.886042279131, 5732.0, 6257.74969001244, 6303.936960630335, 6318.241810982711, 6328.525360597973, 6331.176946996861, 5808.7095645218415, 5924.851878703067, 5934.673592952343, 6007.038873780261, 6016.789412370634, 6101.60339577926, 6121.969390153021, 6122.367660829195, 6130.724117414727, 6133.509319254538, 6010.519158467312, 6107.083937409336, 6123.295820980555, 6127.234095738033, 6137.478314343331, 6077.17958075663, 6100.877982440326, 6117.959946809053, 6126.172966649446, 6143.877122457526, 4806.855794326176, 4822.348982887265, 4828.95171048285, 4837.612990960714, 4866.780994855169, 4212.052288235184, 4298.871033868989, 4336.566343365638, 4372.431583894074, 4410.448693913261, 5556.416576259516, 5584.441555844383, 5591.692456914909, 5595.440455954372, 5637.830865074055, 5996.220491336186, 6147.963375106421, 6172.043333283348, 6189.319174890758, 6206.813795586139, 5499.807506737245, 5517.36550296713, 5574.916376254359, 5577.6932268724795, 5588.0, 5269.920951185659, 5283.762230699628, 5286.762095705702, 5288.391834938954, 5292.417834038252, 6219.004959206505, 6287.528435367274, 6292.339304373067, 6297.370262973666, 6304.779332723333, 6124.938750612437, 6178.691065446799, 6188.690565471798, 6197.1324014638885, 6203.503719702503, 6270.874582508233, 6285.120083188449, 6302.432781049728, 6306.558540902093, 6308.053791931247, 5886.323072846678, 5940.435658612425, 5984.072468767348, 6047.637141771508, 6060.0, 6043.909341359971, 6055.2430946131735, 6067.787627433018, 6078.179445774764, 6082.878342433215, 5867.149263356875, 5872.119182122716, 5895.616784908967, 5969.910451343147, 5977.163197152489, 5165.793368265227, 5953.7023148843255, 5957.970210148921, 5959.642421454727, 5976.043832986701, 5754.482096611325, 5788.855278618068, 5788.884222315446, 5812.883742325045, 5818.214541037075, 5770.942290577041, 5869.0, 5900.675462849413, 5968.731407086779, 5974.9402505974385, 5838.970805145947, 5848.970755146197, 5858.765649373977, 5861.736221869941, 5862.944669959448], "value": 5596.338370598572, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [525.818592585558, 538.8733647592815, 539.8704310965368, 549.7031602934416, 582.2488989203927, 27572.58641120382, 31720.0, 31764.04707858762, 38500.42249366262, 39371.0, 42197.367039494435, 42561.57438425622, 42617.147657046844, 42794.572054279444, 42929.0, 9413.57638906251, 9638.421694698305, 9642.75893102671, 9646.903530964706, 9655.22758179344, 8554.52950087745, 8720.34597405194, 9459.716208513755, 11415.48630311638, 11612.36132012739, 11411.48648310828, 11470.942645286761, 11505.137114716388, 11507.194496385257, 11512.481938312796, 11307.0, 11406.600768973047, 11415.942920285346, 11421.143414243965, 11427.257228280218, 8539.701110461167, 8740.956295218546, 8746.34402419821, 8750.693725719633, 11327.490262938187, 38975.80512097421, 39407.43851649634, 39422.21155576872, 39450.21099578021, 39509.0, 3220.22714548509, 3253.544503769474, 3279.8852040178717, 3315.8507867146036, 3337.766356355056, 27034.0, 28314.71685283162, 28319.150425487267, 28640.0, 28677.713222867715, 2338.286822519131, 2712.715164907676, 2762.1575419497044, 2766.5711814668757, 2796.398774263543, 484701.0, 485505.0, 486063.0, 486528.0, 487791.0, 64962.0255696165, 65736.67131664311, 65895.0, 65989.70051497502, 66053.33946660568, 69907.60184796275, 70070.0, 70188.0, 70252.0, 70365.64817175882, 17294.0, 18075.36736214226, 18075.54811129732, 18141.820781649414, 18210.817891820912, 12496.687582810502, 12763.553275635308, 13783.862161378458, 13867.86132138686, 13914.304284785923, 18158.90920545389, 18273.725894111598, 18304.725429118575, 18322.541936451697, 18334.908325458553, 5288.915772266676, 6645.700943457556, 6693.732250709917, 6775.881979473332, 6917.654117294115, 847.4152834544145, 939.1641439119189, 949.2202121023082, 1028.3572767020612, 1107.6234080412537, 29231.853840731084, 29455.263618409284, 29835.850820746193, 29905.700942990727, 30614.38771224563, 950.9144177024101, 1095.4030053620795, 1100.4057808783327, 1184.7748927703708, 1224.7672942140964, 15159.696806063817, 15359.84801139919, 15442.922785386001, 15445.613859653598, 15877.444289449813, 567892.0, 571125.0, 572320.0, 572367.0, 574509.0, 59633.0, 61650.38349616536, 61792.0, 61823.0, 61887.0, 1362.8977826662842, 1391.4851504943235, 1399.510171439988, 1489.9180545069908, 1512.7201467728505, 28493.572596410657, 28838.567421489115, 30301.0, 30335.544966825073, 31130.844345778125, 43768.90577735455, 51503.74248128735, 52074.0, 52150.478495214564, 52234.69413264699, 436319.0, 438740.0, 440837.0, 443231.0, 457211.0, 37535.81232093822, 39011.219775603764, 39927.0, 39930.80034599808, 39975.0, 38468.0, 44508.66474005785, 45582.54417455916, 45587.0, 47015.529844701116, 37309.25381492301, 37852.0, 38174.37343452367, 38558.80720596379, 39318.2136357277, 35499.0, 36107.63892361043, 36386.90839274826, 36906.81546592357, 37231.88304350874, 35404.822975884956, 35969.28061438809, 36349.27301453903, 36649.63350366569, 36654.0, 31567.84216078905, 32130.678693213704, 32163.356732864744, 33877.322453551285, 34854.0, 24175.879120604284, 24190.75809241885, 24583.877080614482, 24654.63018054695, 24674.0], "value": 19981.351271517364, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.77856843031043, 381.91406933439964, 403.03473181729737, 404.3994667918142, 404.6418919256459, 19511.707324390147, 19902.701459478103, 20076.69884951727, 20508.87201203931, 20540.38378848635, 16831.91584042078, 20444.18223271071, 20939.581208375825, 21718.348449546498, 21754.912254387298, 5747.913781293263, 5835.79574714884, 5998.040313549833, 6041.395860413967, 6082.878342433149, 5971.940280597182, 5992.400759924017, 6009.399060094, 6016.939830601682, 6025.969870150665, 5565.2486914266465, 5870.618409803371, 5874.412558744113, 5893.38119497453, 5927.555433342495, 5816.709164541777, 5851.76592936282, 5871.9119213211625, 5884.823455296305, 5885.117232415129, 5574.052411090124, 5618.690971996539, 5704.401037891044, 5711.600187986843, 5718.5711071669375, 13273.0, 13716.314184290846, 13863.306834658226, 13905.79141312881, 13937.303134843218, 2132.3070002249174, 2148.237375731624, 2188.6060509108356, 2397.8681172535503, 2566.1788227767124, 9970.9501452493, 10059.730587559072, 10156.238282128796, 10358.741031474197, 14180.574582762534, 1716.373523663866, 1791.167107295114, 1793.6861049316449, 1821.9362322318655, 1889.2915156816193, 110400.0, 116873.99999999916, 119619.40190298992, 120453.59092818094, 122079.0, 9687.951560242154, 11533.538658453568, 11748.412579371168, 11836.585719499777, 11937.343446110548, 15191.392344306325, 15306.158161301013, 15353.850460935479, 15387.692246155015, 15418.691626167638, 8866.73399798007, 8880.955595222113, 8904.8664270036, 8914.465132091966, 9059.68291109808, 8937.0, 8952.283817294601, 8954.731358059269, 9033.457992520469, 9047.954760226157, 4673.906521869543, 4678.929816052762, 4682.929756053662, 4695.154872122982, 4749.881252968704, 1913.866029377958, 1986.0864002558696, 2007.708882212071, 2015.6472617292209, 2039.3677959832498, 772.7720322504874, 908.4276905549461, 1059.523214553454, 1071.844582535528, 1080.6487891435158, 19876.60246795056, 20122.597548048958, 20219.696704549442, 20302.898485507772, 20460.897695511427, 925.8194652042922, 940.7836197674574, 961.6009356117273, 962.6341990043738, 993.6969224386631, 10347.189241882641, 10725.78548429027, 10726.0, 10807.0, 10923.781524369628, 591321.0, 592207.0, 598601.0, 612372.0, 613829.0, 121540.39229803794, 125244.12133818, 125266.0, 126111.10833337507, 126295.73704263024, 1006.0291818395284, 1006.5621454667244, 1014.8274793285159, 1027.024326889458, 1028.6708253359002, 17585.560360991076, 17788.644227115645, 17822.821771782117, 18191.818081819543, 18339.71621986448, 53246.0, 53320.73339633432, 53846.0, 54891.725541372034, 56204.0, 77954.22045779697, 78269.60865195637, 78586.0, 79209.60395197987, 79439.60280198562, 8035.397345199251, 8078.959605202172, 8104.756857294291, 8291.046529649167, 8497.065322814362, 10463.68608941733, 12168.45241964096, 12393.132480726263, 12399.690007749878, 12537.937310313755, 10507.947460262956, 10773.407462589634, 10953.908256147537, 10983.066439352744, 11082.279651822606, 10175.643852465424, 10233.539490722786, 10452.634157804441, 10637.095846853104, 10991.780164396507, 10843.783124337628, 11050.613228536962, 11099.278546894426, 11113.277636953571, 11344.659660210207, 7977.960110199412, 7986.600669966362, 8055.597220138852, 8246.0, 8256.752297431087, 8093.757187284391, 9915.058069483388, 10337.397703355991, 11064.059554937598, 11066.225364224749], "value": 9447.368297709854, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1358.2325985818018, 1554.1063888264248, 1567.1850637668413, 1783.9197236124373, 1927.9228830846757, 7909.644066017028, 8616.483011019336, 8747.169018943207, 8754.0, 8782.25350845179, 7481.5885126318035, 8512.0, 8734.475931444118, 8746.737597872074, 8785.692500762474, 2159.4277516458073, 2403.531311394279, 2429.125514814665, 2466.3587467258462, 2467.6915385576804, 2484.5527804995095, 2492.7881130103965, 2494.6008638617823, 2495.7129930058004, 2496.151308555091, 2390.916317928873, 2412.662227288181, 2429.6841410616594, 2429.6962879640046, 2434.7078350597953, 2131.509752756859, 2384.141708984764, 2429.866357350345, 2434.9391265218424, 2435.537247922889, 2424.7211570669424, 2447.596146635803, 2451.399407145243, 2453.766892145243, 2455.484348286862, 8665.263452606536, 8723.60743766532, 8742.86885696715, 8752.562371881444, 8759.2992560595, 5137.691738495702, 5306.283651707009, 5306.867328316785, 5344.331958505187, 5371.409144994048, 3059.4492991261563, 3566.2154326048226, 3613.295407395559, 3629.383004889175, 3660.084978755311, 1046.6964580271713, 1051.2588625019343, 1064.2390690656218, 1605.8474444927713, 1654.8096968848615, 9804.46075465849, 10701.625443109453, 10855.457227138611, 11666.708332291675, 11672.182947193785, 4466.798994045275, 4558.407407037064, 4642.489326174118, 4673.906521869543, 4678.157931572349, 9179.862302065474, 9199.0, 9213.677521286723, 9225.0, 9235.12266334697, 5322.2282768998275, 5333.893322133536, 5363.758630861621, 5373.35519737634, 5389.9759045781175, 4069.409935559327, 4401.889952751143, 4479.888002799956, 4868.9269660955115, 4879.4876537963, 3276.0, 3762.774233545996, 3815.2369526094335, 3862.1310205203604, 3895.6883449323986, 1044.968650940473, 1188.6493484422117, 1191.0, 1194.235689158939, 1204.5422739359162, 842.8145807922248, 891.7993451473496, 902.2962089570145, 910.4537277633499, 922.8938672052666, 5078.187490001582, 5789.942100579025, 5963.98612235921, 5970.791022314198, 6120.622859856497, 468.62978247185026, 472.5534370020298, 472.75889296458365, 1006.8187726209353, 1026.779242462874, 3618.782873027627, 3662.61542538035, 3706.592274849764, 3719.1817800083936, 3723.478712980185, 10466.790664186674, 10773.676789696321, 10870.673879783619, 10878.075363594025, 10886.564537418415, 10631.989960953852, 10697.83953240702, 10708.12607793633, 10710.839337409945, 10717.571297148026, 8055.0, 8211.671533138728, 8486.533240671823, 8535.445196062235, 8550.529720865288, 5736.4550367715, 5838.678872662044, 5851.64890106595, 5855.23881895368, 5880.14737863016, 9403.717888463358, 9550.997145299922, 9702.369345992487, 9708.320417570701, 9820.852687209555, 9505.762355941157, 9788.510574471391, 9934.056264654846, 9955.900440995498, 10063.295569310078, 5822.272215973004, 6398.168238129014, 6597.769078082244, 7024.8595028098125, 7135.500514963903, 8235.13531079228, 8284.710035148983, 8350.206730360605, 8350.415470916978, 8351.95824020876, 7300.087489063867, 7429.442791790745, 7453.329200371993, 7536.397088233038, 7592.506487078323, 7328.670209840463, 7368.557886526825, 7384.52000619958, 7434.256574342522, 7453.142888567884, 7024.297570242935, 7043.0, 7069.399101076466, 7075.646217689198, 7089.964550177217, 4757.738324392191, 4790.473047964651, 4826.782794774173, 4898.2652602109965, 4902.7058376497525, 4831.275308703723, 4902.9019419610695, 4926.605871530341, 4952.8761780955765, 5080.796768129233], "value": 4511.828626495364, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [314.11106568411395, 320.1563879178366, 323.59388966846603, 328.91119397762606, 330.57686161713013, 6638.23660279068, 6684.899726504106, 6695.096162018126, 6720.327967203277, 6733.293004234552, 6619.7683081092055, 6626.834329141774, 6626.867462650756, 6649.002649602562, 6654.800355989328, 4638.559336863001, 4746.0, 4758.262469317261, 4758.381410416641, 4761.976190119045, 4739.549742774431, 4775.044991001813, 4817.181079216542, 4839.080574690815, 4874.610031197496, 4730.479647238801, 4797.304390863323, 4799.13615549201, 4821.059893320804, 4828.589569886564, 4633.050224703928, 4822.445418776851, 4828.203346447832, 4831.178699621073, 4837.153498137812, 4054.2702313583545, 4075.6128167824304, 4121.567235440296, 4136.276151673445, 4148.688848336386, 6316.147320111772, 6326.778562750282, 6331.493480521547, 6358.109864618957, 6384.297727249998, 5748.97125514374, 5768.134779783024, 5772.07646776518, 5777.913331300034, 5800.158976948319, 5432.483914028161, 6017.789377371771, 7067.363937245621, 7075.681594328216, 7091.6454177290925, 3885.3783394657003, 4361.542038085987, 4670.229412146991, 4695.530446955311, 4710.599599034086, 5954.851128721773, 7343.559386436831, 7454.776356709307, 7455.664495097733, 7462.291082347223, 4646.930296045494, 4660.1145782301255, 4662.787675204471, 4671.0, 4674.719516829001, 4737.644676649265, 4738.147133515931, 4741.881452963704, 4741.905161896742, 4747.620190384761, 5671.177679236487, 5675.1770993205755, 5678.205051292823, 5685.5167310778215, 5689.544836413077, 6856.657167141623, 7064.50548461613, 7069.257727938492, 7069.82325441868, 7087.574745515182, 1618.9271482783304, 1637.3696126991194, 1648.2912347690608, 1651.3229575873834, 1654.1894471708777, 1023.7747695506977, 1026.557686450538, 1046.016744260393, 1047.0681093826502, 1049.396596956753, 5875.23621929155, 6215.627062376272, 6319.778807741707, 6479.22249330074, 6573.342665733484, 7388.593627350548, 7393.667284972189, 7395.334419902127, 7396.371308438843, 7411.0, 6623.966880165568, 6630.834229144213, 6633.336666333425, 6648.601083934883, 6651.135352404156, 3834.405667121601, 3881.4760007399204, 3884.2231553689376, 3897.493325867619, 3899.883003509899, 6195.535334849904, 6278.6860656966965, 7375.741849035258, 7704.191059938737, 7745.457817952689, 7571.772846814604, 7587.317141457295, 7591.924080759121, 7599.886001709979, 7613.314801667876, 7664.0, 7690.0, 7690.307872291297, 7694.115176754744, 7705.691772329268, 6004.939950600438, 7362.263773622595, 7373.18894921569, 7374.373178279906, 7382.704691812267, 6952.235254121942, 7208.531445456028, 7225.494215404871, 7238.710451581877, 7268.309510596485, 7640.312371886557, 7693.769186924401, 7695.422843286664, 7704.845903081795, 7718.459707820399, 4657.534246575316, 4665.300204969146, 4668.416447944007, 4669.39297891272, 4676.462206846256, 6373.235211774431, 6984.3364880336285, 7342.6695798688115, 7348.77953661391, 7387.667554959932, 6322.083297921684, 6569.310222426577, 6854.897176542256, 7079.9646001771725, 7102.18324892644, 7039.788806335818, 7057.788266352018, 7079.539829911039, 7083.008378826866, 7083.327083927019, 6905.792826215222, 6913.446924246149, 6919.169699636076, 6932.61870597122, 6951.540176562979, 5968.4926781224085, 6934.202566704894, 7085.929140708733, 7097.929020709933, 7099.929000709927, 6619.66901654925, 6641.402273795382, 6936.653167341714, 6941.271166527442, 6972.825679358057], "value": 5124.455782750564, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [692.141744237146, 739.2053542441877, 747.6075060593191, 750.5923109084755, 796.9003874515686, 14770.630734231623, 14910.030847994887, 15265.771013434793, 15317.693646127085, 15358.616034599141, 13855.515056973009, 13952.44190232392, 13958.0, 13975.580732578013, 13996.230207338593, 731.3162193349209, 748.5994992678918, 861.5046348349692, 909.5997760985179, 939.0374865762614, 923.8706581078654, 927.9721608351726, 934.8971613122551, 935.434062392253, 935.6163972771166, 980.7989362180735, 985.3102828020378, 985.9309848310621, 987.0918754745651, 988.9912289464742, 761.3224230434889, 780.8165081205912, 780.8321210939675, 781.917898620648, 951.7430293820698, 710.4813486155084, 713.2012146396017, 713.350850725837, 713.7216485570632, 714.2821464428216, 14775.556733298019, 14852.925735371362, 14861.92569037148, 14879.0, 14896.478623248135, 11051.502682379312, 12112.39438028095, 12591.118621696485, 12733.172343797623, 12905.419256133402, 10049.246306526966, 10193.133583645398, 10305.278630495868, 10345.63790267337, 11151.38667373294, 3473.722102231841, 3506.6318036606044, 3550.5384300041087, 3617.8733744319084, 3630.963690363089, 76306.0, 77048.61475692586, 77216.22783772147, 80899.5955020227, 81325.0, 9443.527823608792, 9641.758956026015, 9651.758706032266, 9774.657886973922, 9864.950675246579, 22623.773762262168, 22673.659895101588, 22742.65886011711, 22872.199473018365, 22908.88545557295, 7229.132504099542, 7470.2903224193615, 7510.4742668013805, 7586.848263034708, 7604.429667774939, 4021.2962731521866, 4029.81865816039, 4032.1330913853076, 4035.3745169498775, 4036.0918793271867, 3490.110021944388, 3545.858165673344, 3573.3746594346408, 3573.428251479803, 3579.0, 637.5377851057946, 642.5502148496072, 647.4043879630751, 659.9109120268798, 670.3598063849013, 522.5323335614604, 541.8374487653688, 604.9364816694272, 606.4117805728437, 613.0773186354508, 7731.458797884206, 7848.293653571206, 7934.682612695427, 8088.0, 8224.300934420522, 582.0628787651877, 587.6767777722321, 588.2470437839572, 589.675678376891, 608.8660494691161, 3660.194757153422, 3782.6028267031566, 3800.315943130208, 3820.8853734388013, 3848.8845334640005, 83603.0, 85012.0, 86305.70541441883, 86518.13481865101, 86752.0, 52123.47876521186, 63249.0, 65297.673511632136, 66778.6644267112, 67623.32376676268, 59512.70243648754, 59547.0, 59801.40198597958, 60030.99999999913, 60050.59797608047, 7460.368974211835, 7583.696652133853, 7587.544747315179, 7628.0, 7643.694252229849, 46136.76931615321, 46274.768626156656, 46449.76775116238, 46831.48763842657, 47128.52871471241, 60099.0, 63780.681096596076, 63865.7226855451, 64839.67580162069, 65026.0, 9993.700188994342, 10193.235507337127, 10210.948945255226, 10211.540480678239, 10531.21015923824, 10433.53049112807, 10516.631917882838, 10709.625163119254, 11038.392888391216, 11150.888491115309, 10247.282690211614, 10739.624113156002, 11093.778124437627, 11245.550177992789, 11437.970582647602, 10647.148228141576, 10745.925407459505, 11157.609483668033, 11158.218924675195, 11488.19582629208, 10646.254762166884, 10770.784584308114, 11162.49768760424, 11196.104311654884, 11209.215354925076, 8862.778430539289, 9332.81334373295, 9412.764680883032, 9616.51917404113, 9749.610015599581, 9053.77365565866, 9458.574364153767, 9495.24038076966, 9559.091886270793, 9579.425234485952], "value": 6702.898053885256, "unit": "score"}], "name": "dromaeo_css", "value": 7396.454168556304, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 119.0, 112.0, 112.0, 111.0, 114.0, 113.0, 113.0, 135.0], "value": 115.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 121.0, 165.0, 181.0, 146.0, 143.0, 118.0, 152.0, 158.0, 117.0], "value": 145.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 188.0, 187.0, 185.0, 185.0, 185.0, 183.0, 185.0, 183.0, 183.0], "value": 184.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 75.0, 70.0, 154.0, 55.0, 56.0, 58.0, 64.0, 131.0, 54.0], "value": 78.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 89.0, 138.0, 76.0, 79.0, 78.0, 125.0, 78.0, 76.0, 124.0], "value": 95.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 91.0, 87.0, 89.0, 86.0, 91.0, 87.0, 75.0, 115.0], "value": 89.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 96.0, 96.0, 102.0, 138.0, 96.0, 96.0, 94.0, 94.0], "value": 100.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 85.0, 86.0, 85.0, 85.0, 85.0, 85.0, 86.0, 85.0], "value": 85.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 64.0, 64.0, 64.0, 65.0, 64.0, 65.0, 84.0, 58.0, 57.0], "value": 64.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 71.0, 73.0, 76.0, 72.0, 66.0, 67.0, 69.0, 66.0], "value": 68.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 80.0, 81.0, 79.0, 79.0, 80.0, 77.0, 80.0, 82.0, 85.0], "value": 80.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 108.0, 142.0, 140.0, 113.0, 142.0, 142.0, 138.0, 156.0, 153.0], "value": 137.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 166.0, 167.0, 164.0, 174.0, 167.0, 164.0, 164.0, 165.0, 210.0], "value": 170.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 67.0, 71.0, 75.0, 92.0, 91.0, 92.0, 77.0, 66.0], "value": 76.3, "unit": "score"}], "name": "kraken", "value": 1493.4, "alertThreshold": 2.0}]} 02:42:46 INFO - Return code: 0 02:42:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 02:42:46 ERROR - # TBPL SUCCESS # 02:42:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:42:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:42:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:42:46 INFO - 2016-06-05 02:42:46.676 screenresolution[1688:10317] starting screenresolution argv=screenresolution get 02:42:46 INFO - 2016-06-05 02:42:46.679 screenresolution[1688:10317] Display 0: 1600x1200x32@60 02:42:46 INFO - 2016-06-05 02:42:46.685 screenresolution[1689:10320] starting screenresolution argv=screenresolution list 02:42:46 INFO - Available Modes on Display 0 02:42:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:42:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:42:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:42:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:42:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:42:46 INFO - Intel Iris: 02:42:46 INFO - Chipset Model: Intel Iris 02:42:46 INFO - Type: GPU 02:42:46 INFO - Bus: Built-In 02:42:46 INFO - VRAM (Dynamic, Max): 1536 MB 02:42:46 INFO - Vendor: Intel (0x8086) 02:42:46 INFO - Device ID: 0x0a2e 02:42:46 INFO - Revision ID: 0x0009 02:42:46 INFO - Displays: 02:42:46 INFO - Display: 02:42:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:42:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:42:46 INFO - Display Serial Number: 1600x1200 60 02:42:46 INFO - Main Display: Yes 02:42:46 INFO - Mirror: Off 02:42:46 INFO - Online: Yes 02:42:46 INFO - Rotation: Supported 02:42:46 INFO - Return code: 0 02:42:46 INFO - Running post-action listener: _resource_record_post_action 02:42:46 INFO - Running post-run listener: _resource_record_post_run 02:42:47 INFO - Total resource usage - Wall time: 1208s; CPU: 52.0%; Read bytes: 31677952; Write bytes: 654796288; Read time: 196; Write time: 1837 02:42:47 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 353284608; Write bytes: 347904000; Read time: 27517; Write time: 705 02:42:48 INFO - run-tests - Wall time: 1177s; CPU: 52.0%; Read bytes: 29888000; Write bytes: 297143808; Read time: 180; Write time: 1099 02:42:48 INFO - Running post-run listener: _upload_blobber_files 02:42:48 INFO - Blob upload gear active. 02:42:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:42:48 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 02:42:48 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:42:48 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:42:48 INFO - (blobuploader) - INFO - Open directory for files ... 02:42:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:42:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:42:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:42:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:42:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:42:50 INFO - (blobuploader) - INFO - Done attempting. 02:42:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:42:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:42:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:42:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:42:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:42:51 INFO - (blobuploader) - INFO - Done attempting. 02:42:51 INFO - (blobuploader) - INFO - Iteration through files over. 02:42:51 INFO - Return code: 0 02:42:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:42:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:42:51 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6437265560224364a6c9f43abd44c3c926b400751855056c06d99a5ebf40490191860a9306dccea7e3612d08db11234f80b7fda190204dbf6e6de90ca0bff498", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/422338ad2ba5e774ed584c99dfbe5a1337f1290e4935f06da0ddfc33b459e3bfbc55c605c0bf2b217e370477b6db3a61cb73e567e9901e31ac7fb70a59e27eea"} 02:42:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:42:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:42:51 INFO - Contents: 02:42:51 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6437265560224364a6c9f43abd44c3c926b400751855056c06d99a5ebf40490191860a9306dccea7e3612d08db11234f80b7fda190204dbf6e6de90ca0bff498", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/422338ad2ba5e774ed584c99dfbe5a1337f1290e4935f06da0ddfc33b459e3bfbc55c605c0bf2b217e370477b6db3a61cb73e567e9901e31ac7fb70a59e27eea"} 02:42:51 INFO - Running post-run listener: copy_logs_to_upload_dir 02:42:51 INFO - Copying logs to upload dir... 02:42:51 INFO - mkdir: /builds/slave/test/build/upload/logs 02:42:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1357.773332 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 37 secs) (at 2016-06-05 02:42:51.129039) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-05 02:42:51.132941) ========= 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.vW4o3gwK5I/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.IdP3E3HlJY/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6437265560224364a6c9f43abd44c3c926b400751855056c06d99a5ebf40490191860a9306dccea7e3612d08db11234f80b7fda190204dbf6e6de90ca0bff498", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/422338ad2ba5e774ed584c99dfbe5a1337f1290e4935f06da0ddfc33b459e3bfbc55c605c0bf2b217e370477b6db3a61cb73e567e9901e31ac7fb70a59e27eea"} build_url:https://queue.taskcluster.net/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011217 build_url: 'https://queue.taskcluster.net/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6437265560224364a6c9f43abd44c3c926b400751855056c06d99a5ebf40490191860a9306dccea7e3612d08db11234f80b7fda190204dbf6e6de90ca0bff498", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/422338ad2ba5e774ed584c99dfbe5a1337f1290e4935f06da0ddfc33b459e3bfbc55c605c0bf2b217e370477b6db3a61cb73e567e9901e31ac7fb70a59e27eea"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-05 02:42:51.168556) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-05 02:42:51.169250) ========= 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.vW4o3gwK5I/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.IdP3E3HlJY/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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.005132 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-05 02:42:51.221834) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-05 02:42:51.222499) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-05 02:42:51.223281) ========= ========= Total master_lag: 0.27 =========