builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0112 starttime: 1464651733.63 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.625896) ========= 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.626332) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 16:42:13.626636) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 16:42:14.178545) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 16:42:14.178922) ========= 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.OyZg6PZdlu/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.qi3f9DE4qn/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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% 10.8M=0.001s 2016-05-30 16:42:14 (10.8 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.134623 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 16:42:14.339679) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 16:42:14.340051) ========= 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.OyZg6PZdlu/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.qi3f9DE4qn/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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.028081 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 16:42:14.388434) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-30 16:42:14.388796) ========= 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.OyZg6PZdlu/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.qi3f9DE4qn/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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,458 truncating revision to first 12 chars 2016-05-30 16:42:14,458 Setting DEBUG logging. 2016-05-30 16:42:14,458 attempt 1/10 2016-05-30 16:42:14,458 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:15,706 unpacking tar archive at: mozilla-beta-417e1ec42d8f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.506599 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-30 16:42:15.915333) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 16:42:15.915665) ========= script_repo_revision: 417e1ec42d8f68c55e52c336cdf81e83323db88c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 16:42:15.916154) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 16:42:15.916470) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 16:42:15.933536) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 23 mins, 8 secs) (at 2016-05-30 16:42:15.933904) ========= /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.OyZg6PZdlu/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.qi3f9DE4qn/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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-0112', 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:17 INFO - Running post-action listener: _resource_record_post_action 16:42:17 INFO - ##### 16:42:17 INFO - ##### Running read-buildbot-config step. 16:42:17 INFO - ##### 16:42:17 INFO - Running pre-action listener: _resource_record_pre_action 16:42:17 INFO - Running main action method: read_buildbot_config 16:42:17 INFO - Using buildbot properties: 16:42:17 INFO - { 16:42:17 INFO - "project": "", 16:42:17 INFO - "product": "firefox", 16:42:17 INFO - "script_repo_revision": "production", 16:42:17 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 16:42:17 INFO - "repository": "", 16:42:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 16:42:17 INFO - "buildid": "20160526112438", 16:42:17 INFO - "pgo_build": "False", 16:42:17 INFO - "basedir": "/builds/slave/test", 16:42:17 INFO - "buildnumber": 26, 16:42:17 INFO - "slavename": "t-yosemite-r7-0112", 16:42:17 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 16:42:17 INFO - "platform": "yosemite_r7", 16:42:17 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 16:42:17 INFO - "branch": "mozilla-beta", 16:42:17 INFO - "repo_path": "releases/mozilla-beta", 16:42:17 INFO - "revision": "417e1ec42d8f68c55e52c336cdf81e83323db88c", 16:42:17 INFO - "stage_platform": "macosx64", 16:42:17 INFO - "builduid": "7b7803f5dee74ad79ba6e8f098a8fa00", 16:42:17 INFO - "slavebuilddir": "test" 16:42:17 INFO - } 16:42:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.dmg. 16:42:17 INFO - Running post-action listener: _resource_record_post_action 16:42:17 INFO - ##### 16:42:17 INFO - ##### Running download-and-extract step. 16:42:17 INFO - ##### 16:42:17 INFO - Running pre-action listener: _resource_record_pre_action 16:42:17 INFO - Running main action method: download_and_extract 16:42:17 INFO - mkdir: /builds/slave/test/build/tests 16:42:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:42:17 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:17 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:17 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:17 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:17 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:30 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.test_packages.json: timed out 16:43:30 INFO - retry: attempt #2 caught exception: timed out 16:43:30 INFO - retry: Failed, sleeping 60 seconds before retrying 16:44:30 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 #3 16:44:38 INFO - Downloaded 1241 bytes. 16:44:38 INFO - Reading from file /builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json 16:44:38 INFO - Using the following test package requirements: 16:44:38 INFO - {u'common': [u'firefox-47.0.en-US.mac.common.tests.zip'], 16:44:38 INFO - u'cppunittest': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:44:38 INFO - u'firefox-47.0.en-US.mac.cppunittest.tests.zip'], 16:44:38 INFO - u'gtest': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:44:38 INFO - u'firefox-47.0.en-US.mac.gtest.tests.zip'], 16:44:38 INFO - u'jittest': [u'firefox-47.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 16:44:38 INFO - u'mochitest': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:44:38 INFO - u'firefox-47.0.en-US.mac.mochitest.tests.zip'], 16:44:38 INFO - u'mozbase': [u'firefox-47.0.en-US.mac.common.tests.zip'], 16:44:38 INFO - u'reftest': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:44:38 INFO - u'firefox-47.0.en-US.mac.reftest.tests.zip'], 16:44:38 INFO - u'talos': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:44:38 INFO - u'firefox-47.0.en-US.mac.talos.tests.zip'], 16:44:38 INFO - u'web-platform': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:44:38 INFO - u'firefox-47.0.en-US.mac.web-platform.tests.zip'], 16:44:38 INFO - u'webapprt': [u'firefox-47.0.en-US.mac.common.tests.zip'], 16:44:38 INFO - u'xpcshell': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:44:38 INFO - u'firefox-47.0.en-US.mac.xpcshell.tests.zip']} 16:44:38 INFO - Downloading packages: [u'firefox-47.0.en-US.mac.common.tests.zip'] for test suite category: common 16:44:38 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:38 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:38 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:38 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:38 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:38 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:39 INFO - Downloaded 17816939 bytes. 16:44:39 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:39 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:42 INFO - Return code: 0 16:44:42 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: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 - 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:42 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:42 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:42 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:42 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:45 INFO - Downloaded 17816939 bytes. 16:44:45 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:45 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:48 INFO - Return code: 0 16:44:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:44:48 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:48 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:48 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:48 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:48 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:49 INFO - Downloaded 10845194 bytes. 16:44:49 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:49 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:49 INFO - Return code: 0 16:44:49 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:49 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:49 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:49 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:49 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:49 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:52 INFO - Downloaded 88467627 bytes. 16:44:52 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:52 INFO - mkdir: /builds/slave/test/properties 16:44:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:44:52 INFO - Writing to file /builds/slave/test/properties/build_url 16:44:52 INFO - Contents: 16:44:52 INFO - build_url:https://queue.taskcluster.net/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.dmg 16:44:53 INFO - Running post-action listener: _resource_record_post_action 16:44:53 INFO - Running post-action listener: set_extra_try_arguments 16:44:53 INFO - ##### 16:44:53 INFO - ##### Running populate-webroot step. 16:44:53 INFO - ##### 16:44:53 INFO - Running pre-action listener: _resource_record_pre_action 16:44:53 INFO - Running main action method: populate_webroot 16:44:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:44:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:44:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:44:53 INFO - '--tppagecycles', 16:44:53 INFO - '7'], 16:44:53 INFO - 'tests': ['tsvgm']}}, 16:44:53 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 16:44:53 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 16:44:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:44:53 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 16:44:53 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 16:44:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:44:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:44:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:44:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:44:53 INFO - 'talos_options': ['--e10s'], 16:44:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:44:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:44:53 INFO - 'tests': ['damp', 'tps']}, 16:44:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:44:53 INFO - 'talos_options': ['--e10s'], 16:44:53 INFO - 'tests': ['damp', 'tps']}, 16:44:53 INFO - 'g3': {'tests': ['dromaeo_dom']}, 16:44:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:44:53 INFO - 'other': {'tests': ['a11yr', 16:44:53 INFO - 'ts_paint', 16:44:53 INFO - 'tpaint', 16:44:53 INFO - 'sessionrestore', 16:44:53 INFO - 'sessionrestore_no_auto_restore', 16:44:53 INFO - 'tabpaint']}, 16:44:53 INFO - 'other-e10s': {'talos_options': ['--e10s'], 16:44:53 INFO - 'tests': ['a11yr', 16:44:53 INFO - 'ts_paint', 16:44:53 INFO - 'tpaint', 16:44:53 INFO - 'sessionrestore', 16:44:53 INFO - 'sessionrestore_no_auto_restore', 16:44:53 INFO - 'tabpaint']}, 16:44:53 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 16:44:53 INFO - 'tests': ['a11yr', 16:44:53 INFO - 'ts_paint', 16:44:53 INFO - 'tpaint', 16:44:53 INFO - 'sessionrestore', 16:44:53 INFO - 'sessionrestore_no_auto_restore', 16:44:53 INFO - 'tabpaint']}, 16:44:53 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 16:44:53 INFO - 'tests': ['a11yr', 16:44:53 INFO - 'ts_paint', 16:44:53 INFO - 'tpaint', 16:44:53 INFO - 'sessionrestore', 16:44:53 INFO - 'sessionrestore_no_auto_restore', 16:44:53 INFO - 'tabpaint']}, 16:44:53 INFO - 'other_l64': {'tests': ['a11yr', 16:44:53 INFO - 'ts_paint', 16:44:53 INFO - 'tpaint', 16:44:53 INFO - 'sessionrestore', 16:44:53 INFO - 'sessionrestore_no_auto_restore', 16:44:53 INFO - 'tabpaint']}, 16:44:53 INFO - 'other_nol64': {'tests': ['a11yr', 16:44:53 INFO - 'ts_paint', 16:44:53 INFO - 'tpaint', 16:44:53 INFO - 'sessionrestore', 16:44:53 INFO - 'sessionrestore_no_auto_restore', 16:44:53 INFO - 'tabpaint']}, 16:44:53 INFO - 'svgr': {'tests': ['tsvgx', 16:44:53 INFO - 'tsvgr_opacity', 16:44:53 INFO - 'tart', 16:44:53 INFO - 'tscrollx', 16:44:53 INFO - 'cart']}, 16:44:53 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 16:44:53 INFO - 'tests': ['tsvgx', 16:44:53 INFO - 'tsvgr_opacity', 16:44:53 INFO - 'tart', 16:44:53 INFO - 'tscrollx', 16:44:53 INFO - 'cart']}, 16:44:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:44:53 INFO - 'tests': ['tp5o']}, 16:44:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:44:53 INFO - 'talos_options': ['--e10s'], 16:44:53 INFO - 'tests': ['tp5o']}, 16:44:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:44:53 INFO - 'talos_options': ['--xperf_path', 16:44:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:44:53 INFO - 'tests': ['tp5n']}, 16:44:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:44:53 INFO - 'talos_options': ['--e10s', 16:44:53 INFO - '--xperf_path', 16:44:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:44:53 INFO - 'tests': ['tp5n']}}, 16:44:53 INFO - 'talos.zip': {'path': '', 16:44:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:44:53 INFO - Running post-action listener: _resource_record_post_action 16:44:53 INFO - ##### 16:44:53 INFO - ##### Running create-virtualenv step. 16:44:53 INFO - ##### 16:44:53 INFO - Running pre-action listener: _resource_record_pre_action 16:44:53 INFO - Running main action method: create_virtualenv 16:44:53 INFO - Creating virtualenv /builds/slave/test/build/venv 16:44:53 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:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:44:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:44:53 INFO - Using real prefix '/tools/python27' 16:44:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:44:54 INFO - Installing distribute.............................................................................................................................................................................................done. 16:44:57 INFO - Installing pip.................done. 16:44:57 INFO - Return code: 0 16:44:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:44:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:44:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:44:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:57 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 0x10a18aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1914c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1f24ec310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a02ed30>, '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 0x109e92db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a025b20>, '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.OyZg6PZdlu/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.qi3f9DE4qn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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:57 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:57 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:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OyZg6PZdlu/Render', 16:44:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:44:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:44:57 INFO - 'HOME': '/Users/cltbld', 16:44:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:44:57 INFO - 'LOGNAME': 'cltbld', 16:44:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:57 INFO - 'MOZ_NO_REMOTE': '1', 16:44:57 INFO - 'NO_EM_RESTART': '1', 16:44:57 INFO - 'PAGER': '/bin/cat', 16:44:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:44:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:57 INFO - 'PWD': '/builds/slave/test', 16:44:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:44:57 INFO - 'SHELL': '/bin/bash', 16:44:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qi3f9DE4qn/Listeners', 16:44:57 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 16:44:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:44:57 INFO - 'USER': 'cltbld', 16:44:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:44:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:44:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:57 INFO - 'XPC_FLAGS': '0x0', 16:44:57 INFO - 'XPC_SERVICE_NAME': '0', 16:44:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:44:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:57 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:57 INFO - Cleaning up... 16:44:57 INFO - Return code: 0 16:44:57 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 16:44:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:44:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:44:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:57 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 0x10a18aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1914c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1f24ec310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a02ed30>, '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 0x109e92db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a025b20>, '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.OyZg6PZdlu/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.qi3f9DE4qn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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:57 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:57 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:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OyZg6PZdlu/Render', 16:44:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:44:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:44:57 INFO - 'HOME': '/Users/cltbld', 16:44:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:44:57 INFO - 'LOGNAME': 'cltbld', 16:44:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:57 INFO - 'MOZ_NO_REMOTE': '1', 16:44:57 INFO - 'NO_EM_RESTART': '1', 16:44:57 INFO - 'PAGER': '/bin/cat', 16:44:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:44:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:57 INFO - 'PWD': '/builds/slave/test', 16:44:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:44:57 INFO - 'SHELL': '/bin/bash', 16:44:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qi3f9DE4qn/Listeners', 16:44:57 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 16:44:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:44:57 INFO - 'USER': 'cltbld', 16:44:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:44:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:44:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:57 INFO - 'XPC_FLAGS': '0x0', 16:44:57 INFO - 'XPC_SERVICE_NAME': '0', 16:44:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:44:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:57 INFO - Downloading/unpacking psutil>=0.7.1 16:44:57 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:57 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:57 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:57 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:57 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:57 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:45:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:45:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:45:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:45:00 INFO - Installing collected packages: psutil 16:45:00 INFO - Running setup.py install for psutil 16:45:01 INFO - building 'psutil._psutil_osx' extension 16:45:01 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:45:01 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:45:01 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:45:01 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:45:01 INFO - building 'psutil._psutil_posix' extension 16:45:01 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:45:01 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:45:01 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:45:01 INFO - ^ 16:45:02 INFO - 1 warning generated. 16:45:02 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:45:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:45:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:45:02 INFO - Successfully installed psutil 16:45:02 INFO - Cleaning up... 16:45:02 INFO - Return code: 0 16:45:02 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 16:45:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:45:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:45:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a18aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1914c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1f24ec310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a02ed30>, '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 0x109e92db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a025b20>, '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.OyZg6PZdlu/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.qi3f9DE4qn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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:45:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 16:45:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 16:45:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OyZg6PZdlu/Render', 16:45:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:02 INFO - 'HOME': '/Users/cltbld', 16:45:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:02 INFO - 'LOGNAME': 'cltbld', 16:45:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:02 INFO - 'MOZ_NO_REMOTE': '1', 16:45:02 INFO - 'NO_EM_RESTART': '1', 16:45:02 INFO - 'PAGER': '/bin/cat', 16:45:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:02 INFO - 'PWD': '/builds/slave/test', 16:45:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:02 INFO - 'SHELL': '/bin/bash', 16:45:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qi3f9DE4qn/Listeners', 16:45:02 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 16:45:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:02 INFO - 'USER': 'cltbld', 16:45:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:02 INFO - 'XPC_FLAGS': '0x0', 16:45:02 INFO - 'XPC_SERVICE_NAME': '0', 16:45:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:02 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 16:45:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:45:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:45:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:05 INFO - Downloading mozsystemmonitor-0.0.tar.gz 16:45:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:45:05 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:45:05 INFO - Installing collected packages: mozsystemmonitor 16:45:05 INFO - Running setup.py install for mozsystemmonitor 16:45:05 INFO - Successfully installed mozsystemmonitor 16:45:05 INFO - Cleaning up... 16:45:05 INFO - Return code: 0 16:45:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:45: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:45:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45: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:45:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a18aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1914c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1f24ec310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a02ed30>, '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 0x109e92db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a025b20>, '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.OyZg6PZdlu/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.qi3f9DE4qn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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:45:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:45:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:45:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OyZg6PZdlu/Render', 16:45:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:05 INFO - 'HOME': '/Users/cltbld', 16:45:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:05 INFO - 'LOGNAME': 'cltbld', 16:45:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:05 INFO - 'MOZ_NO_REMOTE': '1', 16:45:05 INFO - 'NO_EM_RESTART': '1', 16:45:05 INFO - 'PAGER': '/bin/cat', 16:45:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:05 INFO - 'PWD': '/builds/slave/test', 16:45:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:05 INFO - 'SHELL': '/bin/bash', 16:45:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qi3f9DE4qn/Listeners', 16:45:05 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 16:45:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:05 INFO - 'USER': 'cltbld', 16:45:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:05 INFO - 'XPC_FLAGS': '0x0', 16:45:05 INFO - 'XPC_SERVICE_NAME': '0', 16:45:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:05 INFO - Downloading/unpacking blobuploader==1.2.4 16:45:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:45:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:45:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:08 INFO - Downloading blobuploader-1.2.4.tar.gz 16:45:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:45:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:45:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:45:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:45:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:45:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:45:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:45:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:45:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:09 INFO - Downloading docopt-0.6.1.tar.gz 16:45:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:45:09 INFO - Installing collected packages: blobuploader, requests, docopt 16:45:09 INFO - Running setup.py install for blobuploader 16:45:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:45:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:45:09 INFO - Running setup.py install for requests 16:45:10 INFO - Running setup.py install for docopt 16:45:10 INFO - Successfully installed blobuploader requests docopt 16:45:10 INFO - Cleaning up... 16:45:10 INFO - Return code: 0 16:45:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:45:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:45:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:45:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:10 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 0x10a18aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1914c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1f24ec310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a02ed30>, '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 0x109e92db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a025b20>, '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.OyZg6PZdlu/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.qi3f9DE4qn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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:45:10 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:45:10 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:45:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OyZg6PZdlu/Render', 16:45:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:10 INFO - 'HOME': '/Users/cltbld', 16:45:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:10 INFO - 'LOGNAME': 'cltbld', 16:45:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:10 INFO - 'MOZ_NO_REMOTE': '1', 16:45:10 INFO - 'NO_EM_RESTART': '1', 16:45:10 INFO - 'PAGER': '/bin/cat', 16:45:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:10 INFO - 'PWD': '/builds/slave/test', 16:45:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:10 INFO - 'SHELL': '/bin/bash', 16:45:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qi3f9DE4qn/Listeners', 16:45:10 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 16:45:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:10 INFO - 'USER': 'cltbld', 16:45:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:10 INFO - 'XPC_FLAGS': '0x0', 16:45:10 INFO - 'XPC_SERVICE_NAME': '0', 16:45:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:45:10 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-k4YwvJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:45:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:45:10 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-q_bxCX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:45:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:45:10 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-F8IFK2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:45:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:45:10 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-4MAtOx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:45:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:45:10 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-nkMeYS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:45:11 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-agb7kx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:45:11 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-Mjp_FP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:45:11 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-q_OZAf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:45:11 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-9Z93WG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:45:11 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-YUJGjw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:45:11 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-sK90XU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:45:11 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-7g3zNb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:45:11 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-CBydYu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:45:11 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-HhX17i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:45:11 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-NioP1w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:45:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:45:12 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-J8g6rF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:45:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:45:12 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-_hIMDY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:45:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:45:12 INFO - Running setup.py install for manifestparser 16:45:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:45:12 INFO - Running setup.py install for mozcrash 16:45:12 INFO - Running setup.py install for mozdebug 16:45:12 INFO - Running setup.py install for mozdevice 16:45:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:45:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:45:12 INFO - Running setup.py install for mozfile 16:45:13 INFO - Running setup.py install for mozhttpd 16:45:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:45:13 INFO - Running setup.py install for mozinfo 16:45:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:45:13 INFO - Running setup.py install for mozInstall 16:45:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:45:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:45:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:45:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:45:13 INFO - Running setup.py install for mozleak 16:45:13 INFO - Running setup.py install for mozlog 16:45:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:45:13 INFO - Running setup.py install for moznetwork 16:45:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:45:13 INFO - Running setup.py install for mozprocess 16:45:14 INFO - Running setup.py install for mozprofile 16:45:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:45:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:45:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:45:14 INFO - Running setup.py install for mozrunner 16:45:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:45:14 INFO - Running setup.py install for mozscreenshot 16:45:14 INFO - Running setup.py install for moztest 16:45:14 INFO - Running setup.py install for mozversion 16:45:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:45:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:45:14 INFO - Cleaning up... 16:45:14 INFO - Return code: 0 16:45:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 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 - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 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 - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:14 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 0x10a18aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1914c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1f24ec310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a02ed30>, '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 0x109e92db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a025b20>, '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.OyZg6PZdlu/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.qi3f9DE4qn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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:45:14 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:45:14 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:45:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OyZg6PZdlu/Render', 16:45:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:14 INFO - 'HOME': '/Users/cltbld', 16:45:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:14 INFO - 'LOGNAME': 'cltbld', 16:45:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:14 INFO - 'MOZ_NO_REMOTE': '1', 16:45:14 INFO - 'NO_EM_RESTART': '1', 16:45:14 INFO - 'PAGER': '/bin/cat', 16:45:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:14 INFO - 'PWD': '/builds/slave/test', 16:45:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:14 INFO - 'SHELL': '/bin/bash', 16:45:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qi3f9DE4qn/Listeners', 16:45:14 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 16:45:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:14 INFO - 'USER': 'cltbld', 16:45:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:14 INFO - 'XPC_FLAGS': '0x0', 16:45:14 INFO - 'XPC_SERVICE_NAME': '0', 16:45:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:45:15 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-9eGf7S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:45:15 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:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:45:15 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-vuw0zM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:45:15 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:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:45:15 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-bpSDn7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:45:15 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:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:45:15 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-4Ry0PM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:45:15 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:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:45:15 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-qTN2x4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:45:15 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:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:45:15 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-oRMMAu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:45:15 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:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:45:15 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-XH_dQx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:45:15 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:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:45:15 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-QCwwfZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:45:16 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:45:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:45:16 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-oZlYtu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:45:16 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:45:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:45:16 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-0BBrzH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:45:16 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:45:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:45:16 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-6WyPkO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:45:16 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:45:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:45:16 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-yU536U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:45:16 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:45:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:45:16 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-lL97B2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:45:16 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:45:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:45:16 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-yAaVn3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:45:16 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:45:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:45:16 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-4y5x9Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:45:16 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:45:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:45:16 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-JYuLvW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:45:16 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:45:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:45:16 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-2qxtk5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:45:16 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:45:16 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:45:16 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:45:16 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:45:16 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:45:16 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:45:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:45:16 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:45:16 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:45:16 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:45:16 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:45:16 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:45:16 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:45:19 INFO - Downloading blessings-1.6.tar.gz 16:45:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:45:19 INFO - Installing collected packages: blessings 16:45:19 INFO - Running setup.py install for blessings 16:45:19 INFO - Successfully installed blessings 16:45:19 INFO - Cleaning up... 16:45:19 INFO - Return code: 0 16:45:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:45:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:45:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:45:20 INFO - Reading from file tmpfile_stdout 16:45:20 INFO - Current package versions: 16:45:20 INFO - blessings == 1.6 16:45:20 INFO - blobuploader == 1.2.4 16:45:20 INFO - docopt == 0.6.1 16:45:20 INFO - manifestparser == 1.1 16:45:20 INFO - mozInstall == 1.12 16:45:20 INFO - mozcrash == 0.17 16:45:20 INFO - mozdebug == 0.1 16:45:20 INFO - mozdevice == 0.48 16:45:20 INFO - mozfile == 1.2 16:45:20 INFO - mozhttpd == 0.7 16:45:20 INFO - mozinfo == 0.9 16:45:20 INFO - mozleak == 0.1 16:45:20 INFO - mozlog == 3.1 16:45:20 INFO - moznetwork == 0.27 16:45:20 INFO - mozprocess == 0.22 16:45:20 INFO - mozprofile == 0.28 16:45:20 INFO - mozrunner == 6.11 16:45:20 INFO - mozscreenshot == 0.1 16:45:20 INFO - mozsystemmonitor == 0.0 16:45:20 INFO - moztest == 0.7 16:45:20 INFO - mozversion == 1.4 16:45:20 INFO - psutil == 3.1.1 16:45:20 INFO - requests == 1.2.3 16:45:20 INFO - wsgiref == 0.1.2 16:45:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:45: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:45:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45: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:45:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:20 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 0x10a18aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1914c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1f24ec310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a02ed30>, '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 0x109e92db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a025b20>, '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.OyZg6PZdlu/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.qi3f9DE4qn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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:45:20 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:45:20 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:45:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OyZg6PZdlu/Render', 16:45:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:20 INFO - 'HOME': '/Users/cltbld', 16:45:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:20 INFO - 'LOGNAME': 'cltbld', 16:45:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:20 INFO - 'MOZ_NO_REMOTE': '1', 16:45:20 INFO - 'NO_EM_RESTART': '1', 16:45:20 INFO - 'PAGER': '/bin/cat', 16:45:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:20 INFO - 'PWD': '/builds/slave/test', 16:45:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:20 INFO - 'SHELL': '/bin/bash', 16:45:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qi3f9DE4qn/Listeners', 16:45:20 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 16:45:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:20 INFO - 'USER': 'cltbld', 16:45:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:20 INFO - 'XPC_FLAGS': '0x0', 16:45:20 INFO - 'XPC_SERVICE_NAME': '0', 16:45:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:20 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:45:20 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:45:20 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:45:20 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:45:20 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:45:20 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:45:20 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:45:20 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:45:20 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:45:20 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:45:20 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:45:20 INFO - Cleaning up... 16:45:20 INFO - Return code: 0 16:45:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:45: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:45:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45: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:45:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a18aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1914c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1f24ec310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a02ed30>, '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 0x109e92db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a025b20>, '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.OyZg6PZdlu/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.qi3f9DE4qn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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:45: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', 'jsonschema'] in /builds/slave/test/build 16:45: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 jsonschema 16:45:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OyZg6PZdlu/Render', 16:45:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:20 INFO - 'HOME': '/Users/cltbld', 16:45:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:20 INFO - 'LOGNAME': 'cltbld', 16:45:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:20 INFO - 'MOZ_NO_REMOTE': '1', 16:45:20 INFO - 'NO_EM_RESTART': '1', 16:45:20 INFO - 'PAGER': '/bin/cat', 16:45:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:20 INFO - 'PWD': '/builds/slave/test', 16:45:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:20 INFO - 'SHELL': '/bin/bash', 16:45:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qi3f9DE4qn/Listeners', 16:45:20 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 16:45:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:20 INFO - 'USER': 'cltbld', 16:45:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:20 INFO - 'XPC_FLAGS': '0x0', 16:45:20 INFO - 'XPC_SERVICE_NAME': '0', 16:45:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:20 INFO - Downloading/unpacking jsonschema 16:45: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:45: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:45: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:45: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:45: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:45: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:45:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:45:23 INFO - Downloading/unpacking functools32 (from jsonschema) 16:45:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:45:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:45:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:23 INFO - Downloading functools32-3.2.3-2.tar.gz 16:45:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:45:23 INFO - warning: no files found matching '*.txt' 16:45:23 INFO - no previously-included directories found matching 'build' 16:45:23 INFO - no previously-included directories found matching 'dist' 16:45:23 INFO - no previously-included directories found matching '.git*' 16:45:23 INFO - Installing collected packages: jsonschema, functools32 16:45:23 INFO - Running setup.py install for functools32 16:45:23 INFO - warning: no files found matching '*.txt' 16:45:23 INFO - no previously-included directories found matching 'build' 16:45:23 INFO - no previously-included directories found matching 'dist' 16:45:23 INFO - no previously-included directories found matching '.git*' 16:45:23 INFO - Successfully installed jsonschema functools32 16:45:23 INFO - Cleaning up... 16:45:23 INFO - Return code: 0 16:45:23 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 16:45:23 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:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:23 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:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:23 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 0x10a18aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1914c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1f24ec310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a02ed30>, '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 0x109e92db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a025b20>, '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.OyZg6PZdlu/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.qi3f9DE4qn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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:45:23 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:45:23 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:45:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OyZg6PZdlu/Render', 16:45:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:23 INFO - 'HOME': '/Users/cltbld', 16:45:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:23 INFO - 'LOGNAME': 'cltbld', 16:45:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:23 INFO - 'MOZ_NO_REMOTE': '1', 16:45:23 INFO - 'NO_EM_RESTART': '1', 16:45:23 INFO - 'PAGER': '/bin/cat', 16:45:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:23 INFO - 'PWD': '/builds/slave/test', 16:45:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:23 INFO - 'SHELL': '/bin/bash', 16:45:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qi3f9DE4qn/Listeners', 16:45:23 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 16:45:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:23 INFO - 'USER': 'cltbld', 16:45:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:23 INFO - 'XPC_FLAGS': '0x0', 16:45:23 INFO - 'XPC_SERVICE_NAME': '0', 16:45:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:24 INFO - Downloading/unpacking flake8 16:45:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:45:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:45:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:26 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:45:26 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:45:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:45:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:45:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:26 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:45:26 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:45:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:45:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:45:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:27 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:45:27 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:45:27 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:45:27 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:45:27 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:45:27 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:45:27 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:45:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 16:45:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:45:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:45:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:45:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:45:27 INFO - no previously-included directories found matching 'docs/_build' 16:45:27 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:45:27 INFO - Running setup.py install for pep8 16:45:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:45:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:45:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:45:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:45:27 INFO - no previously-included directories found matching 'docs/_build' 16:45:27 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 16:45:27 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:45:27 INFO - Cleaning up... 16:45:28 INFO - Return code: 0 16:45:28 INFO - Running post-action listener: _resource_record_post_action 16:45:28 INFO - Running post-action listener: _start_resource_monitoring 16:45:28 INFO - Starting resource monitoring. 16:45:28 INFO - ##### 16:45:28 INFO - ##### Running install step. 16:45:28 INFO - ##### 16:45:28 INFO - Running pre-action listener: _resource_record_pre_action 16:45:28 INFO - Running main action method: install 16:45:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:45:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:45:28 INFO - Reading from file tmpfile_stdout 16:45:28 INFO - Detecting whether we're running mozinstall >=1.0... 16:45:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:45:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:45:28 INFO - Reading from file tmpfile_stdout 16:45:28 INFO - Output received: 16:45:28 INFO - Usage: mozinstall [options] installer 16:45:28 INFO - Options: 16:45:28 INFO - -h, --help show this help message and exit 16:45:28 INFO - -d DEST, --destination=DEST 16:45:28 INFO - Directory to install application into. [default: 16:45:28 INFO - "/builds/slave/test"] 16:45:28 INFO - --app=APP Application being installed. [default: firefox] 16:45:28 INFO - mkdir: /builds/slave/test/build/application 16:45:28 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:45:28 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:50 INFO - Reading from file tmpfile_stdout 16:45:50 INFO - Output received: 16:45:50 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 16:45:50 INFO - Running post-action listener: _resource_record_post_action 16:45:50 INFO - ##### 16:45:50 INFO - ##### Running run-tests step. 16:45:50 INFO - ##### 16:45:50 INFO - Running pre-action listener: _resource_record_pre_action 16:45:50 INFO - Running main action method: run_tests 16:45:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:45:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:45:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:45:50 INFO - 2016-05-30 16:45:50.807 screenresolution[1650:9043] starting screenresolution argv=screenresolution get 16:45:50 INFO - 2016-05-30 16:45:50.810 screenresolution[1650:9043] Display 0: 1600x1200x32@60 16:45:50 INFO - 2016-05-30 16:45:50.815 screenresolution[1651:9045] starting screenresolution argv=screenresolution list 16:45:50 INFO - Available Modes on Display 0 16:45:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:45:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:45:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:45:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:45:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:45:51 INFO - Intel Iris: 16:45:51 INFO - Chipset Model: Intel Iris 16:45:51 INFO - Type: GPU 16:45:51 INFO - Bus: Built-In 16:45:51 INFO - VRAM (Dynamic, Max): 1536 MB 16:45:51 INFO - Vendor: Intel (0x8086) 16:45:51 INFO - Device ID: 0x0a2e 16:45:51 INFO - Revision ID: 0x0009 16:45:51 INFO - Displays: 16:45:51 INFO - Display: 16:45:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:45:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:45:51 INFO - Display Serial Number: 1600x1200 60 16:45:51 INFO - Main Display: Yes 16:45:51 INFO - Mirror: Off 16:45:51 INFO - Online: Yes 16:45:51 INFO - Rotation: Supported 16:45:51 INFO - Return code: 0 16:45:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:45:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:45:51 INFO - Python 2.7.3 16:45:51 INFO - Return code: 0 16:45:51 INFO - grabbing minidump binary from tooltool 16:45:51 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:51 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 0x10a02ed30>, '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 0x109e92db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a025b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:45:51 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:51 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:51 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:45:51 INFO - Return code: 0 16:45:51 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:45:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:45:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:45:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:45:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:45:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 16:45:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 16:45:52 INFO - Return code: 0 16:45:52 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-0112', '--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:52 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-0112 --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:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OyZg6PZdlu/Render', 16:45:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:52 INFO - 'HOME': '/Users/cltbld', 16:45:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:52 INFO - 'LOGNAME': 'cltbld', 16:45:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:45:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:45:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:52 INFO - 'MOZ_NO_REMOTE': '1', 16:45:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:45:52 INFO - 'NO_EM_RESTART': '1', 16:45:52 INFO - 'PAGER': '/bin/cat', 16:45:52 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:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:52 INFO - 'PWD': '/builds/slave/test', 16:45:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:45:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:52 INFO - 'SHELL': '/bin/bash', 16:45:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qi3f9DE4qn/Listeners', 16:45:52 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 16:45:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:52 INFO - 'USER': 'cltbld', 16:45:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:52 INFO - 'XPC_FLAGS': '0x0', 16:45:52 INFO - 'XPC_SERVICE_NAME': '0', 16:45:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:52 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-0112', '--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:52 INFO - mozversion application_buildid: 20160526112438 16:45:52 INFO - mozversion application_changeset: 417e1ec42d8f68c55e52c336cdf81e83323db88c 16:45:52 INFO - mozversion application_display_name: Firefox 16:45:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:45:52 INFO - mozversion application_name: Firefox 16:45:52 INFO - mozversion application_remotingname: firefox 16:45:52 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 16:45:52 INFO - mozversion application_vendor: Mozilla 16:45:52 INFO - mozversion application_version: 47.0 16:45:52 INFO - mozversion platform_buildid: 20160526112438 16:45:52 INFO - mozversion platform_changeset: 417e1ec42d8f68c55e52c336cdf81e83323db88c 16:45:52 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 16:45:52 INFO - mozversion platform_version: 47.0 16:45:52 INFO - using testdate: 1464651952 16:45:52 INFO - actual date: 1464651952 16:45:52 INFO - starting webserver on 'localhost:49272' 16:45:52 INFO - SUITE-START | Running 2 tests 16:45:52 INFO - TEST-START | dromaeo_css 16:45:52 INFO - operating with platform_type : mac_ 16:45:52 INFO - Initialising browser for dromaeo_css test... 16:45:52 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/tmpXV5LXb/profile http://localhost:49272/getInfo.html) 16:45:55 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 16:45:55 INFO - PROCESS | 1669 | colorDepth:24 16:45:55 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 16:45:55 INFO - PROCESS | 1669 | __metrics 16:45:55 ERROR - PROCESS | 1669 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2844: TypeError: this.tabs is undefined 16:45:56 INFO - TEST-INFO | 1669: exit 0 16:45:56 INFO - Browser initialized. 16:45:56 INFO - Running cycle 1/1 for dromaeo_css test... 16:45:56 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/tmpXV5LXb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:46:08 INFO - PROCESS | 1674 | RSS: Main: 168345600 16:46:08 INFO - PROCESS | 1674 | 16:46:57 INFO - PROCESS | 1674 | 1464652017932 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:49:08 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 16:49:09 INFO - PROCESS | 1674 | RSS: Main: 178192384 16:49:09 INFO - PROCESS | 1674 | 16:52:11 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 16:52:12 INFO - PROCESS | 1674 | RSS: Main: 189804544 16:52:12 INFO - PROCESS | 1674 | 16:55:13 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 16:55:14 INFO - PROCESS | 1674 | RSS: Main: 191758336 16:55:14 INFO - PROCESS | 1674 | 16:58:16 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 16:58:16 INFO - PROCESS | 1674 | RSS: Main: 195190784 16:58:16 INFO - PROCESS | 1674 | 17:01:19 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 17:01:19 INFO - PROCESS | 1674 | RSS: Main: 194330624 17:01:19 INFO - PROCESS | 1674 | 17:04:21 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 17:04:22 INFO - PROCESS | 1674 | RSS: Main: 199737344 17:04:22 INFO - PROCESS | 1674 | 17:04:22 INFO - PROCESS | 1674 | __start_tp_report 17:04:22 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 17:04:22 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 17:04:22 INFO - PROCESS | 1674 | |i|pagename|runs| 17:04:22 INFO - PROCESS | 1674 | |0;dojo.html;3624.2207925296066;3651.9087022824424;3672.375696131657;3692.2800053989477;3702.999999999998;5794.304683437988;5799.072148456248;5814.418558144189;5834.329052159003;5863.706814659271;5841.357450680422;5868;5878.794242201524;5917.911231331534;5928.1404196391495;4724.716517008973;4841.298011788289;4905.215165573509;4975;5324.893502129955;4684.2973553967;4949.703017818924;5375.516203541681;5393.325834270716;5401.378841433227;5280.5247527722495;5360.812371566996;5389.380221274545;5394.649347792381;5411.648242864221;5329.493698098674;5363.168708850135;5400.108982017962;5405.621606487547;5421.620486565942;5264.447233040552;5323.041852466555;5346.144616861284;5382.8923421531745;5424.213489044067;5461.344638643388;5738.684372359518;5747.971260143672;5751.827445176651;5812.128180772876;6217.937820621781;6221.720022598994;6230.626162430269;6255.78104766331;6278.591891527082;6181;6232.626042437423;6331.55679102463;6359.968200159016;6364.204474440695;4951.257311403282;6019.307779605358;6033.245844269466;6060.121282414026;6072.635641861502;6387.137736405619;6397;6398.616083035033;6401.167848179707;6407.839804004937;5902.734376953047;6200.596961197508;6243;6254.186955695731;6264.029075493306;6250.093736408196;6294.9685251574365;6303.401176888188;6313.968430157912;6321;6232.594881332699;6238.75044998195;6257.5932564383165;6270.372962703785;6288.434040936338;6150;6171.9691401542705;6185.752569897154;6235.033569796688;6261.843453913689;4361.389405483234;4906.386701662293;4956.306117143603;5010;5016.623753218523;4459.910801783946;4499.212637788374;4521.954780452219;4540.250858608359;4565.452145742533;5883.764649414061;5946.732397042144;5956.404359564009;6012.849678758066;6068.544859135494;6263.530235232376;6280.591761535486;6308.243010838729;6331.620102793755;6343.238811342576;5860.238169037998;6052.001419765689;6063.454289113913;6074.75700971965;6075.210222671025;5445.782168713286;5492.780288788484;5497.697626630574;5546.278983732089;5553.305836770403;6484.805455836333;6519.478441724651;6529.738810447624;6532.379423954717;6538.738450461929;6173.6913154343;6203.596766210182;6226.3151053385;6287.905681414874;6322.272938612118;6402.647854368054;6408.359164083555;6437.549371544134;6483.222013358239;6526.967365163144;5747.994101032219;6222.595531290452;6255.718492667932;6266.467350275278;6267.5299352549655;5514.696691681996;6082.391760823883;6168.136460895388;6273.62358258506;6288.088227207121;5638.295213098363;5982.102684597346;6150.538709596887;6251.499880009679;6259.812205633838;6185.505159587132;6205.751769929333;6231.22109736283;6244.094606282155;6254.687265636791;5868.559858010752;6056.06131049688;6067.332593414809;6158.076288556573;6166.537509686881;6174.567780255518;6175.351588083367;6206.410391012847;6211.440970312694;6218.657973811303;6081.1486391906055;6114.633122012693;6131.049687298476;6132.110843927517;6141.355157708376 17:04:22 INFO - PROCESS | 1674 | |1;ext.html;486.61314255167116;576.2940398012436;617.7652492053019;652.924913634932;682.6245564939284;39148.608513914885;40025.79987100061;40031.599684003224;40039;40144.598554014454;41482.58517414825;41679.166416671724;42088.15823683532;42146.15707685844;42183.156336873246;9711.708648740549;9892.30753847231;9899.307048506607;9940.602375904955;9945.850812237823;9731.464769437678;10005.249606279522;10020.398776073422;10034.046765557261;10044.698659040205;9977.950110249438;10013.249006324519;10042.397456152617;10071.798564028715;10079.5464204111;9940.502974851228;9953.701388958343;10007.649732259337;10047.849282260735;10051.798964020752;9990.750231244205;9991.100800927952;10037.146842518394;10063.5471403787;10085.949570252102;35233;40541.59458405407;41162.176756465;41245.1750964979;41252.38121428181;2749.5463248563965;3367.3770352484867;3400.506926495644;3449.931001379958;3456.602490713575;27770;27925.581116283272;28210;28348.29129271764;29708.8514557428;2796.930076748077;2814.0713564523658;2819.1824370932404;2833.4333133373407;2868.6270784798053;334150;338118;340332;340477;341262;48732.756336218095;70410.64794675994;70590.64704676444;70924;70954.2904570958;47901.520984790404;47933.52066479361;48017.03965920662;48026;48026;17006.80952333325;17262.136893155293;17278.827211727974;17294.221760020828;17325.393611223546;13341.866581334256;13636.863631363758;13757.31213439324;13758;13759.862401375858;15247.313870875616;15302.617434564225;15481.83886208539;15722.449714260174;15735.213239337987;5514.959464997976;6795.626240556718;6797.898031529531;6813.625250611264;6831.385175334244;954.1508057828538;1080.0171843622422;1089.716673664837;1099.8185299425666;1174.5712814822527;30843.98721534168;30954.380912381625;31107.911223107083;31319.373612527623;31566;1153.8961493465629;1204.1811568133578;1234.8641649418557;1244.5457408045997;1267.5563552756462;15061.623459413602;15490.845091549165;15830.60423489422;15950.165730940858;16093.275802588912;527566;532076;533738;537731;538467;63173;63361.049584256274;63793.36206637967;63824.68087659532;64079.35920640734;1524.4207201263448;1539.0611726846614;1566.9764953525707;1575.3068649794054;1575.3855996161506;26015.609765853907;29295.41409171762;30069.549500970887;30335.848320758254;30821;55349.446505534426;55457.445425546844;55659.72170139123;55858;55886.882262355335;435903;495380;505193;511101;511680.3247951358;34257.48613770845;35412.645873541966;35514.467282990256;35743.28513429769;36206.637933620324;38252.42621360626;47098.76450617725;48127.55617331486;48637;49536.75231623819;33196.004119876445;38676.80661596674;38710;39745.60254397535;39752.40371394489;38263.42604860874;38718.80640596779;38815.029624259616;39256.80371598238;39625.80187099046;31583.052508424786;34627.4805877907;38242.0439489015;38346;39851.80074099611;31486.055418337488;32337.35325293528;32561.674383255864;32726;32770.508442372906;25219.87390063111;26469.470610588065;26722.59916101221;26918.32704182411;27003.86498067497 17:04:22 INFO - PROCESS | 1674 | |2;jquery.html;377.9111908701456;382.7550367764631;384.81913500654684;385.0988686473653;390.14947414636083;19680.11439485223;20684.793152068476;21399.57200855982;21578.244761433332;21646.567068658656;20860;21121.894390528025;21130.683039754414;21547.67678484824;21915.890420547916;4886.462489126211;5779.913301300484;6065.575409721321;6085.2697676278685;6091.878162436748;6530;6608.405243528082;6642.734290628369;6648.800535983928;6649.235337936127;6335.619862808247;6472.158619379497;6493.545451818374;6543.116679248312;6562.737490500374;6423.036544518313;6440.355964403522;6464.482841372703;6499.967500162493;6528.575642583217;5530.5299049580835;5662.09406494963;5700.173474846166;5733.082706766939;5751.25233719618;23777;24309.635355469683;24952.625710614357;26047.479050418886;26538.469230615472;2099.2442720620566;2246.48330883896;2283.486215601493;2353.51752890657;2788.539890917996;9768.658096966643;9834.655787047419;9953.054459826304;10149.847752283722;10819.404932728698;1722.7243641017503;1752.6056637256643;1798.7301904714266;1807.792103908041;1810.7736532933384;110081.69754907285;110321;111605;113641.43179284134;116357;11741.823872641917;11913.940430297793;11938.701532461759;11988.88011119895;12048.578299759467;14113.506027288995;14152;14429.639259018608;14517.346719397441;14547.05444146127;9018.09819018093;9021.684241051531;9077.636894524278;9094.727158185266;9134;7569.432292577969;9258.305627077996;9391.90608093924;9406.48264345454;9478.383905046288;4152.522459917088;4337.262665346835;4522.66080043998;4599.793009314589;4671.5094915034115;1843.4469659102215;1888.1125870840958;1961.8234358907766;1966.3904189701238;1968.0848405491229;791.8574656561874;828.2918105020242;936.0265324062898;988.5897352598735;1023.9590416383261;19142.24488581103;20729.58540829175;20760.273390431263;20774.48063798387;20945;1013.3514550687565;1034.0486752188006;1043.4626167523732;1052.321252791945;1087.3693257910393;10660.680179594625;11051.336919784839;11101.72245693848;11135.498902549405;11181.720456988478;680858;683844;683963;685243.6561418993;693141;123989.38005310096;124383;125909;127384.72615273914;128276.71723282835;1063.6170978447599;1063.9787204255872;1105.2429086076063;1118.3178261260625;1130.2596799096605;17563.209655565784;18024.2790288387;18157.54606134857;18329.90835045816;18434;48744.512554875415;48856.75571622119;49305.753471233846;49671.751641241564;50865.745671271405;58189.5452613688;72796.63601681958;73407.26592734005;74133;75429.86855197066;8054.395920306118;8193.549354785544;8412.369072319478;8454.535000575028;8699.173578510032;12326.75346493083;13546.390412431267;13575.8460530856;13586.184828910296;13949.3722782473;11521.942390287995;11571.768564628492;11684.88315116872;11971.580994665144;12074.034077273973;9151.816963660822;9438.716838494856;11725;11803.822942655695;11884.82172767392;11318.265631948978;11651.242669226473;11799.882001179878;11930.343831089373;12280.81578776337;7903.486273392211;7951.920480795118;7953.880691789744;8091.393145513992;8238.093809680811;10701.785964280827;11085.27945683528;11153.442327883411;11781.52873885035;11935.82096268574 17:04:22 INFO - PROCESS | 1674 | |3;mootools.html;1452.8692417682407;1668.3576822923171;1682.5036614198807;2044.9897750511245;2075.4915045813777;8395.706150284741;8969.32730045246;9072.228860546853;9317;9336.299777516688;8944.776380590489;8948.328875334344;9039.502827344493;9055.683051093212;9073.455592664444;1861.64628720543;2537.4417628121782;2546.503431830794;2553.923382298534;2554.246497283297;2322.4890524084676;2575.88408521616;2583.599542070973;2627.487639910218;2633.262686447788;2519.282004628681;2522.445062086338;2526.4694414173046;2537.936551586207;2541.2376287113884;2540.8475491470476;2566.4610431809156;2568.139673209477;2571.215779187366;2596.7533084356955;2172.663237198221;2308.9422764430856;2633.210036988896;2655.5087308847924;2660.773834224093;8863.11368863115;8873.06832782555;8890.777730556723;8914.50970196639;8968.775780605472;5796.565257605696;5857.974854400463;5863.179154918314;5942.554308426886;5957.344692083867;3142.701443362877;3719.851205951759;3764.5482542095124;3849.480320156771;3873.86441474547;930.3255140023497;1089.2320913755862;1110.7112150840758;1111.5164903267105;1113.6826004588654;12372.44324005413;12506.18709783861;12577.308248046354;12596.370181490889;12657.430415631226;4439.977800110979;5120.359955005624;5126.641135120581;5212.947870521246;5287.1804870244405;10661.147108231324;10697.786044279072;10847.837282440769;10875.45622718861;10903.236773425784;5704.771809127588;5709.3434255060365;5815.82552523425;5857.355690874;5924.822255332347;4754.120487709784;5106.821261255838;5196.68819870809;5207.661502002357;5308.734563271821;4157.210130075276;4165.791710414467;4182;4194.2450358935075;4197.370394440797;1377.125525291447;1390.0408717984558;1404.262762049932;1418.0357356997135;1418.9503367382092;1382.654336415896;1392.1229625335973;1443.6579318876977;1446.9493567725078;1466.2302291296942;8566.357523185787;8572.61423235956;8606.44058136219;8616.655333786579;8632.266257368194;541.8618252345626;546.3634865381865;554.464941331617;558.7681112338335;560.2828379673942;6404.391582799627;6406.8718625627225;6432.228132624022;6471.611703297723;6530.738770449224;9534.666286679932;9621.51892405377;10742.462876856125;11351.432428378714;11432.94283528577;10800.405977671146;10949.781004380027;10982.011618954333;11166;11219.102471802236;8698.391112622056;8787.5166865823;8828.646854125891;8933.481308177625;8968.730938071869;8139.3488520917;8181.018277806464;8263.83472330538;8291.419600627898;8307.044689860744;9738;9762.365446246255;9965.152961998021;10050.145737612385;10183.643572474928;9866.457344846101;10009.049140331657;10064.597416103274;10096;10465.476726163814;5910.822675319747;6513.446357059703;7768.572728499761;7831.060272767077;7872.330851877655;7086.433085353263;8436.53599052058;8487.405881588229;8512.872306915526;8515.361347899056;6776;7310.671019804228;7771.067471903408;7771.300582947561;7819.1398946117015;6663.600183988976;7096.751613693703;7477.4391920605085;7665.578393188428;7776.377889768918;8161.3062889652665;8178.7955301117945;8210;8238.50568965864;8267.917320826715;7025.473089518408;7033.839416496426;7074.398676112588;7433.145188303414;7460.515066520658;7038.260982596754;7499.437542184249;7508.549487030796;7548.773536793905;7574.3183113520045 17:04:22 INFO - PROCESS | 1674 | |4;prototype.html;344.1345017281537;348.258210012673;349.10802898594096;349.1463372055326;350.23648446386875;6692.130023096991;6705.128333316674;6763.932360676392;6770.424513916317;6800.421964133048;4708.85873423797;5566.526845218162;6567.277599464067;6635.336466353362;6792.694328755218;4041.818118184689;4878.365812444395;4897.363342765436;4991.850244492671;5101.107306221405;3796.373598356268;4960.181570040957;4986.900261994758;5021.799128034892;5087.10975579274;4677.111348843726;4954.281629163769;5011.32347133136;5039.19372900336;5047.26814611881;3967.662748666367;4229.49246090468;5026.47222041684;5038.345015148026;5039.672421292605;4323.265044942367;4342.392065110887;4354.042110735633;4357.673174511892;4358.2808836541935;6234.844128896769;6292.685365731695;6427.42153206209;6430.7749228776765;6458.806235812933;5842.824715258549;5878.970605146989;5879.441453061952;5895.616784908967;5921.555883308769;5489.451054894518;5876.44173803488;7001.089858318386;7147.392471639917;7166.784996450115;4823.85528434144;4837.4920633333695;4843.07981483517;4849.854504364875;4865.318855360252;7517.699292028312;7517.774466766005;7546.981157543771;7556.546607203585;7565.6973721051645;3757.4739536464917;4746.905061898812;4770.117528257283;4903.387076615423;4922.901541969212;4806.543378379049;4831.275308703653;4842.709437433765;4853.757312134449;4860.076585448754;5716.828495145152;5772.624779389326;5777.035807825359;5790.855228619234;5795;7203.135623725187;7239.276072392824;7243.420526357879;7247.057882475245;7252.854942901113;1664.267722202227;1683.75585540096;1687.4262750664832;1691.2833474023876;1691.6532110917233;1045.994770026145;1075.93544387337;1084.7776205877776;1089.1395797320188;1094.529352378469;6751.324867513209;6789.0834737310815;6801.863962720718;6807.761728339583;6831.829204269834;7423.814404639819;7443.255674432622;7452;7455.85088298231;7481;5709.800156994485;6625.701843417058;6760.121184246016;6954.687039083254;6976.511644184859;3953.3081710700512;3989.6409323160474;3998.4402183694306;4007.458993035961;4020.959790402117;7874.173211812792;7883.2905038546805;7885.763427097197;7888.635727705101;7900.802479938047;6035.913535563641;6543.9202531582705;7533.4349923755935;7836.686532538634;7854.803629909184;7626.6186690665245;7885.605719714106;7893.881591776128;7908.248716371935;7911.960440197762;7518.849623007619;7565;7572.39420846342;7581.203973582695;7598.962005189938;5362.731863406736;6266.780662676784;7374.889376659462;7407.629618518945;7414.518056326321;7646.732364367221;7891.289783919475;7892.4080693947035;7902.802429939297;7926.960365198137;4722.173619616622;4772.403449568804;4778.37881075458;4787.760611969457;4790.77835152034;7459.627018649155;7499.800031994854;7514.211007844317;7515.473916825769;7524.435667325082;7190.676419561028;7215.783526494213;7217.494775365674;7218.205997340393;7235.746748863765;7071.080759501232;7128.679209435484;7220.458465614995;7225.530340527849;7231.385332246818;7029.61337126463;7031.117241930784;7044.401225895856;7052.365287124184;7072.257412971564;7196.316349946746;7210.05328561275;7225.963870180615;7236.457265705199;7237.3486386225495;5791.82624521265;7079.1505019398;7086.57480551148;7097.467689923174;7098.041764361745 17:04:22 INFO - PROCESS | 1674 | |5;yui.html;688.5214775730863;779.3609240422855;791.0546896458733;793.2464159048905;806.0971711682914;12297.938510307458;12325.876741232607;12357.69105772355;12526.874731252685;12569.748605027906;11996.82004769927;14405.27973601321;14548.490802821876;14554.272286385665;14590.489332873352;783.8980932478798;792.6551949901797;933.3419938943028;991.6281394477071;991.9752006199867;864.7665130414784;959.6928982725495;971.4171497101718;979.7795496013412;979.8530220466952;971.6259240192542;998.5057396588699;999.1357475783468;1025.08255111675;1041.5833666533372;1022.8516865054563;1022.918166546682;1033.4161198922623;1033.8707661542308;1037.8235699930992;753.2881427051439;758.6282721466497;770.6146926536732;771.0285040848571;772.420684486635;15390.384384624602;15476.76784848228;15731.528054158394;15774.76337854933;15804.841951580453;13513.729725405437;13539.458421663121;13574.932125339408;13594.524191653245;13685.521006764715;10495;10553;10557.313774604627;10576.365418074864;10706.518206680717;3496.091016335771;3497.8600855965733;3501;3502.194495266103;3644.599094099647;88088;88627;90714.99999999935;91371;91898.5405072977;9842.311038227399;9979.501024948724;9982.450965196838;9987;10242;22748.545029099656;23205.53588928212;23213.88393058024;23250;23271.883640581687;7706.036745406825;7807.375409967189;7811;7879.290863822283;7959.721409750631;4125.071858831799;4168.749875007509;4225.746455212636;4238.152369526107;4243.766592837424;3240.4653232216892;3698.63013698628;3747.512823332949;3787.507624008861;3791.7345785795283;651.9674016299166;662.6487961380476;662.7580932959531;674.9730010799512;688.786476192382;565.3187908570163;610.1457958857635;612.984675383119;621.1521273461692;624.7032659486707;8499.872501912476;8571.442856214326;8601.870971935426;8669.133086691407;8674.349423793241;600.3516202501288;604.3473049106954;605.6093819486495;624.8233386393706;640.0942666127378;3893.9221215576094;3968.047668559525;4064.9390259146135;4075.5109386873764;4096.508418989741;97051.51474242724;97385.51307243419;98233;98573.50713246531;98583.01416985739;80405;81494.18505814867;81838;84715.57642211873;88549;55538.44461555414;69990;70759;73065.63467182737;74992.87510687344;7873.37013038967;7921;8069.6772129116525;8114.472559283628;8146.4297499176855;53358.99999999845;53520;54415.72792136014;54580.90838183294;55449.44550554443;72941;74305.6284718573;74440;74601.62699186469;75059.62470187614;10417.583296668048;10469.371837689763;10515.94742026285;10748.89251107479;10826.729331766453;11002.064824490008;11305.943470282926;11456.599019034295;11459.828102578635;12002.939985300018;10176;10571.788564228826;10642.73393165177;10720.946395267974;10777.784444310913;9882.752931176778;10085.142762865238;10194.490275486047;10640.733981650521;11151.275167114112;9187.236844730824;9761.804763904824;10347.223958203014;10462.529186186488;10555;8558.058613552628;8901.55492225399;9066.909330906607;9110.498922559058;9315.767105822408;7618.771436856903;8982.056884027083;9101.137019853002;9227.677031303872;9322.207612352755 17:04:22 INFO - PROCESS | 1674 | __end_tp_report 17:04:22 INFO - PROCESS | 1674 | __start_cc_report 17:04:22 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,355 17:04:22 INFO - PROCESS | 1674 | __end_cc_report 17:04:22 INFO - PROCESS | 1674 | __startTimestamp1464653062001__endTimestamp 17:04:22 INFO - PROCESS | 1674 | 17:04:22 INFO - PROCESS | 1674 | ------- Summary: start ------- 17:04:22 INFO - PROCESS | 1674 | Number of tests: 6 17:04:22 INFO - PROCESS | 1674 | 17:04:22 INFO - PROCESS | 1674 | [#0] dojo.html Cycles:165 Average:5825.93 Median:6075.21 stddev:636.78 (10.5%) stddev-sans-first:614.86 17:04:22 INFO - PROCESS | 1674 | Values: 3624.2 3651.9 3672.4 3692.3 3703.0 5794.3 5799.1 5814.4 5834.3 5863.7 5841.4 5868.0 5878.8 5917.9 5928.1 4724.7 4841.3 4905.2 4975.0 5324.9 4684.3 4949.7 5375.5 5393.3 5401.4 5280.5 5360.8 5389.4 5394.6 5411.6 5329.5 5363.2 5400.1 5405.6 5421.6 5264.4 5323.0 5346.1 5382.9 5424.2 5461.3 5738.7 5748.0 5751.8 5812.1 6217.9 6221.7 6230.6 6255.8 6278.6 6181.0 6232.6 6331.6 6360.0 6364.2 4951.3 6019.3 6033.2 6060.1 6072.6 6387.1 6397.0 6398.6 6401.2 6407.8 5902.7 6200.6 6243.0 6254.2 6264.0 6250.1 6295.0 6303.4 6314.0 6321.0 6232.6 6238.8 6257.6 6270.4 6288.4 6150.0 6172.0 6185.8 6235.0 6261.8 4361.4 4906.4 4956.3 5010.0 5016.6 4459.9 4499.2 4522.0 4540.3 4565.5 5883.8 5946.7 5956.4 6012.8 6068.5 6263.5 6280.6 6308.2 6331.6 6343.2 5860.2 6052.0 6063.5 6074.8 6075.2 5445.8 5492.8 5497.7 5546.3 5553.3 6484.8 6519.5 6529.7 6532.4 6538.7 6173.7 6203.6 6226.3 6287.9 6322.3 6402.6 6408.4 6437.5 6483.2 6527.0 5748.0 6222.6 6255.7 6266.5 6267.5 5514.7 6082.4 6168.1 6273.6 6288.1 5638.3 5982.1 6150.5 6251.5 6259.8 6185.5 6205.8 6231.2 6244.1 6254.7 5868.6 6056.1 6067.3 6158.1 6166.5 6174.6 6175.4 6206.4 6211.4 6218.7 6081.1 6114.6 6131.0 6132.1 6141.4 17:04:22 INFO - PROCESS | 1674 | 17:04:22 INFO - PROCESS | 1674 | [#1] ext.html Cycles:180 Average:60887.61 Median:19042.04 stddev:123011.00 (646.0%) stddev-sans-first:123272.48 17:04:22 INFO - PROCESS | 1674 | Values: 486.6 576.3 617.8 652.9 682.6 39148.6 40025.8 40031.6 40039.0 40144.6 41482.6 41679.2 42088.2 42146.2 42183.2 9711.7 9892.3 9899.3 9940.6 9945.9 9731.5 10005.2 10020.4 10034.0 10044.7 9978.0 10013.2 10042.4 10071.8 10079.5 9940.5 9953.7 10007.6 10047.8 10051.8 9990.8 9991.1 10037.1 10063.5 10085.9 35233.0 40541.6 41162.2 41245.2 41252.4 2749.5 3367.4 3400.5 3449.9 3456.6 27770.0 27925.6 28210.0 28348.3 29708.9 2796.9 2814.1 2819.2 2833.4 2868.6 334150.0 338118.0 340332.0 340477.0 341262.0 48732.8 70410.6 70590.6 70924.0 70954.3 47901.5 47933.5 48017.0 48026.0 48026.0 17006.8 17262.1 17278.8 17294.2 17325.4 13341.9 13636.9 13757.3 13758.0 13759.9 15247.3 15302.6 15481.8 15722.4 15735.2 5515.0 6795.6 6797.9 6813.6 6831.4 954.2 1080.0 1089.7 1099.8 1174.6 30844.0 30954.4 31107.9 31319.4 31566.0 1153.9 1204.2 1234.9 1244.5 1267.6 15061.6 15490.8 15830.6 15950.2 16093.3 527566.0 532076.0 533738.0 537731.0 538467.0 63173.0 63361.0 63793.4 63824.7 64079.4 1524.4 1539.1 1567.0 1575.3 1575.4 26015.6 29295.4 30069.5 30335.8 30821.0 55349.4 55457.4 55659.7 55858.0 55886.9 435903.0 495380.0 505193.0 511101.0 511680.3 34257.5 35412.6 35514.5 35743.3 36206.6 38252.4 47098.8 48127.6 48637.0 49536.8 33196.0 38676.8 38710.0 39745.6 39752.4 38263.4 38718.8 38815.0 39256.8 39625.8 31583.1 34627.5 38242.0 38346.0 39851.8 31486.1 32337.4 32561.7 32726.0 32770.5 25219.9 26469.5 26722.6 26918.3 27003.9 17:04:22 INFO - PROCESS | 1674 | 17:04:22 INFO - PROCESS | 1674 | [#2] jquery.html Cycles:180 Average:37112.55 Median:21781.23 stddev:113393.10 (520.6%) stddev-sans-first:113677.65 17:04:22 INFO - PROCESS | 1674 | Values: 377.9 382.8 384.8 385.1 390.1 19680.1 20684.8 21399.6 21578.2 21646.6 20860.0 21121.9 21130.7 21547.7 21915.9 4886.5 5779.9 6065.6 6085.3 6091.9 6530.0 6608.4 6642.7 6648.8 6649.2 6335.6 6472.2 6493.5 6543.1 6562.7 6423.0 6440.4 6464.5 6500.0 6528.6 5530.5 5662.1 5700.2 5733.1 5751.3 23777.0 24309.6 24952.6 26047.5 26538.5 2099.2 2246.5 2283.5 2353.5 2788.5 9768.7 9834.7 9953.1 10149.8 10819.4 1722.7 1752.6 1798.7 1807.8 1810.8 110081.7 110321.0 111605.0 113641.4 116357.0 11741.8 11913.9 11938.7 11988.9 12048.6 14113.5 14152.0 14429.6 14517.3 14547.1 9018.1 9021.7 9077.6 9094.7 9134.0 7569.4 9258.3 9391.9 9406.5 9478.4 4152.5 4337.3 4522.7 4599.8 4671.5 1843.4 1888.1 1961.8 1966.4 1968.1 791.9 828.3 936.0 988.6 1024.0 19142.2 20729.6 20760.3 20774.5 20945.0 1013.4 1034.0 1043.5 1052.3 1087.4 10660.7 11051.3 11101.7 11135.5 11181.7 680858.0 683844.0 683963.0 685243.7 693141.0 123989.4 124383.0 125909.0 127384.7 128276.7 1063.6 1064.0 1105.2 1118.3 1130.3 17563.2 18024.3 18157.5 18329.9 18434.0 48744.5 48856.8 49305.8 49671.8 50865.7 58189.5 72796.6 73407.3 74133.0 75429.9 8054.4 8193.5 8412.4 8454.5 8699.2 12326.8 13546.4 13575.8 13586.2 13949.4 11521.9 11571.8 11684.9 11971.6 12074.0 9151.8 9438.7 11725.0 11803.8 11884.8 11318.3 11651.2 11799.9 11930.3 12280.8 7903.5 7951.9 7953.9 8091.4 8238.1 10701.8 11085.3 11153.4 11781.5 11935.8 17:04:22 INFO - PROCESS | 1674 | 17:04:22 INFO - PROCESS | 1674 | [#3] mootools.html Cycles:180 Average:6264.74 Median:556.62 stddev:3320.55 (596.6%) stddev-sans-first:3310.16 17:04:22 INFO - PROCESS | 1674 | Values: 1452.9 1668.4 1682.5 2045.0 2075.5 8395.7 8969.3 9072.2 9317.0 9336.3 8944.8 8948.3 9039.5 9055.7 9073.5 1861.6 2537.4 2546.5 2553.9 2554.2 2322.5 2575.9 2583.6 2627.5 2633.3 2519.3 2522.4 2526.5 2537.9 2541.2 2540.8 2566.5 2568.1 2571.2 2596.8 2172.7 2308.9 2633.2 2655.5 2660.8 8863.1 8873.1 8890.8 8914.5 8968.8 5796.6 5858.0 5863.2 5942.6 5957.3 3142.7 3719.9 3764.5 3849.5 3873.9 930.3 1089.2 1110.7 1111.5 1113.7 12372.4 12506.2 12577.3 12596.4 12657.4 4440.0 5120.4 5126.6 5212.9 5287.2 10661.1 10697.8 10847.8 10875.5 10903.2 5704.8 5709.3 5815.8 5857.4 5924.8 4754.1 5106.8 5196.7 5207.7 5308.7 4157.2 4165.8 4182.0 4194.2 4197.4 1377.1 1390.0 1404.3 1418.0 1419.0 1382.7 1392.1 1443.7 1446.9 1466.2 8566.4 8572.6 8606.4 8616.7 8632.3 541.9 546.4 554.5 558.8 560.3 6404.4 6406.9 6432.2 6471.6 6530.7 9534.7 9621.5 10742.5 11351.4 11432.9 10800.4 10949.8 10982.0 11166.0 11219.1 8698.4 8787.5 8828.6 8933.5 8968.7 8139.3 8181.0 8263.8 8291.4 8307.0 9738.0 9762.4 9965.2 10050.1 10183.6 9866.5 10009.0 10064.6 10096.0 10465.5 5910.8 6513.4 7768.6 7831.1 7872.3 7086.4 8436.5 8487.4 8512.9 8515.4 6776.0 7310.7 7771.1 7771.3 7819.1 6663.6 7096.8 7477.4 7665.6 7776.4 8161.3 8178.8 8210.0 8238.5 8267.9 7025.5 7033.8 7074.4 7433.1 7460.5 7038.3 7499.4 7508.5 7548.8 7574.3 17:04:22 INFO - PROCESS | 1674 | 17:04:22 INFO - PROCESS | 1674 | [#4] prototype.html Cycles:180 Average:5853.97 Median:6596.49 stddev:1888.95 (28.6%) stddev-sans-first:1848.43 17:04:22 INFO - PROCESS | 1674 | Values: 344.1 348.3 349.1 349.1 350.2 6692.1 6705.1 6763.9 6770.4 6800.4 4708.9 5566.5 6567.3 6635.3 6792.7 4041.8 4878.4 4897.4 4991.9 5101.1 3796.4 4960.2 4986.9 5021.8 5087.1 4677.1 4954.3 5011.3 5039.2 5047.3 3967.7 4229.5 5026.5 5038.3 5039.7 4323.3 4342.4 4354.0 4357.7 4358.3 6234.8 6292.7 6427.4 6430.8 6458.8 5842.8 5879.0 5879.4 5895.6 5921.6 5489.5 5876.4 7001.1 7147.4 7166.8 4823.9 4837.5 4843.1 4849.9 4865.3 7517.7 7517.8 7547.0 7556.5 7565.7 3757.5 4746.9 4770.1 4903.4 4922.9 4806.5 4831.3 4842.7 4853.8 4860.1 5716.8 5772.6 5777.0 5790.9 5795.0 7203.1 7239.3 7243.4 7247.1 7252.9 1664.3 1683.8 1687.4 1691.3 1691.7 1046.0 1075.9 1084.8 1089.1 1094.5 6751.3 6789.1 6801.9 6807.8 6831.8 7423.8 7443.3 7452.0 7455.9 7481.0 5709.8 6625.7 6760.1 6954.7 6976.5 3953.3 3989.6 3998.4 4007.5 4021.0 7874.2 7883.3 7885.8 7888.6 7900.8 6035.9 6543.9 7533.4 7836.7 7854.8 7626.6 7885.6 7893.9 7908.2 7912.0 7518.8 7565.0 7572.4 7581.2 7599.0 5362.7 6266.8 7374.9 7407.6 7414.5 7646.7 7891.3 7892.4 7902.8 7927.0 4722.2 4772.4 4778.4 4787.8 4790.8 7459.6 7499.8 7514.2 7515.5 7524.4 7190.7 7215.8 7217.5 7218.2 7235.7 7071.1 7128.7 7220.5 7225.5 7231.4 7029.6 7031.1 7044.4 7052.4 7072.3 7196.3 7210.1 7226.0 7236.5 7237.3 5791.8 7079.2 7086.6 7097.5 7098.0 17:04:22 INFO - PROCESS | 1674 | 17:04:22 INFO - PROCESS | 1674 | [#5] yui.html Cycles:180 Average:19065.94 Median:611.57 stddev:27651.89 (4521.5%) stddev-sans-first:27695.03 17:04:22 INFO - PROCESS | 1674 | Values: 688.5 779.4 791.1 793.2 806.1 12297.9 12325.9 12357.7 12526.9 12569.7 11996.8 14405.3 14548.5 14554.3 14590.5 783.9 792.7 933.3 991.6 992.0 864.8 959.7 971.4 979.8 979.9 971.6 998.5 999.1 1025.1 1041.6 1022.9 1022.9 1033.4 1033.9 1037.8 753.3 758.6 770.6 771.0 772.4 15390.4 15476.8 15731.5 15774.8 15804.8 13513.7 13539.5 13574.9 13594.5 13685.5 10495.0 10553.0 10557.3 10576.4 10706.5 3496.1 3497.9 3501.0 3502.2 3644.6 88088.0 88627.0 90715.0 91371.0 91898.5 9842.3 9979.5 9982.5 9987.0 10242.0 22748.5 23205.5 23213.9 23250.0 23271.9 7706.0 7807.4 7811.0 7879.3 7959.7 4125.1 4168.7 4225.7 4238.2 4243.8 3240.5 3698.6 3747.5 3787.5 3791.7 652.0 662.6 662.8 675.0 688.8 565.3 610.1 613.0 621.2 624.7 8499.9 8571.4 8601.9 8669.1 8674.3 600.4 604.3 605.6 624.8 640.1 3893.9 3968.0 4064.9 4075.5 4096.5 97051.5 97385.5 98233.0 98573.5 98583.0 80405.0 81494.2 81838.0 84715.6 88549.0 55538.4 69990.0 70759.0 73065.6 74992.9 7873.4 7921.0 8069.7 8114.5 8146.4 53359.0 53520.0 54415.7 54580.9 55449.4 72941.0 74305.6 74440.0 74601.6 75059.6 10417.6 10469.4 10515.9 10748.9 10826.7 11002.1 11305.9 11456.6 11459.8 12002.9 10176.0 10571.8 10642.7 10720.9 10777.8 9882.8 10085.1 10194.5 10640.7 11151.3 9187.2 9761.8 10347.2 10462.5 10555.0 8558.1 8901.6 9066.9 9110.5 9315.8 7618.8 8982.1 9101.1 9227.7 9322.2 17:04:22 INFO - PROCESS | 1674 | -------- Summary: end -------- 17:04:22 INFO - PROCESS | 1674 | 17:04:22 INFO - TEST-INFO | 1674: exit 0 17:04:22 INFO - TEST-OK | dromaeo_css | took 1110039ms 17:04:22 INFO - TEST-START | kraken 17:04:22 INFO - operating with platform_type : mac_ 17:04:22 INFO - Initialising browser for kraken test... 17:04:22 INFO - TEST-INFO | started process 1683 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/tmpuHbFrE/profile http://localhost:49272/getInfo.html) 17:04:25 INFO - PROCESS | 1683 | __metrics Screen width/height:1600/1200 17:04:25 INFO - PROCESS | 1683 | colorDepth:24 17:04:25 INFO - PROCESS | 1683 | Browser inner width/height: 1024/689 17:04:25 INFO - PROCESS | 1683 | __metrics 17:04:25 ERROR - PROCESS | 1683 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2844: TypeError: this.tabs is undefined 17:04:25 INFO - TEST-INFO | 1683: exit 0 17:04:25 INFO - Browser initialized. 17:04:25 INFO - Running cycle 1/1 for kraken test... 17:04:25 INFO - TEST-INFO | started process 1687 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/tmpuHbFrE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:04:37 INFO - PROCESS | 1687 | RSS: Main: 167940096 17:04:37 INFO - PROCESS | 1687 | 17:04:40 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 17:04:41 INFO - PROCESS | 1687 | RSS: Main: 180264960 17:04:41 INFO - PROCESS | 1687 | 17:04:43 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 17:04:44 INFO - PROCESS | 1687 | RSS: Main: 189243392 17:04:44 INFO - PROCESS | 1687 | 17:04:47 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 17:04:48 INFO - PROCESS | 1687 | RSS: Main: 192565248 17:04:48 INFO - PROCESS | 1687 | 17:04:50 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 17:04:50 INFO - PROCESS | 1687 | RSS: Main: 194158592 17:04:50 INFO - PROCESS | 1687 | 17:04:52 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:04:53 INFO - PROCESS | 1687 | RSS: Main: 194777088 17:04:53 INFO - PROCESS | 1687 | 17:04:56 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 17:04:56 INFO - PROCESS | 1687 | RSS: Main: 195084288 17:04:56 INFO - PROCESS | 1687 | 17:04:59 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 17:05:00 INFO - PROCESS | 1687 | RSS: Main: 194465792 17:05:00 INFO - PROCESS | 1687 | 17:05:03 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 17:05:03 INFO - PROCESS | 1687 | RSS: Main: 195026944 17:05:03 INFO - PROCESS | 1687 | 17:05:05 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:05:05 INFO - PROCESS | 1687 | RSS: Main: 194236416 17:05:05 INFO - PROCESS | 1687 | 17:05:07 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:05:08 INFO - PROCESS | 1687 | RSS: Main: 194400256 17:05:08 INFO - PROCESS | 1687 | 17:05:10 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:05:11 INFO - PROCESS | 1687 | RSS: Main: 194576384 17:05:11 INFO - PROCESS | 1687 | 17:05:13 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:05:14 INFO - PROCESS | 1687 | RSS: Main: 194568192 17:05:14 INFO - PROCESS | 1687 | 17:05:16 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:05:17 INFO - PROCESS | 1687 | RSS: Main: 194662400 17:05:17 INFO - PROCESS | 1687 | 17:05:19 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 17:05:19 INFO - PROCESS | 1687 | RSS: Main: 196206592 17:05:19 INFO - PROCESS | 1687 | 17:05:19 INFO - PROCESS | 1687 | __start_tp_report 17:05:19 INFO - PROCESS | 1687 | _x_x_mozilla_page_load 17:05:19 INFO - PROCESS | 1687 | _x_x_mozilla_page_load_details 17:05:19 INFO - PROCESS | 1687 | |i|pagename|runs| 17:05:19 INFO - PROCESS | 1687 | |0;ai-astar;121;127;115;115;118;129;115;115;117;116 17:05:19 INFO - PROCESS | 1687 | |1;audio-beat-detection;146;119;153;148;120;140;136;117;160;120 17:05:19 INFO - PROCESS | 1687 | |2;audio-dft;194;241;278;199;196;196;193;194;196;196 17:05:19 INFO - PROCESS | 1687 | |3;audio-fft;68;74;71;110;58;56;63;77;158;46 17:05:19 INFO - PROCESS | 1687 | |4;audio-oscillator;85;79;113;78;84;81;118;80;79;120 17:05:19 INFO - PROCESS | 1687 | |5;imaging-gaussian-blur;88;86;87;85;87;85;89;85;92;123 17:05:19 INFO - PROCESS | 1687 | |6;imaging-darkroom;124;106;122;104;123;98;154;152;127;93 17:05:19 INFO - PROCESS | 1687 | |7;imaging-desaturate;92;92;90;97;94;91;90;94;91;91 17:05:19 INFO - PROCESS | 1687 | |8;json-parse-financial;51;55;55;59;59;60;58;60;59;56 17:05:19 INFO - PROCESS | 1687 | |9;json-stringify-tinderbox;67;79;95;102;99;70;67;65;67;65 17:05:19 INFO - PROCESS | 1687 | |10;stanford-crypto-aes;84;94;109;91;70;108;111;109;80;86 17:05:19 INFO - PROCESS | 1687 | |11;stanford-crypto-ccm;124;137;137;117;128;145;132;137;117;119 17:05:19 INFO - PROCESS | 1687 | |12;stanford-crypto-pbkdf2;122;135;133;132;145;123;171;168;156;122 17:05:19 INFO - PROCESS | 1687 | |13;stanford-crypto-sha256-iterative;68;81;65;67;69;64;66;69;67;67 17:05:19 INFO - PROCESS | 1687 | __end_tp_report 17:05:19 INFO - PROCESS | 1687 | __start_cc_report 17:05:19 INFO - PROCESS | 1687 | _x_x_mozilla_cycle_collect,913 17:05:19 INFO - PROCESS | 1687 | __end_cc_report 17:05:19 INFO - PROCESS | 1687 | __startTimestamp1464653119318__endTimestamp 17:05:19 INFO - PROCESS | 1687 | 17:05:19 INFO - PROCESS | 1687 | ------- Summary: start ------- 17:05:19 INFO - PROCESS | 1687 | Number of tests: 14 17:05:19 INFO - PROCESS | 1687 | 17:05:19 INFO - PROCESS | 1687 | [#0] ai-astar Cycles:10 Average:118.80 Median:117.50 stddev:5.22 (4.4%) stddev-sans-first:5.48 17:05:19 INFO - PROCESS | 1687 | Values: 121.0 127.0 115.0 115.0 118.0 129.0 115.0 115.0 117.0 116.0 17:05:19 INFO - PROCESS | 1687 | 17:05:19 INFO - PROCESS | 1687 | [#1] audio-beat-detection Cycles:10 Average:135.90 Median:143.00 stddev:15.94 (11.1%) stddev-sans-first:16.48 17:05:19 INFO - PROCESS | 1687 | Values: 146.0 119.0 153.0 148.0 120.0 140.0 136.0 117.0 160.0 120.0 17:05:19 INFO - PROCESS | 1687 | 17:05:19 INFO - PROCESS | 1687 | [#2] audio-dft Cycles:10 Average:208.30 Median:196.00 stddev:28.41 (14.5%) stddev-sans-first:29.65 17:05:19 INFO - PROCESS | 1687 | Values: 194.0 241.0 278.0 199.0 196.0 196.0 193.0 194.0 196.0 196.0 17:05:19 INFO - PROCESS | 1687 | 17:05:19 INFO - PROCESS | 1687 | [#3] audio-fft Cycles:10 Average:78.10 Median:65.50 stddev:32.87 (50.2%) stddev-sans-first:34.66 17:05:19 INFO - PROCESS | 1687 | Values: 68.0 74.0 71.0 110.0 58.0 56.0 63.0 77.0 158.0 46.0 17:05:19 INFO - PROCESS | 1687 | 17:05:19 INFO - PROCESS | 1687 | [#4] audio-oscillator Cycles:10 Average:91.70 Median:79.50 stddev:17.68 (22.2%) stddev-sans-first:18.58 17:05:19 INFO - PROCESS | 1687 | Values: 85.0 79.0 113.0 78.0 84.0 81.0 118.0 80.0 79.0 120.0 17:05:19 INFO - PROCESS | 1687 | 17:05:19 INFO - PROCESS | 1687 | [#5] imaging-gaussian-blur Cycles:10 Average:90.70 Median:87.00 stddev:11.56 (13.3%) stddev-sans-first:12.22 17:05:19 INFO - PROCESS | 1687 | Values: 88.0 86.0 87.0 85.0 87.0 85.0 89.0 85.0 92.0 123.0 17:05:19 INFO - PROCESS | 1687 | 17:05:19 INFO - PROCESS | 1687 | [#6] imaging-darkroom Cycles:10 Average:120.30 Median:139.50 stddev:20.88 (15.0%) stddev-sans-first:22.10 17:05:19 INFO - PROCESS | 1687 | Values: 124.0 106.0 122.0 104.0 123.0 98.0 154.0 152.0 127.0 93.0 17:05:19 INFO - PROCESS | 1687 | 17:05:19 INFO - PROCESS | 1687 | [#7] imaging-desaturate Cycles:10 Average:92.20 Median:92.00 stddev:2.20 (2.4%) stddev-sans-first:2.33 17:05:19 INFO - PROCESS | 1687 | Values: 92.0 92.0 90.0 97.0 94.0 91.0 90.0 94.0 91.0 91.0 17:05:19 INFO - PROCESS | 1687 | 17:05:19 INFO - PROCESS | 1687 | [#8] json-parse-financial Cycles:10 Average:57.20 Median:59.00 stddev:2.90 (4.9%) stddev-sans-first:2.03 17:05:19 INFO - PROCESS | 1687 | Values: 51.0 55.0 55.0 59.0 59.0 60.0 58.0 60.0 59.0 56.0 17:05:19 INFO - PROCESS | 1687 | 17:05:19 INFO - PROCESS | 1687 | [#9] json-stringify-tinderbox Cycles:10 Average:77.60 Median:68.50 stddev:15.17 (22.1%) stddev-sans-first:15.59 17:05:19 INFO - PROCESS | 1687 | Values: 67.0 79.0 95.0 102.0 99.0 70.0 67.0 65.0 67.0 65.0 17:05:19 INFO - PROCESS | 1687 | 17:05:19 INFO - PROCESS | 1687 | [#10] stanford-crypto-aes Cycles:10 Average:94.20 Median:82.00 stddev:14.45 (17.6%) stddev-sans-first:14.85 17:05:19 INFO - PROCESS | 1687 | Values: 84.0 94.0 109.0 91.0 70.0 108.0 111.0 109.0 80.0 86.0 17:05:19 INFO - PROCESS | 1687 | 17:05:19 INFO - PROCESS | 1687 | [#11] stanford-crypto-ccm Cycles:10 Average:129.30 Median:134.50 stddev:9.83 (7.3%) stddev-sans-first:10.24 17:05:19 INFO - PROCESS | 1687 | Values: 124.0 137.0 137.0 117.0 128.0 145.0 132.0 137.0 117.0 119.0 17:05:19 INFO - PROCESS | 1687 | 17:05:19 INFO - PROCESS | 1687 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:140.70 Median:140.00 stddev:18.55 (13.2%) stddev-sans-first:18.40 17:05:19 INFO - PROCESS | 1687 | Values: 122.0 135.0 133.0 132.0 145.0 123.0 171.0 168.0 156.0 122.0 17:05:19 INFO - PROCESS | 1687 | 17:05:19 INFO - PROCESS | 1687 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:67.50 stddev:4.74 (7.0%) stddev-sans-first:5.02 17:05:19 INFO - PROCESS | 1687 | Values: 68.0 81.0 65.0 67.0 69.0 64.0 66.0 69.0 67.0 67.0 17:05:19 INFO - PROCESS | 1687 | -------- Summary: end -------- 17:05:19 INFO - PROCESS | 1687 | 17:05:19 INFO - TEST-INFO | 1687: exit 0 17:05:20 INFO - TEST-OK | kraken | took 57422ms 17:05:20 INFO - SUITE-END | took 1167s 17:05:20 INFO - Completed test suite (00:19:27) 17:05:20 INFO - javascript benchmark 17:05:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3624.2207925296066, 3651.9087022824424, 3672.375696131657, 3692.2800053989477, 3702.999999999998, 5794.304683437988, 5799.072148456248, 5814.418558144189, 5834.329052159003, 5863.706814659271, 5841.357450680422, 5868.0, 5878.794242201524, 5917.911231331534, 5928.1404196391495, 4724.716517008973, 4841.298011788289, 4905.215165573509, 4975.0, 5324.893502129955, 4684.2973553967, 4949.703017818924, 5375.516203541681, 5393.325834270716, 5401.378841433227, 5280.5247527722495, 5360.812371566996, 5389.380221274545, 5394.649347792381, 5411.648242864221, 5329.493698098674, 5363.168708850135, 5400.108982017962, 5405.621606487547, 5421.620486565942, 5264.447233040552, 5323.041852466555, 5346.144616861284, 5382.8923421531745, 5424.213489044067, 5461.344638643388, 5738.684372359518, 5747.971260143672, 5751.827445176651, 5812.128180772876, 6217.937820621781, 6221.720022598994, 6230.626162430269, 6255.78104766331, 6278.591891527082, 6181.0, 6232.626042437423, 6331.55679102463, 6359.968200159016, 6364.204474440695, 4951.257311403282, 6019.307779605358, 6033.245844269466, 6060.121282414026, 6072.635641861502, 6387.137736405619, 6397.0, 6398.616083035033, 6401.167848179707, 6407.839804004937, 5902.734376953047, 6200.596961197508, 6243.0, 6254.186955695731, 6264.029075493306, 6250.093736408196, 6294.9685251574365, 6303.401176888188, 6313.968430157912, 6321.0, 6232.594881332699, 6238.75044998195, 6257.5932564383165, 6270.372962703785, 6288.434040936338, 6150.0, 6171.9691401542705, 6185.752569897154, 6235.033569796688, 6261.843453913689, 4361.389405483234, 4906.386701662293, 4956.306117143603, 5010.0, 5016.623753218523, 4459.910801783946, 4499.212637788374, 4521.954780452219, 4540.250858608359, 4565.452145742533, 5883.764649414061, 5946.732397042144, 5956.404359564009, 6012.849678758066, 6068.544859135494, 6263.530235232376, 6280.591761535486, 6308.243010838729, 6331.620102793755, 6343.238811342576, 5860.238169037998, 6052.001419765689, 6063.454289113913, 6074.75700971965, 6075.210222671025, 5445.782168713286, 5492.780288788484, 5497.697626630574, 5546.278983732089, 5553.305836770403, 6484.805455836333, 6519.478441724651, 6529.738810447624, 6532.379423954717, 6538.738450461929, 6173.6913154343, 6203.596766210182, 6226.3151053385, 6287.905681414874, 6322.272938612118, 6402.647854368054, 6408.359164083555, 6437.549371544134, 6483.222013358239, 6526.967365163144, 5747.994101032219, 6222.595531290452, 6255.718492667932, 6266.467350275278, 6267.5299352549655, 5514.696691681996, 6082.391760823883, 6168.136460895388, 6273.62358258506, 6288.088227207121, 5638.295213098363, 5982.102684597346, 6150.538709596887, 6251.499880009679, 6259.812205633838, 6185.505159587132, 6205.751769929333, 6231.22109736283, 6244.094606282155, 6254.687265636791, 5868.559858010752, 6056.06131049688, 6067.332593414809, 6158.076288556573, 6166.537509686881, 6174.567780255518, 6175.351588083367, 6206.410391012847, 6211.440970312694, 6218.657973811303, 6081.1486391906055, 6114.633122012693, 6131.049687298476, 6132.110843927517, 6141.355157708376], "unit": "score", "name": "dojo.html", "value": 5787.876040194347}, {"lowerIsBetter": false, "replicates": [486.61314255167116, 576.2940398012436, 617.7652492053019, 652.924913634932, 682.6245564939284, 39148.608513914885, 40025.79987100061, 40031.599684003224, 40039.0, 40144.598554014454, 41482.58517414825, 41679.166416671724, 42088.15823683532, 42146.15707685844, 42183.156336873246, 9711.708648740549, 9892.30753847231, 9899.307048506607, 9940.602375904955, 9945.850812237823, 9731.464769437678, 10005.249606279522, 10020.398776073422, 10034.046765557261, 10044.698659040205, 9977.950110249438, 10013.249006324519, 10042.397456152617, 10071.798564028715, 10079.5464204111, 9940.502974851228, 9953.701388958343, 10007.649732259337, 10047.849282260735, 10051.798964020752, 9990.750231244205, 9991.100800927952, 10037.146842518394, 10063.5471403787, 10085.949570252102, 35233.0, 40541.59458405407, 41162.176756465, 41245.1750964979, 41252.38121428181, 2749.5463248563965, 3367.3770352484867, 3400.506926495644, 3449.931001379958, 3456.602490713575, 27770.0, 27925.581116283272, 28210.0, 28348.29129271764, 29708.8514557428, 2796.930076748077, 2814.0713564523658, 2819.1824370932404, 2833.4333133373407, 2868.6270784798053, 334150.0, 338118.0, 340332.0, 340477.0, 341262.0, 48732.756336218095, 70410.64794675994, 70590.64704676444, 70924.0, 70954.2904570958, 47901.520984790404, 47933.52066479361, 48017.03965920662, 48026.0, 48026.0, 17006.80952333325, 17262.136893155293, 17278.827211727974, 17294.221760020828, 17325.393611223546, 13341.866581334256, 13636.863631363758, 13757.31213439324, 13758.0, 13759.862401375858, 15247.313870875616, 15302.617434564225, 15481.83886208539, 15722.449714260174, 15735.213239337987, 5514.959464997976, 6795.626240556718, 6797.898031529531, 6813.625250611264, 6831.385175334244, 954.1508057828538, 1080.0171843622422, 1089.716673664837, 1099.8185299425666, 1174.5712814822527, 30843.98721534168, 30954.380912381625, 31107.911223107083, 31319.373612527623, 31566.0, 1153.8961493465629, 1204.1811568133578, 1234.8641649418557, 1244.5457408045997, 1267.5563552756462, 15061.623459413602, 15490.845091549165, 15830.60423489422, 15950.165730940858, 16093.275802588912, 527566.0, 532076.0, 533738.0, 537731.0, 538467.0, 63173.0, 63361.049584256274, 63793.36206637967, 63824.68087659532, 64079.35920640734, 1524.4207201263448, 1539.0611726846614, 1566.9764953525707, 1575.3068649794054, 1575.3855996161506, 26015.609765853907, 29295.41409171762, 30069.549500970887, 30335.848320758254, 30821.0, 55349.446505534426, 55457.445425546844, 55659.72170139123, 55858.0, 55886.882262355335, 435903.0, 495380.0, 505193.0, 511101.0, 511680.3247951358, 34257.48613770845, 35412.645873541966, 35514.467282990256, 35743.28513429769, 36206.637933620324, 38252.42621360626, 47098.76450617725, 48127.55617331486, 48637.0, 49536.75231623819, 33196.004119876445, 38676.80661596674, 38710.0, 39745.60254397535, 39752.40371394489, 38263.42604860874, 38718.80640596779, 38815.029624259616, 39256.80371598238, 39625.80187099046, 31583.052508424786, 34627.4805877907, 38242.0439489015, 38346.0, 39851.80074099611, 31486.055418337488, 32337.35325293528, 32561.674383255864, 32726.0, 32770.508442372906, 25219.87390063111, 26469.470610588065, 26722.59916101221, 26918.32704182411, 27003.86498067497], "unit": "score", "name": "ext.html", "value": 19891.597678676917}, {"lowerIsBetter": false, "replicates": [377.9111908701456, 382.7550367764631, 384.81913500654684, 385.0988686473653, 390.14947414636083, 19680.11439485223, 20684.793152068476, 21399.57200855982, 21578.244761433332, 21646.567068658656, 20860.0, 21121.894390528025, 21130.683039754414, 21547.67678484824, 21915.890420547916, 4886.462489126211, 5779.913301300484, 6065.575409721321, 6085.2697676278685, 6091.878162436748, 6530.0, 6608.405243528082, 6642.734290628369, 6648.800535983928, 6649.235337936127, 6335.619862808247, 6472.158619379497, 6493.545451818374, 6543.116679248312, 6562.737490500374, 6423.036544518313, 6440.355964403522, 6464.482841372703, 6499.967500162493, 6528.575642583217, 5530.5299049580835, 5662.09406494963, 5700.173474846166, 5733.082706766939, 5751.25233719618, 23777.0, 24309.635355469683, 24952.625710614357, 26047.479050418886, 26538.469230615472, 2099.2442720620566, 2246.48330883896, 2283.486215601493, 2353.51752890657, 2788.539890917996, 9768.658096966643, 9834.655787047419, 9953.054459826304, 10149.847752283722, 10819.404932728698, 1722.7243641017503, 1752.6056637256643, 1798.7301904714266, 1807.792103908041, 1810.7736532933384, 110081.69754907285, 110321.0, 111605.0, 113641.43179284134, 116357.0, 11741.823872641917, 11913.940430297793, 11938.701532461759, 11988.88011119895, 12048.578299759467, 14113.506027288995, 14152.0, 14429.639259018608, 14517.346719397441, 14547.05444146127, 9018.09819018093, 9021.684241051531, 9077.636894524278, 9094.727158185266, 9134.0, 7569.432292577969, 9258.305627077996, 9391.90608093924, 9406.48264345454, 9478.383905046288, 4152.522459917088, 4337.262665346835, 4522.66080043998, 4599.793009314589, 4671.5094915034115, 1843.4469659102215, 1888.1125870840958, 1961.8234358907766, 1966.3904189701238, 1968.0848405491229, 791.8574656561874, 828.2918105020242, 936.0265324062898, 988.5897352598735, 1023.9590416383261, 19142.24488581103, 20729.58540829175, 20760.273390431263, 20774.48063798387, 20945.0, 1013.3514550687565, 1034.0486752188006, 1043.4626167523732, 1052.321252791945, 1087.3693257910393, 10660.680179594625, 11051.336919784839, 11101.72245693848, 11135.498902549405, 11181.720456988478, 680858.0, 683844.0, 683963.0, 685243.6561418993, 693141.0, 123989.38005310096, 124383.0, 125909.0, 127384.72615273914, 128276.71723282835, 1063.6170978447599, 1063.9787204255872, 1105.2429086076063, 1118.3178261260625, 1130.2596799096605, 17563.209655565784, 18024.2790288387, 18157.54606134857, 18329.90835045816, 18434.0, 48744.512554875415, 48856.75571622119, 49305.753471233846, 49671.751641241564, 50865.745671271405, 58189.5452613688, 72796.63601681958, 73407.26592734005, 74133.0, 75429.86855197066, 8054.395920306118, 8193.549354785544, 8412.369072319478, 8454.535000575028, 8699.173578510032, 12326.75346493083, 13546.390412431267, 13575.8460530856, 13586.184828910296, 13949.3722782473, 11521.942390287995, 11571.768564628492, 11684.88315116872, 11971.580994665144, 12074.034077273973, 9151.816963660822, 9438.716838494856, 11725.0, 11803.822942655695, 11884.82172767392, 11318.265631948978, 11651.242669226473, 11799.882001179878, 11930.343831089373, 12280.81578776337, 7903.486273392211, 7951.920480795118, 7953.880691789744, 8091.393145513992, 8238.093809680811, 10701.785964280827, 11085.27945683528, 11153.442327883411, 11781.52873885035, 11935.82096268574], "unit": "score", "name": "jquery.html", "value": 9779.119807888239}, {"lowerIsBetter": false, "replicates": [1452.8692417682407, 1668.3576822923171, 1682.5036614198807, 2044.9897750511245, 2075.4915045813777, 8395.706150284741, 8969.32730045246, 9072.228860546853, 9317.0, 9336.299777516688, 8944.776380590489, 8948.328875334344, 9039.502827344493, 9055.683051093212, 9073.455592664444, 1861.64628720543, 2537.4417628121782, 2546.503431830794, 2553.923382298534, 2554.246497283297, 2322.4890524084676, 2575.88408521616, 2583.599542070973, 2627.487639910218, 2633.262686447788, 2519.282004628681, 2522.445062086338, 2526.4694414173046, 2537.936551586207, 2541.2376287113884, 2540.8475491470476, 2566.4610431809156, 2568.139673209477, 2571.215779187366, 2596.7533084356955, 2172.663237198221, 2308.9422764430856, 2633.210036988896, 2655.5087308847924, 2660.773834224093, 8863.11368863115, 8873.06832782555, 8890.777730556723, 8914.50970196639, 8968.775780605472, 5796.565257605696, 5857.974854400463, 5863.179154918314, 5942.554308426886, 5957.344692083867, 3142.701443362877, 3719.851205951759, 3764.5482542095124, 3849.480320156771, 3873.86441474547, 930.3255140023497, 1089.2320913755862, 1110.7112150840758, 1111.5164903267105, 1113.6826004588654, 12372.44324005413, 12506.18709783861, 12577.308248046354, 12596.370181490889, 12657.430415631226, 4439.977800110979, 5120.359955005624, 5126.641135120581, 5212.947870521246, 5287.1804870244405, 10661.147108231324, 10697.786044279072, 10847.837282440769, 10875.45622718861, 10903.236773425784, 5704.771809127588, 5709.3434255060365, 5815.82552523425, 5857.355690874, 5924.822255332347, 4754.120487709784, 5106.821261255838, 5196.68819870809, 5207.661502002357, 5308.734563271821, 4157.210130075276, 4165.791710414467, 4182.0, 4194.2450358935075, 4197.370394440797, 1377.125525291447, 1390.0408717984558, 1404.262762049932, 1418.0357356997135, 1418.9503367382092, 1382.654336415896, 1392.1229625335973, 1443.6579318876977, 1446.9493567725078, 1466.2302291296942, 8566.357523185787, 8572.61423235956, 8606.44058136219, 8616.655333786579, 8632.266257368194, 541.8618252345626, 546.3634865381865, 554.464941331617, 558.7681112338335, 560.2828379673942, 6404.391582799627, 6406.8718625627225, 6432.228132624022, 6471.611703297723, 6530.738770449224, 9534.666286679932, 9621.51892405377, 10742.462876856125, 11351.432428378714, 11432.94283528577, 10800.405977671146, 10949.781004380027, 10982.011618954333, 11166.0, 11219.102471802236, 8698.391112622056, 8787.5166865823, 8828.646854125891, 8933.481308177625, 8968.730938071869, 8139.3488520917, 8181.018277806464, 8263.83472330538, 8291.419600627898, 8307.044689860744, 9738.0, 9762.365446246255, 9965.152961998021, 10050.145737612385, 10183.643572474928, 9866.457344846101, 10009.049140331657, 10064.597416103274, 10096.0, 10465.476726163814, 5910.822675319747, 6513.446357059703, 7768.572728499761, 7831.060272767077, 7872.330851877655, 7086.433085353263, 8436.53599052058, 8487.405881588229, 8512.872306915526, 8515.361347899056, 6776.0, 7310.671019804228, 7771.067471903408, 7771.300582947561, 7819.1398946117015, 6663.600183988976, 7096.751613693703, 7477.4391920605085, 7665.578393188428, 7776.377889768918, 8161.3062889652665, 8178.7955301117945, 8210.0, 8238.50568965864, 8267.917320826715, 7025.473089518408, 7033.839416496426, 7074.398676112588, 7433.145188303414, 7460.515066520658, 7038.260982596754, 7499.437542184249, 7508.549487030796, 7548.773536793905, 7574.3183113520045], "unit": "score", "name": "mootools.html", "value": 5034.46631132977}, {"lowerIsBetter": false, "replicates": [344.1345017281537, 348.258210012673, 349.10802898594096, 349.1463372055326, 350.23648446386875, 6692.130023096991, 6705.128333316674, 6763.932360676392, 6770.424513916317, 6800.421964133048, 4708.85873423797, 5566.526845218162, 6567.277599464067, 6635.336466353362, 6792.694328755218, 4041.818118184689, 4878.365812444395, 4897.363342765436, 4991.850244492671, 5101.107306221405, 3796.373598356268, 4960.181570040957, 4986.900261994758, 5021.799128034892, 5087.10975579274, 4677.111348843726, 4954.281629163769, 5011.32347133136, 5039.19372900336, 5047.26814611881, 3967.662748666367, 4229.49246090468, 5026.47222041684, 5038.345015148026, 5039.672421292605, 4323.265044942367, 4342.392065110887, 4354.042110735633, 4357.673174511892, 4358.2808836541935, 6234.844128896769, 6292.685365731695, 6427.42153206209, 6430.7749228776765, 6458.806235812933, 5842.824715258549, 5878.970605146989, 5879.441453061952, 5895.616784908967, 5921.555883308769, 5489.451054894518, 5876.44173803488, 7001.089858318386, 7147.392471639917, 7166.784996450115, 4823.85528434144, 4837.4920633333695, 4843.07981483517, 4849.854504364875, 4865.318855360252, 7517.699292028312, 7517.774466766005, 7546.981157543771, 7556.546607203585, 7565.6973721051645, 3757.4739536464917, 4746.905061898812, 4770.117528257283, 4903.387076615423, 4922.901541969212, 4806.543378379049, 4831.275308703653, 4842.709437433765, 4853.757312134449, 4860.076585448754, 5716.828495145152, 5772.624779389326, 5777.035807825359, 5790.855228619234, 5795.0, 7203.135623725187, 7239.276072392824, 7243.420526357879, 7247.057882475245, 7252.854942901113, 1664.267722202227, 1683.75585540096, 1687.4262750664832, 1691.2833474023876, 1691.6532110917233, 1045.994770026145, 1075.93544387337, 1084.7776205877776, 1089.1395797320188, 1094.529352378469, 6751.324867513209, 6789.0834737310815, 6801.863962720718, 6807.761728339583, 6831.829204269834, 7423.814404639819, 7443.255674432622, 7452.0, 7455.85088298231, 7481.0, 5709.800156994485, 6625.701843417058, 6760.121184246016, 6954.687039083254, 6976.511644184859, 3953.3081710700512, 3989.6409323160474, 3998.4402183694306, 4007.458993035961, 4020.959790402117, 7874.173211812792, 7883.2905038546805, 7885.763427097197, 7888.635727705101, 7900.802479938047, 6035.913535563641, 6543.9202531582705, 7533.4349923755935, 7836.686532538634, 7854.803629909184, 7626.6186690665245, 7885.605719714106, 7893.881591776128, 7908.248716371935, 7911.960440197762, 7518.849623007619, 7565.0, 7572.39420846342, 7581.203973582695, 7598.962005189938, 5362.731863406736, 6266.780662676784, 7374.889376659462, 7407.629618518945, 7414.518056326321, 7646.732364367221, 7891.289783919475, 7892.4080693947035, 7902.802429939297, 7926.960365198137, 4722.173619616622, 4772.403449568804, 4778.37881075458, 4787.760611969457, 4790.77835152034, 7459.627018649155, 7499.800031994854, 7514.211007844317, 7515.473916825769, 7524.435667325082, 7190.676419561028, 7215.783526494213, 7217.494775365674, 7218.205997340393, 7235.746748863765, 7071.080759501232, 7128.679209435484, 7220.458465614995, 7225.530340527849, 7231.385332246818, 7029.61337126463, 7031.117241930784, 7044.401225895856, 7052.365287124184, 7072.257412971564, 7196.316349946746, 7210.05328561275, 7225.963870180615, 7236.457265705199, 7237.3486386225495, 5791.82624521265, 7079.1505019398, 7086.57480551148, 7097.467689923174, 7098.041764361745], "unit": "score", "name": "prototype.html", "value": 5234.456835946328}, {"lowerIsBetter": false, "replicates": [688.5214775730863, 779.3609240422855, 791.0546896458733, 793.2464159048905, 806.0971711682914, 12297.938510307458, 12325.876741232607, 12357.69105772355, 12526.874731252685, 12569.748605027906, 11996.82004769927, 14405.27973601321, 14548.490802821876, 14554.272286385665, 14590.489332873352, 783.8980932478798, 792.6551949901797, 933.3419938943028, 991.6281394477071, 991.9752006199867, 864.7665130414784, 959.6928982725495, 971.4171497101718, 979.7795496013412, 979.8530220466952, 971.6259240192542, 998.5057396588699, 999.1357475783468, 1025.08255111675, 1041.5833666533372, 1022.8516865054563, 1022.918166546682, 1033.4161198922623, 1033.8707661542308, 1037.8235699930992, 753.2881427051439, 758.6282721466497, 770.6146926536732, 771.0285040848571, 772.420684486635, 15390.384384624602, 15476.76784848228, 15731.528054158394, 15774.76337854933, 15804.841951580453, 13513.729725405437, 13539.458421663121, 13574.932125339408, 13594.524191653245, 13685.521006764715, 10495.0, 10553.0, 10557.313774604627, 10576.365418074864, 10706.518206680717, 3496.091016335771, 3497.8600855965733, 3501.0, 3502.194495266103, 3644.599094099647, 88088.0, 88627.0, 90714.99999999935, 91371.0, 91898.5405072977, 9842.311038227399, 9979.501024948724, 9982.450965196838, 9987.0, 10242.0, 22748.545029099656, 23205.53588928212, 23213.88393058024, 23250.0, 23271.883640581687, 7706.036745406825, 7807.375409967189, 7811.0, 7879.290863822283, 7959.721409750631, 4125.071858831799, 4168.749875007509, 4225.746455212636, 4238.152369526107, 4243.766592837424, 3240.4653232216892, 3698.63013698628, 3747.512823332949, 3787.507624008861, 3791.7345785795283, 651.9674016299166, 662.6487961380476, 662.7580932959531, 674.9730010799512, 688.786476192382, 565.3187908570163, 610.1457958857635, 612.984675383119, 621.1521273461692, 624.7032659486707, 8499.872501912476, 8571.442856214326, 8601.870971935426, 8669.133086691407, 8674.349423793241, 600.3516202501288, 604.3473049106954, 605.6093819486495, 624.8233386393706, 640.0942666127378, 3893.9221215576094, 3968.047668559525, 4064.9390259146135, 4075.5109386873764, 4096.508418989741, 97051.51474242724, 97385.51307243419, 98233.0, 98573.50713246531, 98583.01416985739, 80405.0, 81494.18505814867, 81838.0, 84715.57642211873, 88549.0, 55538.44461555414, 69990.0, 70759.0, 73065.63467182737, 74992.87510687344, 7873.37013038967, 7921.0, 8069.6772129116525, 8114.472559283628, 8146.4297499176855, 53358.99999999845, 53520.0, 54415.72792136014, 54580.90838183294, 55449.44550554443, 72941.0, 74305.6284718573, 74440.0, 74601.62699186469, 75059.62470187614, 10417.583296668048, 10469.371837689763, 10515.94742026285, 10748.89251107479, 10826.729331766453, 11002.064824490008, 11305.943470282926, 11456.599019034295, 11459.828102578635, 12002.939985300018, 10176.0, 10571.788564228826, 10642.73393165177, 10720.946395267974, 10777.784444310913, 9882.752931176778, 10085.142762865238, 10194.490275486047, 10640.733981650521, 11151.275167114112, 9187.236844730824, 9761.804763904824, 10347.223958203014, 10462.529186186488, 10555.0, 8558.058613552628, 8901.55492225399, 9066.909330906607, 9110.498922559058, 9315.767105822408, 7618.771436856903, 8982.056884027083, 9101.137019853002, 9227.677031303872, 9322.207612352755], "unit": "score", "name": "yui.html", "value": 7028.125650251871}], "name": "dromaeo_css", "value": 7700.717504101225}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [121.0, 127.0, 115.0, 115.0, 118.0, 129.0, 115.0, 115.0, 117.0, 116.0], "unit": "score", "name": "ai-astar", "value": 118.8}, {"lowerIsBetter": true, "replicates": [146.0, 119.0, 153.0, 148.0, 120.0, 140.0, 136.0, 117.0, 160.0, 120.0], "unit": "score", "name": "audio-beat-detection", "value": 135.9}, {"lowerIsBetter": true, "replicates": [194.0, 241.0, 278.0, 199.0, 196.0, 196.0, 193.0, 194.0, 196.0, 196.0], "unit": "score", "name": "audio-dft", "value": 208.3}, {"lowerIsBetter": true, "replicates": [68.0, 74.0, 71.0, 110.0, 58.0, 56.0, 63.0, 77.0, 158.0, 46.0], "unit": "score", "name": "audio-fft", "value": 78.1}, {"lowerIsBetter": true, "replicates": [85.0, 79.0, 113.0, 78.0, 84.0, 81.0, 118.0, 80.0, 79.0, 120.0], "unit": "score", "name": "audio-oscillator", "value": 91.7}, {"lowerIsBetter": true, "replicates": [88.0, 86.0, 87.0, 85.0, 87.0, 85.0, 89.0, 85.0, 92.0, 123.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 90.7}, {"lowerIsBetter": true, "replicates": [124.0, 106.0, 122.0, 104.0, 123.0, 98.0, 154.0, 152.0, 127.0, 93.0], "unit": "score", "name": "imaging-darkroom", "value": 120.3}, {"lowerIsBetter": true, "replicates": [92.0, 92.0, 90.0, 97.0, 94.0, 91.0, 90.0, 94.0, 91.0, 91.0], "unit": "score", "name": "imaging-desaturate", "value": 92.2}, {"lowerIsBetter": true, "replicates": [51.0, 55.0, 55.0, 59.0, 59.0, 60.0, 58.0, 60.0, 59.0, 56.0], "unit": "score", "name": "json-parse-financial", "value": 57.2}, {"lowerIsBetter": true, "replicates": [67.0, 79.0, 95.0, 102.0, 99.0, 70.0, 67.0, 65.0, 67.0, 65.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 77.6}, {"lowerIsBetter": true, "replicates": [84.0, 94.0, 109.0, 91.0, 70.0, 108.0, 111.0, 109.0, 80.0, 86.0], "unit": "score", "name": "stanford-crypto-aes", "value": 94.2}, {"lowerIsBetter": true, "replicates": [124.0, 137.0, 137.0, 117.0, 128.0, 145.0, 132.0, 137.0, 117.0, 119.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 129.3}, {"lowerIsBetter": true, "replicates": [122.0, 135.0, 133.0, 132.0, 145.0, 123.0, 171.0, 168.0, 156.0, 122.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 140.7}, {"lowerIsBetter": true, "replicates": [68.0, 81.0, 65.0, 67.0, 69.0, 64.0, 66.0, 69.0, 67.0, 67.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 68.3}], "name": "kraken", "value": 1503.3000000000002}]} 17:05:20 INFO - Return code: 0 17:05:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 17:05:20 ERROR - # TBPL SUCCESS # 17:05:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:05:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:05:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:05:20 INFO - 2016-05-30 17:05:20.648 screenresolution[1692:11748] starting screenresolution argv=screenresolution get 17:05:20 INFO - 2016-05-30 17:05:20.651 screenresolution[1692:11748] Display 0: 1600x1200x32@60 17:05:20 INFO - 2016-05-30 17:05:20.656 screenresolution[1693:11751] starting screenresolution argv=screenresolution list 17:05:20 INFO - Available Modes on Display 0 17:05:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:05:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:05:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:05:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:05:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:05:20 INFO - Intel Iris: 17:05:20 INFO - Chipset Model: Intel Iris 17:05:20 INFO - Type: GPU 17:05:20 INFO - Bus: Built-In 17:05:20 INFO - VRAM (Dynamic, Max): 1536 MB 17:05:20 INFO - Vendor: Intel (0x8086) 17:05:20 INFO - Device ID: 0x0a2e 17:05:20 INFO - Revision ID: 0x0009 17:05:20 INFO - Displays: 17:05:20 INFO - Display: 17:05:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:05:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:05:20 INFO - Display Serial Number: 1600x1200 60 17:05:20 INFO - Main Display: Yes 17:05:20 INFO - Mirror: Off 17:05:20 INFO - Online: Yes 17:05:20 INFO - Rotation: Supported 17:05:20 INFO - Return code: 0 17:05:20 INFO - Running post-action listener: _resource_record_post_action 17:05:20 INFO - Running post-run listener: _resource_record_post_run 17:05:21 INFO - Total resource usage - Wall time: 1192s; CPU: 53.0%; Read bytes: 32480768; Write bytes: 512798208; Read time: 325; Write time: 1719 17:05:21 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 198679040; Write bytes: 198182912; Read time: 17903; Write time: 400 17:05:21 INFO - run-tests - Wall time: 1170s; CPU: 53.0%; Read bytes: 31559168; Write bytes: 304653824; Read time: 310; Write time: 1290 17:05:21 INFO - Running post-run listener: _upload_blobber_files 17:05:21 INFO - Blob upload gear active. 17:05:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:05:21 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:05:21 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:05:21 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:05:22 INFO - (blobuploader) - INFO - Open directory for files ... 17:05:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:05:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:05:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:05:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:05:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:05:23 INFO - (blobuploader) - INFO - Done attempting. 17:05:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:05:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:05:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:05:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:05:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:05:24 INFO - (blobuploader) - INFO - Done attempting. 17:05:24 INFO - (blobuploader) - INFO - Iteration through files over. 17:05:24 INFO - Return code: 0 17:05:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:05:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:05:24 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3c32447864992ea8910f8482552a03b6f70aa2fc0d6934d5a22d7b28fdf9eb1e3c6bfcfe21c5ca07e60669b731125c4ec5ddde07bb7280eafda2db7c3c6d7e7c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5b7cf53efb0af8d12569d0c428a48c1ebdb961731641edcaadcdcd93f571493dd29440a3ea7413a84692dbe3205beee4926cbd462ebd56d961eed5c59ae86930"} 17:05:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:05:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:05:24 INFO - Contents: 17:05:24 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3c32447864992ea8910f8482552a03b6f70aa2fc0d6934d5a22d7b28fdf9eb1e3c6bfcfe21c5ca07e60669b731125c4ec5ddde07bb7280eafda2db7c3c6d7e7c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5b7cf53efb0af8d12569d0c428a48c1ebdb961731641edcaadcdcd93f571493dd29440a3ea7413a84692dbe3205beee4926cbd462ebd56d961eed5c59ae86930"} 17:05:24 INFO - Running post-run listener: copy_logs_to_upload_dir 17:05:24 INFO - Copying logs to upload dir... 17:05:24 INFO - mkdir: /builds/slave/test/build/upload/logs 17:05:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1388.215440 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 23 mins, 8 secs) (at 2016-05-30 17:05:24.185866) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 17:05:24.189273) ========= 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.OyZg6PZdlu/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.qi3f9DE4qn/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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/3c32447864992ea8910f8482552a03b6f70aa2fc0d6934d5a22d7b28fdf9eb1e3c6bfcfe21c5ca07e60669b731125c4ec5ddde07bb7280eafda2db7c3c6d7e7c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5b7cf53efb0af8d12569d0c428a48c1ebdb961731641edcaadcdcd93f571493dd29440a3ea7413a84692dbe3205beee4926cbd462ebd56d961eed5c59ae86930"} 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.011095 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/3c32447864992ea8910f8482552a03b6f70aa2fc0d6934d5a22d7b28fdf9eb1e3c6bfcfe21c5ca07e60669b731125c4ec5ddde07bb7280eafda2db7c3c6d7e7c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5b7cf53efb0af8d12569d0c428a48c1ebdb961731641edcaadcdcd93f571493dd29440a3ea7413a84692dbe3205beee4926cbd462ebd56d961eed5c59ae86930"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 17:05:24.218648) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 17:05:24.218939) ========= 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.OyZg6PZdlu/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.qi3f9DE4qn/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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.005246 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 17:05:24.267654) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 17:05:24.267993) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 17:05:24.268343) ========= ========= Total master_lag: 0.16 =========