builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0137 starttime: 1465118410.65 results: success (0) buildid: 20160605004013 builduid: f2e7e1e69a8447ca99b55e3f1fac1182 revision: dc972d877cd697c2c8f6248534f21bb5e1dba9ae ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-05 02:20:10.655608) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-05 02:20:10.656038) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-05 02:20:10.656351) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-05 02:20:10.791211) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-05 02:20:10.791542) ========= 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.kDYxWdEQIS/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.wd83DXyy4M/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-06-05 02:20:10-- 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% 14.3M=0.001s 2016-06-05 02:20:11 (14.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.502518 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-05 02:20:11.339067) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-05 02:20:11.339636) ========= 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.kDYxWdEQIS/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.wd83DXyy4M/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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.028330 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-05 02:20:11.416361) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-05 02:20:11.416724) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev dc972d877cd697c2c8f6248534f21bb5e1dba9ae --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 dc972d877cd697c2c8f6248534f21bb5e1dba9ae --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kDYxWdEQIS/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.wd83DXyy4M/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-06-05 02:20:11,493 truncating revision to first 12 chars 2016-06-05 02:20:11,493 Setting DEBUG logging. 2016-06-05 02:20:11,493 attempt 1/10 2016-06-05 02:20:11,493 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/dc972d877cd6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-05 02:20:12,720 unpacking tar archive at: mozilla-aurora-dc972d877cd6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.505760 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-05 02:20:12.942323) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-05 02:20:12.942610) ========= script_repo_revision: dc972d877cd697c2c8f6248534f21bb5e1dba9ae ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-05 02:20:12.942974) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-05 02:20:12.943235) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-05 02:20:12.971201) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 22 secs) (at 2016-06-05 02:20:12.971448) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.kDYxWdEQIS/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.wd83DXyy4M/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 02:20:13 INFO - MultiFileLogger online at 20160605 02:20:13 in /builds/slave/test 02:20:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:20:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:20:13 INFO - {'append_to_log': False, 02:20:13 INFO - 'base_work_dir': '/builds/slave/test', 02:20:13 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:20:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:20:13 INFO - 'branch': 'Mozilla-Aurora', 02:20:13 INFO - 'buildbot_json_path': 'buildprops.json', 02:20:13 INFO - 'config_files': ('talos/mac_config.py',), 02:20:13 INFO - 'default_actions': ('clobber', 02:20:13 INFO - 'read-buildbot-config', 02:20:13 INFO - 'download-and-extract', 02:20:13 INFO - 'populate-webroot', 02:20:13 INFO - 'create-virtualenv', 02:20:13 INFO - 'install', 02:20:13 INFO - 'run-tests'), 02:20:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:20:13 INFO - 'download_minidump_stackwalk': True, 02:20:13 INFO - 'download_symbols': 'ondemand', 02:20:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:20:13 INFO - 'tooltool.py': '/tools/tooltool.py', 02:20:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:20:13 INFO - '/tools/misc-python/virtualenv.py')}, 02:20:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:20:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:20:13 INFO - 'installer_path': 'installer.exe', 02:20:13 INFO - 'log_level': 'info', 02:20:13 INFO - 'log_name': 'talos', 02:20:13 INFO - 'log_to_console': True, 02:20:13 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:20:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:20:13 INFO - 'opt_config_files': (), 02:20:13 INFO - 'pip_index': False, 02:20:13 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:20:13 INFO - 'cmd': ('bash', 02:20:13 INFO - '-c', 02:20:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:20:13 INFO - 'enabled': True, 02:20:13 INFO - 'halt_on_failure': False, 02:20:13 INFO - 'name': 'check_screen_resolution'},), 02:20:13 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:20:13 INFO - 'cmd': ('bash', 02:20:13 INFO - '-c', 02:20:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:20:13 INFO - 'enabled': True, 02:20:13 INFO - 'halt_on_failure': False, 02:20:13 INFO - 'name': 'check_screen_resolution'},), 02:20:13 INFO - 'run_cmd_checks_enabled': True, 02:20:13 INFO - 'sps_profile': False, 02:20:13 INFO - 'sps_profile_interval': 0, 02:20:13 INFO - 'suite': 'dromaeojs-e10s', 02:20:13 INFO - 'system_bits': '32', 02:20:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:20:13 INFO - 'title': 't-yosemite-r7-0137', 02:20:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:20:13 INFO - 'use_talos_json': True, 02:20:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:20:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:20:13 INFO - 'work_dir': 'build'} 02:20:13 INFO - ##### 02:20:13 INFO - ##### Running clobber step. 02:20:13 INFO - ##### 02:20:13 INFO - Running pre-action listener: _resource_record_pre_action 02:20:13 INFO - Running main action method: clobber 02:20:13 INFO - rmtree: /builds/slave/test/build 02:20:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:20:14 INFO - Running post-action listener: _resource_record_post_action 02:20:14 INFO - ##### 02:20:14 INFO - ##### Running read-buildbot-config step. 02:20:14 INFO - ##### 02:20:14 INFO - Running pre-action listener: _resource_record_pre_action 02:20:14 INFO - Running main action method: read_buildbot_config 02:20:14 INFO - Using buildbot properties: 02:20:14 INFO - { 02:20:14 INFO - "project": "", 02:20:14 INFO - "product": "firefox", 02:20:14 INFO - "script_repo_revision": "production", 02:20:14 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 02:20:14 INFO - "repository": "", 02:20:14 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 02:20:14 INFO - "buildid": "20160605004013", 02:20:14 INFO - "stage_platform": "macosx64", 02:20:14 INFO - "basedir": "/builds/slave/test", 02:20:14 INFO - "nightly_build": "True", 02:20:14 INFO - "buildnumber": 21, 02:20:14 INFO - "slavename": "t-yosemite-r7-0137", 02:20:14 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 02:20:14 INFO - "platform": "yosemite_r7", 02:20:14 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:20:14 INFO - "branch": "mozilla-aurora", 02:20:14 INFO - "repo_path": "releases/mozilla-aurora", 02:20:14 INFO - "revision": "dc972d877cd697c2c8f6248534f21bb5e1dba9ae", 02:20:14 INFO - "pgo_build": "False", 02:20:14 INFO - "builduid": "f2e7e1e69a8447ca99b55e3f1fac1182", 02:20:14 INFO - "slavebuilddir": "test" 02:20:14 INFO - } 02:20:14 INFO - Found installer url https://queue.taskcluster.net/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 02:20:14 INFO - Running post-action listener: _resource_record_post_action 02:20:14 INFO - ##### 02:20:14 INFO - ##### Running download-and-extract step. 02:20:14 INFO - ##### 02:20:14 INFO - Running pre-action listener: _resource_record_pre_action 02:20:14 INFO - Running main action method: download_and_extract 02:20:14 INFO - mkdir: /builds/slave/test/build/tests 02:20:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:20:14 INFO - https://queue.taskcluster.net/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 02:20:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:20:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:20:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 02:20:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 02:20:45 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json: timed out 02:20:45 INFO - retry: attempt #1 caught exception: timed out 02:20:45 INFO - retry: Failed, sleeping 30 seconds before retrying 02:21:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #2 02:21:35 INFO - Downloaded 1199 bytes. 02:21:35 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 02:21:35 INFO - Using the following test package requirements: 02:21:35 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:21:35 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:21:35 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 02:21:35 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:21:35 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 02:21:35 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:21:35 INFO - u'jsshell-mac.zip'], 02:21:35 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:21:35 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 02:21:35 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:21:35 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:21:35 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 02:21:35 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:21:35 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 02:21:35 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:21:35 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 02:21:35 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:21:35 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 02:21:35 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:21:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:21:35 INFO - https://queue.taskcluster.net/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:21:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:21:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:21:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:21:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:21:37 INFO - Downloaded 18466847 bytes. 02:21:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:21:37 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:21:40 INFO - Return code: 0 02:21:40 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:21: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')]} 02:21:40 INFO - https://queue.taskcluster.net/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:21:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:21:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:21:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:21:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:21:42 INFO - Downloaded 18466847 bytes. 02:21:42 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:21:42 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:21:45 INFO - Return code: 0 02:21:45 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')]} 02:21:45 INFO - https://queue.taskcluster.net/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 02:21:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:21:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:21:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:21:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:21:47 INFO - Downloaded 10840145 bytes. 02:21:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:21:47 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 02:21:47 INFO - Return code: 0 02:21:47 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')]} 02:21:47 INFO - https://queue.taskcluster.net/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 02:21:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:21:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:21:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 02:21:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 02:21:50 INFO - Downloaded 114222919 bytes. 02:21:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:21:50 INFO - mkdir: /builds/slave/test/properties 02:21:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:21:50 INFO - Writing to file /builds/slave/test/properties/build_url 02:21:50 INFO - Contents: 02:21:50 INFO - build_url:https://queue.taskcluster.net/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:21:51 INFO - Running post-action listener: _resource_record_post_action 02:21:51 INFO - Running post-action listener: set_extra_try_arguments 02:21:51 INFO - ##### 02:21:51 INFO - ##### Running populate-webroot step. 02:21:51 INFO - ##### 02:21:51 INFO - Running pre-action listener: _resource_record_pre_action 02:21:51 INFO - Running main action method: populate_webroot 02:21:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:21:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:21:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:21:51 INFO - '--tppagecycles', 02:21:51 INFO - '7'], 02:21:51 INFO - 'tests': ['tsvgm']}}, 02:21:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:21:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:21:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:21:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:21:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:21:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:21:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:21:51 INFO - 'talos_options': ['--disable-e10s'], 02:21:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:21:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:21:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:21:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:21:51 INFO - 'talos_options': ['--disable-e10s'], 02:21:51 INFO - 'tests': ['damp', 'tps']}, 02:21:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:21:51 INFO - 'tests': ['damp', 'tps']}, 02:21:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:21:51 INFO - 'tests': ['dromaeo_dom']}, 02:21:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:21:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:21:51 INFO - 'tests': ['a11yr', 02:21:51 INFO - 'ts_paint', 02:21:51 INFO - 'tpaint', 02:21:51 INFO - 'sessionrestore', 02:21:51 INFO - 'sessionrestore_no_auto_restore', 02:21:51 INFO - 'tabpaint']}, 02:21:51 INFO - 'other-e10s': {'tests': ['a11yr', 02:21:51 INFO - 'ts_paint', 02:21:51 INFO - 'tpaint', 02:21:51 INFO - 'sessionrestore', 02:21:51 INFO - 'sessionrestore_no_auto_restore', 02:21:51 INFO - 'tabpaint']}, 02:21:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:21:51 INFO - 'ts_paint', 02:21:51 INFO - 'tpaint', 02:21:51 INFO - 'sessionrestore', 02:21:51 INFO - 'sessionrestore_no_auto_restore', 02:21:51 INFO - 'tabpaint']}, 02:21:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:21:51 INFO - 'ts_paint', 02:21:51 INFO - 'tpaint', 02:21:51 INFO - 'sessionrestore', 02:21:51 INFO - 'sessionrestore_no_auto_restore', 02:21:51 INFO - 'tabpaint']}, 02:21:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:21:51 INFO - 'tests': ['a11yr', 02:21:51 INFO - 'ts_paint', 02:21:51 INFO - 'tpaint', 02:21:51 INFO - 'sessionrestore', 02:21:51 INFO - 'sessionrestore_no_auto_restore', 02:21:51 INFO - 'tabpaint']}, 02:21:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:21:51 INFO - 'tests': ['a11yr', 02:21:51 INFO - 'ts_paint', 02:21:51 INFO - 'tpaint', 02:21:51 INFO - 'sessionrestore', 02:21:51 INFO - 'sessionrestore_no_auto_restore', 02:21:51 INFO - 'tabpaint']}, 02:21:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:21:51 INFO - 'tests': ['tsvgx', 02:21:51 INFO - 'tsvgr_opacity', 02:21:51 INFO - 'tart', 02:21:51 INFO - 'tscrollx', 02:21:51 INFO - 'cart']}, 02:21:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:21:51 INFO - 'tsvgr_opacity', 02:21:51 INFO - 'tart', 02:21:51 INFO - 'tscrollx', 02:21:51 INFO - 'cart']}, 02:21:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:21:51 INFO - 'talos_options': ['--disable-e10s'], 02:21:51 INFO - 'tests': ['tp5o']}, 02:21:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:21:51 INFO - 'tests': ['tp5o']}, 02:21:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:21:51 INFO - 'talos_options': ['--disable-e10s', 02:21:51 INFO - '--xperf_path', 02:21:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:21:51 INFO - 'tests': ['tp5n']}, 02:21:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:21:51 INFO - 'talos_options': ['--xperf_path', 02:21:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:21:51 INFO - 'tests': ['tp5n']}}, 02:21:51 INFO - 'talos.zip': {'path': '', 02:21:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:21:51 INFO - Running post-action listener: _resource_record_post_action 02:21:51 INFO - ##### 02:21:51 INFO - ##### Running create-virtualenv step. 02:21:51 INFO - ##### 02:21:51 INFO - Running pre-action listener: _resource_record_pre_action 02:21:51 INFO - Running main action method: create_virtualenv 02:21:51 INFO - Creating virtualenv /builds/slave/test/build/venv 02:21:51 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 02:21:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:21:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:21:52 INFO - Using real prefix '/tools/python27' 02:21:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:21:52 INFO - Installing distribute.............................................................................................................................................................................................done. 02:21:55 INFO - Installing pip.................done. 02:21:55 INFO - Return code: 0 02:21:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:21:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:21:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:21:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:21:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:21:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:21:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:21:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a6fba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a70b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4034ab600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a59ae00>, '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 0x10a403db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a595b20>, '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.kDYxWdEQIS/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.wd83DXyy4M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 02:21:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:21:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:21:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kDYxWdEQIS/Render', 02:21:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:21:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:21:55 INFO - 'HOME': '/Users/cltbld', 02:21:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:21:55 INFO - 'LOGNAME': 'cltbld', 02:21:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:21:55 INFO - 'MOZ_NO_REMOTE': '1', 02:21:55 INFO - 'NO_EM_RESTART': '1', 02:21:55 INFO - 'PAGER': '/bin/cat', 02:21:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:21:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:21:55 INFO - 'PWD': '/builds/slave/test', 02:21:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:21:55 INFO - 'SHELL': '/bin/bash', 02:21:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wd83DXyy4M/Listeners', 02:21:55 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 02:21:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:21:55 INFO - 'USER': 'cltbld', 02:21:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:21:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:21:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:21:55 INFO - 'XPC_FLAGS': '0x0', 02:21:55 INFO - 'XPC_SERVICE_NAME': '0', 02:21:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:21:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:21:55 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 02:21:55 INFO - Cleaning up... 02:21:55 INFO - Return code: 0 02:21:55 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 02:21:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:21:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:21:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:21:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:21:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:21:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:21:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a6fba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a70b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4034ab600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a59ae00>, '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 0x10a403db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a595b20>, '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.kDYxWdEQIS/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.wd83DXyy4M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 02:21:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 02:21:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 02:21:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kDYxWdEQIS/Render', 02:21:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:21:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:21:55 INFO - 'HOME': '/Users/cltbld', 02:21:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:21:55 INFO - 'LOGNAME': 'cltbld', 02:21:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:21:55 INFO - 'MOZ_NO_REMOTE': '1', 02:21:55 INFO - 'NO_EM_RESTART': '1', 02:21:55 INFO - 'PAGER': '/bin/cat', 02:21:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:21:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:21:55 INFO - 'PWD': '/builds/slave/test', 02:21:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:21:55 INFO - 'SHELL': '/bin/bash', 02:21:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wd83DXyy4M/Listeners', 02:21:55 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 02:21:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:21:55 INFO - 'USER': 'cltbld', 02:21:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:21:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:21:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:21:55 INFO - 'XPC_FLAGS': '0x0', 02:21:55 INFO - 'XPC_SERVICE_NAME': '0', 02:21:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:21:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:21:56 INFO - Downloading/unpacking psutil>=0.7.1 02:21:56 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 02:21:56 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 02:21:56 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 02:21:56 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 02:21:56 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 02:21:56 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 02:21:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:21:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:21:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:21:59 INFO - Installing collected packages: psutil 02:21:59 INFO - Running setup.py install for psutil 02:21:59 INFO - building 'psutil._psutil_osx' extension 02:21:59 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 02:21:59 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 02:21:59 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 02:21:59 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 02:22:00 INFO - building 'psutil._psutil_posix' extension 02:22:00 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 02:22:00 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:22:00 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:22:00 INFO - ^ 02:22:00 INFO - 1 warning generated. 02:22:00 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 02:22:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:22:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:22:00 INFO - Successfully installed psutil 02:22:00 INFO - Cleaning up... 02:22:00 INFO - Return code: 0 02:22:00 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 02:22:00 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')]} 02:22:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:22:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:00 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')]} 02:22:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:22:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:00 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 0x10a6fba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a70b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4034ab600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a59ae00>, '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 0x10a403db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a595b20>, '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.kDYxWdEQIS/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.wd83DXyy4M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 02:22:00 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 02:22:00 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 02:22:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kDYxWdEQIS/Render', 02:22:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:22:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:22:00 INFO - 'HOME': '/Users/cltbld', 02:22:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:22:00 INFO - 'LOGNAME': 'cltbld', 02:22:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:22:00 INFO - 'MOZ_NO_REMOTE': '1', 02:22:00 INFO - 'NO_EM_RESTART': '1', 02:22:00 INFO - 'PAGER': '/bin/cat', 02:22:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:22:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:22:00 INFO - 'PWD': '/builds/slave/test', 02:22:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:22:00 INFO - 'SHELL': '/bin/bash', 02:22:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wd83DXyy4M/Listeners', 02:22:00 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 02:22:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:22:00 INFO - 'USER': 'cltbld', 02:22:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:22:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:22:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:22:00 INFO - 'XPC_FLAGS': '0x0', 02:22:00 INFO - 'XPC_SERVICE_NAME': '0', 02:22:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:22:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:22:00 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 02:22:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:22:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:22:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:22:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:22:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:22:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:22:03 INFO - Downloading mozsystemmonitor-0.0.tar.gz 02:22:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:22:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 02:22:03 INFO - Installing collected packages: mozsystemmonitor 02:22:03 INFO - Running setup.py install for mozsystemmonitor 02:22:03 INFO - Successfully installed mozsystemmonitor 02:22:03 INFO - Cleaning up... 02:22:03 INFO - Return code: 0 02:22:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:22: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')]} 02:22:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:22:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22: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')]} 02:22:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:22:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22: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', '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 0x10a6fba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a70b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4034ab600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a59ae00>, '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 0x10a403db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a595b20>, '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.kDYxWdEQIS/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.wd83DXyy4M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 02:22: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:22: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 blobuploader==1.2.4 02:22:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kDYxWdEQIS/Render', 02:22:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:22:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:22:03 INFO - 'HOME': '/Users/cltbld', 02:22:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:22:03 INFO - 'LOGNAME': 'cltbld', 02:22:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:22:03 INFO - 'MOZ_NO_REMOTE': '1', 02:22:03 INFO - 'NO_EM_RESTART': '1', 02:22:03 INFO - 'PAGER': '/bin/cat', 02:22:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:22:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:22:03 INFO - 'PWD': '/builds/slave/test', 02:22:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:22:03 INFO - 'SHELL': '/bin/bash', 02:22:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wd83DXyy4M/Listeners', 02:22:03 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 02:22:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:22:03 INFO - 'USER': 'cltbld', 02:22:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:22:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:22:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:22:03 INFO - 'XPC_FLAGS': '0x0', 02:22:03 INFO - 'XPC_SERVICE_NAME': '0', 02:22:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:22:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:22:03 INFO - Downloading/unpacking blobuploader==1.2.4 02:22: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 02:22: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 02:22: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 02:22: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 02:22: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 02:22: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 02:22:05 INFO - Downloading blobuploader-1.2.4.tar.gz 02:22:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:22:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:22: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 02:22: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 02:22: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 02:22: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 02:22: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 02:22: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 02:22:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:22:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:22: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 02:22: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 02:22: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 02:22: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 02:22: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 02:22: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 02:22:07 INFO - Downloading docopt-0.6.1.tar.gz 02:22:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:22:07 INFO - Installing collected packages: blobuploader, requests, docopt 02:22:07 INFO - Running setup.py install for blobuploader 02:22:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:22:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:22:07 INFO - Running setup.py install for requests 02:22:07 INFO - Running setup.py install for docopt 02:22:08 INFO - Successfully installed blobuploader requests docopt 02:22:08 INFO - Cleaning up... 02:22:08 INFO - Return code: 0 02:22:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:22:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:22:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:22:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:22:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:22:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:08 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 0x10a6fba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a70b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4034ab600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a59ae00>, '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 0x10a403db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a595b20>, '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.kDYxWdEQIS/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.wd83DXyy4M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 02:22:08 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 02:22:08 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 02:22:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kDYxWdEQIS/Render', 02:22:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:22:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:22:08 INFO - 'HOME': '/Users/cltbld', 02:22:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:22:08 INFO - 'LOGNAME': 'cltbld', 02:22:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:22:08 INFO - 'MOZ_NO_REMOTE': '1', 02:22:08 INFO - 'NO_EM_RESTART': '1', 02:22:08 INFO - 'PAGER': '/bin/cat', 02:22:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:22:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:22:08 INFO - 'PWD': '/builds/slave/test', 02:22:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:22:08 INFO - 'SHELL': '/bin/bash', 02:22:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wd83DXyy4M/Listeners', 02:22:08 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 02:22:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:22:08 INFO - 'USER': 'cltbld', 02:22:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:22:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:22:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:22:08 INFO - 'XPC_FLAGS': '0x0', 02:22:08 INFO - 'XPC_SERVICE_NAME': '0', 02:22:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:22:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:22:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:22:08 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-xdkX6V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:22:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:22:08 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-1rygXQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:22:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:22:08 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-4fXxeK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:22:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:22:08 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-zqWJY9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:22:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:22:08 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-4Q8J_d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:22:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:22:08 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-npUw6j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:22:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:22:09 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-WQ3Q03-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:22:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:22:09 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-mHKK1h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:22:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:22:09 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-kYsPfd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:22:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:22:09 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-DSgoOs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:22:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:22:09 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-UVS2DI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:22:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:22:09 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-HEM3q5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:22:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:22:09 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-KBsxcj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:22:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:22:09 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-Y5tZzG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:22:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:22:09 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-kMOqlZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:22:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:22:10 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-DLMq77-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:22:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:22:10 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-RWXpOa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:22:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:22:10 INFO - Running setup.py install for manifestparser 02:22:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:22:10 INFO - Running setup.py install for mozcrash 02:22:10 INFO - Running setup.py install for mozdebug 02:22:10 INFO - Running setup.py install for mozdevice 02:22:10 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:22:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:22:10 INFO - Running setup.py install for mozfile 02:22:10 INFO - Running setup.py install for mozhttpd 02:22:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:22:11 INFO - Running setup.py install for mozinfo 02:22:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:22:11 INFO - Running setup.py install for mozInstall 02:22:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:22:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:22:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:22:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:22:11 INFO - Running setup.py install for mozleak 02:22:11 INFO - Running setup.py install for mozlog 02:22:11 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:22:11 INFO - Running setup.py install for moznetwork 02:22:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:22:11 INFO - Running setup.py install for mozprocess 02:22:12 INFO - Running setup.py install for mozprofile 02:22:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:22:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:22:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:22:12 INFO - Running setup.py install for mozrunner 02:22:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:22:12 INFO - Running setup.py install for mozscreenshot 02:22:12 INFO - Running setup.py install for moztest 02:22:12 INFO - Running setup.py install for mozversion 02:22:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:22:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:22:12 INFO - Cleaning up... 02:22:12 INFO - Return code: 0 02:22:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:22:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:22:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:22:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:22:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:22:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:12 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 0x10a6fba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a70b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4034ab600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a59ae00>, '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 0x10a403db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a595b20>, '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.kDYxWdEQIS/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.wd83DXyy4M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 02:22:12 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 02:22:12 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 02:22:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kDYxWdEQIS/Render', 02:22:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:22:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:22:12 INFO - 'HOME': '/Users/cltbld', 02:22:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:22:12 INFO - 'LOGNAME': 'cltbld', 02:22:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:22:12 INFO - 'MOZ_NO_REMOTE': '1', 02:22:12 INFO - 'NO_EM_RESTART': '1', 02:22:12 INFO - 'PAGER': '/bin/cat', 02:22:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:22:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:22:12 INFO - 'PWD': '/builds/slave/test', 02:22:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:22:12 INFO - 'SHELL': '/bin/bash', 02:22:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wd83DXyy4M/Listeners', 02:22:12 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 02:22:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:22:12 INFO - 'USER': 'cltbld', 02:22:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:22:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:22:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:22:12 INFO - 'XPC_FLAGS': '0x0', 02:22:12 INFO - 'XPC_SERVICE_NAME': '0', 02:22:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:22:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:22:13 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-lfSKFE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:22:13 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)) 02:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:22:13 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-RdIdc5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:22:13 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)) 02:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:22:13 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-kLpbWv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:22:13 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)) 02:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:22:13 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-3IgBn1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:22:13 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)) 02:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:22:13 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-V2xgC8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:22:13 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)) 02:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:22:13 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-ZmWihx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:22:13 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)) 02:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:22:13 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-VGLRhM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:22:13 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)) 02:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:22:13 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-zwbJH6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:22:13 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)) 02:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:22:13 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-FxKwpu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:22:14 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)) 02:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:22:14 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-oPH1vc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:22:14 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)) 02:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:22:14 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-7uc2BD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:22:14 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)) 02:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:22:14 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-j6FnuQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:22:14 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)) 02:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:22:14 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-8P7HFz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:22:14 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)) 02:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:22:14 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-JWT7Bp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:22:14 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)) 02:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:22:14 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-QhlusY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:22:14 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)) 02:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:22:14 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-LmIiJ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:22:14 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)) 02:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:22:14 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-__Z4LE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:22:14 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)) 02:22:14 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)) 02:22:14 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)) 02:22:14 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)) 02:22:14 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)) 02:22:14 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)) 02:22:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:22:14 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 02:22:14 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 02:22:14 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 02:22:14 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 02:22:14 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 02:22:14 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 02:22:17 INFO - Downloading blessings-1.6.tar.gz 02:22:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:22:17 INFO - Installing collected packages: blessings 02:22:17 INFO - Running setup.py install for blessings 02:22:17 INFO - Successfully installed blessings 02:22:17 INFO - Cleaning up... 02:22:17 INFO - Return code: 0 02:22:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:22:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:22:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:22:17 INFO - Reading from file tmpfile_stdout 02:22:17 INFO - Current package versions: 02:22:17 INFO - blessings == 1.6 02:22:17 INFO - blobuploader == 1.2.4 02:22:17 INFO - docopt == 0.6.1 02:22:17 INFO - manifestparser == 1.1 02:22:17 INFO - mozInstall == 1.12 02:22:17 INFO - mozcrash == 0.17 02:22:17 INFO - mozdebug == 0.1 02:22:17 INFO - mozdevice == 0.48 02:22:17 INFO - mozfile == 1.2 02:22:17 INFO - mozhttpd == 0.7 02:22:17 INFO - mozinfo == 0.9 02:22:17 INFO - mozleak == 0.1 02:22:17 INFO - mozlog == 3.1 02:22:17 INFO - moznetwork == 0.27 02:22:17 INFO - mozprocess == 0.22 02:22:17 INFO - mozprofile == 0.28 02:22:17 INFO - mozrunner == 6.11 02:22:17 INFO - mozscreenshot == 0.1 02:22:17 INFO - mozsystemmonitor == 0.0 02:22:17 INFO - moztest == 0.7 02:22:17 INFO - mozversion == 1.4 02:22:17 INFO - psutil == 3.1.1 02:22:17 INFO - requests == 1.2.3 02:22:17 INFO - wsgiref == 0.1.2 02:22:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:22:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:22:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:22:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:22:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:22:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:17 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 0x10a6fba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a70b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4034ab600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a59ae00>, '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 0x10a403db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a595b20>, '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.kDYxWdEQIS/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.wd83DXyy4M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 02:22:17 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 02:22:17 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 02:22:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kDYxWdEQIS/Render', 02:22:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:22:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:22:17 INFO - 'HOME': '/Users/cltbld', 02:22:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:22:17 INFO - 'LOGNAME': 'cltbld', 02:22:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:22:17 INFO - 'MOZ_NO_REMOTE': '1', 02:22:17 INFO - 'NO_EM_RESTART': '1', 02:22:17 INFO - 'PAGER': '/bin/cat', 02:22:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:22:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:22:17 INFO - 'PWD': '/builds/slave/test', 02:22:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:22:17 INFO - 'SHELL': '/bin/bash', 02:22:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wd83DXyy4M/Listeners', 02:22:17 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 02:22:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:22:17 INFO - 'USER': 'cltbld', 02:22:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:22:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:22:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:22:17 INFO - 'XPC_FLAGS': '0x0', 02:22:17 INFO - 'XPC_SERVICE_NAME': '0', 02:22:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:22:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:22:18 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)) 02:22:18 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)) 02:22:18 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)) 02:22:18 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)) 02:22:18 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)) 02:22:18 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)) 02:22:18 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)) 02:22:18 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)) 02:22:18 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)) 02:22:18 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)) 02:22:18 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)) 02:22:18 INFO - Cleaning up... 02:22:18 INFO - Return code: 0 02:22:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:22:18 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')]} 02:22:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:22:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:18 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')]} 02:22:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:22:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:18 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 0x10a6fba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a70b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4034ab600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a59ae00>, '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 0x10a403db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a595b20>, '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.kDYxWdEQIS/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.wd83DXyy4M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 02:22:18 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 02:22:18 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 02:22:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kDYxWdEQIS/Render', 02:22:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:22:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:22:18 INFO - 'HOME': '/Users/cltbld', 02:22:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:22:18 INFO - 'LOGNAME': 'cltbld', 02:22:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:22:18 INFO - 'MOZ_NO_REMOTE': '1', 02:22:18 INFO - 'NO_EM_RESTART': '1', 02:22:18 INFO - 'PAGER': '/bin/cat', 02:22:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:22:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:22:18 INFO - 'PWD': '/builds/slave/test', 02:22:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:22:18 INFO - 'SHELL': '/bin/bash', 02:22:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wd83DXyy4M/Listeners', 02:22:18 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 02:22:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:22:18 INFO - 'USER': 'cltbld', 02:22:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:22:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:22:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:22:18 INFO - 'XPC_FLAGS': '0x0', 02:22:18 INFO - 'XPC_SERVICE_NAME': '0', 02:22:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:22:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:22:18 INFO - Downloading/unpacking jsonschema 02:22:18 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 02:22:18 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 02:22:18 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 02:22:18 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 02:22:18 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 02:22:18 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 02:22:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:22:20 INFO - Downloading/unpacking functools32 (from jsonschema) 02:22:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:22:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:22:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:22:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:22:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:22:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:22:21 INFO - Downloading functools32-3.2.3-2.tar.gz 02:22:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:22:21 INFO - warning: no files found matching '*.txt' 02:22:21 INFO - no previously-included directories found matching 'build' 02:22:21 INFO - no previously-included directories found matching 'dist' 02:22:21 INFO - no previously-included directories found matching '.git*' 02:22:21 INFO - Installing collected packages: jsonschema, functools32 02:22:21 INFO - Running setup.py install for functools32 02:22:21 INFO - warning: no files found matching '*.txt' 02:22:21 INFO - no previously-included directories found matching 'build' 02:22:21 INFO - no previously-included directories found matching 'dist' 02:22:21 INFO - no previously-included directories found matching '.git*' 02:22:21 INFO - Successfully installed jsonschema functools32 02:22:21 INFO - Cleaning up... 02:22:21 INFO - Return code: 0 02:22:21 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 02:22: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')]} 02:22:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:22:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22: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')]} 02:22:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:22:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a6fba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a70b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4034ab600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a59ae00>, '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 0x10a403db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a595b20>, '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.kDYxWdEQIS/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.wd83DXyy4M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 02:22: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', 'flake8'] in /builds/slave/test/build 02:22: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 flake8 02:22:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kDYxWdEQIS/Render', 02:22:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:22:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:22:21 INFO - 'HOME': '/Users/cltbld', 02:22:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:22:21 INFO - 'LOGNAME': 'cltbld', 02:22:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:22:21 INFO - 'MOZ_NO_REMOTE': '1', 02:22:21 INFO - 'NO_EM_RESTART': '1', 02:22:21 INFO - 'PAGER': '/bin/cat', 02:22:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:22:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:22:21 INFO - 'PWD': '/builds/slave/test', 02:22:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:22:21 INFO - 'SHELL': '/bin/bash', 02:22:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wd83DXyy4M/Listeners', 02:22:21 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 02:22:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:22:21 INFO - 'USER': 'cltbld', 02:22:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:22:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:22:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:22:21 INFO - 'XPC_FLAGS': '0x0', 02:22:21 INFO - 'XPC_SERVICE_NAME': '0', 02:22:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:22:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:22:21 INFO - Downloading/unpacking flake8 02:22: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 02:22: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 02:22: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 02:22: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 02:22: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 02:22: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 02:22:24 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 02:22:24 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 02:22: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 02:22: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 02:22: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 02:22: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 02:22: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 02:22: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 02:22:24 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 02:22:24 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 02:22: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 02:22: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 02:22: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 02:22: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 02:22: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 02:22: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 02:22:25 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 02:22:25 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 02:22:25 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 02:22:25 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 02:22:25 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 02:22:25 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 02:22:25 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 02:22:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 02:22:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:22:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:22:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:22:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:22:25 INFO - no previously-included directories found matching 'docs/_build' 02:22:25 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 02:22:25 INFO - Running setup.py install for pep8 02:22:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:22:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:22:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:22:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:22:25 INFO - no previously-included directories found matching 'docs/_build' 02:22:25 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 02:22:25 INFO - Successfully installed flake8 mccabe pyflakes pep8 02:22:25 INFO - Cleaning up... 02:22:25 INFO - Return code: 0 02:22:25 INFO - Running post-action listener: _resource_record_post_action 02:22:25 INFO - Running post-action listener: _start_resource_monitoring 02:22:26 INFO - Starting resource monitoring. 02:22:26 INFO - ##### 02:22:26 INFO - ##### Running install step. 02:22:26 INFO - ##### 02:22:26 INFO - Running pre-action listener: _resource_record_pre_action 02:22:26 INFO - Running main action method: install 02:22:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:22:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:22:26 INFO - Reading from file tmpfile_stdout 02:22:26 INFO - Detecting whether we're running mozinstall >=1.0... 02:22:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:22:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:22:26 INFO - Reading from file tmpfile_stdout 02:22:26 INFO - Output received: 02:22:26 INFO - Usage: mozinstall [options] installer 02:22:26 INFO - Options: 02:22:26 INFO - -h, --help show this help message and exit 02:22:26 INFO - -d DEST, --destination=DEST 02:22:26 INFO - Directory to install application into. [default: 02:22:26 INFO - "/builds/slave/test"] 02:22:26 INFO - --app=APP Application being installed. [default: firefox] 02:22:26 INFO - mkdir: /builds/slave/test/build/application 02:22:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:22:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:22:57 INFO - Reading from file tmpfile_stdout 02:22:57 INFO - Output received: 02:22:57 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:22:57 INFO - Running post-action listener: _resource_record_post_action 02:22:57 INFO - ##### 02:22:57 INFO - ##### Running run-tests step. 02:22:57 INFO - ##### 02:22:57 INFO - Running pre-action listener: _resource_record_pre_action 02:22:57 INFO - Running main action method: run_tests 02:22:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:22:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:22:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:22:57 INFO - 2016-06-05 02:22:57.833 screenresolution[1646:7970] starting screenresolution argv=screenresolution get 02:22:57 INFO - 2016-06-05 02:22:57.835 screenresolution[1646:7970] Display 0: 1600x1200x32@60 02:22:57 INFO - 2016-06-05 02:22:57.841 screenresolution[1647:7973] starting screenresolution argv=screenresolution list 02:22:57 INFO - Available Modes on Display 0 02:22:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:22:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:22:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:22:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:22:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:22:58 INFO - Intel Iris: 02:22:58 INFO - Chipset Model: Intel Iris 02:22:58 INFO - Type: GPU 02:22:58 INFO - Bus: Built-In 02:22:58 INFO - VRAM (Dynamic, Max): 1536 MB 02:22:58 INFO - Vendor: Intel (0x8086) 02:22:58 INFO - Device ID: 0x0a2e 02:22:58 INFO - Revision ID: 0x0009 02:22:58 INFO - Displays: 02:22:58 INFO - Display: 02:22:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:22:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:22:58 INFO - Display Serial Number: 1600x1200 60 02:22:58 INFO - Main Display: Yes 02:22:58 INFO - Mirror: Off 02:22:58 INFO - Online: Yes 02:22:58 INFO - Rotation: Supported 02:22:58 INFO - Return code: 0 02:22:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:22:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:22:58 INFO - Python 2.7.3 02:22:58 INFO - Return code: 0 02:22:58 INFO - grabbing minidump binary from tooltool 02:22: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')]} 02:22:58 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 0x10a59ae00>, '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 0x10a403db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a595b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:22:58 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 02:22:58 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 02:22:58 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:22:58 INFO - Return code: 0 02:22:58 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:22:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:22:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:22:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:22:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:22:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 02:22:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 02:22:59 INFO - Return code: 0 02:22:59 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-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0137', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 02:22:59 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-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0137 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 02:22:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kDYxWdEQIS/Render', 02:22:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:22:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:22:59 INFO - 'HOME': '/Users/cltbld', 02:22:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:22:59 INFO - 'LOGNAME': 'cltbld', 02:22:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:22:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:22:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:22:59 INFO - 'MOZ_NO_REMOTE': '1', 02:22:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:22:59 INFO - 'NO_EM_RESTART': '1', 02:22:59 INFO - 'PAGER': '/bin/cat', 02:22:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:22:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:22:59 INFO - 'PWD': '/builds/slave/test', 02:22:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:22:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:22:59 INFO - 'SHELL': '/bin/bash', 02:22:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wd83DXyy4M/Listeners', 02:22:59 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 02:22:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:22:59 INFO - 'USER': 'cltbld', 02:22:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:22:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:22:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:22:59 INFO - 'XPC_FLAGS': '0x0', 02:22:59 INFO - 'XPC_SERVICE_NAME': '0', 02:22:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:22:59 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0137', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 02:22:59 INFO - mozversion application_buildid: 20160605004013 02:22:59 INFO - mozversion application_changeset: dc972d877cd697c2c8f6248534f21bb5e1dba9ae 02:22:59 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:22:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:22:59 INFO - mozversion application_name: Firefox 02:22:59 INFO - mozversion application_remotingname: firefox-dev 02:22:59 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:22:59 INFO - mozversion application_vendor: Mozilla 02:22:59 INFO - mozversion application_version: 48.0a2 02:22:59 INFO - mozversion platform_buildid: 20160605004013 02:22:59 INFO - mozversion platform_changeset: dc972d877cd697c2c8f6248534f21bb5e1dba9ae 02:22:59 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:22:59 INFO - mozversion platform_version: 48.0a2 02:22:59 INFO - using testdate: 1465118579 02:22:59 INFO - actual date: 1465118579 02:22:59 INFO - starting webserver on 'localhost:49276' 02:22:59 INFO - SUITE-START | Running 2 tests 02:22:59 INFO - TEST-START | dromaeo_css 02:22:59 INFO - operating with platform_type : mac_ 02:22:59 INFO - Initialising browser for dromaeo_css test... 02:22:59 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/tmpB3xTdl/profile http://localhost:49276/getInfo.html) 02:23:02 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 02:23:02 INFO - PROCESS | 1665 | colorDepth:24 02:23:02 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 02:23:02 INFO - PROCESS | 1665 | __metrics 02:23:02 ERROR - PROCESS | 1665 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2940: TypeError: this.tabs is undefined 02:23:03 INFO - TEST-INFO | 1665: exit 0 02:23:03 INFO - Browser initialized. 02:23:03 INFO - Running cycle 1/1 for dromaeo_css test... 02:23:03 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/tmpB3xTdl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:23:15 INFO - PROCESS | 1670 | RSS: Main: 220729344 02:23:15 INFO - PROCESS | 1670 | 02:24:04 INFO - PROCESS | 1670 | 1465118644717 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] 02:26:16 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49276/tests/dromaeo/cssquery-ext.html) 02:26:17 INFO - PROCESS | 1670 | RSS: Main: 231288832 02:26:17 INFO - PROCESS | 1670 | 02:29:19 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-ext.html (next: http://localhost:49276/tests/dromaeo/cssquery-jquery.html) 02:29:20 INFO - PROCESS | 1670 | RSS: Main: 243195904 02:29:20 INFO - PROCESS | 1670 | 02:32:21 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49276/tests/dromaeo/cssquery-mootools.html) 02:32:22 INFO - PROCESS | 1670 | RSS: Main: 245784576 02:32:22 INFO - PROCESS | 1670 | 02:35:24 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49276/tests/dromaeo/cssquery-prototype.html) 02:35:24 INFO - PROCESS | 1670 | RSS: Main: 246964224 02:35:24 INFO - PROCESS | 1670 | 02:38:27 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49276/tests/dromaeo/cssquery-yui.html) 02:38:28 INFO - PROCESS | 1670 | RSS: Main: 249815040 02:38:28 INFO - PROCESS | 1670 | 02:41:30 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-yui.html (next: http://localhost:49276/tests/dromaeo/cssquery-dojo.html) 02:41:30 INFO - PROCESS | 1670 | RSS: Main: 251469824 02:41:30 INFO - PROCESS | 1670 | 02:41:30 INFO - PROCESS | 1670 | __start_tp_report 02:41:30 INFO - PROCESS | 1670 | _x_x_mozilla_page_load 02:41:30 INFO - PROCESS | 1670 | _x_x_mozilla_page_load_details 02:41:30 INFO - PROCESS | 1670 | |i|pagename|runs| 02:41:30 INFO - PROCESS | 1670 | |0;dojo.html;3409.7272218222543;3440.8795692150748;3497.2131270464133;3502.4221003534417;3503.597086335071;5052.646314757963;5287.788488460457;5694.34515030771;5699.088145896658;5726.112452569848;4594.770261486929;4609.354690343346;5676.772929082842;5708.372079071299;5733.942660573393;5099.643024988252;5148.897022059557;5246.7638956247065;5275.657082289658;5312.73436328184;5013.222950442687;5200.193969934666;5212.5308722214995;5217.86955326118;5224.8316948466345;5125.410577783566;5186.507281808223;5187.844364669066;5188.299579556769;5193.45468725784;5149.407818100892;5204.479552044803;5213.400458947212;5218.217267409881;5226.8693282667855;5066.847994560169;5156.355455568054;5159.793608255665;5162.22566615007;5215;5408.729563521808;5420.186971954199;5426.75579598919;5540.778368865241;5603.299587551556;5307.575393968474;6181.949068658339;6221.160143380631;6231.813045608594;6233.906491402633;6230.06549017651;6271.059341098826;6272.905906411407;6278.4663303619245;6289.37106289372;5632.859178520488;5734.741936612862;5758.654480731169;5785.392533783935;5789.218455508537;6220.284667263322;6241.251049874044;6248.31268560458;6256.405641464054;6271.216097987752;6098.237720284965;6098.481629061579;6114.419130182626;6119.510439164945;6134.355892631299;5352.892942141136;6142.099766648211;6231.065340199007;6234.688265586702;6299.5275354348605;6085.9695701521205;6141.662208578482;6145.354737752561;6163.7842675506145;6167.105769663373;5072.594192464594;6173.81478555644;6182.474489668339;6185.721642526097;6186.072089186658;4617.145828021827;4843.370361852936;4909.729964851967;4910.508949105061;4935;4318.985038515946;4348.782560871944;4357.455318085239;4361.214981303332;4423.889402764892;5590.105583106683;5630.465105815023;5688.23208866806;5694.629849059798;5730.684812335278;6138.32709113608;6221.222347206599;6258.061290806416;6264.123022776815;6270.435660790551;5738.311402631627;5745.253117094751;5757.539396848242;5759.942400576025;5778.104393818965;5284.180951952453;5315.867103322448;5378.2470454136455;5404.702741349263;5407.188921661704;6172.475339596095;6228.844278893064;6240.438360547481;6250.218722659668;6252.687365631791;6201.596896201732;6216.564840461305;6227.439530442282;6231.314555398993;6255.874882502416;6110.572259941761;6227.626342419469;6248.562600617913;6255;6260.154879091265;6039.667818270037;6081.756729730938;6097.32929377777;6104.664243466652;6134.14122022926;4632.606228470618;6172.4136207060255;6176.474999624902;6177.382261773787;6203.069539569101;4976.353074100344;5013.323201367769;5410.404855465974;5530.834074977757;5871.735771890189;5012.924806127984;5978.8206353809455;6019.909701354571;6029.035354343284;6031.879362412815;5217.34783152106;5775.422457754191;5802.796902108406;5805.82582522525;5860.589758717019;5027;5853.326867410302;6093.146959425595;6093.177421048279;6104.20645316106;5857.765689372377;5867.882642347044;5898.1152827076285;5926.970365148147;5934.881302374015 02:41:30 INFO - PROCESS | 1670 | |1;ext.html;512.0808149371879;550.7163810637521;582.1006544888147;589.9204455845803;605.5397897597827;38872.80563597182;39865.60134398655;39889.40165897507;40172;40191.59808401915;40900.18199636013;41599.16801663965;41639.58360416395;42133.7893310533;42165.52420665277;9556.331056826042;9686.273529485281;9813.214942804558;9838.704838854846;9900.554475048604;9514.571844267024;9728.17310528606;9774.90225097747;9798.510074496282;9865.90134098657;9728.270379721549;9754.975727548612;9778.11996920277;9808.362456440344;9880.950595247014;9734;9747.512624368788;9763.21894248462;9766.121049105544;9818;8643.654253829902;9257.351985361027;9699.563519641633;9703.90296097037;9713.70858874235;39444.60555394438;39532.81401557958;39961.20077598461;39965;40055;3145.3709258148233;3237.7895436796534;3298.0765385692043;3595.460680897886;3627.1838836261895;27612.447751045067;27761.444771104667;27913.44173116526;28059.71940280612;28108.859455702794;2766.3775650478683;2775.4449110178043;2788.553831386989;2793.748562629353;2806.4246829400126;433510;434971;435095.82452087855;435155;436519;64611;64972.67513662496;65057.67471162614;65427.69144617081;65629;66549.33450665431;66725.99911001242;70793.64603177054;72967;73077;17516.386926457766;17584.472465826046;17611.55971100707;17737;17754.467365979042;12685.23888566689;12981.026423018311;13019.544315948897;13043.543475978295;13048.412821423059;20038.59922801536;20050.59898802016;20268.98655067241;20303.88328641909;20315.390538283875;8247.917520824714;8425.95787021061;8439.746807595782;8497.957510212409;8538.70114545988;962.9855552166723;1023.7338292044122;1023.8515415264745;1058.7300238439302;1188.5424111717052;30346.393072138435;30575.847120764698;30760.846195768878;31008.53487197694;31065.378692426024;1114.648885601033;1134.0304040045808;1134.325076579434;1220.9633710988685;1294.4563283420798;14020.369083391271;15174.620634484005;15586.610334741496;16090.195490225677;16163.67672646564;508258;638790;639087.8045609836;651177;660517;53070;63636.36363636304;63913;64177;64211.71576568442;1476.6160798192548;1503.3535579700833;1507.811523559555;1509.2906334022991;1509.4037855047195;29857.805687772245;30601.48538309224;30610.540841886945;30683;30762;47627.52372476231;56502.71748641231;57105.714471427375;57139.42860571341;57392.1391179141;578478;578529;580829;583360;589400;27861.44277114406;28244.43511129807;28246;28564.714352856205;28712;47776.52223477721;48442;48513.27230091622;49423.25865112098;49672.751636241584;31707;39488;39524.2095158101;39876.2055707488;40604.79697601493;30930.536041958938;32154.678453216104;39134.45626034299;39170.608293916695;39242.80378598089;33038.66961330356;37729.81135094307;38649.8405047849;39063.02342441462;39137.80431097826;30774;32772;32918.835405822814;32933.50599741054;33090.834545827114;24387.878060610292;24710.50578988374;24876.875615621808;24972.37569060788;25207.74792252054 02:41:30 INFO - PROCESS | 1670 | |2;jquery.html;341.7249114462857;396.70643723644514;397.5686380277399;407.7145997801538;412.76678676547766;18882.811171888276;19001.714974275397;21089.26187583435;21094.367168984918;21124.366269011934;20670.06984685689;21074.262400815973;21149.154033838666;21197.576048479023;21328.4667883303;5664.603477756558;5787.536997040237;6042.788502402417;6055.182550355689;6067.544934129935;5888.411158884121;5951.345352011275;5985.551083668721;5985.5810093293485;6004.609700369484;5823.563232757539;5888.087346461305;6055.666938318391;6062.4543791058795;6075.058365953262;6013.398660133996;6055.2430946131735;6056.21269235001;6060.060690592965;6066.96966515169;5811.767529298823;5907.556933230025;5953.851153721148;5994.460498555151;6005.249343832021;13602.863971360259;13950.441982320695;14301.57095287143;25719.871400642878;26407.603885941728;1968.7342208801772;2015.1536354731047;2144.1959265275523;2238.5187184755355;2370.13490076121;9722.270829687732;9849.950750246275;9914.206863450907;9934.90065099347;10128.392296462162;1645.3254165791968;1685.1321569391773;1705.8464738173625;1729.723244280909;1753.4213709475841;88867.11132888717;99897.50051249698;102259;103724.96275037229;104669;11488.023518000897;11698.941505292589;11769;11776.94111529454;11933;14662.926685366505;14830.887683423787;14888.925555372154;14917.627059323386;14924.850751492346;8844.248238899765;8854.690085847093;8856.38005339633;8863.689770858118;8936.195742383217;8998.820023599492;9022.86465703015;9024.954875225581;9041.457512549268;9051.864222036675;3840.635139661728;4656.3946686930485;4668.719876807402;4700.106979673851;4719.1977363847545;1717.9141042948052;1931.5460866696314;1986.3742920979846;2026.8885211313225;2046.3758553641283;906.8413027720254;1005.3816902604846;1056.9365838049741;1064.9467526623637;1092.0662833277593;15958.361665533248;20235.494112647008;20779.761904761905;20983.79016209849;21156.365309040753;959.9424034557949;987.5013118375175;997.9023074618008;1003.518311210623;1004.7437903334603;10650.414227217423;11046.447677616086;11050.065311250493;11061.944690276498;11086.478919548901;596080;601455;602606;609810;610877;95113.57329640062;121123;124706;128606;129237;1005.2460654509118;1008.3193844155165;1041.068243921686;1044.2064031336088;1079.773247618009;18334;18337.541561461072;18506.722399164293;18603.627927441106;18794.906025470333;52351.73824131007;52362;54798;55781.442185579246;56726.7163664179;72614.27385726075;73311.63344183245;76415;77863;78171.60914195393;6952.791416257521;7962.163972782774;8009.118996910219;8173.509589424653;8390.07709152005;9319.86020209684;11861.822072668745;12534.373281335715;12720;12733.172343797623;9788.85316720264;10937.453127343442;11010.779784404427;11058.723531911766;11249.550017999189;8119.863219149206;8574.356923230658;8875.28997680171;10236.33463824849;11295.887041129483;10011.39817629176;11304.660860174208;11305.43472826339;11864.644060678194;11902.880971190178;8309.792255193426;8335.749927502186;8346.207110324509;8425.70510032146;8493.617787199468;10363.378197307884;11418.31490110596;11632.127590430582;11634;11716.179867409199 02:41:30 INFO - PROCESS | 1670 | |3;mootools.html;1439.4242303078768;1615.2973456546404;1827.762390889185;1928.2576208159862;1993.710911917771;7992.320652744509;8558.614862331195;8770.166834150754;8978.147076027775;8984.775380615489;8743.912560874389;8745.475271483716;8851.734447966555;8871.911280887189;8874.334424918141;2442.743511931244;2468.3829042739317;2470.5305991861487;2472.221250306148;2477.492114116602;2414.9034038638433;2447.68180136582;2461.6307553866973;2496.500699860026;2499.250224932522;2394.563262042769;2398.268528098929;2398.2805158452393;2446.0338166424253;2465.100238412975;2444.340028192396;2460.188137914493;2484.2050543825985;2495.238952119603;2495.413577809213;2233.6984507091497;2403.42317843718;2426.211481268595;2431.6960379952507;2440.280117365364;8898.555072246427;8972.147645973622;9010.59452324647;9025.909740902573;9029.864552031724;5381.488758567969;5528.502434780889;5547.583931205136;5553.500184983371;5575.609707320489;3522.771019883725;3587.6232995535356;3610.6028336882923;3624.2207925295925;3650.762700424464;840.4243093480924;911.4394647291867;1029.3000759483548;1047.7537778622018;1048.3762161513887;11905.404729763564;11917.463714132884;12189.939050304693;12192.634220973296;12234.694132646666;4831.758412079453;4875.658703890765;4893.828715994923;4903.092927808367;4939.407271127416;10207.948960255151;10319;10336.224783141295;10346.844797328045;10362.637307694195;5506.614536982453;5538.307711536058;5538.6122971391615;5541.556675466034;5549.445055494418;4426.3581780641625;4817.710937343771;4904.8038078477175;4986.227134794112;5020.447750747415;4053.966238609136;4091.0603480337254;4136.276151673505;4138.089620283533;4153.1070819773895;1134.7843909657304;1375.0443441807847;1380.565121986571;1386.7573174694387;1396.9022168448314;848.9575521223915;885.9335549833788;956.0964888180603;959.5442164971722;973.9951300243595;5907.586468947218;5972.343042265347;6022.728977196037;6030.758769649165;6216.502679785606;382.7952045655561;460.7327749905047;461.09395038749017;461.84759029520177;462.6992454904339;3895.24042811649;3994.921371229752;4001.4598029265676;4014.578469260744;4039.7980100994832;10585.259031867849;10971.561137554569;11012.39431831257;11018.228723989243;11065.557377704801;10404.011618896193;10754.516046777913;10763.56945722178;10788.975047370488;10869.347839129678;6744.494162937799;8125.349972002344;8441.451305665112;8469.74590762278;8572.099929507436;5795.536357091507;5826.242588463472;5861.384554621874;5924.733386997509;5940.079287710412;8921.955390223007;9705.9029409705;9749.415035097916;9757.073078057858;9796.21630269591;9125.908740912506;9434.198093162158;9441.858372124287;9554.808903821746;10016.549255283675;6790.898136527857;7179.174394944648;7347.70609175627;7427;7442.181360050282;8097.959510202411;8107.716229931924;8173.5504547250475;8174.591270436574;8185.5497947611075;7118.430525557839;7133.607651579213;7213.71145154188;7287.744928927674;7341.265873412616;7189.209186989531;7192.100987376578;7207.207207207098;7262.818429539304;7284.307990740871;6893.827654308682;6938.098047253825;6941.965290173517;6947.062146610143;7018.473614478832;4694.835680751294;4725.527447255247;4739.928901066556;4848.587870031087;4854.563089321978;4782.928256076231;4810.903781924272;4821.710697358029;4834.105690447279;4881.829135980223 02:41:30 INFO - PROCESS | 1670 | |4;prototype.html;318.42047473598063;327.3600111781467;332.78867918871515;333.3250168408973;333.4248421472961;6562.687462507506;6664.466842652589;6694.096296999904;6778.89831652525;6857.0742949701835;6731.091302674137;6736.663166841663;6757.864842703143;6761.459083273338;6772.796816095512;4348.847790327339;4449.710768800026;4648.209804333272;4957.702537847741;5002.974985125087;4968.453470118284;4986.601071914256;4990.725510096943;4998.350214472113;4998.475464983178;4913.434954980187;4923.113839508905;4930.802767889298;4938.604911607063;4943.505649435046;4601.723896566184;4780.928286075712;4910.993246384501;4923.55687988081;4926.5812405945535;3379.5099710541836;4000.6199411055904;4038.1318016626565;4072.1878763950053;4074.0425999890003;6379.968100159516;6427.260865000538;6439.8068057958335;6442.516811239129;6486.805395838132;5642.605017648766;5713.085906254979;5715.428257229254;5724.856878578027;5741.655500669973;7107.182673992505;7121.92878071223;7134;7147.535410198372;7173.210946795848;4437.134758722051;4602.470715867685;4603.677742558022;4606.157536985199;4609.585137337655;7590.506617069928;7647;7650.770476885702;7680.19357967411;7681.155072941972;4689.085628302517;4692.648051396159;4703.412073490814;4712.31671407644;4715.221988371949;4769.284607308931;4789.209780386197;4803.207470767285;4806.567408933143;4809.639277054235;5703.258576385043;5717.913596416667;5728.541716662657;5742.397048309867;5763.049096899049;6945.236024037453;7209.6034718091005;7233;7269.091363579552;7352.411807055422;1634.182908545727;1638.033560199488;1646.9258883350278;1655.8095818980971;1657.651893102438;976.3507267667064;1023.2785885950387;1030.0677886512717;1043.4782608695652;1045.0959919669583;6673.733050677918;6781.423578995742;6836.794896153124;6868.313168683092;6877;7559.773206803805;7605.429592780563;7605.847883042308;7642.8089297768;7643.1974642662835;6797.218319893177;6821.897671534931;6822.283660215705;6825.419839313714;6841.281665425158;3822.7324087313623;3959.1883663848844;4003.619656132721;4027.395890616431;4032.6169013943627;7856.567888766059;7908.920910790934;7909.6836126555445;7918.326942209861;7941.841163176704;6281.654509001957;7831.765047048598;7860.960695196487;7878.369730421668;7889;7864.41016923733;7869.724559640385;7873.606319683993;7893.526388416599;7904.13054564009;7332.633368331669;7490.70037198506;7560.58416787082;7583.9620801895635;7587.772366829004;7376.520526165782;7426.851462970602;7429.182789893215;7432.145303290191;7486;7827.943227664193;7852.60736963161;7852.803679908048;7862.174471680391;7884.448088633741;3835.7890316032885;4639.907201856012;4692.389989301506;4692.765361731968;4697.875912518274;6255.999040153553;7221.386182174364;7489.513181643176;7494.538564979776;7615.238476152341;7207.351338379571;7260.419166466776;7279.745208917662;7292.927070729437;7331.230220826949;5857.648541087548;6425.036244563353;7233.819154521179;7238.674259658434;7294.708211671474;6469.77355792545;6475.449586785176;7046.647667616701;7059.435245180271;7091.149062112373;6590.978398348264;6593.241777195684;6934.133233345832;7284;7284.817879553054;6875.346842049998;7032.753853615099;7133.643317834191;7139.643017849191;7160.248173941661 02:41:30 INFO - PROCESS | 1670 | |5;yui.html;582.7872826418356;682.3244987462414;704.4329314901504;721.0554174032019;758.4539131825086;13171.275579843104;14700.558983230494;14758.704825903476;14778.926105369472;14899.404023839033;13333.399997000133;13344.332783360818;13358;13362.064655474122;13412.329383530809;712.8930660400943;785.8467598818204;913.3834661603424;936.7143021378477;939.3236869453982;883.690708252113;883.9204471597554;908.9000209976897;917.3670167584379;918.091089821079;979.6962941488126;980.1129977370491;980.4117529482289;980.715592478184;983.1839573154265;938.8028514012066;950.2113246005798;967.4485543240356;975.1613612293422;978.2516374973151;700.1563116444672;706.5937086175418;720.9942130727658;729.2379463460696;731.9861991142253;14502.854971450255;14620.342084606113;14653.120812751162;14667.48663796762;14781;12625.242485450903;12650.81023784644;12803.48786048557;12867.678308042374;12872.227666339955;9848.162906152915;9958.850617240747;10068.647597334131;10243.539040743184;10391.220658450571;3301.9339613207603;3488.919386243492;3518.876839310612;3576.3920133577353;3729.869454569104;78067;78793;78885.60557197235;79760;80862;9524.904750952403;9537.523123843781;9746.853797193047;9783.902160978441;9825.852612210823;21946.012429440714;22047.889760551414;22108.88945555294;22113.778862211493;22113.889430552743;7130.786076417716;7212.567245965259;7252.709891604277;7266.237045110187;7319.74380896666;4040.7768346084454;4133.359329303963;4138.710290279651;4156.729812562174;4183.937240941388;3431.0907609483506;3500.7199424046016;3508.8947331580093;3511.297740451869;3515.70116540097;615.3753939751156;618.4650277510011;623.557274335218;626.858956734731;627.7112528237062;516.3855012535071;538.0154317598863;596.5555661032558;599.7061439894508;609.9024156135085;7709.691612335556;7994;8104.230098140667;8180.6318715657935;8274.089850116603;511.4476365525224;518.9247559103984;526.8709166254292;568.7497501099502;590.094205394717;3789.8484060637816;3802.7147963902817;3815.4276858471453;3915.334393153114;3927.626875446828;85284;85637;85795.14204857996;88638;88667.55666221627;75516.86724699134;75768.24231757723;75846.86229706559;76300;76349;61846.69076654588;62705.74588508204;64312.678436607515;66910.66178676399;67362.31594210184;7620.657070431955;7684.4236682250175;7748.225177482207;7887.250711182429;8071.51570905724;44439;44607.776961114985;45400.77299613481;46186;47562.52437475581;63196.05205921823;63603.36396635974;65787;65855.68288634297;69004.65497672479;8751.824963500821;10811.35131892089;10821.837672434762;10998.945005274922;11353.716157096138;11648.405307503514;11837;11907.16649834538;11913.52345906154;12401.813972790596;11142.331460112418;11206.607768728056;11289.887101128883;11307.547698091985;11637.650870473899;11044.668659940215;11070.28043177191;11132.666020019089;11226.214165008698;11575.13186511032;10972;11451.48468318911;11510;11532.423378831192;11560.710982225512;9597;9632.807343853225;9656.468894210604;9967.202623789934;10144.087032167141;8399.454035487674;8642.672006951632;9393.577289022147;9733.172680322252;9758.80482390334 02:41:30 INFO - PROCESS | 1670 | __end_tp_report 02:41:30 INFO - PROCESS | 1670 | __start_cc_report 02:41:30 INFO - PROCESS | 1670 | _x_x_mozilla_cycle_collect,415 02:41:30 INFO - PROCESS | 1670 | __end_cc_report 02:41:30 INFO - PROCESS | 1670 | __startTimestamp1465119690649__endTimestamp 02:41:30 INFO - PROCESS | 1670 | 02:41:30 INFO - PROCESS | 1670 | ------- Summary: start ------- 02:41:30 INFO - PROCESS | 1670 | Number of tests: 6 02:41:30 INFO - PROCESS | 1670 | 02:41:30 INFO - PROCESS | 1670 | [#0] dojo.html Cycles:165 Average:5625.48 Median:5759.94 stddev:643.51 (11.2%) stddev-sans-first:621.56 02:41:30 INFO - PROCESS | 1670 | Values: 3409.7 3440.9 3497.2 3502.4 3503.6 5052.6 5287.8 5694.3 5699.1 5726.1 4594.8 4609.4 5676.8 5708.4 5733.9 5099.6 5148.9 5246.8 5275.7 5312.7 5013.2 5200.2 5212.5 5217.9 5224.8 5125.4 5186.5 5187.8 5188.3 5193.5 5149.4 5204.5 5213.4 5218.2 5226.9 5066.8 5156.4 5159.8 5162.2 5215.0 5408.7 5420.2 5426.8 5540.8 5603.3 5307.6 6181.9 6221.2 6231.8 6233.9 6230.1 6271.1 6272.9 6278.5 6289.4 5632.9 5734.7 5758.7 5785.4 5789.2 6220.3 6241.3 6248.3 6256.4 6271.2 6098.2 6098.5 6114.4 6119.5 6134.4 5352.9 6142.1 6231.1 6234.7 6299.5 6086.0 6141.7 6145.4 6163.8 6167.1 5072.6 6173.8 6182.5 6185.7 6186.1 4617.1 4843.4 4909.7 4910.5 4935.0 4319.0 4348.8 4357.5 4361.2 4423.9 5590.1 5630.5 5688.2 5694.6 5730.7 6138.3 6221.2 6258.1 6264.1 6270.4 5738.3 5745.3 5757.5 5759.9 5778.1 5284.2 5315.9 5378.2 5404.7 5407.2 6172.5 6228.8 6240.4 6250.2 6252.7 6201.6 6216.6 6227.4 6231.3 6255.9 6110.6 6227.6 6248.6 6255.0 6260.2 6039.7 6081.8 6097.3 6104.7 6134.1 4632.6 6172.4 6176.5 6177.4 6203.1 4976.4 5013.3 5410.4 5530.8 5871.7 5012.9 5978.8 6019.9 6029.0 6031.9 5217.3 5775.4 5802.8 5805.8 5860.6 5027.0 5853.3 6093.1 6093.2 6104.2 5857.8 5867.9 5898.1 5927.0 5934.9 02:41:30 INFO - PROCESS | 1670 | 02:41:30 INFO - PROCESS | 1670 | [#1] ext.html Cycles:180 Average:68864.94 Median:39870.90 stddev:147493.79 (369.9%) stddev-sans-first:147818.26 02:41:30 INFO - PROCESS | 1670 | Values: 512.1 550.7 582.1 589.9 605.5 38872.8 39865.6 39889.4 40172.0 40191.6 40900.2 41599.2 41639.6 42133.8 42165.5 9556.3 9686.3 9813.2 9838.7 9900.6 9514.6 9728.2 9774.9 9798.5 9865.9 9728.3 9755.0 9778.1 9808.4 9881.0 9734.0 9747.5 9763.2 9766.1 9818.0 8643.7 9257.4 9699.6 9703.9 9713.7 39444.6 39532.8 39961.2 39965.0 40055.0 3145.4 3237.8 3298.1 3595.5 3627.2 27612.4 27761.4 27913.4 28059.7 28108.9 2766.4 2775.4 2788.6 2793.7 2806.4 433510.0 434971.0 435095.8 435155.0 436519.0 64611.0 64972.7 65057.7 65427.7 65629.0 66549.3 66726.0 70793.6 72967.0 73077.0 17516.4 17584.5 17611.6 17737.0 17754.5 12685.2 12981.0 13019.5 13043.5 13048.4 20038.6 20050.6 20269.0 20303.9 20315.4 8247.9 8426.0 8439.7 8498.0 8538.7 963.0 1023.7 1023.9 1058.7 1188.5 30346.4 30575.8 30760.8 31008.5 31065.4 1114.6 1134.0 1134.3 1221.0 1294.5 14020.4 15174.6 15586.6 16090.2 16163.7 508258.0 638790.0 639087.8 651177.0 660517.0 53070.0 63636.4 63913.0 64177.0 64211.7 1476.6 1503.4 1507.8 1509.3 1509.4 29857.8 30601.5 30610.5 30683.0 30762.0 47627.5 56502.7 57105.7 57139.4 57392.1 578478.0 578529.0 580829.0 583360.0 589400.0 27861.4 28244.4 28246.0 28564.7 28712.0 47776.5 48442.0 48513.3 49423.3 49672.8 31707.0 39488.0 39524.2 39876.2 40604.8 30930.5 32154.7 39134.5 39170.6 39242.8 33038.7 37729.8 38649.8 39063.0 39137.8 30774.0 32772.0 32918.8 32933.5 33090.8 24387.9 24710.5 24876.9 24972.4 25207.7 02:41:30 INFO - PROCESS | 1670 | 02:41:30 INFO - PROCESS | 1670 | [#2] jquery.html Cycles:180 Average:34230.22 Median:2304.33 stddev:100243.33 (4350.2%) stddev-sans-first:100492.25 02:41:30 INFO - PROCESS | 1670 | Values: 341.7 396.7 397.6 407.7 412.8 18882.8 19001.7 21089.3 21094.4 21124.4 20670.1 21074.3 21149.2 21197.6 21328.5 5664.6 5787.5 6042.8 6055.2 6067.5 5888.4 5951.3 5985.6 5985.6 6004.6 5823.6 5888.1 6055.7 6062.5 6075.1 6013.4 6055.2 6056.2 6060.1 6067.0 5811.8 5907.6 5953.9 5994.5 6005.2 13602.9 13950.4 14301.6 25719.9 26407.6 1968.7 2015.2 2144.2 2238.5 2370.1 9722.3 9850.0 9914.2 9934.9 10128.4 1645.3 1685.1 1705.8 1729.7 1753.4 88867.1 99897.5 102259.0 103725.0 104669.0 11488.0 11698.9 11769.0 11776.9 11933.0 14662.9 14830.9 14888.9 14917.6 14924.9 8844.2 8854.7 8856.4 8863.7 8936.2 8998.8 9022.9 9025.0 9041.5 9051.9 3840.6 4656.4 4668.7 4700.1 4719.2 1717.9 1931.5 1986.4 2026.9 2046.4 906.8 1005.4 1056.9 1064.9 1092.1 15958.4 20235.5 20779.8 20983.8 21156.4 959.9 987.5 997.9 1003.5 1004.7 10650.4 11046.4 11050.1 11061.9 11086.5 596080.0 601455.0 602606.0 609810.0 610877.0 95113.6 121123.0 124706.0 128606.0 129237.0 1005.2 1008.3 1041.1 1044.2 1079.8 18334.0 18337.5 18506.7 18603.6 18794.9 52351.7 52362.0 54798.0 55781.4 56726.7 72614.3 73311.6 76415.0 77863.0 78171.6 6952.8 7962.2 8009.1 8173.5 8390.1 9319.9 11861.8 12534.4 12720.0 12733.2 9788.9 10937.5 11010.8 11058.7 11249.6 8119.9 8574.4 8875.3 10236.3 11295.9 10011.4 11304.7 11305.4 11864.6 11902.9 8309.8 8335.7 8346.2 8425.7 8493.6 10363.4 11418.3 11632.1 11634.0 11716.2 02:41:30 INFO - PROCESS | 1670 | 02:41:30 INFO - PROCESS | 1670 | [#3] mootools.html Cycles:180 Average:5724.74 Median:4865.11 stddev:3207.16 (65.9%) stddev-sans-first:3199.98 02:41:30 INFO - PROCESS | 1670 | Values: 1439.4 1615.3 1827.8 1928.3 1993.7 7992.3 8558.6 8770.2 8978.1 8984.8 8743.9 8745.5 8851.7 8871.9 8874.3 2442.7 2468.4 2470.5 2472.2 2477.5 2414.9 2447.7 2461.6 2496.5 2499.3 2394.6 2398.3 2398.3 2446.0 2465.1 2444.3 2460.2 2484.2 2495.2 2495.4 2233.7 2403.4 2426.2 2431.7 2440.3 8898.6 8972.1 9010.6 9025.9 9029.9 5381.5 5528.5 5547.6 5553.5 5575.6 3522.8 3587.6 3610.6 3624.2 3650.8 840.4 911.4 1029.3 1047.8 1048.4 11905.4 11917.5 12189.9 12192.6 12234.7 4831.8 4875.7 4893.8 4903.1 4939.4 10207.9 10319.0 10336.2 10346.8 10362.6 5506.6 5538.3 5538.6 5541.6 5549.4 4426.4 4817.7 4904.8 4986.2 5020.4 4054.0 4091.1 4136.3 4138.1 4153.1 1134.8 1375.0 1380.6 1386.8 1396.9 849.0 885.9 956.1 959.5 974.0 5907.6 5972.3 6022.7 6030.8 6216.5 382.8 460.7 461.1 461.8 462.7 3895.2 3994.9 4001.5 4014.6 4039.8 10585.3 10971.6 11012.4 11018.2 11065.6 10404.0 10754.5 10763.6 10789.0 10869.3 6744.5 8125.3 8441.5 8469.7 8572.1 5795.5 5826.2 5861.4 5924.7 5940.1 8922.0 9705.9 9749.4 9757.1 9796.2 9125.9 9434.2 9441.9 9554.8 10016.5 6790.9 7179.2 7347.7 7427.0 7442.2 8098.0 8107.7 8173.6 8174.6 8185.5 7118.4 7133.6 7213.7 7287.7 7341.3 7189.2 7192.1 7207.2 7262.8 7284.3 6893.8 6938.1 6942.0 6947.1 7018.5 4694.8 4725.5 4739.9 4848.6 4854.6 4782.9 4810.9 4821.7 4834.1 4881.8 02:41:30 INFO - PROCESS | 1670 | 02:41:30 INFO - PROCESS | 1670 | [#4] prototype.html Cycles:180 Average:5881.26 Median:6712.59 stddev:1924.81 (28.7%) stddev-sans-first:1884.38 02:41:30 INFO - PROCESS | 1670 | Values: 318.4 327.4 332.8 333.3 333.4 6562.7 6664.5 6694.1 6778.9 6857.1 6731.1 6736.7 6757.9 6761.5 6772.8 4348.8 4449.7 4648.2 4957.7 5003.0 4968.5 4986.6 4990.7 4998.4 4998.5 4913.4 4923.1 4930.8 4938.6 4943.5 4601.7 4780.9 4911.0 4923.6 4926.6 3379.5 4000.6 4038.1 4072.2 4074.0 6380.0 6427.3 6439.8 6442.5 6486.8 5642.6 5713.1 5715.4 5724.9 5741.7 7107.2 7121.9 7134.0 7147.5 7173.2 4437.1 4602.5 4603.7 4606.2 4609.6 7590.5 7647.0 7650.8 7680.2 7681.2 4689.1 4692.6 4703.4 4712.3 4715.2 4769.3 4789.2 4803.2 4806.6 4809.6 5703.3 5717.9 5728.5 5742.4 5763.0 6945.2 7209.6 7233.0 7269.1 7352.4 1634.2 1638.0 1646.9 1655.8 1657.7 976.4 1023.3 1030.1 1043.5 1045.1 6673.7 6781.4 6836.8 6868.3 6877.0 7559.8 7605.4 7605.8 7642.8 7643.2 6797.2 6821.9 6822.3 6825.4 6841.3 3822.7 3959.2 4003.6 4027.4 4032.6 7856.6 7908.9 7909.7 7918.3 7941.8 6281.7 7831.8 7861.0 7878.4 7889.0 7864.4 7869.7 7873.6 7893.5 7904.1 7332.6 7490.7 7560.6 7584.0 7587.8 7376.5 7426.9 7429.2 7432.1 7486.0 7827.9 7852.6 7852.8 7862.2 7884.4 3835.8 4639.9 4692.4 4692.8 4697.9 6256.0 7221.4 7489.5 7494.5 7615.2 7207.4 7260.4 7279.7 7292.9 7331.2 5857.6 6425.0 7233.8 7238.7 7294.7 6469.8 6475.4 7046.6 7059.4 7091.1 6591.0 6593.2 6934.1 7284.0 7284.8 6875.3 7032.8 7133.6 7139.6 7160.2 02:41:30 INFO - PROCESS | 1670 | 02:41:30 INFO - PROCESS | 1670 | [#5] yui.html Cycles:180 Average:17644.95 Median:31232.58 stddev:24478.03 (78.4%) stddev-sans-first:24513.17 02:41:30 INFO - PROCESS | 1670 | Values: 582.8 682.3 704.4 721.1 758.5 13171.3 14700.6 14758.7 14778.9 14899.4 13333.4 13344.3 13358.0 13362.1 13412.3 712.9 785.8 913.4 936.7 939.3 883.7 883.9 908.9 917.4 918.1 979.7 980.1 980.4 980.7 983.2 938.8 950.2 967.4 975.2 978.3 700.2 706.6 721.0 729.2 732.0 14502.9 14620.3 14653.1 14667.5 14781.0 12625.2 12650.8 12803.5 12867.7 12872.2 9848.2 9958.9 10068.6 10243.5 10391.2 3301.9 3488.9 3518.9 3576.4 3729.9 78067.0 78793.0 78885.6 79760.0 80862.0 9524.9 9537.5 9746.9 9783.9 9825.9 21946.0 22047.9 22108.9 22113.8 22113.9 7130.8 7212.6 7252.7 7266.2 7319.7 4040.8 4133.4 4138.7 4156.7 4183.9 3431.1 3500.7 3508.9 3511.3 3515.7 615.4 618.5 623.6 626.9 627.7 516.4 538.0 596.6 599.7 609.9 7709.7 7994.0 8104.2 8180.6 8274.1 511.4 518.9 526.9 568.7 590.1 3789.8 3802.7 3815.4 3915.3 3927.6 85284.0 85637.0 85795.1 88638.0 88667.6 75516.9 75768.2 75846.9 76300.0 76349.0 61846.7 62705.7 64312.7 66910.7 67362.3 7620.7 7684.4 7748.2 7887.3 8071.5 44439.0 44607.8 45400.8 46186.0 47562.5 63196.1 63603.4 65787.0 65855.7 69004.7 8751.8 10811.4 10821.8 10998.9 11353.7 11648.4 11837.0 11907.2 11913.5 12401.8 11142.3 11206.6 11289.9 11307.5 11637.7 11044.7 11070.3 11132.7 11226.2 11575.1 10972.0 11451.5 11510.0 11532.4 11560.7 9597.0 9632.8 9656.5 9967.2 10144.1 8399.5 8642.7 9393.6 9733.2 9758.8 02:41:30 INFO - PROCESS | 1670 | -------- Summary: end -------- 02:41:30 INFO - PROCESS | 1670 | 02:41:31 INFO - TEST-INFO | 1670: exit 0 02:41:31 INFO - TEST-OK | dromaeo_css | took 1111780ms 02:41:31 INFO - TEST-START | kraken 02:41:31 INFO - operating with platform_type : mac_ 02:41:31 INFO - Initialising browser for kraken test... 02:41:31 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/tmpckn5WW/profile http://localhost:49276/getInfo.html) 02:41:34 INFO - PROCESS | 1678 | __metrics Screen width/height:1600/1200 02:41:34 INFO - PROCESS | 1678 | colorDepth:24 02:41:34 INFO - PROCESS | 1678 | Browser inner width/height: 1024/689 02:41:34 INFO - PROCESS | 1678 | __metrics 02:41:34 ERROR - PROCESS | 1678 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2940: TypeError: this.tabs is undefined 02:41:34 INFO - TEST-INFO | 1678: exit 0 02:41:34 INFO - Browser initialized. 02:41:34 INFO - Running cycle 1/1 for kraken test... 02:41:34 INFO - TEST-INFO | started process 1682 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/tmpckn5WW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:41:46 INFO - PROCESS | 1682 | RSS: Main: 221044736 02:41:46 INFO - PROCESS | 1682 | 02:41:49 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection) 02:41:50 INFO - PROCESS | 1682 | RSS: Main: 232841216 02:41:50 INFO - PROCESS | 1682 | 02:41:53 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-dft) 02:41:53 INFO - PROCESS | 1682 | RSS: Main: 241655808 02:41:53 INFO - PROCESS | 1682 | 02:41:57 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-fft) 02:41:57 INFO - PROCESS | 1682 | RSS: Main: 245522432 02:41:57 INFO - PROCESS | 1682 | 02:41:59 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator) 02:42:00 INFO - PROCESS | 1682 | RSS: Main: 245661696 02:42:00 INFO - PROCESS | 1682 | 02:42:02 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:42:02 INFO - PROCESS | 1682 | RSS: Main: 245899264 02:42:02 INFO - PROCESS | 1682 | 02:42:05 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom) 02:42:06 INFO - PROCESS | 1682 | RSS: Main: 247853056 02:42:06 INFO - PROCESS | 1682 | 02:42:09 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate) 02:42:10 INFO - PROCESS | 1682 | RSS: Main: 247902208 02:42:10 INFO - PROCESS | 1682 | 02:42:13 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial) 02:42:14 INFO - PROCESS | 1682 | RSS: Main: 247947264 02:42:14 INFO - PROCESS | 1682 | 02:42:15 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:42:16 INFO - PROCESS | 1682 | RSS: Main: 247840768 02:42:16 INFO - PROCESS | 1682 | 02:42:18 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:42:18 INFO - PROCESS | 1682 | RSS: Main: 247328768 02:42:18 INFO - PROCESS | 1682 | 02:42:20 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:42:21 INFO - PROCESS | 1682 | RSS: Main: 247537664 02:42:21 INFO - PROCESS | 1682 | 02:42:24 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:42:25 INFO - PROCESS | 1682 | RSS: Main: 247492608 02:42:25 INFO - PROCESS | 1682 | 02:42:28 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:42:28 INFO - PROCESS | 1682 | RSS: Main: 247472128 02:42:28 INFO - PROCESS | 1682 | 02:42:30 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49276/tests/kraken/driver.html?testName=ai-astar) 02:42:30 INFO - PROCESS | 1682 | RSS: Main: 249491456 02:42:30 INFO - PROCESS | 1682 | 02:42:30 INFO - PROCESS | 1682 | __start_tp_report 02:42:30 INFO - PROCESS | 1682 | _x_x_mozilla_page_load 02:42:30 INFO - PROCESS | 1682 | _x_x_mozilla_page_load_details 02:42:30 INFO - PROCESS | 1682 | |i|pagename|runs| 02:42:30 INFO - PROCESS | 1682 | |0;ai-astar;132;178;141;116;119;115;106;117;115;115 02:42:30 INFO - PROCESS | 1682 | |1;audio-beat-detection;153;134;160;173;154;170;143;152;156;119 02:42:30 INFO - PROCESS | 1682 | |2;audio-dft;198;198;194;200;197;189;196;192;197;196 02:42:30 INFO - PROCESS | 1682 | |3;audio-fft;62;75;72;144;68;83;84;63;127;47 02:42:30 INFO - PROCESS | 1682 | |4;audio-oscillator;78;80;132;75;79;79;121;82;80;120 02:42:30 INFO - PROCESS | 1682 | |5;imaging-gaussian-blur;89;86;116;131;83;132;136;87;89;86 02:42:30 INFO - PROCESS | 1682 | |6;imaging-darkroom;120;101;154;137;103;108;154;119;95;94 02:42:30 INFO - PROCESS | 1682 | |7;imaging-desaturate;91;129;98;150;75;145;105;138;150;113 02:42:30 INFO - PROCESS | 1682 | |8;json-parse-financial;59;67;65;64;64;55;67;102;53;62 02:42:30 INFO - PROCESS | 1682 | |9;json-stringify-tinderbox;65;65;93;77;64;89;63;56;63;64 02:42:30 INFO - PROCESS | 1682 | |10;stanford-crypto-aes;81;100;72;88;96;108;70;84;75;97 02:42:30 INFO - PROCESS | 1682 | |11;stanford-crypto-ccm;128;128;129;128;138;153;154;154;192;169 02:42:30 INFO - PROCESS | 1682 | |12;stanford-crypto-pbkdf2;167;170;169;168;225;223;171;167;168;167 02:42:30 INFO - PROCESS | 1682 | |13;stanford-crypto-sha256-iterative;66;70;92;67;67;67;100;98;104;87 02:42:30 INFO - PROCESS | 1682 | __end_tp_report 02:42:30 INFO - PROCESS | 1682 | __start_cc_report 02:42:30 INFO - PROCESS | 1682 | _x_x_mozilla_cycle_collect,1116 02:42:30 INFO - PROCESS | 1682 | __end_cc_report 02:42:30 INFO - PROCESS | 1682 | __startTimestamp1465119750834__endTimestamp 02:42:30 INFO - PROCESS | 1682 | 02:42:30 INFO - PROCESS | 1682 | ------- Summary: start ------- 02:42:30 INFO - PROCESS | 1682 | Number of tests: 14 02:42:30 INFO - PROCESS | 1682 | 02:42:30 INFO - PROCESS | 1682 | [#0] ai-astar Cycles:10 Average:125.40 Median:118.00 stddev:20.96 (17.8%) stddev-sans-first:22.10 02:42:30 INFO - PROCESS | 1682 | Values: 132.0 178.0 141.0 116.0 119.0 115.0 106.0 117.0 115.0 115.0 02:42:30 INFO - PROCESS | 1682 | 02:42:30 INFO - PROCESS | 1682 | [#1] audio-beat-detection Cycles:10 Average:151.40 Median:155.00 stddev:16.13 (10.4%) stddev-sans-first:17.09 02:42:30 INFO - PROCESS | 1682 | Values: 153.0 134.0 160.0 173.0 154.0 170.0 143.0 152.0 156.0 119.0 02:42:30 INFO - PROCESS | 1682 | 02:42:30 INFO - PROCESS | 1682 | [#2] audio-dft Cycles:10 Average:195.70 Median:197.00 stddev:3.23 (1.6%) stddev-sans-first:3.32 02:42:30 INFO - PROCESS | 1682 | Values: 198.0 198.0 194.0 200.0 197.0 189.0 196.0 192.0 197.0 196.0 02:42:30 INFO - PROCESS | 1682 | 02:42:30 INFO - PROCESS | 1682 | [#3] audio-fft Cycles:10 Average:82.50 Median:70.00 stddev:30.19 (43.1%) stddev-sans-first:31.10 02:42:30 INFO - PROCESS | 1682 | Values: 62.0 75.0 72.0 144.0 68.0 83.0 84.0 63.0 127.0 47.0 02:42:30 INFO - PROCESS | 1682 | 02:42:30 INFO - PROCESS | 1682 | [#4] audio-oscillator Cycles:10 Average:92.60 Median:79.00 stddev:22.19 (28.1%) stddev-sans-first:22.90 02:42:30 INFO - PROCESS | 1682 | Values: 78.0 80.0 132.0 75.0 79.0 79.0 121.0 82.0 80.0 120.0 02:42:30 INFO - PROCESS | 1682 | 02:42:30 INFO - PROCESS | 1682 | [#5] imaging-gaussian-blur Cycles:10 Average:103.50 Median:86.00 stddev:22.38 (26.0%) stddev-sans-first:23.11 02:42:30 INFO - PROCESS | 1682 | Values: 89.0 86.0 116.0 131.0 83.0 132.0 136.0 87.0 89.0 86.0 02:42:30 INFO - PROCESS | 1682 | 02:42:30 INFO - PROCESS | 1682 | [#6] imaging-darkroom Cycles:10 Average:118.50 Median:145.50 stddev:22.79 (15.7%) stddev-sans-first:24.17 02:42:30 INFO - PROCESS | 1682 | Values: 120.0 101.0 154.0 137.0 103.0 108.0 154.0 119.0 95.0 94.0 02:42:30 INFO - PROCESS | 1682 | 02:42:30 INFO - PROCESS | 1682 | [#7] imaging-desaturate Cycles:10 Average:119.40 Median:150.00 stddev:26.77 (17.8%) stddev-sans-first:26.35 02:42:30 INFO - PROCESS | 1682 | Values: 91.0 129.0 98.0 150.0 75.0 145.0 105.0 138.0 150.0 113.0 02:42:30 INFO - PROCESS | 1682 | 02:42:30 INFO - PROCESS | 1682 | [#8] json-parse-financial Cycles:10 Average:65.80 Median:64.00 stddev:13.59 (21.2%) stddev-sans-first:14.19 02:42:30 INFO - PROCESS | 1682 | Values: 59.0 67.0 65.0 64.0 64.0 55.0 67.0 102.0 53.0 62.0 02:42:30 INFO - PROCESS | 1682 | 02:42:30 INFO - PROCESS | 1682 | [#9] json-stringify-tinderbox Cycles:10 Average:69.90 Median:65.00 stddev:12.27 (18.9%) stddev-sans-first:12.89 02:42:30 INFO - PROCESS | 1682 | Values: 65.0 65.0 93.0 77.0 64.0 89.0 63.0 56.0 63.0 64.0 02:42:30 INFO - PROCESS | 1682 | 02:42:30 INFO - PROCESS | 1682 | [#10] stanford-crypto-aes Cycles:10 Average:87.10 Median:82.50 stddev:12.89 (15.6%) stddev-sans-first:13.48 02:42:30 INFO - PROCESS | 1682 | Values: 81.0 100.0 72.0 88.0 96.0 108.0 70.0 84.0 75.0 97.0 02:42:30 INFO - PROCESS | 1682 | 02:42:30 INFO - PROCESS | 1682 | [#11] stanford-crypto-ccm Cycles:10 Average:147.30 Median:153.50 stddev:21.42 (14.0%) stddev-sans-first:21.55 02:42:30 INFO - PROCESS | 1682 | Values: 128.0 128.0 129.0 128.0 138.0 153.0 154.0 154.0 192.0 169.0 02:42:30 INFO - PROCESS | 1682 | 02:42:30 INFO - PROCESS | 1682 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.50 Median:169.50 stddev:23.50 (13.9%) stddev-sans-first:24.48 02:42:30 INFO - PROCESS | 1682 | Values: 167.0 170.0 169.0 168.0 225.0 223.0 171.0 167.0 168.0 167.0 02:42:30 INFO - PROCESS | 1682 | 02:42:30 INFO - PROCESS | 1682 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:81.80 Median:68.50 stddev:15.86 (23.2%) stddev-sans-first:15.76 02:42:30 INFO - PROCESS | 1682 | Values: 66.0 70.0 92.0 67.0 67.0 67.0 100.0 98.0 104.0 87.0 02:42:30 INFO - PROCESS | 1682 | -------- Summary: end -------- 02:42:30 INFO - PROCESS | 1682 | 02:42:31 INFO - TEST-INFO | 1682: exit 0 02:42:31 INFO - TEST-OK | kraken | took 60298ms 02:42:31 INFO - SUITE-END | took 1172s 02:42:31 INFO - Completed test suite (00:19:32) 02:42:31 INFO - javascript benchmark 02:42:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3409.7272218222543, 3440.8795692150748, 3497.2131270464133, 3502.4221003534417, 3503.597086335071, 5052.646314757963, 5287.788488460457, 5694.34515030771, 5699.088145896658, 5726.112452569848, 4594.770261486929, 4609.354690343346, 5676.772929082842, 5708.372079071299, 5733.942660573393, 5099.643024988252, 5148.897022059557, 5246.7638956247065, 5275.657082289658, 5312.73436328184, 5013.222950442687, 5200.193969934666, 5212.5308722214995, 5217.86955326118, 5224.8316948466345, 5125.410577783566, 5186.507281808223, 5187.844364669066, 5188.299579556769, 5193.45468725784, 5149.407818100892, 5204.479552044803, 5213.400458947212, 5218.217267409881, 5226.8693282667855, 5066.847994560169, 5156.355455568054, 5159.793608255665, 5162.22566615007, 5215.0, 5408.729563521808, 5420.186971954199, 5426.75579598919, 5540.778368865241, 5603.299587551556, 5307.575393968474, 6181.949068658339, 6221.160143380631, 6231.813045608594, 6233.906491402633, 6230.06549017651, 6271.059341098826, 6272.905906411407, 6278.4663303619245, 6289.37106289372, 5632.859178520488, 5734.741936612862, 5758.654480731169, 5785.392533783935, 5789.218455508537, 6220.284667263322, 6241.251049874044, 6248.31268560458, 6256.405641464054, 6271.216097987752, 6098.237720284965, 6098.481629061579, 6114.419130182626, 6119.510439164945, 6134.355892631299, 5352.892942141136, 6142.099766648211, 6231.065340199007, 6234.688265586702, 6299.5275354348605, 6085.9695701521205, 6141.662208578482, 6145.354737752561, 6163.7842675506145, 6167.105769663373, 5072.594192464594, 6173.81478555644, 6182.474489668339, 6185.721642526097, 6186.072089186658, 4617.145828021827, 4843.370361852936, 4909.729964851967, 4910.508949105061, 4935.0, 4318.985038515946, 4348.782560871944, 4357.455318085239, 4361.214981303332, 4423.889402764892, 5590.105583106683, 5630.465105815023, 5688.23208866806, 5694.629849059798, 5730.684812335278, 6138.32709113608, 6221.222347206599, 6258.061290806416, 6264.123022776815, 6270.435660790551, 5738.311402631627, 5745.253117094751, 5757.539396848242, 5759.942400576025, 5778.104393818965, 5284.180951952453, 5315.867103322448, 5378.2470454136455, 5404.702741349263, 5407.188921661704, 6172.475339596095, 6228.844278893064, 6240.438360547481, 6250.218722659668, 6252.687365631791, 6201.596896201732, 6216.564840461305, 6227.439530442282, 6231.314555398993, 6255.874882502416, 6110.572259941761, 6227.626342419469, 6248.562600617913, 6255.0, 6260.154879091265, 6039.667818270037, 6081.756729730938, 6097.32929377777, 6104.664243466652, 6134.14122022926, 4632.606228470618, 6172.4136207060255, 6176.474999624902, 6177.382261773787, 6203.069539569101, 4976.353074100344, 5013.323201367769, 5410.404855465974, 5530.834074977757, 5871.735771890189, 5012.924806127984, 5978.8206353809455, 6019.909701354571, 6029.035354343284, 6031.879362412815, 5217.34783152106, 5775.422457754191, 5802.796902108406, 5805.82582522525, 5860.589758717019, 5027.0, 5853.326867410302, 6093.146959425595, 6093.177421048279, 6104.20645316106, 5857.765689372377, 5867.882642347044, 5898.1152827076285, 5926.970365148147, 5934.881302374015], "value": 5588.716871561253, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [512.0808149371879, 550.7163810637521, 582.1006544888147, 589.9204455845803, 605.5397897597827, 38872.80563597182, 39865.60134398655, 39889.40165897507, 40172.0, 40191.59808401915, 40900.18199636013, 41599.16801663965, 41639.58360416395, 42133.7893310533, 42165.52420665277, 9556.331056826042, 9686.273529485281, 9813.214942804558, 9838.704838854846, 9900.554475048604, 9514.571844267024, 9728.17310528606, 9774.90225097747, 9798.510074496282, 9865.90134098657, 9728.270379721549, 9754.975727548612, 9778.11996920277, 9808.362456440344, 9880.950595247014, 9734.0, 9747.512624368788, 9763.21894248462, 9766.121049105544, 9818.0, 8643.654253829902, 9257.351985361027, 9699.563519641633, 9703.90296097037, 9713.70858874235, 39444.60555394438, 39532.81401557958, 39961.20077598461, 39965.0, 40055.0, 3145.3709258148233, 3237.7895436796534, 3298.0765385692043, 3595.460680897886, 3627.1838836261895, 27612.447751045067, 27761.444771104667, 27913.44173116526, 28059.71940280612, 28108.859455702794, 2766.3775650478683, 2775.4449110178043, 2788.553831386989, 2793.748562629353, 2806.4246829400126, 433510.0, 434971.0, 435095.82452087855, 435155.0, 436519.0, 64611.0, 64972.67513662496, 65057.67471162614, 65427.69144617081, 65629.0, 66549.33450665431, 66725.99911001242, 70793.64603177054, 72967.0, 73077.0, 17516.386926457766, 17584.472465826046, 17611.55971100707, 17737.0, 17754.467365979042, 12685.23888566689, 12981.026423018311, 13019.544315948897, 13043.543475978295, 13048.412821423059, 20038.59922801536, 20050.59898802016, 20268.98655067241, 20303.88328641909, 20315.390538283875, 8247.917520824714, 8425.95787021061, 8439.746807595782, 8497.957510212409, 8538.70114545988, 962.9855552166723, 1023.7338292044122, 1023.8515415264745, 1058.7300238439302, 1188.5424111717052, 30346.393072138435, 30575.847120764698, 30760.846195768878, 31008.53487197694, 31065.378692426024, 1114.648885601033, 1134.0304040045808, 1134.325076579434, 1220.9633710988685, 1294.4563283420798, 14020.369083391271, 15174.620634484005, 15586.610334741496, 16090.195490225677, 16163.67672646564, 508258.0, 638790.0, 639087.8045609836, 651177.0, 660517.0, 53070.0, 63636.36363636304, 63913.0, 64177.0, 64211.71576568442, 1476.6160798192548, 1503.3535579700833, 1507.811523559555, 1509.2906334022991, 1509.4037855047195, 29857.805687772245, 30601.48538309224, 30610.540841886945, 30683.0, 30762.0, 47627.52372476231, 56502.71748641231, 57105.714471427375, 57139.42860571341, 57392.1391179141, 578478.0, 578529.0, 580829.0, 583360.0, 589400.0, 27861.44277114406, 28244.43511129807, 28246.0, 28564.714352856205, 28712.0, 47776.52223477721, 48442.0, 48513.27230091622, 49423.25865112098, 49672.751636241584, 31707.0, 39488.0, 39524.2095158101, 39876.2055707488, 40604.79697601493, 30930.536041958938, 32154.678453216104, 39134.45626034299, 39170.608293916695, 39242.80378598089, 33038.66961330356, 37729.81135094307, 38649.8405047849, 39063.02342441462, 39137.80431097826, 30774.0, 32772.0, 32918.835405822814, 32933.50599741054, 33090.834545827114, 24387.878060610292, 24710.50578988374, 24876.875615621808, 24972.37569060788, 25207.74792252054], "value": 20334.26125330917, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.7249114462857, 396.70643723644514, 397.5686380277399, 407.7145997801538, 412.76678676547766, 18882.811171888276, 19001.714974275397, 21089.26187583435, 21094.367168984918, 21124.366269011934, 20670.06984685689, 21074.262400815973, 21149.154033838666, 21197.576048479023, 21328.4667883303, 5664.603477756558, 5787.536997040237, 6042.788502402417, 6055.182550355689, 6067.544934129935, 5888.411158884121, 5951.345352011275, 5985.551083668721, 5985.5810093293485, 6004.609700369484, 5823.563232757539, 5888.087346461305, 6055.666938318391, 6062.4543791058795, 6075.058365953262, 6013.398660133996, 6055.2430946131735, 6056.21269235001, 6060.060690592965, 6066.96966515169, 5811.767529298823, 5907.556933230025, 5953.851153721148, 5994.460498555151, 6005.249343832021, 13602.863971360259, 13950.441982320695, 14301.57095287143, 25719.871400642878, 26407.603885941728, 1968.7342208801772, 2015.1536354731047, 2144.1959265275523, 2238.5187184755355, 2370.13490076121, 9722.270829687732, 9849.950750246275, 9914.206863450907, 9934.90065099347, 10128.392296462162, 1645.3254165791968, 1685.1321569391773, 1705.8464738173625, 1729.723244280909, 1753.4213709475841, 88867.11132888717, 99897.50051249698, 102259.0, 103724.96275037229, 104669.0, 11488.023518000897, 11698.941505292589, 11769.0, 11776.94111529454, 11933.0, 14662.926685366505, 14830.887683423787, 14888.925555372154, 14917.627059323386, 14924.850751492346, 8844.248238899765, 8854.690085847093, 8856.38005339633, 8863.689770858118, 8936.195742383217, 8998.820023599492, 9022.86465703015, 9024.954875225581, 9041.457512549268, 9051.864222036675, 3840.635139661728, 4656.3946686930485, 4668.719876807402, 4700.106979673851, 4719.1977363847545, 1717.9141042948052, 1931.5460866696314, 1986.3742920979846, 2026.8885211313225, 2046.3758553641283, 906.8413027720254, 1005.3816902604846, 1056.9365838049741, 1064.9467526623637, 1092.0662833277593, 15958.361665533248, 20235.494112647008, 20779.761904761905, 20983.79016209849, 21156.365309040753, 959.9424034557949, 987.5013118375175, 997.9023074618008, 1003.518311210623, 1004.7437903334603, 10650.414227217423, 11046.447677616086, 11050.065311250493, 11061.944690276498, 11086.478919548901, 596080.0, 601455.0, 602606.0, 609810.0, 610877.0, 95113.57329640062, 121123.0, 124706.0, 128606.0, 129237.0, 1005.2460654509118, 1008.3193844155165, 1041.068243921686, 1044.2064031336088, 1079.773247618009, 18334.0, 18337.541561461072, 18506.722399164293, 18603.627927441106, 18794.906025470333, 52351.73824131007, 52362.0, 54798.0, 55781.442185579246, 56726.7163664179, 72614.27385726075, 73311.63344183245, 76415.0, 77863.0, 78171.60914195393, 6952.791416257521, 7962.163972782774, 8009.118996910219, 8173.509589424653, 8390.07709152005, 9319.86020209684, 11861.822072668745, 12534.373281335715, 12720.0, 12733.172343797623, 9788.85316720264, 10937.453127343442, 11010.779784404427, 11058.723531911766, 11249.550017999189, 8119.863219149206, 8574.356923230658, 8875.28997680171, 10236.33463824849, 11295.887041129483, 10011.39817629176, 11304.660860174208, 11305.43472826339, 11864.644060678194, 11902.880971190178, 8309.792255193426, 8335.749927502186, 8346.207110324509, 8425.70510032146, 8493.617787199468, 10363.378197307884, 11418.31490110596, 11632.127590430582, 11634.0, 11716.179867409199], "value": 9462.543012315347, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1439.4242303078768, 1615.2973456546404, 1827.762390889185, 1928.2576208159862, 1993.710911917771, 7992.320652744509, 8558.614862331195, 8770.166834150754, 8978.147076027775, 8984.775380615489, 8743.912560874389, 8745.475271483716, 8851.734447966555, 8871.911280887189, 8874.334424918141, 2442.743511931244, 2468.3829042739317, 2470.5305991861487, 2472.221250306148, 2477.492114116602, 2414.9034038638433, 2447.68180136582, 2461.6307553866973, 2496.500699860026, 2499.250224932522, 2394.563262042769, 2398.268528098929, 2398.2805158452393, 2446.0338166424253, 2465.100238412975, 2444.340028192396, 2460.188137914493, 2484.2050543825985, 2495.238952119603, 2495.413577809213, 2233.6984507091497, 2403.42317843718, 2426.211481268595, 2431.6960379952507, 2440.280117365364, 8898.555072246427, 8972.147645973622, 9010.59452324647, 9025.909740902573, 9029.864552031724, 5381.488758567969, 5528.502434780889, 5547.583931205136, 5553.500184983371, 5575.609707320489, 3522.771019883725, 3587.6232995535356, 3610.6028336882923, 3624.2207925295925, 3650.762700424464, 840.4243093480924, 911.4394647291867, 1029.3000759483548, 1047.7537778622018, 1048.3762161513887, 11905.404729763564, 11917.463714132884, 12189.939050304693, 12192.634220973296, 12234.694132646666, 4831.758412079453, 4875.658703890765, 4893.828715994923, 4903.092927808367, 4939.407271127416, 10207.948960255151, 10319.0, 10336.224783141295, 10346.844797328045, 10362.637307694195, 5506.614536982453, 5538.307711536058, 5538.6122971391615, 5541.556675466034, 5549.445055494418, 4426.3581780641625, 4817.710937343771, 4904.8038078477175, 4986.227134794112, 5020.447750747415, 4053.966238609136, 4091.0603480337254, 4136.276151673505, 4138.089620283533, 4153.1070819773895, 1134.7843909657304, 1375.0443441807847, 1380.565121986571, 1386.7573174694387, 1396.9022168448314, 848.9575521223915, 885.9335549833788, 956.0964888180603, 959.5442164971722, 973.9951300243595, 5907.586468947218, 5972.343042265347, 6022.728977196037, 6030.758769649165, 6216.502679785606, 382.7952045655561, 460.7327749905047, 461.09395038749017, 461.84759029520177, 462.6992454904339, 3895.24042811649, 3994.921371229752, 4001.4598029265676, 4014.578469260744, 4039.7980100994832, 10585.259031867849, 10971.561137554569, 11012.39431831257, 11018.228723989243, 11065.557377704801, 10404.011618896193, 10754.516046777913, 10763.56945722178, 10788.975047370488, 10869.347839129678, 6744.494162937799, 8125.349972002344, 8441.451305665112, 8469.74590762278, 8572.099929507436, 5795.536357091507, 5826.242588463472, 5861.384554621874, 5924.733386997509, 5940.079287710412, 8921.955390223007, 9705.9029409705, 9749.415035097916, 9757.073078057858, 9796.21630269591, 9125.908740912506, 9434.198093162158, 9441.858372124287, 9554.808903821746, 10016.549255283675, 6790.898136527857, 7179.174394944648, 7347.70609175627, 7427.0, 7442.181360050282, 8097.959510202411, 8107.716229931924, 8173.5504547250475, 8174.591270436574, 8185.5497947611075, 7118.430525557839, 7133.607651579213, 7213.71145154188, 7287.744928927674, 7341.265873412616, 7189.209186989531, 7192.100987376578, 7207.207207207098, 7262.818429539304, 7284.307990740871, 6893.827654308682, 6938.098047253825, 6941.965290173517, 6947.062146610143, 7018.473614478832, 4694.835680751294, 4725.527447255247, 4739.928901066556, 4848.587870031087, 4854.563089321978, 4782.928256076231, 4810.903781924272, 4821.710697358029, 4834.105690447279, 4881.829135980223], "value": 4540.266520774766, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [318.42047473598063, 327.3600111781467, 332.78867918871515, 333.3250168408973, 333.4248421472961, 6562.687462507506, 6664.466842652589, 6694.096296999904, 6778.89831652525, 6857.0742949701835, 6731.091302674137, 6736.663166841663, 6757.864842703143, 6761.459083273338, 6772.796816095512, 4348.847790327339, 4449.710768800026, 4648.209804333272, 4957.702537847741, 5002.974985125087, 4968.453470118284, 4986.601071914256, 4990.725510096943, 4998.350214472113, 4998.475464983178, 4913.434954980187, 4923.113839508905, 4930.802767889298, 4938.604911607063, 4943.505649435046, 4601.723896566184, 4780.928286075712, 4910.993246384501, 4923.55687988081, 4926.5812405945535, 3379.5099710541836, 4000.6199411055904, 4038.1318016626565, 4072.1878763950053, 4074.0425999890003, 6379.968100159516, 6427.260865000538, 6439.8068057958335, 6442.516811239129, 6486.805395838132, 5642.605017648766, 5713.085906254979, 5715.428257229254, 5724.856878578027, 5741.655500669973, 7107.182673992505, 7121.92878071223, 7134.0, 7147.535410198372, 7173.210946795848, 4437.134758722051, 4602.470715867685, 4603.677742558022, 4606.157536985199, 4609.585137337655, 7590.506617069928, 7647.0, 7650.770476885702, 7680.19357967411, 7681.155072941972, 4689.085628302517, 4692.648051396159, 4703.412073490814, 4712.31671407644, 4715.221988371949, 4769.284607308931, 4789.209780386197, 4803.207470767285, 4806.567408933143, 4809.639277054235, 5703.258576385043, 5717.913596416667, 5728.541716662657, 5742.397048309867, 5763.049096899049, 6945.236024037453, 7209.6034718091005, 7233.0, 7269.091363579552, 7352.411807055422, 1634.182908545727, 1638.033560199488, 1646.9258883350278, 1655.8095818980971, 1657.651893102438, 976.3507267667064, 1023.2785885950387, 1030.0677886512717, 1043.4782608695652, 1045.0959919669583, 6673.733050677918, 6781.423578995742, 6836.794896153124, 6868.313168683092, 6877.0, 7559.773206803805, 7605.429592780563, 7605.847883042308, 7642.8089297768, 7643.1974642662835, 6797.218319893177, 6821.897671534931, 6822.283660215705, 6825.419839313714, 6841.281665425158, 3822.7324087313623, 3959.1883663848844, 4003.619656132721, 4027.395890616431, 4032.6169013943627, 7856.567888766059, 7908.920910790934, 7909.6836126555445, 7918.326942209861, 7941.841163176704, 6281.654509001957, 7831.765047048598, 7860.960695196487, 7878.369730421668, 7889.0, 7864.41016923733, 7869.724559640385, 7873.606319683993, 7893.526388416599, 7904.13054564009, 7332.633368331669, 7490.70037198506, 7560.58416787082, 7583.9620801895635, 7587.772366829004, 7376.520526165782, 7426.851462970602, 7429.182789893215, 7432.145303290191, 7486.0, 7827.943227664193, 7852.60736963161, 7852.803679908048, 7862.174471680391, 7884.448088633741, 3835.7890316032885, 4639.907201856012, 4692.389989301506, 4692.765361731968, 4697.875912518274, 6255.999040153553, 7221.386182174364, 7489.513181643176, 7494.538564979776, 7615.238476152341, 7207.351338379571, 7260.419166466776, 7279.745208917662, 7292.927070729437, 7331.230220826949, 5857.648541087548, 6425.036244563353, 7233.819154521179, 7238.674259658434, 7294.708211671474, 6469.77355792545, 6475.449586785176, 7046.647667616701, 7059.435245180271, 7091.149062112373, 6590.978398348264, 6593.241777195684, 6934.133233345832, 7284.0, 7284.817879553054, 6875.346842049998, 7032.753853615099, 7133.643317834191, 7139.643017849191, 7160.248173941661], "value": 5229.325670025574, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [582.7872826418356, 682.3244987462414, 704.4329314901504, 721.0554174032019, 758.4539131825086, 13171.275579843104, 14700.558983230494, 14758.704825903476, 14778.926105369472, 14899.404023839033, 13333.399997000133, 13344.332783360818, 13358.0, 13362.064655474122, 13412.329383530809, 712.8930660400943, 785.8467598818204, 913.3834661603424, 936.7143021378477, 939.3236869453982, 883.690708252113, 883.9204471597554, 908.9000209976897, 917.3670167584379, 918.091089821079, 979.6962941488126, 980.1129977370491, 980.4117529482289, 980.715592478184, 983.1839573154265, 938.8028514012066, 950.2113246005798, 967.4485543240356, 975.1613612293422, 978.2516374973151, 700.1563116444672, 706.5937086175418, 720.9942130727658, 729.2379463460696, 731.9861991142253, 14502.854971450255, 14620.342084606113, 14653.120812751162, 14667.48663796762, 14781.0, 12625.242485450903, 12650.81023784644, 12803.48786048557, 12867.678308042374, 12872.227666339955, 9848.162906152915, 9958.850617240747, 10068.647597334131, 10243.539040743184, 10391.220658450571, 3301.9339613207603, 3488.919386243492, 3518.876839310612, 3576.3920133577353, 3729.869454569104, 78067.0, 78793.0, 78885.60557197235, 79760.0, 80862.0, 9524.904750952403, 9537.523123843781, 9746.853797193047, 9783.902160978441, 9825.852612210823, 21946.012429440714, 22047.889760551414, 22108.88945555294, 22113.778862211493, 22113.889430552743, 7130.786076417716, 7212.567245965259, 7252.709891604277, 7266.237045110187, 7319.74380896666, 4040.7768346084454, 4133.359329303963, 4138.710290279651, 4156.729812562174, 4183.937240941388, 3431.0907609483506, 3500.7199424046016, 3508.8947331580093, 3511.297740451869, 3515.70116540097, 615.3753939751156, 618.4650277510011, 623.557274335218, 626.858956734731, 627.7112528237062, 516.3855012535071, 538.0154317598863, 596.5555661032558, 599.7061439894508, 609.9024156135085, 7709.691612335556, 7994.0, 8104.230098140667, 8180.6318715657935, 8274.089850116603, 511.4476365525224, 518.9247559103984, 526.8709166254292, 568.7497501099502, 590.094205394717, 3789.8484060637816, 3802.7147963902817, 3815.4276858471453, 3915.334393153114, 3927.626875446828, 85284.0, 85637.0, 85795.14204857996, 88638.0, 88667.55666221627, 75516.86724699134, 75768.24231757723, 75846.86229706559, 76300.0, 76349.0, 61846.69076654588, 62705.74588508204, 64312.678436607515, 66910.66178676399, 67362.31594210184, 7620.657070431955, 7684.4236682250175, 7748.225177482207, 7887.250711182429, 8071.51570905724, 44439.0, 44607.776961114985, 45400.77299613481, 46186.0, 47562.52437475581, 63196.05205921823, 63603.36396635974, 65787.0, 65855.68288634297, 69004.65497672479, 8751.824963500821, 10811.35131892089, 10821.837672434762, 10998.945005274922, 11353.716157096138, 11648.405307503514, 11837.0, 11907.16649834538, 11913.52345906154, 12401.813972790596, 11142.331460112418, 11206.607768728056, 11289.887101128883, 11307.547698091985, 11637.650870473899, 11044.668659940215, 11070.28043177191, 11132.666020019089, 11226.214165008698, 11575.13186511032, 10972.0, 11451.48468318911, 11510.0, 11532.423378831192, 11560.710982225512, 9597.0, 9632.807343853225, 9656.468894210604, 9967.202623789934, 10144.087032167141, 8399.454035487674, 8642.672006951632, 9393.577289022147, 9733.172680322252, 9758.80482390334], "value": 6773.558309514345, "unit": "score"}], "name": "dromaeo_css", "value": 7464.289815398664, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 178.0, 141.0, 116.0, 119.0, 115.0, 106.0, 117.0, 115.0, 115.0], "value": 125.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 134.0, 160.0, 173.0, 154.0, 170.0, 143.0, 152.0, 156.0, 119.0], "value": 151.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 198.0, 194.0, 200.0, 197.0, 189.0, 196.0, 192.0, 197.0, 196.0], "value": 195.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 75.0, 72.0, 144.0, 68.0, 83.0, 84.0, 63.0, 127.0, 47.0], "value": 82.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 80.0, 132.0, 75.0, 79.0, 79.0, 121.0, 82.0, 80.0, 120.0], "value": 92.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 116.0, 131.0, 83.0, 132.0, 136.0, 87.0, 89.0, 86.0], "value": 103.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 101.0, 154.0, 137.0, 103.0, 108.0, 154.0, 119.0, 95.0, 94.0], "value": 118.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 129.0, 98.0, 150.0, 75.0, 145.0, 105.0, 138.0, 150.0, 113.0], "value": 119.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 67.0, 65.0, 64.0, 64.0, 55.0, 67.0, 102.0, 53.0, 62.0], "value": 65.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 93.0, 77.0, 64.0, 89.0, 63.0, 56.0, 63.0, 64.0], "value": 69.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 100.0, 72.0, 88.0, 96.0, 108.0, 70.0, 84.0, 75.0, 97.0], "value": 87.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 128.0, 129.0, 128.0, 138.0, 153.0, 154.0, 154.0, 192.0, 169.0], "value": 147.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 170.0, 169.0, 168.0, 225.0, 223.0, 171.0, 167.0, 168.0, 167.0], "value": 179.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 70.0, 92.0, 67.0, 67.0, 67.0, 100.0, 98.0, 104.0, 87.0], "value": 81.8, "unit": "score"}], "name": "kraken", "value": 1620.3999999999999, "alertThreshold": 2.0}]} 02:42:32 INFO - Return code: 0 02:42:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 02:42:32 ERROR - # TBPL SUCCESS # 02:42:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:42:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:42:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:42:32 INFO - 2016-06-05 02:42:32.160 screenresolution[1687:10898] starting screenresolution argv=screenresolution get 02:42:32 INFO - 2016-06-05 02:42:32.163 screenresolution[1687:10898] Display 0: 1600x1200x32@60 02:42:32 INFO - 2016-06-05 02:42:32.169 screenresolution[1688:10901] starting screenresolution argv=screenresolution list 02:42:32 INFO - Available Modes on Display 0 02:42:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:42:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:42:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:42:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:42:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:42:32 INFO - Intel Iris: 02:42:32 INFO - Chipset Model: Intel Iris 02:42:32 INFO - Type: GPU 02:42:32 INFO - Bus: Built-In 02:42:32 INFO - VRAM (Dynamic, Max): 1536 MB 02:42:32 INFO - Vendor: Intel (0x8086) 02:42:32 INFO - Device ID: 0x0a2e 02:42:32 INFO - Revision ID: 0x0009 02:42:32 INFO - Displays: 02:42:32 INFO - Display: 02:42:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:42:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:42:32 INFO - Display Serial Number: 1600x1200 60 02:42:32 INFO - Main Display: Yes 02:42:32 INFO - Mirror: Off 02:42:32 INFO - Online: Yes 02:42:32 INFO - Rotation: Supported 02:42:32 INFO - Return code: 0 02:42:32 INFO - Running post-action listener: _resource_record_post_action 02:42:32 INFO - Running post-run listener: _resource_record_post_run 02:42:33 INFO - Total resource usage - Wall time: 1205s; CPU: 53.0%; Read bytes: 32521728; Write bytes: 654505984; Read time: 324; Write time: 2597 02:42:33 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 345940480; Write bytes: 345761280; Read time: 26436; Write time: 636 02:42:33 INFO - run-tests - Wall time: 1175s; CPU: 53.0%; Read bytes: 31600128; Write bytes: 298783232; Read time: 311; Write time: 1927 02:42:33 INFO - Running post-run listener: _upload_blobber_files 02:42:33 INFO - Blob upload gear active. 02:42:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:42:33 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 02:42:33 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'] 02:42:33 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 02:42:33 INFO - (blobuploader) - INFO - Open directory for files ... 02:42:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:42:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:42:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:42:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:42:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:42:34 INFO - (blobuploader) - INFO - Done attempting. 02:42:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:42:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:42:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:42:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:42:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:42:35 INFO - (blobuploader) - INFO - Done attempting. 02:42:35 INFO - (blobuploader) - INFO - Iteration through files over. 02:42:35 INFO - Return code: 0 02:42:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:42:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:42:35 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/696a80f46fc1110f132f4e46395e967d4f99c698969526b6a3c2e69fa13a2a70a90e955968d3a91ae53d521d716934306f9dd8e74cf0879c650f7423b373e5bd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b84ab8e0d62b9105ad040f78b9eac1813a91d19f02d1d04c34b6b5d927ed22dbbbbad0e6132fa8170e6c9d3ad2ec0b6d9de85bd612637ee8047aad6b3ab64c6"} 02:42:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:42:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:42:35 INFO - Contents: 02:42:35 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/696a80f46fc1110f132f4e46395e967d4f99c698969526b6a3c2e69fa13a2a70a90e955968d3a91ae53d521d716934306f9dd8e74cf0879c650f7423b373e5bd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b84ab8e0d62b9105ad040f78b9eac1813a91d19f02d1d04c34b6b5d927ed22dbbbbad0e6132fa8170e6c9d3ad2ec0b6d9de85bd612637ee8047aad6b3ab64c6"} 02:42:35 INFO - Running post-run listener: copy_logs_to_upload_dir 02:42:35 INFO - Copying logs to upload dir... 02:42:35 INFO - mkdir: /builds/slave/test/build/upload/logs 02:42:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1342.914191 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 22 secs) (at 2016-06-05 02:42:35.925970) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-05 02:42:35.929988) ========= 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.kDYxWdEQIS/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.wd83DXyy4M/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/696a80f46fc1110f132f4e46395e967d4f99c698969526b6a3c2e69fa13a2a70a90e955968d3a91ae53d521d716934306f9dd8e74cf0879c650f7423b373e5bd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b84ab8e0d62b9105ad040f78b9eac1813a91d19f02d1d04c34b6b5d927ed22dbbbbad0e6132fa8170e6c9d3ad2ec0b6d9de85bd612637ee8047aad6b3ab64c6"} build_url:https://queue.taskcluster.net/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011394 build_url: 'https://queue.taskcluster.net/v1/task/OfAthp28Sei8LvR_DV7_Mw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/696a80f46fc1110f132f4e46395e967d4f99c698969526b6a3c2e69fa13a2a70a90e955968d3a91ae53d521d716934306f9dd8e74cf0879c650f7423b373e5bd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b84ab8e0d62b9105ad040f78b9eac1813a91d19f02d1d04c34b6b5d927ed22dbbbbad0e6132fa8170e6c9d3ad2ec0b6d9de85bd612637ee8047aad6b3ab64c6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-05 02:42:35.961868) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-05 02:42:35.962238) ========= 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.kDYxWdEQIS/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.wd83DXyy4M/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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.005173 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-05 02:42:36.014146) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-05 02:42:36.014855) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-05 02:42:36.015612) ========= ========= Total master_lag: 0.22 =========