builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0306 starttime: 1464651733.76 results: success (0) buildid: 20160526112438 builduid: 7b7803f5dee74ad79ba6e8f098a8fa00 revision: 417e1ec42d8f68c55e52c336cdf81e83323db88c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 16:42:13.758129) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 16:42:13.758546) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 16:42:13.758869) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 16:42:14.192785) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 16:42:14.193072) ========= 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.xGdUDAfZFX/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.wEzhRjUDZN/Listeners TMPDIR=/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-30 16:42:14-- 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.3M=0.001s 2016-05-30 16:42:14 (19.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.590770 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 16:42:14.854010) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 16:42:14.854314) ========= 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.xGdUDAfZFX/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.wEzhRjUDZN/Listeners TMPDIR=/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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.025415 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 16:42:14.913590) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-30 16:42:14.914025) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 417e1ec42d8f68c55e52c336cdf81e83323db88c --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-beta --rev 417e1ec42d8f68c55e52c336cdf81e83323db88c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xGdUDAfZFX/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.wEzhRjUDZN/Listeners TMPDIR=/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-30 16:42:14,988 truncating revision to first 12 chars 2016-05-30 16:42:14,988 Setting DEBUG logging. 2016-05-30 16:42:14,988 attempt 1/10 2016-05-30 16:42:14,988 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/417e1ec42d8f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-30 16:42:16,086 unpacking tar archive at: mozilla-beta-417e1ec42d8f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.390268 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-30 16:42:16.325771) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 16:42:16.326130) ========= script_repo_revision: 417e1ec42d8f68c55e52c336cdf81e83323db88c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 16:42:16.326596) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 16:42:16.326928) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 16:42:16.343362) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 31 secs) (at 2016-05-30 16:42:16.343691) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xGdUDAfZFX/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.wEzhRjUDZN/Listeners TMPDIR=/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 16:42:16 INFO - MultiFileLogger online at 20160530 16:42:16 in /builds/slave/test 16:42:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 16:42:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:42:16 INFO - {'append_to_log': False, 16:42:16 INFO - 'base_work_dir': '/builds/slave/test', 16:42:16 INFO - 'blob_upload_branch': 'Mozilla-Beta', 16:42:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:42:16 INFO - 'branch': 'Mozilla-Beta', 16:42:16 INFO - 'buildbot_json_path': 'buildprops.json', 16:42:16 INFO - 'config_files': ('talos/mac_config.py',), 16:42:16 INFO - 'default_actions': ('clobber', 16:42:16 INFO - 'read-buildbot-config', 16:42:16 INFO - 'download-and-extract', 16:42:16 INFO - 'populate-webroot', 16:42:16 INFO - 'create-virtualenv', 16:42:16 INFO - 'install', 16:42:16 INFO - 'run-tests'), 16:42:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:42:16 INFO - 'download_minidump_stackwalk': True, 16:42:16 INFO - 'download_symbols': 'ondemand', 16:42:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:42:16 INFO - 'tooltool.py': '/tools/tooltool.py', 16:42:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:42:16 INFO - '/tools/misc-python/virtualenv.py')}, 16:42:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:42:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:42:16 INFO - 'installer_path': 'installer.exe', 16:42:16 INFO - 'log_level': 'info', 16:42:16 INFO - 'log_name': 'talos', 16:42:16 INFO - 'log_to_console': True, 16:42:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:42:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:42:16 INFO - 'opt_config_files': (), 16:42:16 INFO - 'pip_index': False, 16:42:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:42:16 INFO - 'cmd': ('bash', 16:42:16 INFO - '-c', 16:42:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:42:16 INFO - 'enabled': True, 16:42:16 INFO - 'halt_on_failure': False, 16:42:16 INFO - 'name': 'check_screen_resolution'},), 16:42:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:42:16 INFO - 'cmd': ('bash', 16:42:16 INFO - '-c', 16:42:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:42:16 INFO - 'enabled': True, 16:42:16 INFO - 'halt_on_failure': False, 16:42:16 INFO - 'name': 'check_screen_resolution'},), 16:42:16 INFO - 'run_cmd_checks_enabled': True, 16:42:16 INFO - 'sps_profile': False, 16:42:16 INFO - 'sps_profile_interval': 0, 16:42:16 INFO - 'suite': 'dromaeojs-e10s', 16:42:16 INFO - 'system_bits': '32', 16:42:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:42:16 INFO - 'title': 't-yosemite-r7-0306', 16:42:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:42:16 INFO - 'use_talos_json': True, 16:42:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:42:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:42:16 INFO - 'work_dir': 'build'} 16:42:16 INFO - ##### 16:42:16 INFO - ##### Running clobber step. 16:42:16 INFO - ##### 16:42:16 INFO - Running pre-action listener: _resource_record_pre_action 16:42:16 INFO - Running main action method: clobber 16:42:16 INFO - rmtree: /builds/slave/test/build 16:42:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:42:18 INFO - Running post-action listener: _resource_record_post_action 16:42:18 INFO - ##### 16:42:18 INFO - ##### Running read-buildbot-config step. 16:42:18 INFO - ##### 16:42:18 INFO - Running pre-action listener: _resource_record_pre_action 16:42:18 INFO - Running main action method: read_buildbot_config 16:42:18 INFO - Using buildbot properties: 16:42:18 INFO - { 16:42:18 INFO - "project": "", 16:42:18 INFO - "product": "firefox", 16:42:18 INFO - "script_repo_revision": "production", 16:42:18 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 16:42:18 INFO - "repository": "", 16:42:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 16:42:18 INFO - "buildid": "20160526112438", 16:42:18 INFO - "pgo_build": "False", 16:42:18 INFO - "basedir": "/builds/slave/test", 16:42:18 INFO - "buildnumber": 29, 16:42:18 INFO - "slavename": "t-yosemite-r7-0306", 16:42:18 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 16:42:18 INFO - "platform": "yosemite_r7", 16:42:18 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 16:42:18 INFO - "branch": "mozilla-beta", 16:42:18 INFO - "repo_path": "releases/mozilla-beta", 16:42:18 INFO - "revision": "417e1ec42d8f68c55e52c336cdf81e83323db88c", 16:42:18 INFO - "stage_platform": "macosx64", 16:42:18 INFO - "builduid": "7b7803f5dee74ad79ba6e8f098a8fa00", 16:42:18 INFO - "slavebuilddir": "test" 16:42:18 INFO - } 16:42:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.dmg. 16:42:18 INFO - Running post-action listener: _resource_record_post_action 16:42:18 INFO - ##### 16:42:18 INFO - ##### Running download-and-extract step. 16:42:18 INFO - ##### 16:42:18 INFO - Running pre-action listener: _resource_record_pre_action 16:42:18 INFO - Running main action method: download_and_extract 16:42:18 INFO - mkdir: /builds/slave/test/build/tests 16:42:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:42:18 INFO - https://queue.taskcluster.net/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 16:42:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 16:42:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 16:42:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json 16:42:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json'}, attempt #1 16:42:30 INFO - retry: attempt #1 caught exception: '' 16:42:30 INFO - retry: Failed, sleeping 30 seconds before retrying 16:43:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json'}, attempt #2 16:43:01 INFO - Downloaded 1241 bytes. 16:43:01 INFO - Reading from file /builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json 16:43:01 INFO - Using the following test package requirements: 16:43:01 INFO - {u'common': [u'firefox-47.0.en-US.mac.common.tests.zip'], 16:43:01 INFO - u'cppunittest': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:43:01 INFO - u'firefox-47.0.en-US.mac.cppunittest.tests.zip'], 16:43:01 INFO - u'gtest': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:43:01 INFO - u'firefox-47.0.en-US.mac.gtest.tests.zip'], 16:43:01 INFO - u'jittest': [u'firefox-47.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 16:43:01 INFO - u'mochitest': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:43:01 INFO - u'firefox-47.0.en-US.mac.mochitest.tests.zip'], 16:43:01 INFO - u'mozbase': [u'firefox-47.0.en-US.mac.common.tests.zip'], 16:43:01 INFO - u'reftest': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:43:01 INFO - u'firefox-47.0.en-US.mac.reftest.tests.zip'], 16:43:01 INFO - u'talos': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:43:01 INFO - u'firefox-47.0.en-US.mac.talos.tests.zip'], 16:43:01 INFO - u'web-platform': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:43:01 INFO - u'firefox-47.0.en-US.mac.web-platform.tests.zip'], 16:43:01 INFO - u'webapprt': [u'firefox-47.0.en-US.mac.common.tests.zip'], 16:43:01 INFO - u'xpcshell': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:43:01 INFO - u'firefox-47.0.en-US.mac.xpcshell.tests.zip']} 16:43:01 INFO - Downloading packages: [u'firefox-47.0.en-US.mac.common.tests.zip'] for test suite category: common 16:43:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:43:01 INFO - https://queue.taskcluster.net/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:43:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 16:43:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 16:43:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip 16:43:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip'}, attempt #1 16:43:31 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip: timed out 16:43:31 INFO - retry: attempt #1 caught exception: timed out 16:43:31 INFO - retry: Failed, sleeping 30 seconds before retrying 16:44:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip'}, attempt #2 16:44:02 INFO - Downloaded 17816939 bytes. 16:44:02 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:44:02 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:44:05 INFO - Return code: 0 16:44:05 INFO - Downloading packages: [u'firefox-47.0.en-US.mac.common.tests.zip', u'firefox-47.0.en-US.mac.talos.tests.zip'] for test suite category: talos 16:44:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:44:05 INFO - https://queue.taskcluster.net/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:44:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 16:44:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 16:44:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip 16:44:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip'}, attempt #1 16:44:08 INFO - Downloaded 17816939 bytes. 16:44:08 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:44:08 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:44:11 INFO - Return code: 0 16:44:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:44:11 INFO - https://queue.taskcluster.net/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 16:44:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 16:44:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 16:44:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip 16:44:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip'}, attempt #1 16:44:12 INFO - Downloaded 10845194 bytes. 16:44:12 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:44:12 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 16:44:13 INFO - Return code: 0 16:44:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:44:13 INFO - https://queue.taskcluster.net/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.dmg matches https://queue.taskcluster.net 16:44:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.dmg 16:44:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.dmg 16:44:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0.en-US.mac.dmg 16:44:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.dmg'}, attempt #1 16:44:15 INFO - Downloaded 88467627 bytes. 16:44:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.dmg 16:44:15 INFO - mkdir: /builds/slave/test/properties 16:44:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:44:15 INFO - Writing to file /builds/slave/test/properties/build_url 16:44:15 INFO - Contents: 16:44:15 INFO - build_url:https://queue.taskcluster.net/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.dmg 16:44:16 INFO - Running post-action listener: _resource_record_post_action 16:44:16 INFO - Running post-action listener: set_extra_try_arguments 16:44:16 INFO - ##### 16:44:16 INFO - ##### Running populate-webroot step. 16:44:16 INFO - ##### 16:44:16 INFO - Running pre-action listener: _resource_record_pre_action 16:44:16 INFO - Running main action method: populate_webroot 16:44:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:44:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:44:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:44:16 INFO - '--tppagecycles', 16:44:16 INFO - '7'], 16:44:16 INFO - 'tests': ['tsvgm']}}, 16:44:16 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 16:44:16 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 16:44:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:44:16 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 16:44:16 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 16:44:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:44:16 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:44:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:44:16 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:44:16 INFO - 'talos_options': ['--e10s'], 16:44:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:44:16 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:44:16 INFO - 'tests': ['damp', 'tps']}, 16:44:16 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:44:16 INFO - 'talos_options': ['--e10s'], 16:44:16 INFO - 'tests': ['damp', 'tps']}, 16:44:16 INFO - 'g3': {'tests': ['dromaeo_dom']}, 16:44:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:44:16 INFO - 'other': {'tests': ['a11yr', 16:44:16 INFO - 'ts_paint', 16:44:16 INFO - 'tpaint', 16:44:16 INFO - 'sessionrestore', 16:44:16 INFO - 'sessionrestore_no_auto_restore', 16:44:16 INFO - 'tabpaint']}, 16:44:16 INFO - 'other-e10s': {'talos_options': ['--e10s'], 16:44:16 INFO - 'tests': ['a11yr', 16:44:16 INFO - 'ts_paint', 16:44:16 INFO - 'tpaint', 16:44:16 INFO - 'sessionrestore', 16:44:16 INFO - 'sessionrestore_no_auto_restore', 16:44:16 INFO - 'tabpaint']}, 16:44:16 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 16:44:16 INFO - 'tests': ['a11yr', 16:44:16 INFO - 'ts_paint', 16:44:16 INFO - 'tpaint', 16:44:16 INFO - 'sessionrestore', 16:44:16 INFO - 'sessionrestore_no_auto_restore', 16:44:16 INFO - 'tabpaint']}, 16:44:16 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 16:44:16 INFO - 'tests': ['a11yr', 16:44:16 INFO - 'ts_paint', 16:44:16 INFO - 'tpaint', 16:44:16 INFO - 'sessionrestore', 16:44:16 INFO - 'sessionrestore_no_auto_restore', 16:44:16 INFO - 'tabpaint']}, 16:44:16 INFO - 'other_l64': {'tests': ['a11yr', 16:44:16 INFO - 'ts_paint', 16:44:16 INFO - 'tpaint', 16:44:16 INFO - 'sessionrestore', 16:44:16 INFO - 'sessionrestore_no_auto_restore', 16:44:16 INFO - 'tabpaint']}, 16:44:16 INFO - 'other_nol64': {'tests': ['a11yr', 16:44:16 INFO - 'ts_paint', 16:44:16 INFO - 'tpaint', 16:44:16 INFO - 'sessionrestore', 16:44:16 INFO - 'sessionrestore_no_auto_restore', 16:44:16 INFO - 'tabpaint']}, 16:44:16 INFO - 'svgr': {'tests': ['tsvgx', 16:44:16 INFO - 'tsvgr_opacity', 16:44:16 INFO - 'tart', 16:44:16 INFO - 'tscrollx', 16:44:16 INFO - 'cart']}, 16:44:16 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 16:44:16 INFO - 'tests': ['tsvgx', 16:44:16 INFO - 'tsvgr_opacity', 16:44:16 INFO - 'tart', 16:44:16 INFO - 'tscrollx', 16:44:16 INFO - 'cart']}, 16:44:16 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:44:16 INFO - 'tests': ['tp5o']}, 16:44:16 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:44:16 INFO - 'talos_options': ['--e10s'], 16:44:16 INFO - 'tests': ['tp5o']}, 16:44:16 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:44:16 INFO - 'talos_options': ['--xperf_path', 16:44:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:44:16 INFO - 'tests': ['tp5n']}, 16:44:16 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:44:16 INFO - 'talos_options': ['--e10s', 16:44:16 INFO - '--xperf_path', 16:44:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:44:16 INFO - 'tests': ['tp5n']}}, 16:44:16 INFO - 'talos.zip': {'path': '', 16:44:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:44:16 INFO - Running post-action listener: _resource_record_post_action 16:44:16 INFO - ##### 16:44:16 INFO - ##### Running create-virtualenv step. 16:44:16 INFO - ##### 16:44:16 INFO - Running pre-action listener: _resource_record_pre_action 16:44:16 INFO - Running main action method: create_virtualenv 16:44:16 INFO - Creating virtualenv /builds/slave/test/build/venv 16:44:16 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:44:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:44:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:44:16 INFO - Using real prefix '/tools/python27' 16:44:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:44:17 INFO - Installing distribute.............................................................................................................................................................................................done. 16:44:20 INFO - Installing pip.................done. 16:44:20 INFO - Return code: 0 16:44:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:44:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:44:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:44:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109818a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10981f4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe301d85450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096bcd30>, '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 0x109520db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096b3b20>, '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.xGdUDAfZFX/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.wEzhRjUDZN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:44:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:44:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:44:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xGdUDAfZFX/Render', 16:44:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:44:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:44:20 INFO - 'HOME': '/Users/cltbld', 16:44:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:44:20 INFO - 'LOGNAME': 'cltbld', 16:44:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:20 INFO - 'MOZ_NO_REMOTE': '1', 16:44:20 INFO - 'NO_EM_RESTART': '1', 16:44:20 INFO - 'PAGER': '/bin/cat', 16:44:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:44:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:20 INFO - 'PWD': '/builds/slave/test', 16:44:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:44:20 INFO - 'SHELL': '/bin/bash', 16:44:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wEzhRjUDZN/Listeners', 16:44:20 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 16:44:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:44:20 INFO - 'USER': 'cltbld', 16:44:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:44:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:44:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:20 INFO - 'XPC_FLAGS': '0x0', 16:44:20 INFO - 'XPC_SERVICE_NAME': '0', 16:44:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:44:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:20 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 16:44:20 INFO - Cleaning up... 16:44:20 INFO - Return code: 0 16:44:20 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 16:44:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:44:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:44:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109818a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10981f4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe301d85450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096bcd30>, '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 0x109520db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096b3b20>, '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.xGdUDAfZFX/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.wEzhRjUDZN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:44:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 16:44:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 16:44:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xGdUDAfZFX/Render', 16:44:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:44:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:44:20 INFO - 'HOME': '/Users/cltbld', 16:44:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:44:20 INFO - 'LOGNAME': 'cltbld', 16:44:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:20 INFO - 'MOZ_NO_REMOTE': '1', 16:44:20 INFO - 'NO_EM_RESTART': '1', 16:44:20 INFO - 'PAGER': '/bin/cat', 16:44:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:44:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:20 INFO - 'PWD': '/builds/slave/test', 16:44:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:44:20 INFO - 'SHELL': '/bin/bash', 16:44:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wEzhRjUDZN/Listeners', 16:44:20 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 16:44:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:44:20 INFO - 'USER': 'cltbld', 16:44:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:44:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:44:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:20 INFO - 'XPC_FLAGS': '0x0', 16:44:20 INFO - 'XPC_SERVICE_NAME': '0', 16:44:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:44:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:20 INFO - Downloading/unpacking psutil>=0.7.1 16:44:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:44:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:44:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:44:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:44:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:44:24 INFO - Installing collected packages: psutil 16:44:24 INFO - Running setup.py install for psutil 16:44:24 INFO - building 'psutil._psutil_osx' extension 16:44:24 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 16:44:24 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 16:44:24 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 16:44:25 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 16:44:25 INFO - building 'psutil._psutil_posix' extension 16:44:25 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 16:44:25 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:44:25 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:44:25 INFO - ^ 16:44:25 INFO - 1 warning generated. 16:44:25 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 16:44:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:44:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:44:25 INFO - Successfully installed psutil 16:44:25 INFO - Cleaning up... 16:44:25 INFO - Return code: 0 16:44:25 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 16:44:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:44:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:44:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:25 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 0x109818a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10981f4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe301d85450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096bcd30>, '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 0x109520db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096b3b20>, '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.xGdUDAfZFX/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.wEzhRjUDZN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:44:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 16:44:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 16:44:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xGdUDAfZFX/Render', 16:44:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:44:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:44:25 INFO - 'HOME': '/Users/cltbld', 16:44:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:44:25 INFO - 'LOGNAME': 'cltbld', 16:44:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:25 INFO - 'MOZ_NO_REMOTE': '1', 16:44:25 INFO - 'NO_EM_RESTART': '1', 16:44:25 INFO - 'PAGER': '/bin/cat', 16:44:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:44:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:25 INFO - 'PWD': '/builds/slave/test', 16:44:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:44:25 INFO - 'SHELL': '/bin/bash', 16:44:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wEzhRjUDZN/Listeners', 16:44:25 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 16:44:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:44:25 INFO - 'USER': 'cltbld', 16:44:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:44:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:44:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:25 INFO - 'XPC_FLAGS': '0x0', 16:44:25 INFO - 'XPC_SERVICE_NAME': '0', 16:44:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:44:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:25 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 16:44:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:44:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:44:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:28 INFO - Downloading mozsystemmonitor-0.0.tar.gz 16:44:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:44:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 16:44:28 INFO - Installing collected packages: mozsystemmonitor 16:44:28 INFO - Running setup.py install for mozsystemmonitor 16:44:28 INFO - Successfully installed mozsystemmonitor 16:44:28 INFO - Cleaning up... 16:44:28 INFO - Return code: 0 16:44:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:44:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:44:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:44:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:28 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 0x109818a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10981f4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe301d85450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096bcd30>, '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 0x109520db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096b3b20>, '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.xGdUDAfZFX/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.wEzhRjUDZN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:44:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:44:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:44:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xGdUDAfZFX/Render', 16:44:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:44:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:44:28 INFO - 'HOME': '/Users/cltbld', 16:44:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:44:28 INFO - 'LOGNAME': 'cltbld', 16:44:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:28 INFO - 'MOZ_NO_REMOTE': '1', 16:44:28 INFO - 'NO_EM_RESTART': '1', 16:44:28 INFO - 'PAGER': '/bin/cat', 16:44:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:44:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:28 INFO - 'PWD': '/builds/slave/test', 16:44:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:44:28 INFO - 'SHELL': '/bin/bash', 16:44:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wEzhRjUDZN/Listeners', 16:44:28 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 16:44:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:44:28 INFO - 'USER': 'cltbld', 16:44:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:44:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:44:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:28 INFO - 'XPC_FLAGS': '0x0', 16:44:28 INFO - 'XPC_SERVICE_NAME': '0', 16:44:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:44:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:28 INFO - Downloading/unpacking blobuploader==1.2.4 16:44:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:44:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:44:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:31 INFO - Downloading blobuploader-1.2.4.tar.gz 16:44:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:44:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:44:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:44:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:44:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:44:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:44:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:44:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:44:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:32 INFO - Downloading docopt-0.6.1.tar.gz 16:44:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:44:32 INFO - Installing collected packages: blobuploader, requests, docopt 16:44:32 INFO - Running setup.py install for blobuploader 16:44:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:44:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:44:32 INFO - Running setup.py install for requests 16:44:32 INFO - Running setup.py install for docopt 16:44:33 INFO - Successfully installed blobuploader requests docopt 16:44:33 INFO - Cleaning up... 16:44:33 INFO - Return code: 0 16:44:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:44: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')]} 16:44:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44: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')]} 16:44:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:33 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 0x109818a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10981f4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe301d85450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096bcd30>, '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 0x109520db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096b3b20>, '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.xGdUDAfZFX/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.wEzhRjUDZN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:44:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:44:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:44:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xGdUDAfZFX/Render', 16:44:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:44:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:44:33 INFO - 'HOME': '/Users/cltbld', 16:44:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:44:33 INFO - 'LOGNAME': 'cltbld', 16:44:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:33 INFO - 'MOZ_NO_REMOTE': '1', 16:44:33 INFO - 'NO_EM_RESTART': '1', 16:44:33 INFO - 'PAGER': '/bin/cat', 16:44:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:44:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:33 INFO - 'PWD': '/builds/slave/test', 16:44:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:44:33 INFO - 'SHELL': '/bin/bash', 16:44:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wEzhRjUDZN/Listeners', 16:44:33 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 16:44:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:44:33 INFO - 'USER': 'cltbld', 16:44:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:44:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:44:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:33 INFO - 'XPC_FLAGS': '0x0', 16:44:33 INFO - 'XPC_SERVICE_NAME': '0', 16:44:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:44:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:44:33 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-YREyp7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:44:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:44:33 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-4gwh9E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:44:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:44:33 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-kUR0Cq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:44:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:44:33 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-HYXN7l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:44:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:44:33 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-rEzGXy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:44:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:44:33 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-DUpfss-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:44:34 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-Cb95tG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:44:34 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-ZPGi91-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:44:34 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-_FeGy1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:44:34 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-VRZN8c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:44:34 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-OA4arx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:44:34 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-wYbiI7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:44:34 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-_eYhDX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:44:34 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-nIhe25-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:44:34 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-CAGwUi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:44:35 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-CFe8Qt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:44:35 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-6pqinY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:44:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:44:35 INFO - Running setup.py install for manifestparser 16:44:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:44:35 INFO - Running setup.py install for mozcrash 16:44:35 INFO - Running setup.py install for mozdebug 16:44:35 INFO - Running setup.py install for mozdevice 16:44:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:44:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:44:35 INFO - Running setup.py install for mozfile 16:44:35 INFO - Running setup.py install for mozhttpd 16:44:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:44:36 INFO - Running setup.py install for mozinfo 16:44:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:44:36 INFO - Running setup.py install for mozInstall 16:44:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:44:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:44:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:44:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:44:36 INFO - Running setup.py install for mozleak 16:44:36 INFO - Running setup.py install for mozlog 16:44:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:44:36 INFO - Running setup.py install for moznetwork 16:44:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:44:36 INFO - Running setup.py install for mozprocess 16:44:37 INFO - Running setup.py install for mozprofile 16:44:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:44:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:44:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:44:37 INFO - Running setup.py install for mozrunner 16:44:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:44:37 INFO - Running setup.py install for mozscreenshot 16:44:37 INFO - Running setup.py install for moztest 16:44:37 INFO - Running setup.py install for mozversion 16:44:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:44:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:44:37 INFO - Cleaning up... 16:44:37 INFO - Return code: 0 16:44:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:44:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:44:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:44:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:37 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 0x109818a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10981f4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe301d85450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096bcd30>, '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 0x109520db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096b3b20>, '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.xGdUDAfZFX/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.wEzhRjUDZN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:44:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:44:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:44:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xGdUDAfZFX/Render', 16:44:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:44:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:44:37 INFO - 'HOME': '/Users/cltbld', 16:44:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:44:37 INFO - 'LOGNAME': 'cltbld', 16:44:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:37 INFO - 'MOZ_NO_REMOTE': '1', 16:44:37 INFO - 'NO_EM_RESTART': '1', 16:44:37 INFO - 'PAGER': '/bin/cat', 16:44:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:44:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:37 INFO - 'PWD': '/builds/slave/test', 16:44:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:44:37 INFO - 'SHELL': '/bin/bash', 16:44:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wEzhRjUDZN/Listeners', 16:44:37 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 16:44:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:44:37 INFO - 'USER': 'cltbld', 16:44:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:44:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:44:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:37 INFO - 'XPC_FLAGS': '0x0', 16:44:37 INFO - 'XPC_SERVICE_NAME': '0', 16:44:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:44:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:44:38 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-mwSq_r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:44:38 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 16:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:44:38 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-e4UtXb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:44:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:44:38 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-HihavY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:44:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:44:38 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-n9LS6W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:44:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:44:38 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-qivLob-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:44:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 16:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:44:38 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-i3gfVL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:44:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 16:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:44:38 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-yRHrqu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:44:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 16:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:44:38 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-424p8I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:44:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:44:38 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-u2x9fy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:44:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 16:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:44:38 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-9jAbpD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:44:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:44:39 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-rG_dF5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:44:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 16:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:44:39 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-XSpZ1D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:44:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 16:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:44:39 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-0hKWWn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:44:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 16:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:44:39 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-YerzKR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:44:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 16:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:44:39 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-NWhNXS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:44:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 16:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:44:39 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-CqaG3K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:44:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:44:39 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-wpiz6N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:44:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 16:44:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:44:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:44:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:44:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:44:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:44:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:44:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:44:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:44:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:42 INFO - Downloading blessings-1.6.tar.gz 16:44:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:44:42 INFO - Installing collected packages: blessings 16:44:42 INFO - Running setup.py install for blessings 16:44:42 INFO - Successfully installed blessings 16:44:42 INFO - Cleaning up... 16:44:42 INFO - Return code: 0 16:44:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:44:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:44:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:44:42 INFO - Reading from file tmpfile_stdout 16:44:42 INFO - Current package versions: 16:44:42 INFO - blessings == 1.6 16:44:42 INFO - blobuploader == 1.2.4 16:44:42 INFO - docopt == 0.6.1 16:44:42 INFO - manifestparser == 1.1 16:44:42 INFO - mozInstall == 1.12 16:44:42 INFO - mozcrash == 0.17 16:44:42 INFO - mozdebug == 0.1 16:44:42 INFO - mozdevice == 0.48 16:44:42 INFO - mozfile == 1.2 16:44:42 INFO - mozhttpd == 0.7 16:44:42 INFO - mozinfo == 0.9 16:44:42 INFO - mozleak == 0.1 16:44:42 INFO - mozlog == 3.1 16:44:42 INFO - moznetwork == 0.27 16:44:42 INFO - mozprocess == 0.22 16:44:42 INFO - mozprofile == 0.28 16:44:42 INFO - mozrunner == 6.11 16:44:42 INFO - mozscreenshot == 0.1 16:44:42 INFO - mozsystemmonitor == 0.0 16:44:42 INFO - moztest == 0.7 16:44:42 INFO - mozversion == 1.4 16:44:42 INFO - psutil == 3.1.1 16:44:42 INFO - requests == 1.2.3 16:44:42 INFO - wsgiref == 0.1.2 16:44:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:44:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:44:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:44:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:42 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 0x109818a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10981f4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe301d85450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096bcd30>, '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 0x109520db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096b3b20>, '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.xGdUDAfZFX/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.wEzhRjUDZN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:44:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 16:44:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:44:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xGdUDAfZFX/Render', 16:44:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:44:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:44:42 INFO - 'HOME': '/Users/cltbld', 16:44:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:44:42 INFO - 'LOGNAME': 'cltbld', 16:44:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:42 INFO - 'MOZ_NO_REMOTE': '1', 16:44:42 INFO - 'NO_EM_RESTART': '1', 16:44:42 INFO - 'PAGER': '/bin/cat', 16:44:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:44:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:42 INFO - 'PWD': '/builds/slave/test', 16:44:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:44:42 INFO - 'SHELL': '/bin/bash', 16:44:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wEzhRjUDZN/Listeners', 16:44:42 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 16:44:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:44:42 INFO - 'USER': 'cltbld', 16:44:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:44:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:44:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:42 INFO - 'XPC_FLAGS': '0x0', 16:44:42 INFO - 'XPC_SERVICE_NAME': '0', 16:44:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:44:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:44:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 16:44:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 16:44:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:44:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 16:44:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 16:44:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 16:44:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 16:44:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 16:44:43 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:44:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:44:43 INFO - Cleaning up... 16:44:43 INFO - Return code: 0 16:44:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:44:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:44:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:44:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109818a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10981f4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe301d85450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096bcd30>, '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 0x109520db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096b3b20>, '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.xGdUDAfZFX/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.wEzhRjUDZN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:44:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:44:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:44:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xGdUDAfZFX/Render', 16:44:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:44:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:44:43 INFO - 'HOME': '/Users/cltbld', 16:44:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:44:43 INFO - 'LOGNAME': 'cltbld', 16:44:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:43 INFO - 'MOZ_NO_REMOTE': '1', 16:44:43 INFO - 'NO_EM_RESTART': '1', 16:44:43 INFO - 'PAGER': '/bin/cat', 16:44:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:44:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:43 INFO - 'PWD': '/builds/slave/test', 16:44:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:44:43 INFO - 'SHELL': '/bin/bash', 16:44:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wEzhRjUDZN/Listeners', 16:44:43 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 16:44:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:44:43 INFO - 'USER': 'cltbld', 16:44:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:44:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:44:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:43 INFO - 'XPC_FLAGS': '0x0', 16:44:43 INFO - 'XPC_SERVICE_NAME': '0', 16:44:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:44:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:43 INFO - Downloading/unpacking jsonschema 16:44:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:44:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:44:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:44:45 INFO - Downloading/unpacking functools32 (from jsonschema) 16:44:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:44:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:44:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:46 INFO - Downloading functools32-3.2.3-2.tar.gz 16:44:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:44:46 INFO - warning: no files found matching '*.txt' 16:44:46 INFO - no previously-included directories found matching 'build' 16:44:46 INFO - no previously-included directories found matching 'dist' 16:44:46 INFO - no previously-included directories found matching '.git*' 16:44:46 INFO - Installing collected packages: jsonschema, functools32 16:44:46 INFO - Running setup.py install for functools32 16:44:46 INFO - warning: no files found matching '*.txt' 16:44:46 INFO - no previously-included directories found matching 'build' 16:44:46 INFO - no previously-included directories found matching 'dist' 16:44:46 INFO - no previously-included directories found matching '.git*' 16:44:46 INFO - Successfully installed jsonschema functools32 16:44:46 INFO - Cleaning up... 16:44:46 INFO - Return code: 0 16:44:46 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 16:44:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:44:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:44:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:46 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 0x109818a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10981f4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe301d85450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096bcd30>, '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 0x109520db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096b3b20>, '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.xGdUDAfZFX/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.wEzhRjUDZN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:44:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 16:44:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 16:44:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xGdUDAfZFX/Render', 16:44:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:44:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:44:46 INFO - 'HOME': '/Users/cltbld', 16:44:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:44:46 INFO - 'LOGNAME': 'cltbld', 16:44:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:46 INFO - 'MOZ_NO_REMOTE': '1', 16:44:46 INFO - 'NO_EM_RESTART': '1', 16:44:46 INFO - 'PAGER': '/bin/cat', 16:44:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:44:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:46 INFO - 'PWD': '/builds/slave/test', 16:44:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:44:46 INFO - 'SHELL': '/bin/bash', 16:44:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wEzhRjUDZN/Listeners', 16:44:46 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 16:44:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:44:46 INFO - 'USER': 'cltbld', 16:44:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:44:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:44:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:46 INFO - 'XPC_FLAGS': '0x0', 16:44:46 INFO - 'XPC_SERVICE_NAME': '0', 16:44:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:44:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:46 INFO - Downloading/unpacking flake8 16:44:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:44:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:44:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:49 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:44:49 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:44:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:44:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:44:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:49 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:44:49 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:44:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:44:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:44:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:50 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:44:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:44:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:44:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 16:44:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:44:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:44:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:44:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:44:50 INFO - no previously-included directories found matching 'docs/_build' 16:44:50 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:44:50 INFO - Running setup.py install for pep8 16:44:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:44:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:44:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:44:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:44:50 INFO - no previously-included directories found matching 'docs/_build' 16:44:50 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 16:44:50 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:44:50 INFO - Cleaning up... 16:44:50 INFO - Return code: 0 16:44:50 INFO - Running post-action listener: _resource_record_post_action 16:44:50 INFO - Running post-action listener: _start_resource_monitoring 16:44:50 INFO - Starting resource monitoring. 16:44:50 INFO - ##### 16:44:50 INFO - ##### Running install step. 16:44:50 INFO - ##### 16:44:50 INFO - Running pre-action listener: _resource_record_pre_action 16:44:50 INFO - Running main action method: install 16:44:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:44:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:44:51 INFO - Reading from file tmpfile_stdout 16:44:51 INFO - Detecting whether we're running mozinstall >=1.0... 16:44:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:44:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:44:51 INFO - Reading from file tmpfile_stdout 16:44:51 INFO - Output received: 16:44:51 INFO - Usage: mozinstall [options] installer 16:44:51 INFO - Options: 16:44:51 INFO - -h, --help show this help message and exit 16:44:51 INFO - -d DEST, --destination=DEST 16:44:51 INFO - Directory to install application into. [default: 16:44:51 INFO - "/builds/slave/test"] 16:44:51 INFO - --app=APP Application being installed. [default: firefox] 16:44:51 INFO - mkdir: /builds/slave/test/build/application 16:44:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:44:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0.en-US.mac.dmg --destination /builds/slave/test/build/application 16:45:13 INFO - Reading from file tmpfile_stdout 16:45:13 INFO - Output received: 16:45:13 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 16:45:13 INFO - Running post-action listener: _resource_record_post_action 16:45:13 INFO - ##### 16:45:13 INFO - ##### Running run-tests step. 16:45:13 INFO - ##### 16:45:13 INFO - Running pre-action listener: _resource_record_pre_action 16:45:13 INFO - Running main action method: run_tests 16:45:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:45:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:45:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:45:13 INFO - 2016-05-30 16:45:13.755 screenresolution[1651:8859] starting screenresolution argv=screenresolution get 16:45:13 INFO - 2016-05-30 16:45:13.758 screenresolution[1651:8859] Display 0: 1600x1200x32@60 16:45:13 INFO - 2016-05-30 16:45:13.764 screenresolution[1652:8862] starting screenresolution argv=screenresolution list 16:45:13 INFO - Available Modes on Display 0 16:45:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:45:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:45:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:45:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:45:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:45:13 INFO - Intel Iris: 16:45:13 INFO - Chipset Model: Intel Iris 16:45:13 INFO - Type: GPU 16:45:13 INFO - Bus: Built-In 16:45:13 INFO - VRAM (Dynamic, Max): 1536 MB 16:45:13 INFO - Vendor: Intel (0x8086) 16:45:13 INFO - Device ID: 0x0a2e 16:45:13 INFO - Revision ID: 0x0009 16:45:13 INFO - Displays: 16:45:13 INFO - Display: 16:45:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:45:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:45:13 INFO - Display Serial Number: 1600x1200 60 16:45:13 INFO - Main Display: Yes 16:45:13 INFO - Mirror: Off 16:45:13 INFO - Online: Yes 16:45:13 INFO - Rotation: Supported 16:45:13 INFO - Return code: 0 16:45:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:45:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:45:14 INFO - Python 2.7.3 16:45:14 INFO - Return code: 0 16:45:14 INFO - grabbing minidump binary from tooltool 16:45:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:45:14 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 0x1096bcd30>, '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 0x109520db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096b3b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:45:14 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 16:45:14 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 16:45:14 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:45:14 INFO - Return code: 0 16:45:14 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:45:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:45:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:45:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:45:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:45:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 16:45:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 16:45:15 INFO - Return code: 0 16:45:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0306', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 16:45:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0306 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 16:45:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xGdUDAfZFX/Render', 16:45:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:15 INFO - 'HOME': '/Users/cltbld', 16:45:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:15 INFO - 'LOGNAME': 'cltbld', 16:45:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:45:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:45:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:15 INFO - 'MOZ_NO_REMOTE': '1', 16:45:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:45:15 INFO - 'NO_EM_RESTART': '1', 16:45:15 INFO - 'PAGER': '/bin/cat', 16:45:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:15 INFO - 'PWD': '/builds/slave/test', 16:45:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:45:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:15 INFO - 'SHELL': '/bin/bash', 16:45:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wEzhRjUDZN/Listeners', 16:45:15 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 16:45:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:15 INFO - 'USER': 'cltbld', 16:45:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:15 INFO - 'XPC_FLAGS': '0x0', 16:45:15 INFO - 'XPC_SERVICE_NAME': '0', 16:45:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:15 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0306', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 16:45:15 INFO - mozversion application_buildid: 20160526112438 16:45:15 INFO - mozversion application_changeset: 417e1ec42d8f68c55e52c336cdf81e83323db88c 16:45:15 INFO - mozversion application_display_name: Firefox 16:45:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:45:15 INFO - mozversion application_name: Firefox 16:45:15 INFO - mozversion application_remotingname: firefox 16:45:15 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 16:45:15 INFO - mozversion application_vendor: Mozilla 16:45:15 INFO - mozversion application_version: 47.0 16:45:15 INFO - mozversion platform_buildid: 20160526112438 16:45:15 INFO - mozversion platform_changeset: 417e1ec42d8f68c55e52c336cdf81e83323db88c 16:45:15 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 16:45:15 INFO - mozversion platform_version: 47.0 16:45:15 INFO - using testdate: 1464651915 16:45:15 INFO - actual date: 1464651915 16:45:15 INFO - starting webserver on 'localhost:49277' 16:45:15 INFO - SUITE-START | Running 2 tests 16:45:15 INFO - TEST-START | dromaeo_css 16:45:15 INFO - operating with platform_type : mac_ 16:45:15 INFO - Initialising browser for dromaeo_css test... 16:45:15 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/tmpzOqYCJ/profile http://localhost:49277/getInfo.html) 16:45:18 INFO - PROCESS | 1670 | __metrics Screen width/height:1600/1200 16:45:18 INFO - PROCESS | 1670 | colorDepth:24 16:45:18 INFO - PROCESS | 1670 | Browser inner width/height: 1024/689 16:45:18 INFO - PROCESS | 1670 | __metrics 16:45:18 ERROR - PROCESS | 1670 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2844: TypeError: this.tabs is undefined 16:45:19 INFO - TEST-INFO | 1670: exit 0 16:45:19 INFO - Browser initialized. 16:45:19 INFO - Running cycle 1/1 for dromaeo_css test... 16:45:19 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/tmpzOqYCJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:45:30 INFO - PROCESS | 1675 | RSS: Main: 168226816 16:45:30 INFO - PROCESS | 1675 | 16:46:20 INFO - PROCESS | 1675 | 1464651980530 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 16:48:31 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 16:48:32 INFO - PROCESS | 1675 | RSS: Main: 178434048 16:48:32 INFO - PROCESS | 1675 | 16:51:34 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 16:51:35 INFO - PROCESS | 1675 | RSS: Main: 189849600 16:51:35 INFO - PROCESS | 1675 | 16:54:36 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 16:54:37 INFO - PROCESS | 1675 | RSS: Main: 192667648 16:54:37 INFO - PROCESS | 1675 | 16:57:39 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 16:57:39 INFO - PROCESS | 1675 | RSS: Main: 195477504 16:57:39 INFO - PROCESS | 1675 | 17:00:42 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 17:00:42 INFO - PROCESS | 1675 | RSS: Main: 194469888 17:00:42 INFO - PROCESS | 1675 | 17:03:44 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 17:03:45 INFO - PROCESS | 1675 | RSS: Main: 200093696 17:03:45 INFO - PROCESS | 1675 | 17:03:45 INFO - PROCESS | 1675 | __start_tp_report 17:03:45 INFO - PROCESS | 1675 | _x_x_mozilla_page_load 17:03:45 INFO - PROCESS | 1675 | _x_x_mozilla_page_load_details 17:03:45 INFO - PROCESS | 1675 | |i|pagename|runs| 17:03:45 INFO - PROCESS | 1675 | |0;dojo.html;3620.7827530348177;3665.028767376644;3695.9445608315896;3701.8519259229615;3709.017110465726;5960.135780311857;5974.1337506061645;6004.159417681522;6014.188084608577;6026.4576188143055;5879.470847623713;5887;5971.0744834550715;5979.073243647242;6036.641755604986;5317.8936421271555;5351.625386222966;5413.377461591908;5447.972760136194;5485.588580856432;5331.173668081454;5452.182172674111;5460.563154947595;5471;5484.478974497416;5124.154514505102;5372.838814835562;5397.190421436777;5398.568114550846;5543.1408131739645;5464.480874316953;5498.505134537871;5514.944850551483;5536.307961504812;5564.805231816867;5358.812441564565;5418.160185171305;5433.972830135824;5441.619086663935;5449.373322067973;5348.171033489815;5860.062390017577;5947.821565353003;5973.223480947492;5989.580729348947;6248.562600617913;6268.937310626881;6282.842928926768;6293.024581189923;6336.081268216129;6311.053341998691;6331.620102793847;6350.587211831216;6350.682465876734;6392.392722691384;4684.882877928045;5002.724850133278;5630.746616402272;6027.487663548603;6178.629282243035;6458.515611329169;6462.32145624712;6463.127477790532;6465.1595292611655;6468.6442245676;6161.291451483136;6200.441960223511;6208.565400421927;6234.812955611339;6266.436020758062;6346.873062538723;6374.776882809079;6388.8083357499345;6401.4238718514625;6421.967890160519;6267.4986001119805;6270.435660790551;6278.686065696788;6282.465990390868;6290.685465726695;6103.176071230416;6246.125542424064;6262.15460912771;6273.215848018997;6305.464035557029;5060.544550990428;5069.290299358093;5079.517445842639;5101.744912754348;5129.256257842592;4593.494715581283;4595.931061034087;4602.263637817933;4602.309653551927;4612.192866248461;6082.2093127893095;6087.9391206087375;6104.116776136042;6117.235345581802;6143.481172719164;6401.03984402334;6410.391012853772;6436.356364363526;6437.195350581177;6442.549021568539;6068.362821903725;6182.196314479089;6192.845178870564;6193;6209.0996805462955;5602.159676048544;5646;5664.971675141598;5669.631473954262;5713.857153571194;6520.673966301666;6576.934230657632;6580.80257592273;6606.141201643852;6618.503612229102;6218.232886365524;6276.968615156895;6323.20959880015;6328.620282782864;6353.491720662243;6383.585066970632;6402.935970640234;6502.967485162544;6541.6075035498025;6544.44372228366;6279.96860015697;6284.151639528605;6290.874182516415;6296.307406185408;6296.433321001132;6195.411435913581;6261.436470717657;6305.653189074462;6334.398232167937;6335.461485773577;6107.786227482017;6177.752889884534;6233.937660623335;6242.843928901813;6272.8118156455375;6244.500439964701;6246.906296405648;6265.843353916189;6267.2479302484;6289.6540690260645;6082.787102451393;6103.7253323601435;6209.379062093935;6223.626582405071;6245.656488893154;6226.782062627786;6236.469900058546;6242;6246.781362652286;6269.717862696099;6110.602810817282;6153;6156.476699480415;6165.6608886509875;6191.9690401547705 17:03:45 INFO - PROCESS | 1675 | |1;ext.html;520.040525230949;530.7372850439033;565.2538648983341;612.0757655939535;624.8656538844149;36071;39426.8028659857;39475.60524394755;39503.80248098759;39546.40680389797;41258.79370603143;41838;41911;41914.58085419145;41944.16111677764;9656.179224765903;9858.408495490257;9870.654527091554;9938.254630902675;9946.65186718465;9622;9933.155681767059;9934.751631209205;9963.202943764518;10010.749731256705;9914.702558923207;9973.50132493376;10003.849942250872;10032.197424206082;10041.347312424668;9855;9959.302848800588;9988.101070903616;10016.09855113036;10038.698839034842;8465;9792.363496372713;9957;9957.103860652576;10021.649242276559;40542.189156217006;40565.18869622621;40738.59261407378;40759.59240407588;40782.79608601938;3393.694567488938;3462.117160124177;3514.6661067198575;3585.587657419404;3601.3877640800865;27485;28322.85838570794;28429.00498482564;28443;28646.856765716246;2759.7378249066305;2794.0311599764577;2824.6751623563346;2842.7725781937397;2884.524053531165;465130;482583;522593;527349;530420;48839.5116048835;75338;75909;76004.23995759971;76539.23460765432;47901;48243.75878120587;48246.03507929822;48317.792055198195;48322.791930201325;16877.32490700358;16921.069341186354;17345.2194651241;17355.30578776835;17384.391546295818;13754.968377371537;13808.309584520734;13868.583942481742;13902.304884755722;13902.51341203053;15379;15472;15792.289346979414;15808.209589520478;15843.049417035014;6735.0805020568905;6788.321167883271;6829;6835.555688880207;6847.931520684829;961.5096300886598;1057.3867157048894;1139.8290256461432;1161.5353858456515;1162.918595698293;25349.61975570368;26777.464450710875;30151.547726784116;31556.526652100238;32567.674323256466;1017.5878769098526;1087.3530249501534;1131.468725807403;1155.364549497773;1228.1157566552067;15356.462523811613;15891.443799467195;15952.92023539875;15953.04281743076;16385.83614163867;691982;696221;696556;696776;700710;64964;65628.67185664136;66075;66187.66906165535;66311;1542.5218182363503;1561.9843801562067;1582.8179759327595;1584.3741722019968;1591.1089789717796;26169.476610468064;31039;31080.533791992686;31311.84344078265;31970;56234.437655624555;56600.716996414754;56978;57177;57243.71378143083;511010;516690;517721;519610;519975;34206.657933421346;34732.65267347294;35377.29245415026;35446;36031.27937441289;47674;47934.76032619815;48289.75855120702;49006.264906025724;49228.75385623049;38084.619153808104;38753;39012.024699382746;39351.40972885466;39640.40539391854;37884.62115378811;38363.23273534569;38924.80537597294;39449;39764.40353394643;33365.83317083399;38846.80576597099;38847.223055539296;39038.024049397856;39877.003074923356;27547.86226068857;32123.51814722828;33741;33863.83068084742;33903.830480848424;21154.788452115896;25296.735163241396;25400.6734006734;25632.48735025252;26044.218673439827 17:03:45 INFO - PROCESS | 1675 | |2;jquery.html;379.19799623795683;382.82390100553744;390.05800990607685;392.7741548609549;395.44440061713266;20112;20452.36381089513;20580.38258852233;23048.769512304872;23163.652545211837;22335.553288934254;22378.88810555945;22384;22665.320040398772;22823.54352912941;5032.345795046657;5910.556708246877;6049.455549000589;6157.661328626924;6167.753289868411;4459.888502787424;6414.743410263607;6557.639329836857;6600.735970561172;6663.967085101793;5712.9428705713035;6538.1827271591055;6574.8685026299445;6586.901196482057;6607.80176594703;5803.274590676187;5821.330546987108;5918.319393269786;5963.135345374918;5997.490213331872;5045.672031317952;5634.12671035986;5939.851503712399;5969.194158788595;5977.372375900555;13910.930445347809;13935.512257071056;14245.85754142466;22235.44411389712;25789.484210315877;2275.988620056906;2293.9655905161435;2316.791488766002;2441.7680320369536;2656.269525880379;8422.28410585101;8788.8681669775;9871.802563948753;10027.197824174049;10080;1691.8900271482437;1699.1504247876062;1761.1898526677744;1795.1831916477972;1809.787442413586;102020;102722;107044.46477767639;107612.4619376898;110472.4476377621;11411.771764564663;11655.825162622568;11823.645290641294;11854.229475084092;11870.050395968301;11761.823572646417;11962.521499140112;14427.78358324626;14439.35022923971;14511;8420.39281236943;8839.734807955772;8935.329850261256;8958.05940376251;9016.323775716848;8097.433179677366;9282.535873206312;9327.300452465957;9333.020032896584;9373.531323433801;3799.2971300308977;4580.38164847748;4598.908021839611;4628.791704373311;4650.093231819763;1787.1064467766116;1825.7402392349236;1833.9266429342676;1862.6833438315518;1937.6899696048847;949.520492151459;952.7951490429591;1036.2176556699644;1057.1701214546545;1058.9841152382567;20274.89862550678;20971.161153553992;21077;21388.465288367915;22005.889970550044;991.8561808537721;1012.3875055591409;1031.4120951057826;1053.3837704942694;1101.6474728086935;9497.287703422271;9560.378575392577;11610.361430121424;11731.178817482865;11802.46888890002;694244;694854;696223;705538;709600;122009;122342.39725034966;123457;124786;130349;933.803901180746;1054.5254635413942;1058.2645061682058;1061.6072053340313;1067.9252452328417;15754.606134846721;19425.902870485555;19592.510187245432;19722.605547888674;19863.205471780966;51081.48918510916;52108.478915211876;52707.20939185992;52938;53394.932101358536;66350.67772627935;74249;74706.87939681018;79698.81957672733;80054;8267.007959044953;8407.831843363221;8532.360072994674;8685.565721714016;8796.988346339996;12252.877471225174;12756.170848894792;13125.474981001034;13307.401166947704;13358.666033348854;10151.390916544735;10233.590656373663;10285.382877027403;10872.075873550837;11229.3262404256;10603.787924241626;11065.170112241774;11228.270162439741;11416.31502109876;11509.884901150881;11645.475953582278;11744.236624619372;11828.940855295668;11872.347020913932;11984.340861252715;8170.346372290083;8367.28878045373;8368;8401.411901166859;8546.316294696286;8676.584186788066;10385.428801415994;11743.001844843284;11776.411179440822;12057.095717821374 17:03:45 INFO - PROCESS | 1675 | |3;mootools.html;1633.4808627975985;1687.5274923021555;2024.4736368544175;2082.4481512399216;2112.260708751936;8389.161083891608;9100.271978241724;9177.54112294385;9362.85955710664;9386.48374339411;9008.54957252138;9097.408668436547;9161.17549420552;9185.724428267162;9218.032106628809;2576.6135079738046;2585.58630619101;2597.000154940343;2598.9610155847677;2603.713591504933;2555.1440267510407;2607.5436798560177;2610.765031147196;2611.738826117392;2612.6603541539575;2581.7676409123173;2598.259496043628;2619.8559079250635;2630.6974697909795;2630.828996115256;2604.960925586118;2605.2054123492326;2624.5407053765516;2626.474705058996;2629.368951451657;2574.6652935118314;2592.520383729016;2606.882690278942;2622.5279449699046;2626.6716660417446;9329.813403731956;9432.669856554987;9463.763405914839;9487.288453365956;9498.19265362445;5811.564132690023;5931.584789064767;5970.223870896755;5989.341172471025;6026.638401695912;3673.210259794157;3748.7188460865545;3859.9228015439817;3865.768053916774;3902.2975864344407;1079.1043433949803;1088.2327958789035;1091.9235653504259;1131.3099009604134;1137.5108703257597;12594.118411711184;12658.810117848148;12680.429380677799;12680.936595317056;12688.682782930407;5146.30524879144;5190.091733946544;5228.738563071831;5267.604929630216;5279.155335146358;10711.678649640524;10761.623343182951;10828.891711082946;10835.18736094796;10845.295055821347;5736.311642602915;5939.910901336483;5985;5990.9101363479585;5998;4760.880977975578;5133.281340612317;5188.766505507261;5237.73811309433;5327.227552005015;4147.6267135957905;4160.417541544186;4172.269852775752;4191.937120943188;4206.72656277341;1379.9379027943567;1399.2793711238721;1401.5585090696397;1411.3648858013935;1413.6889884225454;1404.1154072933987;1420.3324437514352;1434.4262295081842;1451.122071146941;1473.0057211382275;8546.31629469641;8659.870101948476;8688.869666955;8719.084496127942;8729.258013068833;539.1050855579797;546.4535464535464;550.0813641219173;554.4649413316089;554.7226386806597;6340.556161068681;6397.776077837253;6406.135171751754;6407.871842563122;6501.447376972916;11197.49611267495;11479.13906457019;11676.357800320893;11686.883131168579;11701.707457313465;11203.935626115506;11242.943785281184;11414.029807466291;11485.483153257956;11528.02011828987;8848.778780530409;8884.200421961925;8994.415363001384;9001.234895033995;9019.819603608023;8077.919220807717;8321.292690121407;8322.45904016237;8424.536650484282;8455.788605284917;7754.457187997012;8900.688475903571;9986.450745208793;10045.045720656526;10260.333078349884;8131.67473301085;8976.371653984159;9915.355501892354;10022.548985295825;10604.681859543918;7091.82270443243;7574.810629734301;7866.567338796192;7881.684732610632;7905.802354941173;7176.928230717626;7331.340179383881;8644.610992505479;8698.782530436789;8845.115488451103;7757.1079325878245;7899.881501777593;7910.406719495946;7940.364770818205;8045;7758.728444504415;7771.067471903408;7818.296353328228;7862.213778622092;7899.407544434305;8208.876866847122;8279.172082791672;8293.087760346478;8327.6668933242;8333.458325208841;7449.478536502393;7543.585102819397;7548.584827834522;7556.433267505069;7564.659590318559;7556.811079723051;7575.848483030199;7662.310392064741;7663.846723065619;7665.38676905835 17:03:45 INFO - PROCESS | 1675 | |4;prototype.html;325.60154137977383;343.22088858291664;346.31256954944433;347.32618719683813;351.2045217582176;5404.864978354545;6458.903116453251;6932.098827152476;6935.271796461375;6945.131858517685;5802.477777000069;6872.518923675345;6880.139982502187;6895.793126206222;6906.893397513756;4895.926561101586;4967;5079.974600126994;5082.898342033157;5092.134337162691;5028.120078986163;5068.8225912093085;5097.847064588068;5099.821506247282;5103.4896510349035;5016.27264046713;5028.84913452597;5042.865355295066;5051.873703157414;5074.467180946003;5086.084504789155;5111.2333150027425;5116.258142569307;5123.1546794778815;5131.871703207413;4403.669724770655;4405.867823965286;4407.898025493627;4409;4414.426124603813;6345.302016778151;6626.503012274099;6647.302033286532;6680.632565208912;6708.83227919301;4726.007538416937;4998.6750861193905;5653.547716182696;5839.532837373;5893.292804863401;7344.412447004227;7354.411647068222;7363.190049094595;7366.705331786722;7369.705211791522;3832.4059770735303;4674.976625116853;4689.249720044811;4693.7653117344;4712.835050773241;6859.142607174103;7436.144843343085;7723.150453450116;7819.413543984224;7828.217178282126;4725.29120631908;4814.109389762905;4820.68665536739;4821.566059054702;4879.975600121977;4940.530649588283;4945.357103576512;4958.404991401055;4959.107360675113;4965.776540055706;5931.436513531207;5934.43622855828;5937.762489500372;5941.55438342126;5952.017917043726;7489.700411983568;7495;7511.51175173612;7516.098068231847;7538.133114691771;1626.6096136927051;1666.5083800278946;1680.134730613735;1712.1182590965664;1714.1429285357322;938.8685584018243;1008.8184126857236;1083.4041277297456;1091.1870656361061;1093.4915264401932;6926;6968.407685346802;6972.511924165361;6986.615736134561;6990.056342393712;6545.574537655038;6601.801945941629;7228.494005419675;7359.153697324769;7728.497647652885;5829.795957141479;6576.914809056452;7002.964985175144;7047.40097091753;7072.398846098036;3771.1891943232336;4044.9797751011056;4100.302948498763;4103.938440923389;4105.97947010263;6711.966440167866;8117.959410202911;8192.672293108328;8210.932578764723;8242.835143297101;8052.637631306605;8062.596870156469;8065.9596702016115;8084.191580841869;8088.231617996281;8183.508989460533;8188.877166842502;8193.4674246175;8224.095349511548;8247.340212782843;7570.242975702606;7808.570528620753;7849.1758365370815;7860.764177074697;7876;7618.542887426551;7664.73173438927;7673.501222420525;7675.846483070419;7708.576028318496;8180.83638327242;8186.97662792151;8197.508149511048;8208.261256486945;8224.83550329002;3981.3032719274593;4845.248986407112;4863.051704917507;4871.318015477907;4885.169521181337;7520.360769334668;7751.806204844924;7789.10425301075;7802;7818.687252510063;7477.541879333696;7489.737859174903;7521.623918804147;7530.962345188239;7542.886856697263;6173.382661733791;7493.475456717977;7495.213002634863;7510.023696919366;7532.849343012999;5880.647161170344;7138.179109402485;7146.535475194096;7328.743493977685;7329.890051649336;7466.962665186639;7523.172451030492;7524.548527088392;7533.6233188339265;7534.397248220239;5831.271091113611;7309.415246780139;7352.5220860643885;7371.8157046074275;7387.076615423072 17:03:45 INFO - PROCESS | 1675 | |5;yui.html;716.3373879161776;761.478387304696;768.8923550702903;784.6508303804802;801.7795106345754;15151;15246;15410.460633877816;15441.536753897388;15549;14290.499832505864;14391.64020899478;14407.78388324176;14452;14453.78319325211;800.8958835351397;937.1659223291259;950.3964982236295;971.6987733802508;982.6462473509531;948.8292107420662;960.159860122393;960.8606752020953;962.2494454325637;962.5331714118682;1025.5846382215216;1027.2501074215827;1028.9485525723721;1031.494567661848;1031.922605804564;1006.0995409108843;1010.2877471382658;1019.5004447820552;1019.5004447820552;1021.0555236406294;646.4569761400446;748.5097261293806;751.9699212031567;768.9653965571507;770.3375097416217;15342.923285383613;15361.46234881785;15374.385024599003;15402.998805077634;15442.227888605637;11382.317060976367;11508.194426390157;12036.939815300955;13401.46394144233;13424.194548327034;9561;10468.895311046868;10485.68542943713;10512.0013598708;10673.573057077785;3131.686831316873;3617.222297206113;3629.401148810443;3746.082209858575;3869.4776205212215;88448;89123;90981.54509227411;91900.54049729709;92551.53724231335;9730.172935300438;9872.062154095349;9969.152622027064;10009.149222316184;10022.498875056219;21976.120955161936;22172.667409988862;22229;22275.777242227694;23397.181098661466;7829.217078292125;7887.487313324616;7992.240737129963;8173.18268173178;8215.301699355621;4226.450561426995;4277.230098582285;4305.461817272841;4374.58441448062;4385.934210986838;3761.6990640748672;3813.122981714221;3813.637704418076;3821.063839359375;3821.8280177392085;696.0742212856889;697.1808125452573;698.6297262450909;699.2029086841005;701.7649087555624;572.4172662587947;615.4737699267151;634.9587276826992;635.4090695652994;657.1489920552896;8071.717489887943;8485.872711909326;8569.528675922884;8707.47755134694;8846.424982376126;606.7027156693189;618.7401291457599;631.709413669717;632.610944269271;638.6104476269472;3904.921901561953;3976.781277029791;3990.700697447645;4019.959800402017;4063.167050754588;97015.51492242493;97727.53408698876;98121.50939245259;98792.01207987829;100745.99254007365;63802;85700.57149714211;86037.55849766065;86915;87240.12759872321;73948.26051739522;76330.61834690791;76656;77254.99999999888;78995;7900.051993760785;7982.241687039722;8006.479578827358;8320.375971802017;8391.370647201606;50916.981660367324;52249.738751306;52735.472645273054;53820.73089634527;54549;66710.66644666939;73629.26370736225;74004.62997684977;74237.40169094056;74315.62842185755;9887.456189909337;10202.438865862152;10326.122279606318;10346.172306215636;10381;11054.281471704313;11165.330080195214;11166.385848778395;11295.435228238392;12040.157188996685;10307.330023548446;10362.989466109488;10577.31247468912;10648.89351106479;10669.946650266698;10256.897431025893;10402.687919362432;10504.369737815756;10676.572937082741;11265.662030138781;10343.648910399603;10449;10450.843237351293;10523.210759192938;10866.239363244813;9038.502882341534;9134.725958221265;9267.119623635745;9370.67202647904;9403.4828084456;9034.864477032981;9107.772305692411;9245.537723113952;9264.907350926675;9299.767505812139 17:03:45 INFO - PROCESS | 1675 | __end_tp_report 17:03:45 INFO - PROCESS | 1675 | __start_cc_report 17:03:45 INFO - PROCESS | 1675 | _x_x_mozilla_cycle_collect,352 17:03:45 INFO - PROCESS | 1675 | __end_cc_report 17:03:45 INFO - PROCESS | 1675 | __startTimestamp1464653025122__endTimestamp 17:03:45 INFO - PROCESS | 1675 | 17:03:45 INFO - PROCESS | 1675 | ------- Summary: start ------- 17:03:45 INFO - PROCESS | 1675 | Number of tests: 6 17:03:45 INFO - PROCESS | 1675 | 17:03:45 INFO - PROCESS | 1675 | [#0] dojo.html Cycles:165 Average:5919.36 Median:6191.97 stddev:616.56 (10.0%) stddev-sans-first:591.50 17:03:45 INFO - PROCESS | 1675 | Values: 3620.8 3665.0 3695.9 3701.9 3709.0 5960.1 5974.1 6004.2 6014.2 6026.5 5879.5 5887.0 5971.1 5979.1 6036.6 5317.9 5351.6 5413.4 5448.0 5485.6 5331.2 5452.2 5460.6 5471.0 5484.5 5124.2 5372.8 5397.2 5398.6 5543.1 5464.5 5498.5 5514.9 5536.3 5564.8 5358.8 5418.2 5434.0 5441.6 5449.4 5348.2 5860.1 5947.8 5973.2 5989.6 6248.6 6268.9 6282.8 6293.0 6336.1 6311.1 6331.6 6350.6 6350.7 6392.4 4684.9 5002.7 5630.7 6027.5 6178.6 6458.5 6462.3 6463.1 6465.2 6468.6 6161.3 6200.4 6208.6 6234.8 6266.4 6346.9 6374.8 6388.8 6401.4 6422.0 6267.5 6270.4 6278.7 6282.5 6290.7 6103.2 6246.1 6262.2 6273.2 6305.5 5060.5 5069.3 5079.5 5101.7 5129.3 4593.5 4595.9 4602.3 4602.3 4612.2 6082.2 6087.9 6104.1 6117.2 6143.5 6401.0 6410.4 6436.4 6437.2 6442.5 6068.4 6182.2 6192.8 6193.0 6209.1 5602.2 5646.0 5665.0 5669.6 5713.9 6520.7 6576.9 6580.8 6606.1 6618.5 6218.2 6277.0 6323.2 6328.6 6353.5 6383.6 6402.9 6503.0 6541.6 6544.4 6280.0 6284.2 6290.9 6296.3 6296.4 6195.4 6261.4 6305.7 6334.4 6335.5 6107.8 6177.8 6233.9 6242.8 6272.8 6244.5 6246.9 6265.8 6267.2 6289.7 6082.8 6103.7 6209.4 6223.6 6245.7 6226.8 6236.5 6242.0 6246.8 6269.7 6110.6 6153.0 6156.5 6165.7 6192.0 17:03:45 INFO - PROCESS | 1675 | 17:03:45 INFO - PROCESS | 1675 | [#1] ext.html Cycles:180 Average:70983.49 Median:39025.02 stddev:155086.50 (397.4%) stddev-sans-first:155431.32 17:03:45 INFO - PROCESS | 1675 | Values: 520.0 530.7 565.3 612.1 624.9 36071.0 39426.8 39475.6 39503.8 39546.4 41258.8 41838.0 41911.0 41914.6 41944.2 9656.2 9858.4 9870.7 9938.3 9946.7 9622.0 9933.2 9934.8 9963.2 10010.7 9914.7 9973.5 10003.8 10032.2 10041.3 9855.0 9959.3 9988.1 10016.1 10038.7 8465.0 9792.4 9957.0 9957.1 10021.6 40542.2 40565.2 40738.6 40759.6 40782.8 3393.7 3462.1 3514.7 3585.6 3601.4 27485.0 28322.9 28429.0 28443.0 28646.9 2759.7 2794.0 2824.7 2842.8 2884.5 465130.0 482583.0 522593.0 527349.0 530420.0 48839.5 75338.0 75909.0 76004.2 76539.2 47901.0 48243.8 48246.0 48317.8 48322.8 16877.3 16921.1 17345.2 17355.3 17384.4 13755.0 13808.3 13868.6 13902.3 13902.5 15379.0 15472.0 15792.3 15808.2 15843.0 6735.1 6788.3 6829.0 6835.6 6847.9 961.5 1057.4 1139.8 1161.5 1162.9 25349.6 26777.5 30151.5 31556.5 32567.7 1017.6 1087.4 1131.5 1155.4 1228.1 15356.5 15891.4 15952.9 15953.0 16385.8 691982.0 696221.0 696556.0 696776.0 700710.0 64964.0 65628.7 66075.0 66187.7 66311.0 1542.5 1562.0 1582.8 1584.4 1591.1 26169.5 31039.0 31080.5 31311.8 31970.0 56234.4 56600.7 56978.0 57177.0 57243.7 511010.0 516690.0 517721.0 519610.0 519975.0 34206.7 34732.7 35377.3 35446.0 36031.3 47674.0 47934.8 48289.8 49006.3 49228.8 38084.6 38753.0 39012.0 39351.4 39640.4 37884.6 38363.2 38924.8 39449.0 39764.4 33365.8 38846.8 38847.2 39038.0 39877.0 27547.9 32123.5 33741.0 33863.8 33903.8 21154.8 25296.7 25400.7 25632.5 26044.2 17:03:45 INFO - PROCESS | 1675 | 17:03:45 INFO - PROCESS | 1675 | [#2] jquery.html Cycles:180 Average:37270.89 Median:12470.65 stddev:115692.45 (927.7%) stddev-sans-first:115983.83 17:03:45 INFO - PROCESS | 1675 | Values: 379.2 382.8 390.1 392.8 395.4 20112.0 20452.4 20580.4 23048.8 23163.7 22335.6 22378.9 22384.0 22665.3 22823.5 5032.3 5910.6 6049.5 6157.7 6167.8 4459.9 6414.7 6557.6 6600.7 6664.0 5712.9 6538.2 6574.9 6586.9 6607.8 5803.3 5821.3 5918.3 5963.1 5997.5 5045.7 5634.1 5939.9 5969.2 5977.4 13910.9 13935.5 14245.9 22235.4 25789.5 2276.0 2294.0 2316.8 2441.8 2656.3 8422.3 8788.9 9871.8 10027.2 10080.0 1691.9 1699.2 1761.2 1795.2 1809.8 102020.0 102722.0 107044.5 107612.5 110472.4 11411.8 11655.8 11823.6 11854.2 11870.1 11761.8 11962.5 14427.8 14439.4 14511.0 8420.4 8839.7 8935.3 8958.1 9016.3 8097.4 9282.5 9327.3 9333.0 9373.5 3799.3 4580.4 4598.9 4628.8 4650.1 1787.1 1825.7 1833.9 1862.7 1937.7 949.5 952.8 1036.2 1057.2 1059.0 20274.9 20971.2 21077.0 21388.5 22005.9 991.9 1012.4 1031.4 1053.4 1101.6 9497.3 9560.4 11610.4 11731.2 11802.5 694244.0 694854.0 696223.0 705538.0 709600.0 122009.0 122342.4 123457.0 124786.0 130349.0 933.8 1054.5 1058.3 1061.6 1067.9 15754.6 19425.9 19592.5 19722.6 19863.2 51081.5 52108.5 52707.2 52938.0 53394.9 66350.7 74249.0 74706.9 79698.8 80054.0 8267.0 8407.8 8532.4 8685.6 8797.0 12252.9 12756.2 13125.5 13307.4 13358.7 10151.4 10233.6 10285.4 10872.1 11229.3 10603.8 11065.2 11228.3 11416.3 11509.9 11645.5 11744.2 11828.9 11872.3 11984.3 8170.3 8367.3 8368.0 8401.4 8546.3 8676.6 10385.4 11743.0 11776.4 12057.1 17:03:45 INFO - PROCESS | 1675 | 17:03:45 INFO - PROCESS | 1675 | [#3] mootools.html Cycles:180 Average:6387.00 Median:548.27 stddev:3362.75 (613.3%) stddev-sans-first:3353.20 17:03:45 INFO - PROCESS | 1675 | Values: 1633.5 1687.5 2024.5 2082.4 2112.3 8389.2 9100.3 9177.5 9362.9 9386.5 9008.5 9097.4 9161.2 9185.7 9218.0 2576.6 2585.6 2597.0 2599.0 2603.7 2555.1 2607.5 2610.8 2611.7 2612.7 2581.8 2598.3 2619.9 2630.7 2630.8 2605.0 2605.2 2624.5 2626.5 2629.4 2574.7 2592.5 2606.9 2622.5 2626.7 9329.8 9432.7 9463.8 9487.3 9498.2 5811.6 5931.6 5970.2 5989.3 6026.6 3673.2 3748.7 3859.9 3865.8 3902.3 1079.1 1088.2 1091.9 1131.3 1137.5 12594.1 12658.8 12680.4 12680.9 12688.7 5146.3 5190.1 5228.7 5267.6 5279.2 10711.7 10761.6 10828.9 10835.2 10845.3 5736.3 5939.9 5985.0 5990.9 5998.0 4760.9 5133.3 5188.8 5237.7 5327.2 4147.6 4160.4 4172.3 4191.9 4206.7 1379.9 1399.3 1401.6 1411.4 1413.7 1404.1 1420.3 1434.4 1451.1 1473.0 8546.3 8659.9 8688.9 8719.1 8729.3 539.1 546.5 550.1 554.5 554.7 6340.6 6397.8 6406.1 6407.9 6501.4 11197.5 11479.1 11676.4 11686.9 11701.7 11203.9 11242.9 11414.0 11485.5 11528.0 8848.8 8884.2 8994.4 9001.2 9019.8 8077.9 8321.3 8322.5 8424.5 8455.8 7754.5 8900.7 9986.5 10045.0 10260.3 8131.7 8976.4 9915.4 10022.5 10604.7 7091.8 7574.8 7866.6 7881.7 7905.8 7176.9 7331.3 8644.6 8698.8 8845.1 7757.1 7899.9 7910.4 7940.4 8045.0 7758.7 7771.1 7818.3 7862.2 7899.4 8208.9 8279.2 8293.1 8327.7 8333.5 7449.5 7543.6 7548.6 7556.4 7564.7 7556.8 7575.8 7662.3 7663.8 7665.4 17:03:45 INFO - PROCESS | 1675 | 17:03:45 INFO - PROCESS | 1675 | [#4] prototype.html Cycles:180 Average:6025.88 Median:6865.83 stddev:1976.38 (28.8%) stddev-sans-first:1935.06 17:03:45 INFO - PROCESS | 1675 | Values: 325.6 343.2 346.3 347.3 351.2 5404.9 6458.9 6932.1 6935.3 6945.1 5802.5 6872.5 6880.1 6895.8 6906.9 4895.9 4967.0 5080.0 5082.9 5092.1 5028.1 5068.8 5097.8 5099.8 5103.5 5016.3 5028.8 5042.9 5051.9 5074.5 5086.1 5111.2 5116.3 5123.2 5131.9 4403.7 4405.9 4407.9 4409.0 4414.4 6345.3 6626.5 6647.3 6680.6 6708.8 4726.0 4998.7 5653.5 5839.5 5893.3 7344.4 7354.4 7363.2 7366.7 7369.7 3832.4 4675.0 4689.2 4693.8 4712.8 6859.1 7436.1 7723.2 7819.4 7828.2 4725.3 4814.1 4820.7 4821.6 4880.0 4940.5 4945.4 4958.4 4959.1 4965.8 5931.4 5934.4 5937.8 5941.6 5952.0 7489.7 7495.0 7511.5 7516.1 7538.1 1626.6 1666.5 1680.1 1712.1 1714.1 938.9 1008.8 1083.4 1091.2 1093.5 6926.0 6968.4 6972.5 6986.6 6990.1 6545.6 6601.8 7228.5 7359.2 7728.5 5829.8 6576.9 7003.0 7047.4 7072.4 3771.2 4045.0 4100.3 4103.9 4106.0 6712.0 8118.0 8192.7 8210.9 8242.8 8052.6 8062.6 8066.0 8084.2 8088.2 8183.5 8188.9 8193.5 8224.1 8247.3 7570.2 7808.6 7849.2 7860.8 7876.0 7618.5 7664.7 7673.5 7675.8 7708.6 8180.8 8187.0 8197.5 8208.3 8224.8 3981.3 4845.2 4863.1 4871.3 4885.2 7520.4 7751.8 7789.1 7802.0 7818.7 7477.5 7489.7 7521.6 7531.0 7542.9 6173.4 7493.5 7495.2 7510.0 7532.8 5880.6 7138.2 7146.5 7328.7 7329.9 7467.0 7523.2 7524.5 7533.6 7534.4 5831.3 7309.4 7352.5 7371.8 7387.1 17:03:45 INFO - PROCESS | 1675 | 17:03:45 INFO - PROCESS | 1675 | [#5] yui.html Cycles:180 Average:19241.47 Median:27560.71 stddev:27905.10 (101.2%) stddev-sans-first:27948.71 17:03:45 INFO - PROCESS | 1675 | Values: 716.3 761.5 768.9 784.7 801.8 15151.0 15246.0 15410.5 15441.5 15549.0 14290.5 14391.6 14407.8 14452.0 14453.8 800.9 937.2 950.4 971.7 982.6 948.8 960.2 960.9 962.2 962.5 1025.6 1027.3 1028.9 1031.5 1031.9 1006.1 1010.3 1019.5 1019.5 1021.1 646.5 748.5 752.0 769.0 770.3 15342.9 15361.5 15374.4 15403.0 15442.2 11382.3 11508.2 12036.9 13401.5 13424.2 9561.0 10468.9 10485.7 10512.0 10673.6 3131.7 3617.2 3629.4 3746.1 3869.5 88448.0 89123.0 90981.5 91900.5 92551.5 9730.2 9872.1 9969.2 10009.1 10022.5 21976.1 22172.7 22229.0 22275.8 23397.2 7829.2 7887.5 7992.2 8173.2 8215.3 4226.5 4277.2 4305.5 4374.6 4385.9 3761.7 3813.1 3813.6 3821.1 3821.8 696.1 697.2 698.6 699.2 701.8 572.4 615.5 635.0 635.4 657.1 8071.7 8485.9 8569.5 8707.5 8846.4 606.7 618.7 631.7 632.6 638.6 3904.9 3976.8 3990.7 4020.0 4063.2 97015.5 97727.5 98121.5 98792.0 100746.0 63802.0 85700.6 86037.6 86915.0 87240.1 73948.3 76330.6 76656.0 77255.0 78995.0 7900.1 7982.2 8006.5 8320.4 8391.4 50917.0 52249.7 52735.5 53820.7 54549.0 66710.7 73629.3 74004.6 74237.4 74315.6 9887.5 10202.4 10326.1 10346.2 10381.0 11054.3 11165.3 11166.4 11295.4 12040.2 10307.3 10363.0 10577.3 10648.9 10669.9 10256.9 10402.7 10504.4 10676.6 11265.7 10343.6 10449.0 10450.8 10523.2 10866.2 9038.5 9134.7 9267.1 9370.7 9403.5 9034.9 9107.8 9245.5 9264.9 9299.8 17:03:45 INFO - PROCESS | 1675 | -------- Summary: end -------- 17:03:45 INFO - PROCESS | 1675 | 17:03:45 INFO - TEST-INFO | 1675: exit 0 17:03:45 INFO - TEST-OK | dromaeo_css | took 1110244ms 17:03:45 INFO - TEST-START | kraken 17:03:45 INFO - operating with platform_type : mac_ 17:03:45 INFO - Initialising browser for kraken test... 17:03:45 INFO - TEST-INFO | started process 1683 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/tmpo8fxQN/profile http://localhost:49277/getInfo.html) 17:03:48 INFO - PROCESS | 1683 | __metrics Screen width/height:1600/1200 17:03:48 INFO - PROCESS | 1683 | colorDepth:24 17:03:48 INFO - PROCESS | 1683 | Browser inner width/height: 1024/689 17:03:48 INFO - PROCESS | 1683 | __metrics 17:03:48 ERROR - PROCESS | 1683 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2844: TypeError: this.tabs is undefined 17:03:48 INFO - TEST-INFO | 1683: exit 0 17:03:48 INFO - Browser initialized. 17:03:48 INFO - Running cycle 1/1 for kraken test... 17:03:48 INFO - TEST-INFO | started process 1687 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/tmpo8fxQN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:04:00 INFO - PROCESS | 1687 | RSS: Main: 167763968 17:04:00 INFO - PROCESS | 1687 | 17:04:03 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 17:04:03 INFO - PROCESS | 1687 | RSS: Main: 179822592 17:04:03 INFO - PROCESS | 1687 | 17:04:06 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 17:04:07 INFO - PROCESS | 1687 | RSS: Main: 188776448 17:04:07 INFO - PROCESS | 1687 | 17:04:10 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 17:04:10 INFO - PROCESS | 1687 | RSS: Main: 192622592 17:04:10 INFO - PROCESS | 1687 | 17:04:12 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 17:04:13 INFO - PROCESS | 1687 | RSS: Main: 193916928 17:04:13 INFO - PROCESS | 1687 | 17:04:15 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:04:16 INFO - PROCESS | 1687 | RSS: Main: 194830336 17:04:16 INFO - PROCESS | 1687 | 17:04:19 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 17:04:19 INFO - PROCESS | 1687 | RSS: Main: 195080192 17:04:19 INFO - PROCESS | 1687 | 17:04:22 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 17:04:23 INFO - PROCESS | 1687 | RSS: Main: 195006464 17:04:23 INFO - PROCESS | 1687 | 17:04:26 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 17:04:26 INFO - PROCESS | 1687 | RSS: Main: 195330048 17:04:26 INFO - PROCESS | 1687 | 17:04:28 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:04:28 INFO - PROCESS | 1687 | RSS: Main: 194605056 17:04:28 INFO - PROCESS | 1687 | 17:04:30 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:04:31 INFO - PROCESS | 1687 | RSS: Main: 194859008 17:04:31 INFO - PROCESS | 1687 | 17:04:33 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:04:34 INFO - PROCESS | 1687 | RSS: Main: 194560000 17:04:34 INFO - PROCESS | 1687 | 17:04:36 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:04:37 INFO - PROCESS | 1687 | RSS: Main: 194506752 17:04:37 INFO - PROCESS | 1687 | 17:04:39 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:04:40 INFO - PROCESS | 1687 | RSS: Main: 194482176 17:04:40 INFO - PROCESS | 1687 | 17:04:41 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 17:04:42 INFO - PROCESS | 1687 | RSS: Main: 196116480 17:04:42 INFO - PROCESS | 1687 | 17:04:42 INFO - PROCESS | 1687 | __start_tp_report 17:04:42 INFO - PROCESS | 1687 | _x_x_mozilla_page_load 17:04:42 INFO - PROCESS | 1687 | _x_x_mozilla_page_load_details 17:04:42 INFO - PROCESS | 1687 | |i|pagename|runs| 17:04:42 INFO - PROCESS | 1687 | |0;ai-astar;119;144;115;115;116;128;117;116;113;116 17:04:42 INFO - PROCESS | 1687 | |1;audio-beat-detection;146;130;192;180;107;165;147;177;145;119 17:04:42 INFO - PROCESS | 1687 | |2;audio-dft;190;194;195;195;196;194;197;193;197;191 17:04:42 INFO - PROCESS | 1687 | |3;audio-fft;62;78;70;113;69;71;81;85;158;60 17:04:42 INFO - PROCESS | 1687 | |4;audio-oscillator;86;79;123;79;80;78;116;76;80;117 17:04:42 INFO - PROCESS | 1687 | |5;imaging-gaussian-blur;111;87;88;86;106;129;111;85;94;131 17:04:42 INFO - PROCESS | 1687 | |6;imaging-darkroom;93;102;150;122;94;120;94;96;93;93 17:04:42 INFO - PROCESS | 1687 | |7;imaging-desaturate;90;76;149;150;92;102;128;90;89;92 17:04:42 INFO - PROCESS | 1687 | |8;json-parse-financial;56;59;67;88;89;82;88;61;69;55 17:04:42 INFO - PROCESS | 1687 | |9;json-stringify-tinderbox;64;70;63;75;94;87;100;92;85;66 17:04:42 INFO - PROCESS | 1687 | |10;stanford-crypto-aes;83;82;83;82;83;81;80;83;81;85 17:04:42 INFO - PROCESS | 1687 | |11;stanford-crypto-ccm;118;118;132;132;130;137;109;109;133;118 17:04:42 INFO - PROCESS | 1687 | |12;stanford-crypto-pbkdf2;123;151;173;133;135;134;135;131;136;121 17:04:42 INFO - PROCESS | 1687 | |13;stanford-crypto-sha256-iterative;66;65;64;63;68;65;65;67;65;76 17:04:42 INFO - PROCESS | 1687 | __end_tp_report 17:04:42 INFO - PROCESS | 1687 | __start_cc_report 17:04:42 INFO - PROCESS | 1687 | _x_x_mozilla_cycle_collect,909 17:04:42 INFO - PROCESS | 1687 | __end_cc_report 17:04:42 INFO - PROCESS | 1687 | __startTimestamp1464653082230__endTimestamp 17:04:42 INFO - PROCESS | 1687 | 17:04:42 INFO - PROCESS | 1687 | ------- Summary: start ------- 17:04:42 INFO - PROCESS | 1687 | Number of tests: 14 17:04:42 INFO - PROCESS | 1687 | 17:04:42 INFO - PROCESS | 1687 | [#0] ai-astar Cycles:10 Average:119.90 Median:116.50 stddev:9.41 (8.1%) stddev-sans-first:9.97 17:04:42 INFO - PROCESS | 1687 | Values: 119.0 144.0 115.0 115.0 116.0 128.0 117.0 116.0 113.0 116.0 17:04:42 INFO - PROCESS | 1687 | 17:04:42 INFO - PROCESS | 1687 | [#1] audio-beat-detection Cycles:10 Average:150.80 Median:156.00 stddev:27.63 (17.7%) stddev-sans-first:29.25 17:04:42 INFO - PROCESS | 1687 | Values: 146.0 130.0 192.0 180.0 107.0 165.0 147.0 177.0 145.0 119.0 17:04:42 INFO - PROCESS | 1687 | 17:04:42 INFO - PROCESS | 1687 | [#2] audio-dft Cycles:10 Average:194.20 Median:195.00 stddev:2.35 (1.2%) stddev-sans-first:1.94 17:04:42 INFO - PROCESS | 1687 | Values: 190.0 194.0 195.0 195.0 196.0 194.0 197.0 193.0 197.0 191.0 17:04:42 INFO - PROCESS | 1687 | 17:04:42 INFO - PROCESS | 1687 | [#3] audio-fft Cycles:10 Average:84.70 Median:70.50 stddev:29.83 (42.3%) stddev-sans-first:30.49 17:04:42 INFO - PROCESS | 1687 | Values: 62.0 78.0 70.0 113.0 69.0 71.0 81.0 85.0 158.0 60.0 17:04:42 INFO - PROCESS | 1687 | 17:04:42 INFO - PROCESS | 1687 | [#4] audio-oscillator Cycles:10 Average:91.40 Median:79.00 stddev:19.07 (24.1%) stddev-sans-first:20.12 17:04:42 INFO - PROCESS | 1687 | Values: 86.0 79.0 123.0 79.0 80.0 78.0 116.0 76.0 80.0 117.0 17:04:42 INFO - PROCESS | 1687 | 17:04:42 INFO - PROCESS | 1687 | [#5] imaging-gaussian-blur Cycles:10 Average:102.80 Median:85.50 stddev:17.55 (20.5%) stddev-sans-first:18.36 17:04:42 INFO - PROCESS | 1687 | Values: 111.0 87.0 88.0 86.0 106.0 129.0 111.0 85.0 94.0 131.0 17:04:42 INFO - PROCESS | 1687 | 17:04:42 INFO - PROCESS | 1687 | [#6] imaging-darkroom Cycles:10 Average:105.70 Median:93.00 stddev:19.14 (20.6%) stddev-sans-first:19.74 17:04:42 INFO - PROCESS | 1687 | Values: 93.0 102.0 150.0 122.0 94.0 120.0 94.0 96.0 93.0 93.0 17:04:42 INFO - PROCESS | 1687 | 17:04:42 INFO - PROCESS | 1687 | [#7] imaging-desaturate Cycles:10 Average:105.80 Median:89.50 stddev:26.62 (29.7%) stddev-sans-first:27.61 17:04:42 INFO - PROCESS | 1687 | Values: 90.0 76.0 149.0 150.0 92.0 102.0 128.0 90.0 89.0 92.0 17:04:42 INFO - PROCESS | 1687 | 17:04:42 INFO - PROCESS | 1687 | [#8] json-parse-financial Cycles:10 Average:71.40 Median:75.50 stddev:14.01 (18.6%) stddev-sans-first:13.71 17:04:42 INFO - PROCESS | 1687 | Values: 56.0 59.0 67.0 88.0 89.0 82.0 88.0 61.0 69.0 55.0 17:04:42 INFO - PROCESS | 1687 | 17:04:42 INFO - PROCESS | 1687 | [#9] json-stringify-tinderbox Cycles:10 Average:79.60 Median:80.00 stddev:13.66 (17.1%) stddev-sans-first:13.27 17:04:42 INFO - PROCESS | 1687 | Values: 64.0 70.0 63.0 75.0 94.0 87.0 100.0 92.0 85.0 66.0 17:04:42 INFO - PROCESS | 1687 | 17:04:42 INFO - PROCESS | 1687 | [#10] stanford-crypto-aes Cycles:10 Average:82.30 Median:83.00 stddev:1.42 (1.7%) stddev-sans-first:1.48 17:04:42 INFO - PROCESS | 1687 | Values: 83.0 82.0 83.0 82.0 83.0 81.0 80.0 83.0 81.0 85.0 17:04:42 INFO - PROCESS | 1687 | 17:04:42 INFO - PROCESS | 1687 | [#11] stanford-crypto-ccm Cycles:10 Average:123.60 Median:131.00 stddev:10.38 (7.9%) stddev-sans-first:10.81 17:04:42 INFO - PROCESS | 1687 | Values: 118.0 118.0 132.0 132.0 130.0 137.0 109.0 109.0 133.0 118.0 17:04:42 INFO - PROCESS | 1687 | 17:04:42 INFO - PROCESS | 1687 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:137.20 Median:135.00 stddev:14.96 (11.1%) stddev-sans-first:14.96 17:04:42 INFO - PROCESS | 1687 | Values: 123.0 151.0 173.0 133.0 135.0 134.0 135.0 131.0 136.0 121.0 17:04:42 INFO - PROCESS | 1687 | 17:04:42 INFO - PROCESS | 1687 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.40 Median:65.50 stddev:3.66 (5.6%) stddev-sans-first:3.88 17:04:42 INFO - PROCESS | 1687 | Values: 66.0 65.0 64.0 63.0 68.0 65.0 65.0 67.0 65.0 76.0 17:04:42 INFO - PROCESS | 1687 | -------- Summary: end -------- 17:04:42 INFO - PROCESS | 1687 | 17:04:42 INFO - TEST-INFO | 1687: exit 0 17:04:42 INFO - TEST-OK | kraken | took 57177ms 17:04:42 INFO - SUITE-END | took 1167s 17:04:43 INFO - Completed test suite (00:19:27) 17:04:43 INFO - javascript benchmark 17:04:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3620.7827530348177, 3665.028767376644, 3695.9445608315896, 3701.8519259229615, 3709.017110465726, 5960.135780311857, 5974.1337506061645, 6004.159417681522, 6014.188084608577, 6026.4576188143055, 5879.470847623713, 5887.0, 5971.0744834550715, 5979.073243647242, 6036.641755604986, 5317.8936421271555, 5351.625386222966, 5413.377461591908, 5447.972760136194, 5485.588580856432, 5331.173668081454, 5452.182172674111, 5460.563154947595, 5471.0, 5484.478974497416, 5124.154514505102, 5372.838814835562, 5397.190421436777, 5398.568114550846, 5543.1408131739645, 5464.480874316953, 5498.505134537871, 5514.944850551483, 5536.307961504812, 5564.805231816867, 5358.812441564565, 5418.160185171305, 5433.972830135824, 5441.619086663935, 5449.373322067973, 5348.171033489815, 5860.062390017577, 5947.821565353003, 5973.223480947492, 5989.580729348947, 6248.562600617913, 6268.937310626881, 6282.842928926768, 6293.024581189923, 6336.081268216129, 6311.053341998691, 6331.620102793847, 6350.587211831216, 6350.682465876734, 6392.392722691384, 4684.882877928045, 5002.724850133278, 5630.746616402272, 6027.487663548603, 6178.629282243035, 6458.515611329169, 6462.32145624712, 6463.127477790532, 6465.1595292611655, 6468.6442245676, 6161.291451483136, 6200.441960223511, 6208.565400421927, 6234.812955611339, 6266.436020758062, 6346.873062538723, 6374.776882809079, 6388.8083357499345, 6401.4238718514625, 6421.967890160519, 6267.4986001119805, 6270.435660790551, 6278.686065696788, 6282.465990390868, 6290.685465726695, 6103.176071230416, 6246.125542424064, 6262.15460912771, 6273.215848018997, 6305.464035557029, 5060.544550990428, 5069.290299358093, 5079.517445842639, 5101.744912754348, 5129.256257842592, 4593.494715581283, 4595.931061034087, 4602.263637817933, 4602.309653551927, 4612.192866248461, 6082.2093127893095, 6087.9391206087375, 6104.116776136042, 6117.235345581802, 6143.481172719164, 6401.03984402334, 6410.391012853772, 6436.356364363526, 6437.195350581177, 6442.549021568539, 6068.362821903725, 6182.196314479089, 6192.845178870564, 6193.0, 6209.0996805462955, 5602.159676048544, 5646.0, 5664.971675141598, 5669.631473954262, 5713.857153571194, 6520.673966301666, 6576.934230657632, 6580.80257592273, 6606.141201643852, 6618.503612229102, 6218.232886365524, 6276.968615156895, 6323.20959880015, 6328.620282782864, 6353.491720662243, 6383.585066970632, 6402.935970640234, 6502.967485162544, 6541.6075035498025, 6544.44372228366, 6279.96860015697, 6284.151639528605, 6290.874182516415, 6296.307406185408, 6296.433321001132, 6195.411435913581, 6261.436470717657, 6305.653189074462, 6334.398232167937, 6335.461485773577, 6107.786227482017, 6177.752889884534, 6233.937660623335, 6242.843928901813, 6272.8118156455375, 6244.500439964701, 6246.906296405648, 6265.843353916189, 6267.2479302484, 6289.6540690260645, 6082.787102451393, 6103.7253323601435, 6209.379062093935, 6223.626582405071, 6245.656488893154, 6226.782062627786, 6236.469900058546, 6242.0, 6246.781362652286, 6269.717862696099, 6110.602810817282, 6153.0, 6156.476699480415, 6165.6608886509875, 6191.9690401547705], "unit": "score", "name": "dojo.html", "value": 5883.34342755827}, {"lowerIsBetter": false, "replicates": [520.040525230949, 530.7372850439033, 565.2538648983341, 612.0757655939535, 624.8656538844149, 36071.0, 39426.8028659857, 39475.60524394755, 39503.80248098759, 39546.40680389797, 41258.79370603143, 41838.0, 41911.0, 41914.58085419145, 41944.16111677764, 9656.179224765903, 9858.408495490257, 9870.654527091554, 9938.254630902675, 9946.65186718465, 9622.0, 9933.155681767059, 9934.751631209205, 9963.202943764518, 10010.749731256705, 9914.702558923207, 9973.50132493376, 10003.849942250872, 10032.197424206082, 10041.347312424668, 9855.0, 9959.302848800588, 9988.101070903616, 10016.09855113036, 10038.698839034842, 8465.0, 9792.363496372713, 9957.0, 9957.103860652576, 10021.649242276559, 40542.189156217006, 40565.18869622621, 40738.59261407378, 40759.59240407588, 40782.79608601938, 3393.694567488938, 3462.117160124177, 3514.6661067198575, 3585.587657419404, 3601.3877640800865, 27485.0, 28322.85838570794, 28429.00498482564, 28443.0, 28646.856765716246, 2759.7378249066305, 2794.0311599764577, 2824.6751623563346, 2842.7725781937397, 2884.524053531165, 465130.0, 482583.0, 522593.0, 527349.0, 530420.0, 48839.5116048835, 75338.0, 75909.0, 76004.23995759971, 76539.23460765432, 47901.0, 48243.75878120587, 48246.03507929822, 48317.792055198195, 48322.791930201325, 16877.32490700358, 16921.069341186354, 17345.2194651241, 17355.30578776835, 17384.391546295818, 13754.968377371537, 13808.309584520734, 13868.583942481742, 13902.304884755722, 13902.51341203053, 15379.0, 15472.0, 15792.289346979414, 15808.209589520478, 15843.049417035014, 6735.0805020568905, 6788.321167883271, 6829.0, 6835.555688880207, 6847.931520684829, 961.5096300886598, 1057.3867157048894, 1139.8290256461432, 1161.5353858456515, 1162.918595698293, 25349.61975570368, 26777.464450710875, 30151.547726784116, 31556.526652100238, 32567.674323256466, 1017.5878769098526, 1087.3530249501534, 1131.468725807403, 1155.364549497773, 1228.1157566552067, 15356.462523811613, 15891.443799467195, 15952.92023539875, 15953.04281743076, 16385.83614163867, 691982.0, 696221.0, 696556.0, 696776.0, 700710.0, 64964.0, 65628.67185664136, 66075.0, 66187.66906165535, 66311.0, 1542.5218182363503, 1561.9843801562067, 1582.8179759327595, 1584.3741722019968, 1591.1089789717796, 26169.476610468064, 31039.0, 31080.533791992686, 31311.84344078265, 31970.0, 56234.437655624555, 56600.716996414754, 56978.0, 57177.0, 57243.71378143083, 511010.0, 516690.0, 517721.0, 519610.0, 519975.0, 34206.657933421346, 34732.65267347294, 35377.29245415026, 35446.0, 36031.27937441289, 47674.0, 47934.76032619815, 48289.75855120702, 49006.264906025724, 49228.75385623049, 38084.619153808104, 38753.0, 39012.024699382746, 39351.40972885466, 39640.40539391854, 37884.62115378811, 38363.23273534569, 38924.80537597294, 39449.0, 39764.40353394643, 33365.83317083399, 38846.80576597099, 38847.223055539296, 39038.024049397856, 39877.003074923356, 27547.86226068857, 32123.51814722828, 33741.0, 33863.83068084742, 33903.830480848424, 21154.788452115896, 25296.735163241396, 25400.6734006734, 25632.48735025252, 26044.218673439827], "unit": "score", "name": "ext.html", "value": 20331.967778794155}, {"lowerIsBetter": false, "replicates": [379.19799623795683, 382.82390100553744, 390.05800990607685, 392.7741548609549, 395.44440061713266, 20112.0, 20452.36381089513, 20580.38258852233, 23048.769512304872, 23163.652545211837, 22335.553288934254, 22378.88810555945, 22384.0, 22665.320040398772, 22823.54352912941, 5032.345795046657, 5910.556708246877, 6049.455549000589, 6157.661328626924, 6167.753289868411, 4459.888502787424, 6414.743410263607, 6557.639329836857, 6600.735970561172, 6663.967085101793, 5712.9428705713035, 6538.1827271591055, 6574.8685026299445, 6586.901196482057, 6607.80176594703, 5803.274590676187, 5821.330546987108, 5918.319393269786, 5963.135345374918, 5997.490213331872, 5045.672031317952, 5634.12671035986, 5939.851503712399, 5969.194158788595, 5977.372375900555, 13910.930445347809, 13935.512257071056, 14245.85754142466, 22235.44411389712, 25789.484210315877, 2275.988620056906, 2293.9655905161435, 2316.791488766002, 2441.7680320369536, 2656.269525880379, 8422.28410585101, 8788.8681669775, 9871.802563948753, 10027.197824174049, 10080.0, 1691.8900271482437, 1699.1504247876062, 1761.1898526677744, 1795.1831916477972, 1809.787442413586, 102020.0, 102722.0, 107044.46477767639, 107612.4619376898, 110472.4476377621, 11411.771764564663, 11655.825162622568, 11823.645290641294, 11854.229475084092, 11870.050395968301, 11761.823572646417, 11962.521499140112, 14427.78358324626, 14439.35022923971, 14511.0, 8420.39281236943, 8839.734807955772, 8935.329850261256, 8958.05940376251, 9016.323775716848, 8097.433179677366, 9282.535873206312, 9327.300452465957, 9333.020032896584, 9373.531323433801, 3799.2971300308977, 4580.38164847748, 4598.908021839611, 4628.791704373311, 4650.093231819763, 1787.1064467766116, 1825.7402392349236, 1833.9266429342676, 1862.6833438315518, 1937.6899696048847, 949.520492151459, 952.7951490429591, 1036.2176556699644, 1057.1701214546545, 1058.9841152382567, 20274.89862550678, 20971.161153553992, 21077.0, 21388.465288367915, 22005.889970550044, 991.8561808537721, 1012.3875055591409, 1031.4120951057826, 1053.3837704942694, 1101.6474728086935, 9497.287703422271, 9560.378575392577, 11610.361430121424, 11731.178817482865, 11802.46888890002, 694244.0, 694854.0, 696223.0, 705538.0, 709600.0, 122009.0, 122342.39725034966, 123457.0, 124786.0, 130349.0, 933.803901180746, 1054.5254635413942, 1058.2645061682058, 1061.6072053340313, 1067.9252452328417, 15754.606134846721, 19425.902870485555, 19592.510187245432, 19722.605547888674, 19863.205471780966, 51081.48918510916, 52108.478915211876, 52707.20939185992, 52938.0, 53394.932101358536, 66350.67772627935, 74249.0, 74706.87939681018, 79698.81957672733, 80054.0, 8267.007959044953, 8407.831843363221, 8532.360072994674, 8685.565721714016, 8796.988346339996, 12252.877471225174, 12756.170848894792, 13125.474981001034, 13307.401166947704, 13358.666033348854, 10151.390916544735, 10233.590656373663, 10285.382877027403, 10872.075873550837, 11229.3262404256, 10603.787924241626, 11065.170112241774, 11228.270162439741, 11416.31502109876, 11509.884901150881, 11645.475953582278, 11744.236624619372, 11828.940855295668, 11872.347020913932, 11984.340861252715, 8170.346372290083, 8367.28878045373, 8368.0, 8401.411901166859, 8546.316294696286, 8676.584186788066, 10385.428801415994, 11743.001844843284, 11776.411179440822, 12057.095717821374], "unit": "score", "name": "jquery.html", "value": 9634.940570275952}, {"lowerIsBetter": false, "replicates": [1633.4808627975985, 1687.5274923021555, 2024.4736368544175, 2082.4481512399216, 2112.260708751936, 8389.161083891608, 9100.271978241724, 9177.54112294385, 9362.85955710664, 9386.48374339411, 9008.54957252138, 9097.408668436547, 9161.17549420552, 9185.724428267162, 9218.032106628809, 2576.6135079738046, 2585.58630619101, 2597.000154940343, 2598.9610155847677, 2603.713591504933, 2555.1440267510407, 2607.5436798560177, 2610.765031147196, 2611.738826117392, 2612.6603541539575, 2581.7676409123173, 2598.259496043628, 2619.8559079250635, 2630.6974697909795, 2630.828996115256, 2604.960925586118, 2605.2054123492326, 2624.5407053765516, 2626.474705058996, 2629.368951451657, 2574.6652935118314, 2592.520383729016, 2606.882690278942, 2622.5279449699046, 2626.6716660417446, 9329.813403731956, 9432.669856554987, 9463.763405914839, 9487.288453365956, 9498.19265362445, 5811.564132690023, 5931.584789064767, 5970.223870896755, 5989.341172471025, 6026.638401695912, 3673.210259794157, 3748.7188460865545, 3859.9228015439817, 3865.768053916774, 3902.2975864344407, 1079.1043433949803, 1088.2327958789035, 1091.9235653504259, 1131.3099009604134, 1137.5108703257597, 12594.118411711184, 12658.810117848148, 12680.429380677799, 12680.936595317056, 12688.682782930407, 5146.30524879144, 5190.091733946544, 5228.738563071831, 5267.604929630216, 5279.155335146358, 10711.678649640524, 10761.623343182951, 10828.891711082946, 10835.18736094796, 10845.295055821347, 5736.311642602915, 5939.910901336483, 5985.0, 5990.9101363479585, 5998.0, 4760.880977975578, 5133.281340612317, 5188.766505507261, 5237.73811309433, 5327.227552005015, 4147.6267135957905, 4160.417541544186, 4172.269852775752, 4191.937120943188, 4206.72656277341, 1379.9379027943567, 1399.2793711238721, 1401.5585090696397, 1411.3648858013935, 1413.6889884225454, 1404.1154072933987, 1420.3324437514352, 1434.4262295081842, 1451.122071146941, 1473.0057211382275, 8546.31629469641, 8659.870101948476, 8688.869666955, 8719.084496127942, 8729.258013068833, 539.1050855579797, 546.4535464535464, 550.0813641219173, 554.4649413316089, 554.7226386806597, 6340.556161068681, 6397.776077837253, 6406.135171751754, 6407.871842563122, 6501.447376972916, 11197.49611267495, 11479.13906457019, 11676.357800320893, 11686.883131168579, 11701.707457313465, 11203.935626115506, 11242.943785281184, 11414.029807466291, 11485.483153257956, 11528.02011828987, 8848.778780530409, 8884.200421961925, 8994.415363001384, 9001.234895033995, 9019.819603608023, 8077.919220807717, 8321.292690121407, 8322.45904016237, 8424.536650484282, 8455.788605284917, 7754.457187997012, 8900.688475903571, 9986.450745208793, 10045.045720656526, 10260.333078349884, 8131.67473301085, 8976.371653984159, 9915.355501892354, 10022.548985295825, 10604.681859543918, 7091.82270443243, 7574.810629734301, 7866.567338796192, 7881.684732610632, 7905.802354941173, 7176.928230717626, 7331.340179383881, 8644.610992505479, 8698.782530436789, 8845.115488451103, 7757.1079325878245, 7899.881501777593, 7910.406719495946, 7940.364770818205, 8045.0, 7758.728444504415, 7771.067471903408, 7818.296353328228, 7862.213778622092, 7899.407544434305, 8208.876866847122, 8279.172082791672, 8293.087760346478, 8327.6668933242, 8333.458325208841, 7449.478536502393, 7543.585102819397, 7548.584827834522, 7556.433267505069, 7564.659590318559, 7556.811079723051, 7575.848483030199, 7662.310392064741, 7663.846723065619, 7665.38676905835], "unit": "score", "name": "mootools.html", "value": 5143.761335848128}, {"lowerIsBetter": false, "replicates": [325.60154137977383, 343.22088858291664, 346.31256954944433, 347.32618719683813, 351.2045217582176, 5404.864978354545, 6458.903116453251, 6932.098827152476, 6935.271796461375, 6945.131858517685, 5802.477777000069, 6872.518923675345, 6880.139982502187, 6895.793126206222, 6906.893397513756, 4895.926561101586, 4967.0, 5079.974600126994, 5082.898342033157, 5092.134337162691, 5028.120078986163, 5068.8225912093085, 5097.847064588068, 5099.821506247282, 5103.4896510349035, 5016.27264046713, 5028.84913452597, 5042.865355295066, 5051.873703157414, 5074.467180946003, 5086.084504789155, 5111.2333150027425, 5116.258142569307, 5123.1546794778815, 5131.871703207413, 4403.669724770655, 4405.867823965286, 4407.898025493627, 4409.0, 4414.426124603813, 6345.302016778151, 6626.503012274099, 6647.302033286532, 6680.632565208912, 6708.83227919301, 4726.007538416937, 4998.6750861193905, 5653.547716182696, 5839.532837373, 5893.292804863401, 7344.412447004227, 7354.411647068222, 7363.190049094595, 7366.705331786722, 7369.705211791522, 3832.4059770735303, 4674.976625116853, 4689.249720044811, 4693.7653117344, 4712.835050773241, 6859.142607174103, 7436.144843343085, 7723.150453450116, 7819.413543984224, 7828.217178282126, 4725.29120631908, 4814.109389762905, 4820.68665536739, 4821.566059054702, 4879.975600121977, 4940.530649588283, 4945.357103576512, 4958.404991401055, 4959.107360675113, 4965.776540055706, 5931.436513531207, 5934.43622855828, 5937.762489500372, 5941.55438342126, 5952.017917043726, 7489.700411983568, 7495.0, 7511.51175173612, 7516.098068231847, 7538.133114691771, 1626.6096136927051, 1666.5083800278946, 1680.134730613735, 1712.1182590965664, 1714.1429285357322, 938.8685584018243, 1008.8184126857236, 1083.4041277297456, 1091.1870656361061, 1093.4915264401932, 6926.0, 6968.407685346802, 6972.511924165361, 6986.615736134561, 6990.056342393712, 6545.574537655038, 6601.801945941629, 7228.494005419675, 7359.153697324769, 7728.497647652885, 5829.795957141479, 6576.914809056452, 7002.964985175144, 7047.40097091753, 7072.398846098036, 3771.1891943232336, 4044.9797751011056, 4100.302948498763, 4103.938440923389, 4105.97947010263, 6711.966440167866, 8117.959410202911, 8192.672293108328, 8210.932578764723, 8242.835143297101, 8052.637631306605, 8062.596870156469, 8065.9596702016115, 8084.191580841869, 8088.231617996281, 8183.508989460533, 8188.877166842502, 8193.4674246175, 8224.095349511548, 8247.340212782843, 7570.242975702606, 7808.570528620753, 7849.1758365370815, 7860.764177074697, 7876.0, 7618.542887426551, 7664.73173438927, 7673.501222420525, 7675.846483070419, 7708.576028318496, 8180.83638327242, 8186.97662792151, 8197.508149511048, 8208.261256486945, 8224.83550329002, 3981.3032719274593, 4845.248986407112, 4863.051704917507, 4871.318015477907, 4885.169521181337, 7520.360769334668, 7751.806204844924, 7789.10425301075, 7802.0, 7818.687252510063, 7477.541879333696, 7489.737859174903, 7521.623918804147, 7530.962345188239, 7542.886856697263, 6173.382661733791, 7493.475456717977, 7495.213002634863, 7510.023696919366, 7532.849343012999, 5880.647161170344, 7138.179109402485, 7146.535475194096, 7328.743493977685, 7329.890051649336, 7466.962665186639, 7523.172451030492, 7524.548527088392, 7533.6233188339265, 7534.397248220239, 5831.271091113611, 7309.415246780139, 7352.5220860643885, 7371.8157046074275, 7387.076615423072], "unit": "score", "name": "prototype.html", "value": 5362.6842582405725}, {"lowerIsBetter": false, "replicates": [716.3373879161776, 761.478387304696, 768.8923550702903, 784.6508303804802, 801.7795106345754, 15151.0, 15246.0, 15410.460633877816, 15441.536753897388, 15549.0, 14290.499832505864, 14391.64020899478, 14407.78388324176, 14452.0, 14453.78319325211, 800.8958835351397, 937.1659223291259, 950.3964982236295, 971.6987733802508, 982.6462473509531, 948.8292107420662, 960.159860122393, 960.8606752020953, 962.2494454325637, 962.5331714118682, 1025.5846382215216, 1027.2501074215827, 1028.9485525723721, 1031.494567661848, 1031.922605804564, 1006.0995409108843, 1010.2877471382658, 1019.5004447820552, 1019.5004447820552, 1021.0555236406294, 646.4569761400446, 748.5097261293806, 751.9699212031567, 768.9653965571507, 770.3375097416217, 15342.923285383613, 15361.46234881785, 15374.385024599003, 15402.998805077634, 15442.227888605637, 11382.317060976367, 11508.194426390157, 12036.939815300955, 13401.46394144233, 13424.194548327034, 9561.0, 10468.895311046868, 10485.68542943713, 10512.0013598708, 10673.573057077785, 3131.686831316873, 3617.222297206113, 3629.401148810443, 3746.082209858575, 3869.4776205212215, 88448.0, 89123.0, 90981.54509227411, 91900.54049729709, 92551.53724231335, 9730.172935300438, 9872.062154095349, 9969.152622027064, 10009.149222316184, 10022.498875056219, 21976.120955161936, 22172.667409988862, 22229.0, 22275.777242227694, 23397.181098661466, 7829.217078292125, 7887.487313324616, 7992.240737129963, 8173.18268173178, 8215.301699355621, 4226.450561426995, 4277.230098582285, 4305.461817272841, 4374.58441448062, 4385.934210986838, 3761.6990640748672, 3813.122981714221, 3813.637704418076, 3821.063839359375, 3821.8280177392085, 696.0742212856889, 697.1808125452573, 698.6297262450909, 699.2029086841005, 701.7649087555624, 572.4172662587947, 615.4737699267151, 634.9587276826992, 635.4090695652994, 657.1489920552896, 8071.717489887943, 8485.872711909326, 8569.528675922884, 8707.47755134694, 8846.424982376126, 606.7027156693189, 618.7401291457599, 631.709413669717, 632.610944269271, 638.6104476269472, 3904.921901561953, 3976.781277029791, 3990.700697447645, 4019.959800402017, 4063.167050754588, 97015.51492242493, 97727.53408698876, 98121.50939245259, 98792.01207987829, 100745.99254007365, 63802.0, 85700.57149714211, 86037.55849766065, 86915.0, 87240.12759872321, 73948.26051739522, 76330.61834690791, 76656.0, 77254.99999999888, 78995.0, 7900.051993760785, 7982.241687039722, 8006.479578827358, 8320.375971802017, 8391.370647201606, 50916.981660367324, 52249.738751306, 52735.472645273054, 53820.73089634527, 54549.0, 66710.66644666939, 73629.26370736225, 74004.62997684977, 74237.40169094056, 74315.62842185755, 9887.456189909337, 10202.438865862152, 10326.122279606318, 10346.172306215636, 10381.0, 11054.281471704313, 11165.330080195214, 11166.385848778395, 11295.435228238392, 12040.157188996685, 10307.330023548446, 10362.989466109488, 10577.31247468912, 10648.89351106479, 10669.946650266698, 10256.897431025893, 10402.687919362432, 10504.369737815756, 10676.572937082741, 11265.662030138781, 10343.648910399603, 10449.0, 10450.843237351293, 10523.210759192938, 10866.239363244813, 9038.502882341534, 9134.725958221265, 9267.119623635745, 9370.67202647904, 9403.4828084456, 9034.864477032981, 9107.772305692411, 9245.537723113952, 9264.907350926675, 9299.767505812139], "unit": "score", "name": "yui.html", "value": 7106.077255678973}], "name": "dromaeo_css", "value": 7804.224427455748}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [119.0, 144.0, 115.0, 115.0, 116.0, 128.0, 117.0, 116.0, 113.0, 116.0], "unit": "score", "name": "ai-astar", "value": 119.9}, {"lowerIsBetter": true, "replicates": [146.0, 130.0, 192.0, 180.0, 107.0, 165.0, 147.0, 177.0, 145.0, 119.0], "unit": "score", "name": "audio-beat-detection", "value": 150.8}, {"lowerIsBetter": true, "replicates": [190.0, 194.0, 195.0, 195.0, 196.0, 194.0, 197.0, 193.0, 197.0, 191.0], "unit": "score", "name": "audio-dft", "value": 194.2}, {"lowerIsBetter": true, "replicates": [62.0, 78.0, 70.0, 113.0, 69.0, 71.0, 81.0, 85.0, 158.0, 60.0], "unit": "score", "name": "audio-fft", "value": 84.7}, {"lowerIsBetter": true, "replicates": [86.0, 79.0, 123.0, 79.0, 80.0, 78.0, 116.0, 76.0, 80.0, 117.0], "unit": "score", "name": "audio-oscillator", "value": 91.4}, {"lowerIsBetter": true, "replicates": [111.0, 87.0, 88.0, 86.0, 106.0, 129.0, 111.0, 85.0, 94.0, 131.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 102.8}, {"lowerIsBetter": true, "replicates": [93.0, 102.0, 150.0, 122.0, 94.0, 120.0, 94.0, 96.0, 93.0, 93.0], "unit": "score", "name": "imaging-darkroom", "value": 105.7}, {"lowerIsBetter": true, "replicates": [90.0, 76.0, 149.0, 150.0, 92.0, 102.0, 128.0, 90.0, 89.0, 92.0], "unit": "score", "name": "imaging-desaturate", "value": 105.8}, {"lowerIsBetter": true, "replicates": [56.0, 59.0, 67.0, 88.0, 89.0, 82.0, 88.0, 61.0, 69.0, 55.0], "unit": "score", "name": "json-parse-financial", "value": 71.4}, {"lowerIsBetter": true, "replicates": [64.0, 70.0, 63.0, 75.0, 94.0, 87.0, 100.0, 92.0, 85.0, 66.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 79.6}, {"lowerIsBetter": true, "replicates": [83.0, 82.0, 83.0, 82.0, 83.0, 81.0, 80.0, 83.0, 81.0, 85.0], "unit": "score", "name": "stanford-crypto-aes", "value": 82.3}, {"lowerIsBetter": true, "replicates": [118.0, 118.0, 132.0, 132.0, 130.0, 137.0, 109.0, 109.0, 133.0, 118.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 123.6}, {"lowerIsBetter": true, "replicates": [123.0, 151.0, 173.0, 133.0, 135.0, 134.0, 135.0, 131.0, 136.0, 121.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 137.2}, {"lowerIsBetter": true, "replicates": [66.0, 65.0, 64.0, 63.0, 68.0, 65.0, 65.0, 67.0, 65.0, 76.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 66.4}], "name": "kraken", "value": 1515.8}]} 17:04:43 INFO - Return code: 0 17:04:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 17:04:43 ERROR - # TBPL SUCCESS # 17:04:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:04:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:04:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:04:43 INFO - 2016-05-30 17:04:43.549 screenresolution[1692:11805] starting screenresolution argv=screenresolution get 17:04:43 INFO - 2016-05-30 17:04:43.552 screenresolution[1692:11805] Display 0: 1600x1200x32@60 17:04:43 INFO - 2016-05-30 17:04:43.558 screenresolution[1693:11808] starting screenresolution argv=screenresolution list 17:04:43 INFO - Available Modes on Display 0 17:04:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:04:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:04:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:04:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:04:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:04:43 INFO - Intel Iris: 17:04:43 INFO - Chipset Model: Intel Iris 17:04:43 INFO - Type: GPU 17:04:43 INFO - Bus: Built-In 17:04:43 INFO - VRAM (Dynamic, Max): 1536 MB 17:04:43 INFO - Vendor: Intel (0x8086) 17:04:43 INFO - Device ID: 0x0a2e 17:04:43 INFO - Revision ID: 0x0009 17:04:43 INFO - Displays: 17:04:43 INFO - Display: 17:04:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:04:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:04:43 INFO - Display Serial Number: 1600x1200 60 17:04:43 INFO - Main Display: Yes 17:04:43 INFO - Mirror: Off 17:04:43 INFO - Online: Yes 17:04:43 INFO - Rotation: Supported 17:04:43 INFO - Return code: 0 17:04:43 INFO - Running post-action listener: _resource_record_post_action 17:04:43 INFO - Running post-run listener: _resource_record_post_run 17:04:44 INFO - Total resource usage - Wall time: 1192s; CPU: 53.0%; Read bytes: 31571456; Write bytes: 513566208; Read time: 193; Write time: 1638 17:04:44 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 196844032; Write bytes: 197711360; Read time: 17881; Write time: 452 17:04:44 INFO - run-tests - Wall time: 1170s; CPU: 53.0%; Read bytes: 30649856; Write bytes: 305893376; Read time: 182; Write time: 1153 17:04:44 INFO - Running post-run listener: _upload_blobber_files 17:04:44 INFO - Blob upload gear active. 17:04:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:04:44 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 17:04:44 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-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:04:44 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-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:04:45 INFO - (blobuploader) - INFO - Open directory for files ... 17:04:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:04:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:04:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:04:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:04:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:04:46 INFO - (blobuploader) - INFO - Done attempting. 17:04:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:04:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:04:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:04:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:04:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:04:47 INFO - (blobuploader) - INFO - Done attempting. 17:04:47 INFO - (blobuploader) - INFO - Iteration through files over. 17:04:47 INFO - Return code: 0 17:04:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:04:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:04:47 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1c188eef5c32171662a866537eb1b74324bc52ab90ee960436a3b2e701f35145067ab8bcee97589f6eaf5a8a4aaa57495e38c46cbb7789533b02362c0a8eb99e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5062076c053550136afd2aaf04b2f669ad6c15763ece95877cc9089d4d297acec44d6985327918ecbfadfe9f1161f550671d43829443afcdb3f50b2302d34b31"} 17:04:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:04:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:04:47 INFO - Contents: 17:04:47 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1c188eef5c32171662a866537eb1b74324bc52ab90ee960436a3b2e701f35145067ab8bcee97589f6eaf5a8a4aaa57495e38c46cbb7789533b02362c0a8eb99e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5062076c053550136afd2aaf04b2f669ad6c15763ece95877cc9089d4d297acec44d6985327918ecbfadfe9f1161f550671d43829443afcdb3f50b2302d34b31"} 17:04:47 INFO - Running post-run listener: copy_logs_to_upload_dir 17:04:47 INFO - Copying logs to upload dir... 17:04:47 INFO - mkdir: /builds/slave/test/build/upload/logs 17:04:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1351.352657 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 31 secs) (at 2016-05-30 17:04:47.736365) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 17:04:47.740148) ========= 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.xGdUDAfZFX/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.wEzhRjUDZN/Listeners TMPDIR=/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1c188eef5c32171662a866537eb1b74324bc52ab90ee960436a3b2e701f35145067ab8bcee97589f6eaf5a8a4aaa57495e38c46cbb7789533b02362c0a8eb99e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5062076c053550136afd2aaf04b2f669ad6c15763ece95877cc9089d4d297acec44d6985327918ecbfadfe9f1161f550671d43829443afcdb3f50b2302d34b31"} build_url:https://queue.taskcluster.net/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011071 build_url: 'https://queue.taskcluster.net/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1c188eef5c32171662a866537eb1b74324bc52ab90ee960436a3b2e701f35145067ab8bcee97589f6eaf5a8a4aaa57495e38c46cbb7789533b02362c0a8eb99e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5062076c053550136afd2aaf04b2f669ad6c15763ece95877cc9089d4d297acec44d6985327918ecbfadfe9f1161f550671d43829443afcdb3f50b2302d34b31"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 17:04:47.770060) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 17:04:47.770358) ========= 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.xGdUDAfZFX/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.wEzhRjUDZN/Listeners TMPDIR=/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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.005099 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 17:04:47.818888) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 17:04:47.819220) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 17:04:47.819626) ========= ========= Total master_lag: 0.23 =========