builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0083 starttime: 1464010147.99 results: success (0) buildid: 20160523043629 builduid: 9d939aba9f834d8ca4031d65520bf3e1 revision: fdf13c9edc864bebb7258816b4033af8b5dccc48 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-23 06:29:07.990304) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-23 06:29:07.991131) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-23 06:29:07.991412) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-23 06:29:08.052896) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 06:29:08.053412) ========= 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.iWfFaRmlkL/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.re4Nil0g4X/Listeners TMPDIR=/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-23 06:29:08-- 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% 154M=0s 2016-05-23 06:29:08 (154 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.523747 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 06:29:08.599012) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 06:29:08.599374) ========= 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.iWfFaRmlkL/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.re4Nil0g4X/Listeners TMPDIR=/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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.020219 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 06:29:08.651511) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-23 06:29:08.652386) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev fdf13c9edc864bebb7258816b4033af8b5dccc48 --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 fdf13c9edc864bebb7258816b4033af8b5dccc48 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.iWfFaRmlkL/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.re4Nil0g4X/Listeners TMPDIR=/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-23 06:29:08,728 truncating revision to first 12 chars 2016-05-23 06:29:08,728 Setting DEBUG logging. 2016-05-23 06:29:08,728 attempt 1/10 2016-05-23 06:29:08,728 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/fdf13c9edc86?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-23 06:29:09,669 unpacking tar archive at: mozilla-aurora-fdf13c9edc86/testing/mozharness/ program finished with exit code 0 elapsedTime=1.183356 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-23 06:29:09.864952) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-23 06:29:09.865276) ========= script_repo_revision: fdf13c9edc864bebb7258816b4033af8b5dccc48 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-23 06:29:09.865669) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-23 06:29:09.869992) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-23 06:29:09.894466) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 24 secs) (at 2016-05-23 06:29:09.894865) ========= /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.iWfFaRmlkL/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.re4Nil0g4X/Listeners TMPDIR=/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 06:29:10 INFO - MultiFileLogger online at 20160523 06:29:10 in /builds/slave/test 06:29:10 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 06:29:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:29:10 INFO - {'append_to_log': False, 06:29:10 INFO - 'base_work_dir': '/builds/slave/test', 06:29:10 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 06:29:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:29:10 INFO - 'branch': 'Mozilla-Aurora', 06:29:10 INFO - 'buildbot_json_path': 'buildprops.json', 06:29:10 INFO - 'config_files': ('talos/mac_config.py',), 06:29:10 INFO - 'default_actions': ('clobber', 06:29:10 INFO - 'read-buildbot-config', 06:29:10 INFO - 'download-and-extract', 06:29:10 INFO - 'populate-webroot', 06:29:10 INFO - 'create-virtualenv', 06:29:10 INFO - 'install', 06:29:10 INFO - 'run-tests'), 06:29:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:29:10 INFO - 'download_minidump_stackwalk': True, 06:29:10 INFO - 'download_symbols': 'ondemand', 06:29:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:29:10 INFO - 'tooltool.py': '/tools/tooltool.py', 06:29:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:29:10 INFO - '/tools/misc-python/virtualenv.py')}, 06:29:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:29:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:29:10 INFO - 'installer_path': 'installer.exe', 06:29:10 INFO - 'log_level': 'info', 06:29:10 INFO - 'log_name': 'talos', 06:29:10 INFO - 'log_to_console': True, 06:29:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:29:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:29:10 INFO - 'opt_config_files': (), 06:29:10 INFO - 'pip_index': False, 06:29:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:29:10 INFO - 'cmd': ('bash', 06:29:10 INFO - '-c', 06:29:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:29:10 INFO - 'enabled': True, 06:29:10 INFO - 'halt_on_failure': False, 06:29:10 INFO - 'name': 'check_screen_resolution'},), 06:29:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:29:10 INFO - 'cmd': ('bash', 06:29:10 INFO - '-c', 06:29:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:29:10 INFO - 'enabled': True, 06:29:10 INFO - 'halt_on_failure': False, 06:29:10 INFO - 'name': 'check_screen_resolution'},), 06:29:10 INFO - 'run_cmd_checks_enabled': True, 06:29:10 INFO - 'sps_profile': False, 06:29:10 INFO - 'sps_profile_interval': 0, 06:29:10 INFO - 'suite': 'dromaeojs', 06:29:10 INFO - 'system_bits': '32', 06:29:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:29:10 INFO - 'title': 't-yosemite-r7-0083', 06:29:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:29:10 INFO - 'use_talos_json': True, 06:29:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:29:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:29:10 INFO - 'work_dir': 'build'} 06:29:10 INFO - ##### 06:29:10 INFO - ##### Running clobber step. 06:29:10 INFO - ##### 06:29:10 INFO - Running pre-action listener: _resource_record_pre_action 06:29:10 INFO - Running main action method: clobber 06:29:10 INFO - rmtree: /builds/slave/test/build 06:29:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:29:11 INFO - Running post-action listener: _resource_record_post_action 06:29:11 INFO - ##### 06:29:11 INFO - ##### Running read-buildbot-config step. 06:29:11 INFO - ##### 06:29:11 INFO - Running pre-action listener: _resource_record_pre_action 06:29:11 INFO - Running main action method: read_buildbot_config 06:29:11 INFO - Using buildbot properties: 06:29:11 INFO - { 06:29:11 INFO - "project": "", 06:29:11 INFO - "product": "firefox", 06:29:11 INFO - "script_repo_revision": "production", 06:29:11 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 06:29:11 INFO - "repository": "", 06:29:11 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 06:29:11 INFO - "buildid": "20160523043629", 06:29:11 INFO - "pgo_build": "False", 06:29:11 INFO - "basedir": "/builds/slave/test", 06:29:11 INFO - "buildnumber": 352, 06:29:11 INFO - "slavename": "t-yosemite-r7-0083", 06:29:11 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 06:29:11 INFO - "platform": "yosemite_r7", 06:29:11 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 06:29:11 INFO - "branch": "mozilla-aurora", 06:29:11 INFO - "repo_path": "releases/mozilla-aurora", 06:29:11 INFO - "revision": "fdf13c9edc864bebb7258816b4033af8b5dccc48", 06:29:11 INFO - "stage_platform": "macosx64", 06:29:11 INFO - "builduid": "9d939aba9f834d8ca4031d65520bf3e1", 06:29:11 INFO - "slavebuilddir": "test" 06:29:11 INFO - } 06:29:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/G3T_NDhkQ72_1OnxsCuyUw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 06:29:11 INFO - Running post-action listener: _resource_record_post_action 06:29:11 INFO - ##### 06:29:11 INFO - ##### Running download-and-extract step. 06:29:11 INFO - ##### 06:29:11 INFO - Running pre-action listener: _resource_record_pre_action 06:29:11 INFO - Running main action method: download_and_extract 06:29:11 INFO - mkdir: /builds/slave/test/build/tests 06:29: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')]} 06:29:11 INFO - https://queue.taskcluster.net/v1/task/G3T_NDhkQ72_1OnxsCuyUw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 06:29:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G3T_NDhkQ72_1OnxsCuyUw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 06:29:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G3T_NDhkQ72_1OnxsCuyUw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 06:29:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G3T_NDhkQ72_1OnxsCuyUw/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 06:29:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G3T_NDhkQ72_1OnxsCuyUw/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 06:29:41 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G3T_NDhkQ72_1OnxsCuyUw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json: timed out 06:29:41 INFO - retry: attempt #1 caught exception: timed out 06:29:41 INFO - retry: Failed, sleeping 30 seconds before retrying 06:30:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G3T_NDhkQ72_1OnxsCuyUw/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 06:30:24 INFO - Downloaded 1199 bytes. 06:30:24 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 06:30:24 INFO - Using the following test package requirements: 06:30:24 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 06:30:24 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 06:30:24 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 06:30:24 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 06:30:24 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 06:30:24 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 06:30:24 INFO - u'jsshell-mac.zip'], 06:30:24 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 06:30:24 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 06:30:24 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 06:30:24 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 06:30:24 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 06:30:24 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 06:30:24 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 06:30:24 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 06:30:24 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 06:30:24 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 06:30:24 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 06:30:24 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 06:30:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:30:24 INFO - https://queue.taskcluster.net/v1/task/G3T_NDhkQ72_1OnxsCuyUw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 06:30:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G3T_NDhkQ72_1OnxsCuyUw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 06:30:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G3T_NDhkQ72_1OnxsCuyUw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 06:30:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G3T_NDhkQ72_1OnxsCuyUw/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 06:30:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G3T_NDhkQ72_1OnxsCuyUw/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 06:30:25 INFO - Downloaded 18102464 bytes. 06:30:25 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'] 06:30:25 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 06:30:29 INFO - Return code: 0 06:30:29 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 06:30:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:30:29 INFO - https://queue.taskcluster.net/v1/task/G3T_NDhkQ72_1OnxsCuyUw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 06:30:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G3T_NDhkQ72_1OnxsCuyUw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 06:30:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G3T_NDhkQ72_1OnxsCuyUw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 06:30:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G3T_NDhkQ72_1OnxsCuyUw/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 06:30:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G3T_NDhkQ72_1OnxsCuyUw/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 06:30:32 INFO - Downloaded 18102464 bytes. 06:30:32 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'] 06:30:32 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 06:30:36 INFO - Return code: 0 06:30:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:30:36 INFO - https://queue.taskcluster.net/v1/task/G3T_NDhkQ72_1OnxsCuyUw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 06:30:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G3T_NDhkQ72_1OnxsCuyUw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 06:30:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G3T_NDhkQ72_1OnxsCuyUw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 06:30:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G3T_NDhkQ72_1OnxsCuyUw/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 06:30:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G3T_NDhkQ72_1OnxsCuyUw/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 06:30:37 INFO - Downloaded 10840145 bytes. 06:30:37 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'] 06:30:37 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 06:30:37 INFO - Return code: 0 06:30:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:30:37 INFO - https://queue.taskcluster.net/v1/task/G3T_NDhkQ72_1OnxsCuyUw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 06:30:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G3T_NDhkQ72_1OnxsCuyUw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 06:30:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G3T_NDhkQ72_1OnxsCuyUw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 06:30:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G3T_NDhkQ72_1OnxsCuyUw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 06:30:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G3T_NDhkQ72_1OnxsCuyUw/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 06:30:46 INFO - Downloaded 114134518 bytes. 06:30:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/G3T_NDhkQ72_1OnxsCuyUw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 06:30:46 INFO - mkdir: /builds/slave/test/properties 06:30:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:30:46 INFO - Writing to file /builds/slave/test/properties/build_url 06:30:46 INFO - Contents: 06:30:46 INFO - build_url:https://queue.taskcluster.net/v1/task/G3T_NDhkQ72_1OnxsCuyUw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 06:30:47 INFO - Running post-action listener: _resource_record_post_action 06:30:47 INFO - Running post-action listener: set_extra_try_arguments 06:30:47 INFO - ##### 06:30:47 INFO - ##### Running populate-webroot step. 06:30:47 INFO - ##### 06:30:47 INFO - Running pre-action listener: _resource_record_pre_action 06:30:47 INFO - Running main action method: populate_webroot 06:30:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:30:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:30:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:30:47 INFO - '--tppagecycles', 06:30:47 INFO - '7'], 06:30:47 INFO - 'tests': ['tsvgm']}}, 06:30:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:30:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:30:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:30:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:30:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:30:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:30:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:30:47 INFO - 'talos_options': ['--disable-e10s'], 06:30:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:30:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:30:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:30:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:30:47 INFO - 'talos_options': ['--disable-e10s'], 06:30:47 INFO - 'tests': ['damp', 'tps']}, 06:30:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:30:47 INFO - 'tests': ['damp', 'tps']}, 06:30:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:30:47 INFO - 'tests': ['dromaeo_dom']}, 06:30:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:30:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:30:47 INFO - 'tests': ['a11yr', 06:30:47 INFO - 'ts_paint', 06:30:47 INFO - 'tpaint', 06:30:47 INFO - 'sessionrestore', 06:30:47 INFO - 'sessionrestore_no_auto_restore', 06:30:47 INFO - 'tabpaint']}, 06:30:47 INFO - 'other-e10s': {'tests': ['a11yr', 06:30:47 INFO - 'ts_paint', 06:30:47 INFO - 'tpaint', 06:30:47 INFO - 'sessionrestore', 06:30:47 INFO - 'sessionrestore_no_auto_restore', 06:30:47 INFO - 'tabpaint']}, 06:30:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:30:47 INFO - 'ts_paint', 06:30:47 INFO - 'tpaint', 06:30:47 INFO - 'sessionrestore', 06:30:47 INFO - 'sessionrestore_no_auto_restore', 06:30:47 INFO - 'tabpaint']}, 06:30:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:30:47 INFO - 'ts_paint', 06:30:47 INFO - 'tpaint', 06:30:47 INFO - 'sessionrestore', 06:30:47 INFO - 'sessionrestore_no_auto_restore', 06:30:47 INFO - 'tabpaint']}, 06:30:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:30:47 INFO - 'tests': ['a11yr', 06:30:47 INFO - 'ts_paint', 06:30:47 INFO - 'tpaint', 06:30:47 INFO - 'sessionrestore', 06:30:47 INFO - 'sessionrestore_no_auto_restore', 06:30:47 INFO - 'tabpaint']}, 06:30:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:30:47 INFO - 'tests': ['a11yr', 06:30:47 INFO - 'ts_paint', 06:30:47 INFO - 'tpaint', 06:30:47 INFO - 'sessionrestore', 06:30:47 INFO - 'sessionrestore_no_auto_restore', 06:30:47 INFO - 'tabpaint']}, 06:30:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:30:47 INFO - 'tests': ['tsvgx', 06:30:47 INFO - 'tsvgr_opacity', 06:30:47 INFO - 'tart', 06:30:47 INFO - 'tscrollx', 06:30:47 INFO - 'cart']}, 06:30:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:30:47 INFO - 'tsvgr_opacity', 06:30:47 INFO - 'tart', 06:30:47 INFO - 'tscrollx', 06:30:47 INFO - 'cart']}, 06:30:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:30:47 INFO - 'talos_options': ['--disable-e10s'], 06:30:47 INFO - 'tests': ['tp5o']}, 06:30:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:30:47 INFO - 'tests': ['tp5o']}, 06:30:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:30:47 INFO - 'talos_options': ['--disable-e10s', 06:30:47 INFO - '--xperf_path', 06:30:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:30:47 INFO - 'tests': ['tp5n']}, 06:30:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:30:47 INFO - 'talos_options': ['--xperf_path', 06:30:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:30:47 INFO - 'tests': ['tp5n']}}, 06:30:47 INFO - 'talos.zip': {'path': '', 06:30:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:30:47 INFO - Running post-action listener: _resource_record_post_action 06:30:47 INFO - ##### 06:30:47 INFO - ##### Running create-virtualenv step. 06:30:47 INFO - ##### 06:30:47 INFO - Running pre-action listener: _resource_record_pre_action 06:30:47 INFO - Running main action method: create_virtualenv 06:30:47 INFO - Creating virtualenv /builds/slave/test/build/venv 06:30:47 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 06:30:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:30:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:30:47 INFO - Using real prefix '/tools/python27' 06:30:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:30:48 INFO - Installing distribute.............................................................................................................................................................................................done. 06:30:51 INFO - Installing pip.................done. 06:30:51 INFO - Return code: 0 06:30:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:30:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:30:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:30:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:30:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:30:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:30:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:30:51 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 0x10c3f8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c408420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe78bc8dba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c297e00>, '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 0x10c100db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c292b20>, '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.iWfFaRmlkL/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.re4Nil0g4X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 06:30:51 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 06:30:51 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 06:30:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iWfFaRmlkL/Render', 06:30:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:30:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:30:51 INFO - 'HOME': '/Users/cltbld', 06:30:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:30:51 INFO - 'LOGNAME': 'cltbld', 06:30:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:30:51 INFO - 'MOZ_NO_REMOTE': '1', 06:30:51 INFO - 'NO_EM_RESTART': '1', 06:30:51 INFO - 'PAGER': '/bin/cat', 06:30:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:30:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:30:51 INFO - 'PWD': '/builds/slave/test', 06:30:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:30:51 INFO - 'SHELL': '/bin/bash', 06:30:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.re4Nil0g4X/Listeners', 06:30:51 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 06:30:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:30:51 INFO - 'USER': 'cltbld', 06:30:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:30:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:30:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:30:51 INFO - 'XPC_FLAGS': '0x0', 06:30:51 INFO - 'XPC_SERVICE_NAME': '0', 06:30:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:30:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:30:51 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 06:30:51 INFO - Cleaning up... 06:30:51 INFO - Return code: 0 06:30:51 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 06:30:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:30:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:30:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:30:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:30:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:30:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:30:51 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 0x10c3f8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c408420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe78bc8dba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c297e00>, '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 0x10c100db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c292b20>, '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.iWfFaRmlkL/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.re4Nil0g4X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 06:30:51 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 06:30:51 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 06:30:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iWfFaRmlkL/Render', 06:30:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:30:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:30:51 INFO - 'HOME': '/Users/cltbld', 06:30:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:30:51 INFO - 'LOGNAME': 'cltbld', 06:30:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:30:51 INFO - 'MOZ_NO_REMOTE': '1', 06:30:51 INFO - 'NO_EM_RESTART': '1', 06:30:51 INFO - 'PAGER': '/bin/cat', 06:30:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:30:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:30:51 INFO - 'PWD': '/builds/slave/test', 06:30:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:30:51 INFO - 'SHELL': '/bin/bash', 06:30:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.re4Nil0g4X/Listeners', 06:30:51 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 06:30:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:30:51 INFO - 'USER': 'cltbld', 06:30:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:30:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:30:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:30:51 INFO - 'XPC_FLAGS': '0x0', 06:30:51 INFO - 'XPC_SERVICE_NAME': '0', 06:30:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:30:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:30:51 INFO - Downloading/unpacking psutil>=0.7.1 06:30: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 06:30: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 06:30: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 06:30: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 06:30: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 06:30: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 06:30:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:30:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:30:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:30:54 INFO - Installing collected packages: psutil 06:30:54 INFO - Running setup.py install for psutil 06:30:54 INFO - building 'psutil._psutil_osx' extension 06:30:54 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 06:30:54 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 06:30:54 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 06:30:54 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 06:30:54 INFO - building 'psutil._psutil_posix' extension 06:30:54 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 06:30:54 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:30:54 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:30:54 INFO - ^ 06:30:54 INFO - 1 warning generated. 06:30:54 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 06:30:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:30:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:30:55 INFO - Successfully installed psutil 06:30:55 INFO - Cleaning up... 06:30:55 INFO - Return code: 0 06:30:55 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 06:30:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:30:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:30:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:30:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:30:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:30:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:30:55 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 0x10c3f8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c408420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe78bc8dba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c297e00>, '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 0x10c100db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c292b20>, '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.iWfFaRmlkL/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.re4Nil0g4X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 06:30:55 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 06:30:55 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 06:30:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iWfFaRmlkL/Render', 06:30:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:30:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:30:55 INFO - 'HOME': '/Users/cltbld', 06:30:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:30:55 INFO - 'LOGNAME': 'cltbld', 06:30:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:30:55 INFO - 'MOZ_NO_REMOTE': '1', 06:30:55 INFO - 'NO_EM_RESTART': '1', 06:30:55 INFO - 'PAGER': '/bin/cat', 06:30:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:30:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:30:55 INFO - 'PWD': '/builds/slave/test', 06:30:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:30:55 INFO - 'SHELL': '/bin/bash', 06:30:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.re4Nil0g4X/Listeners', 06:30:55 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 06:30:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:30:55 INFO - 'USER': 'cltbld', 06:30:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:30:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:30:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:30:55 INFO - 'XPC_FLAGS': '0x0', 06:30:55 INFO - 'XPC_SERVICE_NAME': '0', 06:30:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:30:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:30:55 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 06:30:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:30:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:30:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:30:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:30:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:30:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:30:57 INFO - Downloading mozsystemmonitor-0.0.tar.gz 06:30:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:30:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 06:30:57 INFO - Installing collected packages: mozsystemmonitor 06:30:57 INFO - Running setup.py install for mozsystemmonitor 06:30:58 INFO - Successfully installed mozsystemmonitor 06:30:58 INFO - Cleaning up... 06:30:58 INFO - Return code: 0 06:30:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:30:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:30:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:30:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:30:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:30:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:30:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:30:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c3f8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c408420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe78bc8dba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c297e00>, '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 0x10c100db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c292b20>, '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.iWfFaRmlkL/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.re4Nil0g4X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 06:30:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 06:30:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 06:30:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iWfFaRmlkL/Render', 06:30:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:30:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:30:58 INFO - 'HOME': '/Users/cltbld', 06:30:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:30:58 INFO - 'LOGNAME': 'cltbld', 06:30:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:30:58 INFO - 'MOZ_NO_REMOTE': '1', 06:30:58 INFO - 'NO_EM_RESTART': '1', 06:30:58 INFO - 'PAGER': '/bin/cat', 06:30:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:30:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:30:58 INFO - 'PWD': '/builds/slave/test', 06:30:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:30:58 INFO - 'SHELL': '/bin/bash', 06:30:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.re4Nil0g4X/Listeners', 06:30:58 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 06:30:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:30:58 INFO - 'USER': 'cltbld', 06:30:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:30:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:30:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:30:58 INFO - 'XPC_FLAGS': '0x0', 06:30:58 INFO - 'XPC_SERVICE_NAME': '0', 06:30:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:30:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:30:58 INFO - Downloading/unpacking blobuploader==1.2.4 06:30: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 06:30: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 06:30: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 06:30: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 06:30: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 06:30: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 06:31:00 INFO - Downloading blobuploader-1.2.4.tar.gz 06:31:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:31:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:31: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 06:31: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 06:31: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 06:31: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 06:31: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 06:31: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 06:31:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:31:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:31: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 06:31: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 06:31: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 06:31: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 06:31: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 06:31: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 06:31:01 INFO - Downloading docopt-0.6.1.tar.gz 06:31:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:31:02 INFO - Installing collected packages: blobuploader, requests, docopt 06:31:02 INFO - Running setup.py install for blobuploader 06:31:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:31:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:31:02 INFO - Running setup.py install for requests 06:31:02 INFO - Running setup.py install for docopt 06:31:02 INFO - Successfully installed blobuploader requests docopt 06:31:02 INFO - Cleaning up... 06:31:02 INFO - Return code: 0 06:31:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:31:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:31:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:31:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:31:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:31:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:31:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:31:02 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 0x10c3f8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c408420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe78bc8dba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c297e00>, '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 0x10c100db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c292b20>, '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.iWfFaRmlkL/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.re4Nil0g4X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 06:31:02 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 06:31:02 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 06:31:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iWfFaRmlkL/Render', 06:31:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:31:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:31:02 INFO - 'HOME': '/Users/cltbld', 06:31:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:31:02 INFO - 'LOGNAME': 'cltbld', 06:31:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:31:02 INFO - 'MOZ_NO_REMOTE': '1', 06:31:02 INFO - 'NO_EM_RESTART': '1', 06:31:02 INFO - 'PAGER': '/bin/cat', 06:31:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:31:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:31:02 INFO - 'PWD': '/builds/slave/test', 06:31:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:31:02 INFO - 'SHELL': '/bin/bash', 06:31:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.re4Nil0g4X/Listeners', 06:31:02 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 06:31:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:31:02 INFO - 'USER': 'cltbld', 06:31:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:31:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:31:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:31:02 INFO - 'XPC_FLAGS': '0x0', 06:31:02 INFO - 'XPC_SERVICE_NAME': '0', 06:31:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:31:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:31:03 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-hLo7Kr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:31:03 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-TiPDrF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:31:03 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-jOlJ1W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:31:03 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-IG0ybv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:31:03 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-Rbt1Y1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:31:03 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-AWIh5R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:31:03 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-tNa8i2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:31:03 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-Li4Ndl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:31:04 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-5yOnCe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:31:04 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-EweFzz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:31:04 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-hv_vyB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:31:04 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-Yt4Eww-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:31:04 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-9N5i1M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:31:04 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-s70Txi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:31:04 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-fRMCzZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:31:04 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-LJ1VJj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:31:04 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-3w4uBJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:31:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:31:04 INFO - Running setup.py install for manifestparser 06:31:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:31:05 INFO - Running setup.py install for mozcrash 06:31:05 INFO - Running setup.py install for mozdebug 06:31:05 INFO - Running setup.py install for mozdevice 06:31:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:31:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:31:05 INFO - Running setup.py install for mozfile 06:31:05 INFO - Running setup.py install for mozhttpd 06:31:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:31:05 INFO - Running setup.py install for mozinfo 06:31:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:31:05 INFO - Running setup.py install for mozInstall 06:31:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:31:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:31:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:31:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:31:06 INFO - Running setup.py install for mozleak 06:31:06 INFO - Running setup.py install for mozlog 06:31:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:31:06 INFO - Running setup.py install for moznetwork 06:31:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:31:06 INFO - Running setup.py install for mozprocess 06:31:06 INFO - Running setup.py install for mozprofile 06:31:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:31:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:31:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:31:06 INFO - Running setup.py install for mozrunner 06:31:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:31:07 INFO - Running setup.py install for mozscreenshot 06:31:07 INFO - Running setup.py install for moztest 06:31:07 INFO - Running setup.py install for mozversion 06:31:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:31:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:31:07 INFO - Cleaning up... 06:31:07 INFO - Return code: 0 06:31:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:31:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:31:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:31:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:31:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:31:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:31:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:31:07 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 0x10c3f8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c408420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe78bc8dba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c297e00>, '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 0x10c100db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c292b20>, '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.iWfFaRmlkL/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.re4Nil0g4X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 06:31:07 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 06:31:07 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 06:31:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iWfFaRmlkL/Render', 06:31:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:31:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:31:07 INFO - 'HOME': '/Users/cltbld', 06:31:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:31:07 INFO - 'LOGNAME': 'cltbld', 06:31:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:31:07 INFO - 'MOZ_NO_REMOTE': '1', 06:31:07 INFO - 'NO_EM_RESTART': '1', 06:31:07 INFO - 'PAGER': '/bin/cat', 06:31:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:31:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:31:07 INFO - 'PWD': '/builds/slave/test', 06:31:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:31:07 INFO - 'SHELL': '/bin/bash', 06:31:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.re4Nil0g4X/Listeners', 06:31:07 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 06:31:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:31:07 INFO - 'USER': 'cltbld', 06:31:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:31:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:31:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:31:07 INFO - 'XPC_FLAGS': '0x0', 06:31:07 INFO - 'XPC_SERVICE_NAME': '0', 06:31:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:31:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:31:07 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-gm0Sei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:31:07 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)) 06:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:31:07 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-bz7fEO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:31:08 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)) 06:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:31:08 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-uckxBd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:31:08 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)) 06:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:31:08 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-akE1Cz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:31:08 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)) 06:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:31:08 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-iRxodH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:31:08 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)) 06:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:31:08 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-MBfsSV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:31:08 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)) 06:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:31:08 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-EZdD_K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:31:08 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)) 06:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:31:08 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-RBCu5P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:31:08 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)) 06:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:31:08 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-fN6Q0R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:31:08 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)) 06:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:31:08 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-oz0u9Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:31:08 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)) 06:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:31:08 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-tliJLW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:31:08 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)) 06:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:31:08 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-lgjyOS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:31:09 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)) 06:31:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:31:09 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-rXiB99-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:31:09 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)) 06:31:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:31:09 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-1MldCz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:31:09 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)) 06:31:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:31:09 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-ukmGn8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:31:09 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)) 06:31:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:31:09 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-0uqM20-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:31:09 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)) 06:31:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:31:09 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-8_qPUb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:31:09 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)) 06:31:09 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)) 06:31:09 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)) 06:31:09 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)) 06:31:09 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)) 06:31:09 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)) 06:31:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:31:09 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 06:31:09 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 06:31:09 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 06:31:09 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 06:31:09 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 06:31:09 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 06:31:12 INFO - Downloading blessings-1.6.tar.gz 06:31:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:31:12 INFO - Installing collected packages: blessings 06:31:12 INFO - Running setup.py install for blessings 06:31:12 INFO - Successfully installed blessings 06:31:12 INFO - Cleaning up... 06:31:12 INFO - Return code: 0 06:31:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:31:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:31:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:31:12 INFO - Reading from file tmpfile_stdout 06:31:12 INFO - Current package versions: 06:31:12 INFO - blessings == 1.6 06:31:12 INFO - blobuploader == 1.2.4 06:31:12 INFO - docopt == 0.6.1 06:31:12 INFO - manifestparser == 1.1 06:31:12 INFO - mozInstall == 1.12 06:31:12 INFO - mozcrash == 0.17 06:31:12 INFO - mozdebug == 0.1 06:31:12 INFO - mozdevice == 0.48 06:31:12 INFO - mozfile == 1.2 06:31:12 INFO - mozhttpd == 0.7 06:31:12 INFO - mozinfo == 0.9 06:31:12 INFO - mozleak == 0.1 06:31:12 INFO - mozlog == 3.1 06:31:12 INFO - moznetwork == 0.27 06:31:12 INFO - mozprocess == 0.22 06:31:12 INFO - mozprofile == 0.28 06:31:12 INFO - mozrunner == 6.11 06:31:12 INFO - mozscreenshot == 0.1 06:31:12 INFO - mozsystemmonitor == 0.0 06:31:12 INFO - moztest == 0.7 06:31:12 INFO - mozversion == 1.4 06:31:12 INFO - psutil == 3.1.1 06:31:12 INFO - requests == 1.2.3 06:31:12 INFO - wsgiref == 0.1.2 06:31:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:31: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')]} 06:31:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:31:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:31: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')]} 06:31:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:31:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:31:12 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 0x10c3f8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c408420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe78bc8dba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c297e00>, '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 0x10c100db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c292b20>, '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.iWfFaRmlkL/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.re4Nil0g4X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 06:31:12 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 06:31:12 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 06:31:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iWfFaRmlkL/Render', 06:31:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:31:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:31:12 INFO - 'HOME': '/Users/cltbld', 06:31:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:31:12 INFO - 'LOGNAME': 'cltbld', 06:31:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:31:12 INFO - 'MOZ_NO_REMOTE': '1', 06:31:12 INFO - 'NO_EM_RESTART': '1', 06:31:12 INFO - 'PAGER': '/bin/cat', 06:31:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:31:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:31:12 INFO - 'PWD': '/builds/slave/test', 06:31:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:31:12 INFO - 'SHELL': '/bin/bash', 06:31:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.re4Nil0g4X/Listeners', 06:31:12 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 06:31:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:31:12 INFO - 'USER': 'cltbld', 06:31:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:31:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:31:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:31:12 INFO - 'XPC_FLAGS': '0x0', 06:31:12 INFO - 'XPC_SERVICE_NAME': '0', 06:31:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:31:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:31:12 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)) 06:31:12 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)) 06:31:12 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)) 06:31:12 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)) 06:31:12 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)) 06:31:12 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)) 06:31:12 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)) 06:31:12 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)) 06:31:12 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)) 06:31:12 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)) 06:31:12 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)) 06:31:12 INFO - Cleaning up... 06:31:12 INFO - Return code: 0 06:31:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:31: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')]} 06:31:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:31:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:31: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')]} 06:31:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:31:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:31:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c3f8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c408420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe78bc8dba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c297e00>, '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 0x10c100db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c292b20>, '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.iWfFaRmlkL/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.re4Nil0g4X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 06:31:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 06:31:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 06:31:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iWfFaRmlkL/Render', 06:31:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:31:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:31:12 INFO - 'HOME': '/Users/cltbld', 06:31:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:31:12 INFO - 'LOGNAME': 'cltbld', 06:31:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:31:12 INFO - 'MOZ_NO_REMOTE': '1', 06:31:12 INFO - 'NO_EM_RESTART': '1', 06:31:12 INFO - 'PAGER': '/bin/cat', 06:31:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:31:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:31:12 INFO - 'PWD': '/builds/slave/test', 06:31:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:31:12 INFO - 'SHELL': '/bin/bash', 06:31:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.re4Nil0g4X/Listeners', 06:31:12 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 06:31:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:31:12 INFO - 'USER': 'cltbld', 06:31:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:31:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:31:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:31:12 INFO - 'XPC_FLAGS': '0x0', 06:31:12 INFO - 'XPC_SERVICE_NAME': '0', 06:31:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:31:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:31:13 INFO - Downloading/unpacking jsonschema 06:31:13 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 06:31:13 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 06:31:13 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 06:31:13 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 06:31:13 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 06:31:13 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 06:31:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:31:15 INFO - Downloading/unpacking functools32 (from jsonschema) 06:31:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:31:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:31:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:31:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:31:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:31:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:31:16 INFO - Downloading functools32-3.2.3-2.tar.gz 06:31:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:31:16 INFO - warning: no files found matching '*.txt' 06:31:16 INFO - no previously-included directories found matching 'build' 06:31:16 INFO - no previously-included directories found matching 'dist' 06:31:16 INFO - no previously-included directories found matching '.git*' 06:31:16 INFO - Installing collected packages: jsonschema, functools32 06:31:16 INFO - Running setup.py install for functools32 06:31:16 INFO - warning: no files found matching '*.txt' 06:31:16 INFO - no previously-included directories found matching 'build' 06:31:16 INFO - no previously-included directories found matching 'dist' 06:31:16 INFO - no previously-included directories found matching '.git*' 06:31:16 INFO - Successfully installed jsonschema functools32 06:31:16 INFO - Cleaning up... 06:31:16 INFO - Return code: 0 06:31:16 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 06:31: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')]} 06:31:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:31:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:31: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')]} 06:31:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:31:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:31:16 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 0x10c3f8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c408420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe78bc8dba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c297e00>, '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 0x10c100db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c292b20>, '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.iWfFaRmlkL/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.re4Nil0g4X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 06:31:16 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 06:31:16 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 06:31:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iWfFaRmlkL/Render', 06:31:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:31:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:31:16 INFO - 'HOME': '/Users/cltbld', 06:31:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:31:16 INFO - 'LOGNAME': 'cltbld', 06:31:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:31:16 INFO - 'MOZ_NO_REMOTE': '1', 06:31:16 INFO - 'NO_EM_RESTART': '1', 06:31:16 INFO - 'PAGER': '/bin/cat', 06:31:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:31:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:31:16 INFO - 'PWD': '/builds/slave/test', 06:31:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:31:16 INFO - 'SHELL': '/bin/bash', 06:31:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.re4Nil0g4X/Listeners', 06:31:16 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 06:31:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:31:16 INFO - 'USER': 'cltbld', 06:31:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:31:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:31:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:31:16 INFO - 'XPC_FLAGS': '0x0', 06:31:16 INFO - 'XPC_SERVICE_NAME': '0', 06:31:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:31:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:31:16 INFO - Downloading/unpacking flake8 06:31:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:31:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:31:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:31:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:31:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:31:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:31:19 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 06:31:19 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 06:31:19 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 06:31:19 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 06:31:19 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 06:31:19 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 06:31:19 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 06:31:19 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 06:31:19 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 06:31:19 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 06:31:19 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 06:31:19 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 06:31:19 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 06:31:19 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 06:31:19 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 06:31:19 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 06:31:20 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 06:31:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:31:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:31:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:31:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:31:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:31:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:31:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 06:31:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:31:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:31:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:31:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:31:20 INFO - no previously-included directories found matching 'docs/_build' 06:31:20 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 06:31:20 INFO - Running setup.py install for pep8 06:31:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:31:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:31:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:31:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:31:20 INFO - no previously-included directories found matching 'docs/_build' 06:31:21 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 06:31:21 INFO - Successfully installed flake8 mccabe pyflakes pep8 06:31:21 INFO - Cleaning up... 06:31:21 INFO - Return code: 0 06:31:21 INFO - Running post-action listener: _resource_record_post_action 06:31:21 INFO - Running post-action listener: _start_resource_monitoring 06:31:21 INFO - Starting resource monitoring. 06:31:21 INFO - ##### 06:31:21 INFO - ##### Running install step. 06:31:21 INFO - ##### 06:31:21 INFO - Running pre-action listener: _resource_record_pre_action 06:31:21 INFO - Running main action method: install 06:31:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:31:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:31:21 INFO - Reading from file tmpfile_stdout 06:31:21 INFO - Detecting whether we're running mozinstall >=1.0... 06:31:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:31:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:31:21 INFO - Reading from file tmpfile_stdout 06:31:21 INFO - Output received: 06:31:21 INFO - Usage: mozinstall [options] installer 06:31:21 INFO - Options: 06:31:21 INFO - -h, --help show this help message and exit 06:31:21 INFO - -d DEST, --destination=DEST 06:31:21 INFO - Directory to install application into. [default: 06:31:21 INFO - "/builds/slave/test"] 06:31:21 INFO - --app=APP Application being installed. [default: firefox] 06:31:21 INFO - mkdir: /builds/slave/test/build/application 06:31:21 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'] 06:31:21 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 06:31:53 INFO - Reading from file tmpfile_stdout 06:31:53 INFO - Output received: 06:31:53 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 06:31:53 INFO - Running post-action listener: _resource_record_post_action 06:31:53 INFO - ##### 06:31:53 INFO - ##### Running run-tests step. 06:31:53 INFO - ##### 06:31:53 INFO - Running pre-action listener: _resource_record_pre_action 06:31:53 INFO - Running main action method: run_tests 06:31:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:31:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:31:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:31:53 INFO - 2016-05-23 06:31:53.596 screenresolution[1910:13155] starting screenresolution argv=screenresolution get 06:31:53 INFO - 2016-05-23 06:31:53.599 screenresolution[1910:13155] Display 0: 1600x1200x32@60 06:31:53 INFO - 2016-05-23 06:31:53.605 screenresolution[1911:13157] starting screenresolution argv=screenresolution list 06:31:53 INFO - Available Modes on Display 0 06:31:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:31:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:31:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:31:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:31:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:31:53 INFO - Intel Iris: 06:31:53 INFO - Chipset Model: Intel Iris 06:31:53 INFO - Type: GPU 06:31:53 INFO - Bus: Built-In 06:31:53 INFO - VRAM (Dynamic, Max): 1536 MB 06:31:53 INFO - Vendor: Intel (0x8086) 06:31:53 INFO - Device ID: 0x0a2e 06:31:53 INFO - Revision ID: 0x0009 06:31:53 INFO - Displays: 06:31:53 INFO - Display: 06:31:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:31:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:31:53 INFO - Display Serial Number: 1600x1200 60 06:31:53 INFO - Main Display: Yes 06:31:53 INFO - Mirror: Off 06:31:53 INFO - Online: Yes 06:31:53 INFO - Rotation: Supported 06:31:53 INFO - Return code: 0 06:31:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:31:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:31:53 INFO - Python 2.7.3 06:31:53 INFO - Return code: 0 06:31:53 INFO - grabbing minidump binary from tooltool 06:31:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:31:53 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 0x10c297e00>, '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 0x10c100db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c292b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:31:53 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 06:31:53 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 06:31:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:31:53 INFO - Return code: 0 06:31:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:31:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:31:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:31:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:31:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:31:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 06:31:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 06:31:55 INFO - Return code: 0 06:31:55 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/G3T_NDhkQ72_1OnxsCuyUw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0083', '--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 06:31:55 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/G3T_NDhkQ72_1OnxsCuyUw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0083 --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 06:31:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iWfFaRmlkL/Render', 06:31:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:31:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:31:55 INFO - 'HOME': '/Users/cltbld', 06:31:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:31:55 INFO - 'LOGNAME': 'cltbld', 06:31:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:31:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:31:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:31:55 INFO - 'MOZ_NO_REMOTE': '1', 06:31:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:31:55 INFO - 'NO_EM_RESTART': '1', 06:31:55 INFO - 'PAGER': '/bin/cat', 06:31:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:31:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:31:55 INFO - 'PWD': '/builds/slave/test', 06:31:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:31:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:31:55 INFO - 'SHELL': '/bin/bash', 06:31:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.re4Nil0g4X/Listeners', 06:31:55 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 06:31:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:31:55 INFO - 'USER': 'cltbld', 06:31:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:31:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:31:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:31:55 INFO - 'XPC_FLAGS': '0x0', 06:31:55 INFO - 'XPC_SERVICE_NAME': '0', 06:31:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:31:55 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/G3T_NDhkQ72_1OnxsCuyUw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0083', '--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 06:31:55 INFO - mozversion application_buildid: 20160523043629 06:31:55 INFO - mozversion application_changeset: fdf13c9edc864bebb7258816b4033af8b5dccc48 06:31:55 INFO - mozversion application_display_name: FirefoxDeveloperEdition 06:31:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:31:55 INFO - mozversion application_name: Firefox 06:31:55 INFO - mozversion application_remotingname: firefox-dev 06:31:55 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 06:31:55 INFO - mozversion application_vendor: Mozilla 06:31:55 INFO - mozversion application_version: 48.0a2 06:31:55 INFO - mozversion platform_buildid: 20160523043629 06:31:55 INFO - mozversion platform_changeset: fdf13c9edc864bebb7258816b4033af8b5dccc48 06:31:55 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 06:31:55 INFO - mozversion platform_version: 48.0a2 06:31:55 INFO - using testdate: 1464010315 06:31:55 INFO - actual date: 1464010315 06:31:55 INFO - starting webserver on 'localhost:51474' 06:31:55 INFO - SUITE-START | Running 2 tests 06:31:55 INFO - TEST-START | dromaeo_css 06:31:55 INFO - operating with platform_type : mac_ 06:31:55 INFO - Initialising browser for dromaeo_css test... 06:31:55 INFO - TEST-INFO | started process 1929 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/tmpQXyOQT/profile http://localhost:51474/getInfo.html) 06:31:57 INFO - PROCESS | 1929 | __metrics Screen width/height:1600/1200 06:31:57 INFO - PROCESS | 1929 | colorDepth:24 06:31:57 INFO - PROCESS | 1929 | Browser inner width/height: 1024/689 06:31:57 INFO - PROCESS | 1929 | __metrics 06:31:57 INFO - PROCESS | 1929 | 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" 06:31:57 ERROR - PROCESS | 1929 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 06:31:58 INFO - TEST-INFO | 1929: exit 0 06:31:58 INFO - Browser initialized. 06:31:58 INFO - Running cycle 1/1 for dromaeo_css test... 06:31:58 INFO - TEST-INFO | started process 1931 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/tmpQXyOQT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:32:10 INFO - PROCESS | 1931 | RSS: Main: 211202048 06:32:10 INFO - PROCESS | 1931 | 06:33:01 INFO - PROCESS | 1931 | 1464010381991 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] 06:35:12 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:51474/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51474/tests/dromaeo/cssquery-ext.html) 06:35:13 INFO - PROCESS | 1931 | RSS: Main: 351793152 06:35:13 INFO - PROCESS | 1931 | 06:38:17 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:51474/tests/dromaeo/cssquery-ext.html (next: http://localhost:51474/tests/dromaeo/cssquery-jquery.html) 06:38:17 INFO - PROCESS | 1931 | RSS: Main: 357421056 06:38:17 INFO - PROCESS | 1931 | 06:41:19 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:51474/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51474/tests/dromaeo/cssquery-mootools.html) 06:41:20 INFO - PROCESS | 1931 | RSS: Main: 359817216 06:41:20 INFO - PROCESS | 1931 | 06:44:22 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:51474/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51474/tests/dromaeo/cssquery-prototype.html) 06:44:23 INFO - PROCESS | 1931 | RSS: Main: 349487104 06:44:23 INFO - PROCESS | 1931 | 06:47:26 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:51474/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51474/tests/dromaeo/cssquery-yui.html) 06:47:27 INFO - PROCESS | 1931 | RSS: Main: 335728640 06:47:27 INFO - PROCESS | 1931 | 06:50:30 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:51474/tests/dromaeo/cssquery-yui.html (next: http://localhost:51474/tests/dromaeo/cssquery-dojo.html) 06:50:30 INFO - PROCESS | 1931 | RSS: Main: 369238016 06:50:30 INFO - PROCESS | 1931 | 06:50:30 INFO - PROCESS | 1931 | __start_tp_report 06:50:30 INFO - PROCESS | 1931 | _x_x_mozilla_page_load 06:50:30 INFO - PROCESS | 1931 | _x_x_mozilla_page_load_details 06:50:30 INFO - PROCESS | 1931 | |i|pagename|runs| 06:50:30 INFO - PROCESS | 1931 | |0;dojo.html;3362.277110421259;3442.156671615455;3443.2769118485126;3471.5486986691726;3505.684488396044;5661.575381846363;5669.68816715081;5686.402927692595;5701.942980570193;5714.171445140452;5676.886462270762;5736.942630573693;5740.483356497914;5749.195112684228;5752.683602401867;5076.949230507685;5129.12804823181;5133.178691409385;5246.5278124968745;5259.842204733845;5204.895902081956;5213.687178769267;5223.451537588556;5235.685858848462;5242.659227150223;5177.378714554259;5180.844574662766;5189.481051894818;5213.348331458567;5216.921746173811;5222.686638801665;5255.76349064293;5257.08001099806;5263.236830659533;5329.200619907045;5098.158803797406;5113.61647876407;5120.052790233819;5194.766235519411;5197.376314842205;5584.441555844383;5641.915371269434;5645.689487078209;5652.5195358394585;5669.121286200645;6217.440430361289;6258.968705156491;6268.561200715952;6269.310375858652;6290.559660823744;6075.75696972125;6205.410486003822;6272.2786879508985;6300.495960323163;6327.651979141146;5818.8254352369495;5824.912626310609;5852.209951656557;5874.647521148702;5894.882102357972;6259.624422534663;6282.120503129566;6289.622622642564;6292.937070629235;6299.6535190564955;5688.488036076773;5786.016377215799;5795.0727883539275;6172.506199504029;6215.627062376272;6166.982447896136;6211.65835879022;6267.9373206268265;6277.309495955441;6287.182666253358;6130.693465326805;6210.347913469111;6212.378762123842;6223.222097237845;6259.967105427553;5221.32122824038;6233.0338797486465;6251.906221406683;6273.96863015682;6283.59156654816;4852.927206091911;4878.707277563287;4912.968276661941;4927.359443272351;4955.132851750929;4438.911221775546;4441.089576636847;4450.910981780346;4458.8439404620685;4476.395686582269;5591.440855914376;5750.856228594319;5754.424557544212;5758.337791153987;5794.739236734357;6207.410296021871;6228.56400051992;6280.497560195174;6308;6314.936850631527;5729.9713501432225;5731.885362292731;5734.684592347461;5741.511971482387;5748.9425105748405;5282.445343238982;5370.677759334374;5372.382176049804;5377.3816011159215;5396.244525766396;6232.3144454110015;6260.843478912972;6315.557910946373;6319.557630965788;6322.2729386119345;6263.060540918898;6291.96182629861;6297.244330680348;6297.842553936188;6309.842253943688;5139.563137133386;6365.204349456318;6378.521610879296;6381.8723625528155;6387.904181437189;5722.685252311163;5850.444207800252;6124.472479235402;6171.537134715004;6217.906731399123;6233.470155036873;6263.3736626337;6264.968675156595;6271.96864015677;6279.654618995816;5963.373845746308;5979.073243647242;6084.969575152096;6091.42131497507;6128.816135515942;6134.723937422737;6139.723712432862;6157.0456579230295;6158.168647232566;6166.938330616636;6005.969970150121;6012.518998480198;6013.579049466495;6022.036474164288;6055.1220073090035;5979.133025711334;6085.604435711664;6124.510039196764;6144.446999770043;6164.921138800602;5928.79249226275;5941.821745347646;5955.255593050869;5965.403459654;5980.073088671263 06:50:30 INFO - PROCESS | 1931 | |1;ext.html;514.7220500929499;529.0529951387017;577.7573419163951;590.3329237961105;655.6754406568746;30868.382632347366;31734.523982140257;31802;38176;39339.623113191046;42280;42622.14755704884;42734.57265427345;42942.7852860736;42975;9515.857262141057;9711;9717.514124293792;9721.222302215841;9722.368046077017;8707.521086340248;9562;11612.593559225428;11811.291322520676;11852.229605075685;11513.539458421654;11608.419579021058;11617.419129043556;11621.12841536884;11629.70925726855;8713.259372953307;11489;11568.710782230428;11586.536538538448;11589.072874170046;11598.884011159866;11845.23006004607;11849.644510664693;11860.703482412922;11863.52545898163;38978.80510597429;39431.21137577261;39528;39573.60426395728;39578.80210598958;3291.308825146722;3309;3319.020888837799;3545.2554963457706;3554.9111272218142;27621.86189069042;28158;28374.574381384296;28510.28724281889;29222.269443263875;2725.127959053104;2756.6554180727408;2786.6656001279975;2798.552231642948;2866.498362786525;336888;338796;339607;491013.99999999645;492012;62754.0586891197;63267.73464530684;63306.36693633097;63329.36670633327;63355.6832215836;67035.66482167656;67238.66380668164;67255.66372168108;67329;67349;15768.842311576967;17425.56436089108;17592.472225833244;17613.559661008578;17618.2071806769;12086.456109475095;12146.271223726604;12170.513179472722;13440.193588384729;13547;15977.600559986093;16285.67428651444;16877.746833797504;17144.971301721936;17282.308707651555;6223.875522489525;7255.854882902312;7396.889046664304;7440.479166458296;7452.962735186289;931.725141083382;960.3709570231509;1007.6674697350004;1085.3566065508019;1131.8189089745765;24884.751152488243;28405.85797071043;29379.412411751644;29561.408771824445;30159.396812064075;712.9804379737026;1004.9296549241483;1014.8883622801486;1115.5928086248462;1155.289496959364;14246.287685615885;15025.624359391102;15404.306806193748;15968.882178247644;16140.75788863168;605791;611342;613076;613678;613883;60772;61206.693966529885;61274.69362653158;61363;61367.6931615348;1360.3334366160707;1490.2176357412445;1508.0800711565937;1532.9693406132037;1534.8642004916505;28262;28600.26581658649;28855.56716649294;29147.41705165927;29240.83036678509;47342.05315893732;47851.76074119607;47857.282140767216;47887.76056119697;47971.92238659753;356871.2156439201;357210;357278;357524;357654;21637.891810540845;22822.771772282063;26997.0551030713;27374.315642109108;27424;43323.133537329704;44044;44557.33164002478;44594.3310850344;44890.98373724537;36194.4570831443;36609.816950915076;37139.442908356934;37192;37834.243315134096;36029;36144.915652530464;36522;36878.26243475062;37001.259974800894;35331;35589.28821423609;36158.73444429432;37215.06962325964;37293.81353093217;31213.68786312108;32117.678213641295;32257.838710806296;32304.676953230166;32571.674283256863;24214;24366.75633243645;24641.50716985686;24652.260432187064;24728.876355618107 06:50:30 INFO - PROCESS | 1931 | |2;jquery.html;333.57635802530785;399.11794933197643;401.4324064528019;409.4738261334184;411.3315861724697;19777.406677799652;21522.139114435402;21898;22451;22456.663150052762;20901.895490522566;21538.784612153875;21579.676304855402;21603.135874565;21857.453563660878;4704.929426058612;5995.790147344844;6061.272647282332;6163.845903852417;6189.102580125901;5947.4052594740615;6170.981788004974;6199.628022318675;6209.254889413254;6210.09953556734;5219.295395121648;5647.548196144298;5967.283925928873;6175.351588083254;6177.536684748639;4818.614510839125;5870;5910.408959104098;5917.704114794265;5925.940740592582;5678.4037676044245;5740.712964351766;5741.8564535886935;5749.281339832521;5749.453801888814;13328.933355333258;13494.325283735872;13570.525031623945;13577.185368877801;18600;2210.370044537315;2232.910683572655;2326.7673232676766;2364.7044119485063;2736.1244401791437;7955.681772729084;8343.916560834436;9822.901770982342;10263.846042309371;10282;1705.2837808120619;1770.9911450442664;1777.9466616001412;1793.9820601793945;1853.9814601853943;87005;121784.39107804492;122911;123577.38211308976;123655;11693.532258709556;11755.177137600285;11761.411929403495;11803.291802491705;11830.763384732258;15168.393264269527;15246.3901443941;15292.617684557976;15318.617034574225;15329.693406131815;8629;8651.134886511298;8660.393772435995;8787.736367908972;8793.164649358429;7023.403010744042;7297.963510182415;8503.937007874016;8707.956460217785;8777.736667899973;4188.769617671057;4588.220002599566;4660.277656963176;4665.370175026396;4707;1634.4606279927596;1771.409337885262;1940.4663717477524;1950.4246247357064;1975.8024197580128;914.8124634449922;949.6866034208695;984.2765567308023;987.5605355616825;1040.9635662751768;20889.37331880046;21234.681479777817;21650.783492165192;22013.559728805656;22615.54768904613;967.583938906263;973.3089506450499;1005.7435353984833;1023.2019025160387;1028.0336483705294;8256;8982.101789820965;11143.721406964729;11532.538698452136;11564.306141631529;631667;636753;641766;642170.7891460606;643963;128360;130875;135932;138142;138451.61548384588;1054.7310435838813;1059.4596755654673;1078.8435676826973;1078.8759292681443;1082.0910435234284;18405.81594184041;18729.90635046816;18873.43379698611;18963.14665840013;19290.4212873614;55250;55782.163267550204;55877.882442351736;56292.15561766495;58447;77282.61358693171;79110;79367.20632793829;80493;81427;7879.84240315179;8181.427300089174;8267;8351.373646976379;8363.874541881998;11324.6036388726;11337.26307789991;12263.75472490563;12582.496700131893;12638.2417054977;10750.623728169476;11010.339379637247;11097.38964356968;11146.442677866236;11151.10791136691;7982.8802567960365;10169.644062457779;10190.796184076129;10529.315594486334;10550.31422957536;10905;11203.775924481628;11354.886451135713;11371.260868043551;11389.259698119597;8114.715984940499;8127.918720812716;8138.755837324889;8201.71294004707;8523.232909038215;8311;10411.375317480975;10708.357498550113;11210.383428911487;11237.662870113909 06:50:30 INFO - PROCESS | 1931 | |3;mootools.html;1409.1615488784182;1545.1501674079257;1565.9608509787254;1784.607386374997;1933.2556965568244;7807.4144439167;8456.788580285496;8626.56867156642;8636.91363086369;8642.308615310776;8349.123342049088;8607.870881936777;8613.44012639178;8614.913850861489;8616.396852220347;2109.5780843831217;2357.033616217351;2384.213209640823;2409.4096946248105;2416.1060407649193;2429.927102186937;2432.2460037388378;2437.8415402998835;2440.7803297703203;2441.292025312657;2342.8008619267384;2386.5107652931106;2404.915827946022;2407.8675672837985;2412.49337639096;2014.5870096630156;2092.884891330984;2327.359976006595;2379.202967006055;2396.2930935374106;2359.725748096026;2390.7728765767224;2395.137750409851;2398;2401.267613377919;8474.1949514796;8549.829003419958;8559.95720021396;8562.315014798802;8602.87095693565;5135.666181698178;5264.2893209416625;5277.340332458442;5284.180951952453;5307.946920530784;3284.720798732111;3356.9832150839334;3470.219200679827;3477.2871561329866;3491.790492570454;957.2007373842849;989.6931951095183;1002.3735165521549;1024.4212020208558;1029.3978022856638;11679.357635330054;11707;11711.238769480042;11713.180077394585;11726.413679316085;4119.237940980868;4532.3654688342995;4688.203005489075;4746.572808447257;4793.281007848851;9923.354981926152;9941.353812002342;9963.103320701171;9975.102240798364;10000.549975251131;5230.712310822942;5262.236975638511;5271.998320319204;5289.127293996525;5290;4322.243607368698;4438.667099967515;4705.670603057822;4788.880277993078;4848.248521479177;3416.3850506908993;3907.5310962684093;3942.3495123304333;3963.920721585552;3973.4635824163947;1122.910167186623;1303.543759684103;1315.4014923209916;1322.3520474967388;1331.8601546837442;826.8759686047141;849.0999540487103;892.8749975003504;908.8000639859221;936.0452338614575;4677.789499472532;5626.24045753818;5632.971835140798;5956.52347812174;5973.223480947449;825.3809642767924;898.7034278688018;939.0937745076035;945.9594446109224;946.5882341181608;3895.5130608673917;3924.7448915820382;3961.544422391462;3962.049108214008;4055.229506393776;8696;10281.383117013003;10320.22598305115;10454.63408780689;10569.524371403306;10334.689959301233;10336;10347.379157250589;10348.223883208639;10468.52891619879;8097.838043239102;8152.470089444048;8163.795905102301;8203.097659257477;8231.218034286732;5637.915431268452;5759.481646651821;5775.537956963517;5876.6180198286975;5929.0217114175675;9381.624735010797;9441.197498212729;9603.807923841345;9608.183304418922;9645.324827262024;7936;9663;9762.072603102693;9789.951050244703;9882.802343953224;6720.1599800025;6879.277675844164;7241.601711905685;7379.889301660372;7519.060117485315;7992.720254791055;8036;8077.838443230985;8114.5942702865805;8127.390445716476;7111.324424179695;7158.713651453883;7185.604791736505;7294.635268236673;7320.084989376328;6144.109104179958;7009.1589009319205;7057.611831349325;7132.786016419515;7205.963970180115;6659.467242620675;6788.422984046411;6814.250432452525;6817.5909445433435;6820.147481564804;4662.53374662531;4665.4168228971375;4717.127331443694;4737.715737055788;4821.879453013562;4051.5745846685672;4683.8360657376825;4722.598579120813;4793.760311984317;4980.701157930535 06:50:30 INFO - PROCESS | 1931 | |4;prototype.html;312.13227228305306;317.9650238473768;324.6266793187834;325.05247204399177;330.8726140435933;6056.212692349999;6547.443467305273;6695.933040669592;6739.898901516482;6764.56030358027;5901.911471327923;6446.45205157561;6587.209534855824;6645.900311495319;6653.866922661556;4877.926831097537;4917.164082106033;4918.0901533216465;4926.729029903363;4935.901281974358;4184.895377615553;4681.110588988098;4753.1919573672385;4873.8781530461665;4940.555350018498;4869.829555965542;4872.9756351218375;4882.536159064901;4905.043516514281;4949.727764972925;4526.117407105616;4894.535019173173;4905.73018483985;4908.803647854082;4938.62960277977;4184.8744537663615;4187.288161012635;4189.916201675949;4202.537720850704;4204.3903633973205;6342.809715708536;6363.681815909233;6375.649339286338;6380.0748891410485;6386.936130638681;5495.945040549584;5747.3103227612955;5854.882902341972;5883.323417806964;5897.056470964668;5887.764489420418;7112.324329188718;7145.321194486515;7149.821254468628;7237.0230018947295;4615.053913947632;4621.976890115561;4635.096156249533;4636.652251081183;4638.605718513931;7521.586312752796;7637.770866874002;7641.465097443125;7642.770716878447;7653;3838.4242363645676;4666.836660716858;4684.765761711901;4697.577218050392;4703.0593881223895;4576.654469576282;4720.787564559534;4738.905221895543;4755.595774359149;4758.452777930513;4641.721496710208;5748.166515855261;5752.482276595127;5757.798477053283;5763.3084029915835;5444.891102178013;7262.273772622695;7271.963640181871;7273.23631018746;7284.927150728425;1566.69449457357;1605.494269305165;1621.4162901355503;1629.9511014669579;1663.9251233694513;1030.9381437113798;1034.446571084466;1037.3309215556076;1042.6715584590982;1046.6807623674852;6574.093512881211;6592.17597800275;6606.966965165239;6633.568818026813;6640.269570347258;7401.1488678802625;7430.2941220585035;7452.329290363893;7460.14208366034;7462.253774622494;6611.305812889674;6622.238442579069;6627.635480048547;6642.169728783902;6671.999200120021;3253.4794432890626;3943.191645712622;3975.5229372475487;3979.7015223857748;3981.900452488653;7506.62466876654;7699.230076992256;8011.559364234907;8028.678852845821;8051.194880511902;6808.523403361717;7865.370770338359;7878.724244651525;7891.210878912063;7900.209979002168;6480.61116333031;7908.960455197802;8011.7195898144455;8025.759227223192;8220.3834712398;6148.262208534826;7296.817579560554;7576.962115189389;7606.619669016638;7655.157932627518;7458.21688722698;7481.625918704151;7496.58768767723;7497.625118743932;7502.399808015237;7971.402144839044;8010.559419231999;8014.559199244098;8016.559089250147;8022.799430014296;4696.365990591226;4723.858284251478;4731.077439899186;4742.004179122285;4759.14335419614;7188.928110718826;7568.735094271674;7579.62101894892;7601.619919004138;7605.885911711218;7233.276672332725;7275.163356214103;7295.4528410368375;7296.7446139384865;7323.450741194538;7298.985557943004;7312.659948816327;7320.15818180916;7323.267673232847;7325.047743793273;7158.749443769443;7163.283671632795;7166.283371662792;7186.993820864978;7189.921511773276;6964.268751781193;7230.927690723236;7231.132264128128;7244.239354867663;7295.197528271779;5723.370429252695;7082.079329687108;7116.786496404909;7126.429885609035;7146.749863754743 06:50:30 INFO - PROCESS | 1931 | |5;yui.html;684.5721424109933;751.5378042503859;752.7290175536806;763.7021561590979;771.560210679912;14510.854891451083;14723.558293251193;14732;14743.926280368583;14747.705045899089;13481.797773033413;15289.159096249701;15729.764053539206;15766.605834854134;15787.368505259776;729.7701224114413;738.0073800737999;904.2223687628667;906.9047749986255;929.7815013471828;909.5406819556148;912.1380295620642;913.2602591900549;914.6524320758102;914.7027216154743;975.8975307592707;977.6724797192913;978.188103873783;978.7357413498352;981.0973904008293;766.2108028730389;939.1829108675496;958.3626888119393;962.1533050915219;971.2084651009404;711.6299524247414;713.1086142322098;713.7180813578659;713.8857782754734;714.7426926306525;15238.247601525856;15281.770773438408;15297;15309.693806123927;15347.769783453257;12379.381030948507;12480.188787728768;12536.623901282976;12637.368131593475;12778.297193654345;9576.425414475154;9821.901780982242;9831.31180817343;9916.95041524795;10031.247656425725;3390.135515443546;3413.76103672743;3425.0581090200394;3464.8094354810473;3497.3005398920063;82417.76373354404;82547.58726206391;82577.3484530312;82611.17388826035;83085.58457207735;9298.814023719488;9441.716748497556;9789.608415663437;9862.112409883144;9909.65316213929;21973.890130549244;22001;22181;22273.888630557067;22282.331530054125;7597.240275972469;7640.235976402427;7741.61291935401;7897.368210543143;7965.362770978424;3903.2193561287854;3951.328274193394;4014.959850401517;4119.485064366954;4222.852200172979;3386.8306584670668;3440.449528075496;3444.9655503444646;3448.0173150651945;3451;511.79528188724066;563.3380777586401;588.6144575303183;609.3236507476697;622.5828694774511;509.4777852701011;515.5488947171225;585.0317724166512;605.9909101363484;613.3314686991201;6578.638174900427;7882.842343153105;7893.368530517545;7951.403644726669;8170.223828736261;546.9671819690832;577.4687287695247;578.288704892984;610.1640752169557;619.9132121502993;3701.407774756026;3794.6015668354976;3851.345271303885;3861.687026411034;3870.8838734838;70687.93968090483;91160.54419727859;93351.53324233336;93700.5944910827;93946;71633.92549111767;72482.27517724861;73152.53694926071;75376;79200.6039969808;57534;67504.97485075553;68450.65774671195;72470;72562.63718681478;8170.959145204236;8231.835363292581;8266.834663306821;8370.707025254087;8474.74575762728;50179.49820501748;50564;50747.746261268454;50811.23783143182;50870;53160.73419632877;65225.021624676614;67494.97515074555;68793.65603171953;69248.6537567309;11192.104631629287;11652.65042048706;11857.407129643656;11977.341246231192;12023.398830058637;11845.23006004607;12107.33409662477;12113.273203607812;12308.446119924803;12509.499620015462;10368.326058806153;11470.4264786762;11587.478563464832;11629.709257268636;11722.70693232676;11370.658880233606;11458.427078645867;11527.423628818358;11587.015103716278;12391.132620716824;10849.224913769041;11128.276662016942;11230.494627741607;11357.716057098309;11514.021308188898;9470.19503342196;9566.569504372459;9744.220462363135;9754.707358779247;9767.609295628095;9614.326997110134;9628.18160456341;9743.415395076318;9746.463944483121;9862.96438873908 06:50:30 INFO - PROCESS | 1931 | __end_tp_report 06:50:30 INFO - PROCESS | 1931 | __start_cc_report 06:50:30 INFO - PROCESS | 1931 | _x_x_mozilla_cycle_collect,1424 06:50:30 INFO - PROCESS | 1931 | __end_cc_report 06:50:30 INFO - PROCESS | 1931 | __startTimestamp1464011430312__endTimestamp 06:50:30 INFO - PROCESS | 1931 | 06:50:30 INFO - PROCESS | 1931 | ------- Summary: start ------- 06:50:30 INFO - PROCESS | 1931 | Number of tests: 6 06:50:30 INFO - PROCESS | 1931 | 06:50:30 INFO - PROCESS | 1931 | [#0] dojo.html Cycles:165 Average:5736.16 Median:5928.79 stddev:632.87 (10.7%) stddev-sans-first:606.80 06:50:30 INFO - PROCESS | 1931 | Values: 3362.3 3442.2 3443.3 3471.5 3505.7 5661.6 5669.7 5686.4 5701.9 5714.2 5676.9 5736.9 5740.5 5749.2 5752.7 5076.9 5129.1 5133.2 5246.5 5259.8 5204.9 5213.7 5223.5 5235.7 5242.7 5177.4 5180.8 5189.5 5213.3 5216.9 5222.7 5255.8 5257.1 5263.2 5329.2 5098.2 5113.6 5120.1 5194.8 5197.4 5584.4 5641.9 5645.7 5652.5 5669.1 6217.4 6259.0 6268.6 6269.3 6290.6 6075.8 6205.4 6272.3 6300.5 6327.7 5818.8 5824.9 5852.2 5874.6 5894.9 6259.6 6282.1 6289.6 6292.9 6299.7 5688.5 5786.0 5795.1 6172.5 6215.6 6167.0 6211.7 6267.9 6277.3 6287.2 6130.7 6210.3 6212.4 6223.2 6260.0 5221.3 6233.0 6251.9 6274.0 6283.6 4852.9 4878.7 4913.0 4927.4 4955.1 4438.9 4441.1 4450.9 4458.8 4476.4 5591.4 5750.9 5754.4 5758.3 5794.7 6207.4 6228.6 6280.5 6308.0 6314.9 5730.0 5731.9 5734.7 5741.5 5748.9 5282.4 5370.7 5372.4 5377.4 5396.2 6232.3 6260.8 6315.6 6319.6 6322.3 6263.1 6292.0 6297.2 6297.8 6309.8 5139.6 6365.2 6378.5 6381.9 6387.9 5722.7 5850.4 6124.5 6171.5 6217.9 6233.5 6263.4 6265.0 6272.0 6279.7 5963.4 5979.1 6085.0 6091.4 6128.8 6134.7 6139.7 6157.0 6158.2 6166.9 6006.0 6012.5 6013.6 6022.0 6055.1 5979.1 6085.6 6124.5 6144.4 6164.9 5928.8 5941.8 5955.3 5965.4 5980.1 06:50:30 INFO - PROCESS | 1931 | 06:50:30 INFO - PROCESS | 1931 | [#1] ext.html Cycles:180 Average:60563.04 Median:337842.00 stddev:125790.29 (37.2%) stddev-sans-first:126062.36 06:50:30 INFO - PROCESS | 1931 | Values: 514.7 529.1 577.8 590.3 655.7 30868.4 31734.5 31802.0 38176.0 39339.6 42280.0 42622.1 42734.6 42942.8 42975.0 9515.9 9711.0 9717.5 9721.2 9722.4 8707.5 9562.0 11612.6 11811.3 11852.2 11513.5 11608.4 11617.4 11621.1 11629.7 8713.3 11489.0 11568.7 11586.5 11589.1 11598.9 11845.2 11849.6 11860.7 11863.5 38978.8 39431.2 39528.0 39573.6 39578.8 3291.3 3309.0 3319.0 3545.3 3554.9 27621.9 28158.0 28374.6 28510.3 29222.3 2725.1 2756.7 2786.7 2798.6 2866.5 336888.0 338796.0 339607.0 491014.0 492012.0 62754.1 63267.7 63306.4 63329.4 63355.7 67035.7 67238.7 67255.7 67329.0 67349.0 15768.8 17425.6 17592.5 17613.6 17618.2 12086.5 12146.3 12170.5 13440.2 13547.0 15977.6 16285.7 16877.7 17145.0 17282.3 6223.9 7255.9 7396.9 7440.5 7453.0 931.7 960.4 1007.7 1085.4 1131.8 24884.8 28405.9 29379.4 29561.4 30159.4 713.0 1004.9 1014.9 1115.6 1155.3 14246.3 15025.6 15404.3 15968.9 16140.8 605791.0 611342.0 613076.0 613678.0 613883.0 60772.0 61206.7 61274.7 61363.0 61367.7 1360.3 1490.2 1508.1 1533.0 1534.9 28262.0 28600.3 28855.6 29147.4 29240.8 47342.1 47851.8 47857.3 47887.8 47971.9 356871.2 357210.0 357278.0 357524.0 357654.0 21637.9 22822.8 26997.1 27374.3 27424.0 43323.1 44044.0 44557.3 44594.3 44891.0 36194.5 36609.8 37139.4 37192.0 37834.2 36029.0 36144.9 36522.0 36878.3 37001.3 35331.0 35589.3 36158.7 37215.1 37293.8 31213.7 32117.7 32257.8 32304.7 32571.7 24214.0 24366.8 24641.5 24652.3 24728.9 06:50:30 INFO - PROCESS | 1931 | 06:50:30 INFO - PROCESS | 1931 | [#2] jquery.html Cycles:180 Average:36141.69 Median:22453.83 stddev:106553.67 (474.5%) stddev-sans-first:106818.66 06:50:30 INFO - PROCESS | 1931 | Values: 333.6 399.1 401.4 409.5 411.3 19777.4 21522.1 21898.0 22451.0 22456.7 20901.9 21538.8 21579.7 21603.1 21857.5 4704.9 5995.8 6061.3 6163.8 6189.1 5947.4 6171.0 6199.6 6209.3 6210.1 5219.3 5647.5 5967.3 6175.4 6177.5 4818.6 5870.0 5910.4 5917.7 5925.9 5678.4 5740.7 5741.9 5749.3 5749.5 13328.9 13494.3 13570.5 13577.2 18600.0 2210.4 2232.9 2326.8 2364.7 2736.1 7955.7 8343.9 9822.9 10263.8 10282.0 1705.3 1771.0 1777.9 1794.0 1854.0 87005.0 121784.4 122911.0 123577.4 123655.0 11693.5 11755.2 11761.4 11803.3 11830.8 15168.4 15246.4 15292.6 15318.6 15329.7 8629.0 8651.1 8660.4 8787.7 8793.2 7023.4 7298.0 8503.9 8708.0 8777.7 4188.8 4588.2 4660.3 4665.4 4707.0 1634.5 1771.4 1940.5 1950.4 1975.8 914.8 949.7 984.3 987.6 1041.0 20889.4 21234.7 21650.8 22013.6 22615.5 967.6 973.3 1005.7 1023.2 1028.0 8256.0 8982.1 11143.7 11532.5 11564.3 631667.0 636753.0 641766.0 642170.8 643963.0 128360.0 130875.0 135932.0 138142.0 138451.6 1054.7 1059.5 1078.8 1078.9 1082.1 18405.8 18729.9 18873.4 18963.1 19290.4 55250.0 55782.2 55877.9 56292.2 58447.0 77282.6 79110.0 79367.2 80493.0 81427.0 7879.8 8181.4 8267.0 8351.4 8363.9 11324.6 11337.3 12263.8 12582.5 12638.2 10750.6 11010.3 11097.4 11146.4 11151.1 7982.9 10169.6 10190.8 10529.3 10550.3 10905.0 11203.8 11354.9 11371.3 11389.3 8114.7 8127.9 8138.8 8201.7 8523.2 8311.0 10411.4 10708.4 11210.4 11237.7 06:50:30 INFO - PROCESS | 1931 | 06:50:30 INFO - PROCESS | 1931 | [#3] mootools.html Cycles:180 Average:5557.37 Median:5246.47 stddev:3099.55 (59.1%) stddev-sans-first:3092.56 06:50:30 INFO - PROCESS | 1931 | Values: 1409.2 1545.2 1566.0 1784.6 1933.3 7807.4 8456.8 8626.6 8636.9 8642.3 8349.1 8607.9 8613.4 8614.9 8616.4 2109.6 2357.0 2384.2 2409.4 2416.1 2429.9 2432.2 2437.8 2440.8 2441.3 2342.8 2386.5 2404.9 2407.9 2412.5 2014.6 2092.9 2327.4 2379.2 2396.3 2359.7 2390.8 2395.1 2398.0 2401.3 8474.2 8549.8 8560.0 8562.3 8602.9 5135.7 5264.3 5277.3 5284.2 5307.9 3284.7 3357.0 3470.2 3477.3 3491.8 957.2 989.7 1002.4 1024.4 1029.4 11679.4 11707.0 11711.2 11713.2 11726.4 4119.2 4532.4 4688.2 4746.6 4793.3 9923.4 9941.4 9963.1 9975.1 10000.5 5230.7 5262.2 5272.0 5289.1 5290.0 4322.2 4438.7 4705.7 4788.9 4848.2 3416.4 3907.5 3942.3 3963.9 3973.5 1122.9 1303.5 1315.4 1322.4 1331.9 826.9 849.1 892.9 908.8 936.0 4677.8 5626.2 5633.0 5956.5 5973.2 825.4 898.7 939.1 946.0 946.6 3895.5 3924.7 3961.5 3962.0 4055.2 8696.0 10281.4 10320.2 10454.6 10569.5 10334.7 10336.0 10347.4 10348.2 10468.5 8097.8 8152.5 8163.8 8203.1 8231.2 5637.9 5759.5 5775.5 5876.6 5929.0 9381.6 9441.2 9603.8 9608.2 9645.3 7936.0 9663.0 9762.1 9790.0 9882.8 6720.2 6879.3 7241.6 7379.9 7519.1 7992.7 8036.0 8077.8 8114.6 8127.4 7111.3 7158.7 7185.6 7294.6 7320.1 6144.1 7009.2 7057.6 7132.8 7206.0 6659.5 6788.4 6814.3 6817.6 6820.1 4662.5 4665.4 4717.1 4737.7 4821.9 4051.6 4683.8 4722.6 4793.8 4980.7 06:50:30 INFO - PROCESS | 1931 | 06:50:30 INFO - PROCESS | 1931 | [#4] prototype.html Cycles:180 Average:5862.46 Median:6589.69 stddev:1929.12 (29.3%) stddev-sans-first:1889.01 06:50:30 INFO - PROCESS | 1931 | Values: 312.1 318.0 324.6 325.1 330.9 6056.2 6547.4 6695.9 6739.9 6764.6 5901.9 6446.5 6587.2 6645.9 6653.9 4877.9 4917.2 4918.1 4926.7 4935.9 4184.9 4681.1 4753.2 4873.9 4940.6 4869.8 4873.0 4882.5 4905.0 4949.7 4526.1 4894.5 4905.7 4908.8 4938.6 4184.9 4187.3 4189.9 4202.5 4204.4 6342.8 6363.7 6375.6 6380.1 6386.9 5495.9 5747.3 5854.9 5883.3 5897.1 5887.8 7112.3 7145.3 7149.8 7237.0 4615.1 4622.0 4635.1 4636.7 4638.6 7521.6 7637.8 7641.5 7642.8 7653.0 3838.4 4666.8 4684.8 4697.6 4703.1 4576.7 4720.8 4738.9 4755.6 4758.5 4641.7 5748.2 5752.5 5757.8 5763.3 5444.9 7262.3 7272.0 7273.2 7284.9 1566.7 1605.5 1621.4 1630.0 1663.9 1030.9 1034.4 1037.3 1042.7 1046.7 6574.1 6592.2 6607.0 6633.6 6640.3 7401.1 7430.3 7452.3 7460.1 7462.3 6611.3 6622.2 6627.6 6642.2 6672.0 3253.5 3943.2 3975.5 3979.7 3981.9 7506.6 7699.2 8011.6 8028.7 8051.2 6808.5 7865.4 7878.7 7891.2 7900.2 6480.6 7909.0 8011.7 8025.8 8220.4 6148.3 7296.8 7577.0 7606.6 7655.2 7458.2 7481.6 7496.6 7497.6 7502.4 7971.4 8010.6 8014.6 8016.6 8022.8 4696.4 4723.9 4731.1 4742.0 4759.1 7188.9 7568.7 7579.6 7601.6 7605.9 7233.3 7275.2 7295.5 7296.7 7323.5 7299.0 7312.7 7320.2 7323.3 7325.0 7158.7 7163.3 7166.3 7187.0 7189.9 6964.3 7230.9 7231.1 7244.2 7295.2 5723.4 7082.1 7116.8 7126.4 7146.7 06:50:30 INFO - PROCESS | 1931 | 06:50:30 INFO - PROCESS | 1931 | [#5] yui.html Cycles:180 Average:18082.87 Median:616.62 stddev:25107.43 (4071.8%) stddev-sans-first:25143.88 06:50:30 INFO - PROCESS | 1931 | Values: 684.6 751.5 752.7 763.7 771.6 14510.9 14723.6 14732.0 14743.9 14747.7 13481.8 15289.2 15729.8 15766.6 15787.4 729.8 738.0 904.2 906.9 929.8 909.5 912.1 913.3 914.7 914.7 975.9 977.7 978.2 978.7 981.1 766.2 939.2 958.4 962.2 971.2 711.6 713.1 713.7 713.9 714.7 15238.2 15281.8 15297.0 15309.7 15347.8 12379.4 12480.2 12536.6 12637.4 12778.3 9576.4 9821.9 9831.3 9917.0 10031.2 3390.1 3413.8 3425.1 3464.8 3497.3 82417.8 82547.6 82577.3 82611.2 83085.6 9298.8 9441.7 9789.6 9862.1 9909.7 21973.9 22001.0 22181.0 22273.9 22282.3 7597.2 7640.2 7741.6 7897.4 7965.4 3903.2 3951.3 4015.0 4119.5 4222.9 3386.8 3440.4 3445.0 3448.0 3451.0 511.8 563.3 588.6 609.3 622.6 509.5 515.5 585.0 606.0 613.3 6578.6 7882.8 7893.4 7951.4 8170.2 547.0 577.5 578.3 610.2 619.9 3701.4 3794.6 3851.3 3861.7 3870.9 70687.9 91160.5 93351.5 93700.6 93946.0 71633.9 72482.3 73152.5 75376.0 79200.6 57534.0 67505.0 68450.7 72470.0 72562.6 8171.0 8231.8 8266.8 8370.7 8474.7 50179.5 50564.0 50747.7 50811.2 50870.0 53160.7 65225.0 67495.0 68793.7 69248.7 11192.1 11652.7 11857.4 11977.3 12023.4 11845.2 12107.3 12113.3 12308.4 12509.5 10368.3 11470.4 11587.5 11629.7 11722.7 11370.7 11458.4 11527.4 11587.0 12391.1 10849.2 11128.3 11230.5 11357.7 11514.0 9470.2 9566.6 9744.2 9754.7 9767.6 9614.3 9628.2 9743.4 9746.5 9863.0 06:50:30 INFO - PROCESS | 1931 | -------- Summary: end -------- 06:50:30 INFO - PROCESS | 1931 | 06:50:30 INFO - TEST-INFO | 1931: exit 0 06:50:30 INFO - TEST-OK | dromaeo_css | took 1115661ms 06:50:30 INFO - TEST-START | kraken 06:50:30 INFO - operating with platform_type : mac_ 06:50:30 INFO - Initialising browser for kraken test... 06:50:30 INFO - TEST-INFO | started process 1936 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/tmpUEuSCD/profile http://localhost:51474/getInfo.html) 06:50:33 INFO - PROCESS | 1936 | __metrics Screen width/height:1600/1200 06:50:33 INFO - PROCESS | 1936 | colorDepth:24 06:50:33 INFO - PROCESS | 1936 | Browser inner width/height: 1024/689 06:50:33 INFO - PROCESS | 1936 | __metrics 06:50:33 INFO - PROCESS | 1936 | 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" 06:50:33 ERROR - PROCESS | 1936 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 06:50:33 INFO - TEST-INFO | 1936: exit 0 06:50:33 INFO - Browser initialized. 06:50:33 INFO - Running cycle 1/1 for kraken test... 06:50:33 INFO - TEST-INFO | started process 1938 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/tmpUEuSCD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:50:45 INFO - PROCESS | 1938 | RSS: Main: 211181568 06:50:45 INFO - PROCESS | 1938 | 06:50:48 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51474/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51474/tests/kraken/driver.html?testName=audio-beat-detection) 06:50:49 INFO - PROCESS | 1938 | RSS: Main: 271626240 06:50:49 INFO - PROCESS | 1938 | 06:50:52 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51474/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51474/tests/kraken/driver.html?testName=audio-dft) 06:50:52 INFO - PROCESS | 1938 | RSS: Main: 285790208 06:50:52 INFO - PROCESS | 1938 | 06:50:55 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51474/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51474/tests/kraken/driver.html?testName=audio-fft) 06:50:56 INFO - PROCESS | 1938 | RSS: Main: 372363264 06:50:56 INFO - PROCESS | 1938 | 06:50:58 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51474/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51474/tests/kraken/driver.html?testName=audio-oscillator) 06:50:59 INFO - PROCESS | 1938 | RSS: Main: 278085632 06:50:59 INFO - PROCESS | 1938 | 06:51:01 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51474/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51474/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:51:02 INFO - PROCESS | 1938 | RSS: Main: 280436736 06:51:02 INFO - PROCESS | 1938 | 06:51:04 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51474/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51474/tests/kraken/driver.html?testName=imaging-darkroom) 06:51:05 INFO - PROCESS | 1938 | RSS: Main: 319213568 06:51:05 INFO - PROCESS | 1938 | 06:51:08 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51474/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51474/tests/kraken/driver.html?testName=imaging-desaturate) 06:51:09 INFO - PROCESS | 1938 | RSS: Main: 319844352 06:51:09 INFO - PROCESS | 1938 | 06:51:11 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51474/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51474/tests/kraken/driver.html?testName=json-parse-financial) 06:51:12 INFO - PROCESS | 1938 | RSS: Main: 317034496 06:51:12 INFO - PROCESS | 1938 | 06:51:14 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51474/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51474/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:51:14 INFO - PROCESS | 1938 | RSS: Main: 309305344 06:51:14 INFO - PROCESS | 1938 | 06:51:16 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51474/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51474/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:51:17 INFO - PROCESS | 1938 | RSS: Main: 316825600 06:51:17 INFO - PROCESS | 1938 | 06:51:19 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51474/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51474/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:51:19 INFO - PROCESS | 1938 | RSS: Main: 318783488 06:51:19 INFO - PROCESS | 1938 | 06:51:22 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51474/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51474/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:51:23 INFO - PROCESS | 1938 | RSS: Main: 320372736 06:51:23 INFO - PROCESS | 1938 | 06:51:26 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51474/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51474/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:51:26 INFO - PROCESS | 1938 | RSS: Main: 317304832 06:51:26 INFO - PROCESS | 1938 | 06:51:28 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51474/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51474/tests/kraken/driver.html?testName=ai-astar) 06:51:28 INFO - PROCESS | 1938 | RSS: Main: 381399040 06:51:28 INFO - PROCESS | 1938 | 06:51:28 INFO - PROCESS | 1938 | __start_tp_report 06:51:28 INFO - PROCESS | 1938 | _x_x_mozilla_page_load 06:51:28 INFO - PROCESS | 1938 | _x_x_mozilla_page_load_details 06:51:28 INFO - PROCESS | 1938 | |i|pagename|runs| 06:51:28 INFO - PROCESS | 1938 | |0;ai-astar;114;114;119;113;113;113;114;114;115;136 06:51:28 INFO - PROCESS | 1938 | |1;audio-beat-detection;157;122;143;163;120;143;142;121;169;121 06:51:28 INFO - PROCESS | 1938 | |2;audio-dft;184;185;187;183;185;186;200;266;196;185 06:51:28 INFO - PROCESS | 1938 | |3;audio-fft;72;76;77;162;83;82;82;90;137;57 06:51:28 INFO - PROCESS | 1938 | |4;audio-oscillator;91;94;148;77;81;80;127;80;77;131 06:51:28 INFO - PROCESS | 1938 | |5;imaging-gaussian-blur;91;88;91;86;135;88;91;89;91;87 06:51:28 INFO - PROCESS | 1938 | |6;imaging-darkroom;96;95;95;96;100;110;96;96;95;94 06:51:28 INFO - PROCESS | 1938 | |7;imaging-desaturate;86;86;84;85;85;84;87;85;86;86 06:51:28 INFO - PROCESS | 1938 | |8;json-parse-financial;59;64;64;64;64;65;64;80;61;58 06:51:28 INFO - PROCESS | 1938 | |9;json-stringify-tinderbox;64;65;69;77;78;72;68;68;67;67 06:51:28 INFO - PROCESS | 1938 | |10;stanford-crypto-aes;80;81;81;81;80;79;83;78;80;85 06:51:28 INFO - PROCESS | 1938 | |11;stanford-crypto-ccm;126;129;127;124;140;144;113;136;193;173 06:51:28 INFO - PROCESS | 1938 | |12;stanford-crypto-pbkdf2;165;168;169;165;168;163;163;165;165;165 06:51:28 INFO - PROCESS | 1938 | |13;stanford-crypto-sha256-iterative;69;67;70;75;98;99;96;79;66;68 06:51:28 INFO - PROCESS | 1938 | __end_tp_report 06:51:28 INFO - PROCESS | 1938 | __start_cc_report 06:51:28 INFO - PROCESS | 1938 | _x_x_mozilla_cycle_collect,1988 06:51:28 INFO - PROCESS | 1938 | __end_cc_report 06:51:28 INFO - PROCESS | 1938 | __startTimestamp1464011488878__endTimestamp 06:51:28 INFO - PROCESS | 1938 | 06:51:28 INFO - PROCESS | 1938 | ------- Summary: start ------- 06:51:28 INFO - PROCESS | 1938 | Number of tests: 14 06:51:28 INFO - PROCESS | 1938 | 06:51:28 INFO - PROCESS | 1938 | [#0] ai-astar Cycles:10 Average:116.50 Median:114.00 stddev:7.07 (6.2%) stddev-sans-first:7.45 06:51:28 INFO - PROCESS | 1938 | Values: 114.0 114.0 119.0 113.0 113.0 113.0 114.0 114.0 115.0 136.0 06:51:28 INFO - PROCESS | 1938 | 06:51:28 INFO - PROCESS | 1938 | [#1] audio-beat-detection Cycles:10 Average:140.10 Median:143.00 stddev:18.64 (13.0%) stddev-sans-first:18.74 06:51:28 INFO - PROCESS | 1938 | Values: 157.0 122.0 143.0 163.0 120.0 143.0 142.0 121.0 169.0 121.0 06:51:28 INFO - PROCESS | 1938 | 06:51:28 INFO - PROCESS | 1938 | [#2] audio-dft Cycles:10 Average:195.70 Median:186.50 stddev:25.32 (13.6%) stddev-sans-first:26.50 06:51:28 INFO - PROCESS | 1938 | Values: 184.0 185.0 187.0 183.0 185.0 186.0 200.0 266.0 196.0 185.0 06:51:28 INFO - PROCESS | 1938 | 06:51:28 INFO - PROCESS | 1938 | [#3] audio-fft Cycles:10 Average:91.80 Median:79.50 stddev:32.17 (40.5%) stddev-sans-first:33.32 06:51:28 INFO - PROCESS | 1938 | Values: 72.0 76.0 77.0 162.0 83.0 82.0 82.0 90.0 137.0 57.0 06:51:28 INFO - PROCESS | 1938 | 06:51:28 INFO - PROCESS | 1938 | [#4] audio-oscillator Cycles:10 Average:98.60 Median:80.00 stddev:26.48 (33.1%) stddev-sans-first:27.94 06:51:28 INFO - PROCESS | 1938 | Values: 91.0 94.0 148.0 77.0 81.0 80.0 127.0 80.0 77.0 131.0 06:51:28 INFO - PROCESS | 1938 | 06:51:28 INFO - PROCESS | 1938 | [#5] imaging-gaussian-blur Cycles:10 Average:93.70 Median:90.00 stddev:14.63 (16.3%) stddev-sans-first:15.48 06:51:28 INFO - PROCESS | 1938 | Values: 91.0 88.0 91.0 86.0 135.0 88.0 91.0 89.0 91.0 87.0 06:51:28 INFO - PROCESS | 1938 | 06:51:28 INFO - PROCESS | 1938 | [#6] imaging-darkroom Cycles:10 Average:97.30 Median:95.50 stddev:4.74 (5.0%) stddev-sans-first:5.00 06:51:28 INFO - PROCESS | 1938 | Values: 96.0 95.0 95.0 96.0 100.0 110.0 96.0 96.0 95.0 94.0 06:51:28 INFO - PROCESS | 1938 | 06:51:28 INFO - PROCESS | 1938 | [#7] imaging-desaturate Cycles:10 Average:85.40 Median:86.00 stddev:0.97 (1.1%) stddev-sans-first:1.00 06:51:28 INFO - PROCESS | 1938 | Values: 86.0 86.0 84.0 85.0 85.0 84.0 87.0 85.0 86.0 86.0 06:51:28 INFO - PROCESS | 1938 | 06:51:28 INFO - PROCESS | 1938 | [#8] json-parse-financial Cycles:10 Average:64.30 Median:64.00 stddev:6.02 (9.4%) stddev-sans-first:6.07 06:51:28 INFO - PROCESS | 1938 | Values: 59.0 64.0 64.0 64.0 64.0 65.0 64.0 80.0 61.0 58.0 06:51:28 INFO - PROCESS | 1938 | 06:51:28 INFO - PROCESS | 1938 | [#9] json-stringify-tinderbox Cycles:10 Average:69.50 Median:68.50 stddev:4.74 (6.9%) stddev-sans-first:4.59 06:51:28 INFO - PROCESS | 1938 | Values: 64.0 65.0 69.0 77.0 78.0 72.0 68.0 68.0 67.0 67.0 06:51:28 INFO - PROCESS | 1938 | 06:51:28 INFO - PROCESS | 1938 | [#10] stanford-crypto-aes Cycles:10 Average:80.80 Median:81.00 stddev:1.99 (2.5%) stddev-sans-first:2.09 06:51:28 INFO - PROCESS | 1938 | Values: 80.0 81.0 81.0 81.0 80.0 79.0 83.0 78.0 80.0 85.0 06:51:28 INFO - PROCESS | 1938 | 06:51:28 INFO - PROCESS | 1938 | [#11] stanford-crypto-ccm Cycles:10 Average:140.50 Median:138.00 stddev:24.49 (17.7%) stddev-sans-first:25.41 06:51:28 INFO - PROCESS | 1938 | Values: 126.0 129.0 127.0 124.0 140.0 144.0 113.0 136.0 193.0 173.0 06:51:28 INFO - PROCESS | 1938 | 06:51:28 INFO - PROCESS | 1938 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.60 Median:165.00 stddev:2.07 (1.3%) stddev-sans-first:2.18 06:51:28 INFO - PROCESS | 1938 | Values: 165.0 168.0 169.0 165.0 168.0 163.0 163.0 165.0 165.0 165.0 06:51:28 INFO - PROCESS | 1938 | 06:51:28 INFO - PROCESS | 1938 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.70 Median:77.00 stddev:13.66 (17.7%) stddev-sans-first:14.03 06:51:28 INFO - PROCESS | 1938 | Values: 69.0 67.0 70.0 75.0 98.0 99.0 96.0 79.0 66.0 68.0 06:51:28 INFO - PROCESS | 1938 | -------- Summary: end -------- 06:51:28 INFO - PROCESS | 1938 | 06:51:29 INFO - TEST-INFO | 1938: exit 0 06:51:29 INFO - TEST-OK | kraken | took 58781ms 06:51:29 INFO - SUITE-END | took 1174s 06:51:30 INFO - Completed test suite (00:19:34) 06:51:30 INFO - javascript benchmark 06:51:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3362.277110421259, 3442.156671615455, 3443.2769118485126, 3471.5486986691726, 3505.684488396044, 5661.575381846363, 5669.68816715081, 5686.402927692595, 5701.942980570193, 5714.171445140452, 5676.886462270762, 5736.942630573693, 5740.483356497914, 5749.195112684228, 5752.683602401867, 5076.949230507685, 5129.12804823181, 5133.178691409385, 5246.5278124968745, 5259.842204733845, 5204.895902081956, 5213.687178769267, 5223.451537588556, 5235.685858848462, 5242.659227150223, 5177.378714554259, 5180.844574662766, 5189.481051894818, 5213.348331458567, 5216.921746173811, 5222.686638801665, 5255.76349064293, 5257.08001099806, 5263.236830659533, 5329.200619907045, 5098.158803797406, 5113.61647876407, 5120.052790233819, 5194.766235519411, 5197.376314842205, 5584.441555844383, 5641.915371269434, 5645.689487078209, 5652.5195358394585, 5669.121286200645, 6217.440430361289, 6258.968705156491, 6268.561200715952, 6269.310375858652, 6290.559660823744, 6075.75696972125, 6205.410486003822, 6272.2786879508985, 6300.495960323163, 6327.651979141146, 5818.8254352369495, 5824.912626310609, 5852.209951656557, 5874.647521148702, 5894.882102357972, 6259.624422534663, 6282.120503129566, 6289.622622642564, 6292.937070629235, 6299.6535190564955, 5688.488036076773, 5786.016377215799, 5795.0727883539275, 6172.506199504029, 6215.627062376272, 6166.982447896136, 6211.65835879022, 6267.9373206268265, 6277.309495955441, 6287.182666253358, 6130.693465326805, 6210.347913469111, 6212.378762123842, 6223.222097237845, 6259.967105427553, 5221.32122824038, 6233.0338797486465, 6251.906221406683, 6273.96863015682, 6283.59156654816, 4852.927206091911, 4878.707277563287, 4912.968276661941, 4927.359443272351, 4955.132851750929, 4438.911221775546, 4441.089576636847, 4450.910981780346, 4458.8439404620685, 4476.395686582269, 5591.440855914376, 5750.856228594319, 5754.424557544212, 5758.337791153987, 5794.739236734357, 6207.410296021871, 6228.56400051992, 6280.497560195174, 6308.0, 6314.936850631527, 5729.9713501432225, 5731.885362292731, 5734.684592347461, 5741.511971482387, 5748.9425105748405, 5282.445343238982, 5370.677759334374, 5372.382176049804, 5377.3816011159215, 5396.244525766396, 6232.3144454110015, 6260.843478912972, 6315.557910946373, 6319.557630965788, 6322.2729386119345, 6263.060540918898, 6291.96182629861, 6297.244330680348, 6297.842553936188, 6309.842253943688, 5139.563137133386, 6365.204349456318, 6378.521610879296, 6381.8723625528155, 6387.904181437189, 5722.685252311163, 5850.444207800252, 6124.472479235402, 6171.537134715004, 6217.906731399123, 6233.470155036873, 6263.3736626337, 6264.968675156595, 6271.96864015677, 6279.654618995816, 5963.373845746308, 5979.073243647242, 6084.969575152096, 6091.42131497507, 6128.816135515942, 6134.723937422737, 6139.723712432862, 6157.0456579230295, 6158.168647232566, 6166.938330616636, 6005.969970150121, 6012.518998480198, 6013.579049466495, 6022.036474164288, 6055.1220073090035, 5979.133025711334, 6085.604435711664, 6124.510039196764, 6144.446999770043, 6164.921138800602, 5928.79249226275, 5941.821745347646, 5955.255593050869, 5965.403459654, 5980.073088671263], "value": 5696.934089075683, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [514.7220500929499, 529.0529951387017, 577.7573419163951, 590.3329237961105, 655.6754406568746, 30868.382632347366, 31734.523982140257, 31802.0, 38176.0, 39339.623113191046, 42280.0, 42622.14755704884, 42734.57265427345, 42942.7852860736, 42975.0, 9515.857262141057, 9711.0, 9717.514124293792, 9721.222302215841, 9722.368046077017, 8707.521086340248, 9562.0, 11612.593559225428, 11811.291322520676, 11852.229605075685, 11513.539458421654, 11608.419579021058, 11617.419129043556, 11621.12841536884, 11629.70925726855, 8713.259372953307, 11489.0, 11568.710782230428, 11586.536538538448, 11589.072874170046, 11598.884011159866, 11845.23006004607, 11849.644510664693, 11860.703482412922, 11863.52545898163, 38978.80510597429, 39431.21137577261, 39528.0, 39573.60426395728, 39578.80210598958, 3291.308825146722, 3309.0, 3319.020888837799, 3545.2554963457706, 3554.9111272218142, 27621.86189069042, 28158.0, 28374.574381384296, 28510.28724281889, 29222.269443263875, 2725.127959053104, 2756.6554180727408, 2786.6656001279975, 2798.552231642948, 2866.498362786525, 336888.0, 338796.0, 339607.0, 491013.99999999645, 492012.0, 62754.0586891197, 63267.73464530684, 63306.36693633097, 63329.36670633327, 63355.6832215836, 67035.66482167656, 67238.66380668164, 67255.66372168108, 67329.0, 67349.0, 15768.842311576967, 17425.56436089108, 17592.472225833244, 17613.559661008578, 17618.2071806769, 12086.456109475095, 12146.271223726604, 12170.513179472722, 13440.193588384729, 13547.0, 15977.600559986093, 16285.67428651444, 16877.746833797504, 17144.971301721936, 17282.308707651555, 6223.875522489525, 7255.854882902312, 7396.889046664304, 7440.479166458296, 7452.962735186289, 931.725141083382, 960.3709570231509, 1007.6674697350004, 1085.3566065508019, 1131.8189089745765, 24884.751152488243, 28405.85797071043, 29379.412411751644, 29561.408771824445, 30159.396812064075, 712.9804379737026, 1004.9296549241483, 1014.8883622801486, 1115.5928086248462, 1155.289496959364, 14246.287685615885, 15025.624359391102, 15404.306806193748, 15968.882178247644, 16140.75788863168, 605791.0, 611342.0, 613076.0, 613678.0, 613883.0, 60772.0, 61206.693966529885, 61274.69362653158, 61363.0, 61367.6931615348, 1360.3334366160707, 1490.2176357412445, 1508.0800711565937, 1532.9693406132037, 1534.8642004916505, 28262.0, 28600.26581658649, 28855.56716649294, 29147.41705165927, 29240.83036678509, 47342.05315893732, 47851.76074119607, 47857.282140767216, 47887.76056119697, 47971.92238659753, 356871.2156439201, 357210.0, 357278.0, 357524.0, 357654.0, 21637.891810540845, 22822.771772282063, 26997.0551030713, 27374.315642109108, 27424.0, 43323.133537329704, 44044.0, 44557.33164002478, 44594.3310850344, 44890.98373724537, 36194.4570831443, 36609.816950915076, 37139.442908356934, 37192.0, 37834.243315134096, 36029.0, 36144.915652530464, 36522.0, 36878.26243475062, 37001.259974800894, 35331.0, 35589.28821423609, 36158.73444429432, 37215.06962325964, 37293.81353093217, 31213.68786312108, 32117.678213641295, 32257.838710806296, 32304.676953230166, 32571.674283256863, 24214.0, 24366.75633243645, 24641.50716985686, 24652.260432187064, 24728.876355618107], "value": 19593.1479288039, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [333.57635802530785, 399.11794933197643, 401.4324064528019, 409.4738261334184, 411.3315861724697, 19777.406677799652, 21522.139114435402, 21898.0, 22451.0, 22456.663150052762, 20901.895490522566, 21538.784612153875, 21579.676304855402, 21603.135874565, 21857.453563660878, 4704.929426058612, 5995.790147344844, 6061.272647282332, 6163.845903852417, 6189.102580125901, 5947.4052594740615, 6170.981788004974, 6199.628022318675, 6209.254889413254, 6210.09953556734, 5219.295395121648, 5647.548196144298, 5967.283925928873, 6175.351588083254, 6177.536684748639, 4818.614510839125, 5870.0, 5910.408959104098, 5917.704114794265, 5925.940740592582, 5678.4037676044245, 5740.712964351766, 5741.8564535886935, 5749.281339832521, 5749.453801888814, 13328.933355333258, 13494.325283735872, 13570.525031623945, 13577.185368877801, 18600.0, 2210.370044537315, 2232.910683572655, 2326.7673232676766, 2364.7044119485063, 2736.1244401791437, 7955.681772729084, 8343.916560834436, 9822.901770982342, 10263.846042309371, 10282.0, 1705.2837808120619, 1770.9911450442664, 1777.9466616001412, 1793.9820601793945, 1853.9814601853943, 87005.0, 121784.39107804492, 122911.0, 123577.38211308976, 123655.0, 11693.532258709556, 11755.177137600285, 11761.411929403495, 11803.291802491705, 11830.763384732258, 15168.393264269527, 15246.3901443941, 15292.617684557976, 15318.617034574225, 15329.693406131815, 8629.0, 8651.134886511298, 8660.393772435995, 8787.736367908972, 8793.164649358429, 7023.403010744042, 7297.963510182415, 8503.937007874016, 8707.956460217785, 8777.736667899973, 4188.769617671057, 4588.220002599566, 4660.277656963176, 4665.370175026396, 4707.0, 1634.4606279927596, 1771.409337885262, 1940.4663717477524, 1950.4246247357064, 1975.8024197580128, 914.8124634449922, 949.6866034208695, 984.2765567308023, 987.5605355616825, 1040.9635662751768, 20889.37331880046, 21234.681479777817, 21650.783492165192, 22013.559728805656, 22615.54768904613, 967.583938906263, 973.3089506450499, 1005.7435353984833, 1023.2019025160387, 1028.0336483705294, 8256.0, 8982.101789820965, 11143.721406964729, 11532.538698452136, 11564.306141631529, 631667.0, 636753.0, 641766.0, 642170.7891460606, 643963.0, 128360.0, 130875.0, 135932.0, 138142.0, 138451.61548384588, 1054.7310435838813, 1059.4596755654673, 1078.8435676826973, 1078.8759292681443, 1082.0910435234284, 18405.81594184041, 18729.90635046816, 18873.43379698611, 18963.14665840013, 19290.4212873614, 55250.0, 55782.163267550204, 55877.882442351736, 56292.15561766495, 58447.0, 77282.61358693171, 79110.0, 79367.20632793829, 80493.0, 81427.0, 7879.84240315179, 8181.427300089174, 8267.0, 8351.373646976379, 8363.874541881998, 11324.6036388726, 11337.26307789991, 12263.75472490563, 12582.496700131893, 12638.2417054977, 10750.623728169476, 11010.339379637247, 11097.38964356968, 11146.442677866236, 11151.10791136691, 7982.8802567960365, 10169.644062457779, 10190.796184076129, 10529.315594486334, 10550.31422957536, 10905.0, 11203.775924481628, 11354.886451135713, 11371.260868043551, 11389.259698119597, 8114.715984940499, 8127.918720812716, 8138.755837324889, 8201.71294004707, 8523.232909038215, 8311.0, 10411.375317480975, 10708.357498550113, 11210.383428911487, 11237.662870113909], "value": 9493.35975592464, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1409.1615488784182, 1545.1501674079257, 1565.9608509787254, 1784.607386374997, 1933.2556965568244, 7807.4144439167, 8456.788580285496, 8626.56867156642, 8636.91363086369, 8642.308615310776, 8349.123342049088, 8607.870881936777, 8613.44012639178, 8614.913850861489, 8616.396852220347, 2109.5780843831217, 2357.033616217351, 2384.213209640823, 2409.4096946248105, 2416.1060407649193, 2429.927102186937, 2432.2460037388378, 2437.8415402998835, 2440.7803297703203, 2441.292025312657, 2342.8008619267384, 2386.5107652931106, 2404.915827946022, 2407.8675672837985, 2412.49337639096, 2014.5870096630156, 2092.884891330984, 2327.359976006595, 2379.202967006055, 2396.2930935374106, 2359.725748096026, 2390.7728765767224, 2395.137750409851, 2398.0, 2401.267613377919, 8474.1949514796, 8549.829003419958, 8559.95720021396, 8562.315014798802, 8602.87095693565, 5135.666181698178, 5264.2893209416625, 5277.340332458442, 5284.180951952453, 5307.946920530784, 3284.720798732111, 3356.9832150839334, 3470.219200679827, 3477.2871561329866, 3491.790492570454, 957.2007373842849, 989.6931951095183, 1002.3735165521549, 1024.4212020208558, 1029.3978022856638, 11679.357635330054, 11707.0, 11711.238769480042, 11713.180077394585, 11726.413679316085, 4119.237940980868, 4532.3654688342995, 4688.203005489075, 4746.572808447257, 4793.281007848851, 9923.354981926152, 9941.353812002342, 9963.103320701171, 9975.102240798364, 10000.549975251131, 5230.712310822942, 5262.236975638511, 5271.998320319204, 5289.127293996525, 5290.0, 4322.243607368698, 4438.667099967515, 4705.670603057822, 4788.880277993078, 4848.248521479177, 3416.3850506908993, 3907.5310962684093, 3942.3495123304333, 3963.920721585552, 3973.4635824163947, 1122.910167186623, 1303.543759684103, 1315.4014923209916, 1322.3520474967388, 1331.8601546837442, 826.8759686047141, 849.0999540487103, 892.8749975003504, 908.8000639859221, 936.0452338614575, 4677.789499472532, 5626.24045753818, 5632.971835140798, 5956.52347812174, 5973.223480947449, 825.3809642767924, 898.7034278688018, 939.0937745076035, 945.9594446109224, 946.5882341181608, 3895.5130608673917, 3924.7448915820382, 3961.544422391462, 3962.049108214008, 4055.229506393776, 8696.0, 10281.383117013003, 10320.22598305115, 10454.63408780689, 10569.524371403306, 10334.689959301233, 10336.0, 10347.379157250589, 10348.223883208639, 10468.52891619879, 8097.838043239102, 8152.470089444048, 8163.795905102301, 8203.097659257477, 8231.218034286732, 5637.915431268452, 5759.481646651821, 5775.537956963517, 5876.6180198286975, 5929.0217114175675, 9381.624735010797, 9441.197498212729, 9603.807923841345, 9608.183304418922, 9645.324827262024, 7936.0, 9663.0, 9762.072603102693, 9789.951050244703, 9882.802343953224, 6720.1599800025, 6879.277675844164, 7241.601711905685, 7379.889301660372, 7519.060117485315, 7992.720254791055, 8036.0, 8077.838443230985, 8114.5942702865805, 8127.390445716476, 7111.324424179695, 7158.713651453883, 7185.604791736505, 7294.635268236673, 7320.084989376328, 6144.109104179958, 7009.1589009319205, 7057.611831349325, 7132.786016419515, 7205.963970180115, 6659.467242620675, 6788.422984046411, 6814.250432452525, 6817.5909445433435, 6820.147481564804, 4662.53374662531, 4665.4168228971375, 4717.127331443694, 4737.715737055788, 4821.879453013562, 4051.5745846685672, 4683.8360657376825, 4722.598579120813, 4793.760311984317, 4980.701157930535], "value": 4483.223163473745, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [312.13227228305306, 317.9650238473768, 324.6266793187834, 325.05247204399177, 330.8726140435933, 6056.212692349999, 6547.443467305273, 6695.933040669592, 6739.898901516482, 6764.56030358027, 5901.911471327923, 6446.45205157561, 6587.209534855824, 6645.900311495319, 6653.866922661556, 4877.926831097537, 4917.164082106033, 4918.0901533216465, 4926.729029903363, 4935.901281974358, 4184.895377615553, 4681.110588988098, 4753.1919573672385, 4873.8781530461665, 4940.555350018498, 4869.829555965542, 4872.9756351218375, 4882.536159064901, 4905.043516514281, 4949.727764972925, 4526.117407105616, 4894.535019173173, 4905.73018483985, 4908.803647854082, 4938.62960277977, 4184.8744537663615, 4187.288161012635, 4189.916201675949, 4202.537720850704, 4204.3903633973205, 6342.809715708536, 6363.681815909233, 6375.649339286338, 6380.0748891410485, 6386.936130638681, 5495.945040549584, 5747.3103227612955, 5854.882902341972, 5883.323417806964, 5897.056470964668, 5887.764489420418, 7112.324329188718, 7145.321194486515, 7149.821254468628, 7237.0230018947295, 4615.053913947632, 4621.976890115561, 4635.096156249533, 4636.652251081183, 4638.605718513931, 7521.586312752796, 7637.770866874002, 7641.465097443125, 7642.770716878447, 7653.0, 3838.4242363645676, 4666.836660716858, 4684.765761711901, 4697.577218050392, 4703.0593881223895, 4576.654469576282, 4720.787564559534, 4738.905221895543, 4755.595774359149, 4758.452777930513, 4641.721496710208, 5748.166515855261, 5752.482276595127, 5757.798477053283, 5763.3084029915835, 5444.891102178013, 7262.273772622695, 7271.963640181871, 7273.23631018746, 7284.927150728425, 1566.69449457357, 1605.494269305165, 1621.4162901355503, 1629.9511014669579, 1663.9251233694513, 1030.9381437113798, 1034.446571084466, 1037.3309215556076, 1042.6715584590982, 1046.6807623674852, 6574.093512881211, 6592.17597800275, 6606.966965165239, 6633.568818026813, 6640.269570347258, 7401.1488678802625, 7430.2941220585035, 7452.329290363893, 7460.14208366034, 7462.253774622494, 6611.305812889674, 6622.238442579069, 6627.635480048547, 6642.169728783902, 6671.999200120021, 3253.4794432890626, 3943.191645712622, 3975.5229372475487, 3979.7015223857748, 3981.900452488653, 7506.62466876654, 7699.230076992256, 8011.559364234907, 8028.678852845821, 8051.194880511902, 6808.523403361717, 7865.370770338359, 7878.724244651525, 7891.210878912063, 7900.209979002168, 6480.61116333031, 7908.960455197802, 8011.7195898144455, 8025.759227223192, 8220.3834712398, 6148.262208534826, 7296.817579560554, 7576.962115189389, 7606.619669016638, 7655.157932627518, 7458.21688722698, 7481.625918704151, 7496.58768767723, 7497.625118743932, 7502.399808015237, 7971.402144839044, 8010.559419231999, 8014.559199244098, 8016.559089250147, 8022.799430014296, 4696.365990591226, 4723.858284251478, 4731.077439899186, 4742.004179122285, 4759.14335419614, 7188.928110718826, 7568.735094271674, 7579.62101894892, 7601.619919004138, 7605.885911711218, 7233.276672332725, 7275.163356214103, 7295.4528410368375, 7296.7446139384865, 7323.450741194538, 7298.985557943004, 7312.659948816327, 7320.15818180916, 7323.267673232847, 7325.047743793273, 7158.749443769443, 7163.283671632795, 7166.283371662792, 7186.993820864978, 7189.921511773276, 6964.268751781193, 7230.927690723236, 7231.132264128128, 7244.239354867663, 7295.197528271779, 5723.370429252695, 7082.079329687108, 7116.786496404909, 7126.429885609035, 7146.749863754743], "value": 5211.610984955601, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [684.5721424109933, 751.5378042503859, 752.7290175536806, 763.7021561590979, 771.560210679912, 14510.854891451083, 14723.558293251193, 14732.0, 14743.926280368583, 14747.705045899089, 13481.797773033413, 15289.159096249701, 15729.764053539206, 15766.605834854134, 15787.368505259776, 729.7701224114413, 738.0073800737999, 904.2223687628667, 906.9047749986255, 929.7815013471828, 909.5406819556148, 912.1380295620642, 913.2602591900549, 914.6524320758102, 914.7027216154743, 975.8975307592707, 977.6724797192913, 978.188103873783, 978.7357413498352, 981.0973904008293, 766.2108028730389, 939.1829108675496, 958.3626888119393, 962.1533050915219, 971.2084651009404, 711.6299524247414, 713.1086142322098, 713.7180813578659, 713.8857782754734, 714.7426926306525, 15238.247601525856, 15281.770773438408, 15297.0, 15309.693806123927, 15347.769783453257, 12379.381030948507, 12480.188787728768, 12536.623901282976, 12637.368131593475, 12778.297193654345, 9576.425414475154, 9821.901780982242, 9831.31180817343, 9916.95041524795, 10031.247656425725, 3390.135515443546, 3413.76103672743, 3425.0581090200394, 3464.8094354810473, 3497.3005398920063, 82417.76373354404, 82547.58726206391, 82577.3484530312, 82611.17388826035, 83085.58457207735, 9298.814023719488, 9441.716748497556, 9789.608415663437, 9862.112409883144, 9909.65316213929, 21973.890130549244, 22001.0, 22181.0, 22273.888630557067, 22282.331530054125, 7597.240275972469, 7640.235976402427, 7741.61291935401, 7897.368210543143, 7965.362770978424, 3903.2193561287854, 3951.328274193394, 4014.959850401517, 4119.485064366954, 4222.852200172979, 3386.8306584670668, 3440.449528075496, 3444.9655503444646, 3448.0173150651945, 3451.0, 511.79528188724066, 563.3380777586401, 588.6144575303183, 609.3236507476697, 622.5828694774511, 509.4777852701011, 515.5488947171225, 585.0317724166512, 605.9909101363484, 613.3314686991201, 6578.638174900427, 7882.842343153105, 7893.368530517545, 7951.403644726669, 8170.223828736261, 546.9671819690832, 577.4687287695247, 578.288704892984, 610.1640752169557, 619.9132121502993, 3701.407774756026, 3794.6015668354976, 3851.345271303885, 3861.687026411034, 3870.8838734838, 70687.93968090483, 91160.54419727859, 93351.53324233336, 93700.5944910827, 93946.0, 71633.92549111767, 72482.27517724861, 73152.53694926071, 75376.0, 79200.6039969808, 57534.0, 67504.97485075553, 68450.65774671195, 72470.0, 72562.63718681478, 8170.959145204236, 8231.835363292581, 8266.834663306821, 8370.707025254087, 8474.74575762728, 50179.49820501748, 50564.0, 50747.746261268454, 50811.23783143182, 50870.0, 53160.73419632877, 65225.021624676614, 67494.97515074555, 68793.65603171953, 69248.6537567309, 11192.104631629287, 11652.65042048706, 11857.407129643656, 11977.341246231192, 12023.398830058637, 11845.23006004607, 12107.33409662477, 12113.273203607812, 12308.446119924803, 12509.499620015462, 10368.326058806153, 11470.4264786762, 11587.478563464832, 11629.709257268636, 11722.70693232676, 11370.658880233606, 11458.427078645867, 11527.423628818358, 11587.015103716278, 12391.132620716824, 10849.224913769041, 11128.276662016942, 11230.494627741607, 11357.716057098309, 11514.021308188898, 9470.19503342196, 9566.569504372459, 9744.220462363135, 9754.707358779247, 9767.609295628095, 9614.326997110134, 9628.18160456341, 9743.415395076318, 9746.463944483121, 9862.96438873908], "value": 6877.459313291125, "unit": "score"}], "name": "dromaeo_css", "value": 7445.014292040691, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 119.0, 113.0, 113.0, 113.0, 114.0, 114.0, 115.0, 136.0], "value": 116.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 122.0, 143.0, 163.0, 120.0, 143.0, 142.0, 121.0, 169.0, 121.0], "value": 140.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 185.0, 187.0, 183.0, 185.0, 186.0, 200.0, 266.0, 196.0, 185.0], "value": 195.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 76.0, 77.0, 162.0, 83.0, 82.0, 82.0, 90.0, 137.0, 57.0], "value": 91.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 94.0, 148.0, 77.0, 81.0, 80.0, 127.0, 80.0, 77.0, 131.0], "value": 98.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 88.0, 91.0, 86.0, 135.0, 88.0, 91.0, 89.0, 91.0, 87.0], "value": 93.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 96.0, 100.0, 110.0, 96.0, 96.0, 95.0, 94.0], "value": 97.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 84.0, 85.0, 85.0, 84.0, 87.0, 85.0, 86.0, 86.0], "value": 85.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 64.0, 64.0, 64.0, 64.0, 65.0, 64.0, 80.0, 61.0, 58.0], "value": 64.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 69.0, 77.0, 78.0, 72.0, 68.0, 68.0, 67.0, 67.0], "value": 69.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 81.0, 81.0, 81.0, 80.0, 79.0, 83.0, 78.0, 80.0, 85.0], "value": 80.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 129.0, 127.0, 124.0, 140.0, 144.0, 113.0, 136.0, 193.0, 173.0], "value": 140.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 168.0, 169.0, 165.0, 168.0, 163.0, 163.0, 165.0, 165.0, 165.0], "value": 165.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 70.0, 75.0, 98.0, 99.0, 96.0, 79.0, 66.0, 68.0], "value": 78.7, "unit": "score"}], "name": "kraken", "value": 1518.5, "alertThreshold": 2.0}]} 06:51:30 INFO - Return code: 0 06:51:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 06:51:30 ERROR - # TBPL SUCCESS # 06:51:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:51:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:51:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:51:30 INFO - 2016-05-23 06:51:30.192 screenresolution[1942:15289] starting screenresolution argv=screenresolution get 06:51:30 INFO - 2016-05-23 06:51:30.194 screenresolution[1942:15289] Display 0: 1600x1200x32@60 06:51:30 INFO - 2016-05-23 06:51:30.200 screenresolution[1943:15292] starting screenresolution argv=screenresolution list 06:51:30 INFO - Available Modes on Display 0 06:51:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:51:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:51:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:51:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:51:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:51:30 INFO - Intel Iris: 06:51:30 INFO - Chipset Model: Intel Iris 06:51:30 INFO - Type: GPU 06:51:30 INFO - Bus: Built-In 06:51:30 INFO - VRAM (Dynamic, Max): 1536 MB 06:51:30 INFO - Vendor: Intel (0x8086) 06:51:30 INFO - Device ID: 0x0a2e 06:51:30 INFO - Revision ID: 0x0009 06:51:30 INFO - Displays: 06:51:30 INFO - Display: 06:51:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:51:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:51:30 INFO - Display Serial Number: 1600x1200 60 06:51:30 INFO - Main Display: Yes 06:51:30 INFO - Mirror: Off 06:51:30 INFO - Online: Yes 06:51:30 INFO - Rotation: Supported 06:51:30 INFO - Return code: 0 06:51:30 INFO - Running post-action listener: _resource_record_post_action 06:51:30 INFO - Running post-run listener: _resource_record_post_run 06:51:31 INFO - Total resource usage - Wall time: 1208s; CPU: 52.0%; Read bytes: 1122304; Write bytes: 653941760; Read time: 14; Write time: 1764 06:51:31 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 348725760; Write bytes: 346150400; Read time: 27592; Write time: 639 06:51:31 INFO - run-tests - Wall time: 1177s; CPU: 52.0%; Read bytes: 1122304; Write bytes: 296998400; Read time: 14; Write time: 1094 06:51:31 INFO - Running post-run listener: _upload_blobber_files 06:51:31 INFO - Blob upload gear active. 06:51:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:51:31 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 06:51:31 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'] 06:51:31 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 06:51:31 INFO - (blobuploader) - INFO - Open directory for files ... 06:51:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:51:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:51:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:51:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:51:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:51:33 INFO - (blobuploader) - INFO - Done attempting. 06:51:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:51:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:51:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:51:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:51:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:51:34 INFO - (blobuploader) - INFO - Done attempting. 06:51:34 INFO - (blobuploader) - INFO - Iteration through files over. 06:51:34 INFO - Return code: 0 06:51:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:51:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:51:34 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a344f2bfbbec64333e1c1064207b10414c33745572f148252b6d33ec754778aa7bd01b645774803d7ad047c4a0a62d532860591a9cd780fc0fc2df0b1cebf5a1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e70df1c576cc88970d5df18c31e80d4ac27d7243857ce6295e57c6dfb11ff5f267b05327882f02d5ae89519f5b6c067d8f16fa3b209d637cbf90571df7570509"} 06:51:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:51:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:51:34 INFO - Contents: 06:51:34 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a344f2bfbbec64333e1c1064207b10414c33745572f148252b6d33ec754778aa7bd01b645774803d7ad047c4a0a62d532860591a9cd780fc0fc2df0b1cebf5a1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e70df1c576cc88970d5df18c31e80d4ac27d7243857ce6295e57c6dfb11ff5f267b05327882f02d5ae89519f5b6c067d8f16fa3b209d637cbf90571df7570509"} 06:51:34 INFO - Running post-run listener: copy_logs_to_upload_dir 06:51:34 INFO - Copying logs to upload dir... 06:51:34 INFO - mkdir: /builds/slave/test/build/upload/logs 06:51:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1344.204004 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 24 secs) (at 2016-05-23 06:51:34.134284) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-23 06:51:34.138209) ========= 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.iWfFaRmlkL/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.re4Nil0g4X/Listeners TMPDIR=/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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/a344f2bfbbec64333e1c1064207b10414c33745572f148252b6d33ec754778aa7bd01b645774803d7ad047c4a0a62d532860591a9cd780fc0fc2df0b1cebf5a1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e70df1c576cc88970d5df18c31e80d4ac27d7243857ce6295e57c6dfb11ff5f267b05327882f02d5ae89519f5b6c067d8f16fa3b209d637cbf90571df7570509"} build_url:https://queue.taskcluster.net/v1/task/G3T_NDhkQ72_1OnxsCuyUw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011082 build_url: 'https://queue.taskcluster.net/v1/task/G3T_NDhkQ72_1OnxsCuyUw/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/a344f2bfbbec64333e1c1064207b10414c33745572f148252b6d33ec754778aa7bd01b645774803d7ad047c4a0a62d532860591a9cd780fc0fc2df0b1cebf5a1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e70df1c576cc88970d5df18c31e80d4ac27d7243857ce6295e57c6dfb11ff5f267b05327882f02d5ae89519f5b6c067d8f16fa3b209d637cbf90571df7570509"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-23 06:51:34.167553) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 06:51:34.167939) ========= 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.iWfFaRmlkL/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.re4Nil0g4X/Listeners TMPDIR=/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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.005017 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 06:51:34.216919) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-23 06:51:34.217272) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-23 06:51:34.217658) ========= ========= Total master_lag: 0.18 =========