builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0243 starttime: 1465035006.18 results: success (0) buildid: 20160604011925 builduid: 03e7a287c64e4dec9f34514b4444eea2 revision: 240b7e66dfef9a2dccd39bb32846ab41ece57fb8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-04 03:10:06.181060) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-04 03:10:06.181462) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-04 03:10:06.181744) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-04 03:10:06.314149) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 03:10:06.314408) ========= 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.CwYtC5WvLU/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.CoITpf8il9/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-06-04 03:10:06-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.22M=0.001s 2016-06-04 03:10:06 (8.22 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.566524 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 03:10:06.904081) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 03:10:06.904358) ========= 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.CwYtC5WvLU/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.CoITpf8il9/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.020452 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 03:10:06.953966) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-06-04 03:10:06.954299) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 240b7e66dfef9a2dccd39bb32846ab41ece57fb8 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 240b7e66dfef9a2dccd39bb32846ab41ece57fb8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CwYtC5WvLU/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.CoITpf8il9/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-06-04 03:10:07,032 truncating revision to first 12 chars 2016-06-04 03:10:07,032 Setting DEBUG logging. 2016-06-04 03:10:07,032 attempt 1/10 2016-06-04 03:10:07,032 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/240b7e66dfef?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-04 03:10:09,290 unpacking tar archive at: mozilla-aurora-240b7e66dfef/testing/mozharness/ program finished with exit code 0 elapsedTime=2.514334 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-06-04 03:10:09.489255) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-04 03:10:09.492379) ========= script_repo_revision: 240b7e66dfef9a2dccd39bb32846ab41ece57fb8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-04 03:10:09.492802) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-04 03:10:09.493081) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-04 03:10:09.510953) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 4 secs) (at 2016-06-04 03:10:09.511300) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CwYtC5WvLU/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.CoITpf8il9/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 03:10:09 INFO - MultiFileLogger online at 20160604 03:10:09 in /builds/slave/test 03:10:09 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:10:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:10:09 INFO - {'append_to_log': False, 03:10:09 INFO - 'base_work_dir': '/builds/slave/test', 03:10:09 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:10:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:10:09 INFO - 'branch': 'Mozilla-Aurora', 03:10:09 INFO - 'buildbot_json_path': 'buildprops.json', 03:10:09 INFO - 'config_files': ('talos/mac_config.py',), 03:10:09 INFO - 'default_actions': ('clobber', 03:10:09 INFO - 'read-buildbot-config', 03:10:09 INFO - 'download-and-extract', 03:10:09 INFO - 'populate-webroot', 03:10:09 INFO - 'create-virtualenv', 03:10:09 INFO - 'install', 03:10:09 INFO - 'run-tests'), 03:10:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:10:09 INFO - 'download_minidump_stackwalk': True, 03:10:09 INFO - 'download_symbols': 'ondemand', 03:10:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:10:09 INFO - 'tooltool.py': '/tools/tooltool.py', 03:10:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:10:09 INFO - '/tools/misc-python/virtualenv.py')}, 03:10:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:10:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:10:09 INFO - 'installer_path': 'installer.exe', 03:10:09 INFO - 'log_level': 'info', 03:10:09 INFO - 'log_name': 'talos', 03:10:09 INFO - 'log_to_console': True, 03:10:09 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:10:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:10:09 INFO - 'opt_config_files': (), 03:10:09 INFO - 'pip_index': False, 03:10:09 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:10:09 INFO - 'cmd': ('bash', 03:10:09 INFO - '-c', 03:10:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:10:09 INFO - 'enabled': True, 03:10:09 INFO - 'halt_on_failure': False, 03:10:09 INFO - 'name': 'check_screen_resolution'},), 03:10:09 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:10:09 INFO - 'cmd': ('bash', 03:10:09 INFO - '-c', 03:10:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:10:09 INFO - 'enabled': True, 03:10:09 INFO - 'halt_on_failure': False, 03:10:09 INFO - 'name': 'check_screen_resolution'},), 03:10:09 INFO - 'run_cmd_checks_enabled': True, 03:10:09 INFO - 'sps_profile': False, 03:10:09 INFO - 'sps_profile_interval': 0, 03:10:09 INFO - 'suite': 'dromaeojs', 03:10:09 INFO - 'system_bits': '32', 03:10:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:10:09 INFO - 'title': 't-yosemite-r7-0243', 03:10:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:10:09 INFO - 'use_talos_json': True, 03:10:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:10:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:10:09 INFO - 'work_dir': 'build'} 03:10:09 INFO - ##### 03:10:09 INFO - ##### Running clobber step. 03:10:09 INFO - ##### 03:10:09 INFO - Running pre-action listener: _resource_record_pre_action 03:10:09 INFO - Running main action method: clobber 03:10:09 INFO - rmtree: /builds/slave/test/build 03:10:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:10:11 INFO - Running post-action listener: _resource_record_post_action 03:10:11 INFO - ##### 03:10:11 INFO - ##### Running read-buildbot-config step. 03:10:11 INFO - ##### 03:10:11 INFO - Running pre-action listener: _resource_record_pre_action 03:10:11 INFO - Running main action method: read_buildbot_config 03:10:11 INFO - Using buildbot properties: 03:10:11 INFO - { 03:10:11 INFO - "project": "", 03:10:11 INFO - "product": "firefox", 03:10:11 INFO - "script_repo_revision": "production", 03:10:11 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 03:10:11 INFO - "repository": "", 03:10:11 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 03:10:11 INFO - "buildid": "20160604011925", 03:10:11 INFO - "pgo_build": "False", 03:10:11 INFO - "basedir": "/builds/slave/test", 03:10:11 INFO - "buildnumber": 22, 03:10:11 INFO - "slavename": "t-yosemite-r7-0243", 03:10:11 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 03:10:11 INFO - "platform": "yosemite_r7", 03:10:11 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 03:10:11 INFO - "branch": "mozilla-aurora", 03:10:11 INFO - "repo_path": "releases/mozilla-aurora", 03:10:11 INFO - "revision": "240b7e66dfef9a2dccd39bb32846ab41ece57fb8", 03:10:11 INFO - "stage_platform": "macosx64", 03:10:11 INFO - "builduid": "03e7a287c64e4dec9f34514b4444eea2", 03:10:11 INFO - "slavebuilddir": "test" 03:10:11 INFO - } 03:10:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/I7SQXtJ8QZqX-K8VxCYgOQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 03:10:11 INFO - Running post-action listener: _resource_record_post_action 03:10:11 INFO - ##### 03:10:11 INFO - ##### Running download-and-extract step. 03:10:11 INFO - ##### 03:10:11 INFO - Running pre-action listener: _resource_record_pre_action 03:10:11 INFO - Running main action method: download_and_extract 03:10:11 INFO - mkdir: /builds/slave/test/build/tests 03:10:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:10:11 INFO - https://queue.taskcluster.net/v1/task/I7SQXtJ8QZqX-K8VxCYgOQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 03:10:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I7SQXtJ8QZqX-K8VxCYgOQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 03:10:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I7SQXtJ8QZqX-K8VxCYgOQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 03:10:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I7SQXtJ8QZqX-K8VxCYgOQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 03:10:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I7SQXtJ8QZqX-K8VxCYgOQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 03:10:41 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I7SQXtJ8QZqX-K8VxCYgOQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json: timed out 03:10:41 INFO - retry: attempt #1 caught exception: timed out 03:10:41 INFO - retry: Failed, sleeping 30 seconds before retrying 03:11:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I7SQXtJ8QZqX-K8VxCYgOQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #2 03:11:12 INFO - Downloaded 1199 bytes. 03:11:12 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 03:11:12 INFO - Using the following test package requirements: 03:11:12 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 03:11:12 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:11:12 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 03:11:12 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:11:12 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 03:11:12 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:11:12 INFO - u'jsshell-mac.zip'], 03:11:12 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:11:12 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 03:11:12 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 03:11:12 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:11:12 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 03:11:12 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:11:12 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 03:11:12 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:11:12 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 03:11:12 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:11:12 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 03:11:12 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 03:11:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:11:12 INFO - https://queue.taskcluster.net/v1/task/I7SQXtJ8QZqX-K8VxCYgOQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:11:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I7SQXtJ8QZqX-K8VxCYgOQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:11:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I7SQXtJ8QZqX-K8VxCYgOQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:11:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I7SQXtJ8QZqX-K8VxCYgOQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:11:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I7SQXtJ8QZqX-K8VxCYgOQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:11:13 INFO - Downloaded 18466840 bytes. 03:11:13 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:11:13 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:11:16 INFO - Return code: 0 03:11:16 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 03:11:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:11:16 INFO - https://queue.taskcluster.net/v1/task/I7SQXtJ8QZqX-K8VxCYgOQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:11:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I7SQXtJ8QZqX-K8VxCYgOQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:11:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I7SQXtJ8QZqX-K8VxCYgOQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:11:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I7SQXtJ8QZqX-K8VxCYgOQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:11:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I7SQXtJ8QZqX-K8VxCYgOQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:11:17 INFO - Downloaded 18466840 bytes. 03:11:17 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:11:17 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:11:21 INFO - Return code: 0 03:11:21 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')]} 03:11:21 INFO - https://queue.taskcluster.net/v1/task/I7SQXtJ8QZqX-K8VxCYgOQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 03:11:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I7SQXtJ8QZqX-K8VxCYgOQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 03:11:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I7SQXtJ8QZqX-K8VxCYgOQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 03:11:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I7SQXtJ8QZqX-K8VxCYgOQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 03:11:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I7SQXtJ8QZqX-K8VxCYgOQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 03:11:22 INFO - Downloaded 10840145 bytes. 03:11:22 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:11:22 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 03:11:22 INFO - Return code: 0 03:11:22 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')]} 03:11:22 INFO - https://queue.taskcluster.net/v1/task/I7SQXtJ8QZqX-K8VxCYgOQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 03:11:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I7SQXtJ8QZqX-K8VxCYgOQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:11:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I7SQXtJ8QZqX-K8VxCYgOQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:11:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I7SQXtJ8QZqX-K8VxCYgOQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 03:11:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I7SQXtJ8QZqX-K8VxCYgOQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 03:11:25 INFO - Downloaded 114174117 bytes. 03:11:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/I7SQXtJ8QZqX-K8VxCYgOQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:11:25 INFO - mkdir: /builds/slave/test/properties 03:11:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:11:25 INFO - Writing to file /builds/slave/test/properties/build_url 03:11:25 INFO - Contents: 03:11:25 INFO - build_url:https://queue.taskcluster.net/v1/task/I7SQXtJ8QZqX-K8VxCYgOQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:11:26 INFO - Running post-action listener: _resource_record_post_action 03:11:26 INFO - Running post-action listener: set_extra_try_arguments 03:11:26 INFO - ##### 03:11:26 INFO - ##### Running populate-webroot step. 03:11:26 INFO - ##### 03:11:26 INFO - Running pre-action listener: _resource_record_pre_action 03:11:26 INFO - Running main action method: populate_webroot 03:11:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:11:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:11:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:11:26 INFO - '--tppagecycles', 03:11:26 INFO - '7'], 03:11:26 INFO - 'tests': ['tsvgm']}}, 03:11:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:11:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:11:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:11:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:11:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:11:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:11:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:11:26 INFO - 'talos_options': ['--disable-e10s'], 03:11:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:11:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:11:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:11:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:11:26 INFO - 'talos_options': ['--disable-e10s'], 03:11:26 INFO - 'tests': ['damp', 'tps']}, 03:11:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:11:26 INFO - 'tests': ['damp', 'tps']}, 03:11:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:11:26 INFO - 'tests': ['dromaeo_dom']}, 03:11:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:11:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:11:26 INFO - 'tests': ['a11yr', 03:11:26 INFO - 'ts_paint', 03:11:26 INFO - 'tpaint', 03:11:26 INFO - 'sessionrestore', 03:11:26 INFO - 'sessionrestore_no_auto_restore', 03:11:26 INFO - 'tabpaint']}, 03:11:26 INFO - 'other-e10s': {'tests': ['a11yr', 03:11:26 INFO - 'ts_paint', 03:11:26 INFO - 'tpaint', 03:11:26 INFO - 'sessionrestore', 03:11:26 INFO - 'sessionrestore_no_auto_restore', 03:11:26 INFO - 'tabpaint']}, 03:11:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:11:26 INFO - 'ts_paint', 03:11:26 INFO - 'tpaint', 03:11:26 INFO - 'sessionrestore', 03:11:26 INFO - 'sessionrestore_no_auto_restore', 03:11:26 INFO - 'tabpaint']}, 03:11:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:11:26 INFO - 'ts_paint', 03:11:26 INFO - 'tpaint', 03:11:26 INFO - 'sessionrestore', 03:11:26 INFO - 'sessionrestore_no_auto_restore', 03:11:26 INFO - 'tabpaint']}, 03:11:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:11:26 INFO - 'tests': ['a11yr', 03:11:26 INFO - 'ts_paint', 03:11:26 INFO - 'tpaint', 03:11:26 INFO - 'sessionrestore', 03:11:26 INFO - 'sessionrestore_no_auto_restore', 03:11:26 INFO - 'tabpaint']}, 03:11:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:11:26 INFO - 'tests': ['a11yr', 03:11:26 INFO - 'ts_paint', 03:11:26 INFO - 'tpaint', 03:11:26 INFO - 'sessionrestore', 03:11:26 INFO - 'sessionrestore_no_auto_restore', 03:11:26 INFO - 'tabpaint']}, 03:11:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:11:26 INFO - 'tests': ['tsvgx', 03:11:26 INFO - 'tsvgr_opacity', 03:11:26 INFO - 'tart', 03:11:26 INFO - 'tscrollx', 03:11:26 INFO - 'cart']}, 03:11:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:11:26 INFO - 'tsvgr_opacity', 03:11:26 INFO - 'tart', 03:11:26 INFO - 'tscrollx', 03:11:26 INFO - 'cart']}, 03:11:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:11:26 INFO - 'talos_options': ['--disable-e10s'], 03:11:26 INFO - 'tests': ['tp5o']}, 03:11:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:11:26 INFO - 'tests': ['tp5o']}, 03:11:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:11:26 INFO - 'talos_options': ['--disable-e10s', 03:11:26 INFO - '--xperf_path', 03:11:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:11:26 INFO - 'tests': ['tp5n']}, 03:11:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:11:26 INFO - 'talos_options': ['--xperf_path', 03:11:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:11:26 INFO - 'tests': ['tp5n']}}, 03:11:26 INFO - 'talos.zip': {'path': '', 03:11:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:11:26 INFO - Running post-action listener: _resource_record_post_action 03:11:26 INFO - ##### 03:11:26 INFO - ##### Running create-virtualenv step. 03:11:26 INFO - ##### 03:11:26 INFO - Running pre-action listener: _resource_record_pre_action 03:11:26 INFO - Running main action method: create_virtualenv 03:11:26 INFO - Creating virtualenv /builds/slave/test/build/venv 03:11:26 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 03:11:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:11:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:11:27 INFO - Using real prefix '/tools/python27' 03:11:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:11:27 INFO - Installing distribute.............................................................................................................................................................................................done. 03:11:30 INFO - Installing pip.................done. 03:11:30 INFO - Return code: 0 03:11:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:11:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:11:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:11:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:11:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:11:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:11:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:11:30 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 0x109688a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109698420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e31d64450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109527e00>, '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 0x109390db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109522b20>, '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.CwYtC5WvLU/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.CoITpf8il9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:11:30 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 03:11:30 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 03:11:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CwYtC5WvLU/Render', 03:11:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:11:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:11:30 INFO - 'HOME': '/Users/cltbld', 03:11:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:11:30 INFO - 'LOGNAME': 'cltbld', 03:11:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:11:30 INFO - 'MOZ_NO_REMOTE': '1', 03:11:30 INFO - 'NO_EM_RESTART': '1', 03:11:30 INFO - 'PAGER': '/bin/cat', 03:11:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:11:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:11:30 INFO - 'PWD': '/builds/slave/test', 03:11:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:11:30 INFO - 'SHELL': '/bin/bash', 03:11:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CoITpf8il9/Listeners', 03:11:30 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 03:11:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:11:30 INFO - 'USER': 'cltbld', 03:11:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:11:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:11:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:11:30 INFO - 'XPC_FLAGS': '0x0', 03:11:30 INFO - 'XPC_SERVICE_NAME': '0', 03:11:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:11:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:11:30 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 03:11:30 INFO - Cleaning up... 03:11:31 INFO - Return code: 0 03:11:31 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 03:11:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:11:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:11:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:11:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:11:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:11:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:11:31 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 0x109688a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109698420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e31d64450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109527e00>, '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 0x109390db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109522b20>, '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.CwYtC5WvLU/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.CoITpf8il9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:11:31 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 03:11:31 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 03:11:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CwYtC5WvLU/Render', 03:11:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:11:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:11:31 INFO - 'HOME': '/Users/cltbld', 03:11:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:11:31 INFO - 'LOGNAME': 'cltbld', 03:11:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:11:31 INFO - 'MOZ_NO_REMOTE': '1', 03:11:31 INFO - 'NO_EM_RESTART': '1', 03:11:31 INFO - 'PAGER': '/bin/cat', 03:11:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:11:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:11:31 INFO - 'PWD': '/builds/slave/test', 03:11:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:11:31 INFO - 'SHELL': '/bin/bash', 03:11:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CoITpf8il9/Listeners', 03:11:31 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 03:11:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:11:31 INFO - 'USER': 'cltbld', 03:11:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:11:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:11:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:11:31 INFO - 'XPC_FLAGS': '0x0', 03:11:31 INFO - 'XPC_SERVICE_NAME': '0', 03:11:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:11:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:11:31 INFO - Downloading/unpacking psutil>=0.7.1 03:11:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:11:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:11:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:11:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:11:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:11:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:11:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:11:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:11:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:11:34 INFO - Installing collected packages: psutil 03:11:34 INFO - Running setup.py install for psutil 03:11:34 INFO - building 'psutil._psutil_osx' extension 03:11:34 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 03:11:35 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 03:11:35 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 03:11:35 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 03:11:35 INFO - building 'psutil._psutil_posix' extension 03:11:35 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 03:11:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:11:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:11:35 INFO - ^ 03:11:35 INFO - 1 warning generated. 03:11:35 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 03:11:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:11:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:11:35 INFO - Successfully installed psutil 03:11:35 INFO - Cleaning up... 03:11:35 INFO - Return code: 0 03:11:35 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 03:11:35 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')]} 03:11:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:11:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:11:35 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')]} 03:11:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:11:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:11:35 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 0x109688a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109698420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e31d64450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109527e00>, '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 0x109390db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109522b20>, '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.CwYtC5WvLU/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.CoITpf8il9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:11:35 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 03:11:35 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 03:11:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CwYtC5WvLU/Render', 03:11:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:11:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:11:35 INFO - 'HOME': '/Users/cltbld', 03:11:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:11:35 INFO - 'LOGNAME': 'cltbld', 03:11:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:11:35 INFO - 'MOZ_NO_REMOTE': '1', 03:11:35 INFO - 'NO_EM_RESTART': '1', 03:11:35 INFO - 'PAGER': '/bin/cat', 03:11:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:11:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:11:35 INFO - 'PWD': '/builds/slave/test', 03:11:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:11:35 INFO - 'SHELL': '/bin/bash', 03:11:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CoITpf8il9/Listeners', 03:11:35 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 03:11:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:11:35 INFO - 'USER': 'cltbld', 03:11:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:11:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:11:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:11:35 INFO - 'XPC_FLAGS': '0x0', 03:11:35 INFO - 'XPC_SERVICE_NAME': '0', 03:11:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:11:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:11:35 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 03:11:35 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 03:11:35 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 03:11:35 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 03:11:35 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 03:11:35 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 03:11:35 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 03:11:39 INFO - Downloading mozsystemmonitor-0.0.tar.gz 03:11:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:11:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 03:11:39 INFO - Installing collected packages: mozsystemmonitor 03:11:39 INFO - Running setup.py install for mozsystemmonitor 03:11:39 INFO - Successfully installed mozsystemmonitor 03:11:39 INFO - Cleaning up... 03:11:39 INFO - Return code: 0 03:11:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:11:39 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')]} 03:11:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:11:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:11:39 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')]} 03:11:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:11:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:11:39 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 0x109688a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109698420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e31d64450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109527e00>, '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 0x109390db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109522b20>, '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.CwYtC5WvLU/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.CoITpf8il9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:11:39 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 03:11:39 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 03:11:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CwYtC5WvLU/Render', 03:11:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:11:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:11:39 INFO - 'HOME': '/Users/cltbld', 03:11:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:11:39 INFO - 'LOGNAME': 'cltbld', 03:11:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:11:39 INFO - 'MOZ_NO_REMOTE': '1', 03:11:39 INFO - 'NO_EM_RESTART': '1', 03:11:39 INFO - 'PAGER': '/bin/cat', 03:11:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:11:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:11:39 INFO - 'PWD': '/builds/slave/test', 03:11:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:11:39 INFO - 'SHELL': '/bin/bash', 03:11:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CoITpf8il9/Listeners', 03:11:39 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 03:11:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:11:39 INFO - 'USER': 'cltbld', 03:11:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:11:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:11:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:11:39 INFO - 'XPC_FLAGS': '0x0', 03:11:39 INFO - 'XPC_SERVICE_NAME': '0', 03:11:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:11:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:11:39 INFO - Downloading/unpacking blobuploader==1.2.4 03:11:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:11:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:11:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:11:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:11:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:11:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:11:42 INFO - Downloading blobuploader-1.2.4.tar.gz 03:11:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:11:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:11:42 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 03:11:42 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 03:11:42 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 03:11:42 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 03:11:42 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 03:11:42 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 03:11:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:11:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:11:42 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 03:11:42 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 03:11:42 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 03:11:42 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 03:11:42 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 03:11:42 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 03:11:43 INFO - Downloading docopt-0.6.1.tar.gz 03:11:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:11:43 INFO - Installing collected packages: blobuploader, requests, docopt 03:11:43 INFO - Running setup.py install for blobuploader 03:11:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:11:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:11:43 INFO - Running setup.py install for requests 03:11:44 INFO - Running setup.py install for docopt 03:11:44 INFO - Successfully installed blobuploader requests docopt 03:11:44 INFO - Cleaning up... 03:11:44 INFO - Return code: 0 03:11:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:11:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:11:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:11:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:11:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:11:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:11:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:11:44 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 0x109688a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109698420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e31d64450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109527e00>, '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 0x109390db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109522b20>, '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.CwYtC5WvLU/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.CoITpf8il9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:11:44 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 03:11:44 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 03:11:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CwYtC5WvLU/Render', 03:11:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:11:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:11:44 INFO - 'HOME': '/Users/cltbld', 03:11:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:11:44 INFO - 'LOGNAME': 'cltbld', 03:11:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:11:44 INFO - 'MOZ_NO_REMOTE': '1', 03:11:44 INFO - 'NO_EM_RESTART': '1', 03:11:44 INFO - 'PAGER': '/bin/cat', 03:11:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:11:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:11:44 INFO - 'PWD': '/builds/slave/test', 03:11:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:11:44 INFO - 'SHELL': '/bin/bash', 03:11:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CoITpf8il9/Listeners', 03:11:44 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 03:11:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:11:44 INFO - 'USER': 'cltbld', 03:11:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:11:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:11:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:11:44 INFO - 'XPC_FLAGS': '0x0', 03:11:44 INFO - 'XPC_SERVICE_NAME': '0', 03:11:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:11:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:11:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:11:44 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-1Lmbl7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:11:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:11:44 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-brzNIM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:11:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:11:44 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-oFkMIZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:11:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:11:44 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-cwcbbH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:11:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:11:45 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-O_Yqkk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:11:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:11:45 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-xxxyDc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:11:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:11:45 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-pQtv2s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:11:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:11:45 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-lJsrRh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:11:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:11:45 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-2WlBts-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:11:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:11:45 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-ykub0m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:11:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:11:45 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-URph8o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:11:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:11:45 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-SE39Y0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:11:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:11:45 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-c4fuyz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:11:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:11:45 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-OcUOpO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:11:46 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-DWQRPE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:11:46 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-1xlKKM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:11:46 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-Qr7T6p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:11:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:11:46 INFO - Running setup.py install for manifestparser 03:11:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:11:46 INFO - Running setup.py install for mozcrash 03:11:46 INFO - Running setup.py install for mozdebug 03:11:46 INFO - Running setup.py install for mozdevice 03:11:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:11:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:11:46 INFO - Running setup.py install for mozfile 03:11:47 INFO - Running setup.py install for mozhttpd 03:11:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:11:47 INFO - Running setup.py install for mozinfo 03:11:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:11:47 INFO - Running setup.py install for mozInstall 03:11:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:11:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:11:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:11:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:11:47 INFO - Running setup.py install for mozleak 03:11:47 INFO - Running setup.py install for mozlog 03:11:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:11:47 INFO - Running setup.py install for moznetwork 03:11:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:11:47 INFO - Running setup.py install for mozprocess 03:11:48 INFO - Running setup.py install for mozprofile 03:11:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:11:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:11:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:11:48 INFO - Running setup.py install for mozrunner 03:11:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:11:48 INFO - Running setup.py install for mozscreenshot 03:11:48 INFO - Running setup.py install for moztest 03:11:48 INFO - Running setup.py install for mozversion 03:11:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:11:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:11:48 INFO - Cleaning up... 03:11:48 INFO - Return code: 0 03:11:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:11: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')]} 03:11:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:11:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:11: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')]} 03:11:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:11:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:11:48 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 0x109688a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109698420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e31d64450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109527e00>, '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 0x109390db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109522b20>, '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.CwYtC5WvLU/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.CoITpf8il9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:11:48 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 03:11:48 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 03:11:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CwYtC5WvLU/Render', 03:11:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:11:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:11:48 INFO - 'HOME': '/Users/cltbld', 03:11:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:11:48 INFO - 'LOGNAME': 'cltbld', 03:11:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:11:48 INFO - 'MOZ_NO_REMOTE': '1', 03:11:48 INFO - 'NO_EM_RESTART': '1', 03:11:48 INFO - 'PAGER': '/bin/cat', 03:11:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:11:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:11:48 INFO - 'PWD': '/builds/slave/test', 03:11:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:11:48 INFO - 'SHELL': '/bin/bash', 03:11:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CoITpf8il9/Listeners', 03:11:48 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 03:11:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:11:48 INFO - 'USER': 'cltbld', 03:11:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:11:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:11:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:11:48 INFO - 'XPC_FLAGS': '0x0', 03:11:48 INFO - 'XPC_SERVICE_NAME': '0', 03:11:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:11:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:11:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:11:49 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-L5kN9k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:11:49 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)) 03:11:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:11:49 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-fe9lNM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:11:49 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)) 03:11:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:11:49 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-wXVqLo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:11:49 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)) 03:11:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:11:49 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-BSAqOO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:11:49 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)) 03:11:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:11:49 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-azeg4g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:11:49 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)) 03:11:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:11:49 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-YxQdfx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:11:49 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)) 03:11:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:11:49 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-JAYEIe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:11:49 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)) 03:11:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:11:49 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-m75Kme-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:11:50 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)) 03:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:11:50 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-sECRSP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:11:50 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)) 03:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:11:50 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-H7XftY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:11:50 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)) 03:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:11:50 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-18DkpU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:11:50 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)) 03:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:11:50 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-e83R95-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:11:50 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)) 03:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:11:50 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-qSl_7O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:11:50 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)) 03:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:11:50 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-XYzMVr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:11:50 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)) 03:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:11:50 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-d0pMCb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:11:50 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)) 03:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:11:50 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-dmK7o5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:11:50 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)) 03:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:11:50 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-5WwWdC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:11:51 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)) 03:11:51 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)) 03:11:51 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)) 03:11:51 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)) 03:11:51 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)) 03:11:51 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)) 03:11:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:11:51 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 03:11:51 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 03:11:51 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 03:11:51 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 03:11:51 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 03:11:51 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 03:11:53 INFO - Downloading blessings-1.6.tar.gz 03:11:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:11:53 INFO - Installing collected packages: blessings 03:11:53 INFO - Running setup.py install for blessings 03:11:53 INFO - Successfully installed blessings 03:11:53 INFO - Cleaning up... 03:11:53 INFO - Return code: 0 03:11:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:11:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:11:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:11:54 INFO - Reading from file tmpfile_stdout 03:11:54 INFO - Current package versions: 03:11:54 INFO - blessings == 1.6 03:11:54 INFO - blobuploader == 1.2.4 03:11:54 INFO - docopt == 0.6.1 03:11:54 INFO - manifestparser == 1.1 03:11:54 INFO - mozInstall == 1.12 03:11:54 INFO - mozcrash == 0.17 03:11:54 INFO - mozdebug == 0.1 03:11:54 INFO - mozdevice == 0.48 03:11:54 INFO - mozfile == 1.2 03:11:54 INFO - mozhttpd == 0.7 03:11:54 INFO - mozinfo == 0.9 03:11:54 INFO - mozleak == 0.1 03:11:54 INFO - mozlog == 3.1 03:11:54 INFO - moznetwork == 0.27 03:11:54 INFO - mozprocess == 0.22 03:11:54 INFO - mozprofile == 0.28 03:11:54 INFO - mozrunner == 6.11 03:11:54 INFO - mozscreenshot == 0.1 03:11:54 INFO - mozsystemmonitor == 0.0 03:11:54 INFO - moztest == 0.7 03:11:54 INFO - mozversion == 1.4 03:11:54 INFO - psutil == 3.1.1 03:11:54 INFO - requests == 1.2.3 03:11:54 INFO - wsgiref == 0.1.2 03:11:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:11:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:11:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:11:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:11:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:11:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:11:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:11:54 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 0x109688a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109698420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e31d64450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109527e00>, '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 0x109390db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109522b20>, '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.CwYtC5WvLU/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.CoITpf8il9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:11:54 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 03:11:54 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 03:11:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CwYtC5WvLU/Render', 03:11:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:11:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:11:54 INFO - 'HOME': '/Users/cltbld', 03:11:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:11:54 INFO - 'LOGNAME': 'cltbld', 03:11:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:11:54 INFO - 'MOZ_NO_REMOTE': '1', 03:11:54 INFO - 'NO_EM_RESTART': '1', 03:11:54 INFO - 'PAGER': '/bin/cat', 03:11:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:11:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:11:54 INFO - 'PWD': '/builds/slave/test', 03:11:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:11:54 INFO - 'SHELL': '/bin/bash', 03:11:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CoITpf8il9/Listeners', 03:11:54 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 03:11:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:11:54 INFO - 'USER': 'cltbld', 03:11:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:11:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:11:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:11:54 INFO - 'XPC_FLAGS': '0x0', 03:11:54 INFO - 'XPC_SERVICE_NAME': '0', 03:11:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:11:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:11:54 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)) 03:11:54 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)) 03:11:54 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)) 03:11:54 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)) 03:11:54 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)) 03:11:54 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)) 03:11:54 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)) 03:11:54 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)) 03:11:54 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)) 03:11:54 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)) 03:11:54 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)) 03:11:54 INFO - Cleaning up... 03:11:54 INFO - Return code: 0 03:11:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:11:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:11:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:11:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:11:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:11:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:11:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:11:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109688a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109698420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e31d64450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109527e00>, '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 0x109390db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109522b20>, '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.CwYtC5WvLU/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.CoITpf8il9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:11:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:11:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:11:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CwYtC5WvLU/Render', 03:11:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:11:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:11:54 INFO - 'HOME': '/Users/cltbld', 03:11:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:11:54 INFO - 'LOGNAME': 'cltbld', 03:11:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:11:54 INFO - 'MOZ_NO_REMOTE': '1', 03:11:54 INFO - 'NO_EM_RESTART': '1', 03:11:54 INFO - 'PAGER': '/bin/cat', 03:11:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:11:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:11:54 INFO - 'PWD': '/builds/slave/test', 03:11:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:11:54 INFO - 'SHELL': '/bin/bash', 03:11:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CoITpf8il9/Listeners', 03:11:54 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 03:11:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:11:54 INFO - 'USER': 'cltbld', 03:11:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:11:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:11:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:11:54 INFO - 'XPC_FLAGS': '0x0', 03:11:54 INFO - 'XPC_SERVICE_NAME': '0', 03:11:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:11:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:11:54 INFO - Downloading/unpacking jsonschema 03:11:54 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 03:11:54 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 03:11:54 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 03:11:54 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 03:11:54 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 03:11:54 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 03:11:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:11:57 INFO - Downloading/unpacking functools32 (from jsonschema) 03:11: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 03:11: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 03:11: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 03:11: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 03:11: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 03:11: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 03:11:57 INFO - Downloading functools32-3.2.3-2.tar.gz 03:11:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:11:57 INFO - warning: no files found matching '*.txt' 03:11:57 INFO - no previously-included directories found matching 'build' 03:11:57 INFO - no previously-included directories found matching 'dist' 03:11:57 INFO - no previously-included directories found matching '.git*' 03:11:57 INFO - Installing collected packages: jsonschema, functools32 03:11:57 INFO - Running setup.py install for functools32 03:11:57 INFO - warning: no files found matching '*.txt' 03:11:57 INFO - no previously-included directories found matching 'build' 03:11:57 INFO - no previously-included directories found matching 'dist' 03:11:57 INFO - no previously-included directories found matching '.git*' 03:11:57 INFO - Successfully installed jsonschema functools32 03:11:57 INFO - Cleaning up... 03:11:57 INFO - Return code: 0 03:11:57 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:11: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')]} 03:11:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:11:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:11: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')]} 03:11:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:11:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:11: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109688a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109698420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e31d64450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109527e00>, '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 0x109390db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109522b20>, '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.CwYtC5WvLU/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.CoITpf8il9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:11: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', 'flake8'] in /builds/slave/test/build 03:11: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 flake8 03:11:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CwYtC5WvLU/Render', 03:11:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:11:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:11:57 INFO - 'HOME': '/Users/cltbld', 03:11:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:11:57 INFO - 'LOGNAME': 'cltbld', 03:11:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:11:57 INFO - 'MOZ_NO_REMOTE': '1', 03:11:57 INFO - 'NO_EM_RESTART': '1', 03:11:57 INFO - 'PAGER': '/bin/cat', 03:11:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:11:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:11:57 INFO - 'PWD': '/builds/slave/test', 03:11:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:11:57 INFO - 'SHELL': '/bin/bash', 03:11:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CoITpf8il9/Listeners', 03:11:57 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 03:11:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:11:57 INFO - 'USER': 'cltbld', 03:11:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:11:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:11:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:11:57 INFO - 'XPC_FLAGS': '0x0', 03:11:57 INFO - 'XPC_SERVICE_NAME': '0', 03:11:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:11:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:11:58 INFO - Downloading/unpacking flake8 03:11:58 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 03:11:58 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 03:11:58 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 03:11:58 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 03:11:58 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 03:11:58 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 03:12:00 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:12:00 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:12:00 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 03:12:00 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 03:12:00 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 03:12:00 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 03:12:00 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 03:12:00 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 03:12:00 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:12:00 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:12:00 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 03:12:00 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 03:12:00 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 03:12:00 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 03:12:00 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 03:12:00 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 03:12:01 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:12:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:12:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:12:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:12:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:12:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:12:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:12:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:12:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:12:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:12:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:12:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:12:01 INFO - no previously-included directories found matching 'docs/_build' 03:12:01 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:12:01 INFO - Running setup.py install for pep8 03:12:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:12:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:12:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:12:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:12:01 INFO - no previously-included directories found matching 'docs/_build' 03:12:02 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:12:02 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:12:02 INFO - Cleaning up... 03:12:02 INFO - Return code: 0 03:12:02 INFO - Running post-action listener: _resource_record_post_action 03:12:02 INFO - Running post-action listener: _start_resource_monitoring 03:12:02 INFO - Starting resource monitoring. 03:12:02 INFO - ##### 03:12:02 INFO - ##### Running install step. 03:12:02 INFO - ##### 03:12:02 INFO - Running pre-action listener: _resource_record_pre_action 03:12:02 INFO - Running main action method: install 03:12:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:12:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:12:02 INFO - Reading from file tmpfile_stdout 03:12:02 INFO - Detecting whether we're running mozinstall >=1.0... 03:12:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:12:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:12:02 INFO - Reading from file tmpfile_stdout 03:12:02 INFO - Output received: 03:12:02 INFO - Usage: mozinstall [options] installer 03:12:02 INFO - Options: 03:12:02 INFO - -h, --help show this help message and exit 03:12:02 INFO - -d DEST, --destination=DEST 03:12:02 INFO - Directory to install application into. [default: 03:12:02 INFO - "/builds/slave/test"] 03:12:02 INFO - --app=APP Application being installed. [default: firefox] 03:12:02 INFO - mkdir: /builds/slave/test/build/application 03:12:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:12:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 03:12:34 INFO - Reading from file tmpfile_stdout 03:12:34 INFO - Output received: 03:12:34 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 03:12:34 INFO - Running post-action listener: _resource_record_post_action 03:12:34 INFO - ##### 03:12:34 INFO - ##### Running run-tests step. 03:12:34 INFO - ##### 03:12:34 INFO - Running pre-action listener: _resource_record_pre_action 03:12:34 INFO - Running main action method: run_tests 03:12:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:12:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:12:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:12:34 INFO - 2016-06-04 03:12:34.238 screenresolution[2290:31189] starting screenresolution argv=screenresolution get 03:12:34 INFO - 2016-06-04 03:12:34.241 screenresolution[2290:31189] Display 0: 1600x1200x32@60 03:12:34 INFO - 2016-06-04 03:12:34.247 screenresolution[2291:31191] starting screenresolution argv=screenresolution list 03:12:34 INFO - Available Modes on Display 0 03:12:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:12:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:12:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:12:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:12:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:12:34 INFO - Intel Iris: 03:12:34 INFO - Chipset Model: Intel Iris 03:12:34 INFO - Type: GPU 03:12:34 INFO - Bus: Built-In 03:12:34 INFO - VRAM (Dynamic, Max): 1536 MB 03:12:34 INFO - Vendor: Intel (0x8086) 03:12:34 INFO - Device ID: 0x0a2e 03:12:34 INFO - Revision ID: 0x0009 03:12:34 INFO - Displays: 03:12:34 INFO - Display: 03:12:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:12:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:12:34 INFO - Display Serial Number: 1600x1200 60 03:12:34 INFO - Main Display: Yes 03:12:34 INFO - Mirror: Off 03:12:34 INFO - Online: Yes 03:12:34 INFO - Rotation: Supported 03:12:34 INFO - Return code: 0 03:12:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:12:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:12:34 INFO - Python 2.7.3 03:12:34 INFO - Return code: 0 03:12:34 INFO - grabbing minidump binary from tooltool 03:12:34 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')]} 03:12:34 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 0x109527e00>, '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 0x109390db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109522b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:12:34 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 03:12:34 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 03:12:34 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:12:34 INFO - Return code: 0 03:12:34 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:12:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:12:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:12:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:12:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:12:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:12:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:12:35 INFO - Return code: 0 03:12:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/I7SQXtJ8QZqX-K8VxCYgOQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0243', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 03:12:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/I7SQXtJ8QZqX-K8VxCYgOQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0243 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 03:12:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CwYtC5WvLU/Render', 03:12:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:12:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:12:35 INFO - 'HOME': '/Users/cltbld', 03:12:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:12:35 INFO - 'LOGNAME': 'cltbld', 03:12:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:12:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:12:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:12:35 INFO - 'MOZ_NO_REMOTE': '1', 03:12:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:12:35 INFO - 'NO_EM_RESTART': '1', 03:12:35 INFO - 'PAGER': '/bin/cat', 03:12:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:12:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:12:35 INFO - 'PWD': '/builds/slave/test', 03:12:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:12:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:12:35 INFO - 'SHELL': '/bin/bash', 03:12:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CoITpf8il9/Listeners', 03:12:35 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 03:12:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:12:35 INFO - 'USER': 'cltbld', 03:12:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:12:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:12:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:12:35 INFO - 'XPC_FLAGS': '0x0', 03:12:35 INFO - 'XPC_SERVICE_NAME': '0', 03:12:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:12:35 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/I7SQXtJ8QZqX-K8VxCYgOQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0243', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 03:12:35 INFO - mozversion application_buildid: 20160604011925 03:12:35 INFO - mozversion application_changeset: 240b7e66dfef9a2dccd39bb32846ab41ece57fb8 03:12:35 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:12:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:12:35 INFO - mozversion application_name: Firefox 03:12:35 INFO - mozversion application_remotingname: firefox-dev 03:12:35 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:12:35 INFO - mozversion application_vendor: Mozilla 03:12:35 INFO - mozversion application_version: 48.0a2 03:12:35 INFO - mozversion platform_buildid: 20160604011925 03:12:35 INFO - mozversion platform_changeset: 240b7e66dfef9a2dccd39bb32846ab41ece57fb8 03:12:35 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:12:35 INFO - mozversion platform_version: 48.0a2 03:12:35 INFO - using testdate: 1465035155 03:12:35 INFO - actual date: 1465035155 03:12:35 INFO - starting webserver on 'localhost:56635' 03:12:35 INFO - SUITE-START | Running 2 tests 03:12:35 INFO - TEST-START | dromaeo_css 03:12:35 INFO - operating with platform_type : mac_ 03:12:35 INFO - Initialising browser for dromaeo_css test... 03:12:35 INFO - TEST-INFO | started process 2309 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/tmpc3LNgi/profile http://localhost:56635/getInfo.html) 03:12:38 INFO - PROCESS | 2309 | __metrics Screen width/height:1600/1200 03:12:38 INFO - PROCESS | 2309 | colorDepth:24 03:12:38 INFO - PROCESS | 2309 | Browser inner width/height: 1024/689 03:12:38 INFO - PROCESS | 2309 | __metrics 03:12:38 INFO - PROCESS | 2309 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:12:38 ERROR - PROCESS | 2309 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:12:38 INFO - TEST-INFO | 2309: exit 0 03:12:38 INFO - Browser initialized. 03:12:38 INFO - Running cycle 1/1 for dromaeo_css test... 03:12:38 INFO - TEST-INFO | started process 2311 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/tmpc3LNgi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:12:51 INFO - PROCESS | 2311 | RSS: Main: 210862080 03:12:51 INFO - PROCESS | 2311 | 03:13:42 INFO - PROCESS | 2311 | 1465035222781 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] 03:15:53 INFO - PROCESS | 2311 | Cycle 1(1): loaded http://localhost:56635/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56635/tests/dromaeo/cssquery-ext.html) 03:15:55 INFO - PROCESS | 2311 | RSS: Main: 328622080 03:15:55 INFO - PROCESS | 2311 | 03:18:58 INFO - PROCESS | 2311 | Cycle 1(1): loaded http://localhost:56635/tests/dromaeo/cssquery-ext.html (next: http://localhost:56635/tests/dromaeo/cssquery-jquery.html) 03:18:58 INFO - PROCESS | 2311 | RSS: Main: 357711872 03:18:58 INFO - PROCESS | 2311 | 03:22:00 INFO - PROCESS | 2311 | Cycle 1(1): loaded http://localhost:56635/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56635/tests/dromaeo/cssquery-mootools.html) 03:22:01 INFO - PROCESS | 2311 | RSS: Main: 360976384 03:22:01 INFO - PROCESS | 2311 | 03:25:03 INFO - PROCESS | 2311 | Cycle 1(1): loaded http://localhost:56635/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56635/tests/dromaeo/cssquery-prototype.html) 03:25:04 INFO - PROCESS | 2311 | RSS: Main: 361156608 03:25:04 INFO - PROCESS | 2311 | 03:28:07 INFO - PROCESS | 2311 | Cycle 1(1): loaded http://localhost:56635/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56635/tests/dromaeo/cssquery-yui.html) 03:28:08 INFO - PROCESS | 2311 | RSS: Main: 331390976 03:28:08 INFO - PROCESS | 2311 | 03:31:10 INFO - PROCESS | 2311 | Cycle 1(1): loaded http://localhost:56635/tests/dromaeo/cssquery-yui.html (next: http://localhost:56635/tests/dromaeo/cssquery-dojo.html) 03:31:11 INFO - PROCESS | 2311 | RSS: Main: 385036288 03:31:11 INFO - PROCESS | 2311 | 03:31:11 INFO - PROCESS | 2311 | __start_tp_report 03:31:11 INFO - PROCESS | 2311 | _x_x_mozilla_page_load 03:31:11 INFO - PROCESS | 2311 | _x_x_mozilla_page_load_details 03:31:11 INFO - PROCESS | 2311 | |i|pagename|runs| 03:31:11 INFO - PROCESS | 2311 | |0;dojo.html;3295.9176020599484;3341.8830340938066;3403.7447191460633;3413.7269018478523;3448.4654878493825;5640.407757185493;5675.2622159119255;5687.488126068653;5690.60165788395;5712.228849105375;5611.80358687446;5695.914561281584;5700.686462244575;5711.828645140642;5722.771089156439;5081.5426611605135;5144.048351055048;5171.870703232421;5223.608229382792;5258.92111618326;5223.817166399177;5230.7646155922885;5231.581473482112;5234.7120908350025;5247.422783493813;5179.404368497633;5188.974055129719;5203.0374380739495;5229.790808367661;5231.979763946032;5189.169732842746;5240.947590524084;5261.605379596507;5274.894502109974;5313.362396512404;5077.441481437039;5100.974495127538;5146.614003949681;5165.586753059746;5195.454477279869;5421.620486565942;5490.176473528963;5527.170924361378;5536.667799932017;5544.500994910478;6159.81520554384;6186.84532886677;6195.349488303708;6197.845053873689;6210.037444196157;6097.451229389332;6169.568130230886;6194.287656919422;6208.192934918432;6250.593711408789;5654.6324488908085;5737.627054241461;5753.367129615739;5782.132680098019;5785.305763308345;6172.105044768484;6241.656708881055;6267.780627678009;6283.43449089575;6286.339934306832;5468.425815289417;6057.636541807417;6088.056351265561;6098.512119030555;6099.969500152471;5173.844784656466;5936.376680448577;6114.023656277693;6130.581587307925;6150.477209437248;6020.277566692025;6025.849353766103;6030.065339872327;6031.577789554777;6036.245469316335;5983.880322393615;6024.246969128859;6045.667488288186;6067.332593414721;6132.906245166601;4866.367372241586;4874.95125048745;4878.390201224847;4882.877928051827;4916.139675556835;4389.3415987601475;4393.84621538251;4396.582324679214;4407.4050003249795;4438.889027774267;5508.586855985817;5569.916451253234;5584.441555844383;5607.579431542651;5632.971835140798;6115.635867915819;6116.633002019893;6137.8979746733885;6143.877122457526;6148.831721972811;5609.663420194802;5612.719364031782;5615.101583746581;5630.634008789416;5648.51987581052;5214.687118772886;5312.9734351328;5323.707196104251;5351.438098999549;5352.437994010573;5100.591952643779;6232.376762323732;6287.434130928147;6290.465310448663;6292.968535157295;5349.251104845403;5929.703514824155;6222.253329600296;6227.564070515021;6234.844128896814;6209.782657607143;6290.308066112815;6292.465140463112;6293.496520278275;6327.873442531216;6064.969675151596;6079.878402432015;6081.878362432815;6103.786367477117;6106.57253992234;6028.216331876828;6032.431567792318;6048.60684055535;6119.350229749377;6186.4741496973265;5973.1338955852025;6006.159137720635;6032.095185722177;6043.365446628217;6061.212042434456;5190.8183213587345;5640.182173584889;5969.343372229138;6002.549808764273;6007.158997740407;5688.544916406595;5786.9131963019745;5839.824805255849;5843;5848.766049357978;5931.5847890648965;6024.969875150771;6056.57603967718;6082;6088.5737998341465;5787.247657804459;5855.03391940325;5872.706364681834;5875.3243377012195;5895.263092113486 03:31:11 INFO - PROCESS | 2311 | |1;ext.html;503.561901146003;517.1854329431147;530.2523441946855;567.551634208975;576.8952456046669;29979.700202997963;37188.81405592975;37762.622373776256;37770.811145944266;37970.8101459493;40881;41424.757257282254;41435.17129657405;41494.58505414945;41562;7313.378362839138;9522.714318570437;9737.95131024344;9744.951275243613;9771.925088240288;8650.134986501362;11485.712857178554;11698.356590387526;11710.648680539554;11721.886420790011;11427.371494567837;11496.712582185428;11509.827352589718;11543.480543375526;11551.249168804043;8719.651213951434;10206;11437;11541.82687259696;11768.058555315554;11336.093112550976;11479.713007174803;11493.310401375944;11498.827517587242;11537.538498460135;28682.856585716938;38054.42918356227;38060.80969595162;38116.80941595302;38173.23653526914;3178.7933784303946;3332.583427071616;3380.5098260752297;3414.9487757683655;3454.464557993515;27429.725702743115;27653.308667283276;27791.305217369525;28302.433951320858;28307.858460707772;2693.138195777352;2760.5307097793425;2796.9021084261954;2871.8851245950345;2930.619019527469;501536;501875.4906225482;503239;503671;504162;36348;36390;36517;59092;60860;35232.82383588117;35250.64749352525;35385.82307088448;35392;35447.64552354495;15189.9240503799;15385.769213461806;15391.769123463157;15399.384024638888;15407.306671199598;11993.34036627994;12117.636470905887;13044.41300141496;13046.934765326112;13227.73544529104;7921.524708517392;7929.088154862149;7934.682612695543;8001.1198768135455;8003.31971782405;2912.8252304861776;3358.7145092667397;3379.425497665403;3381.458966565338;3413.6927676509235;1021.701172362185;1037.234837539726;1055.2138656700654;1101.6750058732694;1109.57836022311;23652.88173559121;29703.108906732832;29878.850605746833;30156.396872062436;30386.69613303883;992.593036854886;1111.2110401614782;1148.0700632487712;1212.1030437476381;1229.4344601483417;14986.400543978336;15209.923950380398;15339.926205165531;15352.385904563693;15438.45965391206;594643;626438;639359;639776;639901.8004909946;58682;59203;59385;59455.810883782084;59633;1203.813408921636;1261.369315342329;1385.1827421821174;1419.865112814281;1589.5628702107012;28861.422771544872;28864;28941.85529072341;29448.263793405335;29563.556546651387;47219.52780472151;47302.763486182346;47613.52386476091;47778.04443911033;47784;438242;441195;441316;441458;441999;23244.65133022972;26362.34094147585;26415.603765943142;26764.063257785885;27051;41481;42311;43288.567114329715;43751.78124109359;43850;34159.4876076864;34246.48630270498;36566.63433365632;36723.081922951074;38058;24986.875065624554;36467.088322792144;36541.634583653824;36811.26377472489;37075.62924370722;35005.47491787574;35560.82219588885;36045.81977090098;36388;36428.81785591055;32067.839660801546;32145;32280.838595806872;32386.352272953936;32428.337918111643;24308.149214777397;24460.89925953372;24717.011319547735;24740.876295618407;24911 03:31:11 INFO - PROCESS | 2311 | |2;jquery.html;408.552634864823;408.8752930356241;411.7879292164534;413.67319817344264;426.73542403709706;18944.62110757784;20345;21016.684749728767;21051;21591.244306449276;20271.290504832334;20732.585348293025;20740.792592074074;20767.480812979684;20787;5712.485876271135;5728.398518155596;5826.096954971965;5869.9706501467645;5913.970430147865;5763.135529670562;5847.678377689225;5858.970705146468;5866.706664666771;5878.588498805086;5593.608447408683;5841.2114364560875;5847.415258474161;5847.766089356421;5849.561282903799;4945.703257804507;5790.13148027795;5817.534597232211;5828.766849326022;5832.533397328225;4952.455229924705;5043.974780126113;5068.923966140511;5118.897622047538;5121.948780512184;25393.74606253932;25426.872865635552;25710.614340784905;25843.870780646164;25923.611145832827;2255.729312482513;2296.9770302297097;2333.5216280662594;2351.447409858682;2904.6804851466322;14668.70662586753;14755.409783608642;14756.704865902622;14781.778273325908;15265.931384803069;1659.402615058578;1672.1137796967625;1691.739653957797;1772.6277481728853;1830.2861883865303;109052.4547377258;118640.81359186384;119391.80608193894;120153;120162.39918800438;10170.745731356776;11098;11593.362365070003;11641.301521908712;11688.941555292338;14931.776023359658;15030.849691503163;15046.097234165985;15085.773713394308;15089.924550377178;6840.555363901331;8407.2433480987;8729.388942774072;8742;8874.822503549893;8775.868361974575;8780.78048048804;8789;8821.69124080667;8874.911250887537;4522.25382811839;4619.030003699261;4628.143793398232;4644.303354496785;4694.366260554782;1921.932732354361;1964.204497178645;1966.7148263501713;1981.9900900495404;1994.4914046917943;911.9407238529607;970.84466485362;999.2605471950704;1044.8850626431085;1053.8313869780798;19787.505312367306;20049.498762530762;20319.695204571944;20549.280775172796;20893.477663058544;961.110972350573;961.8605302231138;969.4086607169621;985.8964808694984;988.3773222869546;10759.408232547128;10891.782164356668;10899.891001090045;10900;10931.617393391192;591931;595416;610745;611117;620986;125886;128285;133009.3349533246;134567.65432345748;138292;1013.6502906497246;1015.8476228565627;1032.8708911386077;1033.715728174758;1042.7445275907603;17896.46310610684;18008.729869051713;18182.999935003703;18280.085995699897;18574.535636609195;51598.74200628973;51783.446496603654;52118.739406302724;54631;57129.42870571241;76844;77005.61497192478;77980.2201977973;78418.21581784109;79973;7513.668004421684;7938.722144724907;7980.401469889665;8108.635111419882;8212.794680132805;9919.454430006419;12187.329696866758;12229.327386993446;12505.749885002431;12616.116871818884;10415.635452759117;10805.567777288821;10874.347539147677;10973.945130274298;10996.395198264172;10635.989580989793;10643.680689579325;10770.784584308427;10834.512446939749;10848;8550;11054;11278.041366483612;11339.773204535699;11411.828822567502;7968.482048666818;8060.919390806252;8147.470414423044;8177.754667359989;8398.580070996304;9381.530923453936;10422.21833362516;10814.188935830001;11037.613683521038;11055.391953442639 03:31:11 INFO - PROCESS | 2311 | |3;mootools.html;1333.3599872061404;1561.4769052367458;1580.1862041048857;1796.3712700554806;1944.7763507196676;7826.413019023575;8520.062793092762;8664.826703465927;8671.916010498695;8681.696140635078;8396.244338009577;8651.783705407368;8657.95671021644;8660.30717542595;8663.220310172082;2169.2516081951703;2375.821813363996;2449.216250799745;2461.667674863893;2483.428811373376;2476.1333533263396;2480.268320845355;2480.3303108160785;2484.5403600334;2486.8632225227607;2395.880205989702;2437.5856104462196;2468.4075821802817;2469.3579669285937;2473.0849585653245;1826.6985947318676;2220.6113930062174;2221.278084622504;2222.4443889027743;2441.4628781668093;2400.063975049732;2474.3937735254976;2478.157426475007;2483.503299340121;2485.1674688979215;8602.655893764242;8671.4363566368;8684.392092553524;8686;8693.347998900108;5296.496832800878;5425.240466334716;5462.644928079662;5463.125899856004;5470.398256191816;3356.5636467259346;3431.7769344992744;3517.929641407183;3525.189206482498;3540.6990405815277;1021.6015753855928;1026.6098882424706;1029.526417847791;1043;1081.6106201767357;11818.409079546074;11831.112666549958;11835.467403966755;11890.524379024828;11948.999999999913;4754.881127971828;4796.040791841646;4809.798399713952;4873.707577545359;4901.975490122527;10158.034986676254;10166.339187952759;10170.033846784543;10182.083612474913;10200.285979981478;5504.834854954358;5508.228847961289;5527.695976721238;5530.723463826793;5541.224228607998;4259.510156332061;4402.339649052604;4767.73777442244;4886.60907127429;4902.068606964665;3997.600239975979;4033.8184781684895;4077.7553346799286;4114.300568903233;4158.875233742992;1264.279360764374;1265.702559898423;1277.2081309588116;1280.3726174174587;1314.3888092037057;814.8451794159091;874.3486102853415;883.5538053283179;923.4090182283344;948.1656142594611;5765.077587585966;5813.069908814654;5825.271841019872;5895.802304324223;6017.578769486094;889.1686273334453;903.0653273861521;914.6112902016616;946.4415994563241;960.1454705312277;3846.442265871489;3933.8819835404984;3947.8618248361167;4013.7190396672536;4014.7791871446766;10430.582776689;10627.256092073481;10898.400587967579;10936.890631093745;10944.890551094546;10628.787424251472;10756.19328550362;10773.030427261427;10780.407077610807;10790.51442685081;8024.558649274346;8320.875186872201;8496.702615408554;8528.786780330418;8537.359698022674;5741.679413734781;5777.653340799566;5796.507296879812;5864.091065884794;5901.291844978458;9383.061693830563;9421;9544.570494327632;9628.75928101803;9747.268954828558;9693.854592181264;9702.369345992487;9917.65288214938;10067.043630855058;10107.595696172071;6215.906761398493;6379.138816259746;6902.551334163263;7086.822829429306;7229.240929702516;8286.00567931852;8313;8365.038020627706;8369.288610468178;8374.204450577185;7297;7373.5575865448245;7408.444366672414;7500.662470188746;7548.207438219128;7254.600996945008;7254.948032535359;7261;7319.59742214162;7363.116426028911;6896.069030680794;7000.964995174992;7029.402500787286;7049.013138160559;7050.682719277543;4669.136209801198;4814.133455977958;4814.855554333376;4939.678920870132;4957.330760347451;4228.661707063366;4861.829835955725;4932.556069953722;4935.235038569028;5002.374703162105 03:31:11 INFO - PROCESS | 2311 | |4;prototype.html;323.18073683213055;328.0617434138364;331.74952910552537;335.106940004887;340.1224839913025;6544.803655890319;6609.570377925437;6624.801255962323;6647.53467257291;6658.76694315699;6541.640209788459;6563.048357988101;6565.83585410365;6572.572782769117;6583.275839657634;3844.461775351453;4610.907781844361;4716.339712440244;4798.904021919559;4862.465128835825;4828.517148285178;4842.322074909498;4870.829520966767;4877;5017.247412888078;4844.467108618067;4848.87877803056;4852.93235488192;4853.902921941559;4868.97565512172;4601.608863246628;4704.576588107053;4732.834350797705;4798.664093513456;4806.158922188638;4105.44576482174;4112.568180341081;4114.773687447189;4121.093359460914;4139.855105071308;6185.072239164161;6278.183836101278;6280.120783090371;6288.622682639057;6307;5687.061634830248;5689.772409103631;5690.5447564194765;5692.288463942007;5698.2592263005545;6030.13065949557;6692.966535167342;6803.161850460029;7023.789286321418;7028.613426261553;4554.089182163548;4634.814607415699;4670.229412147026;4672.813087476497;4695.60087392572;7283.490155689104;7348.301911318416;7358.632068396559;7374.188839227681;7380;4726.409198850144;4736.218523943579;4742.430908291027;4746.620270378361;4747.0031293428765;4813.109574728686;4817.566419022235;4817.735024573612;4818.903621927542;4841.733704646208;5667.773289068391;5674.971625141848;5680.091185410314;5691.487766100988;5695.971520142372;6745.9662701686175;7030.261822508665;7078.433725301964;7083.504154709224;7088.113985751781;1648.175912043978;1670.8913920595123;1681.3106626283165;1681.6972944869797;1685.7977042754949;1016.1616666250374;1028.8816786069697;1068.278911734576;1072.785442911421;1096.0464395975393;5516.200150978166;6016.09758536223;6453.709583068773;6612.437942774822;6632.2704502504685;7345.3021962913435;7355.264473552709;7374.115106187184;7380.667869945865;7385.113786345673;6605.735770569219;6621.370969757865;6627.867442651216;6644.501662375341;6648.83377915556;3816.160444702161;3864.6715029222833;3865.304245235828;3867.9806600967377;3872.7220017394193;7071.575705457689;7198.208197098315;7246.601436920914;7526.134494533198;7633;7360.668769905367;7571.545707257582;7573.583452910143;7585.317321440986;7592;7646.158922518519;7652.04349456318;7657.808554786175;7658.310752032344;7664.386849052063;7158.617787932202;7334.633268336668;7357;7384.741534046282;7403.407727381904;6071.453569178619;7107.893381599383;7177.712891484282;7222.133343998754;7225.494215404871;7614.581198034161;7617.581033043236;7622.695092196472;7628;7657;4209.2423363794815;4285.142971405794;4585.19759042173;4646.558576935186;4654.232051711429;7064.82337941535;7261.854762904607;7295.744648937261;7319.926800731924;7324.487285889936;7059.329363710439;7071.186813516306;7075.398591119812;7085.929140708733;7087.893681594884;6964.303569642995;7013.333733295329;7026.8945965809535;7029.753958611424;7067.293270672892;6853.383195512428;6886;6886.449084073362;6894.482913781587;6927.134108236471;6757.797266082025;7050.894236586353;7056.54132481387;7067.929320706933;7076.575405475688;6554.705038273384;6754.223264324665;6768.830779230559;6893.138357705287;6969.233384327821 03:31:11 INFO - PROCESS | 2311 | |5;yui.html;698.5494356140287;705.7529864547407;723.0347486106048;738.5421038955848;775.1124961918604;13238.602841914733;14979.101253924771;15069.945103842736;15252.694946101084;15267.770983435257;16200.432984845533;16282.185890705476;16285.267162977665;16301.184940752973;16327.938683985532;748.0761259844088;915.2997956563252;947.8578213267996;949.1220620925616;949.3639261694681;755.3768141283463;927.5825878354734;942.6135284533343;946;949.4350861237588;994.5425104451961;995.5868314649412;997.581015973293;999.6501224571379;1001.43919405133;776.9572673502955;787.4723934963588;988.5600907596123;990.3760630802584;996.5963784667222;580.9651420914759;584.1150656755019;699.2797418658789;710.7192658899705;712.7184762019025;15242.57042165515;15322.387104515807;15328.923355383264;15337.846621533754;15364.923175384163;12118;12359.443825027894;12380.195287306296;12629.368531573477;12729.872701272961;10817.729556761065;10972.670819875417;11158.609448669258;11328.490217940212;11690.649280521598;3500.929981400383;3538.85844566217;3552.751307408457;3590.5870824855215;3721.3301605710963;77241.22758772396;79280.81078783823;79322.20677793263;79464;82322;9504.714858554255;9555.61777528891;9561.474118923386;9645.807083858284;10237.9488102559;21244.681329780065;21261.46846328823;21273.04271307795;21357.03893324773;21359.893200533897;6248.50011999039;7345.228750981177;7380.852382952311;7385.889211661829;7609.619519024027;4061.1877624475223;4169.874903752892;4174.791260436966;4251.29853574163;4442.955570444319;3643.107438677541;3675.9632403676155;3702.9814850925573;3705.073731567108;3706.6849317808287;536.6806749983754;655.1875674164003;655.3381085103983;660.6102399584269;680.608650026227;530.2682298428222;581.102197105473;589.1898639370866;598.4494265275956;615.689077016104;8065.112837587861;8095.757127286191;8116.689226849262;8335.624896879655;8416.663333466715;559.5299948043581;577.1054864959297;601.6961434475562;612.7273363353354;613.7268915332634;3769.151940813295;3831.885043448701;3849.8652547160714;3882.514685664292;4069;92590;94944;95017;95231;95723.08553828884;78108.60945695349;78369;78831.8175227372;79056.41887162339;79940;65137;65920;66750;70036;71666.64166679133;7078.702619767588;8155.941521929277;8211.630476628685;8291.295239904675;8359.414840961075;45754.62736117922;47735.28397074116;47843.5215647853;49002.26496602483;49491.50508495013;65856.67071664611;67476;68609.65695171691;68815.65592172007;69300.57447989297;10599.258051936291;10805.567777288821;10806.567737290421;11075.556977721124;11554.075673946232;11718.88281117212;11904.464299106734;12046.156769026435;12179;12445.439955201857;11270.94364528172;11369.54521819118;11449.198556100993;11566.30602163873;11650.941745291218;11152.88847111551;11317.660470185907;11384.430778460877;11537.596184133516;11550.191486595857;10896.073833724222;11068.225224234226;11247.212695111592;11285.71785705364;11303.491342889425;9294.442333460014;9398.812023759623;9636.46999415039;9792.118709316195;9830.4101753895;9496.810063798823;9555.808883822423;9662.75843103928;9673.274504412057;9680 03:31:11 INFO - PROCESS | 2311 | __end_tp_report 03:31:11 INFO - PROCESS | 2311 | __start_cc_report 03:31:11 INFO - PROCESS | 2311 | _x_x_mozilla_cycle_collect,1226 03:31:11 INFO - PROCESS | 2311 | __end_cc_report 03:31:11 INFO - PROCESS | 2311 | __startTimestamp1465036271012__endTimestamp 03:31:11 INFO - PROCESS | 2311 | 03:31:11 INFO - PROCESS | 2311 | ------- Summary: start ------- 03:31:11 INFO - PROCESS | 2311 | Number of tests: 6 03:31:11 INFO - PROCESS | 2311 | 03:31:11 INFO - PROCESS | 2311 | [#0] dojo.html Cycles:165 Average:5650.85 Median:5786.91 stddev:609.52 (10.5%) stddev-sans-first:582.72 03:31:11 INFO - PROCESS | 2311 | Values: 3295.9 3341.9 3403.7 3413.7 3448.5 5640.4 5675.3 5687.5 5690.6 5712.2 5611.8 5695.9 5700.7 5711.8 5722.8 5081.5 5144.0 5171.9 5223.6 5258.9 5223.8 5230.8 5231.6 5234.7 5247.4 5179.4 5189.0 5203.0 5229.8 5232.0 5189.2 5240.9 5261.6 5274.9 5313.4 5077.4 5101.0 5146.6 5165.6 5195.5 5421.6 5490.2 5527.2 5536.7 5544.5 6159.8 6186.8 6195.3 6197.8 6210.0 6097.5 6169.6 6194.3 6208.2 6250.6 5654.6 5737.6 5753.4 5782.1 5785.3 6172.1 6241.7 6267.8 6283.4 6286.3 5468.4 6057.6 6088.1 6098.5 6100.0 5173.8 5936.4 6114.0 6130.6 6150.5 6020.3 6025.8 6030.1 6031.6 6036.2 5983.9 6024.2 6045.7 6067.3 6132.9 4866.4 4875.0 4878.4 4882.9 4916.1 4389.3 4393.8 4396.6 4407.4 4438.9 5508.6 5569.9 5584.4 5607.6 5633.0 6115.6 6116.6 6137.9 6143.9 6148.8 5609.7 5612.7 5615.1 5630.6 5648.5 5214.7 5313.0 5323.7 5351.4 5352.4 5100.6 6232.4 6287.4 6290.5 6293.0 5349.3 5929.7 6222.3 6227.6 6234.8 6209.8 6290.3 6292.5 6293.5 6327.9 6065.0 6079.9 6081.9 6103.8 6106.6 6028.2 6032.4 6048.6 6119.4 6186.5 5973.1 6006.2 6032.1 6043.4 6061.2 5190.8 5640.2 5969.3 6002.5 6007.2 5688.5 5786.9 5839.8 5843.0 5848.8 5931.6 6025.0 6056.6 6082.0 6088.6 5787.2 5855.0 5872.7 5875.3 5895.3 03:31:11 INFO - PROCESS | 2311 | 03:31:11 INFO - PROCESS | 2311 | [#1] ext.html Cycles:180 Average:64168.75 Median:3380.98 stddev:141724.81 (4191.8%) stddev-sans-first:142041.78 03:31:11 INFO - PROCESS | 2311 | Values: 503.6 517.2 530.3 567.6 576.9 29979.7 37188.8 37762.6 37770.8 37970.8 40881.0 41424.8 41435.2 41494.6 41562.0 7313.4 9522.7 9738.0 9745.0 9771.9 8650.1 11485.7 11698.4 11710.6 11721.9 11427.4 11496.7 11509.8 11543.5 11551.2 8719.7 10206.0 11437.0 11541.8 11768.1 11336.1 11479.7 11493.3 11498.8 11537.5 28682.9 38054.4 38060.8 38116.8 38173.2 3178.8 3332.6 3380.5 3414.9 3454.5 27429.7 27653.3 27791.3 28302.4 28307.9 2693.1 2760.5 2796.9 2871.9 2930.6 501536.0 501875.5 503239.0 503671.0 504162.0 36348.0 36390.0 36517.0 59092.0 60860.0 35232.8 35250.6 35385.8 35392.0 35447.6 15189.9 15385.8 15391.8 15399.4 15407.3 11993.3 12117.6 13044.4 13046.9 13227.7 7921.5 7929.1 7934.7 8001.1 8003.3 2912.8 3358.7 3379.4 3381.5 3413.7 1021.7 1037.2 1055.2 1101.7 1109.6 23652.9 29703.1 29878.9 30156.4 30386.7 992.6 1111.2 1148.1 1212.1 1229.4 14986.4 15209.9 15339.9 15352.4 15438.5 594643.0 626438.0 639359.0 639776.0 639901.8 58682.0 59203.0 59385.0 59455.8 59633.0 1203.8 1261.4 1385.2 1419.9 1589.6 28861.4 28864.0 28941.9 29448.3 29563.6 47219.5 47302.8 47613.5 47778.0 47784.0 438242.0 441195.0 441316.0 441458.0 441999.0 23244.7 26362.3 26415.6 26764.1 27051.0 41481.0 42311.0 43288.6 43751.8 43850.0 34159.5 34246.5 36566.6 36723.1 38058.0 24986.9 36467.1 36541.6 36811.3 37075.6 35005.5 35560.8 36045.8 36388.0 36428.8 32067.8 32145.0 32280.8 32386.4 32428.3 24308.1 24460.9 24717.0 24740.9 24911.0 03:31:11 INFO - PROCESS | 2311 | 03:31:11 INFO - PROCESS | 2311 | [#2] jquery.html Cycles:180 Average:35408.16 Median:2315.25 stddev:101181.07 (4370.2%) stddev-sans-first:101430.78 03:31:11 INFO - PROCESS | 2311 | Values: 408.6 408.9 411.8 413.7 426.7 18944.6 20345.0 21016.7 21051.0 21591.2 20271.3 20732.6 20740.8 20767.5 20787.0 5712.5 5728.4 5826.1 5870.0 5914.0 5763.1 5847.7 5859.0 5866.7 5878.6 5593.6 5841.2 5847.4 5847.8 5849.6 4945.7 5790.1 5817.5 5828.8 5832.5 4952.5 5044.0 5068.9 5118.9 5121.9 25393.7 25426.9 25710.6 25843.9 25923.6 2255.7 2297.0 2333.5 2351.4 2904.7 14668.7 14755.4 14756.7 14781.8 15265.9 1659.4 1672.1 1691.7 1772.6 1830.3 109052.5 118640.8 119391.8 120153.0 120162.4 10170.7 11098.0 11593.4 11641.3 11688.9 14931.8 15030.8 15046.1 15085.8 15089.9 6840.6 8407.2 8729.4 8742.0 8874.8 8775.9 8780.8 8789.0 8821.7 8874.9 4522.3 4619.0 4628.1 4644.3 4694.4 1921.9 1964.2 1966.7 1982.0 1994.5 911.9 970.8 999.3 1044.9 1053.8 19787.5 20049.5 20319.7 20549.3 20893.5 961.1 961.9 969.4 985.9 988.4 10759.4 10891.8 10899.9 10900.0 10931.6 591931.0 595416.0 610745.0 611117.0 620986.0 125886.0 128285.0 133009.3 134567.7 138292.0 1013.7 1015.8 1032.9 1033.7 1042.7 17896.5 18008.7 18183.0 18280.1 18574.5 51598.7 51783.4 52118.7 54631.0 57129.4 76844.0 77005.6 77980.2 78418.2 79973.0 7513.7 7938.7 7980.4 8108.6 8212.8 9919.5 12187.3 12229.3 12505.7 12616.1 10415.6 10805.6 10874.3 10973.9 10996.4 10636.0 10643.7 10770.8 10834.5 10848.0 8550.0 11054.0 11278.0 11339.8 11411.8 7968.5 8060.9 8147.5 8177.8 8398.6 9381.5 10422.2 10814.2 11037.6 11055.4 03:31:11 INFO - PROCESS | 2311 | 03:31:11 INFO - PROCESS | 2311 | [#3] mootools.html Cycles:180 Average:5692.36 Median:4902.02 stddev:3181.52 (64.9%) stddev-sans-first:3173.58 03:31:11 INFO - PROCESS | 2311 | Values: 1333.4 1561.5 1580.2 1796.4 1944.8 7826.4 8520.1 8664.8 8671.9 8681.7 8396.2 8651.8 8658.0 8660.3 8663.2 2169.3 2375.8 2449.2 2461.7 2483.4 2476.1 2480.3 2480.3 2484.5 2486.9 2395.9 2437.6 2468.4 2469.4 2473.1 1826.7 2220.6 2221.3 2222.4 2441.5 2400.1 2474.4 2478.2 2483.5 2485.2 8602.7 8671.4 8684.4 8686.0 8693.3 5296.5 5425.2 5462.6 5463.1 5470.4 3356.6 3431.8 3517.9 3525.2 3540.7 1021.6 1026.6 1029.5 1043.0 1081.6 11818.4 11831.1 11835.5 11890.5 11949.0 4754.9 4796.0 4809.8 4873.7 4902.0 10158.0 10166.3 10170.0 10182.1 10200.3 5504.8 5508.2 5527.7 5530.7 5541.2 4259.5 4402.3 4767.7 4886.6 4902.1 3997.6 4033.8 4077.8 4114.3 4158.9 1264.3 1265.7 1277.2 1280.4 1314.4 814.8 874.3 883.6 923.4 948.2 5765.1 5813.1 5825.3 5895.8 6017.6 889.2 903.1 914.6 946.4 960.1 3846.4 3933.9 3947.9 4013.7 4014.8 10430.6 10627.3 10898.4 10936.9 10944.9 10628.8 10756.2 10773.0 10780.4 10790.5 8024.6 8320.9 8496.7 8528.8 8537.4 5741.7 5777.7 5796.5 5864.1 5901.3 9383.1 9421.0 9544.6 9628.8 9747.3 9693.9 9702.4 9917.7 10067.0 10107.6 6215.9 6379.1 6902.6 7086.8 7229.2 8286.0 8313.0 8365.0 8369.3 8374.2 7297.0 7373.6 7408.4 7500.7 7548.2 7254.6 7254.9 7261.0 7319.6 7363.1 6896.1 7001.0 7029.4 7049.0 7050.7 4669.1 4814.1 4814.9 4939.7 4957.3 4228.7 4861.8 4932.6 4935.2 5002.4 03:31:11 INFO - PROCESS | 2311 | 03:31:11 INFO - PROCESS | 2311 | [#4] prototype.html Cycles:180 Average:5760.22 Median:6577.92 stddev:1835.28 (27.9%) stddev-sans-first:1794.48 03:31:11 INFO - PROCESS | 2311 | Values: 323.2 328.1 331.7 335.1 340.1 6544.8 6609.6 6624.8 6647.5 6658.8 6541.6 6563.0 6565.8 6572.6 6583.3 3844.5 4610.9 4716.3 4798.9 4862.5 4828.5 4842.3 4870.8 4877.0 5017.2 4844.5 4848.9 4852.9 4853.9 4869.0 4601.6 4704.6 4732.8 4798.7 4806.2 4105.4 4112.6 4114.8 4121.1 4139.9 6185.1 6278.2 6280.1 6288.6 6307.0 5687.1 5689.8 5690.5 5692.3 5698.3 6030.1 6693.0 6803.2 7023.8 7028.6 4554.1 4634.8 4670.2 4672.8 4695.6 7283.5 7348.3 7358.6 7374.2 7380.0 4726.4 4736.2 4742.4 4746.6 4747.0 4813.1 4817.6 4817.7 4818.9 4841.7 5667.8 5675.0 5680.1 5691.5 5696.0 6746.0 7030.3 7078.4 7083.5 7088.1 1648.2 1670.9 1681.3 1681.7 1685.8 1016.2 1028.9 1068.3 1072.8 1096.0 5516.2 6016.1 6453.7 6612.4 6632.3 7345.3 7355.3 7374.1 7380.7 7385.1 6605.7 6621.4 6627.9 6644.5 6648.8 3816.2 3864.7 3865.3 3868.0 3872.7 7071.6 7198.2 7246.6 7526.1 7633.0 7360.7 7571.5 7573.6 7585.3 7592.0 7646.2 7652.0 7657.8 7658.3 7664.4 7158.6 7334.6 7357.0 7384.7 7403.4 6071.5 7107.9 7177.7 7222.1 7225.5 7614.6 7617.6 7622.7 7628.0 7657.0 4209.2 4285.1 4585.2 4646.6 4654.2 7064.8 7261.9 7295.7 7319.9 7324.5 7059.3 7071.2 7075.4 7085.9 7087.9 6964.3 7013.3 7026.9 7029.8 7067.3 6853.4 6886.0 6886.4 6894.5 6927.1 6757.8 7050.9 7056.5 7067.9 7076.6 6554.7 6754.2 6768.8 6893.1 6969.2 03:31:11 INFO - PROCESS | 2311 | 03:31:11 INFO - PROCESS | 2311 | [#5] yui.html Cycles:180 Average:18330.70 Median:600.07 stddev:25673.60 (4278.4%) stddev-sans-first:25711.49 03:31:11 INFO - PROCESS | 2311 | Values: 698.5 705.8 723.0 738.5 775.1 13238.6 14979.1 15069.9 15252.7 15267.8 16200.4 16282.2 16285.3 16301.2 16327.9 748.1 915.3 947.9 949.1 949.4 755.4 927.6 942.6 946.0 949.4 994.5 995.6 997.6 999.7 1001.4 777.0 787.5 988.6 990.4 996.6 581.0 584.1 699.3 710.7 712.7 15242.6 15322.4 15328.9 15337.8 15364.9 12118.0 12359.4 12380.2 12629.4 12729.9 10817.7 10972.7 11158.6 11328.5 11690.6 3500.9 3538.9 3552.8 3590.6 3721.3 77241.2 79280.8 79322.2 79464.0 82322.0 9504.7 9555.6 9561.5 9645.8 10237.9 21244.7 21261.5 21273.0 21357.0 21359.9 6248.5 7345.2 7380.9 7385.9 7609.6 4061.2 4169.9 4174.8 4251.3 4443.0 3643.1 3676.0 3703.0 3705.1 3706.7 536.7 655.2 655.3 660.6 680.6 530.3 581.1 589.2 598.4 615.7 8065.1 8095.8 8116.7 8335.6 8416.7 559.5 577.1 601.7 612.7 613.7 3769.2 3831.9 3849.9 3882.5 4069.0 92590.0 94944.0 95017.0 95231.0 95723.1 78108.6 78369.0 78831.8 79056.4 79940.0 65137.0 65920.0 66750.0 70036.0 71666.6 7078.7 8155.9 8211.6 8291.3 8359.4 45754.6 47735.3 47843.5 49002.3 49491.5 65856.7 67476.0 68609.7 68815.7 69300.6 10599.3 10805.6 10806.6 11075.6 11554.1 11718.9 11904.5 12046.2 12179.0 12445.4 11270.9 11369.5 11449.2 11566.3 11650.9 11152.9 11317.7 11384.4 11537.6 11550.2 10896.1 11068.2 11247.2 11285.7 11303.5 9294.4 9398.8 9636.5 9792.1 9830.4 9496.8 9555.8 9662.8 9673.3 9680.0 03:31:11 INFO - PROCESS | 2311 | -------- Summary: end -------- 03:31:11 INFO - PROCESS | 2311 | 03:31:11 INFO - TEST-INFO | 2311: exit 0 03:31:11 INFO - TEST-OK | dromaeo_css | took 1115711ms 03:31:11 INFO - TEST-START | kraken 03:31:11 INFO - operating with platform_type : mac_ 03:31:11 INFO - Initialising browser for kraken test... 03:31:11 INFO - TEST-INFO | started process 2319 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/tmpkiXCxx/profile http://localhost:56635/getInfo.html) 03:31:13 INFO - PROCESS | 2319 | __metrics Screen width/height:1600/1200 03:31:13 INFO - PROCESS | 2319 | colorDepth:24 03:31:13 INFO - PROCESS | 2319 | Browser inner width/height: 1024/689 03:31:13 INFO - PROCESS | 2319 | __metrics 03:31:13 INFO - PROCESS | 2319 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:31:13 ERROR - PROCESS | 2319 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:31:14 INFO - TEST-INFO | 2319: exit 0 03:31:14 INFO - Browser initialized. 03:31:14 INFO - Running cycle 1/1 for kraken test... 03:31:14 INFO - TEST-INFO | started process 2321 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/tmpkiXCxx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:31:26 INFO - PROCESS | 2321 | RSS: Main: 211656704 03:31:26 INFO - PROCESS | 2321 | 03:31:29 INFO - PROCESS | 2321 | Cycle 1(1): loaded http://localhost:56635/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56635/tests/kraken/driver.html?testName=audio-beat-detection) 03:31:30 INFO - PROCESS | 2321 | RSS: Main: 272379904 03:31:30 INFO - PROCESS | 2321 | 03:31:32 INFO - PROCESS | 2321 | Cycle 1(1): loaded http://localhost:56635/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56635/tests/kraken/driver.html?testName=audio-dft) 03:31:33 INFO - PROCESS | 2321 | RSS: Main: 288542720 03:31:33 INFO - PROCESS | 2321 | 03:31:36 INFO - PROCESS | 2321 | Cycle 1(1): loaded http://localhost:56635/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56635/tests/kraken/driver.html?testName=audio-fft) 03:31:37 INFO - PROCESS | 2321 | RSS: Main: 376537088 03:31:37 INFO - PROCESS | 2321 | 03:31:39 INFO - PROCESS | 2321 | Cycle 1(1): loaded http://localhost:56635/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56635/tests/kraken/driver.html?testName=audio-oscillator) 03:31:40 INFO - PROCESS | 2321 | RSS: Main: 279359488 03:31:40 INFO - PROCESS | 2321 | 03:31:42 INFO - PROCESS | 2321 | Cycle 1(1): loaded http://localhost:56635/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56635/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:31:42 INFO - PROCESS | 2321 | RSS: Main: 281673728 03:31:42 INFO - PROCESS | 2321 | 03:31:45 INFO - PROCESS | 2321 | Cycle 1(1): loaded http://localhost:56635/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56635/tests/kraken/driver.html?testName=imaging-darkroom) 03:31:46 INFO - PROCESS | 2321 | RSS: Main: 318562304 03:31:46 INFO - PROCESS | 2321 | 03:31:49 INFO - PROCESS | 2321 | Cycle 1(1): loaded http://localhost:56635/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56635/tests/kraken/driver.html?testName=imaging-desaturate) 03:31:49 INFO - PROCESS | 2321 | RSS: Main: 319442944 03:31:49 INFO - PROCESS | 2321 | 03:31:52 INFO - PROCESS | 2321 | Cycle 1(1): loaded http://localhost:56635/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56635/tests/kraken/driver.html?testName=json-parse-financial) 03:31:53 INFO - PROCESS | 2321 | RSS: Main: 317558784 03:31:53 INFO - PROCESS | 2321 | 03:31:54 INFO - PROCESS | 2321 | Cycle 1(1): loaded http://localhost:56635/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56635/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:31:55 INFO - PROCESS | 2321 | RSS: Main: 309882880 03:31:55 INFO - PROCESS | 2321 | 03:31:57 INFO - PROCESS | 2321 | Cycle 1(1): loaded http://localhost:56635/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56635/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:31:57 INFO - PROCESS | 2321 | RSS: Main: 316977152 03:31:57 INFO - PROCESS | 2321 | 03:31:59 INFO - PROCESS | 2321 | Cycle 1(1): loaded http://localhost:56635/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56635/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:32:00 INFO - PROCESS | 2321 | RSS: Main: 319094784 03:32:00 INFO - PROCESS | 2321 | 03:32:03 INFO - PROCESS | 2321 | Cycle 1(1): loaded http://localhost:56635/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56635/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:32:04 INFO - PROCESS | 2321 | RSS: Main: 324395008 03:32:04 INFO - PROCESS | 2321 | 03:32:07 INFO - PROCESS | 2321 | Cycle 1(1): loaded http://localhost:56635/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56635/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:32:07 INFO - PROCESS | 2321 | RSS: Main: 322465792 03:32:07 INFO - PROCESS | 2321 | 03:32:09 INFO - PROCESS | 2321 | Cycle 1(1): loaded http://localhost:56635/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56635/tests/kraken/driver.html?testName=ai-astar) 03:32:09 INFO - PROCESS | 2321 | RSS: Main: 386736128 03:32:09 INFO - PROCESS | 2321 | 03:32:09 INFO - PROCESS | 2321 | __start_tp_report 03:32:09 INFO - PROCESS | 2321 | _x_x_mozilla_page_load 03:32:09 INFO - PROCESS | 2321 | _x_x_mozilla_page_load_details 03:32:09 INFO - PROCESS | 2321 | |i|pagename|runs| 03:32:09 INFO - PROCESS | 2321 | |0;ai-astar;113;114;121;113;112;111;114;113;113;136 03:32:09 INFO - PROCESS | 2321 | |1;audio-beat-detection;159;125;159;207;120;142;117;180;192;118 03:32:09 INFO - PROCESS | 2321 | |2;audio-dft;185;189;187;182;184;186;184;186;188;185 03:32:09 INFO - PROCESS | 2321 | |3;audio-fft;70;82;77;189;82;82;54;61;137;55 03:32:09 INFO - PROCESS | 2321 | |4;audio-oscillator;94;95;148;78;79;77;128;80;76;129 03:32:09 INFO - PROCESS | 2321 | |5;imaging-gaussian-blur;87;84;86;83;86;83;87;84;87;84 03:32:09 INFO - PROCESS | 2321 | |6;imaging-darkroom;92;92;91;92;92;92;93;93;92;92 03:32:09 INFO - PROCESS | 2321 | |7;imaging-desaturate;84;85;85;86;85;84;84;85;87;84 03:32:09 INFO - PROCESS | 2321 | |8;json-parse-financial;61;65;65;64;64;64;66;83;62;57 03:32:09 INFO - PROCESS | 2321 | |9;json-stringify-tinderbox;63;64;70;73;75;70;69;70;69;70 03:32:09 INFO - PROCESS | 2321 | |10;stanford-crypto-aes;82;82;81;79;79;80;81;80;82;86 03:32:09 INFO - PROCESS | 2321 | |11;stanford-crypto-ccm;134;129;131;131;132;131;134;144;151;158 03:32:09 INFO - PROCESS | 2321 | |12;stanford-crypto-pbkdf2;165;166;182;219;200;167;178;164;165;163 03:32:09 INFO - PROCESS | 2321 | |13;stanford-crypto-sha256-iterative;69;70;67;68;68;67;66;70;67;68 03:32:09 INFO - PROCESS | 2321 | __end_tp_report 03:32:09 INFO - PROCESS | 2321 | __start_cc_report 03:32:09 INFO - PROCESS | 2321 | _x_x_mozilla_cycle_collect,1982 03:32:09 INFO - PROCESS | 2321 | __end_cc_report 03:32:09 INFO - PROCESS | 2321 | __startTimestamp1465036329621__endTimestamp 03:32:09 INFO - PROCESS | 2321 | 03:32:09 INFO - PROCESS | 2321 | ------- Summary: start ------- 03:32:09 INFO - PROCESS | 2321 | Number of tests: 14 03:32:09 INFO - PROCESS | 2321 | 03:32:09 INFO - PROCESS | 2321 | [#0] ai-astar Cycles:10 Average:116.00 Median:113.50 stddev:7.53 (6.6%) stddev-sans-first:7.91 03:32:09 INFO - PROCESS | 2321 | Values: 113.0 114.0 121.0 113.0 112.0 111.0 114.0 113.0 113.0 136.0 03:32:09 INFO - PROCESS | 2321 | 03:32:09 INFO - PROCESS | 2321 | [#1] audio-beat-detection Cycles:10 Average:151.90 Median:159.00 stddev:32.90 (20.7%) stddev-sans-first:34.79 03:32:09 INFO - PROCESS | 2321 | Values: 159.0 125.0 159.0 207.0 120.0 142.0 117.0 180.0 192.0 118.0 03:32:09 INFO - PROCESS | 2321 | 03:32:09 INFO - PROCESS | 2321 | [#2] audio-dft Cycles:10 Average:185.60 Median:186.00 stddev:2.07 (1.1%) stddev-sans-first:2.18 03:32:09 INFO - PROCESS | 2321 | Values: 185.0 189.0 187.0 182.0 184.0 186.0 184.0 186.0 188.0 185.0 03:32:09 INFO - PROCESS | 2321 | 03:32:09 INFO - PROCESS | 2321 | [#3] audio-fft Cycles:10 Average:88.90 Median:73.50 stddev:42.32 (57.6%) stddev-sans-first:44.33 03:32:09 INFO - PROCESS | 2321 | Values: 70.0 82.0 77.0 189.0 82.0 82.0 54.0 61.0 137.0 55.0 03:32:09 INFO - PROCESS | 2321 | 03:32:09 INFO - PROCESS | 2321 | [#4] audio-oscillator Cycles:10 Average:98.40 Median:78.50 stddev:26.65 (34.0%) stddev-sans-first:28.22 03:32:09 INFO - PROCESS | 2321 | Values: 94.0 95.0 148.0 78.0 79.0 77.0 128.0 80.0 76.0 129.0 03:32:09 INFO - PROCESS | 2321 | 03:32:09 INFO - PROCESS | 2321 | [#5] imaging-gaussian-blur Cycles:10 Average:85.10 Median:86.00 stddev:1.66 (1.9%) stddev-sans-first:1.62 03:32:09 INFO - PROCESS | 2321 | Values: 87.0 84.0 86.0 83.0 86.0 83.0 87.0 84.0 87.0 84.0 03:32:09 INFO - PROCESS | 2321 | 03:32:09 INFO - PROCESS | 2321 | [#6] imaging-darkroom Cycles:10 Average:92.10 Median:92.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 03:32:09 INFO - PROCESS | 2321 | Values: 92.0 92.0 91.0 92.0 92.0 92.0 93.0 93.0 92.0 92.0 03:32:09 INFO - PROCESS | 2321 | 03:32:09 INFO - PROCESS | 2321 | [#7] imaging-desaturate Cycles:10 Average:84.90 Median:85.00 stddev:0.99 (1.2%) stddev-sans-first:1.00 03:32:09 INFO - PROCESS | 2321 | Values: 84.0 85.0 85.0 86.0 85.0 84.0 84.0 85.0 87.0 84.0 03:32:09 INFO - PROCESS | 2321 | 03:32:09 INFO - PROCESS | 2321 | [#8] json-parse-financial Cycles:10 Average:65.10 Median:64.50 stddev:6.81 (10.6%) stddev-sans-first:7.06 03:32:09 INFO - PROCESS | 2321 | Values: 61.0 65.0 65.0 64.0 64.0 64.0 66.0 83.0 62.0 57.0 03:32:09 INFO - PROCESS | 2321 | 03:32:09 INFO - PROCESS | 2321 | [#9] json-stringify-tinderbox Cycles:10 Average:69.30 Median:70.00 stddev:3.59 (5.1%) stddev-sans-first:3.00 03:32:09 INFO - PROCESS | 2321 | Values: 63.0 64.0 70.0 73.0 75.0 70.0 69.0 70.0 69.0 70.0 03:32:09 INFO - PROCESS | 2321 | 03:32:09 INFO - PROCESS | 2321 | [#10] stanford-crypto-aes Cycles:10 Average:81.20 Median:81.50 stddev:2.04 (2.5%) stddev-sans-first:2.15 03:32:09 INFO - PROCESS | 2321 | Values: 82.0 82.0 81.0 79.0 79.0 80.0 81.0 80.0 82.0 86.0 03:32:09 INFO - PROCESS | 2321 | 03:32:09 INFO - PROCESS | 2321 | [#11] stanford-crypto-ccm Cycles:10 Average:137.50 Median:134.00 stddev:9.99 (7.5%) stddev-sans-first:10.52 03:32:09 INFO - PROCESS | 2321 | Values: 134.0 129.0 131.0 131.0 132.0 131.0 134.0 144.0 151.0 158.0 03:32:09 INFO - PROCESS | 2321 | 03:32:09 INFO - PROCESS | 2321 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.90 Median:172.50 stddev:18.84 (10.9%) stddev-sans-first:19.48 03:32:09 INFO - PROCESS | 2321 | Values: 165.0 166.0 182.0 219.0 200.0 167.0 178.0 164.0 165.0 163.0 03:32:09 INFO - PROCESS | 2321 | 03:32:09 INFO - PROCESS | 2321 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.00 Median:68.00 stddev:1.33 (2.0%) stddev-sans-first:1.36 03:32:09 INFO - PROCESS | 2321 | Values: 69.0 70.0 67.0 68.0 68.0 67.0 66.0 70.0 67.0 68.0 03:32:09 INFO - PROCESS | 2321 | -------- Summary: end -------- 03:32:09 INFO - PROCESS | 2321 | 03:32:10 INFO - TEST-INFO | 2321: exit 0 03:32:10 INFO - TEST-OK | kraken | took 58868ms 03:32:10 INFO - SUITE-END | took 1174s 03:32:10 INFO - Completed test suite (00:19:34) 03:32:10 INFO - javascript benchmark 03:32:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3295.9176020599484, 3341.8830340938066, 3403.7447191460633, 3413.7269018478523, 3448.4654878493825, 5640.407757185493, 5675.2622159119255, 5687.488126068653, 5690.60165788395, 5712.228849105375, 5611.80358687446, 5695.914561281584, 5700.686462244575, 5711.828645140642, 5722.771089156439, 5081.5426611605135, 5144.048351055048, 5171.870703232421, 5223.608229382792, 5258.92111618326, 5223.817166399177, 5230.7646155922885, 5231.581473482112, 5234.7120908350025, 5247.422783493813, 5179.404368497633, 5188.974055129719, 5203.0374380739495, 5229.790808367661, 5231.979763946032, 5189.169732842746, 5240.947590524084, 5261.605379596507, 5274.894502109974, 5313.362396512404, 5077.441481437039, 5100.974495127538, 5146.614003949681, 5165.586753059746, 5195.454477279869, 5421.620486565942, 5490.176473528963, 5527.170924361378, 5536.667799932017, 5544.500994910478, 6159.81520554384, 6186.84532886677, 6195.349488303708, 6197.845053873689, 6210.037444196157, 6097.451229389332, 6169.568130230886, 6194.287656919422, 6208.192934918432, 6250.593711408789, 5654.6324488908085, 5737.627054241461, 5753.367129615739, 5782.132680098019, 5785.305763308345, 6172.105044768484, 6241.656708881055, 6267.780627678009, 6283.43449089575, 6286.339934306832, 5468.425815289417, 6057.636541807417, 6088.056351265561, 6098.512119030555, 6099.969500152471, 5173.844784656466, 5936.376680448577, 6114.023656277693, 6130.581587307925, 6150.477209437248, 6020.277566692025, 6025.849353766103, 6030.065339872327, 6031.577789554777, 6036.245469316335, 5983.880322393615, 6024.246969128859, 6045.667488288186, 6067.332593414721, 6132.906245166601, 4866.367372241586, 4874.95125048745, 4878.390201224847, 4882.877928051827, 4916.139675556835, 4389.3415987601475, 4393.84621538251, 4396.582324679214, 4407.4050003249795, 4438.889027774267, 5508.586855985817, 5569.916451253234, 5584.441555844383, 5607.579431542651, 5632.971835140798, 6115.635867915819, 6116.633002019893, 6137.8979746733885, 6143.877122457526, 6148.831721972811, 5609.663420194802, 5612.719364031782, 5615.101583746581, 5630.634008789416, 5648.51987581052, 5214.687118772886, 5312.9734351328, 5323.707196104251, 5351.438098999549, 5352.437994010573, 5100.591952643779, 6232.376762323732, 6287.434130928147, 6290.465310448663, 6292.968535157295, 5349.251104845403, 5929.703514824155, 6222.253329600296, 6227.564070515021, 6234.844128896814, 6209.782657607143, 6290.308066112815, 6292.465140463112, 6293.496520278275, 6327.873442531216, 6064.969675151596, 6079.878402432015, 6081.878362432815, 6103.786367477117, 6106.57253992234, 6028.216331876828, 6032.431567792318, 6048.60684055535, 6119.350229749377, 6186.4741496973265, 5973.1338955852025, 6006.159137720635, 6032.095185722177, 6043.365446628217, 6061.212042434456, 5190.8183213587345, 5640.182173584889, 5969.343372229138, 6002.549808764273, 6007.158997740407, 5688.544916406595, 5786.9131963019745, 5839.824805255849, 5843.0, 5848.766049357978, 5931.5847890648965, 6024.969875150771, 6056.57603967718, 6082.0, 6088.5737998341465, 5787.247657804459, 5855.03391940325, 5872.706364681834, 5875.3243377012195, 5895.263092113486], "value": 5614.136172055381, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [503.561901146003, 517.1854329431147, 530.2523441946855, 567.551634208975, 576.8952456046669, 29979.700202997963, 37188.81405592975, 37762.622373776256, 37770.811145944266, 37970.8101459493, 40881.0, 41424.757257282254, 41435.17129657405, 41494.58505414945, 41562.0, 7313.378362839138, 9522.714318570437, 9737.95131024344, 9744.951275243613, 9771.925088240288, 8650.134986501362, 11485.712857178554, 11698.356590387526, 11710.648680539554, 11721.886420790011, 11427.371494567837, 11496.712582185428, 11509.827352589718, 11543.480543375526, 11551.249168804043, 8719.651213951434, 10206.0, 11437.0, 11541.82687259696, 11768.058555315554, 11336.093112550976, 11479.713007174803, 11493.310401375944, 11498.827517587242, 11537.538498460135, 28682.856585716938, 38054.42918356227, 38060.80969595162, 38116.80941595302, 38173.23653526914, 3178.7933784303946, 3332.583427071616, 3380.5098260752297, 3414.9487757683655, 3454.464557993515, 27429.725702743115, 27653.308667283276, 27791.305217369525, 28302.433951320858, 28307.858460707772, 2693.138195777352, 2760.5307097793425, 2796.9021084261954, 2871.8851245950345, 2930.619019527469, 501536.0, 501875.4906225482, 503239.0, 503671.0, 504162.0, 36348.0, 36390.0, 36517.0, 59092.0, 60860.0, 35232.82383588117, 35250.64749352525, 35385.82307088448, 35392.0, 35447.64552354495, 15189.9240503799, 15385.769213461806, 15391.769123463157, 15399.384024638888, 15407.306671199598, 11993.34036627994, 12117.636470905887, 13044.41300141496, 13046.934765326112, 13227.73544529104, 7921.524708517392, 7929.088154862149, 7934.682612695543, 8001.1198768135455, 8003.31971782405, 2912.8252304861776, 3358.7145092667397, 3379.425497665403, 3381.458966565338, 3413.6927676509235, 1021.701172362185, 1037.234837539726, 1055.2138656700654, 1101.6750058732694, 1109.57836022311, 23652.88173559121, 29703.108906732832, 29878.850605746833, 30156.396872062436, 30386.69613303883, 992.593036854886, 1111.2110401614782, 1148.0700632487712, 1212.1030437476381, 1229.4344601483417, 14986.400543978336, 15209.923950380398, 15339.926205165531, 15352.385904563693, 15438.45965391206, 594643.0, 626438.0, 639359.0, 639776.0, 639901.8004909946, 58682.0, 59203.0, 59385.0, 59455.810883782084, 59633.0, 1203.813408921636, 1261.369315342329, 1385.1827421821174, 1419.865112814281, 1589.5628702107012, 28861.422771544872, 28864.0, 28941.85529072341, 29448.263793405335, 29563.556546651387, 47219.52780472151, 47302.763486182346, 47613.52386476091, 47778.04443911033, 47784.0, 438242.0, 441195.0, 441316.0, 441458.0, 441999.0, 23244.65133022972, 26362.34094147585, 26415.603765943142, 26764.063257785885, 27051.0, 41481.0, 42311.0, 43288.567114329715, 43751.78124109359, 43850.0, 34159.4876076864, 34246.48630270498, 36566.63433365632, 36723.081922951074, 38058.0, 24986.875065624554, 36467.088322792144, 36541.634583653824, 36811.26377472489, 37075.62924370722, 35005.47491787574, 35560.82219588885, 36045.81977090098, 36388.0, 36428.81785591055, 32067.839660801546, 32145.0, 32280.838595806872, 32386.352272953936, 32428.337918111643, 24308.149214777397, 24460.89925953372, 24717.011319547735, 24740.876295618407, 24911.0], "value": 18336.121976240334, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [408.552634864823, 408.8752930356241, 411.7879292164534, 413.67319817344264, 426.73542403709706, 18944.62110757784, 20345.0, 21016.684749728767, 21051.0, 21591.244306449276, 20271.290504832334, 20732.585348293025, 20740.792592074074, 20767.480812979684, 20787.0, 5712.485876271135, 5728.398518155596, 5826.096954971965, 5869.9706501467645, 5913.970430147865, 5763.135529670562, 5847.678377689225, 5858.970705146468, 5866.706664666771, 5878.588498805086, 5593.608447408683, 5841.2114364560875, 5847.415258474161, 5847.766089356421, 5849.561282903799, 4945.703257804507, 5790.13148027795, 5817.534597232211, 5828.766849326022, 5832.533397328225, 4952.455229924705, 5043.974780126113, 5068.923966140511, 5118.897622047538, 5121.948780512184, 25393.74606253932, 25426.872865635552, 25710.614340784905, 25843.870780646164, 25923.611145832827, 2255.729312482513, 2296.9770302297097, 2333.5216280662594, 2351.447409858682, 2904.6804851466322, 14668.70662586753, 14755.409783608642, 14756.704865902622, 14781.778273325908, 15265.931384803069, 1659.402615058578, 1672.1137796967625, 1691.739653957797, 1772.6277481728853, 1830.2861883865303, 109052.4547377258, 118640.81359186384, 119391.80608193894, 120153.0, 120162.39918800438, 10170.745731356776, 11098.0, 11593.362365070003, 11641.301521908712, 11688.941555292338, 14931.776023359658, 15030.849691503163, 15046.097234165985, 15085.773713394308, 15089.924550377178, 6840.555363901331, 8407.2433480987, 8729.388942774072, 8742.0, 8874.822503549893, 8775.868361974575, 8780.78048048804, 8789.0, 8821.69124080667, 8874.911250887537, 4522.25382811839, 4619.030003699261, 4628.143793398232, 4644.303354496785, 4694.366260554782, 1921.932732354361, 1964.204497178645, 1966.7148263501713, 1981.9900900495404, 1994.4914046917943, 911.9407238529607, 970.84466485362, 999.2605471950704, 1044.8850626431085, 1053.8313869780798, 19787.505312367306, 20049.498762530762, 20319.695204571944, 20549.280775172796, 20893.477663058544, 961.110972350573, 961.8605302231138, 969.4086607169621, 985.8964808694984, 988.3773222869546, 10759.408232547128, 10891.782164356668, 10899.891001090045, 10900.0, 10931.617393391192, 591931.0, 595416.0, 610745.0, 611117.0, 620986.0, 125886.0, 128285.0, 133009.3349533246, 134567.65432345748, 138292.0, 1013.6502906497246, 1015.8476228565627, 1032.8708911386077, 1033.715728174758, 1042.7445275907603, 17896.46310610684, 18008.729869051713, 18182.999935003703, 18280.085995699897, 18574.535636609195, 51598.74200628973, 51783.446496603654, 52118.739406302724, 54631.0, 57129.42870571241, 76844.0, 77005.61497192478, 77980.2201977973, 78418.21581784109, 79973.0, 7513.668004421684, 7938.722144724907, 7980.401469889665, 8108.635111419882, 8212.794680132805, 9919.454430006419, 12187.329696866758, 12229.327386993446, 12505.749885002431, 12616.116871818884, 10415.635452759117, 10805.567777288821, 10874.347539147677, 10973.945130274298, 10996.395198264172, 10635.989580989793, 10643.680689579325, 10770.784584308427, 10834.512446939749, 10848.0, 8550.0, 11054.0, 11278.041366483612, 11339.773204535699, 11411.828822567502, 7968.482048666818, 8060.919390806252, 8147.470414423044, 8177.754667359989, 8398.580070996304, 9381.530923453936, 10422.21833362516, 10814.188935830001, 11037.613683521038, 11055.391953442639], "value": 9656.60631621745, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1333.3599872061404, 1561.4769052367458, 1580.1862041048857, 1796.3712700554806, 1944.7763507196676, 7826.413019023575, 8520.062793092762, 8664.826703465927, 8671.916010498695, 8681.696140635078, 8396.244338009577, 8651.783705407368, 8657.95671021644, 8660.30717542595, 8663.220310172082, 2169.2516081951703, 2375.821813363996, 2449.216250799745, 2461.667674863893, 2483.428811373376, 2476.1333533263396, 2480.268320845355, 2480.3303108160785, 2484.5403600334, 2486.8632225227607, 2395.880205989702, 2437.5856104462196, 2468.4075821802817, 2469.3579669285937, 2473.0849585653245, 1826.6985947318676, 2220.6113930062174, 2221.278084622504, 2222.4443889027743, 2441.4628781668093, 2400.063975049732, 2474.3937735254976, 2478.157426475007, 2483.503299340121, 2485.1674688979215, 8602.655893764242, 8671.4363566368, 8684.392092553524, 8686.0, 8693.347998900108, 5296.496832800878, 5425.240466334716, 5462.644928079662, 5463.125899856004, 5470.398256191816, 3356.5636467259346, 3431.7769344992744, 3517.929641407183, 3525.189206482498, 3540.6990405815277, 1021.6015753855928, 1026.6098882424706, 1029.526417847791, 1043.0, 1081.6106201767357, 11818.409079546074, 11831.112666549958, 11835.467403966755, 11890.524379024828, 11948.999999999913, 4754.881127971828, 4796.040791841646, 4809.798399713952, 4873.707577545359, 4901.975490122527, 10158.034986676254, 10166.339187952759, 10170.033846784543, 10182.083612474913, 10200.285979981478, 5504.834854954358, 5508.228847961289, 5527.695976721238, 5530.723463826793, 5541.224228607998, 4259.510156332061, 4402.339649052604, 4767.73777442244, 4886.60907127429, 4902.068606964665, 3997.600239975979, 4033.8184781684895, 4077.7553346799286, 4114.300568903233, 4158.875233742992, 1264.279360764374, 1265.702559898423, 1277.2081309588116, 1280.3726174174587, 1314.3888092037057, 814.8451794159091, 874.3486102853415, 883.5538053283179, 923.4090182283344, 948.1656142594611, 5765.077587585966, 5813.069908814654, 5825.271841019872, 5895.802304324223, 6017.578769486094, 889.1686273334453, 903.0653273861521, 914.6112902016616, 946.4415994563241, 960.1454705312277, 3846.442265871489, 3933.8819835404984, 3947.8618248361167, 4013.7190396672536, 4014.7791871446766, 10430.582776689, 10627.256092073481, 10898.400587967579, 10936.890631093745, 10944.890551094546, 10628.787424251472, 10756.19328550362, 10773.030427261427, 10780.407077610807, 10790.51442685081, 8024.558649274346, 8320.875186872201, 8496.702615408554, 8528.786780330418, 8537.359698022674, 5741.679413734781, 5777.653340799566, 5796.507296879812, 5864.091065884794, 5901.291844978458, 9383.061693830563, 9421.0, 9544.570494327632, 9628.75928101803, 9747.268954828558, 9693.854592181264, 9702.369345992487, 9917.65288214938, 10067.043630855058, 10107.595696172071, 6215.906761398493, 6379.138816259746, 6902.551334163263, 7086.822829429306, 7229.240929702516, 8286.00567931852, 8313.0, 8365.038020627706, 8369.288610468178, 8374.204450577185, 7297.0, 7373.5575865448245, 7408.444366672414, 7500.662470188746, 7548.207438219128, 7254.600996945008, 7254.948032535359, 7261.0, 7319.59742214162, 7363.116426028911, 6896.069030680794, 7000.964995174992, 7029.402500787286, 7049.013138160559, 7050.682719277543, 4669.136209801198, 4814.133455977958, 4814.855554333376, 4939.678920870132, 4957.330760347451, 4228.661707063366, 4861.829835955725, 4932.556069953722, 4935.235038569028, 5002.374703162105], "value": 4581.5203443813325, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [323.18073683213055, 328.0617434138364, 331.74952910552537, 335.106940004887, 340.1224839913025, 6544.803655890319, 6609.570377925437, 6624.801255962323, 6647.53467257291, 6658.76694315699, 6541.640209788459, 6563.048357988101, 6565.83585410365, 6572.572782769117, 6583.275839657634, 3844.461775351453, 4610.907781844361, 4716.339712440244, 4798.904021919559, 4862.465128835825, 4828.517148285178, 4842.322074909498, 4870.829520966767, 4877.0, 5017.247412888078, 4844.467108618067, 4848.87877803056, 4852.93235488192, 4853.902921941559, 4868.97565512172, 4601.608863246628, 4704.576588107053, 4732.834350797705, 4798.664093513456, 4806.158922188638, 4105.44576482174, 4112.568180341081, 4114.773687447189, 4121.093359460914, 4139.855105071308, 6185.072239164161, 6278.183836101278, 6280.120783090371, 6288.622682639057, 6307.0, 5687.061634830248, 5689.772409103631, 5690.5447564194765, 5692.288463942007, 5698.2592263005545, 6030.13065949557, 6692.966535167342, 6803.161850460029, 7023.789286321418, 7028.613426261553, 4554.089182163548, 4634.814607415699, 4670.229412147026, 4672.813087476497, 4695.60087392572, 7283.490155689104, 7348.301911318416, 7358.632068396559, 7374.188839227681, 7380.0, 4726.409198850144, 4736.218523943579, 4742.430908291027, 4746.620270378361, 4747.0031293428765, 4813.109574728686, 4817.566419022235, 4817.735024573612, 4818.903621927542, 4841.733704646208, 5667.773289068391, 5674.971625141848, 5680.091185410314, 5691.487766100988, 5695.971520142372, 6745.9662701686175, 7030.261822508665, 7078.433725301964, 7083.504154709224, 7088.113985751781, 1648.175912043978, 1670.8913920595123, 1681.3106626283165, 1681.6972944869797, 1685.7977042754949, 1016.1616666250374, 1028.8816786069697, 1068.278911734576, 1072.785442911421, 1096.0464395975393, 5516.200150978166, 6016.09758536223, 6453.709583068773, 6612.437942774822, 6632.2704502504685, 7345.3021962913435, 7355.264473552709, 7374.115106187184, 7380.667869945865, 7385.113786345673, 6605.735770569219, 6621.370969757865, 6627.867442651216, 6644.501662375341, 6648.83377915556, 3816.160444702161, 3864.6715029222833, 3865.304245235828, 3867.9806600967377, 3872.7220017394193, 7071.575705457689, 7198.208197098315, 7246.601436920914, 7526.134494533198, 7633.0, 7360.668769905367, 7571.545707257582, 7573.583452910143, 7585.317321440986, 7592.0, 7646.158922518519, 7652.04349456318, 7657.808554786175, 7658.310752032344, 7664.386849052063, 7158.617787932202, 7334.633268336668, 7357.0, 7384.741534046282, 7403.407727381904, 6071.453569178619, 7107.893381599383, 7177.712891484282, 7222.133343998754, 7225.494215404871, 7614.581198034161, 7617.581033043236, 7622.695092196472, 7628.0, 7657.0, 4209.2423363794815, 4285.142971405794, 4585.19759042173, 4646.558576935186, 4654.232051711429, 7064.82337941535, 7261.854762904607, 7295.744648937261, 7319.926800731924, 7324.487285889936, 7059.329363710439, 7071.186813516306, 7075.398591119812, 7085.929140708733, 7087.893681594884, 6964.303569642995, 7013.333733295329, 7026.8945965809535, 7029.753958611424, 7067.293270672892, 6853.383195512428, 6886.0, 6886.449084073362, 6894.482913781587, 6927.134108236471, 6757.797266082025, 7050.894236586353, 7056.54132481387, 7067.929320706933, 7076.575405475688, 6554.705038273384, 6754.223264324665, 6768.830779230559, 6893.138357705287, 6969.233384327821], "value": 5146.149605438375, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [698.5494356140287, 705.7529864547407, 723.0347486106048, 738.5421038955848, 775.1124961918604, 13238.602841914733, 14979.101253924771, 15069.945103842736, 15252.694946101084, 15267.770983435257, 16200.432984845533, 16282.185890705476, 16285.267162977665, 16301.184940752973, 16327.938683985532, 748.0761259844088, 915.2997956563252, 947.8578213267996, 949.1220620925616, 949.3639261694681, 755.3768141283463, 927.5825878354734, 942.6135284533343, 946.0, 949.4350861237588, 994.5425104451961, 995.5868314649412, 997.581015973293, 999.6501224571379, 1001.43919405133, 776.9572673502955, 787.4723934963588, 988.5600907596123, 990.3760630802584, 996.5963784667222, 580.9651420914759, 584.1150656755019, 699.2797418658789, 710.7192658899705, 712.7184762019025, 15242.57042165515, 15322.387104515807, 15328.923355383264, 15337.846621533754, 15364.923175384163, 12118.0, 12359.443825027894, 12380.195287306296, 12629.368531573477, 12729.872701272961, 10817.729556761065, 10972.670819875417, 11158.609448669258, 11328.490217940212, 11690.649280521598, 3500.929981400383, 3538.85844566217, 3552.751307408457, 3590.5870824855215, 3721.3301605710963, 77241.22758772396, 79280.81078783823, 79322.20677793263, 79464.0, 82322.0, 9504.714858554255, 9555.61777528891, 9561.474118923386, 9645.807083858284, 10237.9488102559, 21244.681329780065, 21261.46846328823, 21273.04271307795, 21357.03893324773, 21359.893200533897, 6248.50011999039, 7345.228750981177, 7380.852382952311, 7385.889211661829, 7609.619519024027, 4061.1877624475223, 4169.874903752892, 4174.791260436966, 4251.29853574163, 4442.955570444319, 3643.107438677541, 3675.9632403676155, 3702.9814850925573, 3705.073731567108, 3706.6849317808287, 536.6806749983754, 655.1875674164003, 655.3381085103983, 660.6102399584269, 680.608650026227, 530.2682298428222, 581.102197105473, 589.1898639370866, 598.4494265275956, 615.689077016104, 8065.112837587861, 8095.757127286191, 8116.689226849262, 8335.624896879655, 8416.663333466715, 559.5299948043581, 577.1054864959297, 601.6961434475562, 612.7273363353354, 613.7268915332634, 3769.151940813295, 3831.885043448701, 3849.8652547160714, 3882.514685664292, 4069.0, 92590.0, 94944.0, 95017.0, 95231.0, 95723.08553828884, 78108.60945695349, 78369.0, 78831.8175227372, 79056.41887162339, 79940.0, 65137.0, 65920.0, 66750.0, 70036.0, 71666.64166679133, 7078.702619767588, 8155.941521929277, 8211.630476628685, 8291.295239904675, 8359.414840961075, 45754.62736117922, 47735.28397074116, 47843.5215647853, 49002.26496602483, 49491.50508495013, 65856.67071664611, 67476.0, 68609.65695171691, 68815.65592172007, 69300.57447989297, 10599.258051936291, 10805.567777288821, 10806.567737290421, 11075.556977721124, 11554.075673946232, 11718.88281117212, 11904.464299106734, 12046.156769026435, 12179.0, 12445.439955201857, 11270.94364528172, 11369.54521819118, 11449.198556100993, 11566.30602163873, 11650.941745291218, 11152.88847111551, 11317.660470185907, 11384.430778460877, 11537.596184133516, 11550.191486595857, 10896.073833724222, 11068.225224234226, 11247.212695111592, 11285.71785705364, 11303.491342889425, 9294.442333460014, 9398.812023759623, 9636.46999415039, 9792.118709316195, 9830.4101753895, 9496.810063798823, 9555.808883822423, 9662.75843103928, 9673.274504412057, 9680.0], "value": 6944.930285996853, "unit": "score"}], "name": "dromaeo_css", "value": 7389.272113470123, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 121.0, 113.0, 112.0, 111.0, 114.0, 113.0, 113.0, 136.0], "value": 116.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 125.0, 159.0, 207.0, 120.0, 142.0, 117.0, 180.0, 192.0, 118.0], "value": 151.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 189.0, 187.0, 182.0, 184.0, 186.0, 184.0, 186.0, 188.0, 185.0], "value": 185.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 82.0, 77.0, 189.0, 82.0, 82.0, 54.0, 61.0, 137.0, 55.0], "value": 88.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 148.0, 78.0, 79.0, 77.0, 128.0, 80.0, 76.0, 129.0], "value": 98.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 86.0, 83.0, 86.0, 83.0, 87.0, 84.0, 87.0, 84.0], "value": 85.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 91.0, 92.0, 92.0, 92.0, 93.0, 93.0, 92.0, 92.0], "value": 92.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 85.0, 86.0, 85.0, 84.0, 84.0, 85.0, 87.0, 84.0], "value": 84.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 65.0, 65.0, 64.0, 64.0, 64.0, 66.0, 83.0, 62.0, 57.0], "value": 65.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 70.0, 73.0, 75.0, 70.0, 69.0, 70.0, 69.0, 70.0], "value": 69.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 81.0, 79.0, 79.0, 80.0, 81.0, 80.0, 82.0, 86.0], "value": 81.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 129.0, 131.0, 131.0, 132.0, 131.0, 134.0, 144.0, 151.0, 158.0], "value": 137.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 166.0, 182.0, 219.0, 200.0, 167.0, 178.0, 164.0, 165.0, 163.0], "value": 176.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 67.0, 68.0, 68.0, 67.0, 66.0, 70.0, 67.0, 68.0], "value": 68.0, "unit": "score"}], "name": "kraken", "value": 1500.9, "alertThreshold": 2.0}]} 03:32:10 INFO - Return code: 0 03:32:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 03:32:10 ERROR - # TBPL SUCCESS # 03:32:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:32:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:32:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:32:10 INFO - 2016-06-04 03:32:10.961 screenresolution[2325:33399] starting screenresolution argv=screenresolution get 03:32:10 INFO - 2016-06-04 03:32:10.964 screenresolution[2325:33399] Display 0: 1600x1200x32@60 03:32:10 INFO - 2016-06-04 03:32:10.970 screenresolution[2326:33402] starting screenresolution argv=screenresolution list 03:32:10 INFO - Available Modes on Display 0 03:32:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:32:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:32:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:32:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:32:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:32:11 INFO - Intel Iris: 03:32:11 INFO - Chipset Model: Intel Iris 03:32:11 INFO - Type: GPU 03:32:11 INFO - Bus: Built-In 03:32:11 INFO - VRAM (Dynamic, Max): 1536 MB 03:32:11 INFO - Vendor: Intel (0x8086) 03:32:11 INFO - Device ID: 0x0a2e 03:32:11 INFO - Revision ID: 0x0009 03:32:11 INFO - Displays: 03:32:11 INFO - Display: 03:32:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:32:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:32:11 INFO - Display Serial Number: 1600x1200 60 03:32:11 INFO - Main Display: Yes 03:32:11 INFO - Mirror: Off 03:32:11 INFO - Online: Yes 03:32:11 INFO - Rotation: Supported 03:32:11 INFO - Return code: 0 03:32:11 INFO - Running post-action listener: _resource_record_post_action 03:32:11 INFO - Running post-run listener: _resource_record_post_run 03:32:12 INFO - Total resource usage - Wall time: 1208s; CPU: 53.0%; Read bytes: 946176; Write bytes: 655748096; Read time: 12; Write time: 1884 03:32:12 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 354574848; Write bytes: 355898880; Read time: 27745; Write time: 778 03:32:12 INFO - run-tests - Wall time: 1177s; CPU: 52.0%; Read bytes: 946176; Write bytes: 297578496; Read time: 12; Write time: 1095 03:32:12 INFO - Running post-run listener: _upload_blobber_files 03:32:12 INFO - Blob upload gear active. 03:32:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:32:12 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 03:32:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:32:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:32:12 INFO - (blobuploader) - INFO - Open directory for files ... 03:32:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:32:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:32:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:32:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:32:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:32:13 INFO - (blobuploader) - INFO - Done attempting. 03:32:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:32:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:32:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:32:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:32:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:32:14 INFO - (blobuploader) - INFO - Done attempting. 03:32:14 INFO - (blobuploader) - INFO - Iteration through files over. 03:32:14 INFO - Return code: 0 03:32:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:32:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:32:14 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b5af9296ba6b10d28b2ed285c2d2839c451d76908a25f73d1e76e17b47d8fe9948c205eb963206c5e0dedb9fac8fab47b3be436c446623fc9c3a057e268b689e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ffe7ec5aaded042bf10124447603e8ecbbfd8717271132ad7f58ea6833dd2597d36f96c0fe5b70e06d7be345048aeeca23d03c80ff6c5eb215bb450fe275a353"} 03:32:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:32:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:32:14 INFO - Contents: 03:32:14 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b5af9296ba6b10d28b2ed285c2d2839c451d76908a25f73d1e76e17b47d8fe9948c205eb963206c5e0dedb9fac8fab47b3be436c446623fc9c3a057e268b689e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ffe7ec5aaded042bf10124447603e8ecbbfd8717271132ad7f58ea6833dd2597d36f96c0fe5b70e06d7be345048aeeca23d03c80ff6c5eb215bb450fe275a353"} 03:32:14 INFO - Running post-run listener: copy_logs_to_upload_dir 03:32:14 INFO - Copying logs to upload dir... 03:32:14 INFO - mkdir: /builds/slave/test/build/upload/logs 03:32:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1324.928119 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 4 secs) (at 2016-06-04 03:32:14.483690) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-04 03:32:14.486952) ========= 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.CwYtC5WvLU/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.CoITpf8il9/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b5af9296ba6b10d28b2ed285c2d2839c451d76908a25f73d1e76e17b47d8fe9948c205eb963206c5e0dedb9fac8fab47b3be436c446623fc9c3a057e268b689e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ffe7ec5aaded042bf10124447603e8ecbbfd8717271132ad7f58ea6833dd2597d36f96c0fe5b70e06d7be345048aeeca23d03c80ff6c5eb215bb450fe275a353"} build_url:https://queue.taskcluster.net/v1/task/I7SQXtJ8QZqX-K8VxCYgOQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011319 build_url: 'https://queue.taskcluster.net/v1/task/I7SQXtJ8QZqX-K8VxCYgOQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b5af9296ba6b10d28b2ed285c2d2839c451d76908a25f73d1e76e17b47d8fe9948c205eb963206c5e0dedb9fac8fab47b3be436c446623fc9c3a057e268b689e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ffe7ec5aaded042bf10124447603e8ecbbfd8717271132ad7f58ea6833dd2597d36f96c0fe5b70e06d7be345048aeeca23d03c80ff6c5eb215bb450fe275a353"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-04 03:32:14.522003) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 03:32:14.522671) ========= 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.CwYtC5WvLU/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.CoITpf8il9/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005144 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 03:32:14.575736) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-04 03:32:14.576368) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-04 03:32:14.577047) ========= ========= Total master_lag: 0.19 =========