builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0019 starttime: 1462979283.4 results: success (0) buildid: 20160511071708 builduid: ab069f3e3baa40f1aca7a226ea731ac6 revision: 5cc872323d179d22abc2ff1ecbb4229160340e2d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-11 08:08:03.404627) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-11 08:08:03.405354) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-11 08:08:03.405889) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-11 08:08:03.426058) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 08:08:03.426611) ========= 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.pn0tz2tR3q/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.DfpCSEXzMp/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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-11 08:08:03-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.5M=0.001s 2016-05-11 08:08:03 (18.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.390598 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 08:08:03.839421) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 08:08:03.840105) ========= 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.pn0tz2tR3q/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.DfpCSEXzMp/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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.027763 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 08:08:03.891493) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-11 08:08:03.891850) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 5cc872323d179d22abc2ff1ecbb4229160340e2d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 5cc872323d179d22abc2ff1ecbb4229160340e2d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.pn0tz2tR3q/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.DfpCSEXzMp/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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-11 08:08:03,964 truncating revision to first 12 chars 2016-05-11 08:08:03,964 Setting DEBUG logging. 2016-05-11 08:08:03,964 attempt 1/10 2016-05-11 08:08:03,964 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/5cc872323d17?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-11 08:08:05,995 unpacking tar archive at: fx-team-5cc872323d17/testing/mozharness/ program finished with exit code 0 elapsedTime=2.276916 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-11 08:08:06.185821) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-11 08:08:06.186150) ========= script_repo_revision: 5cc872323d179d22abc2ff1ecbb4229160340e2d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-11 08:08:06.186545) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-11 08:08:06.186805) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-11 08:08:06.200356) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-05-11 08:08:06.200629) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.pn0tz2tR3q/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.DfpCSEXzMp/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 08:08:06 INFO - MultiFileLogger online at 20160511 08:08:06 in /builds/slave/test 08:08:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 08:08:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:08:06 INFO - {'append_to_log': False, 08:08:06 INFO - 'base_work_dir': '/builds/slave/test', 08:08:06 INFO - 'blob_upload_branch': 'Fx-Team', 08:08:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:08:06 INFO - 'branch': 'Fx-Team', 08:08:06 INFO - 'buildbot_json_path': 'buildprops.json', 08:08:06 INFO - 'config_files': ('talos/mac_config.py',), 08:08:06 INFO - 'default_actions': ('clobber', 08:08:06 INFO - 'read-buildbot-config', 08:08:06 INFO - 'download-and-extract', 08:08:06 INFO - 'populate-webroot', 08:08:06 INFO - 'create-virtualenv', 08:08:06 INFO - 'install', 08:08:06 INFO - 'run-tests'), 08:08:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:08:06 INFO - 'download_minidump_stackwalk': True, 08:08:06 INFO - 'download_symbols': 'ondemand', 08:08:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:08:06 INFO - 'tooltool.py': '/tools/tooltool.py', 08:08:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:08:06 INFO - '/tools/misc-python/virtualenv.py')}, 08:08:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:08:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:08:06 INFO - 'installer_path': 'installer.exe', 08:08:06 INFO - 'log_level': 'info', 08:08:06 INFO - 'log_name': 'talos', 08:08:06 INFO - 'log_to_console': True, 08:08:06 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:08:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:08:06 INFO - 'opt_config_files': (), 08:08:06 INFO - 'pip_index': False, 08:08:06 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:08:06 INFO - 'cmd': ('bash', 08:08:06 INFO - '-c', 08:08:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:08:06 INFO - 'enabled': True, 08:08:06 INFO - 'halt_on_failure': False, 08:08:06 INFO - 'name': 'check_screen_resolution'},), 08:08:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:08:06 INFO - 'cmd': ('bash', 08:08:06 INFO - '-c', 08:08:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:08:06 INFO - 'enabled': True, 08:08:06 INFO - 'halt_on_failure': False, 08:08:06 INFO - 'name': 'check_screen_resolution'},), 08:08:06 INFO - 'run_cmd_checks_enabled': True, 08:08:06 INFO - 'sps_profile': False, 08:08:06 INFO - 'sps_profile_interval': 0, 08:08:06 INFO - 'suite': 'dromaeojs', 08:08:06 INFO - 'system_bits': '32', 08:08:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:08:06 INFO - 'title': 't-yosemite-r7-0019', 08:08:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:08:06 INFO - 'use_talos_json': True, 08:08:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:08:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:08:06 INFO - 'work_dir': 'build'} 08:08:06 INFO - ##### 08:08:06 INFO - ##### Running clobber step. 08:08:06 INFO - ##### 08:08:06 INFO - Running pre-action listener: _resource_record_pre_action 08:08:06 INFO - Running main action method: clobber 08:08:06 INFO - rmtree: /builds/slave/test/build 08:08:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:08:08 INFO - Running post-action listener: _resource_record_post_action 08:08:08 INFO - ##### 08:08:08 INFO - ##### Finished clobber step (success) 08:08:08 INFO - ##### 08:08:08 INFO - ##### 08:08:08 INFO - ##### Running read-buildbot-config step. 08:08:08 INFO - ##### 08:08:08 INFO - Running pre-action listener: _resource_record_pre_action 08:08:08 INFO - Running main action method: read_buildbot_config 08:08:08 INFO - Using buildbot properties: 08:08:08 INFO - { 08:08:08 INFO - "project": "", 08:08:08 INFO - "product": "firefox", 08:08:08 INFO - "script_repo_revision": "production", 08:08:08 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 08:08:08 INFO - "repository": "", 08:08:08 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 08:08:08 INFO - "buildid": "20160511071708", 08:08:08 INFO - "pgo_build": "False", 08:08:08 INFO - "basedir": "/builds/slave/test", 08:08:08 INFO - "buildnumber": 10, 08:08:08 INFO - "slavename": "t-yosemite-r7-0019", 08:08:08 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 08:08:08 INFO - "platform": "yosemite_r7", 08:08:08 INFO - "scheduler": "tests-fx-team-macosx64-talos", 08:08:08 INFO - "branch": "fx-team", 08:08:08 INFO - "repo_path": "integration/fx-team", 08:08:08 INFO - "revision": "5cc872323d179d22abc2ff1ecbb4229160340e2d", 08:08:08 INFO - "stage_platform": "macosx64", 08:08:08 INFO - "builduid": "ab069f3e3baa40f1aca7a226ea731ac6", 08:08:08 INFO - "slavebuilddir": "test" 08:08:08 INFO - } 08:08:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZUCIybARQhqWMHtil2xCsg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 08:08:08 INFO - Running post-action listener: _resource_record_post_action 08:08:08 INFO - ##### 08:08:08 INFO - ##### Finished read-buildbot-config step (success) 08:08:08 INFO - ##### 08:08:08 INFO - ##### 08:08:08 INFO - ##### Running download-and-extract step. 08:08:08 INFO - ##### 08:08:08 INFO - Running pre-action listener: _resource_record_pre_action 08:08:08 INFO - Running main action method: download_and_extract 08:08:08 INFO - mkdir: /builds/slave/test/build/tests 08:08:08 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')]} 08:08:08 INFO - https://queue.taskcluster.net/v1/task/ZUCIybARQhqWMHtil2xCsg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 08:08:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZUCIybARQhqWMHtil2xCsg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 08:08:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZUCIybARQhqWMHtil2xCsg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 08:08:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZUCIybARQhqWMHtil2xCsg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 08:08:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZUCIybARQhqWMHtil2xCsg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 08:08:09 INFO - Downloaded 1199 bytes. 08:08:09 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 08:08:09 INFO - Using the following test package requirements: 08:08:09 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 08:08:09 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:08:09 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 08:08:09 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:08:09 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 08:08:09 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:08:09 INFO - u'jsshell-mac.zip'], 08:08:09 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:08:09 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 08:08:09 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 08:08:09 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:08:09 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 08:08:09 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:08:09 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 08:08:09 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:08:09 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 08:08:09 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:08:09 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 08:08:09 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:08:09 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')]} 08:08:09 INFO - https://queue.taskcluster.net/v1/task/ZUCIybARQhqWMHtil2xCsg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:08:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZUCIybARQhqWMHtil2xCsg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:08:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZUCIybARQhqWMHtil2xCsg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:08:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZUCIybARQhqWMHtil2xCsg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:08:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZUCIybARQhqWMHtil2xCsg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:08:11 INFO - Downloaded 18150497 bytes. 08:08:11 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:08:11 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 08:08:14 INFO - Return code: 0 08:08:14 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:08:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:08:14 INFO - https://queue.taskcluster.net/v1/task/ZUCIybARQhqWMHtil2xCsg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:08:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZUCIybARQhqWMHtil2xCsg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:08:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZUCIybARQhqWMHtil2xCsg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:08:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZUCIybARQhqWMHtil2xCsg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:08:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZUCIybARQhqWMHtil2xCsg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:08:15 INFO - Downloaded 18150497 bytes. 08:08:15 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:08:15 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 08:08:19 INFO - Return code: 0 08:08:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:08:19 INFO - https://queue.taskcluster.net/v1/task/ZUCIybARQhqWMHtil2xCsg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 08:08:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZUCIybARQhqWMHtil2xCsg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 08:08:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZUCIybARQhqWMHtil2xCsg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 08:08:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZUCIybARQhqWMHtil2xCsg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 08:08:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZUCIybARQhqWMHtil2xCsg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:08:20 INFO - Downloaded 10840152 bytes. 08:08:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:08:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 08:08:21 INFO - Return code: 0 08:08:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:08:21 INFO - https://queue.taskcluster.net/v1/task/ZUCIybARQhqWMHtil2xCsg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 08:08:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZUCIybARQhqWMHtil2xCsg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 08:08:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZUCIybARQhqWMHtil2xCsg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 08:08:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZUCIybARQhqWMHtil2xCsg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 08:08:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZUCIybARQhqWMHtil2xCsg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 08:08:24 INFO - Downloaded 109843082 bytes. 08:08:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZUCIybARQhqWMHtil2xCsg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 08:08:24 INFO - mkdir: /builds/slave/test/properties 08:08:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:08:24 INFO - Writing to file /builds/slave/test/properties/build_url 08:08:24 INFO - Contents: 08:08:24 INFO - build_url:https://queue.taskcluster.net/v1/task/ZUCIybARQhqWMHtil2xCsg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 08:08:25 INFO - Running post-action listener: _resource_record_post_action 08:08:25 INFO - Running post-action listener: set_extra_try_arguments 08:08:25 INFO - ##### 08:08:25 INFO - ##### Finished download-and-extract step (success) 08:08:25 INFO - ##### 08:08:25 INFO - ##### 08:08:25 INFO - ##### Running populate-webroot step. 08:08:25 INFO - ##### 08:08:25 INFO - Running pre-action listener: _resource_record_pre_action 08:08:25 INFO - Running main action method: populate_webroot 08:08:25 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:08:25 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:08:25 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:08:25 INFO - '--tppagecycles', 08:08:25 INFO - '7'], 08:08:25 INFO - 'tests': ['tsvgm']}}, 08:08:25 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:08:25 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:08:25 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:08:25 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:08:25 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:08:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:08:25 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:08:25 INFO - 'talos_options': ['--disable-e10s'], 08:08:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:08:25 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:08:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:08:25 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:08:25 INFO - 'talos_options': ['--disable-e10s'], 08:08:25 INFO - 'tests': ['damp', 'tps']}, 08:08:25 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:08:25 INFO - 'tests': ['damp', 'tps']}, 08:08:25 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:08:25 INFO - 'tests': ['dromaeo_dom']}, 08:08:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:08:25 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:08:25 INFO - 'tests': ['a11yr', 08:08:25 INFO - 'ts_paint', 08:08:25 INFO - 'tpaint', 08:08:25 INFO - 'sessionrestore', 08:08:25 INFO - 'sessionrestore_no_auto_restore', 08:08:25 INFO - 'tabpaint']}, 08:08:25 INFO - 'other-e10s': {'tests': ['a11yr', 08:08:25 INFO - 'ts_paint', 08:08:25 INFO - 'tpaint', 08:08:25 INFO - 'sessionrestore', 08:08:25 INFO - 'sessionrestore_no_auto_restore', 08:08:25 INFO - 'tabpaint']}, 08:08:25 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:08:25 INFO - 'ts_paint', 08:08:25 INFO - 'tpaint', 08:08:25 INFO - 'sessionrestore', 08:08:25 INFO - 'sessionrestore_no_auto_restore', 08:08:25 INFO - 'tabpaint']}, 08:08:25 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:08:25 INFO - 'ts_paint', 08:08:25 INFO - 'tpaint', 08:08:25 INFO - 'sessionrestore', 08:08:25 INFO - 'sessionrestore_no_auto_restore', 08:08:25 INFO - 'tabpaint']}, 08:08:25 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:08:25 INFO - 'tests': ['a11yr', 08:08:25 INFO - 'ts_paint', 08:08:25 INFO - 'tpaint', 08:08:25 INFO - 'sessionrestore', 08:08:25 INFO - 'sessionrestore_no_auto_restore', 08:08:25 INFO - 'tabpaint']}, 08:08:25 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:08:25 INFO - 'tests': ['a11yr', 08:08:25 INFO - 'ts_paint', 08:08:25 INFO - 'tpaint', 08:08:25 INFO - 'sessionrestore', 08:08:25 INFO - 'sessionrestore_no_auto_restore', 08:08:25 INFO - 'tabpaint']}, 08:08:25 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:08:25 INFO - 'tests': ['tsvgx', 08:08:25 INFO - 'tsvgr_opacity', 08:08:25 INFO - 'tart', 08:08:25 INFO - 'tscrollx', 08:08:25 INFO - 'cart']}, 08:08:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:08:25 INFO - 'tsvgr_opacity', 08:08:25 INFO - 'tart', 08:08:25 INFO - 'tscrollx', 08:08:25 INFO - 'cart']}, 08:08:25 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:08:25 INFO - 'talos_options': ['--disable-e10s'], 08:08:25 INFO - 'tests': ['tp5o']}, 08:08:25 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:08:25 INFO - 'tests': ['tp5o']}, 08:08:25 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:08:25 INFO - 'talos_options': ['--disable-e10s', 08:08:25 INFO - '--xperf_path', 08:08:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:08:25 INFO - 'tests': ['tp5n']}, 08:08:25 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:08:25 INFO - 'talos_options': ['--xperf_path', 08:08:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:08:25 INFO - 'tests': ['tp5n']}}, 08:08:25 INFO - 'talos.zip': {'path': '', 08:08:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:08:25 INFO - Running post-action listener: _resource_record_post_action 08:08:25 INFO - ##### 08:08:25 INFO - ##### Finished populate-webroot step (success) 08:08:25 INFO - ##### 08:08:25 INFO - ##### 08:08:25 INFO - ##### Running create-virtualenv step. 08:08:25 INFO - ##### 08:08:25 INFO - Running pre-action listener: _resource_record_pre_action 08:08:25 INFO - Running main action method: create_virtualenv 08:08:25 INFO - Creating virtualenv /builds/slave/test/build/venv 08:08:25 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 08:08:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:08:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:08:25 INFO - Using real prefix '/tools/python27' 08:08:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:08:26 INFO - Installing distribute.............................................................................................................................................................................................done. 08:08:29 INFO - Installing pip.................done. 08:08:29 INFO - Return code: 0 08:08:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:08: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')]} 08:08:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08: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')]} 08:08:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101235a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10123b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef63494fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010d9d30>, '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 0x100efddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010d0b20>, '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.pn0tz2tR3q/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.DfpCSEXzMp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 08:08:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:08:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:08:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pn0tz2tR3q/Render', 08:08:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:08:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:08:29 INFO - 'HOME': '/Users/cltbld', 08:08:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:08:29 INFO - 'LOGNAME': 'cltbld', 08:08:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:29 INFO - 'MOZ_NO_REMOTE': '1', 08:08:29 INFO - 'NO_EM_RESTART': '1', 08:08:29 INFO - 'PAGER': '/bin/cat', 08:08:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:08:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:08:29 INFO - 'PWD': '/builds/slave/test', 08:08:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:08:29 INFO - 'SHELL': '/bin/bash', 08:08:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DfpCSEXzMp/Listeners', 08:08:29 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 08:08:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:08:29 INFO - 'USER': 'cltbld', 08:08:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:08:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:08:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:29 INFO - 'XPC_FLAGS': '0x0', 08:08:29 INFO - 'XPC_SERVICE_NAME': '0', 08:08:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:08:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:29 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 08:08:29 INFO - Cleaning up... 08:08:29 INFO - Return code: 0 08:08:29 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 08:08: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')]} 08:08:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08: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')]} 08:08:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101235a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10123b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef63494fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010d9d30>, '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 0x100efddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010d0b20>, '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.pn0tz2tR3q/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.DfpCSEXzMp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 08:08:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 08:08:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 08:08:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pn0tz2tR3q/Render', 08:08:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:08:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:08:29 INFO - 'HOME': '/Users/cltbld', 08:08:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:08:29 INFO - 'LOGNAME': 'cltbld', 08:08:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:29 INFO - 'MOZ_NO_REMOTE': '1', 08:08:29 INFO - 'NO_EM_RESTART': '1', 08:08:29 INFO - 'PAGER': '/bin/cat', 08:08:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:08:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:08:29 INFO - 'PWD': '/builds/slave/test', 08:08:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:08:29 INFO - 'SHELL': '/bin/bash', 08:08:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DfpCSEXzMp/Listeners', 08:08:29 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 08:08:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:08:29 INFO - 'USER': 'cltbld', 08:08:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:08:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:08:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:29 INFO - 'XPC_FLAGS': '0x0', 08:08:29 INFO - 'XPC_SERVICE_NAME': '0', 08:08:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:08:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:29 INFO - Downloading/unpacking psutil>=0.7.1 08:08:29 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 08:08:29 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 08:08:29 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 08:08:29 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 08:08:29 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 08:08:29 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 08:08:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:08:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:08:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:08:32 INFO - Installing collected packages: psutil 08:08:32 INFO - Running setup.py install for psutil 08:08:32 INFO - building 'psutil._psutil_osx' extension 08:08:32 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 08:08:33 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 08:08:33 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 08:08:33 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 08:08:33 INFO - building 'psutil._psutil_posix' extension 08:08:33 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 08:08:33 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:08:33 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:08:33 INFO - ^ 08:08:33 INFO - 1 warning generated. 08:08:33 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 08:08:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:08:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:08:33 INFO - Successfully installed psutil 08:08:33 INFO - Cleaning up... 08:08:33 INFO - Return code: 0 08:08:33 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 08:08:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:08:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:08:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101235a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10123b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef63494fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010d9d30>, '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 0x100efddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010d0b20>, '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.pn0tz2tR3q/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.DfpCSEXzMp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 08:08:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 08:08:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 08:08:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pn0tz2tR3q/Render', 08:08:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:08:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:08:33 INFO - 'HOME': '/Users/cltbld', 08:08:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:08:33 INFO - 'LOGNAME': 'cltbld', 08:08:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:33 INFO - 'MOZ_NO_REMOTE': '1', 08:08:33 INFO - 'NO_EM_RESTART': '1', 08:08:33 INFO - 'PAGER': '/bin/cat', 08:08:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:08:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:08:33 INFO - 'PWD': '/builds/slave/test', 08:08:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:08:33 INFO - 'SHELL': '/bin/bash', 08:08:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DfpCSEXzMp/Listeners', 08:08:33 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 08:08:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:08:33 INFO - 'USER': 'cltbld', 08:08:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:08:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:08:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:33 INFO - 'XPC_FLAGS': '0x0', 08:08:33 INFO - 'XPC_SERVICE_NAME': '0', 08:08:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:08:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:34 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 08:08:34 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 08:08:34 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 08:08:34 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 08:08:34 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 08:08:34 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 08:08:34 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 08:08:36 INFO - Downloading mozsystemmonitor-0.0.tar.gz 08:08:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:08:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 08:08:36 INFO - Installing collected packages: mozsystemmonitor 08:08:36 INFO - Running setup.py install for mozsystemmonitor 08:08:36 INFO - Successfully installed mozsystemmonitor 08:08:36 INFO - Cleaning up... 08:08:36 INFO - Return code: 0 08:08:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:08: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')]} 08:08:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08: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')]} 08:08:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:36 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 0x101235a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10123b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef63494fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010d9d30>, '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 0x100efddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010d0b20>, '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.pn0tz2tR3q/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.DfpCSEXzMp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 08:08:36 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 08:08:36 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 08:08:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pn0tz2tR3q/Render', 08:08:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:08:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:08:36 INFO - 'HOME': '/Users/cltbld', 08:08:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:08:36 INFO - 'LOGNAME': 'cltbld', 08:08:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:36 INFO - 'MOZ_NO_REMOTE': '1', 08:08:36 INFO - 'NO_EM_RESTART': '1', 08:08:36 INFO - 'PAGER': '/bin/cat', 08:08:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:08:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:08:36 INFO - 'PWD': '/builds/slave/test', 08:08:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:08:36 INFO - 'SHELL': '/bin/bash', 08:08:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DfpCSEXzMp/Listeners', 08:08:36 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 08:08:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:08:36 INFO - 'USER': 'cltbld', 08:08:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:08:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:08:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:36 INFO - 'XPC_FLAGS': '0x0', 08:08:36 INFO - 'XPC_SERVICE_NAME': '0', 08:08:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:08:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:37 INFO - Downloading/unpacking blobuploader==1.2.4 08:08:37 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 08:08:37 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 08:08:37 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 08:08:37 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 08:08:37 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 08:08:37 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 08:08:39 INFO - Downloading blobuploader-1.2.4.tar.gz 08:08:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:08:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:08:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:08:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:08:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:08:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:08:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:08:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:08:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:08:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:08:40 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 08:08:40 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 08:08:40 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 08:08:40 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 08:08:40 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 08:08:40 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 08:08:40 INFO - Downloading docopt-0.6.1.tar.gz 08:08:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:08:40 INFO - Installing collected packages: blobuploader, requests, docopt 08:08:40 INFO - Running setup.py install for blobuploader 08:08:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:08:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:08:40 INFO - Running setup.py install for requests 08:08:41 INFO - Running setup.py install for docopt 08:08:41 INFO - Successfully installed blobuploader requests docopt 08:08:41 INFO - Cleaning up... 08:08:41 INFO - Return code: 0 08:08:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:08:41 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')]} 08:08:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:41 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')]} 08:08:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:41 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 0x101235a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10123b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef63494fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010d9d30>, '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 0x100efddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010d0b20>, '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.pn0tz2tR3q/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.DfpCSEXzMp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 08:08:41 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 08:08:41 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 08:08:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pn0tz2tR3q/Render', 08:08:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:08:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:08:41 INFO - 'HOME': '/Users/cltbld', 08:08:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:08:41 INFO - 'LOGNAME': 'cltbld', 08:08:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:41 INFO - 'MOZ_NO_REMOTE': '1', 08:08:41 INFO - 'NO_EM_RESTART': '1', 08:08:41 INFO - 'PAGER': '/bin/cat', 08:08:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:08:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:08:41 INFO - 'PWD': '/builds/slave/test', 08:08:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:08:41 INFO - 'SHELL': '/bin/bash', 08:08:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DfpCSEXzMp/Listeners', 08:08:41 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 08:08:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:08:41 INFO - 'USER': 'cltbld', 08:08:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:08:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:08:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:41 INFO - 'XPC_FLAGS': '0x0', 08:08:41 INFO - 'XPC_SERVICE_NAME': '0', 08:08:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:08:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:08:41 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-5zMZuH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:08:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:08:41 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-W0Z82b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:08:42 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-FQIhzk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:08:42 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-vRcIr0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:08:42 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-6SEYLW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:08:42 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-V0vId1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:08:42 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-Wq5j0P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:08:42 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-6KfyYv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:08:42 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-xmiVC_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:08:42 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-XFlik4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:08:42 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-X_3VSl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:08:42 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-Jf9Zg5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:08:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:08:43 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-A70RTY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:08:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:08:43 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-FTnguN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:08:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:08:43 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-ZbU_yz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:08:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:08:43 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-soKO7v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:08:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:08:43 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-QmTM7W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:08:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:08:43 INFO - Running setup.py install for manifestparser 08:08:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:08:43 INFO - Running setup.py install for mozcrash 08:08:43 INFO - Running setup.py install for mozdebug 08:08:44 INFO - Running setup.py install for mozdevice 08:08:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:08:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:08:44 INFO - Running setup.py install for mozfile 08:08:44 INFO - Running setup.py install for mozhttpd 08:08:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:08:44 INFO - Running setup.py install for mozinfo 08:08:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:08:44 INFO - Running setup.py install for mozInstall 08:08:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:08:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:08:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:08:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:08:44 INFO - Running setup.py install for mozleak 08:08:44 INFO - Running setup.py install for mozlog 08:08:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:08:45 INFO - Running setup.py install for moznetwork 08:08:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:08:45 INFO - Running setup.py install for mozprocess 08:08:45 INFO - Running setup.py install for mozprofile 08:08:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:08:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:08:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:08:45 INFO - Running setup.py install for mozrunner 08:08:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:08:45 INFO - Running setup.py install for mozscreenshot 08:08:45 INFO - Running setup.py install for moztest 08:08:45 INFO - Running setup.py install for mozversion 08:08:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:08:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:08:46 INFO - Cleaning up... 08:08:46 INFO - Return code: 0 08:08:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:08:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:08:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:08:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:46 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 0x101235a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10123b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef63494fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010d9d30>, '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 0x100efddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010d0b20>, '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.pn0tz2tR3q/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.DfpCSEXzMp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 08:08:46 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 08:08:46 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 08:08:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pn0tz2tR3q/Render', 08:08:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:08:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:08:46 INFO - 'HOME': '/Users/cltbld', 08:08:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:08:46 INFO - 'LOGNAME': 'cltbld', 08:08:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:46 INFO - 'MOZ_NO_REMOTE': '1', 08:08:46 INFO - 'NO_EM_RESTART': '1', 08:08:46 INFO - 'PAGER': '/bin/cat', 08:08:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:08:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:08:46 INFO - 'PWD': '/builds/slave/test', 08:08:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:08:46 INFO - 'SHELL': '/bin/bash', 08:08:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DfpCSEXzMp/Listeners', 08:08:46 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 08:08:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:08:46 INFO - 'USER': 'cltbld', 08:08:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:08:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:08:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:46 INFO - 'XPC_FLAGS': '0x0', 08:08:46 INFO - 'XPC_SERVICE_NAME': '0', 08:08:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:08:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:08:46 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-gmYFzI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:08:46 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)) 08:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:08:46 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-wBZfRZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:08:46 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)) 08:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:08:46 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-7XCSG_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:08:46 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)) 08:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:08:46 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-zbUAnL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:08:46 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)) 08:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:08:46 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-5qMsBO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:08:46 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)) 08:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:08:46 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-oMg6o4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:08:47 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)) 08:08:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:08:47 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-JNJJM3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:08:47 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)) 08:08:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:08:47 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-y95bdG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:08:47 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)) 08:08:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:08:47 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-74xON9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:08:47 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)) 08:08:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:08:47 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-gOP14i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:08:47 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)) 08:08:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:08:47 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-fhiXaY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:08:47 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)) 08:08:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:08:47 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-nxJUVY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:08:47 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)) 08:08:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:08:47 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-GpZUDq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:08:47 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)) 08:08:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:08:47 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-hP8_JI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:08:47 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)) 08:08:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:08:47 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-6sp6Ph-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:08:48 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)) 08:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:08:48 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-Zv2JxQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:08:48 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)) 08:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:08:48 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-fG4UWv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:08:48 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)) 08:08:48 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)) 08:08:48 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)) 08:08:48 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)) 08:08:48 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)) 08:08:48 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)) 08:08:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:08:48 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 08:08:48 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 08:08:48 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 08:08:48 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 08:08:48 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 08:08:48 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 08:08:50 INFO - Downloading blessings-1.6.tar.gz 08:08:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:08:50 INFO - Installing collected packages: blessings 08:08:50 INFO - Running setup.py install for blessings 08:08:50 INFO - Successfully installed blessings 08:08:50 INFO - Cleaning up... 08:08:51 INFO - Return code: 0 08:08:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:08:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:08:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:08:51 INFO - Reading from file tmpfile_stdout 08:08:51 INFO - Current package versions: 08:08:51 INFO - blessings == 1.6 08:08:51 INFO - blobuploader == 1.2.4 08:08:51 INFO - docopt == 0.6.1 08:08:51 INFO - manifestparser == 1.1 08:08:51 INFO - mozInstall == 1.12 08:08:51 INFO - mozcrash == 0.17 08:08:51 INFO - mozdebug == 0.1 08:08:51 INFO - mozdevice == 0.48 08:08:51 INFO - mozfile == 1.2 08:08:51 INFO - mozhttpd == 0.7 08:08:51 INFO - mozinfo == 0.9 08:08:51 INFO - mozleak == 0.1 08:08:51 INFO - mozlog == 3.1 08:08:51 INFO - moznetwork == 0.27 08:08:51 INFO - mozprocess == 0.22 08:08:51 INFO - mozprofile == 0.28 08:08:51 INFO - mozrunner == 6.11 08:08:51 INFO - mozscreenshot == 0.1 08:08:51 INFO - mozsystemmonitor == 0.0 08:08:51 INFO - moztest == 0.7 08:08:51 INFO - mozversion == 1.4 08:08:51 INFO - psutil == 3.1.1 08:08:51 INFO - requests == 1.2.3 08:08:51 INFO - wsgiref == 0.1.2 08:08:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:08: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')]} 08:08:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08: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')]} 08:08:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:51 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 0x101235a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10123b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef63494fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010d9d30>, '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 0x100efddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010d0b20>, '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.pn0tz2tR3q/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.DfpCSEXzMp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 08:08:51 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 08:08:51 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 08:08:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pn0tz2tR3q/Render', 08:08:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:08:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:08:51 INFO - 'HOME': '/Users/cltbld', 08:08:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:08:51 INFO - 'LOGNAME': 'cltbld', 08:08:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:51 INFO - 'MOZ_NO_REMOTE': '1', 08:08:51 INFO - 'NO_EM_RESTART': '1', 08:08:51 INFO - 'PAGER': '/bin/cat', 08:08:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:08:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:08:51 INFO - 'PWD': '/builds/slave/test', 08:08:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:08:51 INFO - 'SHELL': '/bin/bash', 08:08:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DfpCSEXzMp/Listeners', 08:08:51 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 08:08:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:08:51 INFO - 'USER': 'cltbld', 08:08:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:08:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:08:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:51 INFO - 'XPC_FLAGS': '0x0', 08:08:51 INFO - 'XPC_SERVICE_NAME': '0', 08:08:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:08:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:51 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)) 08:08:51 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)) 08:08:51 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)) 08:08:51 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)) 08:08:51 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)) 08:08:51 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)) 08:08:51 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)) 08:08:51 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)) 08:08:51 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)) 08:08:51 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)) 08:08:51 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)) 08:08:51 INFO - Cleaning up... 08:08:51 INFO - Return code: 0 08:08:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:08: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')]} 08:08:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08: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')]} 08:08:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101235a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10123b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef63494fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010d9d30>, '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 0x100efddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010d0b20>, '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.pn0tz2tR3q/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.DfpCSEXzMp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 08:08: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', 'jsonschema'] in /builds/slave/test/build 08:08: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 jsonschema 08:08:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pn0tz2tR3q/Render', 08:08:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:08:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:08:51 INFO - 'HOME': '/Users/cltbld', 08:08:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:08:51 INFO - 'LOGNAME': 'cltbld', 08:08:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:51 INFO - 'MOZ_NO_REMOTE': '1', 08:08:51 INFO - 'NO_EM_RESTART': '1', 08:08:51 INFO - 'PAGER': '/bin/cat', 08:08:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:08:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:08:51 INFO - 'PWD': '/builds/slave/test', 08:08:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:08:51 INFO - 'SHELL': '/bin/bash', 08:08:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DfpCSEXzMp/Listeners', 08:08:51 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 08:08:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:08:51 INFO - 'USER': 'cltbld', 08:08:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:08:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:08:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:51 INFO - 'XPC_FLAGS': '0x0', 08:08:51 INFO - 'XPC_SERVICE_NAME': '0', 08:08:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:08:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:51 INFO - Downloading/unpacking jsonschema 08:08: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 08:08: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 08:08: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 08:08: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 08:08: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 08:08: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 08:08:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:08:54 INFO - Downloading/unpacking functools32 (from jsonschema) 08:08:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:08:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:08:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:08:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:08:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:08:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:08:54 INFO - Downloading functools32-3.2.3-2.tar.gz 08:08:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:08:54 INFO - warning: no files found matching '*.txt' 08:08:54 INFO - no previously-included directories found matching 'build' 08:08:54 INFO - no previously-included directories found matching 'dist' 08:08:54 INFO - no previously-included directories found matching '.git*' 08:08:54 INFO - Installing collected packages: jsonschema, functools32 08:08:54 INFO - Running setup.py install for functools32 08:08:54 INFO - warning: no files found matching '*.txt' 08:08:54 INFO - no previously-included directories found matching 'build' 08:08:54 INFO - no previously-included directories found matching 'dist' 08:08:54 INFO - no previously-included directories found matching '.git*' 08:08:55 INFO - Successfully installed jsonschema functools32 08:08:55 INFO - Cleaning up... 08:08:55 INFO - Return code: 0 08:08:55 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 08:08: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')]} 08:08:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08: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')]} 08:08:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101235a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10123b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef63494fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010d9d30>, '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 0x100efddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010d0b20>, '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.pn0tz2tR3q/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.DfpCSEXzMp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 08:08: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', 'flake8'] in /builds/slave/test/build 08:08: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 flake8 08:08:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pn0tz2tR3q/Render', 08:08:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:08:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:08:55 INFO - 'HOME': '/Users/cltbld', 08:08:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:08:55 INFO - 'LOGNAME': 'cltbld', 08:08:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:55 INFO - 'MOZ_NO_REMOTE': '1', 08:08:55 INFO - 'NO_EM_RESTART': '1', 08:08:55 INFO - 'PAGER': '/bin/cat', 08:08:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:08:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:08:55 INFO - 'PWD': '/builds/slave/test', 08:08:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:08:55 INFO - 'SHELL': '/bin/bash', 08:08:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DfpCSEXzMp/Listeners', 08:08:55 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 08:08:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:08:55 INFO - 'USER': 'cltbld', 08:08:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:08:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:08:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:55 INFO - 'XPC_FLAGS': '0x0', 08:08:55 INFO - 'XPC_SERVICE_NAME': '0', 08:08:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:08:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:55 INFO - Downloading/unpacking flake8 08:08: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 08:08: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 08:08: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 08:08: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 08:08: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 08:08: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 08:08:57 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:08:57 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:08:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:08:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:08:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:08:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:08:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:08:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:08:58 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:08:58 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:08: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 08:08: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 08:08: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 08:08: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 08:08: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 08:08: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 08:08:58 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:08: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 08:08: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 08:08: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 08:08: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 08:08: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 08:08: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 08:08:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 08:08:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:08:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:08:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:08:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:08:59 INFO - no previously-included directories found matching 'docs/_build' 08:08:59 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:08:59 INFO - Running setup.py install for pep8 08:08:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:08:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:08:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:08:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:08:59 INFO - no previously-included directories found matching 'docs/_build' 08:08:59 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 08:08:59 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:08:59 INFO - Cleaning up... 08:08:59 INFO - Return code: 0 08:08:59 INFO - Running post-action listener: _resource_record_post_action 08:08:59 INFO - Running post-action listener: _start_resource_monitoring 08:08:59 INFO - Starting resource monitoring. 08:08:59 INFO - ##### 08:08:59 INFO - ##### Finished create-virtualenv step (success) 08:08:59 INFO - ##### 08:08:59 INFO - ##### 08:08:59 INFO - ##### Running install step. 08:08:59 INFO - ##### 08:08:59 INFO - Running pre-action listener: _resource_record_pre_action 08:08:59 INFO - Running main action method: install 08:08:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:08:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:08:59 INFO - Reading from file tmpfile_stdout 08:08:59 INFO - Detecting whether we're running mozinstall >=1.0... 08:08:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:08:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:08:59 INFO - Reading from file tmpfile_stdout 08:08:59 INFO - Output received: 08:08:59 INFO - Usage: mozinstall [options] installer 08:08:59 INFO - Options: 08:08:59 INFO - -h, --help show this help message and exit 08:08:59 INFO - -d DEST, --destination=DEST 08:08:59 INFO - Directory to install application into. [default: 08:08:59 INFO - "/builds/slave/test"] 08:08:59 INFO - --app=APP Application being installed. [default: firefox] 08:08:59 INFO - mkdir: /builds/slave/test/build/application 08:08:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:08:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:09:33 INFO - Reading from file tmpfile_stdout 08:09:33 INFO - Output received: 08:09:33 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:09:33 INFO - Running post-action listener: _resource_record_post_action 08:09:33 INFO - ##### 08:09:33 INFO - ##### Finished install step (success) 08:09:33 INFO - ##### 08:09:33 INFO - ##### 08:09:33 INFO - ##### Running run-tests step. 08:09:33 INFO - ##### 08:09:33 INFO - Running pre-action listener: _resource_record_pre_action 08:09:33 INFO - Running main action method: run_tests 08:09:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:09:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:09:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:09:33 INFO - 2016-05-11 08:09:33.909 screenresolution[1639:7447] starting screenresolution argv=screenresolution get 08:09:33 INFO - 2016-05-11 08:09:33.912 screenresolution[1639:7447] Display 0: 1600x1200x32@60 08:09:33 INFO - 2016-05-11 08:09:33.917 screenresolution[1640:7449] starting screenresolution argv=screenresolution list 08:09:33 INFO - Available Modes on Display 0 08:09:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:09:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:09:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:09:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:09:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:09:34 INFO - Intel Iris: 08:09:34 INFO - Chipset Model: Intel Iris 08:09:34 INFO - Type: GPU 08:09:34 INFO - Bus: Built-In 08:09:34 INFO - VRAM (Dynamic, Max): 1536 MB 08:09:34 INFO - Vendor: Intel (0x8086) 08:09:34 INFO - Device ID: 0x0a2e 08:09:34 INFO - Revision ID: 0x0009 08:09:34 INFO - Displays: 08:09:34 INFO - Display: 08:09:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:09:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:09:34 INFO - Display Serial Number: 1600x1200 60 08:09:34 INFO - Main Display: Yes 08:09:34 INFO - Mirror: Off 08:09:34 INFO - Online: Yes 08:09:34 INFO - Rotation: Supported 08:09:34 INFO - Return code: 0 08:09:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:09:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:09:34 INFO - Python 2.7.3 08:09:34 INFO - Return code: 0 08:09:34 INFO - grabbing minidump binary from tooltool 08:09:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:09:34 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010d9d30>, '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 0x100efddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010d0b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:09:34 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:09:34 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 08:09:34 INFO - Calling ['/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'] with output_timeout 600 08:09:34 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:09:34 INFO - Return code: 0 08:09:34 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:09:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:09:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:09:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:09:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:09:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 08:09:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 08:09:35 INFO - Return code: 0 08:09:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZUCIybARQhqWMHtil2xCsg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0019', '--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 08:09:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ZUCIybARQhqWMHtil2xCsg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0019 --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 08:09:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pn0tz2tR3q/Render', 08:09:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:35 INFO - 'HOME': '/Users/cltbld', 08:09:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:35 INFO - 'LOGNAME': 'cltbld', 08:09:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:09:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:09:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:35 INFO - 'MOZ_NO_REMOTE': '1', 08:09:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:09:35 INFO - 'NO_EM_RESTART': '1', 08:09:35 INFO - 'PAGER': '/bin/cat', 08:09:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:35 INFO - 'PWD': '/builds/slave/test', 08:09:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:09:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:35 INFO - 'SHELL': '/bin/bash', 08:09:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DfpCSEXzMp/Listeners', 08:09:35 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 08:09:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:35 INFO - 'USER': 'cltbld', 08:09:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:35 INFO - 'XPC_FLAGS': '0x0', 08:09:35 INFO - 'XPC_SERVICE_NAME': '0', 08:09:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:35 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZUCIybARQhqWMHtil2xCsg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0019', '--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 08:09:35 INFO - mozversion application_buildid: 20160511071708 08:09:35 INFO - mozversion application_changeset: 5cc872323d179d22abc2ff1ecbb4229160340e2d 08:09:35 INFO - mozversion application_display_name: Nightly 08:09:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:09:35 INFO - mozversion application_name: Firefox 08:09:35 INFO - mozversion application_remotingname: firefox 08:09:35 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 08:09:35 INFO - mozversion application_vendor: Mozilla 08:09:35 INFO - mozversion application_version: 49.0a1 08:09:35 INFO - mozversion platform_buildid: 20160511071708 08:09:35 INFO - mozversion platform_changeset: 5cc872323d179d22abc2ff1ecbb4229160340e2d 08:09:35 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 08:09:35 INFO - mozversion platform_version: 49.0a1 08:09:35 INFO - using testdate: 1462979375 08:09:35 INFO - actual date: 1462979375 08:09:35 INFO - starting webserver on 'localhost:49275' 08:09:35 INFO - SUITE-START | Running 2 tests 08:09:35 INFO - TEST-START | dromaeo_css 08:09:35 INFO - operating with platform_type : mac_ 08:09:35 INFO - Initialising browser for dromaeo_css test... 08:09:35 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/tmpklCDYW/profile http://localhost:49275/getInfo.html) 08:09:37 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 08:09:37 INFO - PROCESS | 1658 | colorDepth:24 08:09:37 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 08:09:37 INFO - PROCESS | 1658 | __metrics 08:09:38 INFO - PROCESS | 1658 | 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" 08:09:38 ERROR - PROCESS | 1658 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:09:38 INFO - TEST-INFO | 1658: exit 0 08:09:38 INFO - Browser initialized. 08:09:38 INFO - Running cycle 1/1 for dromaeo_css test... 08:09:38 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/tmpklCDYW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:09:50 INFO - PROCESS | 1661 | RSS: Main: 238510080 08:09:50 INFO - PROCESS | 1661 | 08:10:42 INFO - PROCESS | 1661 | 1462979442235 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] 08:12:53 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 08:12:54 INFO - PROCESS | 1661 | RSS: Main: 358973440 08:12:54 INFO - PROCESS | 1661 | 08:15:57 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 08:15:57 INFO - PROCESS | 1661 | RSS: Main: 364576768 08:15:57 INFO - PROCESS | 1661 | 08:19:00 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 08:19:00 INFO - PROCESS | 1661 | RSS: Main: 369594368 08:19:00 INFO - PROCESS | 1661 | 08:22:02 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 08:22:03 INFO - PROCESS | 1661 | RSS: Main: 365043712 08:22:03 INFO - PROCESS | 1661 | 08:25:06 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 08:25:07 INFO - PROCESS | 1661 | RSS: Main: 332533760 08:25:07 INFO - PROCESS | 1661 | 08:28:10 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 08:28:10 INFO - PROCESS | 1661 | RSS: Main: 376135680 08:28:10 INFO - PROCESS | 1661 | 08:28:10 INFO - PROCESS | 1661 | __start_tp_report 08:28:10 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 08:28:10 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 08:28:10 INFO - PROCESS | 1661 | |i|pagename|runs| 08:28:10 INFO - PROCESS | 1661 | |0;dojo.html;3436.7250619950405;3457.1875609231834;3465.2549701814137;3493.4061209594374;3516;5630.746616402262;5657.207990881275;5658.519025882805;5725.828225153242;5796.333421656516;4558.703684260521;5548.944510554893;5610.24261724667;5721.656700597957;5739.368669446368;4076.857309994151;5093.796248150088;5123.5901127909865;5172.456892026339;5177.715225662587;4491.101779644068;5176.171812509999;5256.42179360272;5298.390685071208;5391.514763671269;5084.211947148179;5086.745662716849;5155.819546315898;5228.633995620308;5251.159814429673;5231;5233.9738301308635;5238.5023422774775;5255.159174532057;5272;5177.171652535576;5181;5182.015417070745;5212.55693266069;5254.185601231777;5467.917981230285;5589.524890384322;5627.212190293362;5631.155326701028;5651.886962260732;6260.906086408708;6272.15325930998;6285.214348206474;6291.96854015727;6292.402221788922;5826.329972053226;6144.38556144382;6277.843053923642;6367.936320636781;6368.10846481493;5652.971735141298;5709.857253568694;5787.565932554991;5788.421157884178;5840.474357307863;6289.433950944437;6292.559520833498;6295.213098362705;6306.8423289417215;6326.114343991845;5962.403759624003;6175.505959523317;6182.505399568024;6191.318954914956;6192.659403732748;6193.411625895533;6216.160818289563;6226.1905952225925;6281.560290779693;6297.023961286008;6214.067889816473;6229;6229.408206220402;6241.282252540925;6243.219597550306;6089.1648975218495;6288.402601752826;6299.307076221612;6310.1796766420075;6311.116443697886;4860.513948605111;4918.262260660929;4923.75381230937;4942.678725882806;4969.975150124226;4399.472063352354;4405.07493426384;4406.603405693503;4414.161309351277;4433.179861725591;5526.171074338881;5760.683162426107;5778.3643799182055;5807.593468457168;5841.678707671119;6160.353162917919;6220.813375598739;6235.376462353819;6274.309825919146;6324.841878953063;5761.107028410603;5768.307803063659;5786.913196302059;5798.9130163047585;5830.883382332415;5294.41761406245;5397.3523177218985;5410.513053825174;5420.376656684453;5425.13197888336;6263.3736626337;6286.622802631857;6332.841678958063;6348.968255158695;6418.807435776934;6293.685315734287;6308.022256550242;6312.3687631238345;6323.336049714714;6324.304326523987;6315.715792789244;6335.556511044183;6381.8085457436355;6398.168238129014;6402.743890244338;6090.786822461193;6101.206843110367;6116.701002199415;6200.782972595937;6205.875882482415;6295.464885484785;6301.716422761078;6302.149209856794;6322.146510221061;6325.620462772248;6145.569810113428;6160.291566469913;6164.321924588381;6168.1056246844855;6175.289841668266;4707.4821769606;5881.617694849821;5964.284285885721;6045.032794752994;6100.8779824404155;6004.219451471281;6012.759489620541;6039.275286965592;6041.412911084009;6096.542759292982;6149.139120523219;6157.476614487819;6158.445739883432;6160;6171;5846.736896839738;5866;5935.881282374415;6014.338422773404;6064.302605200459 08:28:10 INFO - PROCESS | 1661 | |1;ext.html;497.0580749479736;502.3870877529414;520.9502851753716;525.0784872548677;529.3409704917378;25295.620565691508;31309.21726956827;43163.13673726524;43803.342949855774;43814;42692.78653606735;45089.8727531811;45230.54769452305;45242.7737861311;45305.8673533161;8266.338692904568;8395;9879.209663226924;9900.702978910644;9937;9611.951940240324;9736.367136136163;9741.756456088584;9752.609895604168;9760.658376956808;9785.26610504215;9809.215262778996;9815.165710914582;9819.656312029081;9825.459599722013;9732.854007189897;9797.90202097977;9800.607975681;9807.411555306704;9809.264305177141;9728.562214700356;9799.461029643368;9811.558479868423;9812.754681132958;9817.165540929029;41803.58196418058;42265.94335141615;42349;42352.36471452931;42361;2801.29967508123;3433.5879694436826;3597.568291805;3781.4327850822597;4079.669103425025;29161;29362.265943351587;29576.260593485335;29581.55627665587;29886;2635.486080214359;2656.2828036430055;2706.5398882190075;2740.1916434651625;2761.006037826381;503052;505636;507903.4604826952;508041;509059;65487.672561636886;66135.33864661388;66141;66150.66924665346;66288.66855665787;36784.63215367812;37607.059823504635;37640.81179594085;38662.22675546473;38681.22637547233;15407.768883466757;16540.83459165393;17182.570435739206;17223.91388043052;17361.3055477782;13009.284489353176;13107.279099649611;13118.672033199246;13120.343982800821;13131.868681313255;16831.24259408305;20062.297819576244;20159.798402016084;20185.798142018684;20274.986250687405;8003.719869804529;8012.158723333966;8049.798755031171;8096.595170241464;8109.7161599343735;953.909378609031;968.7384406210284;994.5922171909482;1080.6325849211305;1119.9360607422818;24951.750482494943;26631.866840665672;28003;29521.114366569036;30451.757389735052;1042.921780866438;1070.2936062198912;1161.5469966712988;1173.559915031863;1187.4715751490676;15336.923315383352;15367.846321536865;15476.993995390263;15521.611959701098;15691.764623530657;626837;636044;642320;642792;644071;61135.08297375543;61580;61623.07565386523;61742;61826.381736182964;1322.702391961801;1359.9184048957095;1401.8644897632541;1474.108164560447;1501.2118637715287;26261.34346641349;29962.25094372658;30666.846665766527;30757.077287681404;31307.06078817639;51918.221226682384;52309.47690523046;52467.212991805914;52551.21173182482;52556.737216313675;509542;512864;513506;513644;513954;27026.054088106823;27925.720742792313;29258.268543286587;29299.85350073236;29324.413511730072;45912;46649.06701866011;46797.766011169726;47585.66900317083;48312.5168748308;37539.81230093832;38742.6125738739;39060.609393905695;39566.2086758269;39811.805645830675;37733.4339984906;37733.867983960525;37883.62116378801;38571;38684.6131538681;37951.05122371963;38305.80847095747;38447.10507505365;38448.423273651475;39182.412263815495;32484.51273230856;32745.017649470556;33470.665293346756;33760.66239337575;33812.661873381934;22754.299692968354;24772.504549909263;25196.74803251944;25256.62115068312;25703.742962570883 08:28:10 INFO - PROCESS | 1661 | |2;jquery.html;385;390.59378246623515;394.74341677909337;398.9381645844893;418.01557332482;12957.870421295784;19820.60358792823;20984.685229721566;21072.789272107275;21089.47276318093;15926.124063176521;17289.04910229937;20095.889931557194;20231.494212644655;20494.897525512388;5946.078357854529;6145.754169833202;6148.292946311164;6179.81460556184;6218.253809542861;6009.5192384609345;6094.695265236743;6109.053096770008;6112.938870611304;6175.691215439232;5918.526517878581;6057.727402266886;6062.818115456544;6075.817725468243;6113.755449782004;5609.242752228438;5939.227900372967;6023.518118550505;6079.270487541501;6090.512758979293;5260.316158899357;5292.9735351323;5295.7881684732565;5298.81454149103;5304.787808487695;25498;26153.73846261532;26301.342466438302;26492.60261096085;26910;2182;2248.640217565198;2374.7031621047367;2494.3390001649577;2633.5259653262406;13992.790108148385;14506.492272770507;14619;14941.70116597662;15054.172020538866;1333.7132516508998;1564.608847788053;1734.37562477508;1819.3450357871156;1883.3878989328387;104939;109799;112943.17642058933;115388;116000;7945.28492435672;10745.785084298426;11087.27932684389;11125.777484450266;11344.659660210207;13914.582562523141;14033.929830350782;14047.08693934891;14057.086289391156;14058.859411405754;7591.620418979029;7738.226177382329;8682.86975695365;8819.426737262058;8862.734117976472;7369.4472914531625;8898.154675305836;8912.643494260286;8914.242289405343;9036.683716069905;6648.501362397839;6756.290589488131;6770.661466926733;6776.119104516381;6844.657767111625;1890.1777726688658;1927.441042097804;1928.7106933960017;1931.903404829781;1964.6856502959745;880.2737741363272;973.868527748759;1007.8941711120374;1015.1117771949545;1049.1134990932649;20694.793052069588;20733.377998660064;21281.36155915325;21528.784712152992;21562.245321413677;997.2420960070253;1008.4050410257983;1026.235454586323;1027.9537420816082;1042.223543460127;9885.308028437941;10778.53805486883;11401;11705.239159454608;11776.76446471083;581603;592771;592779.0361048167;617046;631684;136787;138109.61890381167;138280;140127;144590;1065.1425602389995;1068.8236440987305;1093.8742044664966;1094.68801391603;1095.5727266366123;17412.129393530526;18073.36743213981;18378.63242735111;18412.723809142604;18784;34199.4870076954;37450.625493744716;43355.349669754345;51372.74313628408;56563.71718141383;81276;81305;81778;81859.18140818516;82342.58828705818;7645.885311720217;7869.960650196713;7917.208279172037;8032.236937490929;8295.129011453952;8909.242714369091;11624.883751162719;11906.34515101643;12082.214656047328;12347.506099756269;10445;10604.045635892806;10630.681079567625;10747.946260268649;10825.51285192184;10205.540750666387;10291.588336466757;10372.429516376671;10534.26260161812;10602.045815876609;9209.86185207209;10611.151107911193;10628.8937110631;10642.787144257227;11019.889801101886;7453.2546745325035;7752.689892404241;7837.294643482114;7845.058592968652;8165.836683266182;7868.84262314739;9468.95265523668;10578.471076446302;10824.567017319534;10853.674389768319 08:28:10 INFO - PROCESS | 1661 | |3;mootools.html;1430.1633544376539;1468.36126285066;1615.345784957092;1828;1986.4604931178344;7092.50352475327;8006.279434850862;8872.334574906883;8875.378723489343;9028.864567031484;8675.739727808175;8886.688965886195;8898.37711360205;8916.375853690244;8932.106789321064;2159.147136880931;2366.5976783946685;2417.9395515112087;2469.1604854349516;2472.814538909589;2449.767272109156;2494.738052504488;2497.500499900018;2497.962530562043;2500.4874000829877;2404.4349577849193;2468.691413573303;2470.3700556358103;2473.628955656657;2474.8391354561986;2143.3141394753684;2376.180217824865;2422.8667423291713;2439.8536087834786;2448.8897999590063;2431.346684254706;2431.8784060797066;2458.680371551705;2461.618449140386;2463.3964678653665;8874.556272186364;8941.64233430662;8957;8959.641614335418;8964.327675424369;5343.171808369709;5511.366192887829;5516.531094856942;5520.917186242169;5523.061079616475;3537.7169826413824;3570.7500474966764;3614.6385361463645;3647.6534729200685;3685.299793039314;1044.0812085364905;1057.1701214546545;1058.767071244325;1061.4533515239657;1066.4880857188516;11923.642290731292;12189.902908738168;12190.695232619168;12193.268403895707;12198.939005304917;4823;4827.08285425768;4930.383702037246;4941.45643979162;4972.204447288416;10306.381617102998;10314.48427578618;10325.432101234353;10340.482975851328;10363.792724145474;5460.836174914759;5486.094794358886;5505.614606977553;5505.642133261325;5514.035043867307;4261.914761704748;4454.242778727624;4565.908681826411;4595.391612935446;4628.3983082199775;4080.3063479208604;4100.323446631332;4161.542230354658;4184.790760462026;4203.222403855236;1319.967000824987;1368.9041767076408;1372.7529044771836;1375.559820857336;1387.3548799808138;831.5883637599427;932.1424289653535;948.6679662118204;965.932384733076;986.9753256168509;5538.723063846792;5961.926853166385;6045.637261764308;6091.390860913962;6258.812235632939;433.48415385690924;455.6946845613447;881.8015946412006;973.8782652168479;1059.5338051257422;3318.7345012399433;4009.4186342980106;4047.2917239483563;4070.5929407059057;4096.877093687194;10775.78448431027;10990.505427255792;11039;11157.330560166416;11192.776144477064;9989.60041598328;11154.944225278821;11157.38634375101;11158.665240042812;11170.888291117148;8192.5494097824;8290.958545207235;8343.916560834314;8378.790530236793;8415.242628163373;5448.427915068861;5540.32521451686;5849.444302791228;5881.235439392852;5982;9889.208863291064;10206.948965255126;10238.078572928473;10330.276880618285;10353.223508237063;8301.543415111984;9547.713568592953;10155.289129761142;10436.16510679159;10478.580856765644;7026.437884969088;7048.365647091581;7535.6985720570565;7665.655045523076;7687.692492300469;8524.914750852413;8556.229939305244;8590.613422395882;8612.741617751477;8626.827463450822;7361.30067643573;7570.280823321776;7608.997225525301;7629.580373079534;7711.768646940601;7383.187849336676;7472.29013243741;7526.397888168824;7621.733239336597;7644.923550764644;7156.964215178891;7251.1298644160925;7254.673539690621;7255.238199988925;7264.091988501437;4860.246661767432;5000.049990501939;5074.238864170404;5118.718470484159;5188;4714.740689262124;4757.452892917358;4783.928241076317;4787.329773831736;4970.826021089245 08:28:10 INFO - PROCESS | 1661 | |4;prototype.html;304.61922596754056;313.4686706033273;314.1580564088244;320.3464931539659;324.78564147662547;4651.441826980758;5178.5143131297;5201.453847346031;5235.738213089349;5275.2087186921935;4257.893552661185;5146.511081447256;5172.482751724826;5205.713685747282;5237.947620523803;3754.3805272130066;3818.2745278397015;3897.376419772837;3974.3243648579664;3987.7407968482103;3710.703143748494;3942.2509723152652;3969.340541836976;3998.860039898604;4077.143799802045;3974.4301001104545;3976.065624578222;3998.3802510610903;4014.538328092263;4032.6169013943627;3559.982200088983;3729.776213427203;3750.79370634615;3884.9028774280587;3994.6804255659476;3449.413599688034;3461.238527523941;3485.3377858207114;3491.5810102787573;3540.7521473496868;4002.1795531916177;4402.625776808975;4806.519348065201;4962.801487940478;4988.403710812541;4259.5740425957465;4509.4814096379005;4550.977245113786;4562.58936695699;4572.611328037087;4532.592066713979;5363.034653762321;5420.972895135538;5435.396558015395;5468.890622187534;3832.5209348831395;3851.8844434667003;3868.071662800936;3869.361555343365;3896.376579747255;5287.682739035632;5450.672959622396;5529.806456774034;5635.690037047961;5708;3386.7629265951145;3638.4542318652416;3766.209096089797;3787.5076240089165;3803.3344164771056;3249.23642943908;3339.5157702133056;3856.4408160817084;3856.710746693953;3862.3240932837202;4303.483581970184;4453.710508816916;4517.6160026397465;4530.864074077783;4531.071130418256;5104.927965127291;5243.318368612056;5288.92066619001;5416.8104116355735;5653.773849046074;1120.9407110280706;1288.7293668329569;1290.5031562848371;1310.8361454818148;1321.2666969831835;760.7903433540697;765.6439755513713;791.3076058448858;812.6464987730357;817.358373676661;4704.600108990774;4859.708417494961;4961.5286547777905;5007.699538027658;5124.846254612367;4772.427308722976;5572.86067848307;5598.964191624563;5616.69108199053;5620;4427.623651989553;4823.758812059383;4918.213085906238;5058.873528161826;5152.871178220575;2598.6621739174166;2831.329515585773;2890.7398334149607;2924.707529247101;2940.911772646781;5094.949050509522;5751.798687045934;5798.9420105799245;5809.651420914759;5816.208220054397;4854.466008739036;4968.080905032581;5271.920921186185;5541.251930989345;5767.068032209257;5021.748912554358;5157.948420515821;5788.652680839163;5789.478946894799;5815.331236907725;4554.772261386851;4645.140648979815;5372.435894231049;5612.663240205601;5638.5489160867855;5130.58955283569;5539.529140022982;5562.332520097452;5573.247611572386;5635.971820140873;5152.871178220575;5162.586993040472;5855.385184555731;5892.204552385373;5906.26171728534;3814.580396156476;3831.272058308912;3857.672097871712;3865.130345672201;3894.3768996960353;4715.952840471688;5108.8249702569365;5321.8180545975965;5426.918596221139;5593.818410819865;5110.233464980282;5237.507310416513;5465.453454654503;5480.671159730429;5482.862928426821;4329.264025115801;4480.686351955332;5248.606354523502;5513.58648101386;5559.388467268516;4284.928767808048;4499.055198408371;5167.844964651066;5291;5390.299261096189;4949.688332591794;5149.742512874416;5348.5988550858065;5359.624826262124;5413.56691464674;4912.435069966999;4944.802207911644;5290.68255904647;5313.654612450178;5317.282166907418 08:28:10 INFO - PROCESS | 1661 | |5;yui.html;513.5019031539408;644.5906849150788;651.3649192037764;760.5132715062362;800.487687879757;15124.546263612081;15334.53996380109;15628.609284767857;15642.06147631143;15658.060516369025;14341.713165736679;14427.206503642296;15164.696706065872;15823.525294241192;15873.444429444971;714.0503130836001;915.3409545127497;924.1405492891608;925.2597921662676;926.3839546701436;736.1276886889027;886.9645214191424;911.9908800912006;913.4884464699757;914.3873604684844;965.512416229803;967.4630580028081;967.9419234845897;968.3028219681818;968.428627110005;639.1978067525226;747.7420289999941;879.9924086920472;907.6460180529599;964.9372790768577;692.6675195905993;713.2297119111347;713.9790100156773;716.5521549031855;717.0857157124708;15083.170425626586;15165;15187.088774673555;15187.544373668808;15194.696106077816;12067.577634782829;12150.4532296046;12328.137030407875;12367.93816030914;12551;10803.13574914005;10826.296290741077;11057.391843448688;11162.162837787198;11458.508877147371;4031.697622678311;4038.9798051009852;4040.8181631826637;4149.564295748933;4176.91646167078;84326;84710;85237.86905327355;86027.85859471529;86621;7869.449138560246;9179.449233046038;9226.26189904806;9323.860142097874;9965.598263059293;20702.378928631864;20722;20723.4819129523;20753;20777.06503207359;7215.206327303992;7430.145533263744;7462.70149194037;7609.733659321897;7659.310662040331;3961.1087505311652;4022.1955608878343;4039.364057556694;4039.838406463767;4173.5409104998425;3388.525606415104;3487.616362200156;3488.8255587221047;3491.1970246843366;3503.089196808848;587.820714682026;606.1877084706515;612.2347066167291;612.9724162412613;613.089562000429;469.99765001174774;546.5162084939409;552.0725181694792;581.6364772017489;588.0121396054669;6594.967025164843;6749.223839258548;6772.864542709118;6785.9660701696175;6953.33943275388;480.91908981609186;481.28529134235635;536.8496820890157;563.4281204577358;571.6055921414211;3041.863116159778;3661.139632186434;3733.4586484960073;3780.111673756665;3866.0721426857353;69561;73395;74080.62959685276;80456;91279.54360228157;71882.2811771886;73320.90018649725;74666.25333746593;76083.23916760873;77449.22550774533;68994;69229.30770692229;70330.94503582451;70439;71645.28354716387;8106.513609183467;8222.83554328922;8230.588470576567;8417.789555261168;8421.567599678503;39644.603553964094;41501.75494735163;41699.37450938299;41940;42422.575774243094;44808;54130.18804717853;54292.72853635865;55633.72183139058;58559.70720146372;10406.531706073094;10550.261481696207;10551;10602.20483463728;11047.502862371051;11655.560946148089;11761.294322340687;11859.229150105215;11876.168668193144;12314.630561083182;11147;11188.776224475301;11211.55153793839;11381.943090284496;11481.770364592829;11051.082010765382;11054.613088541862;11116.61091861781;11197.160212983896;11534.711632209262;10844.349339039367;10922.563097476012;11236.775264494829;11274.549018039515;11408.315501069963;9330.533473326172;9359.906400936175;9370.906290937004;9452.858207127036;9486.762830929007;9453.621855125717;9592.424454532471;9606;9629.277804164856;9875.093890991948 08:28:10 INFO - PROCESS | 1661 | __end_tp_report 08:28:10 INFO - PROCESS | 1661 | __start_cc_report 08:28:10 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,1175 08:28:10 INFO - PROCESS | 1661 | __end_cc_report 08:28:10 INFO - PROCESS | 1661 | __startTimestamp1462980490942__endTimestamp 08:28:10 INFO - PROCESS | 1661 | 08:28:10 INFO - PROCESS | 1661 | ------- Summary: start ------- 08:28:10 INFO - PROCESS | 1661 | Number of tests: 6 08:28:10 INFO - PROCESS | 1661 | 08:28:10 INFO - PROCESS | 1661 | [#0] dojo.html Cycles:165 Average:5735.60 Median:5964.28 stddev:667.67 (11.2%) stddev-sans-first:644.90 08:28:10 INFO - PROCESS | 1661 | Values: 3436.7 3457.2 3465.3 3493.4 3516.0 5630.7 5657.2 5658.5 5725.8 5796.3 4558.7 5548.9 5610.2 5721.7 5739.4 4076.9 5093.8 5123.6 5172.5 5177.7 4491.1 5176.2 5256.4 5298.4 5391.5 5084.2 5086.7 5155.8 5228.6 5251.2 5231.0 5234.0 5238.5 5255.2 5272.0 5177.2 5181.0 5182.0 5212.6 5254.2 5467.9 5589.5 5627.2 5631.2 5651.9 6260.9 6272.2 6285.2 6292.0 6292.4 5826.3 6144.4 6277.8 6367.9 6368.1 5653.0 5709.9 5787.6 5788.4 5840.5 6289.4 6292.6 6295.2 6306.8 6326.1 5962.4 6175.5 6182.5 6191.3 6192.7 6193.4 6216.2 6226.2 6281.6 6297.0 6214.1 6229.0 6229.4 6241.3 6243.2 6089.2 6288.4 6299.3 6310.2 6311.1 4860.5 4918.3 4923.8 4942.7 4970.0 4399.5 4405.1 4406.6 4414.2 4433.2 5526.2 5760.7 5778.4 5807.6 5841.7 6160.4 6220.8 6235.4 6274.3 6324.8 5761.1 5768.3 5786.9 5798.9 5830.9 5294.4 5397.4 5410.5 5420.4 5425.1 6263.4 6286.6 6332.8 6349.0 6418.8 6293.7 6308.0 6312.4 6323.3 6324.3 6315.7 6335.6 6381.8 6398.2 6402.7 6090.8 6101.2 6116.7 6200.8 6205.9 6295.5 6301.7 6302.1 6322.1 6325.6 6145.6 6160.3 6164.3 6168.1 6175.3 4707.5 5881.6 5964.3 6045.0 6100.9 6004.2 6012.8 6039.3 6041.4 6096.5 6149.1 6157.5 6158.4 6160.0 6171.0 5846.7 5866.0 5935.9 6014.3 6064.3 08:28:10 INFO - PROCESS | 1661 | 08:28:10 INFO - PROCESS | 1661 | [#1] ext.html Cycles:180 Average:68557.48 Median:39374.31 stddev:148457.51 (377.0%) stddev-sans-first:148786.02 08:28:10 INFO - PROCESS | 1661 | Values: 497.1 502.4 521.0 525.1 529.3 25295.6 31309.2 43163.1 43803.3 43814.0 42692.8 45089.9 45230.5 45242.8 45305.9 8266.3 8395.0 9879.2 9900.7 9937.0 9612.0 9736.4 9741.8 9752.6 9760.7 9785.3 9809.2 9815.2 9819.7 9825.5 9732.9 9797.9 9800.6 9807.4 9809.3 9728.6 9799.5 9811.6 9812.8 9817.2 41803.6 42265.9 42349.0 42352.4 42361.0 2801.3 3433.6 3597.6 3781.4 4079.7 29161.0 29362.3 29576.3 29581.6 29886.0 2635.5 2656.3 2706.5 2740.2 2761.0 503052.0 505636.0 507903.5 508041.0 509059.0 65487.7 66135.3 66141.0 66150.7 66288.7 36784.6 37607.1 37640.8 38662.2 38681.2 15407.8 16540.8 17182.6 17223.9 17361.3 13009.3 13107.3 13118.7 13120.3 13131.9 16831.2 20062.3 20159.8 20185.8 20275.0 8003.7 8012.2 8049.8 8096.6 8109.7 953.9 968.7 994.6 1080.6 1119.9 24951.8 26631.9 28003.0 29521.1 30451.8 1042.9 1070.3 1161.5 1173.6 1187.5 15336.9 15367.8 15477.0 15521.6 15691.8 626837.0 636044.0 642320.0 642792.0 644071.0 61135.1 61580.0 61623.1 61742.0 61826.4 1322.7 1359.9 1401.9 1474.1 1501.2 26261.3 29962.3 30666.8 30757.1 31307.1 51918.2 52309.5 52467.2 52551.2 52556.7 509542.0 512864.0 513506.0 513644.0 513954.0 27026.1 27925.7 29258.3 29299.9 29324.4 45912.0 46649.1 46797.8 47585.7 48312.5 37539.8 38742.6 39060.6 39566.2 39811.8 37733.4 37733.9 37883.6 38571.0 38684.6 37951.1 38305.8 38447.1 38448.4 39182.4 32484.5 32745.0 33470.7 33760.7 33812.7 22754.3 24772.5 25196.7 25256.6 25703.7 08:28:10 INFO - PROCESS | 1661 | 08:28:10 INFO - PROCESS | 1661 | [#2] jquery.html Cycles:180 Average:35165.17 Median:11872.12 stddev:100876.44 (849.7%) stddev-sans-first:101125.62 08:28:10 INFO - PROCESS | 1661 | Values: 385.0 390.6 394.7 398.9 418.0 12957.9 19820.6 20984.7 21072.8 21089.5 15926.1 17289.0 20095.9 20231.5 20494.9 5946.1 6145.8 6148.3 6179.8 6218.3 6009.5 6094.7 6109.1 6112.9 6175.7 5918.5 6057.7 6062.8 6075.8 6113.8 5609.2 5939.2 6023.5 6079.3 6090.5 5260.3 5293.0 5295.8 5298.8 5304.8 25498.0 26153.7 26301.3 26492.6 26910.0 2182.0 2248.6 2374.7 2494.3 2633.5 13992.8 14506.5 14619.0 14941.7 15054.2 1333.7 1564.6 1734.4 1819.3 1883.4 104939.0 109799.0 112943.2 115388.0 116000.0 7945.3 10745.8 11087.3 11125.8 11344.7 13914.6 14033.9 14047.1 14057.1 14058.9 7591.6 7738.2 8682.9 8819.4 8862.7 7369.4 8898.2 8912.6 8914.2 9036.7 6648.5 6756.3 6770.7 6776.1 6844.7 1890.2 1927.4 1928.7 1931.9 1964.7 880.3 973.9 1007.9 1015.1 1049.1 20694.8 20733.4 21281.4 21528.8 21562.2 997.2 1008.4 1026.2 1028.0 1042.2 9885.3 10778.5 11401.0 11705.2 11776.8 581603.0 592771.0 592779.0 617046.0 631684.0 136787.0 138109.6 138280.0 140127.0 144590.0 1065.1 1068.8 1093.9 1094.7 1095.6 17412.1 18073.4 18378.6 18412.7 18784.0 34199.5 37450.6 43355.3 51372.7 56563.7 81276.0 81305.0 81778.0 81859.2 82342.6 7645.9 7870.0 7917.2 8032.2 8295.1 8909.2 11624.9 11906.3 12082.2 12347.5 10445.0 10604.0 10630.7 10747.9 10825.5 10205.5 10291.6 10372.4 10534.3 10602.0 9209.9 10611.2 10628.9 10642.8 11019.9 7453.3 7752.7 7837.3 7845.1 8165.8 7868.8 9469.0 10578.5 10824.6 10853.7 08:28:10 INFO - PROCESS | 1661 | 08:28:10 INFO - PROCESS | 1661 | [#3] mootools.html Cycles:180 Average:5799.10 Median:4770.69 stddev:3270.52 (68.6%) stddev-sans-first:3263.22 08:28:10 INFO - PROCESS | 1661 | Values: 1430.2 1468.4 1615.3 1828.0 1986.5 7092.5 8006.3 8872.3 8875.4 9028.9 8675.7 8886.7 8898.4 8916.4 8932.1 2159.1 2366.6 2417.9 2469.2 2472.8 2449.8 2494.7 2497.5 2498.0 2500.5 2404.4 2468.7 2470.4 2473.6 2474.8 2143.3 2376.2 2422.9 2439.9 2448.9 2431.3 2431.9 2458.7 2461.6 2463.4 8874.6 8941.6 8957.0 8959.6 8964.3 5343.2 5511.4 5516.5 5520.9 5523.1 3537.7 3570.8 3614.6 3647.7 3685.3 1044.1 1057.2 1058.8 1061.5 1066.5 11923.6 12189.9 12190.7 12193.3 12198.9 4823.0 4827.1 4930.4 4941.5 4972.2 10306.4 10314.5 10325.4 10340.5 10363.8 5460.8 5486.1 5505.6 5505.6 5514.0 4261.9 4454.2 4565.9 4595.4 4628.4 4080.3 4100.3 4161.5 4184.8 4203.2 1320.0 1368.9 1372.8 1375.6 1387.4 831.6 932.1 948.7 965.9 987.0 5538.7 5961.9 6045.6 6091.4 6258.8 433.5 455.7 881.8 973.9 1059.5 3318.7 4009.4 4047.3 4070.6 4096.9 10775.8 10990.5 11039.0 11157.3 11192.8 9989.6 11154.9 11157.4 11158.7 11170.9 8192.5 8291.0 8343.9 8378.8 8415.2 5448.4 5540.3 5849.4 5881.2 5982.0 9889.2 10206.9 10238.1 10330.3 10353.2 8301.5 9547.7 10155.3 10436.2 10478.6 7026.4 7048.4 7535.7 7665.7 7687.7 8524.9 8556.2 8590.6 8612.7 8626.8 7361.3 7570.3 7609.0 7629.6 7711.8 7383.2 7472.3 7526.4 7621.7 7644.9 7157.0 7251.1 7254.7 7255.2 7264.1 4860.2 5000.0 5074.2 5118.7 5188.0 4714.7 4757.5 4783.9 4787.3 4970.8 08:28:10 INFO - PROCESS | 1661 | 08:28:10 INFO - PROCESS | 1661 | [#4] prototype.html Cycles:180 Average:4389.53 Median:4931.51 stddev:1331.88 (27.0%) stddev-sans-first:1299.85 08:28:10 INFO - PROCESS | 1661 | Values: 304.6 313.5 314.2 320.3 324.8 4651.4 5178.5 5201.5 5235.7 5275.2 4257.9 5146.5 5172.5 5205.7 5237.9 3754.4 3818.3 3897.4 3974.3 3987.7 3710.7 3942.3 3969.3 3998.9 4077.1 3974.4 3976.1 3998.4 4014.5 4032.6 3560.0 3729.8 3750.8 3884.9 3994.7 3449.4 3461.2 3485.3 3491.6 3540.8 4002.2 4402.6 4806.5 4962.8 4988.4 4259.6 4509.5 4551.0 4562.6 4572.6 4532.6 5363.0 5421.0 5435.4 5468.9 3832.5 3851.9 3868.1 3869.4 3896.4 5287.7 5450.7 5529.8 5635.7 5708.0 3386.8 3638.5 3766.2 3787.5 3803.3 3249.2 3339.5 3856.4 3856.7 3862.3 4303.5 4453.7 4517.6 4530.9 4531.1 5104.9 5243.3 5288.9 5416.8 5653.8 1120.9 1288.7 1290.5 1310.8 1321.3 760.8 765.6 791.3 812.6 817.4 4704.6 4859.7 4961.5 5007.7 5124.8 4772.4 5572.9 5599.0 5616.7 5620.0 4427.6 4823.8 4918.2 5058.9 5152.9 2598.7 2831.3 2890.7 2924.7 2940.9 5094.9 5751.8 5798.9 5809.7 5816.2 4854.5 4968.1 5271.9 5541.3 5767.1 5021.7 5157.9 5788.7 5789.5 5815.3 4554.8 4645.1 5372.4 5612.7 5638.5 5130.6 5539.5 5562.3 5573.2 5636.0 5152.9 5162.6 5855.4 5892.2 5906.3 3814.6 3831.3 3857.7 3865.1 3894.4 4716.0 5108.8 5321.8 5426.9 5593.8 5110.2 5237.5 5465.5 5480.7 5482.9 4329.3 4480.7 5248.6 5513.6 5559.4 4284.9 4499.1 5167.8 5291.0 5390.3 4949.7 5149.7 5348.6 5359.6 5413.6 4912.4 4944.8 5290.7 5313.7 5317.3 08:28:10 INFO - PROCESS | 1661 | 08:28:10 INFO - PROCESS | 1661 | [#5] yui.html Cycles:180 Average:17260.42 Median:28098.57 stddev:23813.65 (84.8%) stddev-sans-first:23847.25 08:28:10 INFO - PROCESS | 1661 | Values: 513.5 644.6 651.4 760.5 800.5 15124.5 15334.5 15628.6 15642.1 15658.1 14341.7 14427.2 15164.7 15823.5 15873.4 714.1 915.3 924.1 925.3 926.4 736.1 887.0 912.0 913.5 914.4 965.5 967.5 967.9 968.3 968.4 639.2 747.7 880.0 907.6 964.9 692.7 713.2 714.0 716.6 717.1 15083.2 15165.0 15187.1 15187.5 15194.7 12067.6 12150.5 12328.1 12367.9 12551.0 10803.1 10826.3 11057.4 11162.2 11458.5 4031.7 4039.0 4040.8 4149.6 4176.9 84326.0 84710.0 85237.9 86027.9 86621.0 7869.4 9179.4 9226.3 9323.9 9965.6 20702.4 20722.0 20723.5 20753.0 20777.1 7215.2 7430.1 7462.7 7609.7 7659.3 3961.1 4022.2 4039.4 4039.8 4173.5 3388.5 3487.6 3488.8 3491.2 3503.1 587.8 606.2 612.2 613.0 613.1 470.0 546.5 552.1 581.6 588.0 6595.0 6749.2 6772.9 6786.0 6953.3 480.9 481.3 536.8 563.4 571.6 3041.9 3661.1 3733.5 3780.1 3866.1 69561.0 73395.0 74080.6 80456.0 91279.5 71882.3 73320.9 74666.3 76083.2 77449.2 68994.0 69229.3 70330.9 70439.0 71645.3 8106.5 8222.8 8230.6 8417.8 8421.6 39644.6 41501.8 41699.4 41940.0 42422.6 44808.0 54130.2 54292.7 55633.7 58559.7 10406.5 10550.3 10551.0 10602.2 11047.5 11655.6 11761.3 11859.2 11876.2 12314.6 11147.0 11188.8 11211.6 11381.9 11481.8 11051.1 11054.6 11116.6 11197.2 11534.7 10844.3 10922.6 11236.8 11274.5 11408.3 9330.5 9359.9 9370.9 9452.9 9486.8 9453.6 9592.4 9606.0 9629.3 9875.1 08:28:10 INFO - PROCESS | 1661 | -------- Summary: end -------- 08:28:10 INFO - PROCESS | 1661 | 08:28:11 INFO - TEST-INFO | 1661: exit 0 08:28:11 INFO - TEST-OK | dromaeo_css | took 1115920ms 08:28:11 INFO - TEST-START | kraken 08:28:11 INFO - operating with platform_type : mac_ 08:28:11 INFO - Initialising browser for kraken test... 08:28:11 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/tmpvYMd_m/profile http://localhost:49275/getInfo.html) 08:28:13 INFO - PROCESS | 1670 | __metrics Screen width/height:1600/1200 08:28:13 INFO - PROCESS | 1670 | colorDepth:24 08:28:13 INFO - PROCESS | 1670 | Browser inner width/height: 1024/689 08:28:13 INFO - PROCESS | 1670 | __metrics 08:28:13 INFO - PROCESS | 1670 | 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" 08:28:13 ERROR - PROCESS | 1670 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:28:14 INFO - TEST-INFO | 1670: exit 0 08:28:14 INFO - Browser initialized. 08:28:14 INFO - Running cycle 1/1 for kraken test... 08:28:14 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/tmpvYMd_m/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:28:26 INFO - PROCESS | 1672 | RSS: Main: 238682112 08:28:26 INFO - PROCESS | 1672 | 08:28:29 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 08:28:29 INFO - PROCESS | 1672 | RSS: Main: 286158848 08:28:29 INFO - PROCESS | 1672 | 08:28:32 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 08:28:33 INFO - PROCESS | 1672 | RSS: Main: 295735296 08:28:33 INFO - PROCESS | 1672 | 08:28:36 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 08:28:37 INFO - PROCESS | 1672 | RSS: Main: 384245760 08:28:37 INFO - PROCESS | 1672 | 08:28:39 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 08:28:40 INFO - PROCESS | 1672 | RSS: Main: 284352512 08:28:40 INFO - PROCESS | 1672 | 08:28:42 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:28:42 INFO - PROCESS | 1672 | RSS: Main: 288264192 08:28:42 INFO - PROCESS | 1672 | 08:28:45 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 08:28:46 INFO - PROCESS | 1672 | RSS: Main: 324517888 08:28:46 INFO - PROCESS | 1672 | 08:28:49 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 08:28:50 INFO - PROCESS | 1672 | RSS: Main: 328208384 08:28:50 INFO - PROCESS | 1672 | 08:28:53 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 08:28:53 INFO - PROCESS | 1672 | RSS: Main: 326017024 08:28:53 INFO - PROCESS | 1672 | 08:28:55 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:28:56 INFO - PROCESS | 1672 | RSS: Main: 315613184 08:28:56 INFO - PROCESS | 1672 | 08:28:58 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:28:58 INFO - PROCESS | 1672 | RSS: Main: 322748416 08:28:58 INFO - PROCESS | 1672 | 08:29:01 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:29:01 INFO - PROCESS | 1672 | RSS: Main: 324550656 08:29:01 INFO - PROCESS | 1672 | 08:29:04 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:29:05 INFO - PROCESS | 1672 | RSS: Main: 328863744 08:29:05 INFO - PROCESS | 1672 | 08:29:08 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:29:09 INFO - PROCESS | 1672 | RSS: Main: 326389760 08:29:09 INFO - PROCESS | 1672 | 08:29:10 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 08:29:11 INFO - PROCESS | 1672 | RSS: Main: 373358592 08:29:11 INFO - PROCESS | 1672 | 08:29:11 INFO - PROCESS | 1672 | __start_tp_report 08:29:11 INFO - PROCESS | 1672 | _x_x_mozilla_page_load 08:29:11 INFO - PROCESS | 1672 | _x_x_mozilla_page_load_details 08:29:11 INFO - PROCESS | 1672 | |i|pagename|runs| 08:29:11 INFO - PROCESS | 1672 | |0;ai-astar;112;106;110;111;111;113;111;112;110;111 08:29:11 INFO - PROCESS | 1672 | |1;audio-beat-detection;156;118;141;202;118;140;138;115;168;115 08:29:11 INFO - PROCESS | 1672 | |2;audio-dft;183;183;186;183;186;186;185;211;185;186 08:29:11 INFO - PROCESS | 1672 | |3;audio-fft;77;72;58;125;57;61;58;59;118;57 08:29:11 INFO - PROCESS | 1672 | |4;audio-oscillator;91;99;131;79;79;78;100;80;77;101 08:29:11 INFO - PROCESS | 1672 | |5;imaging-gaussian-blur;89;86;88;85;88;85;89;85;88;84 08:29:11 INFO - PROCESS | 1672 | |6;imaging-darkroom;93;94;94;95;93;93;94;94;94;93 08:29:11 INFO - PROCESS | 1672 | |7;imaging-desaturate;82;85;83;86;85;86;84;85;85;113 08:29:11 INFO - PROCESS | 1672 | |8;json-parse-financial;61;61;60;59;59;59;59;118;56;57 08:29:11 INFO - PROCESS | 1672 | |9;json-stringify-tinderbox;62;70;78;74;70;67;66;68;68;69 08:29:11 INFO - PROCESS | 1672 | |10;stanford-crypto-aes;99;107;104;71;100;73;81;96;107;105 08:29:11 INFO - PROCESS | 1672 | |11;stanford-crypto-ccm;153;146;135;133;138;136;140;156;249;154 08:29:11 INFO - PROCESS | 1672 | |12;stanford-crypto-pbkdf2;168;167;167;163;166;170;166;166;166;164 08:29:11 INFO - PROCESS | 1672 | |13;stanford-crypto-sha256-iterative;68;67;67;66;66;65;67;64;66;65 08:29:11 INFO - PROCESS | 1672 | __end_tp_report 08:29:11 INFO - PROCESS | 1672 | __start_cc_report 08:29:11 INFO - PROCESS | 1672 | _x_x_mozilla_cycle_collect,2277 08:29:11 INFO - PROCESS | 1672 | __end_cc_report 08:29:11 INFO - PROCESS | 1672 | __startTimestamp1462980551150__endTimestamp 08:29:11 INFO - PROCESS | 1672 | 08:29:11 INFO - PROCESS | 1672 | ------- Summary: start ------- 08:29:11 INFO - PROCESS | 1672 | Number of tests: 14 08:29:11 INFO - PROCESS | 1672 | 08:29:11 INFO - PROCESS | 1672 | [#0] ai-astar Cycles:10 Average:110.70 Median:111.00 stddev:1.89 (1.7%) stddev-sans-first:1.94 08:29:11 INFO - PROCESS | 1672 | Values: 112.0 106.0 110.0 111.0 111.0 113.0 111.0 112.0 110.0 111.0 08:29:11 INFO - PROCESS | 1672 | 08:29:11 INFO - PROCESS | 1672 | [#1] audio-beat-detection Cycles:10 Average:141.10 Median:140.50 stddev:28.08 (20.0%) stddev-sans-first:29.26 08:29:11 INFO - PROCESS | 1672 | Values: 156.0 118.0 141.0 202.0 118.0 140.0 138.0 115.0 168.0 115.0 08:29:11 INFO - PROCESS | 1672 | 08:29:11 INFO - PROCESS | 1672 | [#2] audio-dft Cycles:10 Average:187.40 Median:186.00 stddev:8.40 (4.5%) stddev-sans-first:8.75 08:29:11 INFO - PROCESS | 1672 | Values: 183.0 183.0 186.0 183.0 186.0 186.0 185.0 211.0 185.0 186.0 08:29:11 INFO - PROCESS | 1672 | 08:29:11 INFO - PROCESS | 1672 | [#3] audio-fft Cycles:10 Average:74.20 Median:58.50 stddev:25.89 (44.3%) stddev-sans-first:27.44 08:29:11 INFO - PROCESS | 1672 | Values: 77.0 72.0 58.0 125.0 57.0 61.0 58.0 59.0 118.0 57.0 08:29:11 INFO - PROCESS | 1672 | 08:29:11 INFO - PROCESS | 1672 | [#4] audio-oscillator Cycles:10 Average:91.50 Median:79.00 stddev:17.05 (21.6%) stddev-sans-first:18.08 08:29:11 INFO - PROCESS | 1672 | Values: 91.0 99.0 131.0 79.0 79.0 78.0 100.0 80.0 77.0 101.0 08:29:11 INFO - PROCESS | 1672 | 08:29:11 INFO - PROCESS | 1672 | [#5] imaging-gaussian-blur Cycles:10 Average:86.70 Median:88.00 stddev:1.89 (2.1%) stddev-sans-first:1.81 08:29:11 INFO - PROCESS | 1672 | Values: 89.0 86.0 88.0 85.0 88.0 85.0 89.0 85.0 88.0 84.0 08:29:11 INFO - PROCESS | 1672 | 08:29:11 INFO - PROCESS | 1672 | [#6] imaging-darkroom Cycles:10 Average:93.70 Median:94.00 stddev:0.67 (0.7%) stddev-sans-first:0.67 08:29:11 INFO - PROCESS | 1672 | Values: 93.0 94.0 94.0 95.0 93.0 93.0 94.0 94.0 94.0 93.0 08:29:11 INFO - PROCESS | 1672 | 08:29:11 INFO - PROCESS | 1672 | [#7] imaging-desaturate Cycles:10 Average:87.40 Median:85.00 stddev:9.08 (10.7%) stddev-sans-first:9.42 08:29:11 INFO - PROCESS | 1672 | Values: 82.0 85.0 83.0 86.0 85.0 86.0 84.0 85.0 85.0 113.0 08:29:11 INFO - PROCESS | 1672 | 08:29:11 INFO - PROCESS | 1672 | [#8] json-parse-financial Cycles:10 Average:64.90 Median:59.00 stddev:18.72 (31.7%) stddev-sans-first:19.81 08:29:11 INFO - PROCESS | 1672 | Values: 61.0 61.0 60.0 59.0 59.0 59.0 59.0 118.0 56.0 57.0 08:29:11 INFO - PROCESS | 1672 | 08:29:11 INFO - PROCESS | 1672 | [#9] json-stringify-tinderbox Cycles:10 Average:69.20 Median:69.50 stddev:4.37 (6.3%) stddev-sans-first:3.77 08:29:11 INFO - PROCESS | 1672 | Values: 62.0 70.0 78.0 74.0 70.0 67.0 66.0 68.0 68.0 69.0 08:29:11 INFO - PROCESS | 1672 | 08:29:11 INFO - PROCESS | 1672 | [#10] stanford-crypto-aes Cycles:10 Average:94.30 Median:72.00 stddev:13.99 (19.4%) stddev-sans-first:14.74 08:29:11 INFO - PROCESS | 1672 | Values: 99.0 107.0 104.0 71.0 100.0 73.0 81.0 96.0 107.0 105.0 08:29:11 INFO - PROCESS | 1672 | 08:29:11 INFO - PROCESS | 1672 | [#11] stanford-crypto-ccm Cycles:10 Average:154.00 Median:149.50 stddev:34.44 (23.0%) stddev-sans-first:36.52 08:29:11 INFO - PROCESS | 1672 | Values: 153.0 146.0 135.0 133.0 138.0 136.0 140.0 156.0 249.0 154.0 08:29:11 INFO - PROCESS | 1672 | 08:29:11 INFO - PROCESS | 1672 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.30 Median:166.50 stddev:1.95 (1.2%) stddev-sans-first:1.96 08:29:11 INFO - PROCESS | 1672 | Values: 168.0 167.0 167.0 163.0 166.0 170.0 166.0 166.0 166.0 164.0 08:29:11 INFO - PROCESS | 1672 | 08:29:11 INFO - PROCESS | 1672 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:66.50 stddev:1.20 (1.8%) stddev-sans-first:1.05 08:29:11 INFO - PROCESS | 1672 | Values: 68.0 67.0 67.0 66.0 66.0 65.0 67.0 64.0 66.0 65.0 08:29:11 INFO - PROCESS | 1672 | -------- Summary: end -------- 08:29:11 INFO - PROCESS | 1672 | 08:29:11 INFO - TEST-INFO | 1672: exit 0 08:29:11 INFO - TEST-OK | kraken | took 60452ms 08:29:11 INFO - SUITE-END | took 1176s 08:29:12 INFO - Completed test suite (00:19:36) 08:29:12 INFO - javascript benchmark 08:29:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3436.7250619950405, 3457.1875609231834, 3465.2549701814137, 3493.4061209594374, 3516.0, 5630.746616402262, 5657.207990881275, 5658.519025882805, 5725.828225153242, 5796.333421656516, 4558.703684260521, 5548.944510554893, 5610.24261724667, 5721.656700597957, 5739.368669446368, 4076.857309994151, 5093.796248150088, 5123.5901127909865, 5172.456892026339, 5177.715225662587, 4491.101779644068, 5176.171812509999, 5256.42179360272, 5298.390685071208, 5391.514763671269, 5084.211947148179, 5086.745662716849, 5155.819546315898, 5228.633995620308, 5251.159814429673, 5231.0, 5233.9738301308635, 5238.5023422774775, 5255.159174532057, 5272.0, 5177.171652535576, 5181.0, 5182.015417070745, 5212.55693266069, 5254.185601231777, 5467.917981230285, 5589.524890384322, 5627.212190293362, 5631.155326701028, 5651.886962260732, 6260.906086408708, 6272.15325930998, 6285.214348206474, 6291.96854015727, 6292.402221788922, 5826.329972053226, 6144.38556144382, 6277.843053923642, 6367.936320636781, 6368.10846481493, 5652.971735141298, 5709.857253568694, 5787.565932554991, 5788.421157884178, 5840.474357307863, 6289.433950944437, 6292.559520833498, 6295.213098362705, 6306.8423289417215, 6326.114343991845, 5962.403759624003, 6175.505959523317, 6182.505399568024, 6191.318954914956, 6192.659403732748, 6193.411625895533, 6216.160818289563, 6226.1905952225925, 6281.560290779693, 6297.023961286008, 6214.067889816473, 6229.0, 6229.408206220402, 6241.282252540925, 6243.219597550306, 6089.1648975218495, 6288.402601752826, 6299.307076221612, 6310.1796766420075, 6311.116443697886, 4860.513948605111, 4918.262260660929, 4923.75381230937, 4942.678725882806, 4969.975150124226, 4399.472063352354, 4405.07493426384, 4406.603405693503, 4414.161309351277, 4433.179861725591, 5526.171074338881, 5760.683162426107, 5778.3643799182055, 5807.593468457168, 5841.678707671119, 6160.353162917919, 6220.813375598739, 6235.376462353819, 6274.309825919146, 6324.841878953063, 5761.107028410603, 5768.307803063659, 5786.913196302059, 5798.9130163047585, 5830.883382332415, 5294.41761406245, 5397.3523177218985, 5410.513053825174, 5420.376656684453, 5425.13197888336, 6263.3736626337, 6286.622802631857, 6332.841678958063, 6348.968255158695, 6418.807435776934, 6293.685315734287, 6308.022256550242, 6312.3687631238345, 6323.336049714714, 6324.304326523987, 6315.715792789244, 6335.556511044183, 6381.8085457436355, 6398.168238129014, 6402.743890244338, 6090.786822461193, 6101.206843110367, 6116.701002199415, 6200.782972595937, 6205.875882482415, 6295.464885484785, 6301.716422761078, 6302.149209856794, 6322.146510221061, 6325.620462772248, 6145.569810113428, 6160.291566469913, 6164.321924588381, 6168.1056246844855, 6175.289841668266, 4707.4821769606, 5881.617694849821, 5964.284285885721, 6045.032794752994, 6100.8779824404155, 6004.219451471281, 6012.759489620541, 6039.275286965592, 6041.412911084009, 6096.542759292982, 6149.139120523219, 6157.476614487819, 6158.445739883432, 6160.0, 6171.0, 5846.736896839738, 5866.0, 5935.881282374415, 6014.338422773404, 6064.302605200459], "value": 5693.462534565794, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [497.0580749479736, 502.3870877529414, 520.9502851753716, 525.0784872548677, 529.3409704917378, 25295.620565691508, 31309.21726956827, 43163.13673726524, 43803.342949855774, 43814.0, 42692.78653606735, 45089.8727531811, 45230.54769452305, 45242.7737861311, 45305.8673533161, 8266.338692904568, 8395.0, 9879.209663226924, 9900.702978910644, 9937.0, 9611.951940240324, 9736.367136136163, 9741.756456088584, 9752.609895604168, 9760.658376956808, 9785.26610504215, 9809.215262778996, 9815.165710914582, 9819.656312029081, 9825.459599722013, 9732.854007189897, 9797.90202097977, 9800.607975681, 9807.411555306704, 9809.264305177141, 9728.562214700356, 9799.461029643368, 9811.558479868423, 9812.754681132958, 9817.165540929029, 41803.58196418058, 42265.94335141615, 42349.0, 42352.36471452931, 42361.0, 2801.29967508123, 3433.5879694436826, 3597.568291805, 3781.4327850822597, 4079.669103425025, 29161.0, 29362.265943351587, 29576.260593485335, 29581.55627665587, 29886.0, 2635.486080214359, 2656.2828036430055, 2706.5398882190075, 2740.1916434651625, 2761.006037826381, 503052.0, 505636.0, 507903.4604826952, 508041.0, 509059.0, 65487.672561636886, 66135.33864661388, 66141.0, 66150.66924665346, 66288.66855665787, 36784.63215367812, 37607.059823504635, 37640.81179594085, 38662.22675546473, 38681.22637547233, 15407.768883466757, 16540.83459165393, 17182.570435739206, 17223.91388043052, 17361.3055477782, 13009.284489353176, 13107.279099649611, 13118.672033199246, 13120.343982800821, 13131.868681313255, 16831.24259408305, 20062.297819576244, 20159.798402016084, 20185.798142018684, 20274.986250687405, 8003.719869804529, 8012.158723333966, 8049.798755031171, 8096.595170241464, 8109.7161599343735, 953.909378609031, 968.7384406210284, 994.5922171909482, 1080.6325849211305, 1119.9360607422818, 24951.750482494943, 26631.866840665672, 28003.0, 29521.114366569036, 30451.757389735052, 1042.921780866438, 1070.2936062198912, 1161.5469966712988, 1173.559915031863, 1187.4715751490676, 15336.923315383352, 15367.846321536865, 15476.993995390263, 15521.611959701098, 15691.764623530657, 626837.0, 636044.0, 642320.0, 642792.0, 644071.0, 61135.08297375543, 61580.0, 61623.07565386523, 61742.0, 61826.381736182964, 1322.702391961801, 1359.9184048957095, 1401.8644897632541, 1474.108164560447, 1501.2118637715287, 26261.34346641349, 29962.25094372658, 30666.846665766527, 30757.077287681404, 31307.06078817639, 51918.221226682384, 52309.47690523046, 52467.212991805914, 52551.21173182482, 52556.737216313675, 509542.0, 512864.0, 513506.0, 513644.0, 513954.0, 27026.054088106823, 27925.720742792313, 29258.268543286587, 29299.85350073236, 29324.413511730072, 45912.0, 46649.06701866011, 46797.766011169726, 47585.66900317083, 48312.5168748308, 37539.81230093832, 38742.6125738739, 39060.609393905695, 39566.2086758269, 39811.805645830675, 37733.4339984906, 37733.867983960525, 37883.62116378801, 38571.0, 38684.6131538681, 37951.05122371963, 38305.80847095747, 38447.10507505365, 38448.423273651475, 39182.412263815495, 32484.51273230856, 32745.017649470556, 33470.665293346756, 33760.66239337575, 33812.661873381934, 22754.299692968354, 24772.504549909263, 25196.74803251944, 25256.62115068312, 25703.742962570883], "value": 19860.54339511812, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.0, 390.59378246623515, 394.74341677909337, 398.9381645844893, 418.01557332482, 12957.870421295784, 19820.60358792823, 20984.685229721566, 21072.789272107275, 21089.47276318093, 15926.124063176521, 17289.04910229937, 20095.889931557194, 20231.494212644655, 20494.897525512388, 5946.078357854529, 6145.754169833202, 6148.292946311164, 6179.81460556184, 6218.253809542861, 6009.5192384609345, 6094.695265236743, 6109.053096770008, 6112.938870611304, 6175.691215439232, 5918.526517878581, 6057.727402266886, 6062.818115456544, 6075.817725468243, 6113.755449782004, 5609.242752228438, 5939.227900372967, 6023.518118550505, 6079.270487541501, 6090.512758979293, 5260.316158899357, 5292.9735351323, 5295.7881684732565, 5298.81454149103, 5304.787808487695, 25498.0, 26153.73846261532, 26301.342466438302, 26492.60261096085, 26910.0, 2182.0, 2248.640217565198, 2374.7031621047367, 2494.3390001649577, 2633.5259653262406, 13992.790108148385, 14506.492272770507, 14619.0, 14941.70116597662, 15054.172020538866, 1333.7132516508998, 1564.608847788053, 1734.37562477508, 1819.3450357871156, 1883.3878989328387, 104939.0, 109799.0, 112943.17642058933, 115388.0, 116000.0, 7945.28492435672, 10745.785084298426, 11087.27932684389, 11125.777484450266, 11344.659660210207, 13914.582562523141, 14033.929830350782, 14047.08693934891, 14057.086289391156, 14058.859411405754, 7591.620418979029, 7738.226177382329, 8682.86975695365, 8819.426737262058, 8862.734117976472, 7369.4472914531625, 8898.154675305836, 8912.643494260286, 8914.242289405343, 9036.683716069905, 6648.501362397839, 6756.290589488131, 6770.661466926733, 6776.119104516381, 6844.657767111625, 1890.1777726688658, 1927.441042097804, 1928.7106933960017, 1931.903404829781, 1964.6856502959745, 880.2737741363272, 973.868527748759, 1007.8941711120374, 1015.1117771949545, 1049.1134990932649, 20694.793052069588, 20733.377998660064, 21281.36155915325, 21528.784712152992, 21562.245321413677, 997.2420960070253, 1008.4050410257983, 1026.235454586323, 1027.9537420816082, 1042.223543460127, 9885.308028437941, 10778.53805486883, 11401.0, 11705.239159454608, 11776.76446471083, 581603.0, 592771.0, 592779.0361048167, 617046.0, 631684.0, 136787.0, 138109.61890381167, 138280.0, 140127.0, 144590.0, 1065.1425602389995, 1068.8236440987305, 1093.8742044664966, 1094.68801391603, 1095.5727266366123, 17412.129393530526, 18073.36743213981, 18378.63242735111, 18412.723809142604, 18784.0, 34199.4870076954, 37450.625493744716, 43355.349669754345, 51372.74313628408, 56563.71718141383, 81276.0, 81305.0, 81778.0, 81859.18140818516, 82342.58828705818, 7645.885311720217, 7869.960650196713, 7917.208279172037, 8032.236937490929, 8295.129011453952, 8909.242714369091, 11624.883751162719, 11906.34515101643, 12082.214656047328, 12347.506099756269, 10445.0, 10604.045635892806, 10630.681079567625, 10747.946260268649, 10825.51285192184, 10205.540750666387, 10291.588336466757, 10372.429516376671, 10534.26260161812, 10602.045815876609, 9209.86185207209, 10611.151107911193, 10628.8937110631, 10642.787144257227, 11019.889801101886, 7453.2546745325035, 7752.689892404241, 7837.294643482114, 7845.058592968652, 8165.836683266182, 7868.84262314739, 9468.95265523668, 10578.471076446302, 10824.567017319534, 10853.674389768319], "value": 9651.48260790694, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1430.1633544376539, 1468.36126285066, 1615.345784957092, 1828.0, 1986.4604931178344, 7092.50352475327, 8006.279434850862, 8872.334574906883, 8875.378723489343, 9028.864567031484, 8675.739727808175, 8886.688965886195, 8898.37711360205, 8916.375853690244, 8932.106789321064, 2159.147136880931, 2366.5976783946685, 2417.9395515112087, 2469.1604854349516, 2472.814538909589, 2449.767272109156, 2494.738052504488, 2497.500499900018, 2497.962530562043, 2500.4874000829877, 2404.4349577849193, 2468.691413573303, 2470.3700556358103, 2473.628955656657, 2474.8391354561986, 2143.3141394753684, 2376.180217824865, 2422.8667423291713, 2439.8536087834786, 2448.8897999590063, 2431.346684254706, 2431.8784060797066, 2458.680371551705, 2461.618449140386, 2463.3964678653665, 8874.556272186364, 8941.64233430662, 8957.0, 8959.641614335418, 8964.327675424369, 5343.171808369709, 5511.366192887829, 5516.531094856942, 5520.917186242169, 5523.061079616475, 3537.7169826413824, 3570.7500474966764, 3614.6385361463645, 3647.6534729200685, 3685.299793039314, 1044.0812085364905, 1057.1701214546545, 1058.767071244325, 1061.4533515239657, 1066.4880857188516, 11923.642290731292, 12189.902908738168, 12190.695232619168, 12193.268403895707, 12198.939005304917, 4823.0, 4827.08285425768, 4930.383702037246, 4941.45643979162, 4972.204447288416, 10306.381617102998, 10314.48427578618, 10325.432101234353, 10340.482975851328, 10363.792724145474, 5460.836174914759, 5486.094794358886, 5505.614606977553, 5505.642133261325, 5514.035043867307, 4261.914761704748, 4454.242778727624, 4565.908681826411, 4595.391612935446, 4628.3983082199775, 4080.3063479208604, 4100.323446631332, 4161.542230354658, 4184.790760462026, 4203.222403855236, 1319.967000824987, 1368.9041767076408, 1372.7529044771836, 1375.559820857336, 1387.3548799808138, 831.5883637599427, 932.1424289653535, 948.6679662118204, 965.932384733076, 986.9753256168509, 5538.723063846792, 5961.926853166385, 6045.637261764308, 6091.390860913962, 6258.812235632939, 433.48415385690924, 455.6946845613447, 881.8015946412006, 973.8782652168479, 1059.5338051257422, 3318.7345012399433, 4009.4186342980106, 4047.2917239483563, 4070.5929407059057, 4096.877093687194, 10775.78448431027, 10990.505427255792, 11039.0, 11157.330560166416, 11192.776144477064, 9989.60041598328, 11154.944225278821, 11157.38634375101, 11158.665240042812, 11170.888291117148, 8192.5494097824, 8290.958545207235, 8343.916560834314, 8378.790530236793, 8415.242628163373, 5448.427915068861, 5540.32521451686, 5849.444302791228, 5881.235439392852, 5982.0, 9889.208863291064, 10206.948965255126, 10238.078572928473, 10330.276880618285, 10353.223508237063, 8301.543415111984, 9547.713568592953, 10155.289129761142, 10436.16510679159, 10478.580856765644, 7026.437884969088, 7048.365647091581, 7535.6985720570565, 7665.655045523076, 7687.692492300469, 8524.914750852413, 8556.229939305244, 8590.613422395882, 8612.741617751477, 8626.827463450822, 7361.30067643573, 7570.280823321776, 7608.997225525301, 7629.580373079534, 7711.768646940601, 7383.187849336676, 7472.29013243741, 7526.397888168824, 7621.733239336597, 7644.923550764644, 7156.964215178891, 7251.1298644160925, 7254.673539690621, 7255.238199988925, 7264.091988501437, 4860.246661767432, 5000.049990501939, 5074.238864170404, 5118.718470484159, 5188.0, 4714.740689262124, 4757.452892917358, 4783.928241076317, 4787.329773831736, 4970.826021089245], "value": 4643.991012912908, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.61922596754056, 313.4686706033273, 314.1580564088244, 320.3464931539659, 324.78564147662547, 4651.441826980758, 5178.5143131297, 5201.453847346031, 5235.738213089349, 5275.2087186921935, 4257.893552661185, 5146.511081447256, 5172.482751724826, 5205.713685747282, 5237.947620523803, 3754.3805272130066, 3818.2745278397015, 3897.376419772837, 3974.3243648579664, 3987.7407968482103, 3710.703143748494, 3942.2509723152652, 3969.340541836976, 3998.860039898604, 4077.143799802045, 3974.4301001104545, 3976.065624578222, 3998.3802510610903, 4014.538328092263, 4032.6169013943627, 3559.982200088983, 3729.776213427203, 3750.79370634615, 3884.9028774280587, 3994.6804255659476, 3449.413599688034, 3461.238527523941, 3485.3377858207114, 3491.5810102787573, 3540.7521473496868, 4002.1795531916177, 4402.625776808975, 4806.519348065201, 4962.801487940478, 4988.403710812541, 4259.5740425957465, 4509.4814096379005, 4550.977245113786, 4562.58936695699, 4572.611328037087, 4532.592066713979, 5363.034653762321, 5420.972895135538, 5435.396558015395, 5468.890622187534, 3832.5209348831395, 3851.8844434667003, 3868.071662800936, 3869.361555343365, 3896.376579747255, 5287.682739035632, 5450.672959622396, 5529.806456774034, 5635.690037047961, 5708.0, 3386.7629265951145, 3638.4542318652416, 3766.209096089797, 3787.5076240089165, 3803.3344164771056, 3249.23642943908, 3339.5157702133056, 3856.4408160817084, 3856.710746693953, 3862.3240932837202, 4303.483581970184, 4453.710508816916, 4517.6160026397465, 4530.864074077783, 4531.071130418256, 5104.927965127291, 5243.318368612056, 5288.92066619001, 5416.8104116355735, 5653.773849046074, 1120.9407110280706, 1288.7293668329569, 1290.5031562848371, 1310.8361454818148, 1321.2666969831835, 760.7903433540697, 765.6439755513713, 791.3076058448858, 812.6464987730357, 817.358373676661, 4704.600108990774, 4859.708417494961, 4961.5286547777905, 5007.699538027658, 5124.846254612367, 4772.427308722976, 5572.86067848307, 5598.964191624563, 5616.69108199053, 5620.0, 4427.623651989553, 4823.758812059383, 4918.213085906238, 5058.873528161826, 5152.871178220575, 2598.6621739174166, 2831.329515585773, 2890.7398334149607, 2924.707529247101, 2940.911772646781, 5094.949050509522, 5751.798687045934, 5798.9420105799245, 5809.651420914759, 5816.208220054397, 4854.466008739036, 4968.080905032581, 5271.920921186185, 5541.251930989345, 5767.068032209257, 5021.748912554358, 5157.948420515821, 5788.652680839163, 5789.478946894799, 5815.331236907725, 4554.772261386851, 4645.140648979815, 5372.435894231049, 5612.663240205601, 5638.5489160867855, 5130.58955283569, 5539.529140022982, 5562.332520097452, 5573.247611572386, 5635.971820140873, 5152.871178220575, 5162.586993040472, 5855.385184555731, 5892.204552385373, 5906.26171728534, 3814.580396156476, 3831.272058308912, 3857.672097871712, 3865.130345672201, 3894.3768996960353, 4715.952840471688, 5108.8249702569365, 5321.8180545975965, 5426.918596221139, 5593.818410819865, 5110.233464980282, 5237.507310416513, 5465.453454654503, 5480.671159730429, 5482.862928426821, 4329.264025115801, 4480.686351955332, 5248.606354523502, 5513.58648101386, 5559.388467268516, 4284.928767808048, 4499.055198408371, 5167.844964651066, 5291.0, 5390.299261096189, 4949.688332591794, 5149.742512874416, 5348.5988550858065, 5359.624826262124, 5413.56691464674, 4912.435069966999, 4944.802207911644, 5290.68255904647, 5313.654612450178, 5317.282166907418], "value": 3963.475303275924, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [513.5019031539408, 644.5906849150788, 651.3649192037764, 760.5132715062362, 800.487687879757, 15124.546263612081, 15334.53996380109, 15628.609284767857, 15642.06147631143, 15658.060516369025, 14341.713165736679, 14427.206503642296, 15164.696706065872, 15823.525294241192, 15873.444429444971, 714.0503130836001, 915.3409545127497, 924.1405492891608, 925.2597921662676, 926.3839546701436, 736.1276886889027, 886.9645214191424, 911.9908800912006, 913.4884464699757, 914.3873604684844, 965.512416229803, 967.4630580028081, 967.9419234845897, 968.3028219681818, 968.428627110005, 639.1978067525226, 747.7420289999941, 879.9924086920472, 907.6460180529599, 964.9372790768577, 692.6675195905993, 713.2297119111347, 713.9790100156773, 716.5521549031855, 717.0857157124708, 15083.170425626586, 15165.0, 15187.088774673555, 15187.544373668808, 15194.696106077816, 12067.577634782829, 12150.4532296046, 12328.137030407875, 12367.93816030914, 12551.0, 10803.13574914005, 10826.296290741077, 11057.391843448688, 11162.162837787198, 11458.508877147371, 4031.697622678311, 4038.9798051009852, 4040.8181631826637, 4149.564295748933, 4176.91646167078, 84326.0, 84710.0, 85237.86905327355, 86027.85859471529, 86621.0, 7869.449138560246, 9179.449233046038, 9226.26189904806, 9323.860142097874, 9965.598263059293, 20702.378928631864, 20722.0, 20723.4819129523, 20753.0, 20777.06503207359, 7215.206327303992, 7430.145533263744, 7462.70149194037, 7609.733659321897, 7659.310662040331, 3961.1087505311652, 4022.1955608878343, 4039.364057556694, 4039.838406463767, 4173.5409104998425, 3388.525606415104, 3487.616362200156, 3488.8255587221047, 3491.1970246843366, 3503.089196808848, 587.820714682026, 606.1877084706515, 612.2347066167291, 612.9724162412613, 613.089562000429, 469.99765001174774, 546.5162084939409, 552.0725181694792, 581.6364772017489, 588.0121396054669, 6594.967025164843, 6749.223839258548, 6772.864542709118, 6785.9660701696175, 6953.33943275388, 480.91908981609186, 481.28529134235635, 536.8496820890157, 563.4281204577358, 571.6055921414211, 3041.863116159778, 3661.139632186434, 3733.4586484960073, 3780.111673756665, 3866.0721426857353, 69561.0, 73395.0, 74080.62959685276, 80456.0, 91279.54360228157, 71882.2811771886, 73320.90018649725, 74666.25333746593, 76083.23916760873, 77449.22550774533, 68994.0, 69229.30770692229, 70330.94503582451, 70439.0, 71645.28354716387, 8106.513609183467, 8222.83554328922, 8230.588470576567, 8417.789555261168, 8421.567599678503, 39644.603553964094, 41501.75494735163, 41699.37450938299, 41940.0, 42422.575774243094, 44808.0, 54130.18804717853, 54292.72853635865, 55633.72183139058, 58559.70720146372, 10406.531706073094, 10550.261481696207, 10551.0, 10602.20483463728, 11047.502862371051, 11655.560946148089, 11761.294322340687, 11859.229150105215, 11876.168668193144, 12314.630561083182, 11147.0, 11188.776224475301, 11211.55153793839, 11381.943090284496, 11481.770364592829, 11051.082010765382, 11054.613088541862, 11116.61091861781, 11197.160212983896, 11534.711632209262, 10844.349339039367, 10922.563097476012, 11236.775264494829, 11274.549018039515, 11408.315501069963, 9330.533473326172, 9359.906400936175, 9370.906290937004, 9452.858207127036, 9486.762830929007, 9453.621855125717, 9592.424454532471, 9606.0, 9629.277804164856, 9875.093890991948], "value": 6688.026024445864, "unit": "score"}], "name": "dromaeo_css", "value": 7156.692556878175, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 106.0, 110.0, 111.0, 111.0, 113.0, 111.0, 112.0, 110.0, 111.0], "value": 110.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 118.0, 141.0, 202.0, 118.0, 140.0, 138.0, 115.0, 168.0, 115.0], "value": 141.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 183.0, 186.0, 183.0, 186.0, 186.0, 185.0, 211.0, 185.0, 186.0], "value": 187.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 72.0, 58.0, 125.0, 57.0, 61.0, 58.0, 59.0, 118.0, 57.0], "value": 74.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 99.0, 131.0, 79.0, 79.0, 78.0, 100.0, 80.0, 77.0, 101.0], "value": 91.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 88.0, 85.0, 88.0, 85.0, 89.0, 85.0, 88.0, 84.0], "value": 86.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 94.0, 95.0, 93.0, 93.0, 94.0, 94.0, 94.0, 93.0], "value": 93.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 85.0, 83.0, 86.0, 85.0, 86.0, 84.0, 85.0, 85.0, 113.0], "value": 87.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 60.0, 59.0, 59.0, 59.0, 59.0, 118.0, 56.0, 57.0], "value": 64.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 70.0, 78.0, 74.0, 70.0, 67.0, 66.0, 68.0, 68.0, 69.0], "value": 69.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 107.0, 104.0, 71.0, 100.0, 73.0, 81.0, 96.0, 107.0, 105.0], "value": 94.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 146.0, 135.0, 133.0, 138.0, 136.0, 140.0, 156.0, 249.0, 154.0], "value": 154.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 167.0, 167.0, 163.0, 166.0, 170.0, 166.0, 166.0, 166.0, 164.0], "value": 166.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 67.0, 66.0, 66.0, 65.0, 67.0, 64.0, 66.0, 65.0], "value": 66.1, "unit": "score"}], "name": "kraken", "value": 1487.5, "alertThreshold": 2.0}]} 08:29:12 INFO - Return code: 0 08:29:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 08:29:12 ERROR - # TBPL SUCCESS # 08:29:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:29:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:29:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:29:12 INFO - 2016-05-11 08:29:12.478 screenresolution[1676:9657] starting screenresolution argv=screenresolution get 08:29:12 INFO - 2016-05-11 08:29:12.481 screenresolution[1676:9657] Display 0: 1600x1200x32@60 08:29:12 INFO - 2016-05-11 08:29:12.486 screenresolution[1677:9660] starting screenresolution argv=screenresolution list 08:29:12 INFO - Available Modes on Display 0 08:29:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:29:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:29:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:29:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:29:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:29:12 INFO - Intel Iris: 08:29:12 INFO - Chipset Model: Intel Iris 08:29:12 INFO - Type: GPU 08:29:12 INFO - Bus: Built-In 08:29:12 INFO - VRAM (Dynamic, Max): 1536 MB 08:29:12 INFO - Vendor: Intel (0x8086) 08:29:12 INFO - Device ID: 0x0a2e 08:29:12 INFO - Revision ID: 0x0009 08:29:12 INFO - Displays: 08:29:12 INFO - Display: 08:29:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:29:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:29:12 INFO - Display Serial Number: 1600x1200 60 08:29:12 INFO - Main Display: Yes 08:29:12 INFO - Mirror: Off 08:29:12 INFO - Online: Yes 08:29:12 INFO - Rotation: Supported 08:29:12 INFO - Return code: 0 08:29:12 INFO - Running post-action listener: _resource_record_post_action 08:29:12 INFO - ##### 08:29:12 INFO - ##### Finished run-tests step (success) 08:29:12 INFO - ##### 08:29:12 INFO - Running post-run listener: _resource_record_post_run 08:29:13 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 37979648; Write bytes: 694932992; Read time: 398; Write time: 1979 08:29:13 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 388123136; Write bytes: 382576640; Read time: 29611; Write time: 749 08:29:13 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 36161024; Write bytes: 297160192; Read time: 375; Write time: 1171 08:29:13 INFO - Running post-run listener: _upload_blobber_files 08:29:13 INFO - Blob upload gear active. 08:29:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:29:13 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 08:29:13 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:29:13 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:29:14 INFO - (blobuploader) - INFO - Open directory for files ... 08:29:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:29:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:29:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:29:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:29:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:29:15 INFO - (blobuploader) - INFO - Done attempting. 08:29:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:29:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:29:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:29:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:29:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:29:16 INFO - (blobuploader) - INFO - Done attempting. 08:29:16 INFO - (blobuploader) - INFO - Iteration through files over. 08:29:16 INFO - Return code: 0 08:29:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:29:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:29:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3c15e7a78cc758a83fc7188915e87bb424d047c0f1b8f07950839573e40c07e8c3dbd0458cc5ac2e34e563cff416dc925cbd694e5b99b2790366c463abf4a6ef", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1ac2a31d2fa195a3e6fe8a163c47a86aa7753584a489a3ff598ee88b7c3f45cbb8bee17cb784c89795acea1f91735b52ec3d9e6f9af3437dea717dcce81f7e75"} 08:29:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:29:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:29:16 INFO - Contents: 08:29:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3c15e7a78cc758a83fc7188915e87bb424d047c0f1b8f07950839573e40c07e8c3dbd0458cc5ac2e34e563cff416dc925cbd694e5b99b2790366c463abf4a6ef", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1ac2a31d2fa195a3e6fe8a163c47a86aa7753584a489a3ff598ee88b7c3f45cbb8bee17cb784c89795acea1f91735b52ec3d9e6f9af3437dea717dcce81f7e75"} 08:29:16 INFO - Running post-run listener: copy_logs_to_upload_dir 08:29:16 INFO - Copying logs to upload dir... 08:29:16 INFO - mkdir: /builds/slave/test/build/upload/logs 08:29:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.189320 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-05-11 08:29:16.421284) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-11 08:29:16.424559) ========= 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.pn0tz2tR3q/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.DfpCSEXzMp/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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/Fx-Team/sha512/3c15e7a78cc758a83fc7188915e87bb424d047c0f1b8f07950839573e40c07e8c3dbd0458cc5ac2e34e563cff416dc925cbd694e5b99b2790366c463abf4a6ef", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1ac2a31d2fa195a3e6fe8a163c47a86aa7753584a489a3ff598ee88b7c3f45cbb8bee17cb784c89795acea1f91735b52ec3d9e6f9af3437dea717dcce81f7e75"} build_url:https://queue.taskcluster.net/v1/task/ZUCIybARQhqWMHtil2xCsg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011264 build_url: 'https://queue.taskcluster.net/v1/task/ZUCIybARQhqWMHtil2xCsg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3c15e7a78cc758a83fc7188915e87bb424d047c0f1b8f07950839573e40c07e8c3dbd0458cc5ac2e34e563cff416dc925cbd694e5b99b2790366c463abf4a6ef", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1ac2a31d2fa195a3e6fe8a163c47a86aa7753584a489a3ff598ee88b7c3f45cbb8bee17cb784c89795acea1f91735b52ec3d9e6f9af3437dea717dcce81f7e75"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-11 08:29:16.452023) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 08:29:16.452326) ========= 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.pn0tz2tR3q/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.DfpCSEXzMp/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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.005020 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 08:29:16.500620) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-11 08:29:16.500894) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-11 08:29:16.501177) ========= ========= Total master_lag: 0.15 =========