builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0036 starttime: 1460653916.83 results: success (0) buildid: 20160414083844 builduid: b4f42256dc6c473e9a2cb8ae641d34a7 revision: 74e3c3ac7049652b21d532fca2fc0a7f82e90a71 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-14 10:11:56.833770) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-14 10:11:56.834145) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 11 secs) (at 2016-04-14 10:11:56.834414) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 11 secs) (at 2016-04-14 10:12:08.779716) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-04-14 10:12:08.780033) ========= 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.L0KKuOpl6J/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.Lov7XyUMMI/Listeners TMPDIR=/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-14 10:12:08-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 38.9M=0s 2016-04-14 10:12:09 (38.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.517784 ========= master_lag: 4.82 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-04-14 10:12:14.118179) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 5 secs) (at 2016-04-14 10:12:14.118455) ========= 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.L0KKuOpl6J/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.Lov7XyUMMI/Listeners TMPDIR=/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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.019401 ========= master_lag: 5.85 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 5 secs) (at 2016-04-14 10:12:19.987901) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 10 secs) (at 2016-04-14 10:12:19.988251) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 74e3c3ac7049652b21d532fca2fc0a7f82e90a71 --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 74e3c3ac7049652b21d532fca2fc0a7f82e90a71 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.L0KKuOpl6J/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.Lov7XyUMMI/Listeners TMPDIR=/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-14 10:12:20,415 truncating revision to first 12 chars 2016-04-14 10:12:20,415 Setting DEBUG logging. 2016-04-14 10:12:20,415 attempt 1/10 2016-04-14 10:12:20,415 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/74e3c3ac7049?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-14 10:12:21,365 unpacking tar archive at: mozilla-aurora-74e3c3ac7049/testing/mozharness/ program finished with exit code 0 elapsedTime=1.198730 ========= master_lag: 9.23 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 10 secs) (at 2016-04-14 10:12:30.412823) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-14 10:12:30.416196) ========= script_repo_revision: 74e3c3ac7049652b21d532fca2fc0a7f82e90a71 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-14 10:12:30.416612) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 8 secs) (at 2016-04-14 10:12:30.416893) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 8 secs) (at 2016-04-14 10:12:38.491941) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-04-14 10:12:38.492458) ========= /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.L0KKuOpl6J/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.Lov7XyUMMI/Listeners TMPDIR=/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 10:12:38 INFO - MultiFileLogger online at 20160414 10:12:38 in /builds/slave/test 10:12:38 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 10:12:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:12:38 INFO - {'append_to_log': False, 10:12:38 INFO - 'base_work_dir': '/builds/slave/test', 10:12:38 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 10:12:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:12:38 INFO - 'branch': 'Mozilla-Aurora', 10:12:38 INFO - 'buildbot_json_path': 'buildprops.json', 10:12:38 INFO - 'config_files': ('talos/mac_config.py',), 10:12:38 INFO - 'default_actions': ('clobber', 10:12:38 INFO - 'read-buildbot-config', 10:12:38 INFO - 'download-and-extract', 10:12:38 INFO - 'populate-webroot', 10:12:38 INFO - 'create-virtualenv', 10:12:38 INFO - 'install', 10:12:38 INFO - 'run-tests'), 10:12:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:12:38 INFO - 'download_minidump_stackwalk': True, 10:12:38 INFO - 'download_symbols': 'ondemand', 10:12:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:12:38 INFO - 'tooltool.py': '/tools/tooltool.py', 10:12:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:12:38 INFO - '/tools/misc-python/virtualenv.py')}, 10:12:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:12:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:12:38 INFO - 'installer_path': 'installer.exe', 10:12:38 INFO - 'log_level': 'info', 10:12:38 INFO - 'log_name': 'talos', 10:12:38 INFO - 'log_to_console': True, 10:12:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:12:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:12:38 INFO - 'opt_config_files': (), 10:12:38 INFO - 'pip_index': False, 10:12:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:12:38 INFO - 'cmd': ('bash', 10:12:38 INFO - '-c', 10:12:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:12:38 INFO - 'enabled': True, 10:12:38 INFO - 'halt_on_failure': False, 10:12:38 INFO - 'name': 'check_screen_resolution'},), 10:12:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:12:38 INFO - 'cmd': ('bash', 10:12:38 INFO - '-c', 10:12:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:12:38 INFO - 'enabled': True, 10:12:38 INFO - 'halt_on_failure': False, 10:12:38 INFO - 'name': 'check_screen_resolution'},), 10:12:38 INFO - 'run_cmd_checks_enabled': True, 10:12:38 INFO - 'sps_profile': False, 10:12:38 INFO - 'sps_profile_interval': 0, 10:12:38 INFO - 'suite': 'dromaeojs-e10s', 10:12:38 INFO - 'system_bits': '32', 10:12:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:12:38 INFO - 'title': 't-yosemite-r7-0036', 10:12:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:12:38 INFO - 'use_talos_json': True, 10:12:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:12:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:12:38 INFO - 'work_dir': 'build'} 10:12:38 INFO - ##### 10:12:38 INFO - ##### Running clobber step. 10:12:38 INFO - ##### 10:12:38 INFO - Running pre-action listener: _resource_record_pre_action 10:12:38 INFO - Running main action method: clobber 10:12:38 INFO - rmtree: /builds/slave/test/build 10:12:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:12:39 INFO - Running post-action listener: _resource_record_post_action 10:12:39 INFO - ##### 10:12:39 INFO - ##### Running read-buildbot-config step. 10:12:39 INFO - ##### 10:12:39 INFO - Running pre-action listener: _resource_record_pre_action 10:12:39 INFO - Running main action method: read_buildbot_config 10:12:39 INFO - Using buildbot properties: 10:12:39 INFO - { 10:12:39 INFO - "project": "", 10:12:39 INFO - "product": "firefox", 10:12:39 INFO - "script_repo_revision": "production", 10:12:39 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 10:12:39 INFO - "repository": "", 10:12:39 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 10:12:39 INFO - "buildid": "20160414083844", 10:12:39 INFO - "pgo_build": "False", 10:12:39 INFO - "basedir": "/builds/slave/test", 10:12:39 INFO - "buildnumber": 262, 10:12:39 INFO - "slavename": "t-yosemite-r7-0036", 10:12:39 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 10:12:39 INFO - "platform": "yosemite_r7", 10:12:39 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 10:12:39 INFO - "branch": "mozilla-aurora", 10:12:39 INFO - "repo_path": "releases/mozilla-aurora", 10:12:39 INFO - "revision": "74e3c3ac7049652b21d532fca2fc0a7f82e90a71", 10:12:39 INFO - "stage_platform": "macosx64", 10:12:39 INFO - "builduid": "b4f42256dc6c473e9a2cb8ae641d34a7", 10:12:39 INFO - "slavebuilddir": "test" 10:12:39 INFO - } 10:12:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/Ndv9_s5HQv6jEotVgtLlvg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg. 10:12:39 INFO - Running post-action listener: _resource_record_post_action 10:12:39 INFO - ##### 10:12:39 INFO - ##### Running download-and-extract step. 10:12:39 INFO - ##### 10:12:39 INFO - Running pre-action listener: _resource_record_pre_action 10:12:39 INFO - Running main action method: download_and_extract 10:12:39 INFO - mkdir: /builds/slave/test/build/tests 10:12:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:12:39 INFO - https://queue.taskcluster.net/v1/task/Ndv9_s5HQv6jEotVgtLlvg/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 10:12:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ndv9_s5HQv6jEotVgtLlvg/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 10:12:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ndv9_s5HQv6jEotVgtLlvg/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 10:12:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ndv9_s5HQv6jEotVgtLlvg/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 10:12:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ndv9_s5HQv6jEotVgtLlvg/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json'}, attempt #1 10:12:40 INFO - Downloaded 1277 bytes. 10:12:40 INFO - Reading from file /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 10:12:40 INFO - Using the following test package requirements: 10:12:40 INFO - {u'common': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 10:12:40 INFO - u'cppunittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 10:12:40 INFO - u'firefox-47.0a2.en-US.mac.cppunittest.tests.zip'], 10:12:40 INFO - u'gtest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 10:12:40 INFO - u'firefox-47.0a2.en-US.mac.gtest.tests.zip'], 10:12:40 INFO - u'jittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 10:12:40 INFO - u'jsshell-mac.zip'], 10:12:40 INFO - u'mochitest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 10:12:40 INFO - u'firefox-47.0a2.en-US.mac.mochitest.tests.zip'], 10:12:40 INFO - u'mozbase': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 10:12:40 INFO - u'reftest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 10:12:40 INFO - u'firefox-47.0a2.en-US.mac.reftest.tests.zip'], 10:12:40 INFO - u'talos': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 10:12:40 INFO - u'firefox-47.0a2.en-US.mac.talos.tests.zip'], 10:12:40 INFO - u'web-platform': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 10:12:40 INFO - u'firefox-47.0a2.en-US.mac.web-platform.tests.zip'], 10:12:40 INFO - u'webapprt': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 10:12:40 INFO - u'xpcshell': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 10:12:40 INFO - u'firefox-47.0a2.en-US.mac.xpcshell.tests.zip']} 10:12:40 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip'] for test suite category: common 10:12:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:12:40 INFO - https://queue.taskcluster.net/v1/task/Ndv9_s5HQv6jEotVgtLlvg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:12:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ndv9_s5HQv6jEotVgtLlvg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 10:12:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ndv9_s5HQv6jEotVgtLlvg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 10:12:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ndv9_s5HQv6jEotVgtLlvg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 10:12:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ndv9_s5HQv6jEotVgtLlvg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 10:12:41 INFO - Downloaded 17910960 bytes. 10:12:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:12:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 10:12:45 INFO - Return code: 0 10:12:45 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip', u'firefox-47.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 10:12:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:12:45 INFO - https://queue.taskcluster.net/v1/task/Ndv9_s5HQv6jEotVgtLlvg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:12:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ndv9_s5HQv6jEotVgtLlvg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 10:12:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ndv9_s5HQv6jEotVgtLlvg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 10:12:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ndv9_s5HQv6jEotVgtLlvg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 10:12:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ndv9_s5HQv6jEotVgtLlvg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 10:12:48 INFO - Downloaded 17910960 bytes. 10:12:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:12:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 10:12:51 INFO - Return code: 0 10:12:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:12:51 INFO - https://queue.taskcluster.net/v1/task/Ndv9_s5HQv6jEotVgtLlvg/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 10:12:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ndv9_s5HQv6jEotVgtLlvg/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 10:12:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ndv9_s5HQv6jEotVgtLlvg/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 10:12:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ndv9_s5HQv6jEotVgtLlvg/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip 10:12:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ndv9_s5HQv6jEotVgtLlvg/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip'}, attempt #1 10:12:53 INFO - Downloaded 10845168 bytes. 10:12:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:12:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 10:12:53 INFO - Return code: 0 10:12:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:12:53 INFO - https://queue.taskcluster.net/v1/task/Ndv9_s5HQv6jEotVgtLlvg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 10:12:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ndv9_s5HQv6jEotVgtLlvg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 10:12:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ndv9_s5HQv6jEotVgtLlvg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 10:12:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ndv9_s5HQv6jEotVgtLlvg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg 10:12:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ndv9_s5HQv6jEotVgtLlvg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg'}, attempt #1 10:13:01 INFO - Downloaded 115199711 bytes. 10:13:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ndv9_s5HQv6jEotVgtLlvg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 10:13:01 INFO - mkdir: /builds/slave/test/properties 10:13:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:13:01 INFO - Writing to file /builds/slave/test/properties/build_url 10:13:01 INFO - Contents: 10:13:01 INFO - build_url:https://queue.taskcluster.net/v1/task/Ndv9_s5HQv6jEotVgtLlvg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 10:13:02 INFO - Running post-action listener: _resource_record_post_action 10:13:02 INFO - Running post-action listener: set_extra_try_arguments 10:13:02 INFO - ##### 10:13:02 INFO - ##### Running populate-webroot step. 10:13:02 INFO - ##### 10:13:02 INFO - Running pre-action listener: _resource_record_pre_action 10:13:02 INFO - Running main action method: populate_webroot 10:13:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:13:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:13:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:13:02 INFO - '--tppagecycles', 10:13:02 INFO - '7'], 10:13:02 INFO - 'tests': ['tsvgm']}}, 10:13:02 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 10:13:02 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 10:13:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:13:02 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 10:13:02 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 10:13:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:13:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:13:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:13:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:13:02 INFO - 'talos_options': ['--e10s'], 10:13:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:13:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:13:02 INFO - 'tests': ['damp', 'tps']}, 10:13:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:13:02 INFO - 'talos_options': ['--e10s'], 10:13:02 INFO - 'tests': ['damp', 'tps']}, 10:13:02 INFO - 'g3': {'tests': ['dromaeo_dom']}, 10:13:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:13:02 INFO - 'other': {'tests': ['a11yr', 10:13:02 INFO - 'ts_paint', 10:13:02 INFO - 'tpaint', 10:13:02 INFO - 'sessionrestore', 10:13:02 INFO - 'sessionrestore_no_auto_restore', 10:13:02 INFO - 'tabpaint']}, 10:13:02 INFO - 'other-e10s': {'talos_options': ['--e10s'], 10:13:02 INFO - 'tests': ['a11yr', 10:13:02 INFO - 'ts_paint', 10:13:02 INFO - 'tpaint', 10:13:02 INFO - 'sessionrestore', 10:13:02 INFO - 'sessionrestore_no_auto_restore', 10:13:02 INFO - 'tabpaint']}, 10:13:02 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 10:13:02 INFO - 'tests': ['a11yr', 10:13:02 INFO - 'ts_paint', 10:13:02 INFO - 'tpaint', 10:13:02 INFO - 'sessionrestore', 10:13:02 INFO - 'sessionrestore_no_auto_restore', 10:13:02 INFO - 'tabpaint']}, 10:13:02 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 10:13:02 INFO - 'tests': ['a11yr', 10:13:02 INFO - 'ts_paint', 10:13:02 INFO - 'tpaint', 10:13:02 INFO - 'sessionrestore', 10:13:02 INFO - 'sessionrestore_no_auto_restore', 10:13:02 INFO - 'tabpaint']}, 10:13:02 INFO - 'other_l64': {'tests': ['a11yr', 10:13:02 INFO - 'ts_paint', 10:13:02 INFO - 'tpaint', 10:13:02 INFO - 'sessionrestore', 10:13:02 INFO - 'sessionrestore_no_auto_restore', 10:13:02 INFO - 'tabpaint']}, 10:13:02 INFO - 'other_nol64': {'tests': ['a11yr', 10:13:02 INFO - 'ts_paint', 10:13:02 INFO - 'tpaint', 10:13:02 INFO - 'sessionrestore', 10:13:02 INFO - 'sessionrestore_no_auto_restore', 10:13:02 INFO - 'tabpaint']}, 10:13:02 INFO - 'svgr': {'tests': ['tsvgx', 10:13:02 INFO - 'tsvgr_opacity', 10:13:02 INFO - 'tart', 10:13:02 INFO - 'tscrollx', 10:13:02 INFO - 'cart']}, 10:13:02 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 10:13:02 INFO - 'tests': ['tsvgx', 10:13:02 INFO - 'tsvgr_opacity', 10:13:02 INFO - 'tart', 10:13:02 INFO - 'tscrollx', 10:13:02 INFO - 'cart']}, 10:13:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:13:02 INFO - 'tests': ['tp5o']}, 10:13:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:13:02 INFO - 'talos_options': ['--e10s'], 10:13:02 INFO - 'tests': ['tp5o']}, 10:13:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:13:02 INFO - 'talos_options': ['--xperf_path', 10:13:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:13:02 INFO - 'tests': ['tp5n']}, 10:13:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:13:02 INFO - 'talos_options': ['--e10s', 10:13:02 INFO - '--xperf_path', 10:13:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:13:02 INFO - 'tests': ['tp5n']}}, 10:13:02 INFO - 'talos.zip': {'path': '', 10:13:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:13:02 INFO - Running post-action listener: _resource_record_post_action 10:13:02 INFO - ##### 10:13:02 INFO - ##### Running create-virtualenv step. 10:13:02 INFO - ##### 10:13:02 INFO - Running pre-action listener: _resource_record_pre_action 10:13:02 INFO - Running main action method: create_virtualenv 10:13:02 INFO - Creating virtualenv /builds/slave/test/build/venv 10:13:02 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 10:13:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:13:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:13:02 INFO - Using real prefix '/tools/python27' 10:13:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:13:03 INFO - Installing distribute.............................................................................................................................................................................................done. 10:13:05 INFO - Installing pip.................done. 10:13:05 INFO - Return code: 0 10:13:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:13:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:13:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:13:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:13:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:13:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:05 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 0x10e3eca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3f34c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e73487cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e290d30>, '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 0x10e0f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e287b20>, '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.L0KKuOpl6J/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.Lov7XyUMMI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:13:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:13:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:13:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.L0KKuOpl6J/Render', 10:13:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:13:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:13:05 INFO - 'HOME': '/Users/cltbld', 10:13:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:13:05 INFO - 'LOGNAME': 'cltbld', 10:13:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:13:05 INFO - 'MOZ_NO_REMOTE': '1', 10:13:05 INFO - 'NO_EM_RESTART': '1', 10:13:05 INFO - 'PAGER': '/bin/cat', 10:13:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:13:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:13:05 INFO - 'PWD': '/builds/slave/test', 10:13:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:13:05 INFO - 'SHELL': '/bin/bash', 10:13:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lov7XyUMMI/Listeners', 10:13:05 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 10:13:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:13:05 INFO - 'USER': 'cltbld', 10:13:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:13:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:13:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:13:05 INFO - 'XPC_FLAGS': '0x0', 10:13:05 INFO - 'XPC_SERVICE_NAME': '0', 10:13:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:13:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:13:06 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 10:13:06 INFO - Cleaning up... 10:13:06 INFO - Return code: 0 10:13:06 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 10:13:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:13:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:13:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:13:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:13:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e3eca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3f34c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e73487cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e290d30>, '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 0x10e0f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e287b20>, '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.L0KKuOpl6J/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.Lov7XyUMMI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:13:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 10:13:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 10:13:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.L0KKuOpl6J/Render', 10:13:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:13:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:13:06 INFO - 'HOME': '/Users/cltbld', 10:13:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:13:06 INFO - 'LOGNAME': 'cltbld', 10:13:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:13:06 INFO - 'MOZ_NO_REMOTE': '1', 10:13:06 INFO - 'NO_EM_RESTART': '1', 10:13:06 INFO - 'PAGER': '/bin/cat', 10:13:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:13:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:13:06 INFO - 'PWD': '/builds/slave/test', 10:13:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:13:06 INFO - 'SHELL': '/bin/bash', 10:13:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lov7XyUMMI/Listeners', 10:13:06 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 10:13:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:13:06 INFO - 'USER': 'cltbld', 10:13:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:13:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:13:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:13:06 INFO - 'XPC_FLAGS': '0x0', 10:13:06 INFO - 'XPC_SERVICE_NAME': '0', 10:13:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:13:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:13:06 INFO - Downloading/unpacking psutil>=0.7.1 10:13:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:13:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:13:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:13:09 INFO - Installing collected packages: psutil 10:13:09 INFO - Running setup.py install for psutil 10:13:09 INFO - building 'psutil._psutil_osx' extension 10:13:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 10:13:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 10:13:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 10:13:10 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 10:13:10 INFO - building 'psutil._psutil_posix' extension 10:13:10 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 10:13:10 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:13:10 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:13:10 INFO - ^ 10:13:10 INFO - 1 warning generated. 10:13:10 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 10:13:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:13:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:13:10 INFO - Successfully installed psutil 10:13:10 INFO - Cleaning up... 10:13:10 INFO - Return code: 0 10:13:10 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 10:13:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:13:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:13:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:13:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:13:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:10 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 0x10e3eca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3f34c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e73487cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e290d30>, '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 0x10e0f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e287b20>, '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.L0KKuOpl6J/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.Lov7XyUMMI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:13:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 10:13:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 10:13:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.L0KKuOpl6J/Render', 10:13:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:13:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:13:10 INFO - 'HOME': '/Users/cltbld', 10:13:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:13:10 INFO - 'LOGNAME': 'cltbld', 10:13:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:13:10 INFO - 'MOZ_NO_REMOTE': '1', 10:13:10 INFO - 'NO_EM_RESTART': '1', 10:13:10 INFO - 'PAGER': '/bin/cat', 10:13:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:13:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:13:10 INFO - 'PWD': '/builds/slave/test', 10:13:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:13:10 INFO - 'SHELL': '/bin/bash', 10:13:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lov7XyUMMI/Listeners', 10:13:10 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 10:13:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:13:10 INFO - 'USER': 'cltbld', 10:13:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:13:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:13:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:13:10 INFO - 'XPC_FLAGS': '0x0', 10:13:10 INFO - 'XPC_SERVICE_NAME': '0', 10:13:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:13:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:13:10 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 10:13:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:13 INFO - Downloading mozsystemmonitor-0.0.tar.gz 10:13:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:13:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 10:13:13 INFO - Installing collected packages: mozsystemmonitor 10:13:13 INFO - Running setup.py install for mozsystemmonitor 10:13:13 INFO - Successfully installed mozsystemmonitor 10:13:13 INFO - Cleaning up... 10:13:13 INFO - Return code: 0 10:13:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:13:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:13:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:13:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:13:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:13:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:13 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 0x10e3eca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3f34c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e73487cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e290d30>, '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 0x10e0f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e287b20>, '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.L0KKuOpl6J/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.Lov7XyUMMI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:13:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:13:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:13:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.L0KKuOpl6J/Render', 10:13:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:13:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:13:13 INFO - 'HOME': '/Users/cltbld', 10:13:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:13:13 INFO - 'LOGNAME': 'cltbld', 10:13:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:13:13 INFO - 'MOZ_NO_REMOTE': '1', 10:13:13 INFO - 'NO_EM_RESTART': '1', 10:13:13 INFO - 'PAGER': '/bin/cat', 10:13:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:13:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:13:13 INFO - 'PWD': '/builds/slave/test', 10:13:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:13:13 INFO - 'SHELL': '/bin/bash', 10:13:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lov7XyUMMI/Listeners', 10:13:13 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 10:13:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:13:13 INFO - 'USER': 'cltbld', 10:13:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:13:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:13:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:13:13 INFO - 'XPC_FLAGS': '0x0', 10:13:13 INFO - 'XPC_SERVICE_NAME': '0', 10:13:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:13:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:13:13 INFO - Downloading/unpacking blobuploader==1.2.4 10:13:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:15 INFO - Downloading blobuploader-1.2.4.tar.gz 10:13:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:13:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:13:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:13:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:13:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:17 INFO - Downloading docopt-0.6.1.tar.gz 10:13:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:13:17 INFO - Installing collected packages: blobuploader, requests, docopt 10:13:17 INFO - Running setup.py install for blobuploader 10:13:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:13:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:13:17 INFO - Running setup.py install for requests 10:13:17 INFO - Running setup.py install for docopt 10:13:17 INFO - Successfully installed blobuploader requests docopt 10:13:17 INFO - Cleaning up... 10:13:18 INFO - Return code: 0 10:13:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:13:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:13:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:13:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:13:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:13:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:18 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 0x10e3eca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3f34c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e73487cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e290d30>, '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 0x10e0f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e287b20>, '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.L0KKuOpl6J/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.Lov7XyUMMI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:13:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:13:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:13:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.L0KKuOpl6J/Render', 10:13:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:13:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:13:18 INFO - 'HOME': '/Users/cltbld', 10:13:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:13:18 INFO - 'LOGNAME': 'cltbld', 10:13:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:13:18 INFO - 'MOZ_NO_REMOTE': '1', 10:13:18 INFO - 'NO_EM_RESTART': '1', 10:13:18 INFO - 'PAGER': '/bin/cat', 10:13:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:13:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:13:18 INFO - 'PWD': '/builds/slave/test', 10:13:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:13:18 INFO - 'SHELL': '/bin/bash', 10:13:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lov7XyUMMI/Listeners', 10:13:18 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 10:13:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:13:18 INFO - 'USER': 'cltbld', 10:13:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:13:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:13:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:13:18 INFO - 'XPC_FLAGS': '0x0', 10:13:18 INFO - 'XPC_SERVICE_NAME': '0', 10:13:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:13:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:13:18 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-DGDZL6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:13:18 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-GsIyTV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:13:18 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-UgwSmt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:13:18 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-tSI_Wi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:13:18 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-0Ygk8z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:13:18 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-uHGDas-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:13:18 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-pe5aDj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:13:19 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-UAYRft-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:13:19 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-aeON8S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:13:19 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-Vh1FSc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:13:19 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-ig3aMG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:13:19 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-tIkuMY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:13:19 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-StoTqa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:13:19 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-WZOa8B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:13:19 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-xUtzYa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:13:19 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-LFnPIC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:13:19 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-5Ing9Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:13:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:13:20 INFO - Running setup.py install for manifestparser 10:13:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:13:20 INFO - Running setup.py install for mozcrash 10:13:20 INFO - Running setup.py install for mozdebug 10:13:20 INFO - Running setup.py install for mozdevice 10:13:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:13:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:13:20 INFO - Running setup.py install for mozfile 10:13:20 INFO - Running setup.py install for mozhttpd 10:13:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:13:20 INFO - Running setup.py install for mozinfo 10:13:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:13:21 INFO - Running setup.py install for mozInstall 10:13:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:13:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:13:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:13:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:13:21 INFO - Running setup.py install for mozleak 10:13:21 INFO - Running setup.py install for mozlog 10:13:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:13:21 INFO - Running setup.py install for moznetwork 10:13:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:13:21 INFO - Running setup.py install for mozprocess 10:13:21 INFO - Running setup.py install for mozprofile 10:13:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:13:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:13:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:13:22 INFO - Running setup.py install for mozrunner 10:13:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:13:22 INFO - Running setup.py install for mozscreenshot 10:13:22 INFO - Running setup.py install for moztest 10:13:22 INFO - Running setup.py install for mozversion 10:13:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:13:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:13:22 INFO - Cleaning up... 10:13:22 INFO - Return code: 0 10:13:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:13:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:13:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:13:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:13:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:13:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:22 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 0x10e3eca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3f34c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e73487cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e290d30>, '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 0x10e0f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e287b20>, '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.L0KKuOpl6J/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.Lov7XyUMMI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:13:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:13:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:13:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.L0KKuOpl6J/Render', 10:13:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:13:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:13:22 INFO - 'HOME': '/Users/cltbld', 10:13:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:13:22 INFO - 'LOGNAME': 'cltbld', 10:13:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:13:22 INFO - 'MOZ_NO_REMOTE': '1', 10:13:22 INFO - 'NO_EM_RESTART': '1', 10:13:22 INFO - 'PAGER': '/bin/cat', 10:13:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:13:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:13:22 INFO - 'PWD': '/builds/slave/test', 10:13:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:13:22 INFO - 'SHELL': '/bin/bash', 10:13:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lov7XyUMMI/Listeners', 10:13:22 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 10:13:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:13:22 INFO - 'USER': 'cltbld', 10:13:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:13:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:13:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:13:22 INFO - 'XPC_FLAGS': '0x0', 10:13:22 INFO - 'XPC_SERVICE_NAME': '0', 10:13:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:13:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:13:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:13:22 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-QzAmZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:13:23 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 10:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:13:23 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-Xwotkz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:13:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:13:23 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-PfuDuE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:13:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:13:23 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-670PTH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:13:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:13:23 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-qUcPSq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:13:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 10:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:13:23 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-8XlJpM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:13:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 10:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:13:23 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-ym4diO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:13:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 10:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:13:23 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-t_VFW8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:13:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 10:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:13:23 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-xBNgFq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:13:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 10:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:13:23 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-_e74wP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:13:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:13:23 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-FASSp4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:13:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 10:13:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:13:24 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-VGgEqT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:13:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 10:13:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:13:24 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-rSuxUN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:13:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 10:13:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:13:24 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-0uFrQg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:13:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 10:13:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:13:24 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-lYAY0G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:13:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 10:13:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:13:24 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-NzOEI_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:13:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 10:13:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:13:24 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-gisKAI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:13:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 10:13:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:13:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:13:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:13:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:13:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:13:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:13:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:27 INFO - Downloading blessings-1.6.tar.gz 10:13:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:13:27 INFO - Installing collected packages: blessings 10:13:27 INFO - Running setup.py install for blessings 10:13:27 INFO - Successfully installed blessings 10:13:27 INFO - Cleaning up... 10:13:27 INFO - Return code: 0 10:13:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:13:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:13:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:13:27 INFO - Reading from file tmpfile_stdout 10:13:27 INFO - Current package versions: 10:13:27 INFO - blessings == 1.6 10:13:27 INFO - blobuploader == 1.2.4 10:13:27 INFO - docopt == 0.6.1 10:13:27 INFO - manifestparser == 1.1 10:13:27 INFO - mozInstall == 1.12 10:13:27 INFO - mozcrash == 0.17 10:13:27 INFO - mozdebug == 0.1 10:13:27 INFO - mozdevice == 0.48 10:13:27 INFO - mozfile == 1.2 10:13:27 INFO - mozhttpd == 0.7 10:13:27 INFO - mozinfo == 0.9 10:13:27 INFO - mozleak == 0.1 10:13:27 INFO - mozlog == 3.1 10:13:27 INFO - moznetwork == 0.27 10:13:27 INFO - mozprocess == 0.22 10:13:27 INFO - mozprofile == 0.28 10:13:27 INFO - mozrunner == 6.11 10:13:27 INFO - mozscreenshot == 0.1 10:13:27 INFO - mozsystemmonitor == 0.0 10:13:27 INFO - moztest == 0.7 10:13:27 INFO - mozversion == 1.4 10:13:27 INFO - psutil == 3.1.1 10:13:27 INFO - requests == 1.2.3 10:13:27 INFO - wsgiref == 0.1.2 10:13:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:13:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:13:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:13:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:13:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:13:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:27 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 0x10e3eca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3f34c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e73487cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e290d30>, '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 0x10e0f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e287b20>, '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.L0KKuOpl6J/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.Lov7XyUMMI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:13:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 10:13:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:13:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.L0KKuOpl6J/Render', 10:13:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:13:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:13:27 INFO - 'HOME': '/Users/cltbld', 10:13:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:13:27 INFO - 'LOGNAME': 'cltbld', 10:13:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:13:27 INFO - 'MOZ_NO_REMOTE': '1', 10:13:27 INFO - 'NO_EM_RESTART': '1', 10:13:27 INFO - 'PAGER': '/bin/cat', 10:13:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:13:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:13:27 INFO - 'PWD': '/builds/slave/test', 10:13:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:13:27 INFO - 'SHELL': '/bin/bash', 10:13:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lov7XyUMMI/Listeners', 10:13:27 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 10:13:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:13:27 INFO - 'USER': 'cltbld', 10:13:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:13:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:13:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:13:27 INFO - 'XPC_FLAGS': '0x0', 10:13:27 INFO - 'XPC_SERVICE_NAME': '0', 10:13:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:13:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:13:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:13:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 10:13:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 10:13:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:13:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 10:13:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 10:13:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 10:13:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 10:13:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 10:13:27 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:13:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:13:27 INFO - Cleaning up... 10:13:28 INFO - Return code: 0 10:13:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:13:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:13:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:13:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:13:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:13:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:28 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 0x10e3eca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3f34c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e73487cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e290d30>, '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 0x10e0f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e287b20>, '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.L0KKuOpl6J/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.Lov7XyUMMI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:13:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:13:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:13:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.L0KKuOpl6J/Render', 10:13:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:13:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:13:28 INFO - 'HOME': '/Users/cltbld', 10:13:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:13:28 INFO - 'LOGNAME': 'cltbld', 10:13:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:13:28 INFO - 'MOZ_NO_REMOTE': '1', 10:13:28 INFO - 'NO_EM_RESTART': '1', 10:13:28 INFO - 'PAGER': '/bin/cat', 10:13:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:13:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:13:28 INFO - 'PWD': '/builds/slave/test', 10:13:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:13:28 INFO - 'SHELL': '/bin/bash', 10:13:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lov7XyUMMI/Listeners', 10:13:28 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 10:13:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:13:28 INFO - 'USER': 'cltbld', 10:13:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:13:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:13:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:13:28 INFO - 'XPC_FLAGS': '0x0', 10:13:28 INFO - 'XPC_SERVICE_NAME': '0', 10:13:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:13:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:13:28 INFO - Downloading/unpacking jsonschema 10:13:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:13:30 INFO - Downloading/unpacking functools32 (from jsonschema) 10:13: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 10:13: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 10:13: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 10:13: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 10:13: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 10:13: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 10:13:31 INFO - Downloading functools32-3.2.3-2.tar.gz 10:13:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:13:31 INFO - warning: no files found matching '*.txt' 10:13:31 INFO - no previously-included directories found matching 'build' 10:13:31 INFO - no previously-included directories found matching 'dist' 10:13:31 INFO - no previously-included directories found matching '.git*' 10:13:31 INFO - Installing collected packages: jsonschema, functools32 10:13:31 INFO - Running setup.py install for functools32 10:13:31 INFO - warning: no files found matching '*.txt' 10:13:31 INFO - no previously-included directories found matching 'build' 10:13:31 INFO - no previously-included directories found matching 'dist' 10:13:31 INFO - no previously-included directories found matching '.git*' 10:13:31 INFO - Successfully installed jsonschema functools32 10:13:31 INFO - Cleaning up... 10:13:31 INFO - Return code: 0 10:13:31 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 10:13:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:13:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:13:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:13:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:13:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:31 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 0x10e3eca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3f34c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e73487cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e290d30>, '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 0x10e0f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e287b20>, '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.L0KKuOpl6J/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.Lov7XyUMMI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:13:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 10:13:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 10:13:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.L0KKuOpl6J/Render', 10:13:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:13:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:13:31 INFO - 'HOME': '/Users/cltbld', 10:13:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:13:31 INFO - 'LOGNAME': 'cltbld', 10:13:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:13:31 INFO - 'MOZ_NO_REMOTE': '1', 10:13:31 INFO - 'NO_EM_RESTART': '1', 10:13:31 INFO - 'PAGER': '/bin/cat', 10:13:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:13:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:13:31 INFO - 'PWD': '/builds/slave/test', 10:13:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:13:31 INFO - 'SHELL': '/bin/bash', 10:13:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lov7XyUMMI/Listeners', 10:13:31 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 10:13:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:13:31 INFO - 'USER': 'cltbld', 10:13:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:13:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:13:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:13:31 INFO - 'XPC_FLAGS': '0x0', 10:13:31 INFO - 'XPC_SERVICE_NAME': '0', 10:13:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:13:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:13:31 INFO - Downloading/unpacking flake8 10:13:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:34 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 10:13:34 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 10:13:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:34 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 10:13:34 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 10:13:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:34 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 10:13:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 10:13:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:13:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:13:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:13:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:13:35 INFO - no previously-included directories found matching 'docs/_build' 10:13:35 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 10:13:35 INFO - Running setup.py install for pep8 10:13:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:13:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:13:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:13:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:13:35 INFO - no previously-included directories found matching 'docs/_build' 10:13:35 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 10:13:35 INFO - Successfully installed flake8 mccabe pyflakes pep8 10:13:35 INFO - Cleaning up... 10:13:35 INFO - Return code: 0 10:13:35 INFO - Running post-action listener: _resource_record_post_action 10:13:35 INFO - Running post-action listener: _start_resource_monitoring 10:13:35 INFO - Starting resource monitoring. 10:13:35 INFO - ##### 10:13:35 INFO - ##### Running install step. 10:13:35 INFO - ##### 10:13:35 INFO - Running pre-action listener: _resource_record_pre_action 10:13:35 INFO - Running main action method: install 10:13:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:13:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:13:35 INFO - Reading from file tmpfile_stdout 10:13:35 INFO - Detecting whether we're running mozinstall >=1.0... 10:13:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:13:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:13:36 INFO - Reading from file tmpfile_stdout 10:13:36 INFO - Output received: 10:13:36 INFO - Usage: mozinstall [options] installer 10:13:36 INFO - Options: 10:13:36 INFO - -h, --help show this help message and exit 10:13:36 INFO - -d DEST, --destination=DEST 10:13:36 INFO - Directory to install application into. [default: 10:13:36 INFO - "/builds/slave/test"] 10:13:36 INFO - --app=APP Application being installed. [default: firefox] 10:13:36 INFO - mkdir: /builds/slave/test/build/application 10:13:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:13:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 10:14:08 INFO - Reading from file tmpfile_stdout 10:14:08 INFO - Output received: 10:14:08 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 10:14:08 INFO - Running post-action listener: _resource_record_post_action 10:14:08 INFO - ##### 10:14:08 INFO - ##### Running run-tests step. 10:14:08 INFO - ##### 10:14:08 INFO - Running pre-action listener: _resource_record_pre_action 10:14:08 INFO - Running main action method: run_tests 10:14:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:14:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:14:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:14:08 INFO - 2016-04-14 10:14:08.696 screenresolution[6241:101858] starting screenresolution argv=screenresolution get 10:14:08 INFO - 2016-04-14 10:14:08.699 screenresolution[6241:101858] Display 0: 1600x1200x32@60 10:14:08 INFO - 2016-04-14 10:14:08.705 screenresolution[6242:101860] starting screenresolution argv=screenresolution list 10:14:08 INFO - Available Modes on Display 0 10:14:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:14:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:14:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:14:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:14:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:14:08 INFO - Intel Iris: 10:14:08 INFO - Chipset Model: Intel Iris 10:14:08 INFO - Type: GPU 10:14:08 INFO - Bus: Built-In 10:14:08 INFO - VRAM (Dynamic, Max): 1536 MB 10:14:08 INFO - Vendor: Intel (0x8086) 10:14:08 INFO - Device ID: 0x0a2e 10:14:08 INFO - Revision ID: 0x0009 10:14:08 INFO - Displays: 10:14:08 INFO - Display: 10:14:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:14:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:14:08 INFO - Display Serial Number: 1600x1200 60 10:14:08 INFO - Main Display: Yes 10:14:08 INFO - Mirror: Off 10:14:08 INFO - Online: Yes 10:14:08 INFO - Rotation: Supported 10:14:08 INFO - Return code: 0 10:14:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:14:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:14:08 INFO - Python 2.7.3 10:14:08 INFO - Return code: 0 10:14:08 INFO - grabbing minidump binary from tooltool 10:14:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:14:08 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 0x10e290d30>, '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 0x10e0f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e287b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:14:08 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 10:14:08 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 10:14:09 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:14:09 INFO - Return code: 0 10:14:09 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:14:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:14:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:14:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:14:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:14:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 10:14:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 10:14:10 INFO - Return code: 0 10:14:10 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/Ndv9_s5HQv6jEotVgtLlvg/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0036', '--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 10:14:10 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/Ndv9_s5HQv6jEotVgtLlvg/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0036 --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 10:14:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.L0KKuOpl6J/Render', 10:14:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:14:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:14:10 INFO - 'HOME': '/Users/cltbld', 10:14:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:14:10 INFO - 'LOGNAME': 'cltbld', 10:14:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:14:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:14:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:14:10 INFO - 'MOZ_NO_REMOTE': '1', 10:14:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:14:10 INFO - 'NO_EM_RESTART': '1', 10:14:10 INFO - 'PAGER': '/bin/cat', 10:14:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:14:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:14:10 INFO - 'PWD': '/builds/slave/test', 10:14:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:14:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:14:10 INFO - 'SHELL': '/bin/bash', 10:14:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lov7XyUMMI/Listeners', 10:14:10 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 10:14:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:14:10 INFO - 'USER': 'cltbld', 10:14:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:14:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:14:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:14:10 INFO - 'XPC_FLAGS': '0x0', 10:14:10 INFO - 'XPC_SERVICE_NAME': '0', 10:14:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:14:10 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/Ndv9_s5HQv6jEotVgtLlvg/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0036', '--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 10:14:10 INFO - mozversion application_buildid: 20160414083844 10:14:10 INFO - mozversion application_changeset: 74e3c3ac7049652b21d532fca2fc0a7f82e90a71 10:14:10 INFO - mozversion application_display_name: FirefoxDeveloperEdition 10:14:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:14:10 INFO - mozversion application_name: Firefox 10:14:10 INFO - mozversion application_remotingname: firefox-dev 10:14:10 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:14:10 INFO - mozversion application_vendor: Mozilla 10:14:10 INFO - mozversion application_version: 47.0a2 10:14:10 INFO - mozversion platform_buildid: 20160414083844 10:14:10 INFO - mozversion platform_changeset: 74e3c3ac7049652b21d532fca2fc0a7f82e90a71 10:14:10 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:14:10 INFO - mozversion platform_version: 47.0a2 10:14:10 INFO - using testdate: 1460654050 10:14:10 INFO - actual date: 1460654050 10:14:10 INFO - starting webserver on 'localhost:56951' 10:14:10 INFO - SUITE-START | Running 2 tests 10:14:10 INFO - TEST-START | dromaeo_css 10:14:10 INFO - operating with platform_type : mac_ 10:14:10 INFO - Initialising browser for dromaeo_css test... 10:14:10 INFO - TEST-INFO | started process 6260 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/tmpyG04sb/profile http://localhost:56951/getInfo.html) 10:14:12 INFO - PROCESS | 6260 | __metrics Screen width/height:1600/1200 10:14:12 INFO - PROCESS | 6260 | colorDepth:24 10:14:12 INFO - PROCESS | 6260 | Browser inner width/height: 1024/689 10:14:12 INFO - PROCESS | 6260 | __metrics 10:14:13 ERROR - PROCESS | 6260 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2849: TypeError: this.tabs is undefined 10:14:13 INFO - TEST-INFO | 6260: exit 0 10:14:13 INFO - Browser initialized. 10:14:13 INFO - Running cycle 1/1 for dromaeo_css test... 10:14:13 INFO - TEST-INFO | started process 6264 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/tmpyG04sb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:14:25 INFO - PROCESS | 6264 | RSS: Main: 217518080 10:14:25 INFO - PROCESS | 6264 | 10:15:15 INFO - PROCESS | 6264 | 1460654115348 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 10:17:26 INFO - PROCESS | 6264 | Cycle 1(1): loaded http://localhost:56951/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56951/tests/dromaeo/cssquery-ext.html) 10:17:27 INFO - PROCESS | 6264 | RSS: Main: 228253696 10:17:27 INFO - PROCESS | 6264 | 10:20:29 INFO - PROCESS | 6264 | Cycle 1(1): loaded http://localhost:56951/tests/dromaeo/cssquery-ext.html (next: http://localhost:56951/tests/dromaeo/cssquery-jquery.html) 10:20:30 INFO - PROCESS | 6264 | RSS: Main: 240046080 10:20:30 INFO - PROCESS | 6264 | 10:23:31 INFO - PROCESS | 6264 | Cycle 1(1): loaded http://localhost:56951/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56951/tests/dromaeo/cssquery-mootools.html) 10:23:32 INFO - PROCESS | 6264 | RSS: Main: 242159616 10:23:32 INFO - PROCESS | 6264 | 10:26:34 INFO - PROCESS | 6264 | Cycle 1(1): loaded http://localhost:56951/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56951/tests/dromaeo/cssquery-prototype.html) 10:26:34 INFO - PROCESS | 6264 | RSS: Main: 244445184 10:26:34 INFO - PROCESS | 6264 | 10:29:37 INFO - PROCESS | 6264 | Cycle 1(1): loaded http://localhost:56951/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56951/tests/dromaeo/cssquery-yui.html) 10:29:38 INFO - PROCESS | 6264 | RSS: Main: 246394880 10:29:38 INFO - PROCESS | 6264 | 10:32:40 INFO - PROCESS | 6264 | Cycle 1(1): loaded http://localhost:56951/tests/dromaeo/cssquery-yui.html (next: http://localhost:56951/tests/dromaeo/cssquery-dojo.html) 10:32:40 INFO - PROCESS | 6264 | RSS: Main: 247840768 10:32:40 INFO - PROCESS | 6264 | 10:32:40 INFO - PROCESS | 6264 | __start_tp_report 10:32:40 INFO - PROCESS | 6264 | _x_x_mozilla_page_load 10:32:40 INFO - PROCESS | 6264 | _x_x_mozilla_page_load_details 10:32:40 INFO - PROCESS | 6264 | |i|pagename|runs| 10:32:40 INFO - PROCESS | 6264 | |0;dojo.html;3477.0785741778427;3511.771734837236;3540.504329393885;3575.9450961966218;3593.8562457501703;4459.331100334952;5893.9115913261285;5921.993261145605;5938.346781853998;5945.46490815827;5925.970370148154;5926.318473375563;5935.792247271346;5942.970285148579;5964.642121472714;4339.370791235269;5276.577873770108;5361.222622719703;5383.67697938123;5399.757010934498;5258.474152584749;5337.946620533803;5422.674639521621;5427.972860135694;5467.699276539789;5427.240186373891;5436.646617969839;5445.891082178354;5457.590680698943;5463.562914966813;4578.626412076381;5319.414864364917;5372.1941708744;5383.946160538403;5449.237106805051;5218.321618189649;5224.268602395668;5295.0468915595175;5321.122014867581;5357.955198736248;5089.491050894918;5259.526642602146;5763.711814409263;5859.341823250526;5886.087656413263;6229.127922090911;6267.122602835607;6273.05904114382;6278.6546739929745;6285.340039295854;5324.440933701983;6110.285381942539;6261.436470717612;6328.493720502349;6353.047042943549;5925.051991681354;5935.9686701720575;5942.64344139353;5972.223610930551;5974.970125149346;6221.62670239778;6275.309715931244;6317.9684101580115;6319.652419116993;6332.208473940757;6170.259568851767;6217.564770466114;6258.718357673915;6267.185265915402;6275.874482510416;6249.812505624838;6308.621482711052;6311.842203944938;6316.8420789479715;6324.494040476751;4878.463369029404;5178.766955487012;6131.009681329197;6199.473044791153;6287.056941458818;6186.597871138361;6247.156633854372;6258.968705156445;6275.309715931244;6287.339829317947;4954.727489988085;4958.9008219835405;5003.374578177728;5025.346704928409;5040.521150490697;3667.394879844849;4419.204543182193;4450.332450132441;4529.886752831206;4554.24854898938;5745.85635359111;5846.6784326862435;5904.439078287556;5982.551308651869;6012.969935150296;6302.369763023661;6317.851035908827;6323.588966717149;6326.683665816783;6366.840828979312;5759.078547432391;5860.00379935412;5987.670678112659;6015.6691381974415;6026.638401695912;5526.198701188305;5527.226188333637;5543.889122217533;5581.5255703265675;5585.720713964285;6454.6449945252525;6471.417572418505;6476.319986401454;6478.740850366027;6494.902576461452;6174.043023331391;6200.968995154995;6267.122602835698;6312.274088479883;6329.7468101275435;6433.678316084271;6458.644774537445;6461.0954466373805;6476.70854811544;6480.611163330215;6212.565120441525;6259.593126446766;6278.686065696788;6283.748650053946;6312;4593.586577208067;6160.229971253593;6243.094751261133;6318.4313411793155;6318.62088274705;6204.162438070984;6209.410106039919;6248.843778905381;6250.343713910156;6298.02380631003;6216.627002379872;6238.937610624017;6254.186955695913;6272.686365681607;6273.71768270438;5912.70436478183;6031.728572214173;6039.094135879477;6127.724252408563;6133.815985520441;6171.179502046996;6210.161628180319;6242.812715618538;6244.875102498016;6258.061290806233;6021.397860214119;6166.229221347332;6171.9691401542705;6176.289726681489;6186.876262474815 10:32:40 INFO - PROCESS | 6264 | |1;ext.html;588.7900364750438;611.7400104955393;615.95380346474;670.4837275298021;673.7670063783277;39584.20831583367;40387.79806100966;40593.18813623726;40706.38940415892;40810.38784418229;42288.57711422885;42570.78714606431;42638.654873982;42772.9306767331;42801.50194743185;9551.044895510446;9752.366096203725;9757.316987810857;9800.166985806214;9807.852882206773;9564.473953932531;9788.706338809847;9817.263705222102;9834.409935403863;9846.60613575456;9755.268354873377;9840.753981150458;9855.55649995752;9857.309988300822;9902.702918912444;9759.463229522373;9813.460259685715;9825.1157395834;9830.705078847646;9832.950835245778;9757.12185903265;9868.90131098687;9869.802603947881;9903.900960990442;9922.40465572068;26485.602715959278;41255.58744412548;41582.584174158175;41691;41721.79139104315;2611.3341098020073;2889.061055157082;2987.253186703324;2989.1032690192856;3479.565054368204;29760.70239297601;29864.70135298641;30041.69958300411;30243.848780756176;30736.077917662507;2439.621858611918;2826.434713057397;2837.787165962561;2921.839298838563;2989.970100298991;423686;425881;426449;427430;428705;49355.25967110496;71919;71923;72527;72876.271237288;48515.272270915964;48574.75712621414;48915.26627100597;48955.51044489581;49116.263256051185;16997.745033824503;17120.57198570046;17166.22751976164;17186.656266874594;17209.311627535008;13625.182489050689;13671.931640341734;13686.31568421575;13689.931550342184;13730.794038089436;15810.841891581167;15829.920850395674;15931.203439827963;16039.358425662842;16112.597185070466;5633.690147041952;6594.670266486753;6704.195496540447;6732.394084532416;6757.3242675732035;943.8914524829595;979.9755006124761;1055.525013743803;1092.63396762084;1102.1953973599318;30964.380712386075;31055;31270.90551830675;31336.059918202034;31417.37165256682;1041.578160844844;1083.2417307884512;1101.9724506887233;1122.36586328724;1151.717829131868;13281.734365312834;15565.22173891301;15711.607209819846;15728.606784830243;16171.919140404223;481469;497410;497435.5641108929;498175;548697;68133;68183.65908170427;69064;69191.96212056823;69329;1473.3296350160651;1493.626593351662;1497.1017389566173;1504.262911173517;1510.1995942150677;31719.889803856753;32916.341673166884;32964.011079667645;32972.50541241931;33532.65869365296;55908.72045639746;56219.15671265016;56452.30643080714;56510.43489565052;56599.434005659416;511052;511416;511772;513987;514411;34763.826180868935;35134.29731405307;35780.82109589435;35985.82007089948;36042.639573604974;47998;48133.5186648129;48262.75868620635;49201.753991229816;49626.75186624044;39125.41311880381;39830;39911.716936199184;40122.39816402698;40152.39771403373;39279.41080883846;39824.20351592894;40105;40468.583599573874;40492.595074048884;32794;38470.23059538849;38966.4155037669;39487;40180;34300;34367;35021.54849160535;35217.82391088028;35287.29425411529;26586.867065664548;26592;26680.733192667823;26722.064727734436;27017.054403095797 10:32:40 INFO - PROCESS | 6264 | |2;jquery.html;315.7947334232747;381.9305943358596;389.07594463150014;390.9960900390995;391.35426546198767;19744.605107897834;20043.899780501095;20327.796722032774;20941;20958.89520552399;20187.485938554593;20745.896270518664;21676.458088547795;21844.45388865275;21987.900604969727;5212.4266330703595;5862.179294898718;5877.265341832271;5878.265216847894;5889.64662120272;5180.45605211453;5434.375046869602;5758.395368486312;5815.912761308584;5816.476517113459;5746;5751.654900705971;5757.366689664133;5757.539396848263;5783.392743761908;5483.75323110459;5722.942770572283;5760.4527569881;5769.913451298234;5774.2782152230975;4970.627202959792;5011.448740638527;5017.197248440232;5024.422191447994;5039.44566097729;25564.74435255661;26063.609045864327;26250.474990500083;26297.3425664358;26303.605445918325;2195.3633446300714;2224.377174391173;2229.977700222993;2281.9657705134437;2326.255598208574;12240.571579994657;14500.347484363228;14813.777793333109;14819.18494482803;15132.394704211929;1717.252994947202;1727.8876873003214;1756.0956107604593;1770.9645807083914;1795.9191836367265;90510.54744726235;93164;93392;93849;93961.5301923486;10233.59065637381;10669.253152279422;10775.137988961022;10806.891931080745;10817.513211905483;13136.737265254833;13150.934245328712;13187.208767473981;13203.273819939803;13247.735045299232;8895.777605559784;8912.910870891208;8971.05803890595;9001.684941027164;9022.14289642483;9292.814143717223;9316.068393160629;9320.766980825534;9397.530123493934;9403.106704863027;6062.6359069209075;6193.040078787705;6205.255369355616;6222.937770622236;6272.18461599989;1857.1364315593044;1880.8589355798097;1890.7825600055894;1908.9427317180507;1928.816762407569;902.179017094454;1007.793402352516;1022.9795404091876;1028.0850043461326;1156.8785277545906;18667.786593871628;19447.513812154528;19558.70661940072;20840;21403.57192856134;873.8727042115727;941.7033634405141;987.980240395188;1089.3790539392471;1103.007293435914;11551.653450396501;11697.064234861191;11772.764544709058;11799.823002654966;11810.291382517076;538128;608695;622083;647502;647855;103835;132967;136988;137449;139768.2046359067;1033.5142483032962;1039.0700323210835;1072.753266748652;1078.132103656564;1083.869935607732;15054.247287635793;18134;18259.269629214683;18286.268549258413;18655.067246637886;52145;54206;54383;55445.44554554564;56034.439655604554;63577;77562;79776;80313.59843200746;80644.98387540123;8042.316403105801;8113.945187125872;8114.107448180577;8157.79605509867;8382.74851754424;11918.52325906954;13113.27876966776;13245;13345.866541334462;13463.326833658473;11497.655070347568;11562.94218528902;11631.418429078343;11661.183717139718;11732.061435085343;10491.895081049091;10602.25784195099;10922.836157457485;11029.83455248188;11513.366764828015;9106.726798196065;10877.67366978992;11080.445977701243;11242.213045087092;11961.940190299341;7597.164311925794;7734.690612375442;7736.535807851547;7758;7798.142204357403;9903;10524.842127368249;10627.256092073481;10673.733156671145;10909.890901091205 10:32:40 INFO - PROCESS | 6264 | |3;mootools.html;1572.3317590024237;1638.4674980631294;1955.9119839607217;2039.40857151426;2056.085042156241;8289.253967142971;8897.332700047506;9110.453372797629;9304.767380815483;9306.20897223736;8972.147645973622;9071.455712657245;9135.360524763253;9147.131022552847;9232.8615070774;2550.7449255074484;2570.267473769976;2573.665423494943;2585.133980116663;2589.3785491482004;2153.773853745358;2524.798016158712;2563.3976015636313;2574.665293511841;2580.2001379572393;2497.4880149569294;2539.7968162546954;2543.6566063581363;2545.2618740565213;2555.667763190792;2216.6231740604135;2465.716442609105;2555.501677172952;2561.859097749623;2574.304937666838;2179.487820362214;2548.566743653565;2582.109172335541;2592.183462209398;2597.8181527293095;9192.21866141372;9233.215176709988;9253.861192082124;9265.397749146285;9269.073092690744;5561.77752889888;5704.1443783432405;5711.543076553908;5726.5418766499;5750.223719797816;3675.9632403676155;3741.0834345585504;3775.1128484806322;3780.5652349979828;3782.470454136423;1059.3061544688242;1088.9401082940435;1091.9344839309667;1108.5842808946645;1108.6175269531998;12344.876551234553;12406.937965310115;12463.501459941592;12485.625431236987;12513.436895339732;4459.331100334911;4906.215005599159;4978.327925730053;5051.545360917535;5090.007448547572;10468.947655261674;10579.259451838298;10589.735256618493;10613.991670791262;10653.627123050655;5744.684042377709;5759.6832174229985;5765.077587585966;5780.479756821906;5812.534997200214;4626.606738427204;5009.398872135367;5079.974600126976;5099.464556221617;5216.556592689588;3518.208403109331;4098.6516146127315;4129.318662420667;4140;4156.376543518496;1159.4492616007328;1351.6553278913814;1373.416298073315;1378.0973462382156;1389.2706329177263;1366.3100134432248;1370.616227456314;1384.764590019699;1400.9299535023208;1407.56365526685;8516.105808889977;8538;8570.271526920282;8590.957045214735;8633.093525179891;1155.942202889852;1192.6779769462196;1193.1111322065117;1208.2629595946466;1271.262667652759;6018.458338749533;6578.6052836828985;6632.900506492407;6643.435307998874;6646.60120392778;10615.628453004108;11130.443477826238;11148.163887708455;11239.10087193039;11263.211575189822;10931.289466184831;10966.725831854268;11128.49921753523;11169.497372618252;11218.831717524243;8665.220130188314;8778.868316975251;8790.692325768567;8850.557472126367;8878.200961913459;8239.464434811718;8297.502149871027;8309.83662287268;8325.500469971821;8407.159284071544;9983.400995940267;10087.092161705481;10110.241731870286;10134.746631334276;10191.337563058378;8628.439151455135;9254.676086336945;10504.78990420172;10537;10621.734456638645;6630.801075967729;7243.601601911945;7313.524620899624;7361.190269070291;7452.403807695263;7543.849123017619;8247.587620618826;8601.139886011599;8620.094890036456;8691.217790398894;7484.438667100098;7608.581528016012;7629.427792915442;7689.3464055555905;7709.306162445407;6110.358412366638;7318.3047610476915;7495.662695178621;7537.2085930976455;7591.506552074097;7394.482386232912;7680.694281971966;7910.367170626221;8110.918890811016;8225.095239523767;6240.937590624036;7285.635718213962;7345.0084238627105;7412.481126321106;7413.925860741324;7479.58862262559;7486.850262994819;7503.474756766974;7552.8867066995135;7553.018107645971 10:32:40 INFO - PROCESS | 6264 | |4;prototype.html;288.8079427180925;342.57862828720675;343.1284537275321;346.667199488491;350.91928856363035;5227.424983251839;6215;6507.707153178107;7120.9287907120915;7123.608201548906;6978.790636280907;6983.371496565308;6984.79045628632;6999.300069992998;7010.509264351497;4901.65688401812;5000.849974500771;5071.2139618359215;5072.3405957225505;5192.948070519285;5046.066477701618;5076.543111119999;5076.974615126937;5090.694558326494;5096.388433387999;4995.825146119886;5023.171176755831;5027.271045698371;5035.194368900977;5051.696898186102;5058.4688607696025;5059.544640982311;5073.391193056838;5073.452596957926;5138.332016837825;4405.713628614129;4408.58118478744;4415.116976604692;4416.6025057744955;4434.733915965053;6501.9349806501805;6572.86854262912;6647.135872336566;6730.226024007253;6779.35596118368;6005.219321488178;6048.818535443944;6059.848503787396;6071.848203794896;6080.787172448943;7505.737299194557;7551.924480755177;7556.47104702671;7570.659320330598;7573.166951635316;4966.379202599675;4971.27916452116;4974.37820272466;4982.626303027288;4986.975065124688;7133.536320139175;7943.721969731089;7951.483153594998;7957.721479748239;7962.203779622049;4933.629977751683;4951.826686066042;4977.751112444364;4978;4994.650374473752;3898.7465814721954;4689.320048592935;5066.36670416198;5086.745662716923;5090;5971.522278217819;5974.4324289192455;5978.551608629283;5983.970080149571;5990.760369585168;7454.850902982019;7485.47601667878;7495.737649182252;7569.697212111454;7579.772606821693;1640.6554623528955;1774.8846324988835;1794.3540325482816;1794.9910250448925;1799.6490684316432;1075.4406111138724;1075.8278675411896;1079.519613771864;1086.3861918016453;1089.7330154112135;5781.364049954585;5950.899083669535;6764.93235067643;6846.863062738817;6849.349311815369;7348.706051757977;7755.069391673035;7825.256600622932;7825.804354891059;7844.411669124838;5888.851673923629;6688.565243259172;6917.169939607279;6928.515003949675;6983.171815414765;3341.2315167511124;3990.38149086892;4103.261412945639;4124.608162224584;4130;8044.155363686728;8122.431429799975;8266.504009759434;8283.337333013344;8293.502389856629;6980.197277313073;7866.724664636825;8169.836603267901;8209.507429554245;8215.671373145007;8211.958940205499;8251.8349633007;8254.42219044673;8266.173382661806;8287.378446616527;7575.393968482398;7896.131425543071;7915.802104947423;7926.603669816602;7928.326092282221;7686.231376862269;7761.495502792526;7763.92236077632;7774.572398517909;7782.571958542335;8210.507369557845;8212.260896519341;8230.341572674291;8233.46482478637;8251.793705157419;4878.731669758055;4885.804567817389;4886.047220791911;4895.143349913822;4908.9263661045825;7594.5063570869925;7851.960740196262;7882.960585197037;7885.250901164381;7894.881576776238;7532.435067370079;7538.773836784905;7562.395008399425;7573.431992600466;7593.240675932362;6164.4143806338325;7520.9094681272;7566.205548417337;7568.4323675723435;7605.391568674382;7002.019717239488;7299.087614048244;7379.114506259283;7404.074490688664;7405.407567394704;7461.776146715607;7484.139323977813;7544.509606875536;7563.886541701769;7590.203028681909;7363.852722945404;7367.121384047248;7373.963130184315;7379.372753316028;7379.926200737924 10:32:40 INFO - PROCESS | 6264 | |5;yui.html;682.9009793579928;721.1994685898652;750.7259850154695;776.6660336055496;787.1734678587485;15042.323095460702;15213.84786152138;15306.081635101888;15333.539993800176;15360;13675.179489230653;13703.58889233322;13730.58808235752;13745.106568073094;13766.967477439182;756.6821934787375;767.9462437629368;936.9484678342689;971.825071487132;973.2360097323602;959.0313783079106;964.768455570665;973.4305431322684;974.3277138774223;978.6917121106865;1013.5591017907198;1018.4042335233897;1020.0513522424143;1022.2128960700256;1022.6727447216894;999.2355847776494;1003.413003393016;1006.66276797273;1012.5038731021783;1015.3400289811608;745.9738909138208;754.2457542457543;762.0740799928059;768.2663056780762;769.6728890221633;14188.716225675533;14279.78580321296;14296.642583935381;14355.99508034438;14377.424903003868;11224.765275819653;12972.286524241163;13207.471701131943;13263.336833158304;13412.597622071353;9851.113399794052;10373;10410.219233557438;10651.414172220524;10706.518206680717;3066.47869862124;3508.982455087734;3553.8045407502577;3567.090391950062;3770.359038963383;87424.5628771852;87528;87756;88446.11553884443;90464.54767726186;9581.281403894736;9773.727685581587;9802.852957205647;9804.901950980398;10160.187185025181;23581.88209058978;23640;23649.29052128439;23665.7633423667;23818.523629527313;7501.962490187514;7566.470347075652;7591.089069311607;7629.2370762923265;7744.49660772048;4092.7544347338658;4130.256553820341;4144.585541445831;4146.129312844337;4149.315362965137;3720.069982504374;3754.9249015019545;3772.7359084863797;3774.8675397380675;3777.7355585109326;654.8919428294373;662.7912207654573;666.7099811581977;670.6914819183132;671.5735507952485;549.5548605629454;569.6724383479516;600.9699515024231;602.7106988645475;607.4593611685603;8430.662773488992;8449.36629752771;8508.914910850812;8565.27195188416;8697.086805885418;577.0615548878918;597.2474681900836;611.2054329371799;619.5074915442234;623.1213988276509;3787.905302367463;3923.4221236031995;3943.4479172915817;3971.225611005884;4023.8591649292134;95614.0438595619;100018;102092;102241.48879255557;102346;80593.79109313365;81104.18895811084;83787.5810620943;84998.57500712457;85365.57317213374;73942.63028684822;74193;75793.24206757972;75949.24050759533;79155;8668.653253869774;8744.475331480131;8784.121587841164;8797.780055498664;8935.061818509223;37590;52802.20796687976;53145.4685453156;53921.730391347795;55190;73285.26714732785;77102.61448692721;77629;77639.22360776545;78032.60983695272;11086.556537738723;11219.382933938712;11347.375894325562;11606.187566870576;11637.359945203096;10009.599616015277;11869.881301187223;12219.938900305797;12336.568220112253;12673.93663031716;11861.881381186078;11884.405779711153;11945.223560468541;12053.81919271194;12164.75670486603;11590.826137607773;12001.63995080149;12013.099017573542;12108.455119519818;12328.445219964944;11386.36926889715;11564.537418503165;11578.826317605075;11639;11647.242929209575;9601.32790704644;9965.40207587547;9997.500124993867;10127.341722787995;10175.796484070424;8535.3598480113;9842.261830362895;9959.103680668775;9998.100170984646;10071.143952764098 10:32:40 INFO - PROCESS | 6264 | __end_tp_report 10:32:40 INFO - PROCESS | 6264 | __start_cc_report 10:32:40 INFO - PROCESS | 6264 | _x_x_mozilla_cycle_collect,355 10:32:40 INFO - PROCESS | 6264 | __end_cc_report 10:32:40 INFO - PROCESS | 6264 | __startTimestamp1460655160366__endTimestamp 10:32:40 INFO - PROCESS | 6264 | 10:32:40 INFO - PROCESS | 6264 | ------- Summary: start ------- 10:32:40 INFO - PROCESS | 6264 | Number of tests: 6 10:32:40 INFO - PROCESS | 6264 | 10:32:40 INFO - PROCESS | 6264 | [#0] dojo.html Cycles:165 Average:5806.42 Median:6039.09 stddev:673.14 (11.1%) stddev-sans-first:649.92 10:32:40 INFO - PROCESS | 6264 | Values: 3477.1 3511.8 3540.5 3575.9 3593.9 4459.3 5893.9 5922.0 5938.3 5945.5 5926.0 5926.3 5935.8 5943.0 5964.6 4339.4 5276.6 5361.2 5383.7 5399.8 5258.5 5337.9 5422.7 5428.0 5467.7 5427.2 5436.6 5445.9 5457.6 5463.6 4578.6 5319.4 5372.2 5383.9 5449.2 5218.3 5224.3 5295.0 5321.1 5358.0 5089.5 5259.5 5763.7 5859.3 5886.1 6229.1 6267.1 6273.1 6278.7 6285.3 5324.4 6110.3 6261.4 6328.5 6353.0 5925.1 5936.0 5942.6 5972.2 5975.0 6221.6 6275.3 6318.0 6319.7 6332.2 6170.3 6217.6 6258.7 6267.2 6275.9 6249.8 6308.6 6311.8 6316.8 6324.5 4878.5 5178.8 6131.0 6199.5 6287.1 6186.6 6247.2 6259.0 6275.3 6287.3 4954.7 4958.9 5003.4 5025.3 5040.5 3667.4 4419.2 4450.3 4529.9 4554.2 5745.9 5846.7 5904.4 5982.6 6013.0 6302.4 6317.9 6323.6 6326.7 6366.8 5759.1 5860.0 5987.7 6015.7 6026.6 5526.2 5527.2 5543.9 5581.5 5585.7 6454.6 6471.4 6476.3 6478.7 6494.9 6174.0 6201.0 6267.1 6312.3 6329.7 6433.7 6458.6 6461.1 6476.7 6480.6 6212.6 6259.6 6278.7 6283.7 6312.0 4593.6 6160.2 6243.1 6318.4 6318.6 6204.2 6209.4 6248.8 6250.3 6298.0 6216.6 6238.9 6254.2 6272.7 6273.7 5912.7 6031.7 6039.1 6127.7 6133.8 6171.2 6210.2 6242.8 6244.9 6258.1 6021.4 6166.2 6172.0 6176.3 6186.9 10:32:40 INFO - PROCESS | 6264 | 10:32:40 INFO - PROCESS | 6264 | [#1] ext.html Cycles:180 Average:63671.98 Median:40542.89 stddev:128198.61 (316.2%) stddev-sans-first:128470.75 10:32:40 INFO - PROCESS | 6264 | Values: 588.8 611.7 616.0 670.5 673.8 39584.2 40387.8 40593.2 40706.4 40810.4 42288.6 42570.8 42638.7 42772.9 42801.5 9551.0 9752.4 9757.3 9800.2 9807.9 9564.5 9788.7 9817.3 9834.4 9846.6 9755.3 9840.8 9855.6 9857.3 9902.7 9759.5 9813.5 9825.1 9830.7 9833.0 9757.1 9868.9 9869.8 9903.9 9922.4 26485.6 41255.6 41582.6 41691.0 41721.8 2611.3 2889.1 2987.3 2989.1 3479.6 29760.7 29864.7 30041.7 30243.8 30736.1 2439.6 2826.4 2837.8 2921.8 2990.0 423686.0 425881.0 426449.0 427430.0 428705.0 49355.3 71919.0 71923.0 72527.0 72876.3 48515.3 48574.8 48915.3 48955.5 49116.3 16997.7 17120.6 17166.2 17186.7 17209.3 13625.2 13671.9 13686.3 13689.9 13730.8 15810.8 15829.9 15931.2 16039.4 16112.6 5633.7 6594.7 6704.2 6732.4 6757.3 943.9 980.0 1055.5 1092.6 1102.2 30964.4 31055.0 31270.9 31336.1 31417.4 1041.6 1083.2 1102.0 1122.4 1151.7 13281.7 15565.2 15711.6 15728.6 16171.9 481469.0 497410.0 497435.6 498175.0 548697.0 68133.0 68183.7 69064.0 69192.0 69329.0 1473.3 1493.6 1497.1 1504.3 1510.2 31719.9 32916.3 32964.0 32972.5 33532.7 55908.7 56219.2 56452.3 56510.4 56599.4 511052.0 511416.0 511772.0 513987.0 514411.0 34763.8 35134.3 35780.8 35985.8 36042.6 47998.0 48133.5 48262.8 49201.8 49626.8 39125.4 39830.0 39911.7 40122.4 40152.4 39279.4 39824.2 40105.0 40468.6 40492.6 32794.0 38470.2 38966.4 39487.0 40180.0 34300.0 34367.0 35021.5 35217.8 35287.3 26586.9 26592.0 26680.7 26722.1 27017.1 10:32:40 INFO - PROCESS | 6264 | 10:32:40 INFO - PROCESS | 6264 | [#2] jquery.html Cycles:180 Average:34907.16 Median:2227.18 stddev:101938.64 (4577.0%) stddev-sans-first:102191.51 10:32:40 INFO - PROCESS | 6264 | Values: 315.8 381.9 389.1 391.0 391.4 19744.6 20043.9 20327.8 20941.0 20958.9 20187.5 20745.9 21676.5 21844.5 21987.9 5212.4 5862.2 5877.3 5878.3 5889.6 5180.5 5434.4 5758.4 5815.9 5816.5 5746.0 5751.7 5757.4 5757.5 5783.4 5483.8 5722.9 5760.5 5769.9 5774.3 4970.6 5011.4 5017.2 5024.4 5039.4 25564.7 26063.6 26250.5 26297.3 26303.6 2195.4 2224.4 2230.0 2282.0 2326.3 12240.6 14500.3 14813.8 14819.2 15132.4 1717.3 1727.9 1756.1 1771.0 1795.9 90510.5 93164.0 93392.0 93849.0 93961.5 10233.6 10669.3 10775.1 10806.9 10817.5 13136.7 13150.9 13187.2 13203.3 13247.7 8895.8 8912.9 8971.1 9001.7 9022.1 9292.8 9316.1 9320.8 9397.5 9403.1 6062.6 6193.0 6205.3 6222.9 6272.2 1857.1 1880.9 1890.8 1908.9 1928.8 902.2 1007.8 1023.0 1028.1 1156.9 18667.8 19447.5 19558.7 20840.0 21403.6 873.9 941.7 988.0 1089.4 1103.0 11551.7 11697.1 11772.8 11799.8 11810.3 538128.0 608695.0 622083.0 647502.0 647855.0 103835.0 132967.0 136988.0 137449.0 139768.2 1033.5 1039.1 1072.8 1078.1 1083.9 15054.2 18134.0 18259.3 18286.3 18655.1 52145.0 54206.0 54383.0 55445.4 56034.4 63577.0 77562.0 79776.0 80313.6 80645.0 8042.3 8113.9 8114.1 8157.8 8382.7 11918.5 13113.3 13245.0 13345.9 13463.3 11497.7 11562.9 11631.4 11661.2 11732.1 10491.9 10602.3 10922.8 11029.8 11513.4 9106.7 10877.7 11080.4 11242.2 11961.9 7597.2 7734.7 7736.5 7758.0 7798.1 9903.0 10524.8 10627.3 10673.7 10909.9 10:32:40 INFO - PROCESS | 6264 | 10:32:40 INFO - PROCESS | 6264 | [#3] mootools.html Cycles:180 Average:6282.38 Median:4993.86 stddev:3310.17 (66.3%) stddev-sans-first:3300.53 10:32:40 INFO - PROCESS | 6264 | Values: 1572.3 1638.5 1955.9 2039.4 2056.1 8289.3 8897.3 9110.5 9304.8 9306.2 8972.1 9071.5 9135.4 9147.1 9232.9 2550.7 2570.3 2573.7 2585.1 2589.4 2153.8 2524.8 2563.4 2574.7 2580.2 2497.5 2539.8 2543.7 2545.3 2555.7 2216.6 2465.7 2555.5 2561.9 2574.3 2179.5 2548.6 2582.1 2592.2 2597.8 9192.2 9233.2 9253.9 9265.4 9269.1 5561.8 5704.1 5711.5 5726.5 5750.2 3676.0 3741.1 3775.1 3780.6 3782.5 1059.3 1088.9 1091.9 1108.6 1108.6 12344.9 12406.9 12463.5 12485.6 12513.4 4459.3 4906.2 4978.3 5051.5 5090.0 10468.9 10579.3 10589.7 10614.0 10653.6 5744.7 5759.7 5765.1 5780.5 5812.5 4626.6 5009.4 5080.0 5099.5 5216.6 3518.2 4098.7 4129.3 4140.0 4156.4 1159.4 1351.7 1373.4 1378.1 1389.3 1366.3 1370.6 1384.8 1400.9 1407.6 8516.1 8538.0 8570.3 8591.0 8633.1 1155.9 1192.7 1193.1 1208.3 1271.3 6018.5 6578.6 6632.9 6643.4 6646.6 10615.6 11130.4 11148.2 11239.1 11263.2 10931.3 10966.7 11128.5 11169.5 11218.8 8665.2 8778.9 8790.7 8850.6 8878.2 8239.5 8297.5 8309.8 8325.5 8407.2 9983.4 10087.1 10110.2 10134.7 10191.3 8628.4 9254.7 10504.8 10537.0 10621.7 6630.8 7243.6 7313.5 7361.2 7452.4 7543.8 8247.6 8601.1 8620.1 8691.2 7484.4 7608.6 7629.4 7689.3 7709.3 6110.4 7318.3 7495.7 7537.2 7591.5 7394.5 7680.7 7910.4 8110.9 8225.1 6240.9 7285.6 7345.0 7412.5 7413.9 7479.6 7486.9 7503.5 7552.9 7553.0 10:32:40 INFO - PROCESS | 6264 | 10:32:40 INFO - PROCESS | 6264 | [#4] prototype.html Cycles:180 Average:6117.03 Median:6883.26 stddev:1993.69 (29.0%) stddev-sans-first:1950.70 10:32:40 INFO - PROCESS | 6264 | Values: 288.8 342.6 343.1 346.7 350.9 5227.4 6215.0 6507.7 7120.9 7123.6 6978.8 6983.4 6984.8 6999.3 7010.5 4901.7 5000.8 5071.2 5072.3 5192.9 5046.1 5076.5 5077.0 5090.7 5096.4 4995.8 5023.2 5027.3 5035.2 5051.7 5058.5 5059.5 5073.4 5073.5 5138.3 4405.7 4408.6 4415.1 4416.6 4434.7 6501.9 6572.9 6647.1 6730.2 6779.4 6005.2 6048.8 6059.8 6071.8 6080.8 7505.7 7551.9 7556.5 7570.7 7573.2 4966.4 4971.3 4974.4 4982.6 4987.0 7133.5 7943.7 7951.5 7957.7 7962.2 4933.6 4951.8 4977.8 4978.0 4994.7 3898.7 4689.3 5066.4 5086.7 5090.0 5971.5 5974.4 5978.6 5984.0 5990.8 7454.9 7485.5 7495.7 7569.7 7579.8 1640.7 1774.9 1794.4 1795.0 1799.6 1075.4 1075.8 1079.5 1086.4 1089.7 5781.4 5950.9 6764.9 6846.9 6849.3 7348.7 7755.1 7825.3 7825.8 7844.4 5888.9 6688.6 6917.2 6928.5 6983.2 3341.2 3990.4 4103.3 4124.6 4130.0 8044.2 8122.4 8266.5 8283.3 8293.5 6980.2 7866.7 8169.8 8209.5 8215.7 8212.0 8251.8 8254.4 8266.2 8287.4 7575.4 7896.1 7915.8 7926.6 7928.3 7686.2 7761.5 7763.9 7774.6 7782.6 8210.5 8212.3 8230.3 8233.5 8251.8 4878.7 4885.8 4886.0 4895.1 4908.9 7594.5 7852.0 7883.0 7885.3 7894.9 7532.4 7538.8 7562.4 7573.4 7593.2 6164.4 7520.9 7566.2 7568.4 7605.4 7002.0 7299.1 7379.1 7404.1 7405.4 7461.8 7484.1 7544.5 7563.9 7590.2 7363.9 7367.1 7374.0 7379.4 7379.9 10:32:40 INFO - PROCESS | 6264 | 10:32:40 INFO - PROCESS | 6264 | [#5] yui.html Cycles:180 Average:19469.54 Median:4147.72 stddev:28030.21 (675.8%) stddev-sans-first:28073.35 10:32:40 INFO - PROCESS | 6264 | Values: 682.9 721.2 750.7 776.7 787.2 15042.3 15213.8 15306.1 15333.5 15360.0 13675.2 13703.6 13730.6 13745.1 13767.0 756.7 767.9 936.9 971.8 973.2 959.0 964.8 973.4 974.3 978.7 1013.6 1018.4 1020.1 1022.2 1022.7 999.2 1003.4 1006.7 1012.5 1015.3 746.0 754.2 762.1 768.3 769.7 14188.7 14279.8 14296.6 14356.0 14377.4 11224.8 12972.3 13207.5 13263.3 13412.6 9851.1 10373.0 10410.2 10651.4 10706.5 3066.5 3509.0 3553.8 3567.1 3770.4 87424.6 87528.0 87756.0 88446.1 90464.5 9581.3 9773.7 9802.9 9804.9 10160.2 23581.9 23640.0 23649.3 23665.8 23818.5 7502.0 7566.5 7591.1 7629.2 7744.5 4092.8 4130.3 4144.6 4146.1 4149.3 3720.1 3754.9 3772.7 3774.9 3777.7 654.9 662.8 666.7 670.7 671.6 549.6 569.7 601.0 602.7 607.5 8430.7 8449.4 8508.9 8565.3 8697.1 577.1 597.2 611.2 619.5 623.1 3787.9 3923.4 3943.4 3971.2 4023.9 95614.0 100018.0 102092.0 102241.5 102346.0 80593.8 81104.2 83787.6 84998.6 85365.6 73942.6 74193.0 75793.2 75949.2 79155.0 8668.7 8744.5 8784.1 8797.8 8935.1 37590.0 52802.2 53145.5 53921.7 55190.0 73285.3 77102.6 77629.0 77639.2 78032.6 11086.6 11219.4 11347.4 11606.2 11637.4 10009.6 11869.9 12219.9 12336.6 12673.9 11861.9 11884.4 11945.2 12053.8 12164.8 11590.8 12001.6 12013.1 12108.5 12328.4 11386.4 11564.5 11578.8 11639.0 11647.2 9601.3 9965.4 9997.5 10127.3 10175.8 8535.4 9842.3 9959.1 9998.1 10071.1 10:32:40 INFO - PROCESS | 6264 | -------- Summary: end -------- 10:32:40 INFO - PROCESS | 6264 | 10:32:40 INFO - TEST-INFO | 6264: exit 0 10:32:40 INFO - TEST-OK | dromaeo_css | took 1110426ms 10:32:40 INFO - TEST-START | kraken 10:32:40 INFO - operating with platform_type : mac_ 10:32:40 INFO - Initialising browser for kraken test... 10:32:40 INFO - TEST-INFO | started process 6269 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/tmppkt5J4/profile http://localhost:56951/getInfo.html) 10:32:43 INFO - PROCESS | 6269 | __metrics Screen width/height:1600/1200 10:32:43 INFO - PROCESS | 6269 | colorDepth:24 10:32:43 INFO - PROCESS | 6269 | Browser inner width/height: 1024/689 10:32:43 INFO - PROCESS | 6269 | __metrics 10:32:43 ERROR - PROCESS | 6269 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2849: TypeError: this.tabs is undefined 10:32:44 INFO - TEST-INFO | 6269: exit 0 10:32:44 INFO - Browser initialized. 10:32:44 INFO - Running cycle 1/1 for kraken test... 10:32:44 INFO - TEST-INFO | started process 6272 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/tmppkt5J4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:32:56 INFO - PROCESS | 6272 | RSS: Main: 218046464 10:32:56 INFO - PROCESS | 6272 | 10:32:59 INFO - PROCESS | 6272 | Cycle 1(1): loaded http://localhost:56951/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56951/tests/kraken/driver.html?testName=audio-beat-detection) 10:32:59 INFO - PROCESS | 6272 | RSS: Main: 229896192 10:32:59 INFO - PROCESS | 6272 | 10:33:02 INFO - PROCESS | 6272 | Cycle 1(1): loaded http://localhost:56951/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56951/tests/kraken/driver.html?testName=audio-dft) 10:33:03 INFO - PROCESS | 6272 | RSS: Main: 239140864 10:33:03 INFO - PROCESS | 6272 | 10:33:06 INFO - PROCESS | 6272 | Cycle 1(1): loaded http://localhost:56951/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56951/tests/kraken/driver.html?testName=audio-fft) 10:33:06 INFO - PROCESS | 6272 | RSS: Main: 242929664 10:33:06 INFO - PROCESS | 6272 | 10:33:08 INFO - PROCESS | 6272 | Cycle 1(1): loaded http://localhost:56951/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56951/tests/kraken/driver.html?testName=audio-oscillator) 10:33:09 INFO - PROCESS | 6272 | RSS: Main: 244289536 10:33:09 INFO - PROCESS | 6272 | 10:33:11 INFO - PROCESS | 6272 | Cycle 1(1): loaded http://localhost:56951/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56951/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:33:12 INFO - PROCESS | 6272 | RSS: Main: 245248000 10:33:12 INFO - PROCESS | 6272 | 10:33:15 INFO - PROCESS | 6272 | Cycle 1(1): loaded http://localhost:56951/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56951/tests/kraken/driver.html?testName=imaging-darkroom) 10:33:15 INFO - PROCESS | 6272 | RSS: Main: 245202944 10:33:15 INFO - PROCESS | 6272 | 10:33:18 INFO - PROCESS | 6272 | Cycle 1(1): loaded http://localhost:56951/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56951/tests/kraken/driver.html?testName=imaging-desaturate) 10:33:19 INFO - PROCESS | 6272 | RSS: Main: 244588544 10:33:19 INFO - PROCESS | 6272 | 10:33:21 INFO - PROCESS | 6272 | Cycle 1(1): loaded http://localhost:56951/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56951/tests/kraken/driver.html?testName=json-parse-financial) 10:33:22 INFO - PROCESS | 6272 | RSS: Main: 244883456 10:33:22 INFO - PROCESS | 6272 | 10:33:23 INFO - PROCESS | 6272 | Cycle 1(1): loaded http://localhost:56951/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56951/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:33:24 INFO - PROCESS | 6272 | RSS: Main: 244977664 10:33:24 INFO - PROCESS | 6272 | 10:33:26 INFO - PROCESS | 6272 | Cycle 1(1): loaded http://localhost:56951/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56951/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:33:26 INFO - PROCESS | 6272 | RSS: Main: 244895744 10:33:26 INFO - PROCESS | 6272 | 10:33:29 INFO - PROCESS | 6272 | Cycle 1(1): loaded http://localhost:56951/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56951/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:33:29 INFO - PROCESS | 6272 | RSS: Main: 245231616 10:33:29 INFO - PROCESS | 6272 | 10:33:32 INFO - PROCESS | 6272 | Cycle 1(1): loaded http://localhost:56951/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56951/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:33:32 INFO - PROCESS | 6272 | RSS: Main: 245100544 10:33:32 INFO - PROCESS | 6272 | 10:33:35 INFO - PROCESS | 6272 | Cycle 1(1): loaded http://localhost:56951/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56951/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:33:35 INFO - PROCESS | 6272 | RSS: Main: 245198848 10:33:35 INFO - PROCESS | 6272 | 10:33:37 INFO - PROCESS | 6272 | Cycle 1(1): loaded http://localhost:56951/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56951/tests/kraken/driver.html?testName=ai-astar) 10:33:37 INFO - PROCESS | 6272 | RSS: Main: 246812672 10:33:37 INFO - PROCESS | 6272 | 10:33:37 INFO - PROCESS | 6272 | __start_tp_report 10:33:37 INFO - PROCESS | 6272 | _x_x_mozilla_page_load 10:33:37 INFO - PROCESS | 6272 | _x_x_mozilla_page_load_details 10:33:37 INFO - PROCESS | 6272 | |i|pagename|runs| 10:33:37 INFO - PROCESS | 6272 | |0;ai-astar;118;142;165;103;141;132;169;169;96;161 10:33:37 INFO - PROCESS | 6272 | |1;audio-beat-detection;150;134;180;180;156;172;119;150;147;117 10:33:37 INFO - PROCESS | 6272 | |2;audio-dft;198;205;215;196;196;197;194;198;200;195 10:33:37 INFO - PROCESS | 6272 | |3;audio-fft;67;77;84;103;73;46;50;50;130;82 10:33:37 INFO - PROCESS | 6272 | |4;audio-oscillator;83;80;124;80;80;82;120;77;79;119 10:33:37 INFO - PROCESS | 6272 | |5;imaging-gaussian-blur;136;131;87;88;130;85;88;85;86;84 10:33:37 INFO - PROCESS | 6272 | |6;imaging-darkroom;93;94;93;93;95;93;94;94;94;93 10:33:37 INFO - PROCESS | 6272 | |7;imaging-desaturate;90;94;91;93;94;91;91;90;84;93 10:33:37 INFO - PROCESS | 6272 | |8;json-parse-financial;57;60;59;58;60;62;61;69;57;56 10:33:37 INFO - PROCESS | 6272 | |9;json-stringify-tinderbox;66;76;67;72;78;72;58;79;99;102 10:33:37 INFO - PROCESS | 6272 | |10;stanford-crypto-aes;83;83;85;83;81;82;84;81;84;84 10:33:37 INFO - PROCESS | 6272 | |11;stanford-crypto-ccm;124;121;131;145;129;118;127;141;132;134 10:33:37 INFO - PROCESS | 6272 | |12;stanford-crypto-pbkdf2;124;133;138;135;138;139;132;139;133;138 10:33:37 INFO - PROCESS | 6272 | |13;stanford-crypto-sha256-iterative;70;68;67;65;67;65;65;69;70;67 10:33:37 INFO - PROCESS | 6272 | __end_tp_report 10:33:37 INFO - PROCESS | 6272 | __start_cc_report 10:33:37 INFO - PROCESS | 6272 | _x_x_mozilla_cycle_collect,941 10:33:37 INFO - PROCESS | 6272 | __end_cc_report 10:33:37 INFO - PROCESS | 6272 | __startTimestamp1460655217818__endTimestamp 10:33:37 INFO - PROCESS | 6272 | 10:33:37 INFO - PROCESS | 6272 | ------- Summary: start ------- 10:33:37 INFO - PROCESS | 6272 | Number of tests: 14 10:33:37 INFO - PROCESS | 6272 | 10:33:37 INFO - PROCESS | 6272 | [#0] ai-astar Cycles:10 Average:139.60 Median:163.00 stddev:27.09 (16.6%) stddev-sans-first:27.58 10:33:37 INFO - PROCESS | 6272 | Values: 118.0 142.0 165.0 103.0 141.0 132.0 169.0 169.0 96.0 161.0 10:33:37 INFO - PROCESS | 6272 | 10:33:37 INFO - PROCESS | 6272 | [#1] audio-beat-detection Cycles:10 Average:150.50 Median:153.00 stddev:22.69 (14.8%) stddev-sans-first:24.06 10:33:37 INFO - PROCESS | 6272 | Values: 150.0 134.0 180.0 180.0 156.0 172.0 119.0 150.0 147.0 117.0 10:33:37 INFO - PROCESS | 6272 | 10:33:37 INFO - PROCESS | 6272 | [#2] audio-dft Cycles:10 Average:199.40 Median:198.00 stddev:6.29 (3.2%) stddev-sans-first:6.65 10:33:37 INFO - PROCESS | 6272 | Values: 198.0 205.0 215.0 196.0 196.0 197.0 194.0 198.0 200.0 195.0 10:33:37 INFO - PROCESS | 6272 | 10:33:37 INFO - PROCESS | 6272 | [#3] audio-fft Cycles:10 Average:76.20 Median:70.00 stddev:26.01 (37.2%) stddev-sans-first:27.37 10:33:37 INFO - PROCESS | 6272 | Values: 67.0 77.0 84.0 103.0 73.0 46.0 50.0 50.0 130.0 82.0 10:33:37 INFO - PROCESS | 6272 | 10:33:37 INFO - PROCESS | 6272 | [#4] audio-oscillator Cycles:10 Average:92.40 Median:80.00 stddev:19.84 (24.8%) stddev-sans-first:20.75 10:33:37 INFO - PROCESS | 6272 | Values: 83.0 80.0 124.0 80.0 80.0 82.0 120.0 77.0 79.0 119.0 10:33:37 INFO - PROCESS | 6272 | 10:33:37 INFO - PROCESS | 6272 | [#5] imaging-gaussian-blur Cycles:10 Average:100.00 Median:85.50 stddev:22.40 (26.2%) stddev-sans-first:19.61 10:33:37 INFO - PROCESS | 6272 | Values: 136.0 131.0 87.0 88.0 130.0 85.0 88.0 85.0 86.0 84.0 10:33:37 INFO - PROCESS | 6272 | 10:33:37 INFO - PROCESS | 6272 | [#6] imaging-darkroom Cycles:10 Average:93.60 Median:94.00 stddev:0.70 (0.7%) stddev-sans-first:0.71 10:33:37 INFO - PROCESS | 6272 | Values: 93.0 94.0 93.0 93.0 95.0 93.0 94.0 94.0 94.0 93.0 10:33:37 INFO - PROCESS | 6272 | 10:33:37 INFO - PROCESS | 6272 | [#7] imaging-desaturate Cycles:10 Average:91.10 Median:92.00 stddev:2.92 (3.2%) stddev-sans-first:3.07 10:33:37 INFO - PROCESS | 6272 | Values: 90.0 94.0 91.0 93.0 94.0 91.0 91.0 90.0 84.0 93.0 10:33:37 INFO - PROCESS | 6272 | 10:33:37 INFO - PROCESS | 6272 | [#8] json-parse-financial Cycles:10 Average:59.90 Median:60.00 stddev:3.73 (6.2%) stddev-sans-first:3.80 10:33:37 INFO - PROCESS | 6272 | Values: 57.0 60.0 59.0 58.0 60.0 62.0 61.0 69.0 57.0 56.0 10:33:37 INFO - PROCESS | 6272 | 10:33:37 INFO - PROCESS | 6272 | [#9] json-stringify-tinderbox Cycles:10 Average:76.90 Median:74.00 stddev:13.93 (18.8%) stddev-sans-first:14.21 10:33:37 INFO - PROCESS | 6272 | Values: 66.0 76.0 67.0 72.0 78.0 72.0 58.0 79.0 99.0 102.0 10:33:37 INFO - PROCESS | 6272 | 10:33:37 INFO - PROCESS | 6272 | [#10] stanford-crypto-aes Cycles:10 Average:83.00 Median:83.50 stddev:1.33 (1.6%) stddev-sans-first:1.41 10:33:37 INFO - PROCESS | 6272 | Values: 83.0 83.0 85.0 83.0 81.0 82.0 84.0 81.0 84.0 84.0 10:33:37 INFO - PROCESS | 6272 | 10:33:37 INFO - PROCESS | 6272 | [#11] stanford-crypto-ccm Cycles:10 Average:130.20 Median:131.50 stddev:8.42 (6.4%) stddev-sans-first:8.62 10:33:37 INFO - PROCESS | 6272 | Values: 124.0 121.0 131.0 145.0 129.0 118.0 127.0 141.0 132.0 134.0 10:33:37 INFO - PROCESS | 6272 | 10:33:37 INFO - PROCESS | 6272 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:134.90 Median:138.00 stddev:4.68 (3.4%) stddev-sans-first:2.85 10:33:37 INFO - PROCESS | 6272 | Values: 124.0 133.0 138.0 135.0 138.0 139.0 132.0 139.0 133.0 138.0 10:33:37 INFO - PROCESS | 6272 | 10:33:37 INFO - PROCESS | 6272 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:67.50 stddev:1.95 (2.9%) stddev-sans-first:1.80 10:33:37 INFO - PROCESS | 6272 | Values: 70.0 68.0 67.0 65.0 67.0 65.0 65.0 69.0 70.0 67.0 10:33:37 INFO - PROCESS | 6272 | -------- Summary: end -------- 10:33:37 INFO - PROCESS | 6272 | 10:33:38 INFO - TEST-INFO | 6272: exit 0 10:33:38 INFO - TEST-OK | kraken | took 57683ms 10:33:38 INFO - SUITE-END | took 1168s 10:33:38 INFO - Completed test suite (00:19:28) 10:33:38 INFO - javascript benchmark 10:33:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3477.0785741778427, 3511.771734837236, 3540.504329393885, 3575.9450961966218, 3593.8562457501703, 4459.331100334952, 5893.9115913261285, 5921.993261145605, 5938.346781853998, 5945.46490815827, 5925.970370148154, 5926.318473375563, 5935.792247271346, 5942.970285148579, 5964.642121472714, 4339.370791235269, 5276.577873770108, 5361.222622719703, 5383.67697938123, 5399.757010934498, 5258.474152584749, 5337.946620533803, 5422.674639521621, 5427.972860135694, 5467.699276539789, 5427.240186373891, 5436.646617969839, 5445.891082178354, 5457.590680698943, 5463.562914966813, 4578.626412076381, 5319.414864364917, 5372.1941708744, 5383.946160538403, 5449.237106805051, 5218.321618189649, 5224.268602395668, 5295.0468915595175, 5321.122014867581, 5357.955198736248, 5089.491050894918, 5259.526642602146, 5763.711814409263, 5859.341823250526, 5886.087656413263, 6229.127922090911, 6267.122602835607, 6273.05904114382, 6278.6546739929745, 6285.340039295854, 5324.440933701983, 6110.285381942539, 6261.436470717612, 6328.493720502349, 6353.047042943549, 5925.051991681354, 5935.9686701720575, 5942.64344139353, 5972.223610930551, 5974.970125149346, 6221.62670239778, 6275.309715931244, 6317.9684101580115, 6319.652419116993, 6332.208473940757, 6170.259568851767, 6217.564770466114, 6258.718357673915, 6267.185265915402, 6275.874482510416, 6249.812505624838, 6308.621482711052, 6311.842203944938, 6316.8420789479715, 6324.494040476751, 4878.463369029404, 5178.766955487012, 6131.009681329197, 6199.473044791153, 6287.056941458818, 6186.597871138361, 6247.156633854372, 6258.968705156445, 6275.309715931244, 6287.339829317947, 4954.727489988085, 4958.9008219835405, 5003.374578177728, 5025.346704928409, 5040.521150490697, 3667.394879844849, 4419.204543182193, 4450.332450132441, 4529.886752831206, 4554.24854898938, 5745.85635359111, 5846.6784326862435, 5904.439078287556, 5982.551308651869, 6012.969935150296, 6302.369763023661, 6317.851035908827, 6323.588966717149, 6326.683665816783, 6366.840828979312, 5759.078547432391, 5860.00379935412, 5987.670678112659, 6015.6691381974415, 6026.638401695912, 5526.198701188305, 5527.226188333637, 5543.889122217533, 5581.5255703265675, 5585.720713964285, 6454.6449945252525, 6471.417572418505, 6476.319986401454, 6478.740850366027, 6494.902576461452, 6174.043023331391, 6200.968995154995, 6267.122602835698, 6312.274088479883, 6329.7468101275435, 6433.678316084271, 6458.644774537445, 6461.0954466373805, 6476.70854811544, 6480.611163330215, 6212.565120441525, 6259.593126446766, 6278.686065696788, 6283.748650053946, 6312.0, 4593.586577208067, 6160.229971253593, 6243.094751261133, 6318.4313411793155, 6318.62088274705, 6204.162438070984, 6209.410106039919, 6248.843778905381, 6250.343713910156, 6298.02380631003, 6216.627002379872, 6238.937610624017, 6254.186955695913, 6272.686365681607, 6273.71768270438, 5912.70436478183, 6031.728572214173, 6039.094135879477, 6127.724252408563, 6133.815985520441, 6171.179502046996, 6210.161628180319, 6242.812715618538, 6244.875102498016, 6258.061290806233, 6021.397860214119, 6166.229221347332, 6171.9691401542705, 6176.289726681489, 6186.876262474815], "unit": "score", "name": "dojo.html", "value": 5766.429794930111}, {"lowerIsBetter": false, "replicates": [588.7900364750438, 611.7400104955393, 615.95380346474, 670.4837275298021, 673.7670063783277, 39584.20831583367, 40387.79806100966, 40593.18813623726, 40706.38940415892, 40810.38784418229, 42288.57711422885, 42570.78714606431, 42638.654873982, 42772.9306767331, 42801.50194743185, 9551.044895510446, 9752.366096203725, 9757.316987810857, 9800.166985806214, 9807.852882206773, 9564.473953932531, 9788.706338809847, 9817.263705222102, 9834.409935403863, 9846.60613575456, 9755.268354873377, 9840.753981150458, 9855.55649995752, 9857.309988300822, 9902.702918912444, 9759.463229522373, 9813.460259685715, 9825.1157395834, 9830.705078847646, 9832.950835245778, 9757.12185903265, 9868.90131098687, 9869.802603947881, 9903.900960990442, 9922.40465572068, 26485.602715959278, 41255.58744412548, 41582.584174158175, 41691.0, 41721.79139104315, 2611.3341098020073, 2889.061055157082, 2987.253186703324, 2989.1032690192856, 3479.565054368204, 29760.70239297601, 29864.70135298641, 30041.69958300411, 30243.848780756176, 30736.077917662507, 2439.621858611918, 2826.434713057397, 2837.787165962561, 2921.839298838563, 2989.970100298991, 423686.0, 425881.0, 426449.0, 427430.0, 428705.0, 49355.25967110496, 71919.0, 71923.0, 72527.0, 72876.271237288, 48515.272270915964, 48574.75712621414, 48915.26627100597, 48955.51044489581, 49116.263256051185, 16997.745033824503, 17120.57198570046, 17166.22751976164, 17186.656266874594, 17209.311627535008, 13625.182489050689, 13671.931640341734, 13686.31568421575, 13689.931550342184, 13730.794038089436, 15810.841891581167, 15829.920850395674, 15931.203439827963, 16039.358425662842, 16112.597185070466, 5633.690147041952, 6594.670266486753, 6704.195496540447, 6732.394084532416, 6757.3242675732035, 943.8914524829595, 979.9755006124761, 1055.525013743803, 1092.63396762084, 1102.1953973599318, 30964.380712386075, 31055.0, 31270.90551830675, 31336.059918202034, 31417.37165256682, 1041.578160844844, 1083.2417307884512, 1101.9724506887233, 1122.36586328724, 1151.717829131868, 13281.734365312834, 15565.22173891301, 15711.607209819846, 15728.606784830243, 16171.919140404223, 481469.0, 497410.0, 497435.5641108929, 498175.0, 548697.0, 68133.0, 68183.65908170427, 69064.0, 69191.96212056823, 69329.0, 1473.3296350160651, 1493.626593351662, 1497.1017389566173, 1504.262911173517, 1510.1995942150677, 31719.889803856753, 32916.341673166884, 32964.011079667645, 32972.50541241931, 33532.65869365296, 55908.72045639746, 56219.15671265016, 56452.30643080714, 56510.43489565052, 56599.434005659416, 511052.0, 511416.0, 511772.0, 513987.0, 514411.0, 34763.826180868935, 35134.29731405307, 35780.82109589435, 35985.82007089948, 36042.639573604974, 47998.0, 48133.5186648129, 48262.75868620635, 49201.753991229816, 49626.75186624044, 39125.41311880381, 39830.0, 39911.716936199184, 40122.39816402698, 40152.39771403373, 39279.41080883846, 39824.20351592894, 40105.0, 40468.583599573874, 40492.595074048884, 32794.0, 38470.23059538849, 38966.4155037669, 39487.0, 40180.0, 34300.0, 34367.0, 35021.54849160535, 35217.82391088028, 35287.29425411529, 26586.867065664548, 26592.0, 26680.733192667823, 26722.064727734436, 27017.054403095797], "unit": "score", "name": "ext.html", "value": 20124.310901511388}, {"lowerIsBetter": false, "replicates": [315.7947334232747, 381.9305943358596, 389.07594463150014, 390.9960900390995, 391.35426546198767, 19744.605107897834, 20043.899780501095, 20327.796722032774, 20941.0, 20958.89520552399, 20187.485938554593, 20745.896270518664, 21676.458088547795, 21844.45388865275, 21987.900604969727, 5212.4266330703595, 5862.179294898718, 5877.265341832271, 5878.265216847894, 5889.64662120272, 5180.45605211453, 5434.375046869602, 5758.395368486312, 5815.912761308584, 5816.476517113459, 5746.0, 5751.654900705971, 5757.366689664133, 5757.539396848263, 5783.392743761908, 5483.75323110459, 5722.942770572283, 5760.4527569881, 5769.913451298234, 5774.2782152230975, 4970.627202959792, 5011.448740638527, 5017.197248440232, 5024.422191447994, 5039.44566097729, 25564.74435255661, 26063.609045864327, 26250.474990500083, 26297.3425664358, 26303.605445918325, 2195.3633446300714, 2224.377174391173, 2229.977700222993, 2281.9657705134437, 2326.255598208574, 12240.571579994657, 14500.347484363228, 14813.777793333109, 14819.18494482803, 15132.394704211929, 1717.252994947202, 1727.8876873003214, 1756.0956107604593, 1770.9645807083914, 1795.9191836367265, 90510.54744726235, 93164.0, 93392.0, 93849.0, 93961.5301923486, 10233.59065637381, 10669.253152279422, 10775.137988961022, 10806.891931080745, 10817.513211905483, 13136.737265254833, 13150.934245328712, 13187.208767473981, 13203.273819939803, 13247.735045299232, 8895.777605559784, 8912.910870891208, 8971.05803890595, 9001.684941027164, 9022.14289642483, 9292.814143717223, 9316.068393160629, 9320.766980825534, 9397.530123493934, 9403.106704863027, 6062.6359069209075, 6193.040078787705, 6205.255369355616, 6222.937770622236, 6272.18461599989, 1857.1364315593044, 1880.8589355798097, 1890.7825600055894, 1908.9427317180507, 1928.816762407569, 902.179017094454, 1007.793402352516, 1022.9795404091876, 1028.0850043461326, 1156.8785277545906, 18667.786593871628, 19447.513812154528, 19558.70661940072, 20840.0, 21403.57192856134, 873.8727042115727, 941.7033634405141, 987.980240395188, 1089.3790539392471, 1103.007293435914, 11551.653450396501, 11697.064234861191, 11772.764544709058, 11799.823002654966, 11810.291382517076, 538128.0, 608695.0, 622083.0, 647502.0, 647855.0, 103835.0, 132967.0, 136988.0, 137449.0, 139768.2046359067, 1033.5142483032962, 1039.0700323210835, 1072.753266748652, 1078.132103656564, 1083.869935607732, 15054.247287635793, 18134.0, 18259.269629214683, 18286.268549258413, 18655.067246637886, 52145.0, 54206.0, 54383.0, 55445.44554554564, 56034.439655604554, 63577.0, 77562.0, 79776.0, 80313.59843200746, 80644.98387540123, 8042.316403105801, 8113.945187125872, 8114.107448180577, 8157.79605509867, 8382.74851754424, 11918.52325906954, 13113.27876966776, 13245.0, 13345.866541334462, 13463.326833658473, 11497.655070347568, 11562.94218528902, 11631.418429078343, 11661.183717139718, 11732.061435085343, 10491.895081049091, 10602.25784195099, 10922.836157457485, 11029.83455248188, 11513.366764828015, 9106.726798196065, 10877.67366978992, 11080.445977701243, 11242.213045087092, 11961.940190299341, 7597.164311925794, 7734.690612375442, 7736.535807851547, 7758.0, 7798.142204357403, 9903.0, 10524.842127368249, 10627.256092073481, 10673.733156671145, 10909.890901091205], "unit": "score", "name": "jquery.html", "value": 9662.109898274319}, {"lowerIsBetter": false, "replicates": [1572.3317590024237, 1638.4674980631294, 1955.9119839607217, 2039.40857151426, 2056.085042156241, 8289.253967142971, 8897.332700047506, 9110.453372797629, 9304.767380815483, 9306.20897223736, 8972.147645973622, 9071.455712657245, 9135.360524763253, 9147.131022552847, 9232.8615070774, 2550.7449255074484, 2570.267473769976, 2573.665423494943, 2585.133980116663, 2589.3785491482004, 2153.773853745358, 2524.798016158712, 2563.3976015636313, 2574.665293511841, 2580.2001379572393, 2497.4880149569294, 2539.7968162546954, 2543.6566063581363, 2545.2618740565213, 2555.667763190792, 2216.6231740604135, 2465.716442609105, 2555.501677172952, 2561.859097749623, 2574.304937666838, 2179.487820362214, 2548.566743653565, 2582.109172335541, 2592.183462209398, 2597.8181527293095, 9192.21866141372, 9233.215176709988, 9253.861192082124, 9265.397749146285, 9269.073092690744, 5561.77752889888, 5704.1443783432405, 5711.543076553908, 5726.5418766499, 5750.223719797816, 3675.9632403676155, 3741.0834345585504, 3775.1128484806322, 3780.5652349979828, 3782.470454136423, 1059.3061544688242, 1088.9401082940435, 1091.9344839309667, 1108.5842808946645, 1108.6175269531998, 12344.876551234553, 12406.937965310115, 12463.501459941592, 12485.625431236987, 12513.436895339732, 4459.331100334911, 4906.215005599159, 4978.327925730053, 5051.545360917535, 5090.007448547572, 10468.947655261674, 10579.259451838298, 10589.735256618493, 10613.991670791262, 10653.627123050655, 5744.684042377709, 5759.6832174229985, 5765.077587585966, 5780.479756821906, 5812.534997200214, 4626.606738427204, 5009.398872135367, 5079.974600126976, 5099.464556221617, 5216.556592689588, 3518.208403109331, 4098.6516146127315, 4129.318662420667, 4140.0, 4156.376543518496, 1159.4492616007328, 1351.6553278913814, 1373.416298073315, 1378.0973462382156, 1389.2706329177263, 1366.3100134432248, 1370.616227456314, 1384.764590019699, 1400.9299535023208, 1407.56365526685, 8516.105808889977, 8538.0, 8570.271526920282, 8590.957045214735, 8633.093525179891, 1155.942202889852, 1192.6779769462196, 1193.1111322065117, 1208.2629595946466, 1271.262667652759, 6018.458338749533, 6578.6052836828985, 6632.900506492407, 6643.435307998874, 6646.60120392778, 10615.628453004108, 11130.443477826238, 11148.163887708455, 11239.10087193039, 11263.211575189822, 10931.289466184831, 10966.725831854268, 11128.49921753523, 11169.497372618252, 11218.831717524243, 8665.220130188314, 8778.868316975251, 8790.692325768567, 8850.557472126367, 8878.200961913459, 8239.464434811718, 8297.502149871027, 8309.83662287268, 8325.500469971821, 8407.159284071544, 9983.400995940267, 10087.092161705481, 10110.241731870286, 10134.746631334276, 10191.337563058378, 8628.439151455135, 9254.676086336945, 10504.78990420172, 10537.0, 10621.734456638645, 6630.801075967729, 7243.601601911945, 7313.524620899624, 7361.190269070291, 7452.403807695263, 7543.849123017619, 8247.587620618826, 8601.139886011599, 8620.094890036456, 8691.217790398894, 7484.438667100098, 7608.581528016012, 7629.427792915442, 7689.3464055555905, 7709.306162445407, 6110.358412366638, 7318.3047610476915, 7495.662695178621, 7537.2085930976455, 7591.506552074097, 7394.482386232912, 7680.694281971966, 7910.367170626221, 8110.918890811016, 8225.095239523767, 6240.937590624036, 7285.635718213962, 7345.0084238627105, 7412.481126321106, 7413.925860741324, 7479.58862262559, 7486.850262994819, 7503.474756766974, 7552.8867066995135, 7553.018107645971], "unit": "score", "name": "mootools.html", "value": 5134.8414099879055}, {"lowerIsBetter": false, "replicates": [288.8079427180925, 342.57862828720675, 343.1284537275321, 346.667199488491, 350.91928856363035, 5227.424983251839, 6215.0, 6507.707153178107, 7120.9287907120915, 7123.608201548906, 6978.790636280907, 6983.371496565308, 6984.79045628632, 6999.300069992998, 7010.509264351497, 4901.65688401812, 5000.849974500771, 5071.2139618359215, 5072.3405957225505, 5192.948070519285, 5046.066477701618, 5076.543111119999, 5076.974615126937, 5090.694558326494, 5096.388433387999, 4995.825146119886, 5023.171176755831, 5027.271045698371, 5035.194368900977, 5051.696898186102, 5058.4688607696025, 5059.544640982311, 5073.391193056838, 5073.452596957926, 5138.332016837825, 4405.713628614129, 4408.58118478744, 4415.116976604692, 4416.6025057744955, 4434.733915965053, 6501.9349806501805, 6572.86854262912, 6647.135872336566, 6730.226024007253, 6779.35596118368, 6005.219321488178, 6048.818535443944, 6059.848503787396, 6071.848203794896, 6080.787172448943, 7505.737299194557, 7551.924480755177, 7556.47104702671, 7570.659320330598, 7573.166951635316, 4966.379202599675, 4971.27916452116, 4974.37820272466, 4982.626303027288, 4986.975065124688, 7133.536320139175, 7943.721969731089, 7951.483153594998, 7957.721479748239, 7962.203779622049, 4933.629977751683, 4951.826686066042, 4977.751112444364, 4978.0, 4994.650374473752, 3898.7465814721954, 4689.320048592935, 5066.36670416198, 5086.745662716923, 5090.0, 5971.522278217819, 5974.4324289192455, 5978.551608629283, 5983.970080149571, 5990.760369585168, 7454.850902982019, 7485.47601667878, 7495.737649182252, 7569.697212111454, 7579.772606821693, 1640.6554623528955, 1774.8846324988835, 1794.3540325482816, 1794.9910250448925, 1799.6490684316432, 1075.4406111138724, 1075.8278675411896, 1079.519613771864, 1086.3861918016453, 1089.7330154112135, 5781.364049954585, 5950.899083669535, 6764.93235067643, 6846.863062738817, 6849.349311815369, 7348.706051757977, 7755.069391673035, 7825.256600622932, 7825.804354891059, 7844.411669124838, 5888.851673923629, 6688.565243259172, 6917.169939607279, 6928.515003949675, 6983.171815414765, 3341.2315167511124, 3990.38149086892, 4103.261412945639, 4124.608162224584, 4130.0, 8044.155363686728, 8122.431429799975, 8266.504009759434, 8283.337333013344, 8293.502389856629, 6980.197277313073, 7866.724664636825, 8169.836603267901, 8209.507429554245, 8215.671373145007, 8211.958940205499, 8251.8349633007, 8254.42219044673, 8266.173382661806, 8287.378446616527, 7575.393968482398, 7896.131425543071, 7915.802104947423, 7926.603669816602, 7928.326092282221, 7686.231376862269, 7761.495502792526, 7763.92236077632, 7774.572398517909, 7782.571958542335, 8210.507369557845, 8212.260896519341, 8230.341572674291, 8233.46482478637, 8251.793705157419, 4878.731669758055, 4885.804567817389, 4886.047220791911, 4895.143349913822, 4908.9263661045825, 7594.5063570869925, 7851.960740196262, 7882.960585197037, 7885.250901164381, 7894.881576776238, 7532.435067370079, 7538.773836784905, 7562.395008399425, 7573.431992600466, 7593.240675932362, 6164.4143806338325, 7520.9094681272, 7566.205548417337, 7568.4323675723435, 7605.391568674382, 7002.019717239488, 7299.087614048244, 7379.114506259283, 7404.074490688664, 7405.407567394704, 7461.776146715607, 7484.139323977813, 7544.509606875536, 7563.886541701769, 7590.203028681909, 7363.852722945404, 7367.121384047248, 7373.963130184315, 7379.372753316028, 7379.926200737924], "unit": "score", "name": "prototype.html", "value": 5443.878288208226}, {"lowerIsBetter": false, "replicates": [682.9009793579928, 721.1994685898652, 750.7259850154695, 776.6660336055496, 787.1734678587485, 15042.323095460702, 15213.84786152138, 15306.081635101888, 15333.539993800176, 15360.0, 13675.179489230653, 13703.58889233322, 13730.58808235752, 13745.106568073094, 13766.967477439182, 756.6821934787375, 767.9462437629368, 936.9484678342689, 971.825071487132, 973.2360097323602, 959.0313783079106, 964.768455570665, 973.4305431322684, 974.3277138774223, 978.6917121106865, 1013.5591017907198, 1018.4042335233897, 1020.0513522424143, 1022.2128960700256, 1022.6727447216894, 999.2355847776494, 1003.413003393016, 1006.66276797273, 1012.5038731021783, 1015.3400289811608, 745.9738909138208, 754.2457542457543, 762.0740799928059, 768.2663056780762, 769.6728890221633, 14188.716225675533, 14279.78580321296, 14296.642583935381, 14355.99508034438, 14377.424903003868, 11224.765275819653, 12972.286524241163, 13207.471701131943, 13263.336833158304, 13412.597622071353, 9851.113399794052, 10373.0, 10410.219233557438, 10651.414172220524, 10706.518206680717, 3066.47869862124, 3508.982455087734, 3553.8045407502577, 3567.090391950062, 3770.359038963383, 87424.5628771852, 87528.0, 87756.0, 88446.11553884443, 90464.54767726186, 9581.281403894736, 9773.727685581587, 9802.852957205647, 9804.901950980398, 10160.187185025181, 23581.88209058978, 23640.0, 23649.29052128439, 23665.7633423667, 23818.523629527313, 7501.962490187514, 7566.470347075652, 7591.089069311607, 7629.2370762923265, 7744.49660772048, 4092.7544347338658, 4130.256553820341, 4144.585541445831, 4146.129312844337, 4149.315362965137, 3720.069982504374, 3754.9249015019545, 3772.7359084863797, 3774.8675397380675, 3777.7355585109326, 654.8919428294373, 662.7912207654573, 666.7099811581977, 670.6914819183132, 671.5735507952485, 549.5548605629454, 569.6724383479516, 600.9699515024231, 602.7106988645475, 607.4593611685603, 8430.662773488992, 8449.36629752771, 8508.914910850812, 8565.27195188416, 8697.086805885418, 577.0615548878918, 597.2474681900836, 611.2054329371799, 619.5074915442234, 623.1213988276509, 3787.905302367463, 3923.4221236031995, 3943.4479172915817, 3971.225611005884, 4023.8591649292134, 95614.0438595619, 100018.0, 102092.0, 102241.48879255557, 102346.0, 80593.79109313365, 81104.18895811084, 83787.5810620943, 84998.57500712457, 85365.57317213374, 73942.63028684822, 74193.0, 75793.24206757972, 75949.24050759533, 79155.0, 8668.653253869774, 8744.475331480131, 8784.121587841164, 8797.780055498664, 8935.061818509223, 37590.0, 52802.20796687976, 53145.4685453156, 53921.730391347795, 55190.0, 73285.26714732785, 77102.61448692721, 77629.0, 77639.22360776545, 78032.60983695272, 11086.556537738723, 11219.382933938712, 11347.375894325562, 11606.187566870576, 11637.359945203096, 10009.599616015277, 11869.881301187223, 12219.938900305797, 12336.568220112253, 12673.93663031716, 11861.881381186078, 11884.405779711153, 11945.223560468541, 12053.81919271194, 12164.75670486603, 11590.826137607773, 12001.63995080149, 12013.099017573542, 12108.455119519818, 12328.445219964944, 11386.36926889715, 11564.537418503165, 11578.826317605075, 11639.0, 11647.242929209575, 9601.32790704644, 9965.40207587547, 9997.500124993867, 10127.341722787995, 10175.796484070424, 8535.3598480113, 9842.261830362895, 9959.103680668775, 9998.100170984646, 10071.143952764098], "unit": "score", "name": "yui.html", "value": 7168.919601667683}], "name": "dromaeo_css", "value": 7797.169129061355}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [118.0, 142.0, 165.0, 103.0, 141.0, 132.0, 169.0, 169.0, 96.0, 161.0], "unit": "score", "name": "ai-astar", "value": 139.6}, {"lowerIsBetter": true, "replicates": [150.0, 134.0, 180.0, 180.0, 156.0, 172.0, 119.0, 150.0, 147.0, 117.0], "unit": "score", "name": "audio-beat-detection", "value": 150.5}, {"lowerIsBetter": true, "replicates": [198.0, 205.0, 215.0, 196.0, 196.0, 197.0, 194.0, 198.0, 200.0, 195.0], "unit": "score", "name": "audio-dft", "value": 199.4}, {"lowerIsBetter": true, "replicates": [67.0, 77.0, 84.0, 103.0, 73.0, 46.0, 50.0, 50.0, 130.0, 82.0], "unit": "score", "name": "audio-fft", "value": 76.2}, {"lowerIsBetter": true, "replicates": [83.0, 80.0, 124.0, 80.0, 80.0, 82.0, 120.0, 77.0, 79.0, 119.0], "unit": "score", "name": "audio-oscillator", "value": 92.4}, {"lowerIsBetter": true, "replicates": [136.0, 131.0, 87.0, 88.0, 130.0, 85.0, 88.0, 85.0, 86.0, 84.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 100.0}, {"lowerIsBetter": true, "replicates": [93.0, 94.0, 93.0, 93.0, 95.0, 93.0, 94.0, 94.0, 94.0, 93.0], "unit": "score", "name": "imaging-darkroom", "value": 93.6}, {"lowerIsBetter": true, "replicates": [90.0, 94.0, 91.0, 93.0, 94.0, 91.0, 91.0, 90.0, 84.0, 93.0], "unit": "score", "name": "imaging-desaturate", "value": 91.1}, {"lowerIsBetter": true, "replicates": [57.0, 60.0, 59.0, 58.0, 60.0, 62.0, 61.0, 69.0, 57.0, 56.0], "unit": "score", "name": "json-parse-financial", "value": 59.9}, {"lowerIsBetter": true, "replicates": [66.0, 76.0, 67.0, 72.0, 78.0, 72.0, 58.0, 79.0, 99.0, 102.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 76.9}, {"lowerIsBetter": true, "replicates": [83.0, 83.0, 85.0, 83.0, 81.0, 82.0, 84.0, 81.0, 84.0, 84.0], "unit": "score", "name": "stanford-crypto-aes", "value": 83.0}, {"lowerIsBetter": true, "replicates": [124.0, 121.0, 131.0, 145.0, 129.0, 118.0, 127.0, 141.0, 132.0, 134.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 130.2}, {"lowerIsBetter": true, "replicates": [124.0, 133.0, 138.0, 135.0, 138.0, 139.0, 132.0, 139.0, 133.0, 138.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 134.9}, {"lowerIsBetter": true, "replicates": [70.0, 68.0, 67.0, 65.0, 67.0, 65.0, 65.0, 69.0, 70.0, 67.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 67.3}], "name": "kraken", "value": 1495.0000000000002}]} 10:33:39 INFO - Return code: 0 10:33:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 10:33:39 ERROR - # TBPL SUCCESS # 10:33:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:33:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:33:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:33:39 INFO - 2016-04-14 10:33:39.118 screenresolution[6277:104617] starting screenresolution argv=screenresolution get 10:33:39 INFO - 2016-04-14 10:33:39.120 screenresolution[6277:104617] Display 0: 1600x1200x32@60 10:33:39 INFO - 2016-04-14 10:33:39.126 screenresolution[6278:104620] starting screenresolution argv=screenresolution list 10:33:39 INFO - Available Modes on Display 0 10:33:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:33:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:33:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:33:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:33:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:33:39 INFO - Intel Iris: 10:33:39 INFO - Chipset Model: Intel Iris 10:33:39 INFO - Type: GPU 10:33:39 INFO - Bus: Built-In 10:33:39 INFO - VRAM (Dynamic, Max): 1536 MB 10:33:39 INFO - Vendor: Intel (0x8086) 10:33:39 INFO - Device ID: 0x0a2e 10:33:39 INFO - Revision ID: 0x0009 10:33:39 INFO - Displays: 10:33:39 INFO - Display: 10:33:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:33:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:33:39 INFO - Display Serial Number: 1600x1200 60 10:33:39 INFO - Main Display: Yes 10:33:39 INFO - Mirror: Off 10:33:39 INFO - Online: Yes 10:33:39 INFO - Rotation: Supported 10:33:39 INFO - Return code: 0 10:33:39 INFO - Running post-action listener: _resource_record_post_action 10:33:39 INFO - Running post-run listener: _resource_record_post_run 10:33:40 INFO - Total resource usage - Wall time: 1203s; CPU: 53.0%; Read bytes: 1605120; Write bytes: 670842368; Read time: 26; Write time: 2247 10:33:40 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 351339008; Write bytes: 353859584; Read time: 27820; Write time: 694 10:33:40 INFO - run-tests - Wall time: 1171s; CPU: 53.0%; Read bytes: 1605120; Write bytes: 305509888; Read time: 26; Write time: 1517 10:33:40 INFO - Running post-run listener: _upload_blobber_files 10:33:40 INFO - Blob upload gear active. 10:33:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:33:40 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 10:33:40 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'] 10:33:40 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 10:33:40 INFO - (blobuploader) - INFO - Open directory for files ... 10:33:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:33:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:33:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:33:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:33:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:33:42 INFO - (blobuploader) - INFO - Done attempting. 10:33:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:33:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:33:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:33:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:33:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:33:43 INFO - (blobuploader) - INFO - Done attempting. 10:33:43 INFO - (blobuploader) - INFO - Iteration through files over. 10:33:43 INFO - Return code: 0 10:33:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:33:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:33:43 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/78c38e82af4fab0ef48171fe22e4808c5ed6e39e98d3a77f4a08a5ca6aeab939d44633735b6f87066f8e99b33d5a65b793a3695eb270a66e8771f9877aaf2d54", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a78a26bb2d9b614b208279e8418b1d53ac5c01f91a1936732b599ae78c09cc257e5d6199b69b10553d2327579c07bf5ca0073122af69b9af0295c9426b002e72"} 10:33:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:33:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:33:43 INFO - Contents: 10:33:43 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/78c38e82af4fab0ef48171fe22e4808c5ed6e39e98d3a77f4a08a5ca6aeab939d44633735b6f87066f8e99b33d5a65b793a3695eb270a66e8771f9877aaf2d54", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a78a26bb2d9b614b208279e8418b1d53ac5c01f91a1936732b599ae78c09cc257e5d6199b69b10553d2327579c07bf5ca0073122af69b9af0295c9426b002e72"} 10:33:43 INFO - Running post-run listener: copy_logs_to_upload_dir 10:33:43 INFO - Copying logs to upload dir... 10:33:43 INFO - mkdir: /builds/slave/test/build/upload/logs 10:33:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.558970 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-04-14 10:33:43.121396) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-14 10:33:43.125348) ========= 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.L0KKuOpl6J/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.Lov7XyUMMI/Listeners TMPDIR=/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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/78c38e82af4fab0ef48171fe22e4808c5ed6e39e98d3a77f4a08a5ca6aeab939d44633735b6f87066f8e99b33d5a65b793a3695eb270a66e8771f9877aaf2d54", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a78a26bb2d9b614b208279e8418b1d53ac5c01f91a1936732b599ae78c09cc257e5d6199b69b10553d2327579c07bf5ca0073122af69b9af0295c9426b002e72"} build_url:https://queue.taskcluster.net/v1/task/Ndv9_s5HQv6jEotVgtLlvg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011387 build_url: 'https://queue.taskcluster.net/v1/task/Ndv9_s5HQv6jEotVgtLlvg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/78c38e82af4fab0ef48171fe22e4808c5ed6e39e98d3a77f4a08a5ca6aeab939d44633735b6f87066f8e99b33d5a65b793a3695eb270a66e8771f9877aaf2d54", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a78a26bb2d9b614b208279e8418b1d53ac5c01f91a1936732b599ae78c09cc257e5d6199b69b10553d2327579c07bf5ca0073122af69b9af0295c9426b002e72"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-14 10:33:43.154310) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 10:33:43.154705) ========= 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.L0KKuOpl6J/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.Lov7XyUMMI/Listeners TMPDIR=/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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.005055 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 10:33:43.204005) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-14 10:33:43.204360) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-14 10:33:43.204758) ========= ========= Total master_lag: 20.03 =========