builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0169 starttime: 1461001475.98 results: success (0) buildid: 20160418094943 builduid: 82cf067085264d8d8a7159b7c61cf302 revision: 1a229bf7891d7b453eb6e5269c11ace513e400a4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-18 10:44:35.982874) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-18 10:44:35.983365) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-18 10:44:35.983654) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-18 10:44:36.003768) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 10:44:36.004152) ========= 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.9gZ1hdvNsI/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.RL9Ktcv07c/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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-04-18 10:44:36-- 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% 61.9M=0s 2016-04-18 10:44:36 (61.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.100121 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 10:44:36.121873) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 10:44:36.122188) ========= 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.9gZ1hdvNsI/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.RL9Ktcv07c/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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.026925 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 10:44:36.170518) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-18 10:44:36.170879) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 1a229bf7891d7b453eb6e5269c11ace513e400a4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 1a229bf7891d7b453eb6e5269c11ace513e400a4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.9gZ1hdvNsI/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.RL9Ktcv07c/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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-04-18 10:44:36,245 truncating revision to first 12 chars 2016-04-18 10:44:36,245 Setting DEBUG logging. 2016-04-18 10:44:36,245 attempt 1/10 2016-04-18 10:44:36,245 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/1a229bf7891d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-18 10:44:37,097 unpacking tar archive at: mozilla-aurora-1a229bf7891d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.096447 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-18 10:44:37.284146) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-18 10:44:37.284484) ========= script_repo_revision: 1a229bf7891d7b453eb6e5269c11ace513e400a4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-18 10:44:37.284940) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-18 10:44:37.285207) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-18 10:44:37.299482) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 36 secs) (at 2016-04-18 10:44:37.299894) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.9gZ1hdvNsI/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.RL9Ktcv07c/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 10:44:37 INFO - MultiFileLogger online at 20160418 10:44:37 in /builds/slave/test 10:44:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 10:44:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:44:37 INFO - {'append_to_log': False, 10:44:37 INFO - 'base_work_dir': '/builds/slave/test', 10:44:37 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 10:44:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:44:37 INFO - 'branch': 'Mozilla-Aurora', 10:44:37 INFO - 'buildbot_json_path': 'buildprops.json', 10:44:37 INFO - 'config_files': ('talos/mac_config.py',), 10:44:37 INFO - 'default_actions': ('clobber', 10:44:37 INFO - 'read-buildbot-config', 10:44:37 INFO - 'download-and-extract', 10:44:37 INFO - 'populate-webroot', 10:44:37 INFO - 'create-virtualenv', 10:44:37 INFO - 'install', 10:44:37 INFO - 'run-tests'), 10:44:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:44:37 INFO - 'download_minidump_stackwalk': True, 10:44:37 INFO - 'download_symbols': 'ondemand', 10:44:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:44:37 INFO - 'tooltool.py': '/tools/tooltool.py', 10:44:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:44:37 INFO - '/tools/misc-python/virtualenv.py')}, 10:44:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:44:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:44:37 INFO - 'installer_path': 'installer.exe', 10:44:37 INFO - 'log_level': 'info', 10:44:37 INFO - 'log_name': 'talos', 10:44:37 INFO - 'log_to_console': True, 10:44:37 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:44:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:44:37 INFO - 'opt_config_files': (), 10:44:37 INFO - 'pip_index': False, 10:44:37 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:44:37 INFO - 'cmd': ('bash', 10:44:37 INFO - '-c', 10:44:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:44:37 INFO - 'enabled': True, 10:44:37 INFO - 'halt_on_failure': False, 10:44:37 INFO - 'name': 'check_screen_resolution'},), 10:44:37 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:44:37 INFO - 'cmd': ('bash', 10:44:37 INFO - '-c', 10:44:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:44:37 INFO - 'enabled': True, 10:44:37 INFO - 'halt_on_failure': False, 10:44:37 INFO - 'name': 'check_screen_resolution'},), 10:44:37 INFO - 'run_cmd_checks_enabled': True, 10:44:37 INFO - 'sps_profile': False, 10:44:37 INFO - 'sps_profile_interval': 0, 10:44:37 INFO - 'suite': 'dromaeojs', 10:44:37 INFO - 'system_bits': '32', 10:44:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:44:37 INFO - 'title': 't-yosemite-r7-0169', 10:44:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:44:37 INFO - 'use_talos_json': True, 10:44:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:44:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:44:37 INFO - 'work_dir': 'build'} 10:44:37 INFO - ##### 10:44:37 INFO - ##### Running clobber step. 10:44:37 INFO - ##### 10:44:37 INFO - Running pre-action listener: _resource_record_pre_action 10:44:37 INFO - Running main action method: clobber 10:44:37 INFO - rmtree: /builds/slave/test/build 10:44:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:44:39 INFO - Running post-action listener: _resource_record_post_action 10:44:39 INFO - ##### 10:44:39 INFO - ##### Running read-buildbot-config step. 10:44:39 INFO - ##### 10:44:39 INFO - Running pre-action listener: _resource_record_pre_action 10:44:39 INFO - Running main action method: read_buildbot_config 10:44:39 INFO - Using buildbot properties: 10:44:39 INFO - { 10:44:39 INFO - "project": "", 10:44:39 INFO - "product": "firefox", 10:44:39 INFO - "script_repo_revision": "production", 10:44:39 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 10:44:39 INFO - "repository": "", 10:44:39 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 10:44:39 INFO - "buildid": "20160418094943", 10:44:39 INFO - "pgo_build": "False", 10:44:39 INFO - "basedir": "/builds/slave/test", 10:44:39 INFO - "buildnumber": 288, 10:44:39 INFO - "slavename": "t-yosemite-r7-0169", 10:44:39 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 10:44:39 INFO - "platform": "yosemite_r7", 10:44:39 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 10:44:39 INFO - "branch": "mozilla-aurora", 10:44:39 INFO - "repo_path": "releases/mozilla-aurora", 10:44:39 INFO - "revision": "1a229bf7891d7b453eb6e5269c11ace513e400a4", 10:44:39 INFO - "stage_platform": "macosx64", 10:44:39 INFO - "builduid": "82cf067085264d8d8a7159b7c61cf302", 10:44:39 INFO - "slavebuilddir": "test" 10:44:39 INFO - } 10:44:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/Vxbri5mVQxG84v3-50tBDg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg. 10:44:39 INFO - Running post-action listener: _resource_record_post_action 10:44:39 INFO - ##### 10:44:39 INFO - ##### Running download-and-extract step. 10:44:39 INFO - ##### 10:44:39 INFO - Running pre-action listener: _resource_record_pre_action 10:44:39 INFO - Running main action method: download_and_extract 10:44:39 INFO - mkdir: /builds/slave/test/build/tests 10:44:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:44:39 INFO - https://queue.taskcluster.net/v1/task/Vxbri5mVQxG84v3-50tBDg/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 10:44:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vxbri5mVQxG84v3-50tBDg/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 10:44:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vxbri5mVQxG84v3-50tBDg/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 10:44:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vxbri5mVQxG84v3-50tBDg/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 10:44:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vxbri5mVQxG84v3-50tBDg/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json'}, attempt #1 10:44:40 INFO - Downloaded 1277 bytes. 10:44:40 INFO - Reading from file /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 10:44:40 INFO - Using the following test package requirements: 10:44:40 INFO - {u'common': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 10:44:40 INFO - u'cppunittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 10:44:40 INFO - u'firefox-47.0a2.en-US.mac.cppunittest.tests.zip'], 10:44:40 INFO - u'gtest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 10:44:40 INFO - u'firefox-47.0a2.en-US.mac.gtest.tests.zip'], 10:44:40 INFO - u'jittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 10:44:40 INFO - u'jsshell-mac.zip'], 10:44:40 INFO - u'mochitest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 10:44:40 INFO - u'firefox-47.0a2.en-US.mac.mochitest.tests.zip'], 10:44:40 INFO - u'mozbase': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 10:44:40 INFO - u'reftest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 10:44:40 INFO - u'firefox-47.0a2.en-US.mac.reftest.tests.zip'], 10:44:40 INFO - u'talos': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 10:44:40 INFO - u'firefox-47.0a2.en-US.mac.talos.tests.zip'], 10:44:40 INFO - u'web-platform': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 10:44:40 INFO - u'firefox-47.0a2.en-US.mac.web-platform.tests.zip'], 10:44:40 INFO - u'webapprt': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 10:44:40 INFO - u'xpcshell': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 10:44:40 INFO - u'firefox-47.0a2.en-US.mac.xpcshell.tests.zip']} 10:44:40 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip'] for test suite category: common 10:44:40 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')]} 10:44:40 INFO - https://queue.taskcluster.net/v1/task/Vxbri5mVQxG84v3-50tBDg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:44:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vxbri5mVQxG84v3-50tBDg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 10:44:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vxbri5mVQxG84v3-50tBDg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 10:44:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vxbri5mVQxG84v3-50tBDg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 10:44:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vxbri5mVQxG84v3-50tBDg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 10:44:41 INFO - Downloaded 17910973 bytes. 10:44:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:44:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 10:44:44 INFO - Return code: 0 10:44:44 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip', u'firefox-47.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 10:44:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:44:44 INFO - https://queue.taskcluster.net/v1/task/Vxbri5mVQxG84v3-50tBDg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:44:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vxbri5mVQxG84v3-50tBDg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 10:44:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vxbri5mVQxG84v3-50tBDg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 10:44:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vxbri5mVQxG84v3-50tBDg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 10:44:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vxbri5mVQxG84v3-50tBDg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 10:44:45 INFO - Downloaded 17910973 bytes. 10:44:45 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:44:45 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 10:44:49 INFO - Return code: 0 10:44:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:44:49 INFO - https://queue.taskcluster.net/v1/task/Vxbri5mVQxG84v3-50tBDg/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 10:44:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vxbri5mVQxG84v3-50tBDg/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 10:44:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vxbri5mVQxG84v3-50tBDg/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 10:44:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vxbri5mVQxG84v3-50tBDg/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip 10:44:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vxbri5mVQxG84v3-50tBDg/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip'}, attempt #1 10:44:50 INFO - Downloaded 10845168 bytes. 10:44:50 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:44:50 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 10:44:50 INFO - Return code: 0 10:44:50 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')]} 10:44:50 INFO - https://queue.taskcluster.net/v1/task/Vxbri5mVQxG84v3-50tBDg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 10:44:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vxbri5mVQxG84v3-50tBDg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 10:44:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vxbri5mVQxG84v3-50tBDg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 10:44:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vxbri5mVQxG84v3-50tBDg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg 10:44:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vxbri5mVQxG84v3-50tBDg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg'}, attempt #1 10:44:53 INFO - Downloaded 115239894 bytes. 10:44:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Vxbri5mVQxG84v3-50tBDg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 10:44:53 INFO - mkdir: /builds/slave/test/properties 10:44:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:44:53 INFO - Writing to file /builds/slave/test/properties/build_url 10:44:53 INFO - Contents: 10:44:53 INFO - build_url:https://queue.taskcluster.net/v1/task/Vxbri5mVQxG84v3-50tBDg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 10:44:55 INFO - Running post-action listener: _resource_record_post_action 10:44:55 INFO - Running post-action listener: set_extra_try_arguments 10:44:55 INFO - ##### 10:44:55 INFO - ##### Running populate-webroot step. 10:44:55 INFO - ##### 10:44:55 INFO - Running pre-action listener: _resource_record_pre_action 10:44:55 INFO - Running main action method: populate_webroot 10:44:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:44:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:44:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:44:55 INFO - '--tppagecycles', 10:44:55 INFO - '7'], 10:44:55 INFO - 'tests': ['tsvgm']}}, 10:44:55 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 10:44:55 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 10:44:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:44:55 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 10:44:55 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 10:44:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:44:55 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:44:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:44:55 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:44:55 INFO - 'talos_options': ['--e10s'], 10:44:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:44:55 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:44:55 INFO - 'tests': ['damp', 'tps']}, 10:44:55 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:44:55 INFO - 'talos_options': ['--e10s'], 10:44:55 INFO - 'tests': ['damp', 'tps']}, 10:44:55 INFO - 'g3': {'tests': ['dromaeo_dom']}, 10:44:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:44:55 INFO - 'other': {'tests': ['a11yr', 10:44:55 INFO - 'ts_paint', 10:44:55 INFO - 'tpaint', 10:44:55 INFO - 'sessionrestore', 10:44:55 INFO - 'sessionrestore_no_auto_restore', 10:44:55 INFO - 'tabpaint']}, 10:44:55 INFO - 'other-e10s': {'talos_options': ['--e10s'], 10:44:55 INFO - 'tests': ['a11yr', 10:44:55 INFO - 'ts_paint', 10:44:55 INFO - 'tpaint', 10:44:55 INFO - 'sessionrestore', 10:44:55 INFO - 'sessionrestore_no_auto_restore', 10:44:55 INFO - 'tabpaint']}, 10:44:55 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 10:44:55 INFO - 'tests': ['a11yr', 10:44:55 INFO - 'ts_paint', 10:44:55 INFO - 'tpaint', 10:44:55 INFO - 'sessionrestore', 10:44:55 INFO - 'sessionrestore_no_auto_restore', 10:44:55 INFO - 'tabpaint']}, 10:44:55 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 10:44:55 INFO - 'tests': ['a11yr', 10:44:55 INFO - 'ts_paint', 10:44:55 INFO - 'tpaint', 10:44:55 INFO - 'sessionrestore', 10:44:55 INFO - 'sessionrestore_no_auto_restore', 10:44:55 INFO - 'tabpaint']}, 10:44:55 INFO - 'other_l64': {'tests': ['a11yr', 10:44:55 INFO - 'ts_paint', 10:44:55 INFO - 'tpaint', 10:44:55 INFO - 'sessionrestore', 10:44:55 INFO - 'sessionrestore_no_auto_restore', 10:44:55 INFO - 'tabpaint']}, 10:44:55 INFO - 'other_nol64': {'tests': ['a11yr', 10:44:55 INFO - 'ts_paint', 10:44:55 INFO - 'tpaint', 10:44:55 INFO - 'sessionrestore', 10:44:55 INFO - 'sessionrestore_no_auto_restore', 10:44:55 INFO - 'tabpaint']}, 10:44:55 INFO - 'svgr': {'tests': ['tsvgx', 10:44:55 INFO - 'tsvgr_opacity', 10:44:55 INFO - 'tart', 10:44:55 INFO - 'tscrollx', 10:44:55 INFO - 'cart']}, 10:44:55 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 10:44:55 INFO - 'tests': ['tsvgx', 10:44:55 INFO - 'tsvgr_opacity', 10:44:55 INFO - 'tart', 10:44:55 INFO - 'tscrollx', 10:44:55 INFO - 'cart']}, 10:44:55 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:44:55 INFO - 'tests': ['tp5o']}, 10:44:55 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:44:55 INFO - 'talos_options': ['--e10s'], 10:44:55 INFO - 'tests': ['tp5o']}, 10:44:55 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:44:55 INFO - 'talos_options': ['--xperf_path', 10:44:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:44:55 INFO - 'tests': ['tp5n']}, 10:44:55 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:44:55 INFO - 'talos_options': ['--e10s', 10:44:55 INFO - '--xperf_path', 10:44:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:44:55 INFO - 'tests': ['tp5n']}}, 10:44:55 INFO - 'talos.zip': {'path': '', 10:44:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:44:55 INFO - Running post-action listener: _resource_record_post_action 10:44:55 INFO - ##### 10:44:55 INFO - ##### Running create-virtualenv step. 10:44:55 INFO - ##### 10:44:55 INFO - Running pre-action listener: _resource_record_pre_action 10:44:55 INFO - Running main action method: create_virtualenv 10:44:55 INFO - Creating virtualenv /builds/slave/test/build/venv 10:44:55 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 10:44:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:44:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:44:55 INFO - Using real prefix '/tools/python27' 10:44:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:44:56 INFO - Installing distribute.............................................................................................................................................................................................done. 10:44:58 INFO - Installing pip.................done. 10:44:58 INFO - Return code: 0 10:44:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:44:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:44:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:44:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:44:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:44:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1072c9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1072d04c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b1948fdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10716dd30>, '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 0x106fd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107164b20>, '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.9gZ1hdvNsI/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.RL9Ktcv07c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 10:44:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:44:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:44:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9gZ1hdvNsI/Render', 10:44:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:44:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:44:58 INFO - 'HOME': '/Users/cltbld', 10:44:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:44:58 INFO - 'LOGNAME': 'cltbld', 10:44:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:44:58 INFO - 'MOZ_NO_REMOTE': '1', 10:44:58 INFO - 'NO_EM_RESTART': '1', 10:44:58 INFO - 'PAGER': '/bin/cat', 10:44:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:44:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:44:58 INFO - 'PWD': '/builds/slave/test', 10:44:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:44:58 INFO - 'SHELL': '/bin/bash', 10:44:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RL9Ktcv07c/Listeners', 10:44:58 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 10:44:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:44:58 INFO - 'USER': 'cltbld', 10:44:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:44:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:44:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:44:58 INFO - 'XPC_FLAGS': '0x0', 10:44:58 INFO - 'XPC_SERVICE_NAME': '0', 10:44:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:44:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:44:59 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 10:44:59 INFO - Cleaning up... 10:44:59 INFO - Return code: 0 10:44:59 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 10:44:59 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')]} 10:44:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:44:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44:59 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')]} 10:44:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:44:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44:59 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 0x1072c9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1072d04c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b1948fdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10716dd30>, '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 0x106fd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107164b20>, '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.9gZ1hdvNsI/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.RL9Ktcv07c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 10:44:59 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 10:44:59 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 10:44:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9gZ1hdvNsI/Render', 10:44:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:44:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:44:59 INFO - 'HOME': '/Users/cltbld', 10:44:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:44:59 INFO - 'LOGNAME': 'cltbld', 10:44:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:44:59 INFO - 'MOZ_NO_REMOTE': '1', 10:44:59 INFO - 'NO_EM_RESTART': '1', 10:44:59 INFO - 'PAGER': '/bin/cat', 10:44:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:44:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:44:59 INFO - 'PWD': '/builds/slave/test', 10:44:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:44:59 INFO - 'SHELL': '/bin/bash', 10:44:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RL9Ktcv07c/Listeners', 10:44:59 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 10:44:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:44:59 INFO - 'USER': 'cltbld', 10:44:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:44:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:44:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:44:59 INFO - 'XPC_FLAGS': '0x0', 10:44:59 INFO - 'XPC_SERVICE_NAME': '0', 10:44:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:44:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:44:59 INFO - Downloading/unpacking psutil>=0.7.1 10:44:59 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 10:44:59 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 10:44:59 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 10:44:59 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 10:44:59 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 10:44:59 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 10:45:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:45:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:45:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:45:02 INFO - Installing collected packages: psutil 10:45:02 INFO - Running setup.py install for psutil 10:45:02 INFO - building 'psutil._psutil_osx' extension 10:45:02 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 10:45:02 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 10:45:02 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 10:45:02 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 10:45:02 INFO - building 'psutil._psutil_posix' extension 10:45:02 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 10:45:03 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:45:03 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:45:03 INFO - ^ 10:45:03 INFO - 1 warning generated. 10:45:03 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 10:45:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:45:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:45:03 INFO - Successfully installed psutil 10:45:03 INFO - Cleaning up... 10:45:03 INFO - Return code: 0 10:45:03 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 10:45:03 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')]} 10:45:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:45:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:45:03 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')]} 10:45:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:45:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:45:03 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 0x1072c9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1072d04c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b1948fdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10716dd30>, '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 0x106fd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107164b20>, '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.9gZ1hdvNsI/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.RL9Ktcv07c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 10:45:03 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 10:45:03 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 10:45:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9gZ1hdvNsI/Render', 10:45:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:45:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:45:03 INFO - 'HOME': '/Users/cltbld', 10:45:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:45:03 INFO - 'LOGNAME': 'cltbld', 10:45:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:45:03 INFO - 'MOZ_NO_REMOTE': '1', 10:45:03 INFO - 'NO_EM_RESTART': '1', 10:45:03 INFO - 'PAGER': '/bin/cat', 10:45:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:45:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:45:03 INFO - 'PWD': '/builds/slave/test', 10:45:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:45:03 INFO - 'SHELL': '/bin/bash', 10:45:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RL9Ktcv07c/Listeners', 10:45:03 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 10:45:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:45:03 INFO - 'USER': 'cltbld', 10:45:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:45:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:45:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:45:03 INFO - 'XPC_FLAGS': '0x0', 10:45:03 INFO - 'XPC_SERVICE_NAME': '0', 10:45:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:45:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:45:03 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 10:45:03 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 10:45:03 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 10:45:03 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 10:45:03 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 10:45:03 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 10:45:03 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 10:45:05 INFO - Downloading mozsystemmonitor-0.0.tar.gz 10:45:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:45:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 10:45:05 INFO - Installing collected packages: mozsystemmonitor 10:45:05 INFO - Running setup.py install for mozsystemmonitor 10:45:06 INFO - Successfully installed mozsystemmonitor 10:45:06 INFO - Cleaning up... 10:45:06 INFO - Return code: 0 10:45:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:45:06 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')]} 10:45:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:45:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:45:06 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')]} 10:45:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:45:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:45:06 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 0x1072c9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1072d04c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b1948fdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10716dd30>, '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 0x106fd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107164b20>, '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.9gZ1hdvNsI/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.RL9Ktcv07c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 10:45:06 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 10:45:06 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 10:45:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9gZ1hdvNsI/Render', 10:45:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:45:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:45:06 INFO - 'HOME': '/Users/cltbld', 10:45:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:45:06 INFO - 'LOGNAME': 'cltbld', 10:45:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:45:06 INFO - 'MOZ_NO_REMOTE': '1', 10:45:06 INFO - 'NO_EM_RESTART': '1', 10:45:06 INFO - 'PAGER': '/bin/cat', 10:45:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:45:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:45:06 INFO - 'PWD': '/builds/slave/test', 10:45:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:45:06 INFO - 'SHELL': '/bin/bash', 10:45:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RL9Ktcv07c/Listeners', 10:45:06 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 10:45:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:45:06 INFO - 'USER': 'cltbld', 10:45:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:45:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:45:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:45:06 INFO - 'XPC_FLAGS': '0x0', 10:45:06 INFO - 'XPC_SERVICE_NAME': '0', 10:45:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:45:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:45:06 INFO - Downloading/unpacking blobuploader==1.2.4 10:45:06 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 10:45:06 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 10:45:06 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 10:45:06 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 10:45:06 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 10:45:06 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 10:45:08 INFO - Downloading blobuploader-1.2.4.tar.gz 10:45:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:45:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:45:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:45:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:45:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:45:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:45:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:45:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:45:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:45:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:45:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:45:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:45:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:45:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:45:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:45:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:45:10 INFO - Downloading docopt-0.6.1.tar.gz 10:45:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:45:10 INFO - Installing collected packages: blobuploader, requests, docopt 10:45:10 INFO - Running setup.py install for blobuploader 10:45:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:45:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:45:10 INFO - Running setup.py install for requests 10:45:10 INFO - Running setup.py install for docopt 10:45:10 INFO - Successfully installed blobuploader requests docopt 10:45:10 INFO - Cleaning up... 10:45:10 INFO - Return code: 0 10:45:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:45:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:45:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:45:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:45:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:45:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:45:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:45:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1072c9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1072d04c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b1948fdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10716dd30>, '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 0x106fd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107164b20>, '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.9gZ1hdvNsI/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.RL9Ktcv07c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 10:45:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:45:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:45:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9gZ1hdvNsI/Render', 10:45:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:45:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:45:10 INFO - 'HOME': '/Users/cltbld', 10:45:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:45:10 INFO - 'LOGNAME': 'cltbld', 10:45:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:45:10 INFO - 'MOZ_NO_REMOTE': '1', 10:45:10 INFO - 'NO_EM_RESTART': '1', 10:45:10 INFO - 'PAGER': '/bin/cat', 10:45:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:45:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:45:10 INFO - 'PWD': '/builds/slave/test', 10:45:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:45:10 INFO - 'SHELL': '/bin/bash', 10:45:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RL9Ktcv07c/Listeners', 10:45:10 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 10:45:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:45:10 INFO - 'USER': 'cltbld', 10:45:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:45:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:45:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:45:10 INFO - 'XPC_FLAGS': '0x0', 10:45:10 INFO - 'XPC_SERVICE_NAME': '0', 10:45:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:45:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:45:11 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-IBM9uy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:45:11 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-U7Zhio-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:45:11 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-rUIWUb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:45:11 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-ZwhlTZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:45:11 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-clfOI7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:45:11 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-r_MO6a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:45:11 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-gnmu3Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:45:11 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-ecIbi3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:45:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:45:12 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-vwHD51-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:45:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:45:12 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-sn0_d7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:45:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:45:12 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-muFFgA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:45:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:45:12 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-HmizlD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:45:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:45:12 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-Wg6MXr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:45:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:45:12 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-KEE0kp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:45:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:45:12 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-HvUWiz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:45:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:45:12 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-0kNajc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:45:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:45:12 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-ia2KCH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:45:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:45:13 INFO - Running setup.py install for manifestparser 10:45:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:45:13 INFO - Running setup.py install for mozcrash 10:45:13 INFO - Running setup.py install for mozdebug 10:45:13 INFO - Running setup.py install for mozdevice 10:45:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:45:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:45:13 INFO - Running setup.py install for mozfile 10:45:13 INFO - Running setup.py install for mozhttpd 10:45:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:45:13 INFO - Running setup.py install for mozinfo 10:45:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:45:14 INFO - Running setup.py install for mozInstall 10:45:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:45:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:45:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:45:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:45:14 INFO - Running setup.py install for mozleak 10:45:14 INFO - Running setup.py install for mozlog 10:45:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:45:14 INFO - Running setup.py install for moznetwork 10:45:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:45:14 INFO - Running setup.py install for mozprocess 10:45:14 INFO - Running setup.py install for mozprofile 10:45:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:45:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:45:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:45:14 INFO - Running setup.py install for mozrunner 10:45:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:45:15 INFO - Running setup.py install for mozscreenshot 10:45:15 INFO - Running setup.py install for moztest 10:45:15 INFO - Running setup.py install for mozversion 10:45:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:45:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:45:15 INFO - Cleaning up... 10:45:15 INFO - Return code: 0 10:45:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:45:15 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')]} 10:45:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:45:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:45:15 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')]} 10:45:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:45:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:45:15 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 0x1072c9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1072d04c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b1948fdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10716dd30>, '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 0x106fd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107164b20>, '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.9gZ1hdvNsI/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.RL9Ktcv07c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 10:45:15 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 10:45:15 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 10:45:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9gZ1hdvNsI/Render', 10:45:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:45:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:45:15 INFO - 'HOME': '/Users/cltbld', 10:45:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:45:15 INFO - 'LOGNAME': 'cltbld', 10:45:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:45:15 INFO - 'MOZ_NO_REMOTE': '1', 10:45:15 INFO - 'NO_EM_RESTART': '1', 10:45:15 INFO - 'PAGER': '/bin/cat', 10:45:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:45:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:45:15 INFO - 'PWD': '/builds/slave/test', 10:45:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:45:15 INFO - 'SHELL': '/bin/bash', 10:45:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RL9Ktcv07c/Listeners', 10:45:15 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 10:45:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:45:15 INFO - 'USER': 'cltbld', 10:45:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:45:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:45:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:45:15 INFO - 'XPC_FLAGS': '0x0', 10:45:15 INFO - 'XPC_SERVICE_NAME': '0', 10:45:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:45:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:45:15 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-WwKXMF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:45:15 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 10:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:45:15 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-kTcEUv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:45:16 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)) 10:45:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:45:16 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-CURJ0K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:45:16 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)) 10:45:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:45:16 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-MByfUN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:45:16 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)) 10:45:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:45:16 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-IKtWoh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:45:16 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)) 10:45:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:45:16 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-zLehMT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:45:16 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)) 10:45:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:45:16 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-vU5sHu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:45:16 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)) 10:45:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:45:16 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-wl9VFE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:45:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 10:45:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:45:16 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-7G25ao-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:45:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 10:45:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:45:16 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-7ofktf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:45:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:45:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:45:16 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-10d8uM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:45:17 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)) 10:45:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:45:17 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-we23ps-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:45:17 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)) 10:45:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:45:17 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-r915Be-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:45:17 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)) 10:45:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:45:17 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-uswXVq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:45:17 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)) 10:45:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:45:17 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-rOXyva-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:45:17 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)) 10:45:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:45:17 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-yhHWNV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:45:17 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)) 10:45:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:45:17 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-jnVpyB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:45:17 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)) 10:45:17 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)) 10:45:17 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)) 10:45:17 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)) 10:45:17 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)) 10:45:17 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)) 10:45:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:45:17 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 10:45:17 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 10:45:17 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 10:45:17 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 10:45:17 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 10:45:17 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 10:45:20 INFO - Downloading blessings-1.6.tar.gz 10:45:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:45:20 INFO - Installing collected packages: blessings 10:45:20 INFO - Running setup.py install for blessings 10:45:20 INFO - Successfully installed blessings 10:45:20 INFO - Cleaning up... 10:45:20 INFO - Return code: 0 10:45:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:45:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:45:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:45:20 INFO - Reading from file tmpfile_stdout 10:45:20 INFO - Current package versions: 10:45:20 INFO - blessings == 1.6 10:45:20 INFO - blobuploader == 1.2.4 10:45:20 INFO - docopt == 0.6.1 10:45:20 INFO - manifestparser == 1.1 10:45:20 INFO - mozInstall == 1.12 10:45:20 INFO - mozcrash == 0.17 10:45:20 INFO - mozdebug == 0.1 10:45:20 INFO - mozdevice == 0.48 10:45:20 INFO - mozfile == 1.2 10:45:20 INFO - mozhttpd == 0.7 10:45:20 INFO - mozinfo == 0.9 10:45:20 INFO - mozleak == 0.1 10:45:20 INFO - mozlog == 3.1 10:45:20 INFO - moznetwork == 0.27 10:45:20 INFO - mozprocess == 0.22 10:45:20 INFO - mozprofile == 0.28 10:45:20 INFO - mozrunner == 6.11 10:45:20 INFO - mozscreenshot == 0.1 10:45:20 INFO - mozsystemmonitor == 0.0 10:45:20 INFO - moztest == 0.7 10:45:20 INFO - mozversion == 1.4 10:45:20 INFO - psutil == 3.1.1 10:45:20 INFO - requests == 1.2.3 10:45:20 INFO - wsgiref == 0.1.2 10:45:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:45:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:45:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:45:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:45:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:45:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:45:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:45:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1072c9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1072d04c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b1948fdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10716dd30>, '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 0x106fd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107164b20>, '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.9gZ1hdvNsI/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.RL9Ktcv07c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 10:45:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 10:45:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:45:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9gZ1hdvNsI/Render', 10:45:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:45:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:45:20 INFO - 'HOME': '/Users/cltbld', 10:45:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:45:20 INFO - 'LOGNAME': 'cltbld', 10:45:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:45:20 INFO - 'MOZ_NO_REMOTE': '1', 10:45:20 INFO - 'NO_EM_RESTART': '1', 10:45:20 INFO - 'PAGER': '/bin/cat', 10:45:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:45:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:45:20 INFO - 'PWD': '/builds/slave/test', 10:45:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:45:20 INFO - 'SHELL': '/bin/bash', 10:45:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RL9Ktcv07c/Listeners', 10:45:20 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 10:45:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:45:20 INFO - 'USER': 'cltbld', 10:45:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:45:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:45:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:45:20 INFO - 'XPC_FLAGS': '0x0', 10:45:20 INFO - 'XPC_SERVICE_NAME': '0', 10:45:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:45:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:45:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:45:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 10:45:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 10:45:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:45:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 10:45:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 10:45:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 10:45:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 10:45:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 10:45:20 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:45:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:45:20 INFO - Cleaning up... 10:45:21 INFO - Return code: 0 10:45:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:45: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')]} 10:45:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:45:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:45: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')]} 10:45:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:45:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:45:21 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 0x1072c9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1072d04c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b1948fdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10716dd30>, '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 0x106fd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107164b20>, '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.9gZ1hdvNsI/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.RL9Ktcv07c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 10:45:21 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 10:45:21 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 10:45:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9gZ1hdvNsI/Render', 10:45:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:45:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:45:21 INFO - 'HOME': '/Users/cltbld', 10:45:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:45:21 INFO - 'LOGNAME': 'cltbld', 10:45:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:45:21 INFO - 'MOZ_NO_REMOTE': '1', 10:45:21 INFO - 'NO_EM_RESTART': '1', 10:45:21 INFO - 'PAGER': '/bin/cat', 10:45:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:45:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:45:21 INFO - 'PWD': '/builds/slave/test', 10:45:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:45:21 INFO - 'SHELL': '/bin/bash', 10:45:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RL9Ktcv07c/Listeners', 10:45:21 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 10:45:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:45:21 INFO - 'USER': 'cltbld', 10:45:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:45:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:45:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:45:21 INFO - 'XPC_FLAGS': '0x0', 10:45:21 INFO - 'XPC_SERVICE_NAME': '0', 10:45:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:45:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:45:21 INFO - Downloading/unpacking jsonschema 10:45:21 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 10:45:21 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 10:45:21 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 10:45:21 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 10:45:21 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 10:45:21 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 10:45:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:45:23 INFO - Downloading/unpacking functools32 (from jsonschema) 10:45:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:45:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:45:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:45:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:45:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:45:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:45:24 INFO - Downloading functools32-3.2.3-2.tar.gz 10:45:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:45:24 INFO - warning: no files found matching '*.txt' 10:45:24 INFO - no previously-included directories found matching 'build' 10:45:24 INFO - no previously-included directories found matching 'dist' 10:45:24 INFO - no previously-included directories found matching '.git*' 10:45:24 INFO - Installing collected packages: jsonschema, functools32 10:45:24 INFO - Running setup.py install for functools32 10:45:24 INFO - warning: no files found matching '*.txt' 10:45:24 INFO - no previously-included directories found matching 'build' 10:45:24 INFO - no previously-included directories found matching 'dist' 10:45:24 INFO - no previously-included directories found matching '.git*' 10:45:24 INFO - Successfully installed jsonschema functools32 10:45:24 INFO - Cleaning up... 10:45:24 INFO - Return code: 0 10:45:24 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 10:45:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:45:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:45:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:45:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:45:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:45:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:45:24 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 0x1072c9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1072d04c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b1948fdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10716dd30>, '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 0x106fd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107164b20>, '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.9gZ1hdvNsI/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.RL9Ktcv07c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 10:45:24 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 10:45:24 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 10:45:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9gZ1hdvNsI/Render', 10:45:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:45:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:45:24 INFO - 'HOME': '/Users/cltbld', 10:45:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:45:24 INFO - 'LOGNAME': 'cltbld', 10:45:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:45:24 INFO - 'MOZ_NO_REMOTE': '1', 10:45:24 INFO - 'NO_EM_RESTART': '1', 10:45:24 INFO - 'PAGER': '/bin/cat', 10:45:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:45:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:45:24 INFO - 'PWD': '/builds/slave/test', 10:45:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:45:24 INFO - 'SHELL': '/bin/bash', 10:45:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RL9Ktcv07c/Listeners', 10:45:24 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 10:45:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:45:24 INFO - 'USER': 'cltbld', 10:45:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:45:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:45:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:45:24 INFO - 'XPC_FLAGS': '0x0', 10:45:24 INFO - 'XPC_SERVICE_NAME': '0', 10:45:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:45:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:45:24 INFO - Downloading/unpacking flake8 10:45:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:45:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:45:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:45:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:45:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:45:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:45:27 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 10:45:27 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 10:45:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:45:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:45:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:45:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:45:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:45:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:45:27 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 10:45:27 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 10:45:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:45:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:45:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:45:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:45:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:45:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:45:27 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 10:45:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:45:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:45:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:45:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:45:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:45:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:45:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 10:45:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:45:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:45:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:45:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:45:28 INFO - no previously-included directories found matching 'docs/_build' 10:45:28 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 10:45:28 INFO - Running setup.py install for pep8 10:45:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:45:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:45:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:45:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:45:28 INFO - no previously-included directories found matching 'docs/_build' 10:45:28 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 10:45:28 INFO - Successfully installed flake8 mccabe pyflakes pep8 10:45:28 INFO - Cleaning up... 10:45:28 INFO - Return code: 0 10:45:28 INFO - Running post-action listener: _resource_record_post_action 10:45:28 INFO - Running post-action listener: _start_resource_monitoring 10:45:28 INFO - Starting resource monitoring. 10:45:28 INFO - ##### 10:45:28 INFO - ##### Running install step. 10:45:28 INFO - ##### 10:45:28 INFO - Running pre-action listener: _resource_record_pre_action 10:45:28 INFO - Running main action method: install 10:45:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:45:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:45:28 INFO - Reading from file tmpfile_stdout 10:45:28 INFO - Detecting whether we're running mozinstall >=1.0... 10:45:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:45:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:45:29 INFO - Reading from file tmpfile_stdout 10:45:29 INFO - Output received: 10:45:29 INFO - Usage: mozinstall [options] installer 10:45:29 INFO - Options: 10:45:29 INFO - -h, --help show this help message and exit 10:45:29 INFO - -d DEST, --destination=DEST 10:45:29 INFO - Directory to install application into. [default: 10:45:29 INFO - "/builds/slave/test"] 10:45:29 INFO - --app=APP Application being installed. [default: firefox] 10:45:29 INFO - mkdir: /builds/slave/test/build/application 10:45:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:45:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 10:46:01 INFO - Reading from file tmpfile_stdout 10:46:01 INFO - Output received: 10:46:01 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 10:46:01 INFO - Running post-action listener: _resource_record_post_action 10:46:01 INFO - ##### 10:46:01 INFO - ##### Running run-tests step. 10:46:01 INFO - ##### 10:46:01 INFO - Running pre-action listener: _resource_record_pre_action 10:46:01 INFO - Running main action method: run_tests 10:46:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:46:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:46:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:46:01 INFO - 2016-04-18 10:46:01.218 screenresolution[1639:7432] starting screenresolution argv=screenresolution get 10:46:01 INFO - 2016-04-18 10:46:01.221 screenresolution[1639:7432] Display 0: 1600x1200x32@60 10:46:01 INFO - 2016-04-18 10:46:01.227 screenresolution[1640:7434] starting screenresolution argv=screenresolution list 10:46:01 INFO - Available Modes on Display 0 10:46:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:46:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:46:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:46:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:46:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:46:01 INFO - Intel Iris: 10:46:01 INFO - Chipset Model: Intel Iris 10:46:01 INFO - Type: GPU 10:46:01 INFO - Bus: Built-In 10:46:01 INFO - VRAM (Dynamic, Max): 1536 MB 10:46:01 INFO - Vendor: Intel (0x8086) 10:46:01 INFO - Device ID: 0x0a2e 10:46:01 INFO - Revision ID: 0x0009 10:46:01 INFO - Displays: 10:46:01 INFO - Display: 10:46:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:46:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:46:01 INFO - Display Serial Number: 1600x1200 60 10:46:01 INFO - Main Display: Yes 10:46:01 INFO - Mirror: Off 10:46:01 INFO - Online: Yes 10:46:01 INFO - Rotation: Supported 10:46:01 INFO - Return code: 0 10:46:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:46:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:46:01 INFO - Python 2.7.3 10:46:01 INFO - Return code: 0 10:46:01 INFO - grabbing minidump binary from tooltool 10:46:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:46:01 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10716dd30>, '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 0x106fd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107164b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:46:01 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 10:46:01 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 10:46:01 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:46:01 INFO - Return code: 0 10:46:01 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:46:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:46:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:46:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:46:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:46:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 10:46:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 10:46:02 INFO - Return code: 0 10:46:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Vxbri5mVQxG84v3-50tBDg/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0169', '--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 10:46:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Vxbri5mVQxG84v3-50tBDg/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0169 --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 10:46:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9gZ1hdvNsI/Render', 10:46:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:46:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:46:02 INFO - 'HOME': '/Users/cltbld', 10:46:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:46:02 INFO - 'LOGNAME': 'cltbld', 10:46:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:46:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:46:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:46:02 INFO - 'MOZ_NO_REMOTE': '1', 10:46:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:46:02 INFO - 'NO_EM_RESTART': '1', 10:46:02 INFO - 'PAGER': '/bin/cat', 10:46:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:46:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:46:02 INFO - 'PWD': '/builds/slave/test', 10:46:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:46:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:46:02 INFO - 'SHELL': '/bin/bash', 10:46:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RL9Ktcv07c/Listeners', 10:46:02 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 10:46:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:46:02 INFO - 'USER': 'cltbld', 10:46:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:46:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:46:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:46:02 INFO - 'XPC_FLAGS': '0x0', 10:46:02 INFO - 'XPC_SERVICE_NAME': '0', 10:46:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:46:02 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Vxbri5mVQxG84v3-50tBDg/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0169', '--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 10:46:02 INFO - mozversion application_buildid: 20160418094943 10:46:02 INFO - mozversion application_changeset: 1a229bf7891d7b453eb6e5269c11ace513e400a4 10:46:02 INFO - mozversion application_display_name: FirefoxDeveloperEdition 10:46:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:46:02 INFO - mozversion application_name: Firefox 10:46:02 INFO - mozversion application_remotingname: firefox-dev 10:46:02 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:46:02 INFO - mozversion application_vendor: Mozilla 10:46:02 INFO - mozversion application_version: 47.0a2 10:46:02 INFO - mozversion platform_buildid: 20160418094943 10:46:02 INFO - mozversion platform_changeset: 1a229bf7891d7b453eb6e5269c11ace513e400a4 10:46:02 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:46:02 INFO - mozversion platform_version: 47.0a2 10:46:02 INFO - using testdate: 1461001562 10:46:02 INFO - actual date: 1461001562 10:46:02 INFO - starting webserver on 'localhost:49283' 10:46:02 INFO - SUITE-START | Running 2 tests 10:46:02 INFO - TEST-START | dromaeo_css 10:46:02 INFO - operating with platform_type : mac_ 10:46:02 INFO - Initialising browser for dromaeo_css test... 10:46:02 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/tmpaywSi9/profile http://localhost:49283/getInfo.html) 10:46:05 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 10:46:05 INFO - PROCESS | 1658 | colorDepth:24 10:46:05 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 10:46:05 INFO - PROCESS | 1658 | __metrics 10:46:05 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" 10:46:05 INFO - PROCESS | 1658 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:46:05 INFO - TEST-INFO | 1658: exit 0 10:46:05 INFO - Browser initialized. 10:46:05 INFO - Running cycle 1/1 for dromaeo_css test... 10:46:06 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/tmpaywSi9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:46:17 INFO - PROCESS | 1660 | RSS: Main: 207253504 10:46:17 INFO - PROCESS | 1660 | 10:47:08 INFO - PROCESS | 1660 | 1461001628995 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] 10:49:19 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49283/tests/dromaeo/cssquery-ext.html) 10:49:20 INFO - PROCESS | 1660 | RSS: Main: 295677952 10:49:20 INFO - PROCESS | 1660 | 10:52:23 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-ext.html (next: http://localhost:49283/tests/dromaeo/cssquery-jquery.html) 10:52:23 INFO - PROCESS | 1660 | RSS: Main: 348020736 10:52:23 INFO - PROCESS | 1660 | 10:55:25 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49283/tests/dromaeo/cssquery-mootools.html) 10:55:26 INFO - PROCESS | 1660 | RSS: Main: 350380032 10:55:26 INFO - PROCESS | 1660 | 10:58:28 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49283/tests/dromaeo/cssquery-prototype.html) 10:58:29 INFO - PROCESS | 1660 | RSS: Main: 306638848 10:58:29 INFO - PROCESS | 1660 | 11:01:32 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49283/tests/dromaeo/cssquery-yui.html) 11:01:32 INFO - PROCESS | 1660 | RSS: Main: 308383744 11:01:32 INFO - PROCESS | 1660 | 11:04:35 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-yui.html (next: http://localhost:49283/tests/dromaeo/cssquery-dojo.html) 11:04:35 INFO - PROCESS | 1660 | RSS: Main: 356560896 11:04:35 INFO - PROCESS | 1660 | 11:04:35 INFO - PROCESS | 1660 | __start_tp_report 11:04:35 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 11:04:35 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 11:04:35 INFO - PROCESS | 1660 | |i|pagename|runs| 11:04:35 INFO - PROCESS | 1660 | |0;dojo.html;2551.0561092395815;3106.6116735408073;3426.7087297579706;3463.6709512596312;3529.417646088394;5653.123765816305;5680;5681.659100453976;5684.801031963882;5692.146178073291;5652.406497317784;5701.714914254291;5705.74324155413;5712.514436272912;5716.371199168088;5043.092243396187;5103.617228707843;5184.326037615105;5206.5834733221445;5251.159814429692;5189.714565698885;5204.869878253036;5205.843824685266;5209.973950130244;5210.76551555181;4304.24675681756;5219.973900130513;5254.316938797951;5261.289725887013;5280.102382594968;5250.684958902478;5305.628605997582;5307.495787900143;5324.893502129975;5325.4940780625775;5057.2666963290485;5134.871628209287;5225.268462415265;5253.290805741252;5268.683878967236;5567.693776842272;5580.637258578179;5601.159826026088;5646.2377579027125;5652.830415087554;6114.327423983358;6183.443490085869;6299.716512756937;6308.684565771693;6311.242650881878;6162.7226774795245;6210.503159747254;6226.906596401058;6242.875142497124;6260.217472815898;5595.664260144405;5600.775968961196;5607.214989901417;5643;5658.91511627326;6241.469475094668;6245.469135123565;6257.812265632038;6259.96870015647;6263.812085637438;5879.941200588025;5914.76340946366;5919.970400148058;6099.84750381244;6116.785912493041;6273.8745225095245;6280.183576135073;6308.936910630927;6309.716062777186;6323.747050118036;5270.973645131749;6180.227471566054;6252.187215662026;6257;6261.311255761862;6071.574989750763;6153.507719382439;6160.568760186834;6186.4741496972365;6280.2777680566405;4823.782929768169;4884.926726099111;4888.193448081027;4895.069936712013;4929.014197160583;4428.800703968329;4447.911041779211;4454.777261136995;4454.799534020912;4466;5596.4123767003875;5682.119271512922;5739.253896993364;5758.712064396764;5804.912926306109;6229.781957631551;6314.305426403092;6323.715432805535;6324.71538780756;6347.492200624031;5639.238702775155;5687.345955215121;5693.772249109989;5733.541316694655;5785;5249.606279529051;5297.17893726473;5371.704556249444;5375.16684913839;5382.219578161144;6263.154474145932;6270.372962703785;6285;6306.495480361468;6314.084457753692;6032.939670603238;6072.362401947908;6096.0855871619615;6102.298235702773;6103.145559621567;4781.737004464788;6198.442140207403;6211.503079753518;6243.656598886923;6301.338359472189;6174.166487524307;6216.937830621817;6224.906626400698;6237.812865614039;6260.655663938345;6249.218847644044;6259.405356491126;6271.090691849565;6293.874122517433;6298.370162983665;5702.45826646468;6093.695315234132;6109.69451527413;6124.411449640054;6134.355892631388;5895.557833162444;6075.27096748393;6081.878362432815;6093.329733729198;6112.786052488142;5962.880742385215;5965.194698715618;5966.134910437875;5972.223610930551;5973.820785376446;5992.730327135202;6037.849053773691;6096.055111457731;6154.046122850965;6160.353162917829;5728.799492017926;5782.074868021096;5784.681842498703;5786.537077033743;5847.093700476433 11:04:35 INFO - PROCESS | 1660 | |1;ext.html;485.5751217684526;593.9970300148495;604.3744724210441;617.8053913017397;664.3323459922777;39926.20147597046;40189.19621607566;40281.39577906326;40291.395629065584;40449.79775101124;43719.78140109303;44032.89917752058;44271;44283.55716442835;44287;9598.184154346889;9822.901770982271;9830.754231144243;9834.459104749236;9845.310828242025;9600.087991640783;9734;9735.951320243388;9741.853872191923;9753.170980466668;9836.163926066292;9890.406575605486;9902.55438505269;9910.950445247763;9913.851292230622;9810.558524866397;9876.308658393915;9896.50517474127;9904.851427228561;9908.653197138066;9709.61161553537;9847.655332063343;9868.703938881845;9874.259430542737;9874.703758887244;42979.57020429818;43352.3497147543;43444.913877153005;43464.78267608642;43499.78250108729;3198.1684761962056;3290.7038366547126;3307.1566750478714;3435.1927297085167;3728.291624591319;29807.70192298071;29857.70142298571;29935.401291974256;30262.24344391386;30278.24304392386;2701.1761412769097;2776.8056236063485;2802.7477527022666;2833.8583070846375;2836.489431902257;498627;499030;499976;500643.4967825174;501013;68497;68966.65516672484;68971.3102868975;68994.65502672455;69050.3094969054;71632.64183679048;72090.63954680193;81713;81788.18211817925;81849.18150818534;18716.157772900253;18789;18991.620167596848;18993.715094273597;18994.14526346318;14238.2168980705;14291.64270893236;14309.499167529086;14326.212058336683;14352.641183970276;22756.772432275797;23207.88396058009;23221.9550120245;23233;23254;8991.246706892092;9223.631054757734;9223.76940576491;9228.723138305862;9272;960.2558017536466;990.9801803964025;1079.4494807648157;1100.0484580837679;1106.7233191702076;30473.93341233046;30600.54099188514;30664.693353066184;30998.845005774827;31563.684363156073;1127.785720713062;1161.0305394995273;1168.485866218861;1193.397334346167;1196.557273808679;15410.152441615595;15739.527814165593;15760.605984850241;15783.763243551355;16044;554660;558393;558427;559936;560401;61337.693311533156;63024.054639180446;63090.05364919438;63460.365396346366;63595.68202158959;1401.8668226518464;1417.0010142849267;1417.9291035448186;1417.936547589308;1531.157863175249;28872.566911496764;31157;31230.531542027344;31345.8432707835;32136.357272853944;52628.42114736564;52788;53431.19853202283;53522.19716704175;53644;382722;383571;383696;383995;384454;30128.69871301259;34351.797687080834;35329;35484.46773298454;35804.10489737777;43935.56064439315;44179.11641767211;44574.10851783011;45500.08999819919;45732.77133614311;34791.13022174466;35667.46498802468;36689.44965825461;37179.62820371761;37239;28716.707748151803;35984.460233097045;36034.819825900704;36119.27761444704;37025;33101.00696979094;38141.809290953366;38405.4239186418;39406.81779546618;39467;29733.405331893675;31936.46096788008;32364;33190;34056.829715851265;25176.74823251744;25257.47425257533;25311.987520498973;25667.229983100537;25958.610620840325 11:04:35 INFO - PROCESS | 1660 | |2;jquery.html;370.68676967962074;371.3742344150109;376.2512599926148;378.9583145853957;380.9142942837862;17605;21025;21867.890660546676;22565.774342256613;22591.983360748785;21304.8934755326;21795.67306490404;22038.11847526097;22051.66922496164;22290.88545572723;4887.437944636368;5911.763529458816;5975.701214939258;6009.789657361993;6020.12708157317;5827.446392592718;5851.122331650266;5875.324337701177;5884.352721200665;5887.1463637772495;5703.486686198241;5785.16115163301;5810.360860305363;5874.118882167688;5877.235959325303;5599.552035837143;5746.224259724925;5782;5783.826485205451;5789.971050144764;5031.258412850347;5073.213651883964;5083.720395378253;5091.46539613339;5091.516305950929;21891.233806816686;24530.52816830996;26405.471890562272;26764.73235267642;27232;2243.4279258789065;2262.1743063781764;2272.693186419845;2891.9855400722863;2953.896613618513;14490.275486225752;15214.543563693107;15324.770128448083;15469.149196794171;15623.609409764733;1717.9742303865453;1728.2741248675586;1729.1095086030705;1762.5769815244378;1785.7321401789707;72350;78647;105276.94723052824;105464.41803372955;110090.9999999992;11196.440177991068;11634.476448559835;11690;11742.94128529369;11810.940945295219;12819.551315703906;13133.212007279593;13431.723986221294;14008.089474184146;14009.929950350182;6743.797686069426;6815.897761533581;8551.529665868436;8935.508547029845;9050.275977921881;9104.817903642022;9111.088891110836;9148.725538233864;9181.495017773954;9236.261099112055;3889.5138107736534;4345.718013186118;4468.570057581608;4552.839026048337;4598.195315819718;1882.5670095878024;1910.6656335141572;1914.3299845054119;1924.7209154672494;1937.9612407751765;952.7903861150536;993.2897977945803;1041.5885725138528;1051.7160366700948;1064.1699474409972;21120.577588448454;21593.67609485859;21631.35105946824;21649.13403463844;21862.672059919114;969.3214749675114;997.9800403991879;1012.4380968562372;1025.0723095598496;1039.4802598700649;7813.945117409191;11382.373969431595;11507.482163302671;11578.189526733047;11584.957353838026;672100;675859;676925;686813.527458906;688709;121144;131871;136399;137672;138199;1101.8071837428417;1118.5917140243914;1175.482787573465;1202.4108186988312;1202.4348556178581;19145.61708765789;19620.705689414957;19897.801021989595;19976;20225.88757618345;56109;57125;58037;58462.415375845696;59524.40475595189;82261.58869205616;82692.75960860471;84366;84606;84743.15256847352;8302.83394332122;8312.6674933002;8386.245237928615;8542.82914341722;8757.211850933447;11309.547618095514;11650.76698466043;13017.088803783645;13453.596392108253;13983;11763.353015584225;11855.407229638657;11858.16992810495;11992.280463171888;12045.81931271014;11406.201565890307;11560.190786644855;11683.240589361665;11687.298762074302;11929.880701192877;6162.691865406801;9606.711798646053;11126.388048657078;11966.820497692368;12094.637160885188;8242.134575869448;8274.29668478186;8433.620487077973;8536.70121545743;8648.943016981188;11091.667249982513;11929.522819087138;12021.218620789621;12153.635390938285;12154.027677785933 11:04:35 INFO - PROCESS | 1660 | |3;mootools.html;1501.3169008101315;1642.9753553696696;1660.078656345728;2005.1979208316673;2084.0621720225904;8326.500409975406;8879.778005549864;9138.040505746883;9138.086191380859;9158.49628270445;8961.283097352212;9237;9238.353315267934;9242.815143697138;9243.214326782232;2254.8534345267585;2315.4211447138214;2316.8494047886834;2548.796096312295;2587.275562842398;2525.103588226181;2599.350162459385;2602.7137014928344;2604.1146010356474;2604.817662763607;2486.105002199207;2540.9491810163786;2552.0302285131625;2559.6672432583737;2559.833610815291;2522.798176145904;2541.6568763216915;2542.86842355152;2548.834325768819;2577.1753039027517;2239.8688662225636;2556.629288753139;2563.3079068651546;2563.3591602099477;2565.448428587844;9088.136627020422;9315.813683726356;9321.02129276423;9340.626374944994;9376;5559.305086864142;5728.283964504437;5737.913931291034;5777.248957635523;5784.971075144639;3590.910227244314;3676.466912297702;3719.851205951759;3737.981310093432;3761.9059523511855;1051.2326002018492;1059.5549869055014;1075.784843031389;1077.0898590690854;1089.3953855610134;12673.972164453644;12708.428120734588;12710.682232944158;12713.93643031779;12753.043521735906;4960.727160006234;5061.291419201315;5103.642745007888;5112.897742045213;5118.053160165381;10697.83953240702;10710.571577136827;10722.73193170177;10723;10733.248672592843;5732.971335143381;5783.508401785811;5789.8263052108505;5794.0439827428845;5797.449242321973;4158;4651.232546629836;4767.404074490689;5210.7915683373;5212.113940630102;4055.6552693020835;4103.815328310233;4128.710990230655;4140.60664236897;4180.1848639515;1341.557286095586;1341.9731605368033;1348.1439286053426;1353.1204716934071;1360.5102163221231;1403.4245959156697;1408.7041721238654;1424.472945010332;1447.9131252124905;1451.8693317601467;7534.3595794357;8624.223819856243;8789.736307910773;8862.601182946783;8865.024847266795;1255.5731051442554;1261.2810697902291;1261.4197469164103;1268.8477382714134;1271.0530654662336;6382.648954307558;6457.418832305115;6485.44873685742;6507.9023814642815;6552.312007239267;10676.786464270672;11092;11182.888171118184;11208.27146235492;11331.716707082223;11046.944765276123;11104.333739975627;11157.107431405468;11184.105271578417;11210.159238057178;8674.175953284428;8759.124087591317;8823;8824.5587720615;8903.33225008127;8108.878366824384;8225.753227403187;8248.628811703367;8282.254597086292;8290.088090310182;9971.950140249251;10054.899451005396;10077.69766906994;10086.949565252127;10159.695209143738;10137.08766211015;10303.278770486293;10508.632197873038;10534.578616855546;10697.51861166265;5715.399883012224;6675.265720770809;6830.385265326145;6935.306469353226;7136.4290856730295;7417.443691723034;8585.8712119317;8592.441491303045;8650.91349086501;8657.913420865963;6431.549791514549;7511.812204694927;7585.317321441097;7747.496412733154;7794.376449883882;7433.516821406592;7435.553866768011;7450.254974502506;7546.849063018819;7559.659815308214;7928.920710793049;8126.065502467056;8129.593520323841;8131.674733010614;8163.673453061811;7314.195438501654;7338.229485903903;7346.302101300582;7385.593792341473;7408.62956852166;7425.777226683208;7440.55356678601;7477.925220747723;7494.175640679412;7498.850022999401 11:04:35 INFO - PROCESS | 1660 | |4;prototype.html;329.8565124170985;331.9468884978404;336.89893032090396;339.62471469026724;344.2564061626886;6323.494120470357;6391.584547004453;6418.42234198922;6434.871302573945;6441.4524765395;6351.523635727316;6383.871902767633;6571.769988050419;6572.375624315682;6580.769673061443;3893.357595996657;4643.6517261205545;4685.976570117145;4736.005438857844;4834.4923783002805;3947.6447119759214;4770.165221086313;4787.8324258650955;4788.0423915216925;4799.424069111694;4743.525647435264;4745.505165872756;4758.928616070762;4760.595349395306;4761.476237613877;3890.591487893787;4553.27147656375;4576.199165146103;4769.189237829542;4770.212914869043;4101.384792281152;4108.815103320358;4117.608827161415;4124.278251306039;4129.15352352767;6121.387861213843;6127.387261273881;6145.661988590626;6158.384161583851;6165.938340616582;5551.2228288039705;5558.666480011172;5571.4428557144365;5574.665520068768;5580.386157522669;5555.916661250085;6726.125603671491;6788.456923446112;6806.523543351967;6807.965960170217;4234.407182994383;4406.986393129566;4409.735415875026;4416.734995900256;4424.579664931826;6868.553544019623;7043.542169758949;7129.037579926696;7131.322524360177;7138.964305178492;4473.821047158142;4489.7979590918485;4492.034212644232;4493.370928070073;4506.481754598198;4551.180787458289;4555.498895121534;4562.292844609091;4564.88587785308;4571.405717256735;4643.558861908114;5374.623776335698;5381.381141168816;5384.623076384615;5385.542228910586;6589.423974520921;6661.533692641468;6797.2523022467485;6806.421454176351;6812.693428795617;1559.2437667731142;1569.3251901682386;1579.9921000395154;1584.5484037049387;1587.5792914877566;880.5245167609419;967.4775621164633;984.8719666443317;1011.0546638892658;1038.7091614347994;6043.36544662804;6047.0627052806885;6053.51571874249;6057.4851137653795;6151.015837465984;7074.3633073023675;7079.89380159298;7095.04216930718;7096.716131354791;7121.786346409616;6300.3699630037545;6303.338149494329;6311.684415779193;6327.873442531123;6345.302016778151;3697.352963231424;3735.906602334909;3743.47591337213;3751.8686845960806;3752.418375151856;7061.082059332357;7348.338649521569;7385.9630701846145;7394.704211831574;7395.26047395267;6540.509461790385;6689.531732778659;7271.454640901953;7282.635868206568;7302.488825782144;7380.335769780745;7384.9261507384235;7392.519486233377;7393;7393.445491588152;5815.244018277597;6997.965010174917;7067.646617669199;7084.3351812323035;7098.219195888551;6653.900191497035;6945.791626251221;6961.89557156633;6973.267806880204;6986.301369862973;7308.59802710856;7329.413646908341;7341.302576255247;7355.448341374526;7364.895265710186;4512.165249428866;4513.954860451354;4526.637868970541;4531.116432295671;4538.160440318552;7038.894416583653;7051.294870512907;7063.328983746535;7067.116610423697;7068.858622827412;6515.153030106056;6796.388325050769;6802.081718967876;6814.386705196556;6815.522913396015;6737.865242695217;6774.8645027100165;6786.898196526958;6788.762393316408;6795.38841504267;6631.469482441296;6649.501287403561;6650.73397064112;6656.201255849329;6668.799936001928;6783.18601767791;6787.966060169668;6797.626130562867;6798.932010680028;6808.693608787517;6403.96798016007;6460.45086167681;6502.479801615765;6624.171978502687;6633.270340262471 11:04:35 INFO - PROCESS | 1660 | |5;yui.html;720.2725247500025;771.5062360089544;781.9491733037356;784.2432053068791;789.8933643958068;15217.847821521782;15376.846231537682;15402.614934626612;15404.614884627876;15416.460423885166;14039.087459315162;14150.787738183935;14194.503192388269;14212.502562410318;14279.286035698226;768.0437854870694;770.5145758172343;952.7284723853703;967.405045896772;969.486172328667;943.6744323208517;972.8248915195262;974.6101559376235;975.5073687787657;978.3298440568234;1032.6850310655248;1032.7624646331349;1033.5968972100886;1034.5499707627168;1035.4926086217774;801.8396320735818;804.3324041045918;809.1988930958368;1023.0741179232806;1027.691692492253;617.4134572156424;736.8010637127999;737.7749786315172;739.268124556685;745.5489428895548;14355.856441435557;14359.78460323096;14376.065555738844;14380.35288412024;14393.640158996004;12908.54820081302;13228.338583070905;13426.597202083953;13499.460021599125;13655.38550765218;9832.557534910946;9912.256580756472;10041.397516149054;10076.647317343932;10154.9492252539;3470.305938812248;3476.8087755173456;3623.438367053111;3642.05306620278;3644.034330902313;64060;69609.30390696079;79888;84266.57866710688;84297.57851210705;8314.708985185489;8684.8697269541;10031.498425078717;10047.24645651579;10082.69751907444;19242;23308.883455582614;23336.766632333798;23387.76612233856;23406.64890026651;7595.164531901486;7603.7338693146585;7670.156282808886;7820.633521128241;7935.761887563473;4098.4876890388705;4127.926739047809;4160.83356665736;4289.528151903288;4445.666575006888;3771.773693578339;3815.828287727059;3821.293060783764;3823.885283441501;3828.1386687994977;642.787879999599;651.1047309948821;673.4847841401332;687.5393486364048;688.1088989758275;534.3347532322283;552.0559842669081;560.8065217499956;573.5640912906138;587.6297932302622;8063.556504392197;8464.32285417165;8500.029737713432;8577.82844343122;8654.48073115615;553.5156737854378;596.6181643748005;619.5508256513991;620.9968950155311;632.5572099530348;3778.4332350147147;3968.8171505671767;3998.5801490843623;4002.9199416012098;4055.9391609125887;71986.64006680038;90249;91874;92531.53734231286;92835;81958;82232;82576;83008.58495707483;84068;67429.32570674329;72666.90999634904;73937.6303118481;74959.1260218488;75139.62430187814;8540.40217184791;8702.944650105728;8778.56107194625;8811.603477843384;8839.46963182211;43833.465828695844;51315.743421282656;51612.93548258027;53073.469265306856;55558.611034724454;51939.480605193465;55549;74568;76570.23429765854;78725;10994.670159895217;11237.55049797999;11447.942760286145;11509.53961841517;12121.515139394325;11947.223430476992;12133.119366498207;12196.390180490762;12242.326672033123;12282.938585307016;11691.883081169079;11825.940870295593;11850.407479626158;11948.641540753792;12195.268283902995;11448.885511144781;11519.8272025918;11816.82274765862;12180.756384872077;12209.69475763113;10753.301035432985;10882.945585272022;11075.778484430106;11243.156763242627;11439.428028598702;9395.859062113937;9797.216222702024;9842.11420972116;9876.160526355341;9981.401115932777;9383.436993780395;9756.658516951873;9856.556454959687;9906.356086854332;9981.171340798674 11:04:35 INFO - PROCESS | 1660 | __end_tp_report 11:04:35 INFO - PROCESS | 1660 | __start_cc_report 11:04:35 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,1187 11:04:35 INFO - PROCESS | 1660 | __end_cc_report 11:04:35 INFO - PROCESS | 1660 | __startTimestamp1461002675871__endTimestamp 11:04:35 INFO - PROCESS | 1660 | 11:04:35 INFO - PROCESS | 1660 | ------- Summary: start ------- 11:04:35 INFO - PROCESS | 1660 | Number of tests: 6 11:04:35 INFO - PROCESS | 1660 | 11:04:35 INFO - PROCESS | 1660 | [#0] dojo.html Cycles:165 Average:5699.99 Median:5847.09 stddev:662.97 (11.3%) stddev-sans-first:617.27 11:04:35 INFO - PROCESS | 1660 | Values: 2551.1 3106.6 3426.7 3463.7 3529.4 5653.1 5680.0 5681.7 5684.8 5692.1 5652.4 5701.7 5705.7 5712.5 5716.4 5043.1 5103.6 5184.3 5206.6 5251.2 5189.7 5204.9 5205.8 5210.0 5210.8 4304.2 5220.0 5254.3 5261.3 5280.1 5250.7 5305.6 5307.5 5324.9 5325.5 5057.3 5134.9 5225.3 5253.3 5268.7 5567.7 5580.6 5601.2 5646.2 5652.8 6114.3 6183.4 6299.7 6308.7 6311.2 6162.7 6210.5 6226.9 6242.9 6260.2 5595.7 5600.8 5607.2 5643.0 5658.9 6241.5 6245.5 6257.8 6260.0 6263.8 5879.9 5914.8 5920.0 6099.8 6116.8 6273.9 6280.2 6308.9 6309.7 6323.7 5271.0 6180.2 6252.2 6257.0 6261.3 6071.6 6153.5 6160.6 6186.5 6280.3 4823.8 4884.9 4888.2 4895.1 4929.0 4428.8 4447.9 4454.8 4454.8 4466.0 5596.4 5682.1 5739.3 5758.7 5804.9 6229.8 6314.3 6323.7 6324.7 6347.5 5639.2 5687.3 5693.8 5733.5 5785.0 5249.6 5297.2 5371.7 5375.2 5382.2 6263.2 6270.4 6285.0 6306.5 6314.1 6032.9 6072.4 6096.1 6102.3 6103.1 4781.7 6198.4 6211.5 6243.7 6301.3 6174.2 6216.9 6224.9 6237.8 6260.7 6249.2 6259.4 6271.1 6293.9 6298.4 5702.5 6093.7 6109.7 6124.4 6134.4 5895.6 6075.3 6081.9 6093.3 6112.8 5962.9 5965.2 5966.1 5972.2 5973.8 5992.7 6037.8 6096.1 6154.0 6160.4 5728.8 5782.1 5784.7 5786.5 5847.1 11:04:35 INFO - PROCESS | 1660 | 11:04:35 INFO - PROCESS | 1660 | [#1] ext.html Cycles:180 Average:64183.02 Median:211930.41 stddev:129156.44 (60.9%) stddev-sans-first:129430.21 11:04:35 INFO - PROCESS | 1660 | Values: 485.6 594.0 604.4 617.8 664.3 39926.2 40189.2 40281.4 40291.4 40449.8 43719.8 44032.9 44271.0 44283.6 44287.0 9598.2 9822.9 9830.8 9834.5 9845.3 9600.1 9734.0 9736.0 9741.9 9753.2 9836.2 9890.4 9902.6 9911.0 9913.9 9810.6 9876.3 9896.5 9904.9 9908.7 9709.6 9847.7 9868.7 9874.3 9874.7 42979.6 43352.3 43444.9 43464.8 43499.8 3198.2 3290.7 3307.2 3435.2 3728.3 29807.7 29857.7 29935.4 30262.2 30278.2 2701.2 2776.8 2802.7 2833.9 2836.5 498627.0 499030.0 499976.0 500643.5 501013.0 68497.0 68966.7 68971.3 68994.7 69050.3 71632.6 72090.6 81713.0 81788.2 81849.2 18716.2 18789.0 18991.6 18993.7 18994.1 14238.2 14291.6 14309.5 14326.2 14352.6 22756.8 23207.9 23222.0 23233.0 23254.0 8991.2 9223.6 9223.8 9228.7 9272.0 960.3 991.0 1079.4 1100.0 1106.7 30473.9 30600.5 30664.7 30998.8 31563.7 1127.8 1161.0 1168.5 1193.4 1196.6 15410.2 15739.5 15760.6 15783.8 16044.0 554660.0 558393.0 558427.0 559936.0 560401.0 61337.7 63024.1 63090.1 63460.4 63595.7 1401.9 1417.0 1417.9 1417.9 1531.2 28872.6 31157.0 31230.5 31345.8 32136.4 52628.4 52788.0 53431.2 53522.2 53644.0 382722.0 383571.0 383696.0 383995.0 384454.0 30128.7 34351.8 35329.0 35484.5 35804.1 43935.6 44179.1 44574.1 45500.1 45732.8 34791.1 35667.5 36689.4 37179.6 37239.0 28716.7 35984.5 36034.8 36119.3 37025.0 33101.0 38141.8 38405.4 39406.8 39467.0 29733.4 31936.5 32364.0 33190.0 34056.8 25176.7 25257.5 25312.0 25667.2 25958.6 11:04:35 INFO - PROCESS | 1660 | 11:04:35 INFO - PROCESS | 1660 | [#2] jquery.html Cycles:180 Average:37351.51 Median:12404.60 stddev:112595.08 (907.7%) stddev-sans-first:112876.70 11:04:35 INFO - PROCESS | 1660 | Values: 370.7 371.4 376.3 379.0 380.9 17605.0 21025.0 21867.9 22565.8 22592.0 21304.9 21795.7 22038.1 22051.7 22290.9 4887.4 5911.8 5975.7 6009.8 6020.1 5827.4 5851.1 5875.3 5884.4 5887.1 5703.5 5785.2 5810.4 5874.1 5877.2 5599.6 5746.2 5782.0 5783.8 5790.0 5031.3 5073.2 5083.7 5091.5 5091.5 21891.2 24530.5 26405.5 26764.7 27232.0 2243.4 2262.2 2272.7 2892.0 2953.9 14490.3 15214.5 15324.8 15469.1 15623.6 1718.0 1728.3 1729.1 1762.6 1785.7 72350.0 78647.0 105276.9 105464.4 110091.0 11196.4 11634.5 11690.0 11742.9 11810.9 12819.6 13133.2 13431.7 14008.1 14009.9 6743.8 6815.9 8551.5 8935.5 9050.3 9104.8 9111.1 9148.7 9181.5 9236.3 3889.5 4345.7 4468.6 4552.8 4598.2 1882.6 1910.7 1914.3 1924.7 1938.0 952.8 993.3 1041.6 1051.7 1064.2 21120.6 21593.7 21631.4 21649.1 21862.7 969.3 998.0 1012.4 1025.1 1039.5 7813.9 11382.4 11507.5 11578.2 11585.0 672100.0 675859.0 676925.0 686813.5 688709.0 121144.0 131871.0 136399.0 137672.0 138199.0 1101.8 1118.6 1175.5 1202.4 1202.4 19145.6 19620.7 19897.8 19976.0 20225.9 56109.0 57125.0 58037.0 58462.4 59524.4 82261.6 82692.8 84366.0 84606.0 84743.2 8302.8 8312.7 8386.2 8542.8 8757.2 11309.5 11650.8 13017.1 13453.6 13983.0 11763.4 11855.4 11858.2 11992.3 12045.8 11406.2 11560.2 11683.2 11687.3 11929.9 6162.7 9606.7 11126.4 11966.8 12094.6 8242.1 8274.3 8433.6 8536.7 8648.9 11091.7 11929.5 12021.2 12153.6 12154.0 11:04:35 INFO - PROCESS | 1660 | 11:04:35 INFO - PROCESS | 1660 | [#3] mootools.html Cycles:180 Average:6309.16 Median:4709.32 stddev:3345.96 (71.0%) stddev-sans-first:3335.83 11:04:35 INFO - PROCESS | 1660 | Values: 1501.3 1643.0 1660.1 2005.2 2084.1 8326.5 8879.8 9138.0 9138.1 9158.5 8961.3 9237.0 9238.4 9242.8 9243.2 2254.9 2315.4 2316.8 2548.8 2587.3 2525.1 2599.4 2602.7 2604.1 2604.8 2486.1 2540.9 2552.0 2559.7 2559.8 2522.8 2541.7 2542.9 2548.8 2577.2 2239.9 2556.6 2563.3 2563.4 2565.4 9088.1 9315.8 9321.0 9340.6 9376.0 5559.3 5728.3 5737.9 5777.2 5785.0 3590.9 3676.5 3719.9 3738.0 3761.9 1051.2 1059.6 1075.8 1077.1 1089.4 12674.0 12708.4 12710.7 12713.9 12753.0 4960.7 5061.3 5103.6 5112.9 5118.1 10697.8 10710.6 10722.7 10723.0 10733.2 5733.0 5783.5 5789.8 5794.0 5797.4 4158.0 4651.2 4767.4 5210.8 5212.1 4055.7 4103.8 4128.7 4140.6 4180.2 1341.6 1342.0 1348.1 1353.1 1360.5 1403.4 1408.7 1424.5 1447.9 1451.9 7534.4 8624.2 8789.7 8862.6 8865.0 1255.6 1261.3 1261.4 1268.8 1271.1 6382.6 6457.4 6485.4 6507.9 6552.3 10676.8 11092.0 11182.9 11208.3 11331.7 11046.9 11104.3 11157.1 11184.1 11210.2 8674.2 8759.1 8823.0 8824.6 8903.3 8108.9 8225.8 8248.6 8282.3 8290.1 9972.0 10054.9 10077.7 10086.9 10159.7 10137.1 10303.3 10508.6 10534.6 10697.5 5715.4 6675.3 6830.4 6935.3 7136.4 7417.4 8585.9 8592.4 8650.9 8657.9 6431.5 7511.8 7585.3 7747.5 7794.4 7433.5 7435.6 7450.3 7546.8 7559.7 7928.9 8126.1 8129.6 8131.7 8163.7 7314.2 7338.2 7346.3 7385.6 7408.6 7425.8 7440.6 7477.9 7494.2 7498.9 11:04:35 INFO - PROCESS | 1660 | 11:04:35 INFO - PROCESS | 1660 | [#4] prototype.html Cycles:180 Average:5559.12 Median:6367.70 stddev:1773.68 (27.9%) stddev-sans-first:1734.69 11:04:35 INFO - PROCESS | 1660 | Values: 329.9 331.9 336.9 339.6 344.3 6323.5 6391.6 6418.4 6434.9 6441.5 6351.5 6383.9 6571.8 6572.4 6580.8 3893.4 4643.7 4686.0 4736.0 4834.5 3947.6 4770.2 4787.8 4788.0 4799.4 4743.5 4745.5 4758.9 4760.6 4761.5 3890.6 4553.3 4576.2 4769.2 4770.2 4101.4 4108.8 4117.6 4124.3 4129.2 6121.4 6127.4 6145.7 6158.4 6165.9 5551.2 5558.7 5571.4 5574.7 5580.4 5555.9 6726.1 6788.5 6806.5 6808.0 4234.4 4407.0 4409.7 4416.7 4424.6 6868.6 7043.5 7129.0 7131.3 7139.0 4473.8 4489.8 4492.0 4493.4 4506.5 4551.2 4555.5 4562.3 4564.9 4571.4 4643.6 5374.6 5381.4 5384.6 5385.5 6589.4 6661.5 6797.3 6806.4 6812.7 1559.2 1569.3 1580.0 1584.5 1587.6 880.5 967.5 984.9 1011.1 1038.7 6043.4 6047.1 6053.5 6057.5 6151.0 7074.4 7079.9 7095.0 7096.7 7121.8 6300.4 6303.3 6311.7 6327.9 6345.3 3697.4 3735.9 3743.5 3751.9 3752.4 7061.1 7348.3 7386.0 7394.7 7395.3 6540.5 6689.5 7271.5 7282.6 7302.5 7380.3 7384.9 7392.5 7393.0 7393.4 5815.2 6998.0 7067.6 7084.3 7098.2 6653.9 6945.8 6961.9 6973.3 6986.3 7308.6 7329.4 7341.3 7355.4 7364.9 4512.2 4514.0 4526.6 4531.1 4538.2 7038.9 7051.3 7063.3 7067.1 7068.9 6515.2 6796.4 6802.1 6814.4 6815.5 6737.9 6774.9 6786.9 6788.8 6795.4 6631.5 6649.5 6650.7 6656.2 6668.8 6783.2 6788.0 6797.6 6798.9 6808.7 6404.0 6460.5 6502.5 6624.2 6633.3 11:04:35 INFO - PROCESS | 1660 | 11:04:35 INFO - PROCESS | 1660 | [#5] yui.html Cycles:180 Average:18424.12 Median:28051.26 stddev:25684.42 (91.6%) stddev-sans-first:25722.07 11:04:35 INFO - PROCESS | 1660 | Values: 720.3 771.5 781.9 784.2 789.9 15217.8 15376.8 15402.6 15404.6 15416.5 14039.1 14150.8 14194.5 14212.5 14279.3 768.0 770.5 952.7 967.4 969.5 943.7 972.8 974.6 975.5 978.3 1032.7 1032.8 1033.6 1034.5 1035.5 801.8 804.3 809.2 1023.1 1027.7 617.4 736.8 737.8 739.3 745.5 14355.9 14359.8 14376.1 14380.4 14393.6 12908.5 13228.3 13426.6 13499.5 13655.4 9832.6 9912.3 10041.4 10076.6 10154.9 3470.3 3476.8 3623.4 3642.1 3644.0 64060.0 69609.3 79888.0 84266.6 84297.6 8314.7 8684.9 10031.5 10047.2 10082.7 19242.0 23308.9 23336.8 23387.8 23406.6 7595.2 7603.7 7670.2 7820.6 7935.8 4098.5 4127.9 4160.8 4289.5 4445.7 3771.8 3815.8 3821.3 3823.9 3828.1 642.8 651.1 673.5 687.5 688.1 534.3 552.1 560.8 573.6 587.6 8063.6 8464.3 8500.0 8577.8 8654.5 553.5 596.6 619.6 621.0 632.6 3778.4 3968.8 3998.6 4002.9 4055.9 71986.6 90249.0 91874.0 92531.5 92835.0 81958.0 82232.0 82576.0 83008.6 84068.0 67429.3 72666.9 73937.6 74959.1 75139.6 8540.4 8702.9 8778.6 8811.6 8839.5 43833.5 51315.7 51612.9 53073.5 55558.6 51939.5 55549.0 74568.0 76570.2 78725.0 10994.7 11237.6 11447.9 11509.5 12121.5 11947.2 12133.1 12196.4 12242.3 12282.9 11691.9 11825.9 11850.4 11948.6 12195.3 11448.9 11519.8 11816.8 12180.8 12209.7 10753.3 10882.9 11075.8 11243.2 11439.4 9395.9 9797.2 9842.1 9876.2 9981.4 9383.4 9756.7 9856.6 9906.4 9981.2 11:04:35 INFO - PROCESS | 1660 | -------- Summary: end -------- 11:04:35 INFO - PROCESS | 1660 | 11:04:36 INFO - TEST-INFO | 1660: exit 0 11:04:36 INFO - TEST-OK | dromaeo_css | took 1113416ms 11:04:36 INFO - TEST-START | kraken 11:04:36 INFO - operating with platform_type : mac_ 11:04:36 INFO - Initialising browser for kraken test... 11:04:36 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/tmpIUzztZ/profile http://localhost:49283/getInfo.html) 11:04:38 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 11:04:38 INFO - PROCESS | 1671 | colorDepth:24 11:04:38 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 11:04:38 INFO - PROCESS | 1671 | __metrics 11:04:38 INFO - PROCESS | 1671 | 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" 11:04:38 INFO - PROCESS | 1671 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:04:39 INFO - TEST-INFO | 1671: exit 0 11:04:39 INFO - Browser initialized. 11:04:39 INFO - Running cycle 1/1 for kraken test... 11:04:39 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/tmpIUzztZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:04:51 INFO - PROCESS | 1673 | RSS: Main: 207937536 11:04:51 INFO - PROCESS | 1673 | 11:04:53 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-beat-detection) 11:04:54 INFO - PROCESS | 1673 | RSS: Main: 262733824 11:04:54 INFO - PROCESS | 1673 | 11:04:57 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-dft) 11:04:57 INFO - PROCESS | 1673 | RSS: Main: 273256448 11:04:57 INFO - PROCESS | 1673 | 11:05:00 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-fft) 11:05:01 INFO - PROCESS | 1673 | RSS: Main: 262819840 11:05:01 INFO - PROCESS | 1673 | 11:05:03 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-oscillator) 11:05:03 INFO - PROCESS | 1673 | RSS: Main: 263802880 11:05:03 INFO - PROCESS | 1673 | 11:05:06 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49283/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:05:06 INFO - PROCESS | 1673 | RSS: Main: 263651328 11:05:06 INFO - PROCESS | 1673 | 11:05:09 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49283/tests/kraken/driver.html?testName=imaging-darkroom) 11:05:09 INFO - PROCESS | 1673 | RSS: Main: 264888320 11:05:09 INFO - PROCESS | 1673 | 11:05:12 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49283/tests/kraken/driver.html?testName=imaging-desaturate) 11:05:13 INFO - PROCESS | 1673 | RSS: Main: 264929280 11:05:13 INFO - PROCESS | 1673 | 11:05:16 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49283/tests/kraken/driver.html?testName=json-parse-financial) 11:05:16 INFO - PROCESS | 1673 | RSS: Main: 265269248 11:05:16 INFO - PROCESS | 1673 | 11:05:18 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49283/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:05:19 INFO - PROCESS | 1673 | RSS: Main: 298065920 11:05:19 INFO - PROCESS | 1673 | 11:05:20 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:05:21 INFO - PROCESS | 1673 | RSS: Main: 298807296 11:05:21 INFO - PROCESS | 1673 | 11:05:23 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:05:24 INFO - PROCESS | 1673 | RSS: Main: 306081792 11:05:24 INFO - PROCESS | 1673 | 11:05:26 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:05:27 INFO - PROCESS | 1673 | RSS: Main: 307527680 11:05:27 INFO - PROCESS | 1673 | 11:05:29 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:05:30 INFO - PROCESS | 1673 | RSS: Main: 307589120 11:05:30 INFO - PROCESS | 1673 | 11:05:32 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49283/tests/kraken/driver.html?testName=ai-astar) 11:05:32 INFO - PROCESS | 1673 | RSS: Main: 369627136 11:05:32 INFO - PROCESS | 1673 | 11:05:32 INFO - PROCESS | 1673 | __start_tp_report 11:05:32 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 11:05:32 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 11:05:32 INFO - PROCESS | 1673 | |i|pagename|runs| 11:05:32 INFO - PROCESS | 1673 | |0;ai-astar;112;111;109;109;112;112;120;148;159;114 11:05:32 INFO - PROCESS | 1673 | |1;audio-beat-detection;152;118;153;143;117;137;117;148;146;115 11:05:32 INFO - PROCESS | 1673 | |2;audio-dft;184;184;184;184;185;189;188;182;184;187 11:05:32 INFO - PROCESS | 1673 | |3;audio-fft;71;82;76;120;56;56;59;60;126;81 11:05:32 INFO - PROCESS | 1673 | |4;audio-oscillator;92;96;132;81;78;86;117;76;81;115 11:05:32 INFO - PROCESS | 1673 | |5;imaging-gaussian-blur;88;86;89;86;89;85;90;88;89;84 11:05:32 INFO - PROCESS | 1673 | |6;imaging-darkroom;93;93;94;93;93;94;94;95;94;93 11:05:32 INFO - PROCESS | 1673 | |7;imaging-desaturate;87;89;89;89;93;133;91;94;132;132 11:05:32 INFO - PROCESS | 1673 | |8;json-parse-financial;57;59;58;58;58;59;59;69;56;52 11:05:32 INFO - PROCESS | 1673 | |9;json-stringify-tinderbox;62;71;61;75;69;68;69;66;65;66 11:05:32 INFO - PROCESS | 1673 | |10;stanford-crypto-aes;80;81;81;81;80;81;80;78;81;85 11:05:32 INFO - PROCESS | 1673 | |11;stanford-crypto-ccm;130;125;107;131;132;123;128;130;118;118 11:05:32 INFO - PROCESS | 1673 | |12;stanford-crypto-pbkdf2;133;122;133;158;168;130;133;134;131;135 11:05:32 INFO - PROCESS | 1673 | |13;stanford-crypto-sha256-iterative;67;68;86;92;94;91;65;68;65;65 11:05:32 INFO - PROCESS | 1673 | __end_tp_report 11:05:32 INFO - PROCESS | 1673 | __start_cc_report 11:05:32 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,1209 11:05:32 INFO - PROCESS | 1673 | __end_cc_report 11:05:32 INFO - PROCESS | 1673 | __startTimestamp1461002732493__endTimestamp 11:05:32 INFO - PROCESS | 1673 | 11:05:32 INFO - PROCESS | 1673 | ------- Summary: start ------- 11:05:32 INFO - PROCESS | 1673 | Number of tests: 14 11:05:32 INFO - PROCESS | 1673 | 11:05:32 INFO - PROCESS | 1673 | [#0] ai-astar Cycles:10 Average:120.60 Median:113.00 stddev:17.80 (15.8%) stddev-sans-first:18.61 11:05:32 INFO - PROCESS | 1673 | Values: 112.0 111.0 109.0 109.0 112.0 112.0 120.0 148.0 159.0 114.0 11:05:32 INFO - PROCESS | 1673 | 11:05:32 INFO - PROCESS | 1673 | [#1] audio-beat-detection Cycles:10 Average:134.60 Median:144.50 stddev:16.01 (11.1%) stddev-sans-first:15.69 11:05:32 INFO - PROCESS | 1673 | Values: 152.0 118.0 153.0 143.0 117.0 137.0 117.0 148.0 146.0 115.0 11:05:32 INFO - PROCESS | 1673 | 11:05:32 INFO - PROCESS | 1673 | [#2] audio-dft Cycles:10 Average:185.10 Median:184.50 stddev:2.18 (1.2%) stddev-sans-first:2.28 11:05:32 INFO - PROCESS | 1673 | Values: 184.0 184.0 184.0 184.0 185.0 189.0 188.0 182.0 184.0 187.0 11:05:32 INFO - PROCESS | 1673 | 11:05:32 INFO - PROCESS | 1673 | [#3] audio-fft Cycles:10 Average:78.70 Median:65.50 stddev:25.37 (38.7%) stddev-sans-first:26.76 11:05:32 INFO - PROCESS | 1673 | Values: 71.0 82.0 76.0 120.0 56.0 56.0 59.0 60.0 126.0 81.0 11:05:32 INFO - PROCESS | 1673 | 11:05:32 INFO - PROCESS | 1673 | [#4] audio-oscillator Cycles:10 Average:95.40 Median:81.00 stddev:19.39 (23.9%) stddev-sans-first:20.53 11:05:32 INFO - PROCESS | 1673 | Values: 92.0 96.0 132.0 81.0 78.0 86.0 117.0 76.0 81.0 115.0 11:05:32 INFO - PROCESS | 1673 | 11:05:32 INFO - PROCESS | 1673 | [#5] imaging-gaussian-blur Cycles:10 Average:87.40 Median:88.50 stddev:2.01 (2.3%) stddev-sans-first:2.12 11:05:32 INFO - PROCESS | 1673 | Values: 88.0 86.0 89.0 86.0 89.0 85.0 90.0 88.0 89.0 84.0 11:05:32 INFO - PROCESS | 1673 | 11:05:32 INFO - PROCESS | 1673 | [#6] imaging-darkroom Cycles:10 Average:93.60 Median:94.00 stddev:0.70 (0.7%) stddev-sans-first:0.71 11:05:32 INFO - PROCESS | 1673 | Values: 93.0 93.0 94.0 93.0 93.0 94.0 94.0 95.0 94.0 93.0 11:05:32 INFO - PROCESS | 1673 | 11:05:32 INFO - PROCESS | 1673 | [#7] imaging-desaturate Cycles:10 Average:102.90 Median:89.00 stddev:20.41 (22.9%) stddev-sans-first:20.83 11:05:32 INFO - PROCESS | 1673 | Values: 87.0 89.0 89.0 89.0 93.0 133.0 91.0 94.0 132.0 132.0 11:05:32 INFO - PROCESS | 1673 | 11:05:32 INFO - PROCESS | 1673 | [#8] json-parse-financial Cycles:10 Average:58.50 Median:58.50 stddev:4.25 (7.3%) stddev-sans-first:4.47 11:05:32 INFO - PROCESS | 1673 | Values: 57.0 59.0 58.0 58.0 58.0 59.0 59.0 69.0 56.0 52.0 11:05:32 INFO - PROCESS | 1673 | 11:05:32 INFO - PROCESS | 1673 | [#9] json-stringify-tinderbox Cycles:10 Average:67.20 Median:68.50 stddev:4.16 (6.1%) stddev-sans-first:3.96 11:05:32 INFO - PROCESS | 1673 | Values: 62.0 71.0 61.0 75.0 69.0 68.0 69.0 66.0 65.0 66.0 11:05:32 INFO - PROCESS | 1673 | 11:05:32 INFO - PROCESS | 1673 | [#10] stanford-crypto-aes Cycles:10 Average:80.80 Median:81.00 stddev:1.75 (2.2%) stddev-sans-first:1.83 11:05:32 INFO - PROCESS | 1673 | Values: 80.0 81.0 81.0 81.0 80.0 81.0 80.0 78.0 81.0 85.0 11:05:32 INFO - PROCESS | 1673 | 11:05:32 INFO - PROCESS | 1673 | [#11] stanford-crypto-ccm Cycles:10 Average:124.20 Median:129.00 stddev:7.91 (6.1%) stddev-sans-first:8.11 11:05:32 INFO - PROCESS | 1673 | Values: 130.0 125.0 107.0 131.0 132.0 123.0 128.0 130.0 118.0 118.0 11:05:32 INFO - PROCESS | 1673 | 11:05:32 INFO - PROCESS | 1673 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:137.70 Median:133.50 stddev:14.02 (10.5%) stddev-sans-first:14.76 11:05:32 INFO - PROCESS | 1673 | Values: 133.0 122.0 133.0 158.0 168.0 130.0 133.0 134.0 131.0 135.0 11:05:32 INFO - PROCESS | 1673 | 11:05:32 INFO - PROCESS | 1673 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.10 Median:77.00 stddev:12.81 (16.6%) stddev-sans-first:13.16 11:05:32 INFO - PROCESS | 1673 | Values: 67.0 68.0 86.0 92.0 94.0 91.0 65.0 68.0 65.0 65.0 11:05:32 INFO - PROCESS | 1673 | -------- Summary: end -------- 11:05:32 INFO - PROCESS | 1673 | 11:05:33 INFO - TEST-INFO | 1673: exit 0 11:05:33 INFO - TEST-OK | kraken | took 56853ms 11:05:33 INFO - SUITE-END | took 1170s 11:05:33 INFO - Completed test suite (00:19:30) 11:05:33 INFO - javascript benchmark 11:05:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2551.0561092395815, 3106.6116735408073, 3426.7087297579706, 3463.6709512596312, 3529.417646088394, 5653.123765816305, 5680.0, 5681.659100453976, 5684.801031963882, 5692.146178073291, 5652.406497317784, 5701.714914254291, 5705.74324155413, 5712.514436272912, 5716.371199168088, 5043.092243396187, 5103.617228707843, 5184.326037615105, 5206.5834733221445, 5251.159814429692, 5189.714565698885, 5204.869878253036, 5205.843824685266, 5209.973950130244, 5210.76551555181, 4304.24675681756, 5219.973900130513, 5254.316938797951, 5261.289725887013, 5280.102382594968, 5250.684958902478, 5305.628605997582, 5307.495787900143, 5324.893502129975, 5325.4940780625775, 5057.2666963290485, 5134.871628209287, 5225.268462415265, 5253.290805741252, 5268.683878967236, 5567.693776842272, 5580.637258578179, 5601.159826026088, 5646.2377579027125, 5652.830415087554, 6114.327423983358, 6183.443490085869, 6299.716512756937, 6308.684565771693, 6311.242650881878, 6162.7226774795245, 6210.503159747254, 6226.906596401058, 6242.875142497124, 6260.217472815898, 5595.664260144405, 5600.775968961196, 5607.214989901417, 5643.0, 5658.91511627326, 6241.469475094668, 6245.469135123565, 6257.812265632038, 6259.96870015647, 6263.812085637438, 5879.941200588025, 5914.76340946366, 5919.970400148058, 6099.84750381244, 6116.785912493041, 6273.8745225095245, 6280.183576135073, 6308.936910630927, 6309.716062777186, 6323.747050118036, 5270.973645131749, 6180.227471566054, 6252.187215662026, 6257.0, 6261.311255761862, 6071.574989750763, 6153.507719382439, 6160.568760186834, 6186.4741496972365, 6280.2777680566405, 4823.782929768169, 4884.926726099111, 4888.193448081027, 4895.069936712013, 4929.014197160583, 4428.800703968329, 4447.911041779211, 4454.777261136995, 4454.799534020912, 4466.0, 5596.4123767003875, 5682.119271512922, 5739.253896993364, 5758.712064396764, 5804.912926306109, 6229.781957631551, 6314.305426403092, 6323.715432805535, 6324.71538780756, 6347.492200624031, 5639.238702775155, 5687.345955215121, 5693.772249109989, 5733.541316694655, 5785.0, 5249.606279529051, 5297.17893726473, 5371.704556249444, 5375.16684913839, 5382.219578161144, 6263.154474145932, 6270.372962703785, 6285.0, 6306.495480361468, 6314.084457753692, 6032.939670603238, 6072.362401947908, 6096.0855871619615, 6102.298235702773, 6103.145559621567, 4781.737004464788, 6198.442140207403, 6211.503079753518, 6243.656598886923, 6301.338359472189, 6174.166487524307, 6216.937830621817, 6224.906626400698, 6237.812865614039, 6260.655663938345, 6249.218847644044, 6259.405356491126, 6271.090691849565, 6293.874122517433, 6298.370162983665, 5702.45826646468, 6093.695315234132, 6109.69451527413, 6124.411449640054, 6134.355892631388, 5895.557833162444, 6075.27096748393, 6081.878362432815, 6093.329733729198, 6112.786052488142, 5962.880742385215, 5965.194698715618, 5966.134910437875, 5972.223610930551, 5973.820785376446, 5992.730327135202, 6037.849053773691, 6096.055111457731, 6154.046122850965, 6160.353162917829, 5728.799492017926, 5782.074868021096, 5784.681842498703, 5786.537077033743, 5847.093700476433], "unit": "score", "name": "dojo.html", "value": 5656.307230107295}, {"lowerIsBetter": false, "replicates": [485.5751217684526, 593.9970300148495, 604.3744724210441, 617.8053913017397, 664.3323459922777, 39926.20147597046, 40189.19621607566, 40281.39577906326, 40291.395629065584, 40449.79775101124, 43719.78140109303, 44032.89917752058, 44271.0, 44283.55716442835, 44287.0, 9598.184154346889, 9822.901770982271, 9830.754231144243, 9834.459104749236, 9845.310828242025, 9600.087991640783, 9734.0, 9735.951320243388, 9741.853872191923, 9753.170980466668, 9836.163926066292, 9890.406575605486, 9902.55438505269, 9910.950445247763, 9913.851292230622, 9810.558524866397, 9876.308658393915, 9896.50517474127, 9904.851427228561, 9908.653197138066, 9709.61161553537, 9847.655332063343, 9868.703938881845, 9874.259430542737, 9874.703758887244, 42979.57020429818, 43352.3497147543, 43444.913877153005, 43464.78267608642, 43499.78250108729, 3198.1684761962056, 3290.7038366547126, 3307.1566750478714, 3435.1927297085167, 3728.291624591319, 29807.70192298071, 29857.70142298571, 29935.401291974256, 30262.24344391386, 30278.24304392386, 2701.1761412769097, 2776.8056236063485, 2802.7477527022666, 2833.8583070846375, 2836.489431902257, 498627.0, 499030.0, 499976.0, 500643.4967825174, 501013.0, 68497.0, 68966.65516672484, 68971.3102868975, 68994.65502672455, 69050.3094969054, 71632.64183679048, 72090.63954680193, 81713.0, 81788.18211817925, 81849.18150818534, 18716.157772900253, 18789.0, 18991.620167596848, 18993.715094273597, 18994.14526346318, 14238.2168980705, 14291.64270893236, 14309.499167529086, 14326.212058336683, 14352.641183970276, 22756.772432275797, 23207.88396058009, 23221.9550120245, 23233.0, 23254.0, 8991.246706892092, 9223.631054757734, 9223.76940576491, 9228.723138305862, 9272.0, 960.2558017536466, 990.9801803964025, 1079.4494807648157, 1100.0484580837679, 1106.7233191702076, 30473.93341233046, 30600.54099188514, 30664.693353066184, 30998.845005774827, 31563.684363156073, 1127.785720713062, 1161.0305394995273, 1168.485866218861, 1193.397334346167, 1196.557273808679, 15410.152441615595, 15739.527814165593, 15760.605984850241, 15783.763243551355, 16044.0, 554660.0, 558393.0, 558427.0, 559936.0, 560401.0, 61337.693311533156, 63024.054639180446, 63090.05364919438, 63460.365396346366, 63595.68202158959, 1401.8668226518464, 1417.0010142849267, 1417.9291035448186, 1417.936547589308, 1531.157863175249, 28872.566911496764, 31157.0, 31230.531542027344, 31345.8432707835, 32136.357272853944, 52628.42114736564, 52788.0, 53431.19853202283, 53522.19716704175, 53644.0, 382722.0, 383571.0, 383696.0, 383995.0, 384454.0, 30128.69871301259, 34351.797687080834, 35329.0, 35484.46773298454, 35804.10489737777, 43935.56064439315, 44179.11641767211, 44574.10851783011, 45500.08999819919, 45732.77133614311, 34791.13022174466, 35667.46498802468, 36689.44965825461, 37179.62820371761, 37239.0, 28716.707748151803, 35984.460233097045, 36034.819825900704, 36119.27761444704, 37025.0, 33101.00696979094, 38141.809290953366, 38405.4239186418, 39406.81779546618, 39467.0, 29733.405331893675, 31936.46096788008, 32364.0, 33190.0, 34056.829715851265, 25176.74823251744, 25257.47425257533, 25311.987520498973, 25667.229983100537, 25958.610620840325], "unit": "score", "name": "ext.html", "value": 20626.369978503706}, {"lowerIsBetter": false, "replicates": [370.68676967962074, 371.3742344150109, 376.2512599926148, 378.9583145853957, 380.9142942837862, 17605.0, 21025.0, 21867.890660546676, 22565.774342256613, 22591.983360748785, 21304.8934755326, 21795.67306490404, 22038.11847526097, 22051.66922496164, 22290.88545572723, 4887.437944636368, 5911.763529458816, 5975.701214939258, 6009.789657361993, 6020.12708157317, 5827.446392592718, 5851.122331650266, 5875.324337701177, 5884.352721200665, 5887.1463637772495, 5703.486686198241, 5785.16115163301, 5810.360860305363, 5874.118882167688, 5877.235959325303, 5599.552035837143, 5746.224259724925, 5782.0, 5783.826485205451, 5789.971050144764, 5031.258412850347, 5073.213651883964, 5083.720395378253, 5091.46539613339, 5091.516305950929, 21891.233806816686, 24530.52816830996, 26405.471890562272, 26764.73235267642, 27232.0, 2243.4279258789065, 2262.1743063781764, 2272.693186419845, 2891.9855400722863, 2953.896613618513, 14490.275486225752, 15214.543563693107, 15324.770128448083, 15469.149196794171, 15623.609409764733, 1717.9742303865453, 1728.2741248675586, 1729.1095086030705, 1762.5769815244378, 1785.7321401789707, 72350.0, 78647.0, 105276.94723052824, 105464.41803372955, 110090.9999999992, 11196.440177991068, 11634.476448559835, 11690.0, 11742.94128529369, 11810.940945295219, 12819.551315703906, 13133.212007279593, 13431.723986221294, 14008.089474184146, 14009.929950350182, 6743.797686069426, 6815.897761533581, 8551.529665868436, 8935.508547029845, 9050.275977921881, 9104.817903642022, 9111.088891110836, 9148.725538233864, 9181.495017773954, 9236.261099112055, 3889.5138107736534, 4345.718013186118, 4468.570057581608, 4552.839026048337, 4598.195315819718, 1882.5670095878024, 1910.6656335141572, 1914.3299845054119, 1924.7209154672494, 1937.9612407751765, 952.7903861150536, 993.2897977945803, 1041.5885725138528, 1051.7160366700948, 1064.1699474409972, 21120.577588448454, 21593.67609485859, 21631.35105946824, 21649.13403463844, 21862.672059919114, 969.3214749675114, 997.9800403991879, 1012.4380968562372, 1025.0723095598496, 1039.4802598700649, 7813.945117409191, 11382.373969431595, 11507.482163302671, 11578.189526733047, 11584.957353838026, 672100.0, 675859.0, 676925.0, 686813.527458906, 688709.0, 121144.0, 131871.0, 136399.0, 137672.0, 138199.0, 1101.8071837428417, 1118.5917140243914, 1175.482787573465, 1202.4108186988312, 1202.4348556178581, 19145.61708765789, 19620.705689414957, 19897.801021989595, 19976.0, 20225.88757618345, 56109.0, 57125.0, 58037.0, 58462.415375845696, 59524.40475595189, 82261.58869205616, 82692.75960860471, 84366.0, 84606.0, 84743.15256847352, 8302.83394332122, 8312.6674933002, 8386.245237928615, 8542.82914341722, 8757.211850933447, 11309.547618095514, 11650.76698466043, 13017.088803783645, 13453.596392108253, 13983.0, 11763.353015584225, 11855.407229638657, 11858.16992810495, 11992.280463171888, 12045.81931271014, 11406.201565890307, 11560.190786644855, 11683.240589361665, 11687.298762074302, 11929.880701192877, 6162.691865406801, 9606.711798646053, 11126.388048657078, 11966.820497692368, 12094.637160885188, 8242.134575869448, 8274.29668478186, 8433.620487077973, 8536.70121545743, 8648.943016981188, 11091.667249982513, 11929.522819087138, 12021.218620789621, 12153.635390938285, 12154.027677785933], "unit": "score", "name": "jquery.html", "value": 9842.012632850818}, {"lowerIsBetter": false, "replicates": [1501.3169008101315, 1642.9753553696696, 1660.078656345728, 2005.1979208316673, 2084.0621720225904, 8326.500409975406, 8879.778005549864, 9138.040505746883, 9138.086191380859, 9158.49628270445, 8961.283097352212, 9237.0, 9238.353315267934, 9242.815143697138, 9243.214326782232, 2254.8534345267585, 2315.4211447138214, 2316.8494047886834, 2548.796096312295, 2587.275562842398, 2525.103588226181, 2599.350162459385, 2602.7137014928344, 2604.1146010356474, 2604.817662763607, 2486.105002199207, 2540.9491810163786, 2552.0302285131625, 2559.6672432583737, 2559.833610815291, 2522.798176145904, 2541.6568763216915, 2542.86842355152, 2548.834325768819, 2577.1753039027517, 2239.8688662225636, 2556.629288753139, 2563.3079068651546, 2563.3591602099477, 2565.448428587844, 9088.136627020422, 9315.813683726356, 9321.02129276423, 9340.626374944994, 9376.0, 5559.305086864142, 5728.283964504437, 5737.913931291034, 5777.248957635523, 5784.971075144639, 3590.910227244314, 3676.466912297702, 3719.851205951759, 3737.981310093432, 3761.9059523511855, 1051.2326002018492, 1059.5549869055014, 1075.784843031389, 1077.0898590690854, 1089.3953855610134, 12673.972164453644, 12708.428120734588, 12710.682232944158, 12713.93643031779, 12753.043521735906, 4960.727160006234, 5061.291419201315, 5103.642745007888, 5112.897742045213, 5118.053160165381, 10697.83953240702, 10710.571577136827, 10722.73193170177, 10723.0, 10733.248672592843, 5732.971335143381, 5783.508401785811, 5789.8263052108505, 5794.0439827428845, 5797.449242321973, 4158.0, 4651.232546629836, 4767.404074490689, 5210.7915683373, 5212.113940630102, 4055.6552693020835, 4103.815328310233, 4128.710990230655, 4140.60664236897, 4180.1848639515, 1341.557286095586, 1341.9731605368033, 1348.1439286053426, 1353.1204716934071, 1360.5102163221231, 1403.4245959156697, 1408.7041721238654, 1424.472945010332, 1447.9131252124905, 1451.8693317601467, 7534.3595794357, 8624.223819856243, 8789.736307910773, 8862.601182946783, 8865.024847266795, 1255.5731051442554, 1261.2810697902291, 1261.4197469164103, 1268.8477382714134, 1271.0530654662336, 6382.648954307558, 6457.418832305115, 6485.44873685742, 6507.9023814642815, 6552.312007239267, 10676.786464270672, 11092.0, 11182.888171118184, 11208.27146235492, 11331.716707082223, 11046.944765276123, 11104.333739975627, 11157.107431405468, 11184.105271578417, 11210.159238057178, 8674.175953284428, 8759.124087591317, 8823.0, 8824.5587720615, 8903.33225008127, 8108.878366824384, 8225.753227403187, 8248.628811703367, 8282.254597086292, 8290.088090310182, 9971.950140249251, 10054.899451005396, 10077.69766906994, 10086.949565252127, 10159.695209143738, 10137.08766211015, 10303.278770486293, 10508.632197873038, 10534.578616855546, 10697.51861166265, 5715.399883012224, 6675.265720770809, 6830.385265326145, 6935.306469353226, 7136.4290856730295, 7417.443691723034, 8585.8712119317, 8592.441491303045, 8650.91349086501, 8657.913420865963, 6431.549791514549, 7511.812204694927, 7585.317321441097, 7747.496412733154, 7794.376449883882, 7433.516821406592, 7435.553866768011, 7450.254974502506, 7546.849063018819, 7559.659815308214, 7928.920710793049, 8126.065502467056, 8129.593520323841, 8131.674733010614, 8163.673453061811, 7314.195438501654, 7338.229485903903, 7346.302101300582, 7385.593792341473, 7408.62956852166, 7425.777226683208, 7440.55356678601, 7477.925220747723, 7494.175640679412, 7498.850022999401], "unit": "score", "name": "mootools.html", "value": 5151.263754685887}, {"lowerIsBetter": false, "replicates": [329.8565124170985, 331.9468884978404, 336.89893032090396, 339.62471469026724, 344.2564061626886, 6323.494120470357, 6391.584547004453, 6418.42234198922, 6434.871302573945, 6441.4524765395, 6351.523635727316, 6383.871902767633, 6571.769988050419, 6572.375624315682, 6580.769673061443, 3893.357595996657, 4643.6517261205545, 4685.976570117145, 4736.005438857844, 4834.4923783002805, 3947.6447119759214, 4770.165221086313, 4787.8324258650955, 4788.0423915216925, 4799.424069111694, 4743.525647435264, 4745.505165872756, 4758.928616070762, 4760.595349395306, 4761.476237613877, 3890.591487893787, 4553.27147656375, 4576.199165146103, 4769.189237829542, 4770.212914869043, 4101.384792281152, 4108.815103320358, 4117.608827161415, 4124.278251306039, 4129.15352352767, 6121.387861213843, 6127.387261273881, 6145.661988590626, 6158.384161583851, 6165.938340616582, 5551.2228288039705, 5558.666480011172, 5571.4428557144365, 5574.665520068768, 5580.386157522669, 5555.916661250085, 6726.125603671491, 6788.456923446112, 6806.523543351967, 6807.965960170217, 4234.407182994383, 4406.986393129566, 4409.735415875026, 4416.734995900256, 4424.579664931826, 6868.553544019623, 7043.542169758949, 7129.037579926696, 7131.322524360177, 7138.964305178492, 4473.821047158142, 4489.7979590918485, 4492.034212644232, 4493.370928070073, 4506.481754598198, 4551.180787458289, 4555.498895121534, 4562.292844609091, 4564.88587785308, 4571.405717256735, 4643.558861908114, 5374.623776335698, 5381.381141168816, 5384.623076384615, 5385.542228910586, 6589.423974520921, 6661.533692641468, 6797.2523022467485, 6806.421454176351, 6812.693428795617, 1559.2437667731142, 1569.3251901682386, 1579.9921000395154, 1584.5484037049387, 1587.5792914877566, 880.5245167609419, 967.4775621164633, 984.8719666443317, 1011.0546638892658, 1038.7091614347994, 6043.36544662804, 6047.0627052806885, 6053.51571874249, 6057.4851137653795, 6151.015837465984, 7074.3633073023675, 7079.89380159298, 7095.04216930718, 7096.716131354791, 7121.786346409616, 6300.3699630037545, 6303.338149494329, 6311.684415779193, 6327.873442531123, 6345.302016778151, 3697.352963231424, 3735.906602334909, 3743.47591337213, 3751.8686845960806, 3752.418375151856, 7061.082059332357, 7348.338649521569, 7385.9630701846145, 7394.704211831574, 7395.26047395267, 6540.509461790385, 6689.531732778659, 7271.454640901953, 7282.635868206568, 7302.488825782144, 7380.335769780745, 7384.9261507384235, 7392.519486233377, 7393.0, 7393.445491588152, 5815.244018277597, 6997.965010174917, 7067.646617669199, 7084.3351812323035, 7098.219195888551, 6653.900191497035, 6945.791626251221, 6961.89557156633, 6973.267806880204, 6986.301369862973, 7308.59802710856, 7329.413646908341, 7341.302576255247, 7355.448341374526, 7364.895265710186, 4512.165249428866, 4513.954860451354, 4526.637868970541, 4531.116432295671, 4538.160440318552, 7038.894416583653, 7051.294870512907, 7063.328983746535, 7067.116610423697, 7068.858622827412, 6515.153030106056, 6796.388325050769, 6802.081718967876, 6814.386705196556, 6815.522913396015, 6737.865242695217, 6774.8645027100165, 6786.898196526958, 6788.762393316408, 6795.38841504267, 6631.469482441296, 6649.501287403561, 6650.73397064112, 6656.201255849329, 6668.799936001928, 6783.18601767791, 6787.966060169668, 6797.626130562867, 6798.932010680028, 6808.693608787517, 6403.96798016007, 6460.45086167681, 6502.479801615765, 6624.171978502687, 6633.270340262471], "unit": "score", "name": "prototype.html", "value": 4967.28295536613}, {"lowerIsBetter": false, "replicates": [720.2725247500025, 771.5062360089544, 781.9491733037356, 784.2432053068791, 789.8933643958068, 15217.847821521782, 15376.846231537682, 15402.614934626612, 15404.614884627876, 15416.460423885166, 14039.087459315162, 14150.787738183935, 14194.503192388269, 14212.502562410318, 14279.286035698226, 768.0437854870694, 770.5145758172343, 952.7284723853703, 967.405045896772, 969.486172328667, 943.6744323208517, 972.8248915195262, 974.6101559376235, 975.5073687787657, 978.3298440568234, 1032.6850310655248, 1032.7624646331349, 1033.5968972100886, 1034.5499707627168, 1035.4926086217774, 801.8396320735818, 804.3324041045918, 809.1988930958368, 1023.0741179232806, 1027.691692492253, 617.4134572156424, 736.8010637127999, 737.7749786315172, 739.268124556685, 745.5489428895548, 14355.856441435557, 14359.78460323096, 14376.065555738844, 14380.35288412024, 14393.640158996004, 12908.54820081302, 13228.338583070905, 13426.597202083953, 13499.460021599125, 13655.38550765218, 9832.557534910946, 9912.256580756472, 10041.397516149054, 10076.647317343932, 10154.9492252539, 3470.305938812248, 3476.8087755173456, 3623.438367053111, 3642.05306620278, 3644.034330902313, 64060.0, 69609.30390696079, 79888.0, 84266.57866710688, 84297.57851210705, 8314.708985185489, 8684.8697269541, 10031.498425078717, 10047.24645651579, 10082.69751907444, 19242.0, 23308.883455582614, 23336.766632333798, 23387.76612233856, 23406.64890026651, 7595.164531901486, 7603.7338693146585, 7670.156282808886, 7820.633521128241, 7935.761887563473, 4098.4876890388705, 4127.926739047809, 4160.83356665736, 4289.528151903288, 4445.666575006888, 3771.773693578339, 3815.828287727059, 3821.293060783764, 3823.885283441501, 3828.1386687994977, 642.787879999599, 651.1047309948821, 673.4847841401332, 687.5393486364048, 688.1088989758275, 534.3347532322283, 552.0559842669081, 560.8065217499956, 573.5640912906138, 587.6297932302622, 8063.556504392197, 8464.32285417165, 8500.029737713432, 8577.82844343122, 8654.48073115615, 553.5156737854378, 596.6181643748005, 619.5508256513991, 620.9968950155311, 632.5572099530348, 3778.4332350147147, 3968.8171505671767, 3998.5801490843623, 4002.9199416012098, 4055.9391609125887, 71986.64006680038, 90249.0, 91874.0, 92531.53734231286, 92835.0, 81958.0, 82232.0, 82576.0, 83008.58495707483, 84068.0, 67429.32570674329, 72666.90999634904, 73937.6303118481, 74959.1260218488, 75139.62430187814, 8540.40217184791, 8702.944650105728, 8778.56107194625, 8811.603477843384, 8839.46963182211, 43833.465828695844, 51315.743421282656, 51612.93548258027, 53073.469265306856, 55558.611034724454, 51939.480605193465, 55549.0, 74568.0, 76570.23429765854, 78725.0, 10994.670159895217, 11237.55049797999, 11447.942760286145, 11509.53961841517, 12121.515139394325, 11947.223430476992, 12133.119366498207, 12196.390180490762, 12242.326672033123, 12282.938585307016, 11691.883081169079, 11825.940870295593, 11850.407479626158, 11948.641540753792, 12195.268283902995, 11448.885511144781, 11519.8272025918, 11816.82274765862, 12180.756384872077, 12209.69475763113, 10753.301035432985, 10882.945585272022, 11075.778484430106, 11243.156763242627, 11439.428028598702, 9395.859062113937, 9797.216222702024, 9842.11420972116, 9876.160526355341, 9981.401115932777, 9383.436993780395, 9756.658516951873, 9856.556454959687, 9906.356086854332, 9981.171340798674], "unit": "score", "name": "yui.html", "value": 7045.529062414683}], "name": "dromaeo_css", "value": 7691.370971962091}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [112.0, 111.0, 109.0, 109.0, 112.0, 112.0, 120.0, 148.0, 159.0, 114.0], "unit": "score", "name": "ai-astar", "value": 120.6}, {"lowerIsBetter": true, "replicates": [152.0, 118.0, 153.0, 143.0, 117.0, 137.0, 117.0, 148.0, 146.0, 115.0], "unit": "score", "name": "audio-beat-detection", "value": 134.6}, {"lowerIsBetter": true, "replicates": [184.0, 184.0, 184.0, 184.0, 185.0, 189.0, 188.0, 182.0, 184.0, 187.0], "unit": "score", "name": "audio-dft", "value": 185.1}, {"lowerIsBetter": true, "replicates": [71.0, 82.0, 76.0, 120.0, 56.0, 56.0, 59.0, 60.0, 126.0, 81.0], "unit": "score", "name": "audio-fft", "value": 78.7}, {"lowerIsBetter": true, "replicates": [92.0, 96.0, 132.0, 81.0, 78.0, 86.0, 117.0, 76.0, 81.0, 115.0], "unit": "score", "name": "audio-oscillator", "value": 95.4}, {"lowerIsBetter": true, "replicates": [88.0, 86.0, 89.0, 86.0, 89.0, 85.0, 90.0, 88.0, 89.0, 84.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 87.4}, {"lowerIsBetter": true, "replicates": [93.0, 93.0, 94.0, 93.0, 93.0, 94.0, 94.0, 95.0, 94.0, 93.0], "unit": "score", "name": "imaging-darkroom", "value": 93.6}, {"lowerIsBetter": true, "replicates": [87.0, 89.0, 89.0, 89.0, 93.0, 133.0, 91.0, 94.0, 132.0, 132.0], "unit": "score", "name": "imaging-desaturate", "value": 102.9}, {"lowerIsBetter": true, "replicates": [57.0, 59.0, 58.0, 58.0, 58.0, 59.0, 59.0, 69.0, 56.0, 52.0], "unit": "score", "name": "json-parse-financial", "value": 58.5}, {"lowerIsBetter": true, "replicates": [62.0, 71.0, 61.0, 75.0, 69.0, 68.0, 69.0, 66.0, 65.0, 66.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.2}, {"lowerIsBetter": true, "replicates": [80.0, 81.0, 81.0, 81.0, 80.0, 81.0, 80.0, 78.0, 81.0, 85.0], "unit": "score", "name": "stanford-crypto-aes", "value": 80.8}, {"lowerIsBetter": true, "replicates": [130.0, 125.0, 107.0, 131.0, 132.0, 123.0, 128.0, 130.0, 118.0, 118.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 124.2}, {"lowerIsBetter": true, "replicates": [133.0, 122.0, 133.0, 158.0, 168.0, 130.0, 133.0, 134.0, 131.0, 135.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 137.7}, {"lowerIsBetter": true, "replicates": [67.0, 68.0, 86.0, 92.0, 94.0, 91.0, 65.0, 68.0, 65.0, 65.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 76.1}], "name": "kraken", "value": 1442.8}]} 11:05:33 INFO - Return code: 0 11:05:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 11:05:33 INFO - # TBPL SUCCESS # 11:05:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:05:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:05:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:05:33 INFO - 2016-04-18 11:05:33.834 screenresolution[1677:9636] starting screenresolution argv=screenresolution get 11:05:33 INFO - 2016-04-18 11:05:33.837 screenresolution[1677:9636] Display 0: 1600x1200x32@60 11:05:33 INFO - 2016-04-18 11:05:33.843 screenresolution[1678:9639] starting screenresolution argv=screenresolution list 11:05:33 INFO - Available Modes on Display 0 11:05:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:05:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:05:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:05:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:05:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:05:34 INFO - Intel Iris: 11:05:34 INFO - Chipset Model: Intel Iris 11:05:34 INFO - Type: GPU 11:05:34 INFO - Bus: Built-In 11:05:34 INFO - VRAM (Dynamic, Max): 1536 MB 11:05:34 INFO - Vendor: Intel (0x8086) 11:05:34 INFO - Device ID: 0x0a2e 11:05:34 INFO - Revision ID: 0x0009 11:05:34 INFO - Displays: 11:05:34 INFO - Display: 11:05:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:05:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:05:34 INFO - Display Serial Number: 1600x1200 60 11:05:34 INFO - Main Display: Yes 11:05:34 INFO - Mirror: Off 11:05:34 INFO - Online: Yes 11:05:34 INFO - Rotation: Supported 11:05:34 INFO - Return code: 0 11:05:34 INFO - Running post-action listener: _resource_record_post_action 11:05:34 INFO - Running post-run listener: _resource_record_post_run 11:05:35 INFO - Total resource usage - Wall time: 1204s; CPU: 52.0%; Read bytes: 37807616; Write bytes: 661793792; Read time: 414; Write time: 1956 11:05:35 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 356526592; Write bytes: 353207808; Read time: 28137; Write time: 607 11:05:35 INFO - run-tests - Wall time: 1173s; CPU: 52.0%; Read bytes: 35993088; Write bytes: 297408000; Read time: 377; Write time: 1314 11:05:35 INFO - Running post-run listener: _upload_blobber_files 11:05:35 INFO - Blob upload gear active. 11:05:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:05:35 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 11:05:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:05:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:05:35 INFO - (blobuploader) - INFO - Open directory for files ... 11:05:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:05:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:05:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:05:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:05:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:05:36 INFO - (blobuploader) - INFO - Done attempting. 11:05:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:05:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:05:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:05:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:05:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:05:37 INFO - (blobuploader) - INFO - Done attempting. 11:05:37 INFO - (blobuploader) - INFO - Iteration through files over. 11:05:37 INFO - Return code: 0 11:05:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:05:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:05:37 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9a30e6e05a641ba58111b258ee821e1997d08de1fb92f21113b2b53de386029b79f88e96abae575bcf2d92dd1cc23ece17cfee0b24a414dc677c5b5949b238a8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2704269d2a7d93cb41ca842b856ac6b147e3a995e6f07840394941e30d7fad0c80eab1a32f3626c5c7bc3ff307f8d14a31380d8dfab874e2ae860787f49c4dbf"} 11:05:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:05:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:05:37 INFO - Contents: 11:05:37 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9a30e6e05a641ba58111b258ee821e1997d08de1fb92f21113b2b53de386029b79f88e96abae575bcf2d92dd1cc23ece17cfee0b24a414dc677c5b5949b238a8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2704269d2a7d93cb41ca842b856ac6b147e3a995e6f07840394941e30d7fad0c80eab1a32f3626c5c7bc3ff307f8d14a31380d8dfab874e2ae860787f49c4dbf"} 11:05:37 INFO - Running post-run listener: copy_logs_to_upload_dir 11:05:37 INFO - Copying logs to upload dir... 11:05:37 INFO - mkdir: /builds/slave/test/build/upload/logs 11:05:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.417516 ========= master_lag: 96.43 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 36 secs) (at 2016-04-18 11:07:14.144431) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 16 secs) (at 2016-04-18 11:07:14.148031) ========= 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.9gZ1hdvNsI/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.RL9Ktcv07c/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9a30e6e05a641ba58111b258ee821e1997d08de1fb92f21113b2b53de386029b79f88e96abae575bcf2d92dd1cc23ece17cfee0b24a414dc677c5b5949b238a8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2704269d2a7d93cb41ca842b856ac6b147e3a995e6f07840394941e30d7fad0c80eab1a32f3626c5c7bc3ff307f8d14a31380d8dfab874e2ae860787f49c4dbf"} build_url:https://queue.taskcluster.net/v1/task/Vxbri5mVQxG84v3-50tBDg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011819 build_url: 'https://queue.taskcluster.net/v1/task/Vxbri5mVQxG84v3-50tBDg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9a30e6e05a641ba58111b258ee821e1997d08de1fb92f21113b2b53de386029b79f88e96abae575bcf2d92dd1cc23ece17cfee0b24a414dc677c5b5949b238a8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2704269d2a7d93cb41ca842b856ac6b147e3a995e6f07840394941e30d7fad0c80eab1a32f3626c5c7bc3ff307f8d14a31380d8dfab874e2ae860787f49c4dbf"}' ========= master_lag: 16.84 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 16 secs) (at 2016-04-18 11:07:30.999550) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 4 secs) (at 2016-04-18 11:07:30.999961) ========= 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.9gZ1hdvNsI/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.RL9Ktcv07c/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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.005045 ========= master_lag: 4.43 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 4 secs) (at 2016-04-18 11:07:35.435338) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-18 11:07:35.435668) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-18 11:07:35.867915) ========= ========= Total master_lag: 117.75 =========