builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0194 starttime: 1462882805.67 results: success (0) buildid: 20160510032818 builduid: 9d0f53cdd81b4716a7c35eace595bcd2 revision: 4c5959b81fd5b61fe854f2d2e0743afa66f9aa07 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-10 05:20:05.673877) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-10 05:20:05.674279) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-10 05:20:05.674557) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-10 05:20:05.694113) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 05:20:05.694385) ========= 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.Lr3QeYqt96/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.r0OMV16Lp8/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-10 05:20:05-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 165M=0s 2016-05-10 05:20:05 (165 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.116439 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 05:20:05.828142) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 05:20:05.828459) ========= 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.Lr3QeYqt96/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.r0OMV16Lp8/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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.025978 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 05:20:05.877338) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-10 05:20:05.878067) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 4c5959b81fd5b61fe854f2d2e0743afa66f9aa07 --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 4c5959b81fd5b61fe854f2d2e0743afa66f9aa07 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Lr3QeYqt96/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.r0OMV16Lp8/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-10 05:20:05,947 truncating revision to first 12 chars 2016-05-10 05:20:05,947 Setting DEBUG logging. 2016-05-10 05:20:05,948 attempt 1/10 2016-05-10 05:20:05,948 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/4c5959b81fd5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-10 05:20:06,835 unpacking tar archive at: mozilla-aurora-4c5959b81fd5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.128388 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-10 05:20:07.023147) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-10 05:20:07.023462) ========= script_repo_revision: 4c5959b81fd5b61fe854f2d2e0743afa66f9aa07 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-10 05:20:07.023845) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-10 05:20:07.024104) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-10 05:20:07.038088) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-05-10 05:20:07.038351) ========= /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.Lr3QeYqt96/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.r0OMV16Lp8/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 05:20:07 INFO - MultiFileLogger online at 20160510 05:20:07 in /builds/slave/test 05:20:07 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 05:20:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:20:07 INFO - {'append_to_log': False, 05:20:07 INFO - 'base_work_dir': '/builds/slave/test', 05:20:07 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 05:20:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:20:07 INFO - 'branch': 'Mozilla-Aurora', 05:20:07 INFO - 'buildbot_json_path': 'buildprops.json', 05:20:07 INFO - 'config_files': ('talos/mac_config.py',), 05:20:07 INFO - 'default_actions': ('clobber', 05:20:07 INFO - 'read-buildbot-config', 05:20:07 INFO - 'download-and-extract', 05:20:07 INFO - 'populate-webroot', 05:20:07 INFO - 'create-virtualenv', 05:20:07 INFO - 'install', 05:20:07 INFO - 'run-tests'), 05:20:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:20:07 INFO - 'download_minidump_stackwalk': True, 05:20:07 INFO - 'download_symbols': 'ondemand', 05:20:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:20:07 INFO - 'tooltool.py': '/tools/tooltool.py', 05:20:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:20:07 INFO - '/tools/misc-python/virtualenv.py')}, 05:20:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:20:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:20:07 INFO - 'installer_path': 'installer.exe', 05:20:07 INFO - 'log_level': 'info', 05:20:07 INFO - 'log_name': 'talos', 05:20:07 INFO - 'log_to_console': True, 05:20:07 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:20:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:20:07 INFO - 'opt_config_files': (), 05:20:07 INFO - 'pip_index': False, 05:20:07 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:20:07 INFO - 'cmd': ('bash', 05:20:07 INFO - '-c', 05:20:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:20:07 INFO - 'enabled': True, 05:20:07 INFO - 'halt_on_failure': False, 05:20:07 INFO - 'name': 'check_screen_resolution'},), 05:20:07 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:20:07 INFO - 'cmd': ('bash', 05:20:07 INFO - '-c', 05:20:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:20:07 INFO - 'enabled': True, 05:20:07 INFO - 'halt_on_failure': False, 05:20:07 INFO - 'name': 'check_screen_resolution'},), 05:20:07 INFO - 'run_cmd_checks_enabled': True, 05:20:07 INFO - 'sps_profile': False, 05:20:07 INFO - 'sps_profile_interval': 0, 05:20:07 INFO - 'suite': 'dromaeojs-e10s', 05:20:07 INFO - 'system_bits': '32', 05:20:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:20:07 INFO - 'title': 't-yosemite-r7-0194', 05:20:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:20:07 INFO - 'use_talos_json': True, 05:20:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:20:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:20:07 INFO - 'work_dir': 'build'} 05:20:07 INFO - ##### 05:20:07 INFO - ##### Running clobber step. 05:20:07 INFO - ##### 05:20:07 INFO - Running pre-action listener: _resource_record_pre_action 05:20:07 INFO - Running main action method: clobber 05:20:07 INFO - rmtree: /builds/slave/test/build 05:20:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:20:09 INFO - Running post-action listener: _resource_record_post_action 05:20:09 INFO - ##### 05:20:09 INFO - ##### Running read-buildbot-config step. 05:20:09 INFO - ##### 05:20:09 INFO - Running pre-action listener: _resource_record_pre_action 05:20:09 INFO - Running main action method: read_buildbot_config 05:20:09 INFO - Using buildbot properties: 05:20:09 INFO - { 05:20:09 INFO - "project": "", 05:20:09 INFO - "product": "firefox", 05:20:09 INFO - "script_repo_revision": "production", 05:20:09 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 05:20:09 INFO - "repository": "", 05:20:09 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 05:20:09 INFO - "buildid": "20160510032818", 05:20:09 INFO - "pgo_build": "False", 05:20:09 INFO - "basedir": "/builds/slave/test", 05:20:09 INFO - "buildnumber": 6, 05:20:09 INFO - "slavename": "t-yosemite-r7-0194", 05:20:09 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 05:20:09 INFO - "platform": "yosemite_r7", 05:20:09 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 05:20:09 INFO - "branch": "mozilla-aurora", 05:20:09 INFO - "repo_path": "releases/mozilla-aurora", 05:20:09 INFO - "revision": "4c5959b81fd5b61fe854f2d2e0743afa66f9aa07", 05:20:09 INFO - "stage_platform": "macosx64", 05:20:09 INFO - "builduid": "9d0f53cdd81b4716a7c35eace595bcd2", 05:20:09 INFO - "slavebuilddir": "test" 05:20:09 INFO - } 05:20:09 INFO - Found installer url https://queue.taskcluster.net/v1/task/df8c38IJRoyaYkFY3ZTFeA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 05:20:09 INFO - Running post-action listener: _resource_record_post_action 05:20:09 INFO - ##### 05:20:09 INFO - ##### Running download-and-extract step. 05:20:09 INFO - ##### 05:20:09 INFO - Running pre-action listener: _resource_record_pre_action 05:20:09 INFO - Running main action method: download_and_extract 05:20:09 INFO - mkdir: /builds/slave/test/build/tests 05:20:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:09 INFO - https://queue.taskcluster.net/v1/task/df8c38IJRoyaYkFY3ZTFeA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:20:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df8c38IJRoyaYkFY3ZTFeA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 05:20:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df8c38IJRoyaYkFY3ZTFeA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 05:20:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df8c38IJRoyaYkFY3ZTFeA/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 05:20:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df8c38IJRoyaYkFY3ZTFeA/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 05:20:10 INFO - Downloaded 1199 bytes. 05:20:10 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 05:20:10 INFO - Using the following test package requirements: 05:20:10 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 05:20:10 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:20:10 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 05:20:10 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:20:10 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 05:20:10 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:20:10 INFO - u'jsshell-mac.zip'], 05:20:10 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:20:10 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 05:20:10 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 05:20:10 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:20:10 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 05:20:10 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:20:10 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 05:20:10 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:20:10 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 05:20:10 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:20:10 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 05:20:10 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 05:20:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:10 INFO - https://queue.taskcluster.net/v1/task/df8c38IJRoyaYkFY3ZTFeA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:20:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df8c38IJRoyaYkFY3ZTFeA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 05:20:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df8c38IJRoyaYkFY3ZTFeA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 05:20:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df8c38IJRoyaYkFY3ZTFeA/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 05:20:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df8c38IJRoyaYkFY3ZTFeA/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 05:20:11 INFO - Downloaded 18089258 bytes. 05:20:11 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'] 05:20:11 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 05:20:14 INFO - Return code: 0 05:20:14 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 05: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')]} 05:20:14 INFO - https://queue.taskcluster.net/v1/task/df8c38IJRoyaYkFY3ZTFeA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:20:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df8c38IJRoyaYkFY3ZTFeA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 05:20:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df8c38IJRoyaYkFY3ZTFeA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 05:20:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df8c38IJRoyaYkFY3ZTFeA/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 05:20:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df8c38IJRoyaYkFY3ZTFeA/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 05:20:16 INFO - Downloaded 18089258 bytes. 05:20:16 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'] 05:20:16 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 05:20:19 INFO - Return code: 0 05:20:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:19 INFO - https://queue.taskcluster.net/v1/task/df8c38IJRoyaYkFY3ZTFeA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:20:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df8c38IJRoyaYkFY3ZTFeA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 05:20:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df8c38IJRoyaYkFY3ZTFeA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 05:20:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df8c38IJRoyaYkFY3ZTFeA/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 05:20:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df8c38IJRoyaYkFY3ZTFeA/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 05:20:20 INFO - Downloaded 10840145 bytes. 05:20:20 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'] 05:20:20 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 05:20:20 INFO - Return code: 0 05:20:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:20 INFO - https://queue.taskcluster.net/v1/task/df8c38IJRoyaYkFY3ZTFeA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 05:20:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df8c38IJRoyaYkFY3ZTFeA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 05:20:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df8c38IJRoyaYkFY3ZTFeA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 05:20:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df8c38IJRoyaYkFY3ZTFeA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 05:20:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df8c38IJRoyaYkFY3ZTFeA/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 05:20:25 INFO - Downloaded 114089229 bytes. 05:20:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/df8c38IJRoyaYkFY3ZTFeA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 05:20:25 INFO - mkdir: /builds/slave/test/properties 05:20:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:20:25 INFO - Writing to file /builds/slave/test/properties/build_url 05:20:25 INFO - Contents: 05:20:25 INFO - build_url:https://queue.taskcluster.net/v1/task/df8c38IJRoyaYkFY3ZTFeA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 05:20:26 INFO - Running post-action listener: _resource_record_post_action 05:20:26 INFO - Running post-action listener: set_extra_try_arguments 05:20:26 INFO - ##### 05:20:26 INFO - ##### Running populate-webroot step. 05:20:26 INFO - ##### 05:20:26 INFO - Running pre-action listener: _resource_record_pre_action 05:20:26 INFO - Running main action method: populate_webroot 05:20:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:20:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:20:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:20:26 INFO - '--tppagecycles', 05:20:26 INFO - '7'], 05:20:26 INFO - 'tests': ['tsvgm']}}, 05:20:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:20:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:20:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:20:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:20:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:20:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:20:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:26 INFO - 'talos_options': ['--disable-e10s'], 05:20:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:20:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:20:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:26 INFO - 'talos_options': ['--disable-e10s'], 05:20:26 INFO - 'tests': ['damp', 'tps']}, 05:20:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:26 INFO - 'tests': ['damp', 'tps']}, 05:20:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:20:26 INFO - 'tests': ['dromaeo_dom']}, 05:20:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:20:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:20:26 INFO - 'tests': ['a11yr', 05:20:26 INFO - 'ts_paint', 05:20:26 INFO - 'tpaint', 05:20:26 INFO - 'sessionrestore', 05:20:26 INFO - 'sessionrestore_no_auto_restore', 05:20:26 INFO - 'tabpaint']}, 05:20:26 INFO - 'other-e10s': {'tests': ['a11yr', 05:20:26 INFO - 'ts_paint', 05:20:26 INFO - 'tpaint', 05:20:26 INFO - 'sessionrestore', 05:20:26 INFO - 'sessionrestore_no_auto_restore', 05:20:26 INFO - 'tabpaint']}, 05:20:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:20:26 INFO - 'ts_paint', 05:20:26 INFO - 'tpaint', 05:20:26 INFO - 'sessionrestore', 05:20:26 INFO - 'sessionrestore_no_auto_restore', 05:20:26 INFO - 'tabpaint']}, 05:20:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:20:26 INFO - 'ts_paint', 05:20:26 INFO - 'tpaint', 05:20:26 INFO - 'sessionrestore', 05:20:26 INFO - 'sessionrestore_no_auto_restore', 05:20:26 INFO - 'tabpaint']}, 05:20:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:20:26 INFO - 'tests': ['a11yr', 05:20:26 INFO - 'ts_paint', 05:20:26 INFO - 'tpaint', 05:20:26 INFO - 'sessionrestore', 05:20:26 INFO - 'sessionrestore_no_auto_restore', 05:20:26 INFO - 'tabpaint']}, 05:20:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:20:26 INFO - 'tests': ['a11yr', 05:20:26 INFO - 'ts_paint', 05:20:26 INFO - 'tpaint', 05:20:26 INFO - 'sessionrestore', 05:20:26 INFO - 'sessionrestore_no_auto_restore', 05:20:26 INFO - 'tabpaint']}, 05:20:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:20:26 INFO - 'tests': ['tsvgx', 05:20:26 INFO - 'tsvgr_opacity', 05:20:26 INFO - 'tart', 05:20:26 INFO - 'tscrollx', 05:20:26 INFO - 'cart']}, 05:20:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:20:26 INFO - 'tsvgr_opacity', 05:20:26 INFO - 'tart', 05:20:26 INFO - 'tscrollx', 05:20:26 INFO - 'cart']}, 05:20:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:26 INFO - 'talos_options': ['--disable-e10s'], 05:20:26 INFO - 'tests': ['tp5o']}, 05:20:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:26 INFO - 'tests': ['tp5o']}, 05:20:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:26 INFO - 'talos_options': ['--disable-e10s', 05:20:26 INFO - '--xperf_path', 05:20:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:20:26 INFO - 'tests': ['tp5n']}, 05:20:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:26 INFO - 'talos_options': ['--xperf_path', 05:20:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:20:26 INFO - 'tests': ['tp5n']}}, 05:20:26 INFO - 'talos.zip': {'path': '', 05:20:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:20:26 INFO - Running post-action listener: _resource_record_post_action 05:20:26 INFO - ##### 05:20:26 INFO - ##### Running create-virtualenv step. 05:20:26 INFO - ##### 05:20:26 INFO - Running pre-action listener: _resource_record_pre_action 05:20:26 INFO - Running main action method: create_virtualenv 05:20:26 INFO - Creating virtualenv /builds/slave/test/build/venv 05:20:26 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:20:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:20:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:20:26 INFO - Using real prefix '/tools/python27' 05:20:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:20:27 INFO - Installing distribute.............................................................................................................................................................................................done. 05:20:30 INFO - Installing pip.................done. 05:20:30 INFO - Return code: 0 05:20:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:20:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10285ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10286b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda01d5b090>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026fae00>, '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 0x102563db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026f5b20>, '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.Lr3QeYqt96/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.r0OMV16Lp8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 05:20:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:20:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:20:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Lr3QeYqt96/Render', 05:20:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:20:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:20:30 INFO - 'HOME': '/Users/cltbld', 05:20:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:20:30 INFO - 'LOGNAME': 'cltbld', 05:20:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:30 INFO - 'MOZ_NO_REMOTE': '1', 05:20:30 INFO - 'NO_EM_RESTART': '1', 05:20:30 INFO - 'PAGER': '/bin/cat', 05:20:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:20:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:30 INFO - 'PWD': '/builds/slave/test', 05:20:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:20:30 INFO - 'SHELL': '/bin/bash', 05:20:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r0OMV16Lp8/Listeners', 05:20:30 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 05:20:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:20:30 INFO - 'USER': 'cltbld', 05:20:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:20:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:20:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:30 INFO - 'XPC_FLAGS': '0x0', 05:20:30 INFO - 'XPC_SERVICE_NAME': '0', 05:20:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:20:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:30 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:20:30 INFO - Cleaning up... 05:20:30 INFO - Return code: 0 05:20:30 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:20:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10285ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10286b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda01d5b090>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026fae00>, '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 0x102563db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026f5b20>, '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.Lr3QeYqt96/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.r0OMV16Lp8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 05:20:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 05:20:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 05:20:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Lr3QeYqt96/Render', 05:20:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:20:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:20:30 INFO - 'HOME': '/Users/cltbld', 05:20:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:20:30 INFO - 'LOGNAME': 'cltbld', 05:20:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:30 INFO - 'MOZ_NO_REMOTE': '1', 05:20:30 INFO - 'NO_EM_RESTART': '1', 05:20:30 INFO - 'PAGER': '/bin/cat', 05:20:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:20:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:30 INFO - 'PWD': '/builds/slave/test', 05:20:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:20:30 INFO - 'SHELL': '/bin/bash', 05:20:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r0OMV16Lp8/Listeners', 05:20:30 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 05:20:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:20:30 INFO - 'USER': 'cltbld', 05:20:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:20:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:20:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:30 INFO - 'XPC_FLAGS': '0x0', 05:20:30 INFO - 'XPC_SERVICE_NAME': '0', 05:20:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:20:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:30 INFO - Downloading/unpacking psutil>=0.7.1 05:20:30 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 05:20:30 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 05:20:30 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 05:20:30 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 05:20:30 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 05:20:30 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 05:20:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:20:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:20:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:20:33 INFO - Installing collected packages: psutil 05:20:33 INFO - Running setup.py install for psutil 05:20:33 INFO - building 'psutil._psutil_osx' extension 05:20:33 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:20:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:20:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:20:34 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 05:20:34 INFO - building 'psutil._psutil_posix' extension 05:20:34 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 05:20:34 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:20:34 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:20:34 INFO - ^ 05:20:34 INFO - 1 warning generated. 05:20:34 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 05:20:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:20:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:20:34 INFO - Successfully installed psutil 05:20:34 INFO - Cleaning up... 05:20:34 INFO - Return code: 0 05:20:34 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:20:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:34 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 0x10285ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10286b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda01d5b090>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026fae00>, '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 0x102563db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026f5b20>, '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.Lr3QeYqt96/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.r0OMV16Lp8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 05:20:34 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 05:20:34 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 05:20:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Lr3QeYqt96/Render', 05:20:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:20:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:20:34 INFO - 'HOME': '/Users/cltbld', 05:20:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:20:34 INFO - 'LOGNAME': 'cltbld', 05:20:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:34 INFO - 'MOZ_NO_REMOTE': '1', 05:20:34 INFO - 'NO_EM_RESTART': '1', 05:20:34 INFO - 'PAGER': '/bin/cat', 05:20:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:20:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:34 INFO - 'PWD': '/builds/slave/test', 05:20:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:20:34 INFO - 'SHELL': '/bin/bash', 05:20:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r0OMV16Lp8/Listeners', 05:20:34 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 05:20:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:20:34 INFO - 'USER': 'cltbld', 05:20:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:20:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:20:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:34 INFO - 'XPC_FLAGS': '0x0', 05:20:34 INFO - 'XPC_SERVICE_NAME': '0', 05:20:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:20:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:35 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:20:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:37 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:20:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:20:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:20:37 INFO - Installing collected packages: mozsystemmonitor 05:20:37 INFO - Running setup.py install for mozsystemmonitor 05:20:37 INFO - Successfully installed mozsystemmonitor 05:20:37 INFO - Cleaning up... 05:20:37 INFO - Return code: 0 05:20:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:20:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:37 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 0x10285ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10286b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda01d5b090>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026fae00>, '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 0x102563db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026f5b20>, '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.Lr3QeYqt96/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.r0OMV16Lp8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 05:20:37 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 05:20:37 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 05:20:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Lr3QeYqt96/Render', 05:20:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:20:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:20:37 INFO - 'HOME': '/Users/cltbld', 05:20:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:20:37 INFO - 'LOGNAME': 'cltbld', 05:20:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:37 INFO - 'MOZ_NO_REMOTE': '1', 05:20:37 INFO - 'NO_EM_RESTART': '1', 05:20:37 INFO - 'PAGER': '/bin/cat', 05:20:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:20:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:37 INFO - 'PWD': '/builds/slave/test', 05:20:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:20:37 INFO - 'SHELL': '/bin/bash', 05:20:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r0OMV16Lp8/Listeners', 05:20:37 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 05:20:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:20:37 INFO - 'USER': 'cltbld', 05:20:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:20:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:20:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:37 INFO - 'XPC_FLAGS': '0x0', 05:20:37 INFO - 'XPC_SERVICE_NAME': '0', 05:20:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:20:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:38 INFO - Downloading/unpacking blobuploader==1.2.4 05:20:38 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 05:20:38 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 05:20:38 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 05:20:38 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 05:20:38 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 05:20:38 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 05:20:40 INFO - Downloading blobuploader-1.2.4.tar.gz 05:20:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:20:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:20:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:20:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:20:41 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 05:20:41 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 05:20:41 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 05:20:41 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 05:20:41 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 05:20:41 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 05:20:41 INFO - Downloading docopt-0.6.1.tar.gz 05:20:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:20:41 INFO - Installing collected packages: blobuploader, requests, docopt 05:20:41 INFO - Running setup.py install for blobuploader 05:20:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:20:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:20:42 INFO - Running setup.py install for requests 05:20:42 INFO - Running setup.py install for docopt 05:20:42 INFO - Successfully installed blobuploader requests docopt 05:20:42 INFO - Cleaning up... 05:20:42 INFO - Return code: 0 05:20:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:20:42 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')]} 05:20:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:42 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')]} 05:20:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:42 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 0x10285ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10286b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda01d5b090>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026fae00>, '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 0x102563db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026f5b20>, '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.Lr3QeYqt96/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.r0OMV16Lp8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 05:20:42 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 05:20:42 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 05:20:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Lr3QeYqt96/Render', 05:20:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:20:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:20:42 INFO - 'HOME': '/Users/cltbld', 05:20:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:20:42 INFO - 'LOGNAME': 'cltbld', 05:20:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:42 INFO - 'MOZ_NO_REMOTE': '1', 05:20:42 INFO - 'NO_EM_RESTART': '1', 05:20:42 INFO - 'PAGER': '/bin/cat', 05:20:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:20:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:42 INFO - 'PWD': '/builds/slave/test', 05:20:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:20:42 INFO - 'SHELL': '/bin/bash', 05:20:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r0OMV16Lp8/Listeners', 05:20:42 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 05:20:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:20:42 INFO - 'USER': 'cltbld', 05:20:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:20:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:20:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:42 INFO - 'XPC_FLAGS': '0x0', 05:20:42 INFO - 'XPC_SERVICE_NAME': '0', 05:20:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:20:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:20:43 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-U6fHgx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:20:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:20:43 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-9h3fDp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:20:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:20:43 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-wOrto2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:20:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:20:43 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-LHlmp8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:20:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:20:43 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-gI2ozK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:20:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:20:43 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-32Ide2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:20:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:20:43 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-VmWsBL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:20:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:20:43 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-JbN1ho-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:20:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:20:43 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-wtwlhW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:20:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:20:44 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-Ythjbv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:20:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:20:44 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-r85s6R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:20:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:20:44 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-PPa57j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:20:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:20:44 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-ZdvyeH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:20:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:20:44 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-9QvqYM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:20:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:20:44 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-iB63k4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:20:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:20:44 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-NZ_wCU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:20:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:20:44 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-LHBTSC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:20:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:20:44 INFO - Running setup.py install for manifestparser 05:20:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:20:44 INFO - Running setup.py install for mozcrash 05:20:45 INFO - Running setup.py install for mozdebug 05:20:45 INFO - Running setup.py install for mozdevice 05:20:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:20:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:20:45 INFO - Running setup.py install for mozfile 05:20:45 INFO - Running setup.py install for mozhttpd 05:20:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:20:45 INFO - Running setup.py install for mozinfo 05:20:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:20:45 INFO - Running setup.py install for mozInstall 05:20:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:20:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:20:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:20:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:20:45 INFO - Running setup.py install for mozleak 05:20:46 INFO - Running setup.py install for mozlog 05:20:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:20:46 INFO - Running setup.py install for moznetwork 05:20:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:20:46 INFO - Running setup.py install for mozprocess 05:20:46 INFO - Running setup.py install for mozprofile 05:20:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:20:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:20:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:20:46 INFO - Running setup.py install for mozrunner 05:20:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:20:46 INFO - Running setup.py install for mozscreenshot 05:20:47 INFO - Running setup.py install for moztest 05:20:47 INFO - Running setup.py install for mozversion 05:20:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:20:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:20:47 INFO - Cleaning up... 05:20:47 INFO - Return code: 0 05:20:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:20: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')]} 05:20:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20: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')]} 05:20:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:47 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 0x10285ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10286b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda01d5b090>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026fae00>, '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 0x102563db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026f5b20>, '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.Lr3QeYqt96/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.r0OMV16Lp8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 05:20:47 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 05:20:47 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 05:20:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Lr3QeYqt96/Render', 05:20:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:20:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:20:47 INFO - 'HOME': '/Users/cltbld', 05:20:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:20:47 INFO - 'LOGNAME': 'cltbld', 05:20:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:47 INFO - 'MOZ_NO_REMOTE': '1', 05:20:47 INFO - 'NO_EM_RESTART': '1', 05:20:47 INFO - 'PAGER': '/bin/cat', 05:20:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:20:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:47 INFO - 'PWD': '/builds/slave/test', 05:20:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:20:47 INFO - 'SHELL': '/bin/bash', 05:20:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r0OMV16Lp8/Listeners', 05:20:47 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 05:20:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:20:47 INFO - 'USER': 'cltbld', 05:20:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:20:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:20:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:47 INFO - 'XPC_FLAGS': '0x0', 05:20:47 INFO - 'XPC_SERVICE_NAME': '0', 05:20:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:20:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:20:47 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-5qIS_P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:20:47 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)) 05:20:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:20:47 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-Ti5O8k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:20:47 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)) 05:20:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:20:47 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-euKFuh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:20:47 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)) 05:20:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:20:47 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-xk0Yra-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:20:48 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)) 05:20:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:20:48 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-qeuZuz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:20:48 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)) 05:20:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:20:48 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-WgVUwe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:20:48 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)) 05:20:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:20:48 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-BNUFZj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:20:48 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)) 05:20:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:20:48 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-AfAYob-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:20:48 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)) 05:20:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:20:48 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-atHBWQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:20:48 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)) 05:20:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:20:48 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-7EONqb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:20:48 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)) 05:20:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:20:48 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-toLRsu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:20:48 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)) 05:20:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:20:48 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-_jO7DX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:20:48 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)) 05:20:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:20:48 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-LwoZ7m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:20:49 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)) 05:20:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:20:49 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-mlGcCu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:20:49 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)) 05:20:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:20:49 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-IochnD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:20:49 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)) 05:20:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:20:49 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-0ZWMzu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:20:49 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)) 05:20:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:20:49 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-FxiK6W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:20:49 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)) 05:20:49 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)) 05:20:49 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)) 05:20:49 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)) 05:20:49 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)) 05:20:49 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)) 05:20:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:20:49 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 05:20:49 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 05:20:49 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 05:20:49 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 05:20:49 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 05:20:49 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 05:20:51 INFO - Downloading blessings-1.6.tar.gz 05:20:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:20:51 INFO - Installing collected packages: blessings 05:20:51 INFO - Running setup.py install for blessings 05:20:52 INFO - Successfully installed blessings 05:20:52 INFO - Cleaning up... 05:20:52 INFO - Return code: 0 05:20:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:20:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:20:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:20:52 INFO - Reading from file tmpfile_stdout 05:20:52 INFO - Current package versions: 05:20:52 INFO - blessings == 1.6 05:20:52 INFO - blobuploader == 1.2.4 05:20:52 INFO - docopt == 0.6.1 05:20:52 INFO - manifestparser == 1.1 05:20:52 INFO - mozInstall == 1.12 05:20:52 INFO - mozcrash == 0.17 05:20:52 INFO - mozdebug == 0.1 05:20:52 INFO - mozdevice == 0.48 05:20:52 INFO - mozfile == 1.2 05:20:52 INFO - mozhttpd == 0.7 05:20:52 INFO - mozinfo == 0.9 05:20:52 INFO - mozleak == 0.1 05:20:52 INFO - mozlog == 3.1 05:20:52 INFO - moznetwork == 0.27 05:20:52 INFO - mozprocess == 0.22 05:20:52 INFO - mozprofile == 0.28 05:20:52 INFO - mozrunner == 6.11 05:20:52 INFO - mozscreenshot == 0.1 05:20:52 INFO - mozsystemmonitor == 0.0 05:20:52 INFO - moztest == 0.7 05:20:52 INFO - mozversion == 1.4 05:20:52 INFO - psutil == 3.1.1 05:20:52 INFO - requests == 1.2.3 05:20:52 INFO - wsgiref == 0.1.2 05:20:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:20:52 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')]} 05:20:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:52 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')]} 05:20:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:52 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 0x10285ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10286b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda01d5b090>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026fae00>, '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 0x102563db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026f5b20>, '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.Lr3QeYqt96/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.r0OMV16Lp8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 05:20:52 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 05:20:52 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 05:20:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Lr3QeYqt96/Render', 05:20:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:20:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:20:52 INFO - 'HOME': '/Users/cltbld', 05:20:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:20:52 INFO - 'LOGNAME': 'cltbld', 05:20:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:52 INFO - 'MOZ_NO_REMOTE': '1', 05:20:52 INFO - 'NO_EM_RESTART': '1', 05:20:52 INFO - 'PAGER': '/bin/cat', 05:20:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:20:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:52 INFO - 'PWD': '/builds/slave/test', 05:20:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:20:52 INFO - 'SHELL': '/bin/bash', 05:20:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r0OMV16Lp8/Listeners', 05:20:52 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 05:20:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:20:52 INFO - 'USER': 'cltbld', 05:20:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:20:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:20:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:52 INFO - 'XPC_FLAGS': '0x0', 05:20:52 INFO - 'XPC_SERVICE_NAME': '0', 05:20:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:20:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:52 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)) 05:20:52 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)) 05:20:52 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)) 05:20:52 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)) 05:20:52 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)) 05:20:52 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)) 05:20:52 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)) 05:20:52 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)) 05:20:52 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)) 05:20:52 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)) 05:20:52 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)) 05:20:52 INFO - Cleaning up... 05:20:52 INFO - Return code: 0 05:20:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:20:52 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')]} 05:20:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:52 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')]} 05:20:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:52 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 0x10285ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10286b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda01d5b090>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026fae00>, '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 0x102563db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026f5b20>, '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.Lr3QeYqt96/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.r0OMV16Lp8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 05:20:52 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 05:20:52 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 05:20:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Lr3QeYqt96/Render', 05:20:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:20:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:20:52 INFO - 'HOME': '/Users/cltbld', 05:20:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:20:52 INFO - 'LOGNAME': 'cltbld', 05:20:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:52 INFO - 'MOZ_NO_REMOTE': '1', 05:20:52 INFO - 'NO_EM_RESTART': '1', 05:20:52 INFO - 'PAGER': '/bin/cat', 05:20:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:20:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:52 INFO - 'PWD': '/builds/slave/test', 05:20:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:20:52 INFO - 'SHELL': '/bin/bash', 05:20:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r0OMV16Lp8/Listeners', 05:20:52 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 05:20:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:20:52 INFO - 'USER': 'cltbld', 05:20:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:20:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:20:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:52 INFO - 'XPC_FLAGS': '0x0', 05:20:52 INFO - 'XPC_SERVICE_NAME': '0', 05:20:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:20:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:53 INFO - Downloading/unpacking jsonschema 05:20:53 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 05:20:53 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 05:20:53 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 05:20:53 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 05:20:53 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 05:20:53 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 05:20:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:20:55 INFO - Downloading/unpacking functools32 (from jsonschema) 05:20:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:55 INFO - Downloading functools32-3.2.3-2.tar.gz 05:20:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:20:56 INFO - warning: no files found matching '*.txt' 05:20:56 INFO - no previously-included directories found matching 'build' 05:20:56 INFO - no previously-included directories found matching 'dist' 05:20:56 INFO - no previously-included directories found matching '.git*' 05:20:56 INFO - Installing collected packages: jsonschema, functools32 05:20:56 INFO - Running setup.py install for functools32 05:20:56 INFO - warning: no files found matching '*.txt' 05:20:56 INFO - no previously-included directories found matching 'build' 05:20:56 INFO - no previously-included directories found matching 'dist' 05:20:56 INFO - no previously-included directories found matching '.git*' 05:20:56 INFO - Successfully installed jsonschema functools32 05:20:56 INFO - Cleaning up... 05:20:56 INFO - Return code: 0 05:20:56 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:20:56 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')]} 05:20:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:56 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')]} 05:20:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:56 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 0x10285ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10286b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda01d5b090>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026fae00>, '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 0x102563db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026f5b20>, '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.Lr3QeYqt96/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.r0OMV16Lp8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 05:20:56 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 05:20:56 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 05:20:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Lr3QeYqt96/Render', 05:20:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:20:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:20:56 INFO - 'HOME': '/Users/cltbld', 05:20:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:20:56 INFO - 'LOGNAME': 'cltbld', 05:20:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:56 INFO - 'MOZ_NO_REMOTE': '1', 05:20:56 INFO - 'NO_EM_RESTART': '1', 05:20:56 INFO - 'PAGER': '/bin/cat', 05:20:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:20:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:56 INFO - 'PWD': '/builds/slave/test', 05:20:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:20:56 INFO - 'SHELL': '/bin/bash', 05:20:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r0OMV16Lp8/Listeners', 05:20:56 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 05:20:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:20:56 INFO - 'USER': 'cltbld', 05:20:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:20:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:20:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:56 INFO - 'XPC_FLAGS': '0x0', 05:20:56 INFO - 'XPC_SERVICE_NAME': '0', 05:20:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:20:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:56 INFO - Downloading/unpacking flake8 05:20: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 05:20: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 05:20: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 05:20: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 05:20: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 05:20: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 05:20:58 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:20:59 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:20:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:59 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:20:59 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:20:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:59 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:20:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:21:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:21:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:21:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:21:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:21:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:21:00 INFO - no previously-included directories found matching 'docs/_build' 05:21:00 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:21:00 INFO - Running setup.py install for pep8 05:21:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:21:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:21:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:21:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:21:00 INFO - no previously-included directories found matching 'docs/_build' 05:21:00 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:21:00 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:21:00 INFO - Cleaning up... 05:21:00 INFO - Return code: 0 05:21:00 INFO - Running post-action listener: _resource_record_post_action 05:21:00 INFO - Running post-action listener: _start_resource_monitoring 05:21:00 INFO - Starting resource monitoring. 05:21:00 INFO - ##### 05:21:00 INFO - ##### Running install step. 05:21:00 INFO - ##### 05:21:00 INFO - Running pre-action listener: _resource_record_pre_action 05:21:00 INFO - Running main action method: install 05:21:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:21:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:21:00 INFO - Reading from file tmpfile_stdout 05:21:00 INFO - Detecting whether we're running mozinstall >=1.0... 05:21:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:21:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:21:01 INFO - Reading from file tmpfile_stdout 05:21:01 INFO - Output received: 05:21:01 INFO - Usage: mozinstall [options] installer 05:21:01 INFO - Options: 05:21:01 INFO - -h, --help show this help message and exit 05:21:01 INFO - -d DEST, --destination=DEST 05:21:01 INFO - Directory to install application into. [default: 05:21:01 INFO - "/builds/slave/test"] 05:21:01 INFO - --app=APP Application being installed. [default: firefox] 05:21:01 INFO - mkdir: /builds/slave/test/build/application 05:21:01 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'] 05:21:01 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 05:21:33 INFO - Reading from file tmpfile_stdout 05:21:33 INFO - Output received: 05:21:33 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 05:21:33 INFO - Running post-action listener: _resource_record_post_action 05:21:33 INFO - ##### 05:21:33 INFO - ##### Running run-tests step. 05:21:33 INFO - ##### 05:21:33 INFO - Running pre-action listener: _resource_record_pre_action 05:21:33 INFO - Running main action method: run_tests 05:21:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:21:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:21:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:21:33 INFO - 2016-05-10 05:21:33.889 screenresolution[1649:7455] starting screenresolution argv=screenresolution get 05:21:33 INFO - 2016-05-10 05:21:33.891 screenresolution[1649:7455] Display 0: 1600x1200x32@60 05:21:33 INFO - 2016-05-10 05:21:33.897 screenresolution[1650:7458] starting screenresolution argv=screenresolution list 05:21:33 INFO - Available Modes on Display 0 05:21:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:21:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:21:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:21:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:21:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:21:34 INFO - Intel Iris: 05:21:34 INFO - Chipset Model: Intel Iris 05:21:34 INFO - Type: GPU 05:21:34 INFO - Bus: Built-In 05:21:34 INFO - VRAM (Dynamic, Max): 1536 MB 05:21:34 INFO - Vendor: Intel (0x8086) 05:21:34 INFO - Device ID: 0x0a2e 05:21:34 INFO - Revision ID: 0x0009 05:21:34 INFO - Displays: 05:21:34 INFO - Display: 05:21:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:21:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:21:34 INFO - Display Serial Number: 1600x1200 60 05:21:34 INFO - Main Display: Yes 05:21:34 INFO - Mirror: Off 05:21:34 INFO - Online: Yes 05:21:34 INFO - Rotation: Supported 05:21:34 INFO - Return code: 0 05:21:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:21:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:21:34 INFO - Python 2.7.3 05:21:34 INFO - Return code: 0 05:21:34 INFO - grabbing minidump binary from tooltool 05:21:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:21:34 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026fae00>, '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 0x102563db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026f5b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:21:34 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:21:34 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:21:34 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:21:34 INFO - Return code: 0 05:21:34 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:21:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:21:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:21:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:21:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:21:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:21:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:21:35 INFO - Return code: 0 05:21:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/df8c38IJRoyaYkFY3ZTFeA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0194', '--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 05:21:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/df8c38IJRoyaYkFY3ZTFeA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0194 --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 05:21:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Lr3QeYqt96/Render', 05:21:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:21:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:21:35 INFO - 'HOME': '/Users/cltbld', 05:21:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:21:35 INFO - 'LOGNAME': 'cltbld', 05:21:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:21:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:21:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:35 INFO - 'MOZ_NO_REMOTE': '1', 05:21:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:21:35 INFO - 'NO_EM_RESTART': '1', 05:21:35 INFO - 'PAGER': '/bin/cat', 05:21:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:21:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:35 INFO - 'PWD': '/builds/slave/test', 05:21:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:21:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:21:35 INFO - 'SHELL': '/bin/bash', 05:21:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r0OMV16Lp8/Listeners', 05:21:35 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 05:21:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:21:35 INFO - 'USER': 'cltbld', 05:21:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:21:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:21:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:35 INFO - 'XPC_FLAGS': '0x0', 05:21:35 INFO - 'XPC_SERVICE_NAME': '0', 05:21:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:21:35 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/df8c38IJRoyaYkFY3ZTFeA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0194', '--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 05:21:35 INFO - mozversion application_buildid: 20160510032818 05:21:35 INFO - mozversion application_changeset: 4c5959b81fd5b61fe854f2d2e0743afa66f9aa07 05:21:35 INFO - mozversion application_display_name: FirefoxDeveloperEdition 05:21:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:21:35 INFO - mozversion application_name: Firefox 05:21:35 INFO - mozversion application_remotingname: firefox-dev 05:21:35 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:21:35 INFO - mozversion application_vendor: Mozilla 05:21:35 INFO - mozversion application_version: 48.0a2 05:21:35 INFO - mozversion platform_buildid: 20160510032818 05:21:35 INFO - mozversion platform_changeset: 4c5959b81fd5b61fe854f2d2e0743afa66f9aa07 05:21:35 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:21:35 INFO - mozversion platform_version: 48.0a2 05:21:35 INFO - using testdate: 1462882895 05:21:35 INFO - actual date: 1462882895 05:21:35 INFO - starting webserver on 'localhost:49273' 05:21:35 INFO - SUITE-START | Running 2 tests 05:21:35 INFO - TEST-START | dromaeo_css 05:21:35 INFO - operating with platform_type : mac_ 05:21:35 INFO - Initialising browser for dromaeo_css test... 05:21:35 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/tmpIO3lJb/profile http://localhost:49273/getInfo.html) 05:21:38 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 05:21:38 INFO - PROCESS | 1668 | colorDepth:24 05:21:38 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 05:21:38 INFO - PROCESS | 1668 | __metrics 05:21:38 ERROR - PROCESS | 1668 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 05:21:39 INFO - TEST-INFO | 1668: exit 0 05:21:39 INFO - Browser initialized. 05:21:39 INFO - Running cycle 1/1 for dromaeo_css test... 05:21:39 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/tmpIO3lJb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:21:51 INFO - PROCESS | 1671 | RSS: Main: 220672000 05:21:51 INFO - PROCESS | 1671 | 05:22:40 INFO - PROCESS | 1671 | 1462882960853 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] 05:24:52 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49273/tests/dromaeo/cssquery-ext.html) 05:24:53 INFO - PROCESS | 1671 | RSS: Main: 231321600 05:24:53 INFO - PROCESS | 1671 | 05:27:55 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-ext.html (next: http://localhost:49273/tests/dromaeo/cssquery-jquery.html) 05:27:56 INFO - PROCESS | 1671 | RSS: Main: 242954240 05:27:56 INFO - PROCESS | 1671 | 05:30:58 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49273/tests/dromaeo/cssquery-mootools.html) 05:30:58 INFO - PROCESS | 1671 | RSS: Main: 244936704 05:30:58 INFO - PROCESS | 1671 | 05:34:00 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49273/tests/dromaeo/cssquery-prototype.html) 05:34:01 INFO - PROCESS | 1671 | RSS: Main: 246513664 05:34:01 INFO - PROCESS | 1671 | 05:37:03 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49273/tests/dromaeo/cssquery-yui.html) 05:37:04 INFO - PROCESS | 1671 | RSS: Main: 249675776 05:37:04 INFO - PROCESS | 1671 | 05:40:06 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-yui.html (next: http://localhost:49273/tests/dromaeo/cssquery-dojo.html) 05:40:06 INFO - PROCESS | 1671 | RSS: Main: 250806272 05:40:06 INFO - PROCESS | 1671 | 05:40:06 INFO - PROCESS | 1671 | __start_tp_report 05:40:06 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 05:40:06 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 05:40:06 INFO - PROCESS | 1671 | |i|pagename|runs| 05:40:06 INFO - PROCESS | 1671 | |0;dojo.html;2871.6984716604743;3385.77992430492;3419.384510788059;3466.791992480453;3480.77374970627;4737.43150821901;5714.857128571788;5722.79970201043;5730.255066841315;5788.189653448515;4495.79768910399;5202.4912775295215;5252.947470525303;5632.915506267399;5794.420557944214;5165.509276618715;5211.244369566411;5263.473652634744;5279.973600131994;5308.867278318035;5088.720120393377;5235.424103348648;5262.710550919698;5336.4930331618625;5353.250544923714;5196.454372290912;5201.895962080756;5203;5204.9739751301195;5207.973960130213;5109.744512774347;5186;5209.426963034063;5209.7916083356995;5220.112580861225;5169.663971841818;5239.921401178985;5259.105951988133;5286.36563612369;5307.840764777063;5695.117256825199;5714.657120572779;5727.570432217601;5738.110592858114;5763.855903602402;5948.048312270016;6156.692165391757;6166;6265.248170219603;6291.96854015727;6198.287196972361;6258.812235632939;6264.686765661699;6268.091126786636;6270.153529273535;5828.300603927556;5840.766369345221;5881.5882888198275;5884.205632239636;5914.201582786355;6184.721687524072;6193.969030154821;6245.094461303085;6290.874182516324;6319;6130.938690613037;6165.568410211242;6177.413145751147;6186.505079593621;6197.845053873689;6278.246610406689;6283.434490895841;6303.180586523723;6316.62100273985;6330.873382532323;6156.784512542129;6236;6236.472064344264;6248.4688801451475;6276.497880169575;6150.815475535741;6193.969030154911;6230.875382492415;6236.251649802053;6242.282137554149;4864.124457597666;4970.378702662168;4988.226824842155;5006.64953453262;5009.373828271466;4387.802548885308;4400.779961001937;4443.666724995638;4452.1763473757455;4498.820047198075;5872.471477567039;5872.853178670567;5888.941110588839;5891.793787217427;5933.3769954154195;6148.508119350441;6220.346863579259;6251.593646412968;6284.088807122942;6288.434040936338;5490.81947381307;5724.542036637059;5797.942020579824;5823.475887170175;5838.766449341979;5379.973100134474;5394.838154855361;5409.323834520685;5415.079436495726;5434.91847622286;6317.336679648571;6412.038194270896;6415.166028416463;6415.262244841903;6492.935070649233;6168.691565421801;6205.131281620486;6236.656983865931;6253.687315634291;6280.183576135073;5376.435474275301;5549.250851135206;6200.744609080269;6360.141380913703;6380.968095159495;6067.696615169136;6092.177556029879;6167.136600875791;6176.4441200292185;6189;6189.876202476015;6261.311255761953;6265.373462653881;6285.182926219745;6287.497000240061;6130.111133885473;6160.137580738789;6175.505959523317;6197.1324014638885;6204.038374052029;5108.948910510847;6112.388761123852;6128.050152226234;6150;6153.200083989232;4984.476629953802;5818.592698511233;5963.403659634002;6014.278286605635;6023.698815059317;6077.969610152098;6121.785737499166;6134.907976380268;6164.229471316085;6172.382761723792;5787.363390027178;5986.102084687332;6053.727582258897;6082.787102451393;6112.32764395925 05:40:06 INFO - PROCESS | 1671 | |1;ext.html;520.1313805944073;523.4922125538227;532.7336331834083;566.3091028944688;611.8710978245136;27731.861340693293;39110.02224944378;39281.214375712465;39300;39302.21395572094;42775.78612106943;43311.56688433115;43541.78229108858;43615.3457698134;43879.12241755163;9483.336166468367;9749.658761943334;9757.756056098619;9766.609335626566;9767.706968790912;9603.231741460666;9657.420554766737;9690.66082687106;9756.219502439822;9757.41455512668;9672.951635241849;9735;9799.608015679365;9821.65624203153;9825.066618671215;9589.136977672042;9733.902660973406;9771.267154963336;9789.755256118582;9804.215662747;9595.13643772064;9678.128968392808;9691.466969316685;9707.70876873695;9714.174295184917;31011.68988310133;39162.629307974086;39379.606203938165;39663.40504892429;39767.20465590701;3297.4888892221743;3339.5157702133056;3361.310931259086;3589.856405743767;3736.7757934524016;27596.448071038667;27780.72219277822;29307.853460732775;29443.558346624817;29826.70173298261;2725.3459169799303;2728.7816974641983;2746.2036009557205;2769.5568709006666;2791.1347482280557;466204.66897665634;468603;471919;472920.99999999657;474857;36158.45762313568;63107.05339419912;63221.051684224774;63657.36342636607;63743.36256637374;31836.84081579577;35583.288334233686;36316;36375.45436818397;36491.72278970223;15442.6139346515;15691;15756.369745210293;15788.921055394649;15798.36806527726;12928.418221180069;13222.669433264246;13276.73446531064;13290.003249756306;13335.733285334432;8133.715319963773;8314.334853211729;8372.53951032687;8374.916250837536;8435.831283374298;3395.932081358359;3429.725621950238;3438.123278564;3439.398105331557;3459.5329630500064;804.2359758229588;814.8144449825489;838.279079991207;895.4761464543316;904.4392476664378;25578.36054098625;25578.74421255801;25809.310871979214;26051.609225861626;26307.605385918843;871;871.4117970369975;902.3457992955055;923.1553128886993;996.9152622027209;10237.692869213935;13501.324933753272;15901.761473577906;16203.513894583182;16423.921301836937;624975;628412.7214275368;646581;646671;648049;63496.68251658712;63553.72892542123;63676;63891.680541597925;63977;1498.4980031689288;1500.5648361975122;1505.1495904813746;1512.2287633307092;1515.658976730249;29889.850550747105;31001.379972399973;31111.377772444877;31119.68880311168;31448.528272076393;52634.473655262955;53962.650933726974;53966.73016634892;54581.727091364286;54645.90708185894;594545;598740;603262;604035;605222;22262.77737222607;27420.725792741818;43259;44137.77931110324;45095.0980980372;43866.12267754563;43994.78002609967;44086.55913440953;44494;44808.32787508255;29249.122526324245;31643.84178079095;37071.25857482889;38276.80861595674;39661;35434.46848297226;35655.46516802198;35783.284334312644;36072.098197545274;36357.27285454329;36013.45979810358;38205;38957.02607434837;39012.609873900896;39558;27964.60176991183;29994.95017674371;32153.196170095936;32338.514922276656;32475.83762081269;25254.873725631252;25363.746362536876;25474;25652.845621946683;25681 05:40:06 INFO - PROCESS | 1671 | |2;jquery.html;394.74341677909354;395.90894094358276;400.4513816071982;412.42672684967897;414.17165668662676;14763.704725905476;20190.192392304307;20402.59194816103;20461.693074603856;20780.688289675647;20021.099050542725;20183.899080504576;20355.898220508912;20378.184872605078;21079.47301317468;5646.774129034834;5919.940800591982;6130.325664176937;6176.258848938133;6200.627962322253;5918.201042859224;5975.312839023524;5993.130996005577;6074.210352654149;6092.786752463665;5754.568407369443;5856.502197313233;5975.342712301644;6032;6038.305594856582;5827.41725827416;5904.911426328608;5921.082232254007;5923.614965027259;5968.1346204800275;4768.925517399942;5140.588752899759;5221.660592061504;5259.211118332242;5262.578993680535;11120.666380008613;11213.831792523119;13123.803142952864;13234.602961911158;13242.470301187941;1606.8794840386902;1965.6560101982095;2000.6098810731912;2007.2573147935357;2190;9779.315447918649;9893.159081478083;10324.845127323097;12134.5752898649;15011.549653510303;1734.054940057659;1746.2752957522614;1751.7459968304652;1788.7764029496313;1789.8299661532133;108522.91477085208;109347;113672;113707;113895.2915706265;11595.362255076052;11704.531818727155;11765.764684706259;12027.518899244107;12365.07261955357;15396.384144634088;15489.225538723018;15544.222788860512;15974.92012539953;15980.200989950456;7180.820479488055;7331.413486921034;8924.393609150415;8944.329175311877;9117.31620128493;8869;9037.909620903838;9039.593218305192;9061.68284110053;9115.134062264075;4849.515048495122;4860.562549370503;4862.051899879561;4941.135301322254;4962.851114466572;1974.2004488182308;2021.110711287029;2029.3506078054882;2102.075086961732;2130.179880745924;726.8764310067302;731.8426538294292;759.3583422008394;793.0166593424228;851.6806197675747;16896.817222794532;17274.309027639007;18201.27194912215;18279.177437015365;18358.908205459153;775.5308038636656;803.8030682482829;829.2536716954789;833.0877688930611;880.8678698195193;9280.489573073546;9707.611695532241;9711.417314961125;9758.609655613696;9817.067378599022;506880;598475;639900.8004959945;654016;657911;123604;124798;125285;128035;132052;1067.1036329483115;1073.3184427888348;1093.5680406239492;1095.5124969388705;1100.2353364411656;11648.76702465929;14529.34617942174;15429.382824687336;16211.594710132342;16246.837531624533;37972.43041354327;50115.74942125266;51322.486775133264;51865.48134518607;52469.47530524799;74414.51170976443;76232.85650715354;77055.61472192603;77988;78369.21630783619;8003.079645840803;8105.311048560939;8286.917130828615;8338.082810890675;8475.067742548437;9232;12546.309952952675;12732.809007865075;12975.286359250333;12977.740445191232;8689.652413903374;9605.18355939725;10436.269461137648;10476.266661333633;10610.734731631772;10879.456027198767;10895.836562451728;11233.775324493628;11244.55021799119;11384.943075284902;10217.284790064625;10489.52797124116;10548.578056877639;10728.088112510211;10885.78228435411;6271.498280137487;6305.148804911279;6787.830304242236;7526.134494533198;8256.917430825855;8906.465612063297;9989.001099889952;10139.695809125738;10252.23108266868;10306.536205870903 05:40:06 INFO - PROCESS | 1671 | |3;mootools.html;1360.4558176729306;1579.8183208930973;1664.9667006659859;1764.964700705986;1920.2894928876317;6560.803175904719;6992.475564332676;7469.887951680722;8627.956860215705;8876.201141897229;8651.56742162891;8655.264302534291;8756.080611535777;8782.341324400664;8783.604737786798;2449.4856080223176;2466.3217615155886;2472.6414669872856;2476.393283645509;2492.9376765580914;2414.1912459326145;2492.3395300245447;2492.9252122436355;2495.7254701982765;2504.912328068518;2443.1693224303735;2448.485817978227;2477.665515155458;2497;2500.2624225853324;2436.147348428045;2441.0113903868873;2462.076721229539;2475.5420247254137;2477.0587176872855;2440.255722004788;2470.1848390031246;2472.394263405477;2483.1681386735286;2487.2786891801356;7053.470989675795;8883.333749968713;8923.15230053144;8944.329175311812;9055.777470041527;5296.3379577552805;5322;5409.756560954766;5474.315710536183;5504.532114770209;3051.4812481878134;3471.357798807229;3571.767835090711;3590.2819436112622;3616.5298511193378;1030.0626429948786;1031.3296357367772;1042.5204405973261;1043.7651528406125;1050.9700493516398;11371.374574398405;11504.827427588592;11671.241369310968;11679.766404671944;11937.28376297425;3855.9228815423535;4542.841000564964;4579.977100114478;4584.4498660161;4669.603083737852;9183.862242066374;9191.31065170115;9276.953615231881;9284.907150928539;9295.953520232355;5272.841814745564;5293.100172970604;5294.311739473844;5308.203769434538;5312.601554883399;4404.801783919731;4826.227803551415;4882.926756098661;4957.206846904478;4958.925616115761;3887.5723670396233;3890.688744900401;3945.6448919597374;3970.9205815884097;3987.2224916141654;1213.2720367779261;1229.2501574040007;1231.2797013746879;1240.3612139748036;1242.6396345059832;856.1908995998722;863.5984267315729;863.926566241864;866.7399780066081;880.9163129502687;4667.8599642010795;5515.172724091338;5571.554275657938;5610.635308704921;5632.718364081779;403.9071013666873;414.81540714382413;459.5863722649642;984.9901500984898;1006.3710181136789;3362.4788157835574;3455.51622772812;3607.2424790793702;3704.870329538453;3748.493953316322;10702.197335199891;10749.40878251688;10953.945230273797;10965.287256328473;11054.447277636085;10318.1745460363;10780.568777248978;10828.350298982086;10894.40080795548;10903.182261330432;6609.239937407164;8298.21166989135;8417.494950302878;8424.578771061422;8480.53357065355;4426.867193984185;5438.728063596884;5505.587080968863;5537.197106419627;5828.388019257917;9167.77080572991;9310.394824336396;9499.050094990722;9536.189423899046;9578.616855325708;9609.99095095005;9805.411675299505;9865.901340986498;9955.20358371343;9955.452450115314;6705.966470167618;7147.356737893615;7193.460490463341;7349.595772232365;7420.962895185489;8023.318017968538;8038.15599362082;8095.39284553649;8158.388120890838;8198.713045043394;7155.892661610184;7308.524945878501;7327.120745510573;7407.888881666671;7427.071616047994;6129.509639228761;7092.219855815753;7191.604461754654;7211.3870321023305;7259.782206533812;6617.536772425884;6639.933600663931;6697.732090716317;6699.330066993261;6711.765088221889;4575.016371480147;4596.080783843177;4606.101810146989;4610.216263235191;4672.415948006499;4660.324252983366;4664.067186562633;4692.343071969859;4743.288506724019;4747.430308363018 05:40:06 INFO - PROCESS | 1671 | |4;prototype.html;276.98338099714016;322.3215132146831;324.9967500324996;328.3793630039225;334.824217285925;6755.932440675591;6772.830679233021;6773.8983915241315;6792.083068785712;6798.422134118599;6671.432928201097;6674.06563081169;6684.465242780578;6699.330066993298;6706.631135287557;4166.791660416982;4692.108499385115;4784.5693887550115;4869.026194761062;4892.143874821892;4681.368015317939;4828.454894433783;4917.557419832215;4939.876503087434;4947.480514545975;4876.878078048059;4893.290472881448;4901.53435423636;4906.018796240766;4909.754512274372;4883.804647814083;4902.436219834711;4909.558139767438;4933.901321973576;4950.628702847301;4208.936865947013;4209.031922657806;4222.1766755482695;4231.026863821307;4231.344141658048;6207.875842483125;6350.110984462179;6370.745170193187;6399.87200255997;6474.449671777905;4586.403767510235;5657.519110875581;5694.430556944314;5701.657900525982;5736.713164341766;7102.964485177593;7121.465890058266;7130.6078165700865;7147.2138064812825;7178.748743793994;4615.446146462446;4628.259478483426;4628.32889231063;4635.907281854378;4638.304254361839;7384.483086183969;7539;7576.469647124703;7576.696932122653;7614.657340419694;4473.865784026484;4672.906541869212;4675.5324467553655;4689.601383882408;4701.177293973541;4759.547842954914;4788.23388257884;4789.209780386267;4789.832355867528;4809.989902120594;5693.914591281134;5699.059655156935;5709.628874123168;5729.197912292282;5731.197632331477;6453.676996215764;7212.639368031682;7222.7472038479455;7238.167610724834;7241.094863142107;1569.8351673074392;1654.081984498615;1654.3299963514796;1655.5695519165104;1657.1879778908249;1006.9496525173712;1041.1670663469345;1050.469512895998;1057.138432177788;1065.1957771882335;5398.352197736297;6640.83397915056;6668;6681.231658359351;6704.229013663394;5505.339359276913;5657.632253903483;5911.970440147772;7383.114026316876;7424.665890034961;6627.785524020846;6771.119754431991;6785.592864428051;6798.484560712568;6808.38724514796;3302.3230237801195;3930.469386632768;3970.940435893464;3971.72197946141;3983.4423180754716;6229.501639868799;7017.736807374619;7256;7831.530108193527;7831.921680783234;6901.62041087735;7311.597862117528;7430.6284685765495;7737.883931741028;7786.727464538714;6316.905246421307;7699.846003079907;7703.537787732754;7722.768316950501;7732.9613351934;5952.464278214981;7439.8140046499275;7492;7498.887516687145;7504.324610784837;7337.156227033959;7375.963120184365;7379.560985607755;7396.55620662762;7420.6660700269695;6312.242530896322;7452.292032256927;7748.8450230996195;7824.295813376823;7834.804129896798;4661.137689527448;4691.085238378483;4735.958089220366;4743.620510359094;4746.931940313402;7490.550566966;7496.062992125984;7503.362214211853;7515;7521.210272921265;7235.746748863765;7237.493375463667;7254.274572542915;7280.271972802678;7280.817979550342;6954.965225173842;7200.855982880418;7241.239669834802;7244.225164834649;7265.09186351706;6992.965035174791;7036.19083805369;7043.788686339418;7053.647317634201;7085.0435191248525;7191.101112360955;7201.927980720126;7230;7234.891476627749;7260.4554658399775;7019.649017549;7080.681369338495;7086.893696594659;7102.964485177541;7117.395021423237 05:40:06 INFO - PROCESS | 1671 | |5;yui.html;693.732912828561;734.1447213995696;736.7752835385205;742.2503271695585;743.6653505922333;13234.139780914258;14988.175650339226;15153.166575838324;15245.009074410156;15271.694566108685;12172.81740773889;13905.165690058604;13950.02349835512;13956.162630242192;13967.092139010958;715.7530651925077;902.62992173209;907.7866701325183;921.3734660430904;921.5162039929057;686.5743239191684;765.7740966414921;845.4631309118723;901.6168128545374;903.0472851142042;961.5769061612868;972.747085757701;973.1533565797766;975.4147511493119;977.8728765260506;930.8836395450569;954.1269738189549;962.4177372689562;964.4502633498909;965.5461932891529;681.6830173969128;693.0435998322283;695.0443140681563;706.1385110165589;709.4572651921285;14721.043132196373;14755.852441475556;14804.333804978802;14841.777373339408;14856.405743770236;12145;12480.625581232487;12550.121491495598;12600.873991260061;12699.238045717286;9958.900410995942;10298.588056477733;10581.418022008786;10636.787264254672;10859;3383.035834787074;3442.0534353052863;3448.2413868948793;3499.3701133795907;3681.1349332906743;67331.99002014974;88659.11340886573;88901.99999999936;89396.65905011431;89692;8981.685641002534;9101.02991907419;9199.922539512329;9254.16712495865;9386.62453501866;20977.89511052435;21028.15887364519;21132.577348452945;21146.89426552888;21263.893680531808;7478.962605186939;7502.737404190827;7542.434317426215;7554.660040298199;7641.235876412315;4024.175044116008;4036.919261614751;4096.508418989681;4142.896427589334;4197.748135111903;3543.061088811467;3575.7318201135017;3577.678008979204;3590.2281009583066;3600.5679318481425;636.4844475974476;643.5881036136876;647.3397134922353;658.5291516565678;659.5053709717712;390.4826105410286;554.5646667366179;563.5829486180197;570.8515785895614;581.3401788969466;6664.5334826561675;7318.707251709979;7680;7762.107357653829;7894;459.1345314082911;582.6154737872984;583.3728741602794;584.8391692284656;589.1751547832982;3478.4956181353564;3527.5590551181103;3597.5862775780597;3655.5796083450746;3690.870819521304;83779;84195.57902210573;84234.57882710548;85246.14753852382;85812.71280930791;78364.43271134545;79047.20952790513;79915.60042199751;80097.59951200207;80284;68488;68879.65560172267;68890;70736.29263707297;70993.64503177554;5293.391260005149;6126.172966649446;6755.054292399166;7286.6720997556295;7298.671559779928;48423;48718.0256394863;49068;49126.5087349122;49880;62406.687966561694;65702;66380.33619663934;66868;67355.66322168357;9931.553080111556;9953.751156220862;10020.34867733595;10127.544260508148;10135.391876487434;10600.787984240118;10758.906650662871;10765.084967777826;10879.292845964987;11110.222284440011;8198.959005204935;9611.711648650551;9659.420434773936;9661.372010819274;9848.212143028684;9851.45816980073;10201.74495637615;10218.897811021794;10244.02681745233;10449.529771160163;9468.526573671152;9487.430754154773;9559;9631.566579503798;10002.999700029939;8460.746177614436;8794.604242809217;8833;8854.734357969272;8927.642894284156;8165.387595930448;8356.498610083414;8444.197801208875;8472.364572656952;8556.10160933093 05:40:06 INFO - PROCESS | 1671 | __end_tp_report 05:40:06 INFO - PROCESS | 1671 | __start_cc_report 05:40:06 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,414 05:40:06 INFO - PROCESS | 1671 | __end_cc_report 05:40:06 INFO - PROCESS | 1671 | __startTimestamp1462884006929__endTimestamp 05:40:06 INFO - PROCESS | 1671 | 05:40:06 INFO - PROCESS | 1671 | ------- Summary: start ------- 05:40:06 INFO - PROCESS | 1671 | Number of tests: 6 05:40:06 INFO - PROCESS | 1671 | 05:40:06 INFO - PROCESS | 1671 | [#0] dojo.html Cycles:165 Average:5731.83 Median:5963.40 stddev:662.27 (11.1%) stddev-sans-first:625.14 05:40:06 INFO - PROCESS | 1671 | Values: 2871.7 3385.8 3419.4 3466.8 3480.8 4737.4 5714.9 5722.8 5730.3 5788.2 4495.8 5202.5 5252.9 5632.9 5794.4 5165.5 5211.2 5263.5 5280.0 5308.9 5088.7 5235.4 5262.7 5336.5 5353.3 5196.5 5201.9 5203.0 5205.0 5208.0 5109.7 5186.0 5209.4 5209.8 5220.1 5169.7 5239.9 5259.1 5286.4 5307.8 5695.1 5714.7 5727.6 5738.1 5763.9 5948.0 6156.7 6166.0 6265.2 6292.0 6198.3 6258.8 6264.7 6268.1 6270.2 5828.3 5840.8 5881.6 5884.2 5914.2 6184.7 6194.0 6245.1 6290.9 6319.0 6130.9 6165.6 6177.4 6186.5 6197.8 6278.2 6283.4 6303.2 6316.6 6330.9 6156.8 6236.0 6236.5 6248.5 6276.5 6150.8 6194.0 6230.9 6236.3 6242.3 4864.1 4970.4 4988.2 5006.6 5009.4 4387.8 4400.8 4443.7 4452.2 4498.8 5872.5 5872.9 5888.9 5891.8 5933.4 6148.5 6220.3 6251.6 6284.1 6288.4 5490.8 5724.5 5797.9 5823.5 5838.8 5380.0 5394.8 5409.3 5415.1 5434.9 6317.3 6412.0 6415.2 6415.3 6492.9 6168.7 6205.1 6236.7 6253.7 6280.2 5376.4 5549.3 6200.7 6360.1 6381.0 6067.7 6092.2 6167.1 6176.4 6189.0 6189.9 6261.3 6265.4 6285.2 6287.5 6130.1 6160.1 6175.5 6197.1 6204.0 5108.9 6112.4 6128.1 6150.0 6153.2 4984.5 5818.6 5963.4 6014.3 6023.7 6078.0 6121.8 6134.9 6164.2 6172.4 5787.4 5986.1 6053.7 6082.8 6112.3 05:40:06 INFO - PROCESS | 1671 | 05:40:06 INFO - PROCESS | 1671 | [#1] ext.html Cycles:180 Average:69055.53 Median:39662.20 stddev:153990.13 (388.3%) stddev-sans-first:154336.14 05:40:06 INFO - PROCESS | 1671 | Values: 520.1 523.5 532.7 566.3 611.9 27731.9 39110.0 39281.2 39300.0 39302.2 42775.8 43311.6 43541.8 43615.3 43879.1 9483.3 9749.7 9757.8 9766.6 9767.7 9603.2 9657.4 9690.7 9756.2 9757.4 9673.0 9735.0 9799.6 9821.7 9825.1 9589.1 9733.9 9771.3 9789.8 9804.2 9595.1 9678.1 9691.5 9707.7 9714.2 31011.7 39162.6 39379.6 39663.4 39767.2 3297.5 3339.5 3361.3 3589.9 3736.8 27596.4 27780.7 29307.9 29443.6 29826.7 2725.3 2728.8 2746.2 2769.6 2791.1 466204.7 468603.0 471919.0 472921.0 474857.0 36158.5 63107.1 63221.1 63657.4 63743.4 31836.8 35583.3 36316.0 36375.5 36491.7 15442.6 15691.0 15756.4 15788.9 15798.4 12928.4 13222.7 13276.7 13290.0 13335.7 8133.7 8314.3 8372.5 8374.9 8435.8 3395.9 3429.7 3438.1 3439.4 3459.5 804.2 814.8 838.3 895.5 904.4 25578.4 25578.7 25809.3 26051.6 26307.6 871.0 871.4 902.3 923.2 996.9 10237.7 13501.3 15901.8 16203.5 16423.9 624975.0 628412.7 646581.0 646671.0 648049.0 63496.7 63553.7 63676.0 63891.7 63977.0 1498.5 1500.6 1505.1 1512.2 1515.7 29889.9 31001.4 31111.4 31119.7 31448.5 52634.5 53962.7 53966.7 54581.7 54645.9 594545.0 598740.0 603262.0 604035.0 605222.0 22262.8 27420.7 43259.0 44137.8 45095.1 43866.1 43994.8 44086.6 44494.0 44808.3 29249.1 31643.8 37071.3 38276.8 39661.0 35434.5 35655.5 35783.3 36072.1 36357.3 36013.5 38205.0 38957.0 39012.6 39558.0 27964.6 29995.0 32153.2 32338.5 32475.8 25254.9 25363.7 25474.0 25652.8 25681.0 05:40:06 INFO - PROCESS | 1671 | 05:40:06 INFO - PROCESS | 1671 | [#2] jquery.html Cycles:180 Average:34388.27 Median:398.18 stddev:102280.83 (25687.1%) stddev-sans-first:102535.91 05:40:06 INFO - PROCESS | 1671 | Values: 394.7 395.9 400.5 412.4 414.2 14763.7 20190.2 20402.6 20461.7 20780.7 20021.1 20183.9 20355.9 20378.2 21079.5 5646.8 5919.9 6130.3 6176.3 6200.6 5918.2 5975.3 5993.1 6074.2 6092.8 5754.6 5856.5 5975.3 6032.0 6038.3 5827.4 5904.9 5921.1 5923.6 5968.1 4768.9 5140.6 5221.7 5259.2 5262.6 11120.7 11213.8 13123.8 13234.6 13242.5 1606.9 1965.7 2000.6 2007.3 2190.0 9779.3 9893.2 10324.8 12134.6 15011.5 1734.1 1746.3 1751.7 1788.8 1789.8 108522.9 109347.0 113672.0 113707.0 113895.3 11595.4 11704.5 11765.8 12027.5 12365.1 15396.4 15489.2 15544.2 15974.9 15980.2 7180.8 7331.4 8924.4 8944.3 9117.3 8869.0 9037.9 9039.6 9061.7 9115.1 4849.5 4860.6 4862.1 4941.1 4962.9 1974.2 2021.1 2029.4 2102.1 2130.2 726.9 731.8 759.4 793.0 851.7 16896.8 17274.3 18201.3 18279.2 18358.9 775.5 803.8 829.3 833.1 880.9 9280.5 9707.6 9711.4 9758.6 9817.1 506880.0 598475.0 639900.8 654016.0 657911.0 123604.0 124798.0 125285.0 128035.0 132052.0 1067.1 1073.3 1093.6 1095.5 1100.2 11648.8 14529.3 15429.4 16211.6 16246.8 37972.4 50115.7 51322.5 51865.5 52469.5 74414.5 76232.9 77055.6 77988.0 78369.2 8003.1 8105.3 8286.9 8338.1 8475.1 9232.0 12546.3 12732.8 12975.3 12977.7 8689.7 9605.2 10436.3 10476.3 10610.7 10879.5 10895.8 11233.8 11244.6 11384.9 10217.3 10489.5 10548.6 10728.1 10885.8 6271.5 6305.1 6787.8 7526.1 8256.9 8906.5 9989.0 10139.7 10252.2 10306.5 05:40:06 INFO - PROCESS | 1671 | 05:40:06 INFO - PROCESS | 1671 | [#3] mootools.html Cycles:180 Average:5551.21 Median:4745.36 stddev:3126.74 (65.9%) stddev-sans-first:3119.64 05:40:06 INFO - PROCESS | 1671 | Values: 1360.5 1579.8 1665.0 1765.0 1920.3 6560.8 6992.5 7469.9 8628.0 8876.2 8651.6 8655.3 8756.1 8782.3 8783.6 2449.5 2466.3 2472.6 2476.4 2492.9 2414.2 2492.3 2492.9 2495.7 2504.9 2443.2 2448.5 2477.7 2497.0 2500.3 2436.1 2441.0 2462.1 2475.5 2477.1 2440.3 2470.2 2472.4 2483.2 2487.3 7053.5 8883.3 8923.2 8944.3 9055.8 5296.3 5322.0 5409.8 5474.3 5504.5 3051.5 3471.4 3571.8 3590.3 3616.5 1030.1 1031.3 1042.5 1043.8 1051.0 11371.4 11504.8 11671.2 11679.8 11937.3 3855.9 4542.8 4580.0 4584.4 4669.6 9183.9 9191.3 9277.0 9284.9 9296.0 5272.8 5293.1 5294.3 5308.2 5312.6 4404.8 4826.2 4882.9 4957.2 4958.9 3887.6 3890.7 3945.6 3970.9 3987.2 1213.3 1229.3 1231.3 1240.4 1242.6 856.2 863.6 863.9 866.7 880.9 4667.9 5515.2 5571.6 5610.6 5632.7 403.9 414.8 459.6 985.0 1006.4 3362.5 3455.5 3607.2 3704.9 3748.5 10702.2 10749.4 10953.9 10965.3 11054.4 10318.2 10780.6 10828.4 10894.4 10903.2 6609.2 8298.2 8417.5 8424.6 8480.5 4426.9 5438.7 5505.6 5537.2 5828.4 9167.8 9310.4 9499.1 9536.2 9578.6 9610.0 9805.4 9865.9 9955.2 9955.5 6706.0 7147.4 7193.5 7349.6 7421.0 8023.3 8038.2 8095.4 8158.4 8198.7 7155.9 7308.5 7327.1 7407.9 7427.1 6129.5 7092.2 7191.6 7211.4 7259.8 6617.5 6639.9 6697.7 6699.3 6711.8 4575.0 4596.1 4606.1 4610.2 4672.4 4660.3 4664.1 4692.3 4743.3 4747.4 05:40:06 INFO - PROCESS | 1671 | 05:40:06 INFO - PROCESS | 1671 | [#4] prototype.html Cycles:180 Average:5802.46 Median:6654.42 stddev:1867.20 (28.1%) stddev-sans-first:1825.80 05:40:06 INFO - PROCESS | 1671 | Values: 277.0 322.3 325.0 328.4 334.8 6755.9 6772.8 6773.9 6792.1 6798.4 6671.4 6674.1 6684.5 6699.3 6706.6 4166.8 4692.1 4784.6 4869.0 4892.1 4681.4 4828.5 4917.6 4939.9 4947.5 4876.9 4893.3 4901.5 4906.0 4909.8 4883.8 4902.4 4909.6 4933.9 4950.6 4208.9 4209.0 4222.2 4231.0 4231.3 6207.9 6350.1 6370.7 6399.9 6474.4 4586.4 5657.5 5694.4 5701.7 5736.7 7103.0 7121.5 7130.6 7147.2 7178.7 4615.4 4628.3 4628.3 4635.9 4638.3 7384.5 7539.0 7576.5 7576.7 7614.7 4473.9 4672.9 4675.5 4689.6 4701.2 4759.5 4788.2 4789.2 4789.8 4810.0 5693.9 5699.1 5709.6 5729.2 5731.2 6453.7 7212.6 7222.7 7238.2 7241.1 1569.8 1654.1 1654.3 1655.6 1657.2 1006.9 1041.2 1050.5 1057.1 1065.2 5398.4 6640.8 6668.0 6681.2 6704.2 5505.3 5657.6 5912.0 7383.1 7424.7 6627.8 6771.1 6785.6 6798.5 6808.4 3302.3 3930.5 3970.9 3971.7 3983.4 6229.5 7017.7 7256.0 7831.5 7831.9 6901.6 7311.6 7430.6 7737.9 7786.7 6316.9 7699.8 7703.5 7722.8 7733.0 5952.5 7439.8 7492.0 7498.9 7504.3 7337.2 7376.0 7379.6 7396.6 7420.7 6312.2 7452.3 7748.8 7824.3 7834.8 4661.1 4691.1 4736.0 4743.6 4746.9 7490.6 7496.1 7503.4 7515.0 7521.2 7235.7 7237.5 7254.3 7280.3 7280.8 6955.0 7200.9 7241.2 7244.2 7265.1 6993.0 7036.2 7043.8 7053.6 7085.0 7191.1 7201.9 7230.0 7234.9 7260.5 7019.6 7080.7 7086.9 7103.0 7117.4 05:40:06 INFO - PROCESS | 1671 | 05:40:06 INFO - PROCESS | 1671 | [#5] yui.html Cycles:180 Average:17782.02 Median:37055.36 stddev:25478.37 (68.8%) stddev-sans-first:25517.54 05:40:06 INFO - PROCESS | 1671 | Values: 693.7 734.1 736.8 742.3 743.7 13234.1 14988.2 15153.2 15245.0 15271.7 12172.8 13905.2 13950.0 13956.2 13967.1 715.8 902.6 907.8 921.4 921.5 686.6 765.8 845.5 901.6 903.0 961.6 972.7 973.2 975.4 977.9 930.9 954.1 962.4 964.5 965.5 681.7 693.0 695.0 706.1 709.5 14721.0 14755.9 14804.3 14841.8 14856.4 12145.0 12480.6 12550.1 12600.9 12699.2 9958.9 10298.6 10581.4 10636.8 10859.0 3383.0 3442.1 3448.2 3499.4 3681.1 67332.0 88659.1 88902.0 89396.7 89692.0 8981.7 9101.0 9199.9 9254.2 9386.6 20977.9 21028.2 21132.6 21146.9 21263.9 7479.0 7502.7 7542.4 7554.7 7641.2 4024.2 4036.9 4096.5 4142.9 4197.7 3543.1 3575.7 3577.7 3590.2 3600.6 636.5 643.6 647.3 658.5 659.5 390.5 554.6 563.6 570.9 581.3 6664.5 7318.7 7680.0 7762.1 7894.0 459.1 582.6 583.4 584.8 589.2 3478.5 3527.6 3597.6 3655.6 3690.9 83779.0 84195.6 84234.6 85246.1 85812.7 78364.4 79047.2 79915.6 80097.6 80284.0 68488.0 68879.7 68890.0 70736.3 70993.6 5293.4 6126.2 6755.1 7286.7 7298.7 48423.0 48718.0 49068.0 49126.5 49880.0 62406.7 65702.0 66380.3 66868.0 67355.7 9931.6 9953.8 10020.3 10127.5 10135.4 10600.8 10758.9 10765.1 10879.3 11110.2 8199.0 9611.7 9659.4 9661.4 9848.2 9851.5 10201.7 10218.9 10244.0 10449.5 9468.5 9487.4 9559.0 9631.6 10003.0 8460.7 8794.6 8833.0 8854.7 8927.6 8165.4 8356.5 8444.2 8472.4 8556.1 05:40:06 INFO - PROCESS | 1671 | -------- Summary: end -------- 05:40:06 INFO - PROCESS | 1671 | 05:40:07 INFO - TEST-INFO | 1671: exit 0 05:40:07 INFO - TEST-OK | dromaeo_css | took 1111935ms 05:40:07 INFO - TEST-START | kraken 05:40:07 INFO - operating with platform_type : mac_ 05:40:07 INFO - Initialising browser for kraken test... 05:40:07 INFO - TEST-INFO | started process 1683 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/tmpz_hPWj/profile http://localhost:49273/getInfo.html) 05:40:10 INFO - PROCESS | 1683 | __metrics Screen width/height:1600/1200 05:40:10 INFO - PROCESS | 1683 | colorDepth:24 05:40:10 INFO - PROCESS | 1683 | Browser inner width/height: 1024/689 05:40:10 INFO - PROCESS | 1683 | __metrics 05:40:10 ERROR - PROCESS | 1683 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 05:40:10 INFO - TEST-INFO | 1683: exit 0 05:40:10 INFO - Browser initialized. 05:40:10 INFO - Running cycle 1/1 for kraken test... 05:40:10 INFO - TEST-INFO | started process 1686 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/tmpz_hPWj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:40:23 INFO - PROCESS | 1686 | RSS: Main: 221110272 05:40:23 INFO - PROCESS | 1686 | 05:40:26 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection) 05:40:27 INFO - PROCESS | 1686 | RSS: Main: 232816640 05:40:27 INFO - PROCESS | 1686 | 05:40:29 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-dft) 05:40:30 INFO - PROCESS | 1686 | RSS: Main: 241729536 05:40:30 INFO - PROCESS | 1686 | 05:40:33 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-fft) 05:40:34 INFO - PROCESS | 1686 | RSS: Main: 245354496 05:40:34 INFO - PROCESS | 1686 | 05:40:36 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator) 05:40:37 INFO - PROCESS | 1686 | RSS: Main: 247418880 05:40:37 INFO - PROCESS | 1686 | 05:40:39 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:40:39 INFO - PROCESS | 1686 | RSS: Main: 247791616 05:40:39 INFO - PROCESS | 1686 | 05:40:42 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom) 05:40:43 INFO - PROCESS | 1686 | RSS: Main: 247693312 05:40:43 INFO - PROCESS | 1686 | 05:40:46 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate) 05:40:46 INFO - PROCESS | 1686 | RSS: Main: 247902208 05:40:46 INFO - PROCESS | 1686 | 05:40:49 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial) 05:40:50 INFO - PROCESS | 1686 | RSS: Main: 247939072 05:40:50 INFO - PROCESS | 1686 | 05:40:51 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:40:52 INFO - PROCESS | 1686 | RSS: Main: 247320576 05:40:52 INFO - PROCESS | 1686 | 05:40:54 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:40:54 INFO - PROCESS | 1686 | RSS: Main: 247418880 05:40:54 INFO - PROCESS | 1686 | 05:40:57 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:40:57 INFO - PROCESS | 1686 | RSS: Main: 247721984 05:40:57 INFO - PROCESS | 1686 | 05:41:00 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:41:01 INFO - PROCESS | 1686 | RSS: Main: 247840768 05:41:01 INFO - PROCESS | 1686 | 05:41:04 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:41:05 INFO - PROCESS | 1686 | RSS: Main: 247603200 05:41:05 INFO - PROCESS | 1686 | 05:41:06 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49273/tests/kraken/driver.html?testName=ai-astar) 05:41:07 INFO - PROCESS | 1686 | RSS: Main: 249344000 05:41:07 INFO - PROCESS | 1686 | 05:41:07 INFO - PROCESS | 1686 | __start_tp_report 05:41:07 INFO - PROCESS | 1686 | _x_x_mozilla_page_load 05:41:07 INFO - PROCESS | 1686 | _x_x_mozilla_page_load_details 05:41:07 INFO - PROCESS | 1686 | |i|pagename|runs| 05:41:07 INFO - PROCESS | 1686 | |0;ai-astar;121;113;116;118;118;132;138;115;117;117 05:41:07 INFO - PROCESS | 1686 | |1;audio-beat-detection;165;131;157;162;136;143;129;154;162;119 05:41:07 INFO - PROCESS | 1686 | |2;audio-dft;201;201;198;198;196;198;197;207;218;279 05:41:07 INFO - PROCESS | 1686 | |3;audio-fft;69;71;79;148;59;57;57;62;129;57 05:41:07 INFO - PROCESS | 1686 | |4;audio-oscillator;80;83;131;77;80;84;121;82;78;124 05:41:07 INFO - PROCESS | 1686 | |5;imaging-gaussian-blur;91;91;91;91;77;128;99;91;94;90 05:41:07 INFO - PROCESS | 1686 | |6;imaging-darkroom;97;118;118;98;98;98;98;97;97;97 05:41:07 INFO - PROCESS | 1686 | |7;imaging-desaturate;91;103;92;89;103;90;90;93;117;76 05:41:07 INFO - PROCESS | 1686 | |8;json-parse-financial;62;66;67;64;66;65;66;82;65;61 05:41:07 INFO - PROCESS | 1686 | |9;json-stringify-tinderbox;69;71;70;77;68;76;79;95;64;75 05:41:07 INFO - PROCESS | 1686 | |10;stanford-crypto-aes;84;97;72;70;96;112;102;79;113;110 05:41:07 INFO - PROCESS | 1686 | |11;stanford-crypto-ccm;151;160;146;122;143;114;144;162;176;172 05:41:07 INFO - PROCESS | 1686 | |12;stanford-crypto-pbkdf2;169;175;169;194;207;169;203;165;227;210 05:41:07 INFO - PROCESS | 1686 | |13;stanford-crypto-sha256-iterative;66;69;66;69;70;68;72;70;68;72 05:41:07 INFO - PROCESS | 1686 | __end_tp_report 05:41:07 INFO - PROCESS | 1686 | __start_cc_report 05:41:07 INFO - PROCESS | 1686 | _x_x_mozilla_cycle_collect,1099 05:41:07 INFO - PROCESS | 1686 | __end_cc_report 05:41:07 INFO - PROCESS | 1686 | __startTimestamp1462884067088__endTimestamp 05:41:07 INFO - PROCESS | 1686 | 05:41:07 INFO - PROCESS | 1686 | ------- Summary: start ------- 05:41:07 INFO - PROCESS | 1686 | Number of tests: 14 05:41:07 INFO - PROCESS | 1686 | 05:41:07 INFO - PROCESS | 1686 | [#0] ai-astar Cycles:10 Average:120.50 Median:118.00 stddev:8.05 (6.8%) stddev-sans-first:8.53 05:41:07 INFO - PROCESS | 1686 | Values: 121.0 113.0 116.0 118.0 118.0 132.0 138.0 115.0 117.0 117.0 05:41:07 INFO - PROCESS | 1686 | 05:41:07 INFO - PROCESS | 1686 | [#1] audio-beat-detection Cycles:10 Average:145.80 Median:155.50 stddev:16.36 (10.5%) stddev-sans-first:15.81 05:41:07 INFO - PROCESS | 1686 | Values: 165.0 131.0 157.0 162.0 136.0 143.0 129.0 154.0 162.0 119.0 05:41:07 INFO - PROCESS | 1686 | 05:41:07 INFO - PROCESS | 1686 | [#2] audio-dft Cycles:10 Average:209.30 Median:201.00 stddev:25.36 (12.6%) stddev-sans-first:26.72 05:41:07 INFO - PROCESS | 1686 | Values: 201.0 201.0 198.0 198.0 196.0 198.0 197.0 207.0 218.0 279.0 05:41:07 INFO - PROCESS | 1686 | 05:41:07 INFO - PROCESS | 1686 | [#3] audio-fft Cycles:10 Average:78.80 Median:60.50 stddev:32.60 (53.9%) stddev-sans-first:34.39 05:41:07 INFO - PROCESS | 1686 | Values: 69.0 71.0 79.0 148.0 59.0 57.0 57.0 62.0 129.0 57.0 05:41:07 INFO - PROCESS | 1686 | 05:41:07 INFO - PROCESS | 1686 | [#4] audio-oscillator Cycles:10 Average:94.00 Median:80.00 stddev:21.86 (27.3%) stddev-sans-first:22.59 05:41:07 INFO - PROCESS | 1686 | Values: 80.0 83.0 131.0 77.0 80.0 84.0 121.0 82.0 78.0 124.0 05:41:07 INFO - PROCESS | 1686 | 05:41:07 INFO - PROCESS | 1686 | [#5] imaging-gaussian-blur Cycles:10 Average:94.30 Median:91.00 stddev:13.04 (14.3%) stddev-sans-first:13.77 05:41:07 INFO - PROCESS | 1686 | Values: 91.0 91.0 91.0 91.0 77.0 128.0 99.0 91.0 94.0 90.0 05:41:07 INFO - PROCESS | 1686 | 05:41:07 INFO - PROCESS | 1686 | [#6] imaging-darkroom Cycles:10 Average:101.60 Median:97.50 stddev:8.66 (8.9%) stddev-sans-first:9.02 05:41:07 INFO - PROCESS | 1686 | Values: 97.0 118.0 118.0 98.0 98.0 98.0 98.0 97.0 97.0 97.0 05:41:07 INFO - PROCESS | 1686 | 05:41:07 INFO - PROCESS | 1686 | [#7] imaging-desaturate Cycles:10 Average:94.40 Median:90.00 stddev:10.98 (12.2%) stddev-sans-first:11.57 05:41:07 INFO - PROCESS | 1686 | Values: 91.0 103.0 92.0 89.0 103.0 90.0 90.0 93.0 117.0 76.0 05:41:07 INFO - PROCESS | 1686 | 05:41:07 INFO - PROCESS | 1686 | [#8] json-parse-financial Cycles:10 Average:66.40 Median:66.00 stddev:5.80 (8.8%) stddev-sans-first:5.93 05:41:07 INFO - PROCESS | 1686 | Values: 62.0 66.0 67.0 64.0 66.0 65.0 66.0 82.0 65.0 61.0 05:41:07 INFO - PROCESS | 1686 | 05:41:07 INFO - PROCESS | 1686 | [#9] json-stringify-tinderbox Cycles:10 Average:74.40 Median:75.50 stddev:8.59 (11.4%) stddev-sans-first:8.89 05:41:07 INFO - PROCESS | 1686 | Values: 69.0 71.0 70.0 77.0 68.0 76.0 79.0 95.0 64.0 75.0 05:41:07 INFO - PROCESS | 1686 | 05:41:07 INFO - PROCESS | 1686 | [#10] stanford-crypto-aes Cycles:10 Average:93.50 Median:75.50 stddev:16.33 (21.6%) stddev-sans-first:16.96 05:41:07 INFO - PROCESS | 1686 | Values: 84.0 97.0 72.0 70.0 96.0 112.0 102.0 79.0 113.0 110.0 05:41:07 INFO - PROCESS | 1686 | 05:41:07 INFO - PROCESS | 1686 | [#11] stanford-crypto-ccm Cycles:10 Average:149.00 Median:155.50 stddev:19.93 (12.8%) stddev-sans-first:21.13 05:41:07 INFO - PROCESS | 1686 | Values: 151.0 160.0 146.0 122.0 143.0 114.0 144.0 162.0 176.0 172.0 05:41:07 INFO - PROCESS | 1686 | 05:41:07 INFO - PROCESS | 1686 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:188.80 Median:198.50 stddev:22.11 (11.1%) stddev-sans-first:22.27 05:41:07 INFO - PROCESS | 1686 | Values: 169.0 175.0 169.0 194.0 207.0 169.0 203.0 165.0 227.0 210.0 05:41:07 INFO - PROCESS | 1686 | 05:41:07 INFO - PROCESS | 1686 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.00 Median:69.50 stddev:2.11 (3.0%) stddev-sans-first:1.94 05:41:07 INFO - PROCESS | 1686 | Values: 66.0 69.0 66.0 69.0 70.0 68.0 72.0 70.0 68.0 72.0 05:41:07 INFO - PROCESS | 1686 | -------- Summary: end -------- 05:41:07 INFO - PROCESS | 1686 | 05:41:07 INFO - TEST-INFO | 1686: exit 0 05:41:07 INFO - TEST-OK | kraken | took 60379ms 05:41:07 INFO - SUITE-END | took 1172s 05:41:08 INFO - Completed test suite (00:19:32) 05:41:08 INFO - javascript benchmark 05:41:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2871.6984716604743, 3385.77992430492, 3419.384510788059, 3466.791992480453, 3480.77374970627, 4737.43150821901, 5714.857128571788, 5722.79970201043, 5730.255066841315, 5788.189653448515, 4495.79768910399, 5202.4912775295215, 5252.947470525303, 5632.915506267399, 5794.420557944214, 5165.509276618715, 5211.244369566411, 5263.473652634744, 5279.973600131994, 5308.867278318035, 5088.720120393377, 5235.424103348648, 5262.710550919698, 5336.4930331618625, 5353.250544923714, 5196.454372290912, 5201.895962080756, 5203.0, 5204.9739751301195, 5207.973960130213, 5109.744512774347, 5186.0, 5209.426963034063, 5209.7916083356995, 5220.112580861225, 5169.663971841818, 5239.921401178985, 5259.105951988133, 5286.36563612369, 5307.840764777063, 5695.117256825199, 5714.657120572779, 5727.570432217601, 5738.110592858114, 5763.855903602402, 5948.048312270016, 6156.692165391757, 6166.0, 6265.248170219603, 6291.96854015727, 6198.287196972361, 6258.812235632939, 6264.686765661699, 6268.091126786636, 6270.153529273535, 5828.300603927556, 5840.766369345221, 5881.5882888198275, 5884.205632239636, 5914.201582786355, 6184.721687524072, 6193.969030154821, 6245.094461303085, 6290.874182516324, 6319.0, 6130.938690613037, 6165.568410211242, 6177.413145751147, 6186.505079593621, 6197.845053873689, 6278.246610406689, 6283.434490895841, 6303.180586523723, 6316.62100273985, 6330.873382532323, 6156.784512542129, 6236.0, 6236.472064344264, 6248.4688801451475, 6276.497880169575, 6150.815475535741, 6193.969030154911, 6230.875382492415, 6236.251649802053, 6242.282137554149, 4864.124457597666, 4970.378702662168, 4988.226824842155, 5006.64953453262, 5009.373828271466, 4387.802548885308, 4400.779961001937, 4443.666724995638, 4452.1763473757455, 4498.820047198075, 5872.471477567039, 5872.853178670567, 5888.941110588839, 5891.793787217427, 5933.3769954154195, 6148.508119350441, 6220.346863579259, 6251.593646412968, 6284.088807122942, 6288.434040936338, 5490.81947381307, 5724.542036637059, 5797.942020579824, 5823.475887170175, 5838.766449341979, 5379.973100134474, 5394.838154855361, 5409.323834520685, 5415.079436495726, 5434.91847622286, 6317.336679648571, 6412.038194270896, 6415.166028416463, 6415.262244841903, 6492.935070649233, 6168.691565421801, 6205.131281620486, 6236.656983865931, 6253.687315634291, 6280.183576135073, 5376.435474275301, 5549.250851135206, 6200.744609080269, 6360.141380913703, 6380.968095159495, 6067.696615169136, 6092.177556029879, 6167.136600875791, 6176.4441200292185, 6189.0, 6189.876202476015, 6261.311255761953, 6265.373462653881, 6285.182926219745, 6287.497000240061, 6130.111133885473, 6160.137580738789, 6175.505959523317, 6197.1324014638885, 6204.038374052029, 5108.948910510847, 6112.388761123852, 6128.050152226234, 6150.0, 6153.200083989232, 4984.476629953802, 5818.592698511233, 5963.403659634002, 6014.278286605635, 6023.698815059317, 6077.969610152098, 6121.785737499166, 6134.907976380268, 6164.229471316085, 6172.382761723792, 5787.363390027178, 5986.102084687332, 6053.727582258897, 6082.787102451393, 6112.32764395925], "value": 5689.604725775694, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [520.1313805944073, 523.4922125538227, 532.7336331834083, 566.3091028944688, 611.8710978245136, 27731.861340693293, 39110.02224944378, 39281.214375712465, 39300.0, 39302.21395572094, 42775.78612106943, 43311.56688433115, 43541.78229108858, 43615.3457698134, 43879.12241755163, 9483.336166468367, 9749.658761943334, 9757.756056098619, 9766.609335626566, 9767.706968790912, 9603.231741460666, 9657.420554766737, 9690.66082687106, 9756.219502439822, 9757.41455512668, 9672.951635241849, 9735.0, 9799.608015679365, 9821.65624203153, 9825.066618671215, 9589.136977672042, 9733.902660973406, 9771.267154963336, 9789.755256118582, 9804.215662747, 9595.13643772064, 9678.128968392808, 9691.466969316685, 9707.70876873695, 9714.174295184917, 31011.68988310133, 39162.629307974086, 39379.606203938165, 39663.40504892429, 39767.20465590701, 3297.4888892221743, 3339.5157702133056, 3361.310931259086, 3589.856405743767, 3736.7757934524016, 27596.448071038667, 27780.72219277822, 29307.853460732775, 29443.558346624817, 29826.70173298261, 2725.3459169799303, 2728.7816974641983, 2746.2036009557205, 2769.5568709006666, 2791.1347482280557, 466204.66897665634, 468603.0, 471919.0, 472920.99999999657, 474857.0, 36158.45762313568, 63107.05339419912, 63221.051684224774, 63657.36342636607, 63743.36256637374, 31836.84081579577, 35583.288334233686, 36316.0, 36375.45436818397, 36491.72278970223, 15442.6139346515, 15691.0, 15756.369745210293, 15788.921055394649, 15798.36806527726, 12928.418221180069, 13222.669433264246, 13276.73446531064, 13290.003249756306, 13335.733285334432, 8133.715319963773, 8314.334853211729, 8372.53951032687, 8374.916250837536, 8435.831283374298, 3395.932081358359, 3429.725621950238, 3438.123278564, 3439.398105331557, 3459.5329630500064, 804.2359758229588, 814.8144449825489, 838.279079991207, 895.4761464543316, 904.4392476664378, 25578.36054098625, 25578.74421255801, 25809.310871979214, 26051.609225861626, 26307.605385918843, 871.0, 871.4117970369975, 902.3457992955055, 923.1553128886993, 996.9152622027209, 10237.692869213935, 13501.324933753272, 15901.761473577906, 16203.513894583182, 16423.921301836937, 624975.0, 628412.7214275368, 646581.0, 646671.0, 648049.0, 63496.68251658712, 63553.72892542123, 63676.0, 63891.680541597925, 63977.0, 1498.4980031689288, 1500.5648361975122, 1505.1495904813746, 1512.2287633307092, 1515.658976730249, 29889.850550747105, 31001.379972399973, 31111.377772444877, 31119.68880311168, 31448.528272076393, 52634.473655262955, 53962.650933726974, 53966.73016634892, 54581.727091364286, 54645.90708185894, 594545.0, 598740.0, 603262.0, 604035.0, 605222.0, 22262.77737222607, 27420.725792741818, 43259.0, 44137.77931110324, 45095.0980980372, 43866.12267754563, 43994.78002609967, 44086.55913440953, 44494.0, 44808.32787508255, 29249.122526324245, 31643.84178079095, 37071.25857482889, 38276.80861595674, 39661.0, 35434.46848297226, 35655.46516802198, 35783.284334312644, 36072.098197545274, 36357.27285454329, 36013.45979810358, 38205.0, 38957.02607434837, 39012.609873900896, 39558.0, 27964.60176991183, 29994.95017674371, 32153.196170095936, 32338.514922276656, 32475.83762081269, 25254.873725631252, 25363.746362536876, 25474.0, 25652.845621946683, 25681.0], "value": 18598.242021136626, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [394.74341677909354, 395.90894094358276, 400.4513816071982, 412.42672684967897, 414.17165668662676, 14763.704725905476, 20190.192392304307, 20402.59194816103, 20461.693074603856, 20780.688289675647, 20021.099050542725, 20183.899080504576, 20355.898220508912, 20378.184872605078, 21079.47301317468, 5646.774129034834, 5919.940800591982, 6130.325664176937, 6176.258848938133, 6200.627962322253, 5918.201042859224, 5975.312839023524, 5993.130996005577, 6074.210352654149, 6092.786752463665, 5754.568407369443, 5856.502197313233, 5975.342712301644, 6032.0, 6038.305594856582, 5827.41725827416, 5904.911426328608, 5921.082232254007, 5923.614965027259, 5968.1346204800275, 4768.925517399942, 5140.588752899759, 5221.660592061504, 5259.211118332242, 5262.578993680535, 11120.666380008613, 11213.831792523119, 13123.803142952864, 13234.602961911158, 13242.470301187941, 1606.8794840386902, 1965.6560101982095, 2000.6098810731912, 2007.2573147935357, 2190.0, 9779.315447918649, 9893.159081478083, 10324.845127323097, 12134.5752898649, 15011.549653510303, 1734.054940057659, 1746.2752957522614, 1751.7459968304652, 1788.7764029496313, 1789.8299661532133, 108522.91477085208, 109347.0, 113672.0, 113707.0, 113895.2915706265, 11595.362255076052, 11704.531818727155, 11765.764684706259, 12027.518899244107, 12365.07261955357, 15396.384144634088, 15489.225538723018, 15544.222788860512, 15974.92012539953, 15980.200989950456, 7180.820479488055, 7331.413486921034, 8924.393609150415, 8944.329175311877, 9117.31620128493, 8869.0, 9037.909620903838, 9039.593218305192, 9061.68284110053, 9115.134062264075, 4849.515048495122, 4860.562549370503, 4862.051899879561, 4941.135301322254, 4962.851114466572, 1974.2004488182308, 2021.110711287029, 2029.3506078054882, 2102.075086961732, 2130.179880745924, 726.8764310067302, 731.8426538294292, 759.3583422008394, 793.0166593424228, 851.6806197675747, 16896.817222794532, 17274.309027639007, 18201.27194912215, 18279.177437015365, 18358.908205459153, 775.5308038636656, 803.8030682482829, 829.2536716954789, 833.0877688930611, 880.8678698195193, 9280.489573073546, 9707.611695532241, 9711.417314961125, 9758.609655613696, 9817.067378599022, 506880.0, 598475.0, 639900.8004959945, 654016.0, 657911.0, 123604.0, 124798.0, 125285.0, 128035.0, 132052.0, 1067.1036329483115, 1073.3184427888348, 1093.5680406239492, 1095.5124969388705, 1100.2353364411656, 11648.76702465929, 14529.34617942174, 15429.382824687336, 16211.594710132342, 16246.837531624533, 37972.43041354327, 50115.74942125266, 51322.486775133264, 51865.48134518607, 52469.47530524799, 74414.51170976443, 76232.85650715354, 77055.61472192603, 77988.0, 78369.21630783619, 8003.079645840803, 8105.311048560939, 8286.917130828615, 8338.082810890675, 8475.067742548437, 9232.0, 12546.309952952675, 12732.809007865075, 12975.286359250333, 12977.740445191232, 8689.652413903374, 9605.18355939725, 10436.269461137648, 10476.266661333633, 10610.734731631772, 10879.456027198767, 10895.836562451728, 11233.775324493628, 11244.55021799119, 11384.943075284902, 10217.284790064625, 10489.52797124116, 10548.578056877639, 10728.088112510211, 10885.78228435411, 6271.498280137487, 6305.148804911279, 6787.830304242236, 7526.134494533198, 8256.917430825855, 8906.465612063297, 9989.001099889952, 10139.695809125738, 10252.23108266868, 10306.536205870903], "value": 9096.869826475113, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1360.4558176729306, 1579.8183208930973, 1664.9667006659859, 1764.964700705986, 1920.2894928876317, 6560.803175904719, 6992.475564332676, 7469.887951680722, 8627.956860215705, 8876.201141897229, 8651.56742162891, 8655.264302534291, 8756.080611535777, 8782.341324400664, 8783.604737786798, 2449.4856080223176, 2466.3217615155886, 2472.6414669872856, 2476.393283645509, 2492.9376765580914, 2414.1912459326145, 2492.3395300245447, 2492.9252122436355, 2495.7254701982765, 2504.912328068518, 2443.1693224303735, 2448.485817978227, 2477.665515155458, 2497.0, 2500.2624225853324, 2436.147348428045, 2441.0113903868873, 2462.076721229539, 2475.5420247254137, 2477.0587176872855, 2440.255722004788, 2470.1848390031246, 2472.394263405477, 2483.1681386735286, 2487.2786891801356, 7053.470989675795, 8883.333749968713, 8923.15230053144, 8944.329175311812, 9055.777470041527, 5296.3379577552805, 5322.0, 5409.756560954766, 5474.315710536183, 5504.532114770209, 3051.4812481878134, 3471.357798807229, 3571.767835090711, 3590.2819436112622, 3616.5298511193378, 1030.0626429948786, 1031.3296357367772, 1042.5204405973261, 1043.7651528406125, 1050.9700493516398, 11371.374574398405, 11504.827427588592, 11671.241369310968, 11679.766404671944, 11937.28376297425, 3855.9228815423535, 4542.841000564964, 4579.977100114478, 4584.4498660161, 4669.603083737852, 9183.862242066374, 9191.31065170115, 9276.953615231881, 9284.907150928539, 9295.953520232355, 5272.841814745564, 5293.100172970604, 5294.311739473844, 5308.203769434538, 5312.601554883399, 4404.801783919731, 4826.227803551415, 4882.926756098661, 4957.206846904478, 4958.925616115761, 3887.5723670396233, 3890.688744900401, 3945.6448919597374, 3970.9205815884097, 3987.2224916141654, 1213.2720367779261, 1229.2501574040007, 1231.2797013746879, 1240.3612139748036, 1242.6396345059832, 856.1908995998722, 863.5984267315729, 863.926566241864, 866.7399780066081, 880.9163129502687, 4667.8599642010795, 5515.172724091338, 5571.554275657938, 5610.635308704921, 5632.718364081779, 403.9071013666873, 414.81540714382413, 459.5863722649642, 984.9901500984898, 1006.3710181136789, 3362.4788157835574, 3455.51622772812, 3607.2424790793702, 3704.870329538453, 3748.493953316322, 10702.197335199891, 10749.40878251688, 10953.945230273797, 10965.287256328473, 11054.447277636085, 10318.1745460363, 10780.568777248978, 10828.350298982086, 10894.40080795548, 10903.182261330432, 6609.239937407164, 8298.21166989135, 8417.494950302878, 8424.578771061422, 8480.53357065355, 4426.867193984185, 5438.728063596884, 5505.587080968863, 5537.197106419627, 5828.388019257917, 9167.77080572991, 9310.394824336396, 9499.050094990722, 9536.189423899046, 9578.616855325708, 9609.99095095005, 9805.411675299505, 9865.901340986498, 9955.20358371343, 9955.452450115314, 6705.966470167618, 7147.356737893615, 7193.460490463341, 7349.595772232365, 7420.962895185489, 8023.318017968538, 8038.15599362082, 8095.39284553649, 8158.388120890838, 8198.713045043394, 7155.892661610184, 7308.524945878501, 7327.120745510573, 7407.888881666671, 7427.071616047994, 6129.509639228761, 7092.219855815753, 7191.604461754654, 7211.3870321023305, 7259.782206533812, 6617.536772425884, 6639.933600663931, 6697.732090716317, 6699.330066993261, 6711.765088221889, 4575.016371480147, 4596.080783843177, 4606.101810146989, 4610.216263235191, 4672.415948006499, 4660.324252983366, 4664.067186562633, 4692.343071969859, 4743.288506724019, 4747.430308363018], "value": 4451.317429904763, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [276.98338099714016, 322.3215132146831, 324.9967500324996, 328.3793630039225, 334.824217285925, 6755.932440675591, 6772.830679233021, 6773.8983915241315, 6792.083068785712, 6798.422134118599, 6671.432928201097, 6674.06563081169, 6684.465242780578, 6699.330066993298, 6706.631135287557, 4166.791660416982, 4692.108499385115, 4784.5693887550115, 4869.026194761062, 4892.143874821892, 4681.368015317939, 4828.454894433783, 4917.557419832215, 4939.876503087434, 4947.480514545975, 4876.878078048059, 4893.290472881448, 4901.53435423636, 4906.018796240766, 4909.754512274372, 4883.804647814083, 4902.436219834711, 4909.558139767438, 4933.901321973576, 4950.628702847301, 4208.936865947013, 4209.031922657806, 4222.1766755482695, 4231.026863821307, 4231.344141658048, 6207.875842483125, 6350.110984462179, 6370.745170193187, 6399.87200255997, 6474.449671777905, 4586.403767510235, 5657.519110875581, 5694.430556944314, 5701.657900525982, 5736.713164341766, 7102.964485177593, 7121.465890058266, 7130.6078165700865, 7147.2138064812825, 7178.748743793994, 4615.446146462446, 4628.259478483426, 4628.32889231063, 4635.907281854378, 4638.304254361839, 7384.483086183969, 7539.0, 7576.469647124703, 7576.696932122653, 7614.657340419694, 4473.865784026484, 4672.906541869212, 4675.5324467553655, 4689.601383882408, 4701.177293973541, 4759.547842954914, 4788.23388257884, 4789.209780386267, 4789.832355867528, 4809.989902120594, 5693.914591281134, 5699.059655156935, 5709.628874123168, 5729.197912292282, 5731.197632331477, 6453.676996215764, 7212.639368031682, 7222.7472038479455, 7238.167610724834, 7241.094863142107, 1569.8351673074392, 1654.081984498615, 1654.3299963514796, 1655.5695519165104, 1657.1879778908249, 1006.9496525173712, 1041.1670663469345, 1050.469512895998, 1057.138432177788, 1065.1957771882335, 5398.352197736297, 6640.83397915056, 6668.0, 6681.231658359351, 6704.229013663394, 5505.339359276913, 5657.632253903483, 5911.970440147772, 7383.114026316876, 7424.665890034961, 6627.785524020846, 6771.119754431991, 6785.592864428051, 6798.484560712568, 6808.38724514796, 3302.3230237801195, 3930.469386632768, 3970.940435893464, 3971.72197946141, 3983.4423180754716, 6229.501639868799, 7017.736807374619, 7256.0, 7831.530108193527, 7831.921680783234, 6901.62041087735, 7311.597862117528, 7430.6284685765495, 7737.883931741028, 7786.727464538714, 6316.905246421307, 7699.846003079907, 7703.537787732754, 7722.768316950501, 7732.9613351934, 5952.464278214981, 7439.8140046499275, 7492.0, 7498.887516687145, 7504.324610784837, 7337.156227033959, 7375.963120184365, 7379.560985607755, 7396.55620662762, 7420.6660700269695, 6312.242530896322, 7452.292032256927, 7748.8450230996195, 7824.295813376823, 7834.804129896798, 4661.137689527448, 4691.085238378483, 4735.958089220366, 4743.620510359094, 4746.931940313402, 7490.550566966, 7496.062992125984, 7503.362214211853, 7515.0, 7521.210272921265, 7235.746748863765, 7237.493375463667, 7254.274572542915, 7280.271972802678, 7280.817979550342, 6954.965225173842, 7200.855982880418, 7241.239669834802, 7244.225164834649, 7265.09186351706, 6992.965035174791, 7036.19083805369, 7043.788686339418, 7053.647317634201, 7085.0435191248525, 7191.101112360955, 7201.927980720126, 7230.0, 7234.891476627749, 7260.4554658399775, 7019.649017549, 7080.681369338495, 7086.893696594659, 7102.964485177541, 7117.395021423237], "value": 5172.55530416143, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [693.732912828561, 734.1447213995696, 736.7752835385205, 742.2503271695585, 743.6653505922333, 13234.139780914258, 14988.175650339226, 15153.166575838324, 15245.009074410156, 15271.694566108685, 12172.81740773889, 13905.165690058604, 13950.02349835512, 13956.162630242192, 13967.092139010958, 715.7530651925077, 902.62992173209, 907.7866701325183, 921.3734660430904, 921.5162039929057, 686.5743239191684, 765.7740966414921, 845.4631309118723, 901.6168128545374, 903.0472851142042, 961.5769061612868, 972.747085757701, 973.1533565797766, 975.4147511493119, 977.8728765260506, 930.8836395450569, 954.1269738189549, 962.4177372689562, 964.4502633498909, 965.5461932891529, 681.6830173969128, 693.0435998322283, 695.0443140681563, 706.1385110165589, 709.4572651921285, 14721.043132196373, 14755.852441475556, 14804.333804978802, 14841.777373339408, 14856.405743770236, 12145.0, 12480.625581232487, 12550.121491495598, 12600.873991260061, 12699.238045717286, 9958.900410995942, 10298.588056477733, 10581.418022008786, 10636.787264254672, 10859.0, 3383.035834787074, 3442.0534353052863, 3448.2413868948793, 3499.3701133795907, 3681.1349332906743, 67331.99002014974, 88659.11340886573, 88901.99999999936, 89396.65905011431, 89692.0, 8981.685641002534, 9101.02991907419, 9199.922539512329, 9254.16712495865, 9386.62453501866, 20977.89511052435, 21028.15887364519, 21132.577348452945, 21146.89426552888, 21263.893680531808, 7478.962605186939, 7502.737404190827, 7542.434317426215, 7554.660040298199, 7641.235876412315, 4024.175044116008, 4036.919261614751, 4096.508418989681, 4142.896427589334, 4197.748135111903, 3543.061088811467, 3575.7318201135017, 3577.678008979204, 3590.2281009583066, 3600.5679318481425, 636.4844475974476, 643.5881036136876, 647.3397134922353, 658.5291516565678, 659.5053709717712, 390.4826105410286, 554.5646667366179, 563.5829486180197, 570.8515785895614, 581.3401788969466, 6664.5334826561675, 7318.707251709979, 7680.0, 7762.107357653829, 7894.0, 459.1345314082911, 582.6154737872984, 583.3728741602794, 584.8391692284656, 589.1751547832982, 3478.4956181353564, 3527.5590551181103, 3597.5862775780597, 3655.5796083450746, 3690.870819521304, 83779.0, 84195.57902210573, 84234.57882710548, 85246.14753852382, 85812.71280930791, 78364.43271134545, 79047.20952790513, 79915.60042199751, 80097.59951200207, 80284.0, 68488.0, 68879.65560172267, 68890.0, 70736.29263707297, 70993.64503177554, 5293.391260005149, 6126.172966649446, 6755.054292399166, 7286.6720997556295, 7298.671559779928, 48423.0, 48718.0256394863, 49068.0, 49126.5087349122, 49880.0, 62406.687966561694, 65702.0, 66380.33619663934, 66868.0, 67355.66322168357, 9931.553080111556, 9953.751156220862, 10020.34867733595, 10127.544260508148, 10135.391876487434, 10600.787984240118, 10758.906650662871, 10765.084967777826, 10879.292845964987, 11110.222284440011, 8198.959005204935, 9611.711648650551, 9659.420434773936, 9661.372010819274, 9848.212143028684, 9851.45816980073, 10201.74495637615, 10218.897811021794, 10244.02681745233, 10449.529771160163, 9468.526573671152, 9487.430754154773, 9559.0, 9631.566579503798, 10002.999700029939, 8460.746177614436, 8794.604242809217, 8833.0, 8854.734357969272, 8927.642894284156, 8165.387595930448, 8356.498610083414, 8444.197801208875, 8472.364572656952, 8556.10160933093], "value": 6600.568903387858, "unit": "score"}], "name": "dromaeo_css", "value": 7258.988957943601, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 113.0, 116.0, 118.0, 118.0, 132.0, 138.0, 115.0, 117.0, 117.0], "value": 120.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 131.0, 157.0, 162.0, 136.0, 143.0, 129.0, 154.0, 162.0, 119.0], "value": 145.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 201.0, 198.0, 198.0, 196.0, 198.0, 197.0, 207.0, 218.0, 279.0], "value": 209.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 71.0, 79.0, 148.0, 59.0, 57.0, 57.0, 62.0, 129.0, 57.0], "value": 78.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 83.0, 131.0, 77.0, 80.0, 84.0, 121.0, 82.0, 78.0, 124.0], "value": 94.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 91.0, 91.0, 77.0, 128.0, 99.0, 91.0, 94.0, 90.0], "value": 94.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 118.0, 118.0, 98.0, 98.0, 98.0, 98.0, 97.0, 97.0, 97.0], "value": 101.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 103.0, 92.0, 89.0, 103.0, 90.0, 90.0, 93.0, 117.0, 76.0], "value": 94.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 66.0, 67.0, 64.0, 66.0, 65.0, 66.0, 82.0, 65.0, 61.0], "value": 66.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 71.0, 70.0, 77.0, 68.0, 76.0, 79.0, 95.0, 64.0, 75.0], "value": 74.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 97.0, 72.0, 70.0, 96.0, 112.0, 102.0, 79.0, 113.0, 110.0], "value": 93.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 160.0, 146.0, 122.0, 143.0, 114.0, 144.0, 162.0, 176.0, 172.0], "value": 149.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 175.0, 169.0, 194.0, 207.0, 169.0, 203.0, 165.0, 227.0, 210.0], "value": 188.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 66.0, 69.0, 70.0, 68.0, 72.0, 70.0, 68.0, 72.0], "value": 69.0, "unit": "score"}], "name": "kraken", "value": 1579.8, "alertThreshold": 2.0}]} 05:41:08 INFO - Return code: 0 05:41:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:41:08 ERROR - # TBPL SUCCESS # 05:41:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:41:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:41:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:41:08 INFO - 2016-05-10 05:41:08.417 screenresolution[1691:10286] starting screenresolution argv=screenresolution get 05:41:08 INFO - 2016-05-10 05:41:08.419 screenresolution[1691:10286] Display 0: 1600x1200x32@60 05:41:08 INFO - 2016-05-10 05:41:08.425 screenresolution[1692:10289] starting screenresolution argv=screenresolution list 05:41:08 INFO - Available Modes on Display 0 05:41:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:41:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:41:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:41:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:41:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:41:08 INFO - Intel Iris: 05:41:08 INFO - Chipset Model: Intel Iris 05:41:08 INFO - Type: GPU 05:41:08 INFO - Bus: Built-In 05:41:08 INFO - VRAM (Dynamic, Max): 1536 MB 05:41:08 INFO - Vendor: Intel (0x8086) 05:41:08 INFO - Device ID: 0x0a2e 05:41:08 INFO - Revision ID: 0x0009 05:41:08 INFO - Displays: 05:41:08 INFO - Display: 05:41:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:41:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:41:08 INFO - Display Serial Number: 1600x1200 60 05:41:08 INFO - Main Display: Yes 05:41:08 INFO - Mirror: Off 05:41:08 INFO - Online: Yes 05:41:08 INFO - Rotation: Supported 05:41:08 INFO - Return code: 0 05:41:08 INFO - Running post-action listener: _resource_record_post_action 05:41:08 INFO - Running post-run listener: _resource_record_post_run 05:41:09 INFO - Total resource usage - Wall time: 1207s; CPU: 53.0%; Read bytes: 37211648; Write bytes: 656088064; Read time: 393; Write time: 1994 05:41:09 INFO - install - Wall time: 33s; CPU: 55.0%; Read bytes: 353133056; Write bytes: 347551744; Read time: 28752; Write time: 822 05:41:09 INFO - run-tests - Wall time: 1175s; CPU: 53.0%; Read bytes: 35384832; Write bytes: 298791936; Read time: 371; Write time: 1139 05:41:09 INFO - Running post-run listener: _upload_blobber_files 05:41:09 INFO - Blob upload gear active. 05:41:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:41:09 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 05:41:09 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'] 05:41:09 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 05:41:10 INFO - (blobuploader) - INFO - Open directory for files ... 05:41:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:41:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:41:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:41:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:41:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:41:11 INFO - (blobuploader) - INFO - Done attempting. 05:41:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:41:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:41:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:41:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:41:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:41:13 INFO - (blobuploader) - INFO - Done attempting. 05:41:13 INFO - (blobuploader) - INFO - Iteration through files over. 05:41:13 INFO - Return code: 0 05:41:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:41:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:41:13 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/98ce9217fc9fe3a3cba462b34796f8b4b55cce392331e092d9352f97b49d7e2a753a19abc82e85fc8eadcf798d0ac75fd2a39de027aaf20066d102aaaf9b2fc9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7cce884df151d822dac76335a170f5c9751116e36f613fc86eadacb8cc9111b8b0c97d86b8f40d7b0d1720eb2086c2317744ec22a44c0273bebf28afce0eded7"} 05:41:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:41:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:41:13 INFO - Contents: 05:41:13 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/98ce9217fc9fe3a3cba462b34796f8b4b55cce392331e092d9352f97b49d7e2a753a19abc82e85fc8eadcf798d0ac75fd2a39de027aaf20066d102aaaf9b2fc9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7cce884df151d822dac76335a170f5c9751116e36f613fc86eadacb8cc9111b8b0c97d86b8f40d7b0d1720eb2086c2317744ec22a44c0273bebf28afce0eded7"} 05:41:13 INFO - Running post-run listener: copy_logs_to_upload_dir 05:41:13 INFO - Copying logs to upload dir... 05:41:14 INFO - mkdir: /builds/slave/test/build/upload/logs 05:41:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.988195 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-05-10 05:41:14.068658) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-10 05:41:14.071951) ========= 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.Lr3QeYqt96/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.r0OMV16Lp8/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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/98ce9217fc9fe3a3cba462b34796f8b4b55cce392331e092d9352f97b49d7e2a753a19abc82e85fc8eadcf798d0ac75fd2a39de027aaf20066d102aaaf9b2fc9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7cce884df151d822dac76335a170f5c9751116e36f613fc86eadacb8cc9111b8b0c97d86b8f40d7b0d1720eb2086c2317744ec22a44c0273bebf28afce0eded7"} build_url:https://queue.taskcluster.net/v1/task/df8c38IJRoyaYkFY3ZTFeA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010919 build_url: 'https://queue.taskcluster.net/v1/task/df8c38IJRoyaYkFY3ZTFeA/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/98ce9217fc9fe3a3cba462b34796f8b4b55cce392331e092d9352f97b49d7e2a753a19abc82e85fc8eadcf798d0ac75fd2a39de027aaf20066d102aaaf9b2fc9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7cce884df151d822dac76335a170f5c9751116e36f613fc86eadacb8cc9111b8b0c97d86b8f40d7b0d1720eb2086c2317744ec22a44c0273bebf28afce0eded7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-10 05:41:14.099847) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 05:41:14.100231) ========= 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.Lr3QeYqt96/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.r0OMV16Lp8/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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.004985 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 05:41:14.148424) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-10 05:41:14.148766) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-10 05:41:14.149101) ========= ========= Total master_lag: 0.16 =========