builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0167 starttime: 1461098032.0 results: success (0) buildid: 20160419122433 builduid: e6f8cdf11034455da7cbb73d61f2164f revision: b2cc32b7bbe6a7a999dabacf600444169bc3b3e6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-19 13:33:52.001355) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-19 13:33:52.006943) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-19 13:33:52.007682) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-19 13:33:52.032154) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 13:33:52.032879) ========= 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.UekTpTjc6r/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.7mJNBKECHZ/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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-19 13:33:52-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 71.9M=0s 2016-04-19 13:33:52 (71.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.131909 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 13:33:52.190523) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 13:33:52.191417) ========= 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.UekTpTjc6r/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.7mJNBKECHZ/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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.026186 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 13:33:52.243591) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-19 13:33:52.244490) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev b2cc32b7bbe6a7a999dabacf600444169bc3b3e6 --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 b2cc32b7bbe6a7a999dabacf600444169bc3b3e6 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.UekTpTjc6r/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.7mJNBKECHZ/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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-19 13:33:52,323 truncating revision to first 12 chars 2016-04-19 13:33:52,323 Setting DEBUG logging. 2016-04-19 13:33:52,323 attempt 1/10 2016-04-19 13:33:52,323 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/b2cc32b7bbe6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-19 13:33:53,854 unpacking tar archive at: mozilla-aurora-b2cc32b7bbe6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.782801 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-19 13:33:54.047073) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-19 13:33:54.047446) ========= script_repo_revision: b2cc32b7bbe6a7a999dabacf600444169bc3b3e6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-19 13:33:54.047933) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-19 13:33:54.048245) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-19 13:33:54.062440) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-04-19 13:33:54.062958) ========= /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.UekTpTjc6r/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.7mJNBKECHZ/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 13:33:54 INFO - MultiFileLogger online at 20160419 13:33:54 in /builds/slave/test 13:33:54 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 13:33:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:33:54 INFO - {'append_to_log': False, 13:33:54 INFO - 'base_work_dir': '/builds/slave/test', 13:33:54 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 13:33:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:33:54 INFO - 'branch': 'Mozilla-Aurora', 13:33:54 INFO - 'buildbot_json_path': 'buildprops.json', 13:33:54 INFO - 'config_files': ('talos/mac_config.py',), 13:33:54 INFO - 'default_actions': ('clobber', 13:33:54 INFO - 'read-buildbot-config', 13:33:54 INFO - 'download-and-extract', 13:33:54 INFO - 'populate-webroot', 13:33:54 INFO - 'create-virtualenv', 13:33:54 INFO - 'install', 13:33:54 INFO - 'run-tests'), 13:33:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:33:54 INFO - 'download_minidump_stackwalk': True, 13:33:54 INFO - 'download_symbols': 'ondemand', 13:33:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:33:54 INFO - 'tooltool.py': '/tools/tooltool.py', 13:33:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:33:54 INFO - '/tools/misc-python/virtualenv.py')}, 13:33:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:33:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:33:54 INFO - 'installer_path': 'installer.exe', 13:33:54 INFO - 'log_level': 'info', 13:33:54 INFO - 'log_name': 'talos', 13:33:54 INFO - 'log_to_console': True, 13:33:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:33:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:33:54 INFO - 'opt_config_files': (), 13:33:54 INFO - 'pip_index': False, 13:33:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:33:54 INFO - 'cmd': ('bash', 13:33:54 INFO - '-c', 13:33:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:33:54 INFO - 'enabled': True, 13:33:54 INFO - 'halt_on_failure': False, 13:33:54 INFO - 'name': 'check_screen_resolution'},), 13:33:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:33:54 INFO - 'cmd': ('bash', 13:33:54 INFO - '-c', 13:33:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:33:54 INFO - 'enabled': True, 13:33:54 INFO - 'halt_on_failure': False, 13:33:54 INFO - 'name': 'check_screen_resolution'},), 13:33:54 INFO - 'run_cmd_checks_enabled': True, 13:33:54 INFO - 'sps_profile': False, 13:33:54 INFO - 'sps_profile_interval': 0, 13:33:54 INFO - 'suite': 'dromaeojs-e10s', 13:33:54 INFO - 'system_bits': '32', 13:33:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:33:54 INFO - 'title': 't-yosemite-r7-0167', 13:33:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:33:54 INFO - 'use_talos_json': True, 13:33:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:33:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:33:54 INFO - 'work_dir': 'build'} 13:33:54 INFO - ##### 13:33:54 INFO - ##### Running clobber step. 13:33:54 INFO - ##### 13:33:54 INFO - Running pre-action listener: _resource_record_pre_action 13:33:54 INFO - Running main action method: clobber 13:33:54 INFO - rmtree: /builds/slave/test/build 13:33:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:33:55 INFO - Running post-action listener: _resource_record_post_action 13:33:55 INFO - ##### 13:33:55 INFO - ##### Running read-buildbot-config step. 13:33:55 INFO - ##### 13:33:55 INFO - Running pre-action listener: _resource_record_pre_action 13:33:55 INFO - Running main action method: read_buildbot_config 13:33:55 INFO - Using buildbot properties: 13:33:55 INFO - { 13:33:55 INFO - "project": "", 13:33:55 INFO - "product": "firefox", 13:33:55 INFO - "script_repo_revision": "production", 13:33:55 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 13:33:55 INFO - "repository": "", 13:33:55 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 13:33:55 INFO - "buildid": "20160419122433", 13:33:55 INFO - "pgo_build": "False", 13:33:55 INFO - "basedir": "/builds/slave/test", 13:33:55 INFO - "buildnumber": 281, 13:33:55 INFO - "slavename": "t-yosemite-r7-0167", 13:33:55 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 13:33:55 INFO - "platform": "yosemite_r7", 13:33:55 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 13:33:55 INFO - "branch": "mozilla-aurora", 13:33:55 INFO - "repo_path": "releases/mozilla-aurora", 13:33:55 INFO - "revision": "b2cc32b7bbe6a7a999dabacf600444169bc3b3e6", 13:33:55 INFO - "stage_platform": "macosx64", 13:33:55 INFO - "builduid": "e6f8cdf11034455da7cbb73d61f2164f", 13:33:55 INFO - "slavebuilddir": "test" 13:33:55 INFO - } 13:33:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/TP9zI02fSdeM1EEkvIIWWw/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg. 13:33:55 INFO - Running post-action listener: _resource_record_post_action 13:33:55 INFO - ##### 13:33:55 INFO - ##### Running download-and-extract step. 13:33:55 INFO - ##### 13:33:55 INFO - Running pre-action listener: _resource_record_pre_action 13:33:55 INFO - Running main action method: download_and_extract 13:33:55 INFO - mkdir: /builds/slave/test/build/tests 13:33:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:33:55 INFO - https://queue.taskcluster.net/v1/task/TP9zI02fSdeM1EEkvIIWWw/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 13:33:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TP9zI02fSdeM1EEkvIIWWw/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 13:33:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TP9zI02fSdeM1EEkvIIWWw/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 13:33:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TP9zI02fSdeM1EEkvIIWWw/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 13:33:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TP9zI02fSdeM1EEkvIIWWw/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 13:33:57 INFO - Downloaded 1277 bytes. 13:33:57 INFO - Reading from file /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 13:33:57 INFO - Using the following test package requirements: 13:33:57 INFO - {u'common': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 13:33:57 INFO - u'cppunittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 13:33:57 INFO - u'firefox-47.0a2.en-US.mac.cppunittest.tests.zip'], 13:33:57 INFO - u'gtest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 13:33:57 INFO - u'firefox-47.0a2.en-US.mac.gtest.tests.zip'], 13:33:57 INFO - u'jittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 13:33:57 INFO - u'jsshell-mac.zip'], 13:33:57 INFO - u'mochitest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 13:33:57 INFO - u'firefox-47.0a2.en-US.mac.mochitest.tests.zip'], 13:33:57 INFO - u'mozbase': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 13:33:57 INFO - u'reftest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 13:33:57 INFO - u'firefox-47.0a2.en-US.mac.reftest.tests.zip'], 13:33:57 INFO - u'talos': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 13:33:57 INFO - u'firefox-47.0a2.en-US.mac.talos.tests.zip'], 13:33:57 INFO - u'web-platform': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 13:33:57 INFO - u'firefox-47.0a2.en-US.mac.web-platform.tests.zip'], 13:33:57 INFO - u'webapprt': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 13:33:57 INFO - u'xpcshell': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 13:33:57 INFO - u'firefox-47.0a2.en-US.mac.xpcshell.tests.zip']} 13:33:57 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip'] for test suite category: common 13:33:57 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')]} 13:33:57 INFO - https://queue.taskcluster.net/v1/task/TP9zI02fSdeM1EEkvIIWWw/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:33:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TP9zI02fSdeM1EEkvIIWWw/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 13:33:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TP9zI02fSdeM1EEkvIIWWw/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 13:33:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TP9zI02fSdeM1EEkvIIWWw/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 13:33:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TP9zI02fSdeM1EEkvIIWWw/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 13:33:59 INFO - Downloaded 17911292 bytes. 13:33:59 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'] 13:33:59 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 13:34:02 INFO - Return code: 0 13:34:02 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 13:34:02 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')]} 13:34:02 INFO - https://queue.taskcluster.net/v1/task/TP9zI02fSdeM1EEkvIIWWw/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:34:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TP9zI02fSdeM1EEkvIIWWw/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 13:34:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TP9zI02fSdeM1EEkvIIWWw/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 13:34:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TP9zI02fSdeM1EEkvIIWWw/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 13:34:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TP9zI02fSdeM1EEkvIIWWw/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 13:34:03 INFO - Downloaded 17911292 bytes. 13:34:03 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'] 13:34:03 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 13:34:06 INFO - Return code: 0 13:34: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')]} 13:34:06 INFO - https://queue.taskcluster.net/v1/task/TP9zI02fSdeM1EEkvIIWWw/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 13:34:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TP9zI02fSdeM1EEkvIIWWw/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 13:34:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TP9zI02fSdeM1EEkvIIWWw/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 13:34:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TP9zI02fSdeM1EEkvIIWWw/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 13:34:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TP9zI02fSdeM1EEkvIIWWw/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 13:34:07 INFO - Downloaded 10845168 bytes. 13:34:07 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'] 13:34:07 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 13:34:08 INFO - Return code: 0 13:34: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')]} 13:34:08 INFO - https://queue.taskcluster.net/v1/task/TP9zI02fSdeM1EEkvIIWWw/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 13:34:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TP9zI02fSdeM1EEkvIIWWw/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 13:34:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TP9zI02fSdeM1EEkvIIWWw/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 13:34:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TP9zI02fSdeM1EEkvIIWWw/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg 13:34:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TP9zI02fSdeM1EEkvIIWWw/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 13:34:11 INFO - Downloaded 115247307 bytes. 13:34:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TP9zI02fSdeM1EEkvIIWWw/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 13:34:11 INFO - mkdir: /builds/slave/test/properties 13:34:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:34:11 INFO - Writing to file /builds/slave/test/properties/build_url 13:34:11 INFO - Contents: 13:34:11 INFO - build_url:https://queue.taskcluster.net/v1/task/TP9zI02fSdeM1EEkvIIWWw/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 13:34:12 INFO - Running post-action listener: _resource_record_post_action 13:34:12 INFO - Running post-action listener: set_extra_try_arguments 13:34:12 INFO - ##### 13:34:12 INFO - ##### Running populate-webroot step. 13:34:12 INFO - ##### 13:34:12 INFO - Running pre-action listener: _resource_record_pre_action 13:34:12 INFO - Running main action method: populate_webroot 13:34:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:34:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:34:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:34:12 INFO - '--tppagecycles', 13:34:12 INFO - '7'], 13:34:12 INFO - 'tests': ['tsvgm']}}, 13:34:12 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 13:34:12 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 13:34:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:34:12 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 13:34:12 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 13:34:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:34:12 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:34:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:34:12 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:34:12 INFO - 'talos_options': ['--e10s'], 13:34:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:34:12 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:34:12 INFO - 'tests': ['damp', 'tps']}, 13:34:12 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:34:12 INFO - 'talos_options': ['--e10s'], 13:34:12 INFO - 'tests': ['damp', 'tps']}, 13:34:12 INFO - 'g3': {'tests': ['dromaeo_dom']}, 13:34:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:34:12 INFO - 'other': {'tests': ['a11yr', 13:34:12 INFO - 'ts_paint', 13:34:12 INFO - 'tpaint', 13:34:12 INFO - 'sessionrestore', 13:34:12 INFO - 'sessionrestore_no_auto_restore', 13:34:12 INFO - 'tabpaint']}, 13:34:12 INFO - 'other-e10s': {'talos_options': ['--e10s'], 13:34:12 INFO - 'tests': ['a11yr', 13:34:12 INFO - 'ts_paint', 13:34:12 INFO - 'tpaint', 13:34:12 INFO - 'sessionrestore', 13:34:12 INFO - 'sessionrestore_no_auto_restore', 13:34:12 INFO - 'tabpaint']}, 13:34:12 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 13:34:12 INFO - 'tests': ['a11yr', 13:34:12 INFO - 'ts_paint', 13:34:12 INFO - 'tpaint', 13:34:12 INFO - 'sessionrestore', 13:34:12 INFO - 'sessionrestore_no_auto_restore', 13:34:12 INFO - 'tabpaint']}, 13:34:12 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 13:34:12 INFO - 'tests': ['a11yr', 13:34:12 INFO - 'ts_paint', 13:34:12 INFO - 'tpaint', 13:34:12 INFO - 'sessionrestore', 13:34:12 INFO - 'sessionrestore_no_auto_restore', 13:34:12 INFO - 'tabpaint']}, 13:34:12 INFO - 'other_l64': {'tests': ['a11yr', 13:34:12 INFO - 'ts_paint', 13:34:12 INFO - 'tpaint', 13:34:12 INFO - 'sessionrestore', 13:34:12 INFO - 'sessionrestore_no_auto_restore', 13:34:12 INFO - 'tabpaint']}, 13:34:12 INFO - 'other_nol64': {'tests': ['a11yr', 13:34:12 INFO - 'ts_paint', 13:34:12 INFO - 'tpaint', 13:34:12 INFO - 'sessionrestore', 13:34:12 INFO - 'sessionrestore_no_auto_restore', 13:34:12 INFO - 'tabpaint']}, 13:34:12 INFO - 'svgr': {'tests': ['tsvgx', 13:34:12 INFO - 'tsvgr_opacity', 13:34:12 INFO - 'tart', 13:34:12 INFO - 'tscrollx', 13:34:12 INFO - 'cart']}, 13:34:12 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 13:34:12 INFO - 'tests': ['tsvgx', 13:34:12 INFO - 'tsvgr_opacity', 13:34:12 INFO - 'tart', 13:34:12 INFO - 'tscrollx', 13:34:12 INFO - 'cart']}, 13:34:12 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:34:12 INFO - 'tests': ['tp5o']}, 13:34:12 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:34:12 INFO - 'talos_options': ['--e10s'], 13:34:12 INFO - 'tests': ['tp5o']}, 13:34:12 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:34:12 INFO - 'talos_options': ['--xperf_path', 13:34:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:34:12 INFO - 'tests': ['tp5n']}, 13:34:12 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:34:12 INFO - 'talos_options': ['--e10s', 13:34:12 INFO - '--xperf_path', 13:34:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:34:12 INFO - 'tests': ['tp5n']}}, 13:34:12 INFO - 'talos.zip': {'path': '', 13:34:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:34:12 INFO - Running post-action listener: _resource_record_post_action 13:34:12 INFO - ##### 13:34:12 INFO - ##### Running create-virtualenv step. 13:34:12 INFO - ##### 13:34:12 INFO - Running pre-action listener: _resource_record_pre_action 13:34:12 INFO - Running main action method: create_virtualenv 13:34:12 INFO - Creating virtualenv /builds/slave/test/build/venv 13:34:12 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 13:34:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:34:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:34:12 INFO - Using real prefix '/tools/python27' 13:34:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:34:13 INFO - Installing distribute.............................................................................................................................................................................................done. 13:34:16 INFO - Installing pip.................done. 13:34:16 INFO - Return code: 0 13:34:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:34:16 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')]} 13:34:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:34:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:16 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')]} 13:34:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:34:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:16 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 0x10c245a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c24c4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6a1d985c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0e9d30>, '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 0x10bf4ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c0e0b20>, '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.UekTpTjc6r/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.7mJNBKECHZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 13:34:16 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 13:34:16 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 13:34:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UekTpTjc6r/Render', 13:34:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:34:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:34:16 INFO - 'HOME': '/Users/cltbld', 13:34:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:34:16 INFO - 'LOGNAME': 'cltbld', 13:34:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:34:16 INFO - 'MOZ_NO_REMOTE': '1', 13:34:16 INFO - 'NO_EM_RESTART': '1', 13:34:16 INFO - 'PAGER': '/bin/cat', 13:34:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:34:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:34:16 INFO - 'PWD': '/builds/slave/test', 13:34:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:34:16 INFO - 'SHELL': '/bin/bash', 13:34:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7mJNBKECHZ/Listeners', 13:34:16 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 13:34:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:34:16 INFO - 'USER': 'cltbld', 13:34:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:34:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:34:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:34:16 INFO - 'XPC_FLAGS': '0x0', 13:34:16 INFO - 'XPC_SERVICE_NAME': '0', 13:34:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:34:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:34:16 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 13:34:16 INFO - Cleaning up... 13:34:16 INFO - Return code: 0 13:34:16 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 13:34:16 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')]} 13:34:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:34:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:16 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')]} 13:34:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:34:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:16 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 0x10c245a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c24c4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6a1d985c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0e9d30>, '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 0x10bf4ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c0e0b20>, '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.UekTpTjc6r/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.7mJNBKECHZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 13:34:16 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 13:34:16 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 13:34:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UekTpTjc6r/Render', 13:34:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:34:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:34:16 INFO - 'HOME': '/Users/cltbld', 13:34:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:34:16 INFO - 'LOGNAME': 'cltbld', 13:34:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:34:16 INFO - 'MOZ_NO_REMOTE': '1', 13:34:16 INFO - 'NO_EM_RESTART': '1', 13:34:16 INFO - 'PAGER': '/bin/cat', 13:34:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:34:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:34:16 INFO - 'PWD': '/builds/slave/test', 13:34:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:34:16 INFO - 'SHELL': '/bin/bash', 13:34:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7mJNBKECHZ/Listeners', 13:34:16 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 13:34:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:34:16 INFO - 'USER': 'cltbld', 13:34:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:34:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:34:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:34:16 INFO - 'XPC_FLAGS': '0x0', 13:34:16 INFO - 'XPC_SERVICE_NAME': '0', 13:34:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:34:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:34:16 INFO - Downloading/unpacking psutil>=0.7.1 13:34: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 13:34: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 13:34: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 13:34: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 13:34: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 13:34: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 13:34:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:34:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:34:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:34:19 INFO - Installing collected packages: psutil 13:34:19 INFO - Running setup.py install for psutil 13:34:19 INFO - building 'psutil._psutil_osx' extension 13:34:19 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 13:34:20 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 13:34:20 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 13:34:20 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 13:34:20 INFO - building 'psutil._psutil_posix' extension 13:34:20 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 13:34:20 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:34:20 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:34:20 INFO - ^ 13:34:20 INFO - 1 warning generated. 13:34:20 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 13:34:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:34:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:34:20 INFO - Successfully installed psutil 13:34:20 INFO - Cleaning up... 13:34:20 INFO - Return code: 0 13:34:20 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 13:34:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:34:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:34:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:34:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:34:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:20 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 0x10c245a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c24c4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6a1d985c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0e9d30>, '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 0x10bf4ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c0e0b20>, '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.UekTpTjc6r/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.7mJNBKECHZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 13:34:20 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 13:34:20 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 13:34:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UekTpTjc6r/Render', 13:34:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:34:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:34:20 INFO - 'HOME': '/Users/cltbld', 13:34:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:34:20 INFO - 'LOGNAME': 'cltbld', 13:34:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:34:20 INFO - 'MOZ_NO_REMOTE': '1', 13:34:20 INFO - 'NO_EM_RESTART': '1', 13:34:20 INFO - 'PAGER': '/bin/cat', 13:34:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:34:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:34:20 INFO - 'PWD': '/builds/slave/test', 13:34:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:34:20 INFO - 'SHELL': '/bin/bash', 13:34:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7mJNBKECHZ/Listeners', 13:34:20 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 13:34:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:34:20 INFO - 'USER': 'cltbld', 13:34:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:34:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:34:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:34:20 INFO - 'XPC_FLAGS': '0x0', 13:34:20 INFO - 'XPC_SERVICE_NAME': '0', 13:34:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:34:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:34:21 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 13:34:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:34:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:34:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:34:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:34:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:34:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:34:23 INFO - Downloading mozsystemmonitor-0.0.tar.gz 13:34:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:34:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 13:34:23 INFO - Installing collected packages: mozsystemmonitor 13:34:23 INFO - Running setup.py install for mozsystemmonitor 13:34:23 INFO - Successfully installed mozsystemmonitor 13:34:23 INFO - Cleaning up... 13:34:23 INFO - Return code: 0 13:34:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:34:23 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')]} 13:34:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:34:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:23 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')]} 13:34:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:34:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:23 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 0x10c245a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c24c4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6a1d985c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0e9d30>, '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 0x10bf4ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c0e0b20>, '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.UekTpTjc6r/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.7mJNBKECHZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 13:34:23 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 13:34:23 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 13:34:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UekTpTjc6r/Render', 13:34:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:34:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:34:23 INFO - 'HOME': '/Users/cltbld', 13:34:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:34:23 INFO - 'LOGNAME': 'cltbld', 13:34:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:34:23 INFO - 'MOZ_NO_REMOTE': '1', 13:34:23 INFO - 'NO_EM_RESTART': '1', 13:34:23 INFO - 'PAGER': '/bin/cat', 13:34:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:34:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:34:23 INFO - 'PWD': '/builds/slave/test', 13:34:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:34:23 INFO - 'SHELL': '/bin/bash', 13:34:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7mJNBKECHZ/Listeners', 13:34:23 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 13:34:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:34:23 INFO - 'USER': 'cltbld', 13:34:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:34:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:34:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:34:23 INFO - 'XPC_FLAGS': '0x0', 13:34:23 INFO - 'XPC_SERVICE_NAME': '0', 13:34:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:34:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:34:24 INFO - Downloading/unpacking blobuploader==1.2.4 13:34: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 13:34: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 13:34: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 13:34: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 13:34: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 13:34: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 13:34:26 INFO - Downloading blobuploader-1.2.4.tar.gz 13:34:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:34:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:34:26 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 13:34:26 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 13:34:26 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 13:34:26 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 13:34:26 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 13:34:26 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 13:34:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:34:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:34:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:34:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:34:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:34:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:34:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:34:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:34:27 INFO - Downloading docopt-0.6.1.tar.gz 13:34:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:34:27 INFO - Installing collected packages: blobuploader, requests, docopt 13:34:27 INFO - Running setup.py install for blobuploader 13:34:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:34:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:34:27 INFO - Running setup.py install for requests 13:34:28 INFO - Running setup.py install for docopt 13:34:28 INFO - Successfully installed blobuploader requests docopt 13:34:28 INFO - Cleaning up... 13:34:28 INFO - Return code: 0 13:34:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:34: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')]} 13:34:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:34:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34: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')]} 13:34:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:34:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:28 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 0x10c245a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c24c4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6a1d985c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0e9d30>, '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 0x10bf4ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c0e0b20>, '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.UekTpTjc6r/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.7mJNBKECHZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 13:34:28 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 13:34:28 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 13:34:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UekTpTjc6r/Render', 13:34:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:34:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:34:28 INFO - 'HOME': '/Users/cltbld', 13:34:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:34:28 INFO - 'LOGNAME': 'cltbld', 13:34:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:34:28 INFO - 'MOZ_NO_REMOTE': '1', 13:34:28 INFO - 'NO_EM_RESTART': '1', 13:34:28 INFO - 'PAGER': '/bin/cat', 13:34:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:34:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:34:28 INFO - 'PWD': '/builds/slave/test', 13:34:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:34:28 INFO - 'SHELL': '/bin/bash', 13:34:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7mJNBKECHZ/Listeners', 13:34:28 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 13:34:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:34:28 INFO - 'USER': 'cltbld', 13:34:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:34:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:34:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:34:28 INFO - 'XPC_FLAGS': '0x0', 13:34:28 INFO - 'XPC_SERVICE_NAME': '0', 13:34:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:34:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:34:28 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-BFA0XY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:34:29 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-kn6YhY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:34:29 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-pZwIjP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:34:29 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-Fr6fPY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:34:29 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-gkdY9D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:34:29 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-aiIlNC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:34:29 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-OTyzNG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:34:29 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-Bz6bou-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:34:29 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-1WScMO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:34:29 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-rWqonw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:34:29 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-dKAJoG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:34:30 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-u8NrgP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:34:30 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-hQ7s28-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:34:30 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-U_isdS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:34:30 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-PEDhRV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:34:30 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-n8EUlK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:34:30 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-dA6Wz5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:34:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:34:30 INFO - Running setup.py install for manifestparser 13:34:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:34:31 INFO - Running setup.py install for mozcrash 13:34:31 INFO - Running setup.py install for mozdebug 13:34:31 INFO - Running setup.py install for mozdevice 13:34:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:34:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:34:31 INFO - Running setup.py install for mozfile 13:34:31 INFO - Running setup.py install for mozhttpd 13:34:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:34:31 INFO - Running setup.py install for mozinfo 13:34:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:34:31 INFO - Running setup.py install for mozInstall 13:34:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:34:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:34:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:34:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:34:32 INFO - Running setup.py install for mozleak 13:34:32 INFO - Running setup.py install for mozlog 13:34:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:34:32 INFO - Running setup.py install for moznetwork 13:34:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:34:32 INFO - Running setup.py install for mozprocess 13:34:32 INFO - Running setup.py install for mozprofile 13:34:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:34:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:34:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:34:32 INFO - Running setup.py install for mozrunner 13:34:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:34:33 INFO - Running setup.py install for mozscreenshot 13:34:33 INFO - Running setup.py install for moztest 13:34:33 INFO - Running setup.py install for mozversion 13:34:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:34:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:34:33 INFO - Cleaning up... 13:34:33 INFO - Return code: 0 13:34:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:34:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:34:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:34:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:34:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:34:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:33 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 0x10c245a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c24c4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6a1d985c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0e9d30>, '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 0x10bf4ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c0e0b20>, '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.UekTpTjc6r/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.7mJNBKECHZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 13:34:33 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 13:34:33 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 13:34:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UekTpTjc6r/Render', 13:34:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:34:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:34:33 INFO - 'HOME': '/Users/cltbld', 13:34:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:34:33 INFO - 'LOGNAME': 'cltbld', 13:34:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:34:33 INFO - 'MOZ_NO_REMOTE': '1', 13:34:33 INFO - 'NO_EM_RESTART': '1', 13:34:33 INFO - 'PAGER': '/bin/cat', 13:34:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:34:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:34:33 INFO - 'PWD': '/builds/slave/test', 13:34:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:34:33 INFO - 'SHELL': '/bin/bash', 13:34:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7mJNBKECHZ/Listeners', 13:34:33 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 13:34:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:34:33 INFO - 'USER': 'cltbld', 13:34:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:34:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:34:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:34:33 INFO - 'XPC_FLAGS': '0x0', 13:34:33 INFO - 'XPC_SERVICE_NAME': '0', 13:34:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:34:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:34:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:34:33 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-nUhLmT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:34:33 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)) 13:34:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:34:33 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-vk1EG7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:34:33 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)) 13:34:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:34:33 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-N3qU3C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:34:34 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)) 13:34:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:34:34 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-5K_GRw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:34:34 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)) 13:34:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:34:34 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-pK1joJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:34:34 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)) 13:34:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:34:34 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-frlkNm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:34:34 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)) 13:34:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:34:34 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-a6uqu7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:34:34 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)) 13:34:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:34:34 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-haYxl5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:34:34 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)) 13:34:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:34:34 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-Gq_n9t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:34:34 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)) 13:34:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:34:34 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-koRHLo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:34:34 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)) 13:34:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:34:34 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-l96xtk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:34:34 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)) 13:34:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:34:34 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-kGuMw8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:34:34 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)) 13:34:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:34:35 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-XE7qgq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:34:35 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)) 13:34:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:34:35 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-eoPbQu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:34:35 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)) 13:34:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:34:35 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-GaJx35-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:34:35 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)) 13:34:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:34:35 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-7sjEnh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:34:35 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)) 13:34:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:34:35 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-mfVr3q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:34:35 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)) 13:34:35 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)) 13:34:35 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)) 13:34:35 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)) 13:34:35 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)) 13:34:35 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)) 13:34:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:34:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:34:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:34:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:34:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:34:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:34:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:34:37 INFO - Downloading blessings-1.6.tar.gz 13:34:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:34:38 INFO - Installing collected packages: blessings 13:34:38 INFO - Running setup.py install for blessings 13:34:38 INFO - Successfully installed blessings 13:34:38 INFO - Cleaning up... 13:34:38 INFO - Return code: 0 13:34:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:34:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:34:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:34:38 INFO - Reading from file tmpfile_stdout 13:34:38 INFO - Current package versions: 13:34:38 INFO - blessings == 1.6 13:34:38 INFO - blobuploader == 1.2.4 13:34:38 INFO - docopt == 0.6.1 13:34:38 INFO - manifestparser == 1.1 13:34:38 INFO - mozInstall == 1.12 13:34:38 INFO - mozcrash == 0.17 13:34:38 INFO - mozdebug == 0.1 13:34:38 INFO - mozdevice == 0.48 13:34:38 INFO - mozfile == 1.2 13:34:38 INFO - mozhttpd == 0.7 13:34:38 INFO - mozinfo == 0.9 13:34:38 INFO - mozleak == 0.1 13:34:38 INFO - mozlog == 3.1 13:34:38 INFO - moznetwork == 0.27 13:34:38 INFO - mozprocess == 0.22 13:34:38 INFO - mozprofile == 0.28 13:34:38 INFO - mozrunner == 6.11 13:34:38 INFO - mozscreenshot == 0.1 13:34:38 INFO - mozsystemmonitor == 0.0 13:34:38 INFO - moztest == 0.7 13:34:38 INFO - mozversion == 1.4 13:34:38 INFO - psutil == 3.1.1 13:34:38 INFO - requests == 1.2.3 13:34:38 INFO - wsgiref == 0.1.2 13:34:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:34:38 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')]} 13:34:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:34:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:38 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')]} 13:34:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:34:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:38 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 0x10c245a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c24c4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6a1d985c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0e9d30>, '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 0x10bf4ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c0e0b20>, '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.UekTpTjc6r/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.7mJNBKECHZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 13:34:38 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 13:34:38 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 13:34:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UekTpTjc6r/Render', 13:34:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:34:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:34:38 INFO - 'HOME': '/Users/cltbld', 13:34:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:34:38 INFO - 'LOGNAME': 'cltbld', 13:34:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:34:38 INFO - 'MOZ_NO_REMOTE': '1', 13:34:38 INFO - 'NO_EM_RESTART': '1', 13:34:38 INFO - 'PAGER': '/bin/cat', 13:34:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:34:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:34:38 INFO - 'PWD': '/builds/slave/test', 13:34:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:34:38 INFO - 'SHELL': '/bin/bash', 13:34:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7mJNBKECHZ/Listeners', 13:34:38 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 13:34:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:34:38 INFO - 'USER': 'cltbld', 13:34:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:34:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:34:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:34:38 INFO - 'XPC_FLAGS': '0x0', 13:34:38 INFO - 'XPC_SERVICE_NAME': '0', 13:34:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:34:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:34:38 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)) 13:34:38 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)) 13:34:38 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)) 13:34:38 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)) 13:34:38 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)) 13:34:38 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)) 13:34:38 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)) 13:34:38 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)) 13:34:38 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)) 13:34:38 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)) 13:34:38 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)) 13:34:38 INFO - Cleaning up... 13:34:38 INFO - Return code: 0 13:34:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:34:38 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')]} 13:34:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:34:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:38 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')]} 13:34:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:34:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:38 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 0x10c245a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c24c4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6a1d985c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0e9d30>, '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 0x10bf4ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c0e0b20>, '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.UekTpTjc6r/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.7mJNBKECHZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 13:34:38 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 13:34:38 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 13:34:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UekTpTjc6r/Render', 13:34:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:34:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:34:38 INFO - 'HOME': '/Users/cltbld', 13:34:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:34:38 INFO - 'LOGNAME': 'cltbld', 13:34:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:34:38 INFO - 'MOZ_NO_REMOTE': '1', 13:34:38 INFO - 'NO_EM_RESTART': '1', 13:34:38 INFO - 'PAGER': '/bin/cat', 13:34:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:34:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:34:38 INFO - 'PWD': '/builds/slave/test', 13:34:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:34:38 INFO - 'SHELL': '/bin/bash', 13:34:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7mJNBKECHZ/Listeners', 13:34:38 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 13:34:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:34:38 INFO - 'USER': 'cltbld', 13:34:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:34:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:34:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:34:38 INFO - 'XPC_FLAGS': '0x0', 13:34:38 INFO - 'XPC_SERVICE_NAME': '0', 13:34:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:34:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:34:39 INFO - Downloading/unpacking jsonschema 13:34:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:34:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:34:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:34:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:34:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:34:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:34:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:34:41 INFO - Downloading/unpacking functools32 (from jsonschema) 13:34:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:34:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:34:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:34:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:34:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:34:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:34:41 INFO - Downloading functools32-3.2.3-2.tar.gz 13:34:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:34:42 INFO - warning: no files found matching '*.txt' 13:34:42 INFO - no previously-included directories found matching 'build' 13:34:42 INFO - no previously-included directories found matching 'dist' 13:34:42 INFO - no previously-included directories found matching '.git*' 13:34:42 INFO - Installing collected packages: jsonschema, functools32 13:34:42 INFO - Running setup.py install for functools32 13:34:42 INFO - warning: no files found matching '*.txt' 13:34:42 INFO - no previously-included directories found matching 'build' 13:34:42 INFO - no previously-included directories found matching 'dist' 13:34:42 INFO - no previously-included directories found matching '.git*' 13:34:42 INFO - Successfully installed jsonschema functools32 13:34:42 INFO - Cleaning up... 13:34:42 INFO - Return code: 0 13:34:42 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 13:34:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:34:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:34:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:34:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:34:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:42 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 0x10c245a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c24c4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6a1d985c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0e9d30>, '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 0x10bf4ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c0e0b20>, '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.UekTpTjc6r/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.7mJNBKECHZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 13:34:42 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 13:34:42 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 13:34:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UekTpTjc6r/Render', 13:34:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:34:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:34:42 INFO - 'HOME': '/Users/cltbld', 13:34:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:34:42 INFO - 'LOGNAME': 'cltbld', 13:34:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:34:42 INFO - 'MOZ_NO_REMOTE': '1', 13:34:42 INFO - 'NO_EM_RESTART': '1', 13:34:42 INFO - 'PAGER': '/bin/cat', 13:34:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:34:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:34:42 INFO - 'PWD': '/builds/slave/test', 13:34:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:34:42 INFO - 'SHELL': '/bin/bash', 13:34:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7mJNBKECHZ/Listeners', 13:34:42 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 13:34:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:34:42 INFO - 'USER': 'cltbld', 13:34:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:34:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:34:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:34:42 INFO - 'XPC_FLAGS': '0x0', 13:34:42 INFO - 'XPC_SERVICE_NAME': '0', 13:34:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:34:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:34:42 INFO - Downloading/unpacking flake8 13:34:42 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 13:34:42 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 13:34:42 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 13:34:42 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 13:34:42 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 13:34:42 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 13:34:44 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 13:34:45 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 13:34:45 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 13:34:45 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 13:34:45 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 13:34:45 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 13:34:45 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 13:34:45 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 13:34:45 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 13:34:45 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 13:34:45 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 13:34:45 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 13:34:45 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 13:34:45 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 13:34:45 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 13:34:45 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 13:34:45 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 13:34:45 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 13:34:45 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 13:34:45 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 13:34:45 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 13:34:45 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 13:34:45 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 13:34:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 13:34:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:34:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:34:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:34:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:34:46 INFO - no previously-included directories found matching 'docs/_build' 13:34:46 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 13:34:46 INFO - Running setup.py install for pep8 13:34:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:34:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:34:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:34:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:34:46 INFO - no previously-included directories found matching 'docs/_build' 13:34:46 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 13:34:46 INFO - Successfully installed flake8 mccabe pyflakes pep8 13:34:46 INFO - Cleaning up... 13:34:46 INFO - Return code: 0 13:34:46 INFO - Running post-action listener: _resource_record_post_action 13:34:46 INFO - Running post-action listener: _start_resource_monitoring 13:34:46 INFO - Starting resource monitoring. 13:34:46 INFO - ##### 13:34:46 INFO - ##### Running install step. 13:34:46 INFO - ##### 13:34:46 INFO - Running pre-action listener: _resource_record_pre_action 13:34:46 INFO - Running main action method: install 13:34:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:34:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:34:47 INFO - Reading from file tmpfile_stdout 13:34:47 INFO - Detecting whether we're running mozinstall >=1.0... 13:34:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:34:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:34:47 INFO - Reading from file tmpfile_stdout 13:34:47 INFO - Output received: 13:34:47 INFO - Usage: mozinstall [options] installer 13:34:47 INFO - Options: 13:34:47 INFO - -h, --help show this help message and exit 13:34:47 INFO - -d DEST, --destination=DEST 13:34:47 INFO - Directory to install application into. [default: 13:34:47 INFO - "/builds/slave/test"] 13:34:47 INFO - --app=APP Application being installed. [default: firefox] 13:34:47 INFO - mkdir: /builds/slave/test/build/application 13:34:47 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'] 13:34:47 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 13:35:19 INFO - Reading from file tmpfile_stdout 13:35:19 INFO - Output received: 13:35:19 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 13:35:19 INFO - Running post-action listener: _resource_record_post_action 13:35:19 INFO - ##### 13:35:19 INFO - ##### Running run-tests step. 13:35:19 INFO - ##### 13:35:19 INFO - Running pre-action listener: _resource_record_pre_action 13:35:19 INFO - Running main action method: run_tests 13:35:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:35:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:35:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:35:19 INFO - 2016-04-19 13:35:19.801 screenresolution[1644:7642] starting screenresolution argv=screenresolution get 13:35:19 INFO - 2016-04-19 13:35:19.803 screenresolution[1644:7642] Display 0: 1600x1200x32@60 13:35:19 INFO - 2016-04-19 13:35:19.809 screenresolution[1645:7645] starting screenresolution argv=screenresolution list 13:35:19 INFO - Available Modes on Display 0 13:35:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:35:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:35:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:35:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:35:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:35:20 INFO - Intel Iris: 13:35:20 INFO - Chipset Model: Intel Iris 13:35:20 INFO - Type: GPU 13:35:20 INFO - Bus: Built-In 13:35:20 INFO - VRAM (Dynamic, Max): 1536 MB 13:35:20 INFO - Vendor: Intel (0x8086) 13:35:20 INFO - Device ID: 0x0a2e 13:35:20 INFO - Revision ID: 0x0009 13:35:20 INFO - Displays: 13:35:20 INFO - Display: 13:35:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:35:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:35:20 INFO - Display Serial Number: 1600x1200 60 13:35:20 INFO - Main Display: Yes 13:35:20 INFO - Mirror: Off 13:35:20 INFO - Online: Yes 13:35:20 INFO - Rotation: Supported 13:35:20 INFO - Return code: 0 13:35:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:35:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:35:20 INFO - Python 2.7.3 13:35:20 INFO - Return code: 0 13:35:20 INFO - grabbing minidump binary from tooltool 13:35:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:35:20 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 0x10c0e9d30>, '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 0x10bf4ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c0e0b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:35:20 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 13:35:20 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 13:35:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:35:20 INFO - Return code: 0 13:35:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:35:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:35:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:35:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:35:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:35:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 13:35:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 13:35:21 INFO - Return code: 0 13:35:21 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/TP9zI02fSdeM1EEkvIIWWw/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0167', '--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 13:35:21 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/TP9zI02fSdeM1EEkvIIWWw/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0167 --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 13:35:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UekTpTjc6r/Render', 13:35:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:35:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:35:21 INFO - 'HOME': '/Users/cltbld', 13:35:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:35:21 INFO - 'LOGNAME': 'cltbld', 13:35:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:35:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:35:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:35:21 INFO - 'MOZ_NO_REMOTE': '1', 13:35:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:35:21 INFO - 'NO_EM_RESTART': '1', 13:35:21 INFO - 'PAGER': '/bin/cat', 13:35:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:35:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:35:21 INFO - 'PWD': '/builds/slave/test', 13:35:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:35:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:35:21 INFO - 'SHELL': '/bin/bash', 13:35:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7mJNBKECHZ/Listeners', 13:35:21 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 13:35:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:35:21 INFO - 'USER': 'cltbld', 13:35:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:35:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:35:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:35:21 INFO - 'XPC_FLAGS': '0x0', 13:35:21 INFO - 'XPC_SERVICE_NAME': '0', 13:35:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:35:21 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/TP9zI02fSdeM1EEkvIIWWw/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0167', '--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 13:35:21 INFO - mozversion application_buildid: 20160419122433 13:35:21 INFO - mozversion application_changeset: b2cc32b7bbe6a7a999dabacf600444169bc3b3e6 13:35:21 INFO - mozversion application_display_name: FirefoxDeveloperEdition 13:35:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:35:21 INFO - mozversion application_name: Firefox 13:35:21 INFO - mozversion application_remotingname: firefox-dev 13:35:21 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:35:21 INFO - mozversion application_vendor: Mozilla 13:35:21 INFO - mozversion application_version: 47.0a2 13:35:21 INFO - mozversion platform_buildid: 20160419122433 13:35:21 INFO - mozversion platform_changeset: b2cc32b7bbe6a7a999dabacf600444169bc3b3e6 13:35:21 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:35:21 INFO - mozversion platform_version: 47.0a2 13:35:21 INFO - using testdate: 1461098121 13:35:21 INFO - actual date: 1461098121 13:35:21 INFO - starting webserver on 'localhost:49271' 13:35:21 INFO - SUITE-START | Running 2 tests 13:35:21 INFO - TEST-START | dromaeo_css 13:35:21 INFO - operating with platform_type : mac_ 13:35:21 INFO - Initialising browser for dromaeo_css test... 13:35:21 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/tmp5MkI86/profile http://localhost:49271/getInfo.html) 13:35:24 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 13:35:24 INFO - PROCESS | 1663 | colorDepth:24 13:35:24 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 13:35:24 INFO - PROCESS | 1663 | __metrics 13:35:24 ERROR - PROCESS | 1663 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2849: TypeError: this.tabs is undefined 13:35:25 INFO - TEST-INFO | 1663: exit 0 13:35:25 INFO - Browser initialized. 13:35:25 INFO - Running cycle 1/1 for dromaeo_css test... 13:35:25 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/tmp5MkI86/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:35:37 INFO - PROCESS | 1666 | RSS: Main: 217567232 13:35:37 INFO - PROCESS | 1666 | 13:36:26 INFO - PROCESS | 1666 | 1461098186930 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] 13:38:37 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 13:38:39 INFO - PROCESS | 1666 | RSS: Main: 228425728 13:38:39 INFO - PROCESS | 1666 | 13:41:40 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 13:41:41 INFO - PROCESS | 1666 | RSS: Main: 239927296 13:41:41 INFO - PROCESS | 1666 | 13:44:43 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 13:44:43 INFO - PROCESS | 1666 | RSS: Main: 242044928 13:44:43 INFO - PROCESS | 1666 | 13:47:45 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 13:47:46 INFO - PROCESS | 1666 | RSS: Main: 243482624 13:47:46 INFO - PROCESS | 1666 | 13:50:48 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 13:50:49 INFO - PROCESS | 1666 | RSS: Main: 246276096 13:50:49 INFO - PROCESS | 1666 | 13:53:51 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 13:53:52 INFO - PROCESS | 1666 | RSS: Main: 247394304 13:53:52 INFO - PROCESS | 1666 | 13:53:52 INFO - PROCESS | 1666 | __start_tp_report 13:53:52 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 13:53:52 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 13:53:52 INFO - PROCESS | 1666 | |i|pagename|runs| 13:53:52 INFO - PROCESS | 1666 | |0;dojo.html;3472.600650925144;3513.2622149348617;3534.6642069003437;3555.697765689916;3564.750467467277;5674.574406919487;5714.457126572979;5728.42715728427;5728.742206600703;5729.312482502106;5689.943100568993;5705.828825135242;5735.397783232763;5743.368229494763;5752.424757524245;5150.896982060356;5158.922616160751;5190.4809519047985;5225.477452254782;5229.163333866582;4670.229412146991;5122.206058060988;5267.078261304276;5282.418933917266;5300.363956325246;4278.978605106986;5259.184826351921;5279.2872962150195;5294.52349288564;5313.9734301328635;5204.34945631796;5231.921521177185;5241.161414173752;5244.8688782780355;5310.150375939831;4936.629752768521;5159.14874045782;5221.765020574083;5232.2674825524455;5261.342332208474;5527.917081243784;5611.466910643482;5620.803271885506;5675.545956323525;5693.857653558694;5176;6110.877782444326;6113.633182009049;6142.723577439026;6160.322364539897;5061.519155680205;5831.825045248649;5961.701914904237;6105.870413973429;6192.8761424771255;4994.600431965434;5947.345791962881;5964.7315870785915;5977.551683623746;5980.760769569256;5337.599680024014;6313;6315.684215789192;6316.021016742412;6330.620162790247;5083.7203953782155;6016.458518733335;6120.660465672121;6121.174266083698;6163.044728067157;6152.661603611844;6172.259328880563;6173.47525460332;6195.31851496335;6200.782972596027;6017.2779266488305;6106.267247930276;6118.969405153035;6138.877222455615;6158.568900177034;6072.8785424292155;6095.512359011268;6112.388761123852;6118.649636126507;6124.5100391968535;4814.831480898222;4923.113839508851;4927.975360123248;4934.210526315772;4949.975250123726;4340.913181736348;4365.170617582713;4471.686981911235;4479.731216126977;4500.572445617661;5604;5671.489565939085;5680.801171958961;5698.629589076697;5763.279590051244;6129.448349648464;6147.569670123048;6148.9692551536955;6154.3845615438095;6157.199564056644;5616.129499927518;5689.203511508392;5690.2887139107615;5720.485156335866;5802.65184088956;5365.651232669864;5366.356037275552;5385.919211211835;5392.649477783932;5396.595255355864;6196.566240363221;6249.343818899041;6249.437550620375;6256.092866534236;6285.811425657238;6083.422074902877;6102.877942441215;6142.3550527194;6159.599626024294;6166.290876549254;6189.752409903734;6291.811245662638;6298;6303.36966303366;6320.494360451245;6125.877482450415;6126.816195514141;6144.201253836973;6153.753849845956;6156.230471191101;6115.480184184395;6118.632882027093;6131.866048757656;6148.893199224182;6161.568690191643;5871.735771890189;6126.2827622729355;6141.631502109888;6152.692365381802;6160.260768707783;5126.7180305083575;6159.353267906805;6176.66028368426;6195.473384762166;6206.565540412128;5724.3703192647945;5961.582689211714;5962.16529685853;6053.092036194606;6090.665013424305;6112.419320164578;6115.449609534985;6126;6133.049377346519;6170.7223174958135;5825.679587622722;6002.69986500682;6013.30846952606;6036.185115009417;6042.3655516170165 13:53:52 INFO - PROCESS | 1666 | |1;ext.html;491.8524442667199;541.1909195752348;585.1602949767084;657.6350125680246;666.047552000639;37812.43281350779;38525.61474385255;38596.22807543848;38601.42097868527;38633.613663863354;40207.195856082864;40355;40633.39049914254;40807;41340.58659413405;9341.252699784018;9551.188149007343;9558.139767420931;9563.713088607354;9602.471864047475;9358.672446464376;9578.664746733866;9582.185514231298;9590.904090959106;9604.231661467065;9593.760155996086;9637;9641.566129524188;9680.225581953428;9711.12599866012;9617.374870633386;9657.179139773129;9661.613535458608;9684.322097453181;9707.514624268759;9479.952600237024;9575.425474471554;9604.423734575948;9623.90376096237;9629.56666949982;27347.726522734716;39625.60374396248;39753;39910.401343979865;39932.600673993176;3062.127293721279;3155.936881262362;3326.8170250636203;3402.35355282498;3415.2315728961034;27654.170374888785;28226.29434264139;28418.28954276139;28456;28667.13998580046;2549.6048112542585;2563.128536297649;2602.37542989683;2648.748368904951;2649.483350746605;340464;711626;716147;725963;727527;71482.64258678674;71750.64124679448;72465;72666.63666681632;72822.63588682022;74502.62748686221;74612.62693686497;74964.62517687486;75394;76390;17964.550886227687;18277.086145692927;18302.267909283746;18329.266829326676;18330.541736456697;13873.236971966437;13927.442902283796;14012.439502419993;14017;14147.151170929572;22619.321420357417;22948.88525557395;22979.96590153447;22995.770042299362;23035.654465183037;8165.918340816635;8333.624986875606;9312.90687093134;9405.952970235105;9620.422774633404;898.2903506230135;975.9219262459128;992.2805965674972;1018.5314755212685;1122.0014187373245;29544.704552954194;30081.398372032436;30452.84773576162;30551.38897222043;30611.540826887616;1117.8430324613985;1124.651358078998;1153.4175241502987;1167.9299242045504;1172.9472173752201;14628.853711462749;14873.256337183098;15271.465498707714;15572.143532105843;15689.215539222992;507752;521836;527223.8194045103;531265;537085;60703.08945365735;60710.39289607047;60877.69561152166;61230;61270.69364653148;1516.6132636177704;1550.100941453954;1560.3446552448;1564.7965764450776;1571.591386239563;28477.857610711813;29101.272468188465;29459;29769.70230297756;29920.850395747882;44219.77890110529;52271.73864130655;52285.47714522958;52442.73778631083;52750;396568;408084;411030;485361;488712;33736.831315843265;34672.653273466945;34825.8258708715;35004.299914001065;35240.64759352374;41769.37345939874;43856.78071609622;46632.53367466418;46676.533234668575;47041.05917881692;34374;37737.81131094327;37891.673791418274;38075.80962095172;38634;37613.435798462495;37680.43479347757;37819.62180378161;37843.43234851534;38181;32857.34285314328;33626.66373336333;35509.40207690609;36979.630203698696;37676.623233768405;29929.55105673373;30576.040662868705;31606.56391839955;31866.044018679477;32365.51451728179;24902.50194996126;24953.376165596004;25118.12086576961;25240.873795630905;25345.366365841 13:53:52 INFO - PROCESS | 1666 | |2;jquery.html;358.63777584639524;365.3460306052166;367.89698884312395;368.43813184893054;373.01523976701515;19855.602887942234;19950.60098798025;20792.48018799531;21068.789312106874;21101.788982110193;21858.23496177634;21895.233666821663;22144.22495212668;22173;23338.883305583448;5244.947550524503;5677.858053548653;6003.729832157542;6007.549433792461;6009.429104235091;5554.1113421852515;5810.1284807279035;5922.3485416604135;5925.081612350093;5927.614705044159;5838.9124163137585;5875.823725288248;5884.117382392633;5889.263842019747;5909.468147866691;5502.697351645658;5642.407547207526;5675.262215911905;5795.130730390433;5796.44933731297;5202.453742357074;5457.181422786574;5470.17947307903;5535.778568857281;5552.888942221133;13476.326183690777;13631.931840340834;13644.386002629906;18879.905600472048;24693.87653061741;2101.842361822854;2142.239504975727;2248.9887550562144;2253.8422310438277;2296.816254699637;9572.95213523935;9602.519874006342;9842.65550705729;10117.848232276521;10137.341072830242;1694.8644108471292;1713.4345665930339;1757.885737427063;1763.8324359185856;1775.973360399595;73397;94748.52625736895;100506.49746751292;100837;110194.89805101926;11456.770864582828;11746.76506469883;11804.645860624196;11870.525178992744;11876.228045177037;14141;14233.861291096688;14234.718875301065;14295.57113286603;14365.497207597893;8802.295816334678;8849.823003540021;8897.777555561162;8908.554572271361;8921.866172007425;7491.6254187290415;9002.369834111549;9029.954850225708;9088.681896133603;9108;3611.602723700391;4488.4165058542185;4556.407667003268;4620.145273124483;4625.190591646448;1586.9285882135332;1876.0713446843627;1882.4187682346621;1896.0993528074055;1981.118402310958;851.638053827121;961.6489981156827;979.3878825733916;988.8763904511936;1039.5941106247542;19881.30415435453;20971.056302466426;21184.576308473745;21220.469488262916;21357.46606334854;971.3589031239349;971.7764914069662;1001.9699409017741;1003.2626019875386;1027.7841653252751;10956.28784129029;11385.14611404148;11420;11422.086233101498;11456.312621242585;664506;668160;674358;687790;691534.5423272852;83317.58341208255;116986;118778;119463.40268298602;120562;881.6561540999015;928.8699582058382;1006.4816619440994;1063.1335461598771;1065.925385223027;18027;18059.367922122663;18343;18352.449426517225;18464.169112389707;38442.46230150874;42237.78881105575;46260;46812;50038.499615003384;67696.98454523284;67840.6607966957;70692;71287.64356178186;71927;7310.853782924418;8057.705491361141;8128.471649342774;8161.020677518736;8364.289035432057;12538.49846006186;13101.803472948104;13130.73738525205;13199.736005279648;13571.38928748189;11227.045701115496;11411.372374519482;11559.653210403701;11592.536298547964;11708.531658733555;9508.239340852577;11468.369239691898;11512.30926144434;11812.291262524275;11827.112966527717;11495.252808567417;11693.415329233334;11941.76116477683;11978.820317695066;12084.758304833678;7855.5286682799215;8124.22819832115;8175.345972322319;8180.427370084275;8513.148685131437;8590.742277731679;11704.414779260833;11771.47028383742;11788.115891308358;11818.586349477728 13:53:52 INFO - PROCESS | 1666 | |3;mootools.html;1641.9261133249006;1667.141137631541;1997.7902320256374;2027.8783273003621;2095.947601309968;8262.4629399089;8815.250703690179;9025.232855207307;9262.981072082066;9294.256459483233;8957;9004.954975225131;9076.137766912148;9080.137386948245;9090.954540227876;2521.8739063046864;2531.164715643842;2545.7199708032103;2545.745425457458;2545.809064320174;2130.222468798885;2139.775323591024;2397.1729753235104;2449.2040086971806;2466.1738317663517;2437.85372877627;2450.3261603059123;2535.911243106492;2560.3471114865683;2575.1759436980174;2471.3327401601646;2493.1024831060986;2499.250224932513;2499.487605040962;2532.987335063313;2487.3657217409627;2499.850008999466;2531.721480652277;2539.263613552068;2556.693196816394;9180.862287065633;9220.861687074632;9228.446293222361;9252.444853308823;9279.21126704231;5605.579581531401;5613.7193140342815;5684.090545512699;5773.047447171211;5778.5377169826315;3548.432250839853;3629.437437197212;3645.0340659725193;3675.558932928039;3709.610490898444;1017.7811770469384;1025.953832077551;1033.850091736694;1040.5681642118511;1040.9635662751768;12252.44863981114;12270;12382.938085309515;12396.008319334522;12446.564370246997;4953.083679519301;4994.725290109079;5047.798088076436;5062.468440813662;5077.543021128116;10372;10491.527881245362;10548.525316360781;10557.683269501927;10561.155107591374;5562.749676264497;5697.230873832063;5711.771529138871;5727.8568035799435;5734.197212390269;3948.822302996372;4959.107360675041;5028.270900719375;5075.441701412841;5077.619178561623;3978.1645854370327;4011.638952494231;4058.3100872851687;4071.3689378146437;4113.218488487178;1359.7212571422835;1368.6578355411148;1372.0875617714107;1378.9448422063206;1379.482693989754;1345.838499380061;1377.869102435267;1397.2175581674294;1398.5384823008383;1410.2596137027938;8392;8556.315494760403;8586.31309495239;8586.527740974307;8609.053004169537;506.3114164735956;511.82342091978205;513.2224679610393;515.0548743056534;520.3729505945327;6286.49708023357;6311.558190926591;6331.27190373113;6352.174217351807;6393.328700486475;10455.84316235257;10854.620088296871;10879.619213327496;11174.553017879356;11212.607558735404;10649.041586257274;10897.346159230472;10987.121030317556;11009.669709908716;11092.889071109186;8666.220040196413;8668.219860212612;8677.869831952527;8736.213740763362;8737.03892571804;8113.985751781027;8121.553314567519;8133.186681331819;8137.633806478604;8274.172582741678;7815;8299.419040667095;8447.704330348653;9591.856122158313;9904.306698531034;9956.20350371983;9993.250506211918;10092.84860727075;10094.192464602667;10276.332038417479;7036.964815175891;7471.514351567131;7685;7731.188225236269;7773.455858089879;7122.893156602759;8379.287760540423;8439.493630382196;8491.91508084936;8502.914970850212;7290.125184977836;7450.96274518624;7509.474336796372;7512.474126811071;7644.579548124907;7246.058012458347;7369.263073692588;7406.296401841816;7427.591482468516;7517.361024312776;7908.485948413335;7923.84152316962;7933.563653999086;7949.483283586548;7963.522188668699;7133.85732285341;7204.9913012177285;7209.567425954459;7221.638918054181;7298.96350518244;7285.890711639224;7359.4480413968095;7406.888896666446;7439.8140046499275;7445.994790703186 13:53:52 INFO - PROCESS | 1666 | |4;prototype.html;314.02338726560396;325.63366213010363;329.83013747919813;333.14215894072754;335.69284105043886;6720.73117075317;6759.56062855914;6782.626955517445;6788.355106264909;6839.794806155811;6704.798856034315;6741.292164322749;6765.729370825174;6781.050652908597;6784.864302713942;4741.834035808747;4778.044391121773;4875.536824001714;4886.877828054292;5017.096922553938;4866.537678920497;4868.294097355882;4885.389326334208;4893.975530122362;4897.681650692712;4819.445763737162;4833.10587541306;4835.516448355172;4855.393075865517;4865.951340486586;4210.810513526868;4846.515348465161;4848.2727590861305;4857.805687772485;4885.682430642032;4212.283911735012;4223;4223.070924396628;4223.809928553223;4245.893852653678;6161.19904412428;6375.4262116409745;6427.710753016078;6446.613203207776;6468.676566171673;5847.649141051551;5867.383924687889;5899.115132730082;5899.76400943966;5916.603775470941;5107.719075450848;7012.948057791321;7079.61062141582;7196.964015179943;7208.49540532163;4748.548887855648;4749.957518267143;4767.594754445876;4771.403574553181;4782.450018247911;7614.657340419694;7629.504082234637;7639.579823109727;7666.425018123663;7675;4661.976690116528;4798.5441383068555;4804.975975120102;4833.927491087637;4852.417709874838;4887.608991280689;4932.53140951609;4942.654014218971;4947.5794557462295;4965.131102057126;4812.735299558558;5825.76696932118;5840.88318233633;5846.064629659234;5862.970685146633;6334.334894835976;6840.726370945106;7219.747308844166;7237.819054523574;7278.126624805057;1409.5489443378021;1703.190984282456;1722.6554689062239;1730.8788384813195;1737.957225664591;1070.2240875365453;1110.3005106782675;1120.781447617723;1129.373197875187;1131.2590253384046;6632.872411690024;6697.598144111369;6721.22705888829;6723.025161351675;6767.323267673291;6203.2245969253845;7461.328480436679;7468.477206595486;7475.439342049368;7513.737019204302;6557.704903279364;6566.868662626721;6677.933220667731;6686.83282917931;6742.561733487308;3947.782871942071;3974.16542526067;3985.4221137934837;3996.3805610130476;4010.0776821331256;7734.961325193338;7739.496932699357;7754.030746156731;7795.961020194863;7887.84224315522;6447.387498187759;7456.3662088721985;7840.843183136305;7840.882386764203;7854.725084622125;7829.29536341732;7830.138684744674;7840.059192896889;7864.9606751965875;7867.331276841418;7458;7515.210902855121;7539.434542409451;7589.544627322378;7612.505187162817;6792.864142717019;6889.379955804002;7434.962825185839;7470;7490.588017659081;6283.748650053946;7494.98817659609;7855.450118491879;7857.410694197843;7894.842103158019;4693.624510039121;4729.834455794031;4762.190427627243;4769.856904292877;4790.9281360780315;5491.533219676372;6639.336066393322;7382.003429536944;7454.776356709307;7553.357964572853;6892.379685828299;7280.890786638311;7292.234315397019;7313.524620899624;7317.853642927005;7033.9296607033275;7278;7286.1985181631035;7287.890681639674;7316.817079573053;6506.991416330477;7015.6842942066705;7108.537945033556;7116.46626503004;7186.856262874818;6539.934600654123;6706.429953454011;6731.158605174353;7318.780436586911;7339.155997060407;6119.602225855305;7100.431965442649;7103.396211322096;7137.964310178416;7148.892766608401 13:53:52 INFO - PROCESS | 1666 | |5;yui.html;739.2053542441877;743.7396911081121;743.9739609113675;769.5152054205853;793.1434051224678;14859;15071.54785356441;15218.39126434944;15236.085834849902;15251.542453726392;13827.446902123927;13863.653408664788;14013.50952716655;14026.859731402683;14042;746.4140649590087;945.1399226703701;958.4872093430016;973.3819024919192;976.6288810252095;964.7105868239535;971.1696499492938;975.1711045611245;975.8536219567051;977.1742877268697;994.6121012804975;1000.8748906386702;1003.1924300937742;1003.4882210072842;1006.4816619440957;999.2655398282257;1005.3867141043958;1008.0725732326277;1011.4184344002155;1012.1953047327393;747.5178509861164;747.8466914282559;753.9170691223961;756.1493320014984;757.3713817531491;14636.121832689967;15136.848631513654;15188.772168417372;15214.239288035664;15237.61905952349;13276.668083297898;13294.534691285855;13316.733665326736;13441.596752097355;13454.529091481849;10372.066514013699;10377.117944974687;10387.013233742782;10424.270301078928;10462;3104.627444706627;3579.9081280209525;3601.45978103284;3652.7443078984484;3655.067957670777;83771;85982;86091.56954215189;86444;88015.67976480359;9796.363236389612;9863.654772082942;9919.652812151684;9955.701328960144;10000.399976001463;19528.31650892212;21655;21940.561188776133;22929.968151433226;23702.93336799848;7508.924910750932;7652.311291983637;7768.184340644264;7776;7871.842563148705;4121.258173528794;4145.937810932838;4192.350185721157;4203.369494575777;4367.71609845359;3447.8620855165514;3466.2201004773724;3470.9479357809655;3479.5476588043393;3488.720902327808;592.8321207221766;606.4905479397328;613.3406587917917;617.3671986214076;651.212033439534;597.4025974025974;606.1937622961451;612.4090252905967;613.6011592464844;619.8512357034369;8238.340932725368;8425.4944703318;8450.408471407065;8519.787005324792;8560.272376847914;590.7961753195141;594.0495207667697;627.9843003924939;628.1645411602558;631.4916492223712;3284.9392870155;3565.2691198304287;3653.6894363978827;3910.608939106067;3976.5228172619472;91994;92258;92871.07128928624;95215;95560;37197;37470.43794343087;37584;54857.90284194294;80572;69484;71069.28930710627;72949;75332;76477.61761191269;7671.309582137635;8436.53599052058;8490.617922193393;8723.520206388455;8806.930371362596;39167.608323917535;49436.50563494319;49728;51081.74459127681;53331.73334133304;48739.5126048735;67548.6490270182;71239.64380178066;71889;73291.26708733058;11087.72280692989;11116.277441966246;11195.72010699739;11301.943490282496;11325.433728313386;9933.552990115606;11374.919382658634;12108.636740897788;12159.696007599881;12752.617421477371;10700.197485188486;11413.942930285295;11448.3703396314;12029.75940481203;12048.036157107586;11626;12068.336241506802;12097.153199276318;12156.635300940987;12192.207506512048;10814.405207713651;11622.012128969132;11623;11901.404929753651;11947.342896140795;8079.927482749845;9131.589078491617;9289.117533834275;9377.578008989476;9787.314887957775;9306.860397094184;9394.483303418378;9590.712278631652;9708.708738737849;9814.411135331902 13:53:52 INFO - PROCESS | 1666 | __end_tp_report 13:53:52 INFO - PROCESS | 1666 | __start_cc_report 13:53:52 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,366 13:53:52 INFO - PROCESS | 1666 | __end_cc_report 13:53:52 INFO - PROCESS | 1666 | __startTimestamp1461099232127__endTimestamp 13:53:52 INFO - PROCESS | 1666 | 13:53:52 INFO - PROCESS | 1666 | ------- Summary: start ------- 13:53:52 INFO - PROCESS | 1666 | Number of tests: 6 13:53:52 INFO - PROCESS | 1666 | 13:53:52 INFO - PROCESS | 1666 | [#0] dojo.html Cycles:165 Average:5687.84 Median:5961.70 stddev:619.77 (10.4%) stddev-sans-first:596.81 13:53:52 INFO - PROCESS | 1666 | Values: 3472.6 3513.3 3534.7 3555.7 3564.8 5674.6 5714.5 5728.4 5728.7 5729.3 5689.9 5705.8 5735.4 5743.4 5752.4 5150.9 5158.9 5190.5 5225.5 5229.2 4670.2 5122.2 5267.1 5282.4 5300.4 4279.0 5259.2 5279.3 5294.5 5314.0 5204.3 5231.9 5241.2 5244.9 5310.2 4936.6 5159.1 5221.8 5232.3 5261.3 5527.9 5611.5 5620.8 5675.5 5693.9 5176.0 6110.9 6113.6 6142.7 6160.3 5061.5 5831.8 5961.7 6105.9 6192.9 4994.6 5947.3 5964.7 5977.6 5980.8 5337.6 6313.0 6315.7 6316.0 6330.6 5083.7 6016.5 6120.7 6121.2 6163.0 6152.7 6172.3 6173.5 6195.3 6200.8 6017.3 6106.3 6119.0 6138.9 6158.6 6072.9 6095.5 6112.4 6118.6 6124.5 4814.8 4923.1 4928.0 4934.2 4950.0 4340.9 4365.2 4471.7 4479.7 4500.6 5604.0 5671.5 5680.8 5698.6 5763.3 6129.4 6147.6 6149.0 6154.4 6157.2 5616.1 5689.2 5690.3 5720.5 5802.7 5365.7 5366.4 5385.9 5392.6 5396.6 6196.6 6249.3 6249.4 6256.1 6285.8 6083.4 6102.9 6142.4 6159.6 6166.3 6189.8 6291.8 6298.0 6303.4 6320.5 6125.9 6126.8 6144.2 6153.8 6156.2 6115.5 6118.6 6131.9 6148.9 6161.6 5871.7 6126.3 6141.6 6152.7 6160.3 5126.7 6159.4 6176.7 6195.5 6206.6 5724.4 5961.6 5962.2 6053.1 6090.7 6112.4 6115.4 6126.0 6133.0 6170.7 5825.7 6002.7 6013.3 6036.2 6042.4 13:53:52 INFO - PROCESS | 1666 | 13:53:52 INFO - PROCESS | 1666 | [#1] ext.html Cycles:180 Average:68194.93 Median:38598.82 stddev:147148.24 (381.2%) stddev-sans-first:147473.22 13:53:52 INFO - PROCESS | 1666 | Values: 491.9 541.2 585.2 657.6 666.0 37812.4 38525.6 38596.2 38601.4 38633.6 40207.2 40355.0 40633.4 40807.0 41340.6 9341.3 9551.2 9558.1 9563.7 9602.5 9358.7 9578.7 9582.2 9590.9 9604.2 9593.8 9637.0 9641.6 9680.2 9711.1 9617.4 9657.2 9661.6 9684.3 9707.5 9480.0 9575.4 9604.4 9623.9 9629.6 27347.7 39625.6 39753.0 39910.4 39932.6 3062.1 3155.9 3326.8 3402.4 3415.2 27654.2 28226.3 28418.3 28456.0 28667.1 2549.6 2563.1 2602.4 2648.7 2649.5 340464.0 711626.0 716147.0 725963.0 727527.0 71482.6 71750.6 72465.0 72666.6 72822.6 74502.6 74612.6 74964.6 75394.0 76390.0 17964.6 18277.1 18302.3 18329.3 18330.5 13873.2 13927.4 14012.4 14017.0 14147.2 22619.3 22948.9 22980.0 22995.8 23035.7 8165.9 8333.6 9312.9 9406.0 9620.4 898.3 975.9 992.3 1018.5 1122.0 29544.7 30081.4 30452.8 30551.4 30611.5 1117.8 1124.7 1153.4 1167.9 1172.9 14628.9 14873.3 15271.5 15572.1 15689.2 507752.0 521836.0 527223.8 531265.0 537085.0 60703.1 60710.4 60877.7 61230.0 61270.7 1516.6 1550.1 1560.3 1564.8 1571.6 28477.9 29101.3 29459.0 29769.7 29920.9 44219.8 52271.7 52285.5 52442.7 52750.0 396568.0 408084.0 411030.0 485361.0 488712.0 33736.8 34672.7 34825.8 35004.3 35240.6 41769.4 43856.8 46632.5 46676.5 47041.1 34374.0 37737.8 37891.7 38075.8 38634.0 37613.4 37680.4 37819.6 37843.4 38181.0 32857.3 33626.7 35509.4 36979.6 37676.6 29929.6 30576.0 31606.6 31866.0 32365.5 24902.5 24953.4 25118.1 25240.9 25345.4 13:53:52 INFO - PROCESS | 1666 | 13:53:52 INFO - PROCESS | 1666 | [#2] jquery.html Cycles:180 Average:35568.06 Median:2275.33 stddev:111613.16 (4905.4%) stddev-sans-first:111894.96 13:53:52 INFO - PROCESS | 1666 | Values: 358.6 365.3 367.9 368.4 373.0 19855.6 19950.6 20792.5 21068.8 21101.8 21858.2 21895.2 22144.2 22173.0 23338.9 5244.9 5677.9 6003.7 6007.5 6009.4 5554.1 5810.1 5922.3 5925.1 5927.6 5838.9 5875.8 5884.1 5889.3 5909.5 5502.7 5642.4 5675.3 5795.1 5796.4 5202.5 5457.2 5470.2 5535.8 5552.9 13476.3 13631.9 13644.4 18879.9 24693.9 2101.8 2142.2 2249.0 2253.8 2296.8 9573.0 9602.5 9842.7 10117.8 10137.3 1694.9 1713.4 1757.9 1763.8 1776.0 73397.0 94748.5 100506.5 100837.0 110194.9 11456.8 11746.8 11804.6 11870.5 11876.2 14141.0 14233.9 14234.7 14295.6 14365.5 8802.3 8849.8 8897.8 8908.6 8921.9 7491.6 9002.4 9030.0 9088.7 9108.0 3611.6 4488.4 4556.4 4620.1 4625.2 1586.9 1876.1 1882.4 1896.1 1981.1 851.6 961.6 979.4 988.9 1039.6 19881.3 20971.1 21184.6 21220.5 21357.5 971.4 971.8 1002.0 1003.3 1027.8 10956.3 11385.1 11420.0 11422.1 11456.3 664506.0 668160.0 674358.0 687790.0 691534.5 83317.6 116986.0 118778.0 119463.4 120562.0 881.7 928.9 1006.5 1063.1 1065.9 18027.0 18059.4 18343.0 18352.4 18464.2 38442.5 42237.8 46260.0 46812.0 50038.5 67697.0 67840.7 70692.0 71287.6 71927.0 7310.9 8057.7 8128.5 8161.0 8364.3 12538.5 13101.8 13130.7 13199.7 13571.4 11227.0 11411.4 11559.7 11592.5 11708.5 9508.2 11468.4 11512.3 11812.3 11827.1 11495.3 11693.4 11941.8 11978.8 12084.8 7855.5 8124.2 8175.3 8180.4 8513.1 8590.7 11704.4 11771.5 11788.1 11818.6 13:53:52 INFO - PROCESS | 1666 | 13:53:52 INFO - PROCESS | 1666 | [#3] mootools.html Cycles:180 Average:6198.91 Median:514.14 stddev:3289.73 (639.9%) stddev-sans-first:3281.13 13:53:52 INFO - PROCESS | 1666 | Values: 1641.9 1667.1 1997.8 2027.9 2095.9 8262.5 8815.3 9025.2 9263.0 9294.3 8957.0 9005.0 9076.1 9080.1 9091.0 2521.9 2531.2 2545.7 2545.7 2545.8 2130.2 2139.8 2397.2 2449.2 2466.2 2437.9 2450.3 2535.9 2560.3 2575.2 2471.3 2493.1 2499.3 2499.5 2533.0 2487.4 2499.9 2531.7 2539.3 2556.7 9180.9 9220.9 9228.4 9252.4 9279.2 5605.6 5613.7 5684.1 5773.0 5778.5 3548.4 3629.4 3645.0 3675.6 3709.6 1017.8 1026.0 1033.9 1040.6 1041.0 12252.4 12270.0 12382.9 12396.0 12446.6 4953.1 4994.7 5047.8 5062.5 5077.5 10372.0 10491.5 10548.5 10557.7 10561.2 5562.7 5697.2 5711.8 5727.9 5734.2 3948.8 4959.1 5028.3 5075.4 5077.6 3978.2 4011.6 4058.3 4071.4 4113.2 1359.7 1368.7 1372.1 1378.9 1379.5 1345.8 1377.9 1397.2 1398.5 1410.3 8392.0 8556.3 8586.3 8586.5 8609.1 506.3 511.8 513.2 515.1 520.4 6286.5 6311.6 6331.3 6352.2 6393.3 10455.8 10854.6 10879.6 11174.6 11212.6 10649.0 10897.3 10987.1 11009.7 11092.9 8666.2 8668.2 8677.9 8736.2 8737.0 8114.0 8121.6 8133.2 8137.6 8274.2 7815.0 8299.4 8447.7 9591.9 9904.3 9956.2 9993.3 10092.8 10094.2 10276.3 7037.0 7471.5 7685.0 7731.2 7773.5 7122.9 8379.3 8439.5 8491.9 8502.9 7290.1 7451.0 7509.5 7512.5 7644.6 7246.1 7369.3 7406.3 7427.6 7517.4 7908.5 7923.8 7933.6 7949.5 7963.5 7133.9 7205.0 7209.6 7221.6 7299.0 7285.9 7359.4 7406.9 7439.8 7446.0 13:53:52 INFO - PROCESS | 1666 | 13:53:52 INFO - PROCESS | 1666 | [#4] prototype.html Cycles:180 Average:5854.88 Median:6599.87 stddev:1869.14 (28.3%) stddev-sans-first:1827.53 13:53:52 INFO - PROCESS | 1666 | Values: 314.0 325.6 329.8 333.1 335.7 6720.7 6759.6 6782.6 6788.4 6839.8 6704.8 6741.3 6765.7 6781.1 6784.9 4741.8 4778.0 4875.5 4886.9 5017.1 4866.5 4868.3 4885.4 4894.0 4897.7 4819.4 4833.1 4835.5 4855.4 4866.0 4210.8 4846.5 4848.3 4857.8 4885.7 4212.3 4223.0 4223.1 4223.8 4245.9 6161.2 6375.4 6427.7 6446.6 6468.7 5847.6 5867.4 5899.1 5899.8 5916.6 5107.7 7012.9 7079.6 7197.0 7208.5 4748.5 4750.0 4767.6 4771.4 4782.5 7614.7 7629.5 7639.6 7666.4 7675.0 4662.0 4798.5 4805.0 4833.9 4852.4 4887.6 4932.5 4942.7 4947.6 4965.1 4812.7 5825.8 5840.9 5846.1 5863.0 6334.3 6840.7 7219.7 7237.8 7278.1 1409.5 1703.2 1722.7 1730.9 1738.0 1070.2 1110.3 1120.8 1129.4 1131.3 6632.9 6697.6 6721.2 6723.0 6767.3 6203.2 7461.3 7468.5 7475.4 7513.7 6557.7 6566.9 6677.9 6686.8 6742.6 3947.8 3974.2 3985.4 3996.4 4010.1 7735.0 7739.5 7754.0 7796.0 7887.8 6447.4 7456.4 7840.8 7840.9 7854.7 7829.3 7830.1 7840.1 7865.0 7867.3 7458.0 7515.2 7539.4 7589.5 7612.5 6792.9 6889.4 7435.0 7470.0 7490.6 6283.7 7495.0 7855.5 7857.4 7894.8 4693.6 4729.8 4762.2 4769.9 4790.9 5491.5 6639.3 7382.0 7454.8 7553.4 6892.4 7280.9 7292.2 7313.5 7317.9 7033.9 7278.0 7286.2 7287.9 7316.8 6507.0 7015.7 7108.5 7116.5 7186.9 6539.9 6706.4 6731.2 7318.8 7339.2 6119.6 7100.4 7103.4 7138.0 7148.9 13:53:52 INFO - PROCESS | 1666 | 13:53:52 INFO - PROCESS | 1666 | [#5] yui.html Cycles:180 Average:17792.40 Median:52206.74 stddev:25003.61 (47.9%) stddev-sans-first:25040.96 13:53:52 INFO - PROCESS | 1666 | Values: 739.2 743.7 744.0 769.5 793.1 14859.0 15071.5 15218.4 15236.1 15251.5 13827.4 13863.7 14013.5 14026.9 14042.0 746.4 945.1 958.5 973.4 976.6 964.7 971.2 975.2 975.9 977.2 994.6 1000.9 1003.2 1003.5 1006.5 999.3 1005.4 1008.1 1011.4 1012.2 747.5 747.8 753.9 756.1 757.4 14636.1 15136.8 15188.8 15214.2 15237.6 13276.7 13294.5 13316.7 13441.6 13454.5 10372.1 10377.1 10387.0 10424.3 10462.0 3104.6 3579.9 3601.5 3652.7 3655.1 83771.0 85982.0 86091.6 86444.0 88015.7 9796.4 9863.7 9919.7 9955.7 10000.4 19528.3 21655.0 21940.6 22930.0 23702.9 7508.9 7652.3 7768.2 7776.0 7871.8 4121.3 4145.9 4192.4 4203.4 4367.7 3447.9 3466.2 3470.9 3479.5 3488.7 592.8 606.5 613.3 617.4 651.2 597.4 606.2 612.4 613.6 619.9 8238.3 8425.5 8450.4 8519.8 8560.3 590.8 594.0 628.0 628.2 631.5 3284.9 3565.3 3653.7 3910.6 3976.5 91994.0 92258.0 92871.1 95215.0 95560.0 37197.0 37470.4 37584.0 54857.9 80572.0 69484.0 71069.3 72949.0 75332.0 76477.6 7671.3 8436.5 8490.6 8723.5 8806.9 39167.6 49436.5 49728.0 51081.7 53331.7 48739.5 67548.6 71239.6 71889.0 73291.3 11087.7 11116.3 11195.7 11301.9 11325.4 9933.6 11374.9 12108.6 12159.7 12752.6 10700.2 11413.9 11448.4 12029.8 12048.0 11626.0 12068.3 12097.2 12156.6 12192.2 10814.4 11622.0 11623.0 11901.4 11947.3 8079.9 9131.6 9289.1 9377.6 9787.3 9306.9 9394.5 9590.7 9708.7 9814.4 13:53:52 INFO - PROCESS | 1666 | -------- Summary: end -------- 13:53:52 INFO - PROCESS | 1666 | 13:53:52 INFO - TEST-INFO | 1666: exit 0 13:53:52 INFO - TEST-OK | dromaeo_css | took 1111168ms 13:53:52 INFO - TEST-START | kraken 13:53:52 INFO - operating with platform_type : mac_ 13:53:52 INFO - Initialising browser for kraken test... 13:53:52 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/tmpfFkfVz/profile http://localhost:49271/getInfo.html) 13:53:55 INFO - PROCESS | 1674 | __metrics Screen width/height:1600/1200 13:53:55 INFO - PROCESS | 1674 | colorDepth:24 13:53:55 INFO - PROCESS | 1674 | Browser inner width/height: 1024/689 13:53:55 INFO - PROCESS | 1674 | __metrics 13:53:55 ERROR - PROCESS | 1674 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2849: TypeError: this.tabs is undefined 13:53:56 INFO - TEST-INFO | 1674: exit 0 13:53:56 INFO - Browser initialized. 13:53:56 INFO - Running cycle 1/1 for kraken test... 13:53:56 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/tmpfFkfVz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:54:08 INFO - PROCESS | 1677 | RSS: Main: 218099712 13:54:08 INFO - PROCESS | 1677 | 13:54:10 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 13:54:11 INFO - PROCESS | 1677 | RSS: Main: 230707200 13:54:11 INFO - PROCESS | 1677 | 13:54:13 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 13:54:14 INFO - PROCESS | 1677 | RSS: Main: 239431680 13:54:14 INFO - PROCESS | 1677 | 13:54:17 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 13:54:18 INFO - PROCESS | 1677 | RSS: Main: 242630656 13:54:18 INFO - PROCESS | 1677 | 13:54:20 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 13:54:20 INFO - PROCESS | 1677 | RSS: Main: 243699712 13:54:20 INFO - PROCESS | 1677 | 13:54:22 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:54:23 INFO - PROCESS | 1677 | RSS: Main: 244056064 13:54:23 INFO - PROCESS | 1677 | 13:54:26 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 13:54:27 INFO - PROCESS | 1677 | RSS: Main: 243343360 13:54:27 INFO - PROCESS | 1677 | 13:54:30 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 13:54:30 INFO - PROCESS | 1677 | RSS: Main: 243175424 13:54:30 INFO - PROCESS | 1677 | 13:54:33 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 13:54:34 INFO - PROCESS | 1677 | RSS: Main: 244699136 13:54:34 INFO - PROCESS | 1677 | 13:54:35 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:54:36 INFO - PROCESS | 1677 | RSS: Main: 244588544 13:54:36 INFO - PROCESS | 1677 | 13:54:38 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:54:39 INFO - PROCESS | 1677 | RSS: Main: 245432320 13:54:39 INFO - PROCESS | 1677 | 13:54:41 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:54:41 INFO - PROCESS | 1677 | RSS: Main: 245657600 13:54:41 INFO - PROCESS | 1677 | 13:54:44 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:54:45 INFO - PROCESS | 1677 | RSS: Main: 245714944 13:54:45 INFO - PROCESS | 1677 | 13:54:47 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:54:48 INFO - PROCESS | 1677 | RSS: Main: 245161984 13:54:48 INFO - PROCESS | 1677 | 13:54:49 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 13:54:50 INFO - PROCESS | 1677 | RSS: Main: 246312960 13:54:50 INFO - PROCESS | 1677 | 13:54:50 INFO - PROCESS | 1677 | __start_tp_report 13:54:50 INFO - PROCESS | 1677 | _x_x_mozilla_page_load 13:54:50 INFO - PROCESS | 1677 | _x_x_mozilla_page_load_details 13:54:50 INFO - PROCESS | 1677 | |i|pagename|runs| 13:54:50 INFO - PROCESS | 1677 | |0;ai-astar;116;114;117;123;115;130;116;115;116;116 13:54:50 INFO - PROCESS | 1677 | |1;audio-beat-detection;179;151;143;148;119;143;149;149;150;119 13:54:50 INFO - PROCESS | 1677 | |2;audio-dft;188;196;200;202;197;195;201;205;205;200 13:54:50 INFO - PROCESS | 1677 | |3;audio-fft;65;71;74;116;57;55;58;58;127;67 13:54:50 INFO - PROCESS | 1677 | |4;audio-oscillator;84;84;121;82;82;89;114;102;110;116 13:54:50 INFO - PROCESS | 1677 | |5;imaging-gaussian-blur;90;90;78;83;120;124;129;89;128;124 13:54:50 INFO - PROCESS | 1677 | |6;imaging-darkroom;97;97;130;96;104;128;97;128;140;121 13:54:50 INFO - PROCESS | 1677 | |7;imaging-desaturate;79;148;99;113;104;141;141;93;129;77 13:54:50 INFO - PROCESS | 1677 | |8;json-parse-financial;55;61;60;59;59;61;61;68;58;55 13:54:50 INFO - PROCESS | 1677 | |9;json-stringify-tinderbox;69;61;81;96;105;94;90;67;66;65 13:54:50 INFO - PROCESS | 1677 | |10;stanford-crypto-aes;83;81;83;84;80;82;82;82;84;87 13:54:50 INFO - PROCESS | 1677 | |11;stanford-crypto-ccm;124;168;115;110;135;132;130;137;121;120 13:54:50 INFO - PROCESS | 1677 | |12;stanford-crypto-pbkdf2;126;129;138;164;171;146;132;140;134;137 13:54:50 INFO - PROCESS | 1677 | |13;stanford-crypto-sha256-iterative;61;97;62;68;66;68;67;65;67;69 13:54:50 INFO - PROCESS | 1677 | __end_tp_report 13:54:50 INFO - PROCESS | 1677 | __start_cc_report 13:54:50 INFO - PROCESS | 1677 | _x_x_mozilla_cycle_collect,934 13:54:50 INFO - PROCESS | 1677 | __end_cc_report 13:54:50 INFO - PROCESS | 1677 | __startTimestamp1461099290049__endTimestamp 13:54:50 INFO - PROCESS | 1677 | 13:54:50 INFO - PROCESS | 1677 | ------- Summary: start ------- 13:54:50 INFO - PROCESS | 1677 | Number of tests: 14 13:54:50 INFO - PROCESS | 1677 | 13:54:50 INFO - PROCESS | 1677 | [#0] ai-astar Cycles:10 Average:117.80 Median:116.00 stddev:4.94 (4.3%) stddev-sans-first:5.20 13:54:50 INFO - PROCESS | 1677 | Values: 116.0 114.0 117.0 123.0 115.0 130.0 116.0 115.0 116.0 116.0 13:54:50 INFO - PROCESS | 1677 | 13:54:50 INFO - PROCESS | 1677 | [#1] audio-beat-detection Cycles:10 Average:145.00 Median:149.00 stddev:17.06 (11.4%) stddev-sans-first:12.91 13:54:50 INFO - PROCESS | 1677 | Values: 179.0 151.0 143.0 148.0 119.0 143.0 149.0 149.0 150.0 119.0 13:54:50 INFO - PROCESS | 1677 | 13:54:50 INFO - PROCESS | 1677 | [#2] audio-dft Cycles:10 Average:198.90 Median:200.50 stddev:5.13 (2.6%) stddev-sans-first:3.62 13:54:50 INFO - PROCESS | 1677 | Values: 188.0 196.0 200.0 202.0 197.0 195.0 201.0 205.0 205.0 200.0 13:54:50 INFO - PROCESS | 1677 | 13:54:50 INFO - PROCESS | 1677 | [#3] audio-fft Cycles:10 Average:74.80 Median:61.50 stddev:25.53 (41.5%) stddev-sans-first:26.83 13:54:50 INFO - PROCESS | 1677 | Values: 65.0 71.0 74.0 116.0 57.0 55.0 58.0 58.0 127.0 67.0 13:54:50 INFO - PROCESS | 1677 | 13:54:50 INFO - PROCESS | 1677 | [#4] audio-oscillator Cycles:10 Average:98.40 Median:82.00 stddev:15.82 (19.3%) stddev-sans-first:15.90 13:54:50 INFO - PROCESS | 1677 | Values: 84.0 84.0 121.0 82.0 82.0 89.0 114.0 102.0 110.0 116.0 13:54:50 INFO - PROCESS | 1677 | 13:54:50 INFO - PROCESS | 1677 | [#5] imaging-gaussian-blur Cycles:10 Average:105.50 Median:80.50 stddev:21.00 (26.1%) stddev-sans-first:21.51 13:54:50 INFO - PROCESS | 1677 | Values: 90.0 90.0 78.0 83.0 120.0 124.0 129.0 89.0 128.0 124.0 13:54:50 INFO - PROCESS | 1677 | 13:54:50 INFO - PROCESS | 1677 | [#6] imaging-darkroom Cycles:10 Average:113.80 Median:118.00 stddev:17.20 (14.6%) stddev-sans-first:17.14 13:54:50 INFO - PROCESS | 1677 | Values: 97.0 97.0 130.0 96.0 104.0 128.0 97.0 128.0 140.0 121.0 13:54:50 INFO - PROCESS | 1677 | 13:54:50 INFO - PROCESS | 1677 | [#7] imaging-desaturate Cycles:10 Average:112.40 Median:112.50 stddev:26.19 (23.3%) stddev-sans-first:24.84 13:54:50 INFO - PROCESS | 1677 | Values: 79.0 148.0 99.0 113.0 104.0 141.0 141.0 93.0 129.0 77.0 13:54:50 INFO - PROCESS | 1677 | 13:54:50 INFO - PROCESS | 1677 | [#8] json-parse-financial Cycles:10 Average:59.70 Median:60.50 stddev:3.68 (6.1%) stddev-sans-first:3.49 13:54:50 INFO - PROCESS | 1677 | Values: 55.0 61.0 60.0 59.0 59.0 61.0 61.0 68.0 58.0 55.0 13:54:50 INFO - PROCESS | 1677 | 13:54:50 INFO - PROCESS | 1677 | [#9] json-stringify-tinderbox Cycles:10 Average:79.40 Median:75.00 stddev:15.80 (21.1%) stddev-sans-first:16.30 13:54:50 INFO - PROCESS | 1677 | Values: 69.0 61.0 81.0 96.0 105.0 94.0 90.0 67.0 66.0 65.0 13:54:50 INFO - PROCESS | 1677 | 13:54:50 INFO - PROCESS | 1677 | [#10] stanford-crypto-aes Cycles:10 Average:82.80 Median:83.00 stddev:1.93 (2.3%) stddev-sans-first:2.05 13:54:50 INFO - PROCESS | 1677 | Values: 83.0 81.0 83.0 84.0 80.0 82.0 82.0 82.0 84.0 87.0 13:54:50 INFO - PROCESS | 1677 | 13:54:50 INFO - PROCESS | 1677 | [#11] stanford-crypto-ccm Cycles:10 Average:129.20 Median:131.00 stddev:16.19 (12.4%) stddev-sans-first:17.06 13:54:50 INFO - PROCESS | 1677 | Values: 124.0 168.0 115.0 110.0 135.0 132.0 130.0 137.0 121.0 120.0 13:54:50 INFO - PROCESS | 1677 | 13:54:50 INFO - PROCESS | 1677 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:141.70 Median:139.00 stddev:14.81 (10.7%) stddev-sans-first:14.58 13:54:50 INFO - PROCESS | 1677 | Values: 126.0 129.0 138.0 164.0 171.0 146.0 132.0 140.0 134.0 137.0 13:54:50 INFO - PROCESS | 1677 | 13:54:50 INFO - PROCESS | 1677 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.00 Median:67.50 stddev:10.18 (15.1%) stddev-sans-first:10.37 13:54:50 INFO - PROCESS | 1677 | Values: 61.0 97.0 62.0 68.0 66.0 68.0 67.0 65.0 67.0 69.0 13:54:50 INFO - PROCESS | 1677 | -------- Summary: end -------- 13:54:50 INFO - PROCESS | 1677 | 13:54:50 INFO - TEST-INFO | 1677: exit 0 13:54:50 INFO - TEST-OK | kraken | took 58220ms 13:54:50 INFO - SUITE-END | took 1169s 13:54:51 INFO - Completed test suite (00:19:29) 13:54:51 INFO - javascript benchmark 13:54:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3472.600650925144, 3513.2622149348617, 3534.6642069003437, 3555.697765689916, 3564.750467467277, 5674.574406919487, 5714.457126572979, 5728.42715728427, 5728.742206600703, 5729.312482502106, 5689.943100568993, 5705.828825135242, 5735.397783232763, 5743.368229494763, 5752.424757524245, 5150.896982060356, 5158.922616160751, 5190.4809519047985, 5225.477452254782, 5229.163333866582, 4670.229412146991, 5122.206058060988, 5267.078261304276, 5282.418933917266, 5300.363956325246, 4278.978605106986, 5259.184826351921, 5279.2872962150195, 5294.52349288564, 5313.9734301328635, 5204.34945631796, 5231.921521177185, 5241.161414173752, 5244.8688782780355, 5310.150375939831, 4936.629752768521, 5159.14874045782, 5221.765020574083, 5232.2674825524455, 5261.342332208474, 5527.917081243784, 5611.466910643482, 5620.803271885506, 5675.545956323525, 5693.857653558694, 5176.0, 6110.877782444326, 6113.633182009049, 6142.723577439026, 6160.322364539897, 5061.519155680205, 5831.825045248649, 5961.701914904237, 6105.870413973429, 6192.8761424771255, 4994.600431965434, 5947.345791962881, 5964.7315870785915, 5977.551683623746, 5980.760769569256, 5337.599680024014, 6313.0, 6315.684215789192, 6316.021016742412, 6330.620162790247, 5083.7203953782155, 6016.458518733335, 6120.660465672121, 6121.174266083698, 6163.044728067157, 6152.661603611844, 6172.259328880563, 6173.47525460332, 6195.31851496335, 6200.782972596027, 6017.2779266488305, 6106.267247930276, 6118.969405153035, 6138.877222455615, 6158.568900177034, 6072.8785424292155, 6095.512359011268, 6112.388761123852, 6118.649636126507, 6124.5100391968535, 4814.831480898222, 4923.113839508851, 4927.975360123248, 4934.210526315772, 4949.975250123726, 4340.913181736348, 4365.170617582713, 4471.686981911235, 4479.731216126977, 4500.572445617661, 5604.0, 5671.489565939085, 5680.801171958961, 5698.629589076697, 5763.279590051244, 6129.448349648464, 6147.569670123048, 6148.9692551536955, 6154.3845615438095, 6157.199564056644, 5616.129499927518, 5689.203511508392, 5690.2887139107615, 5720.485156335866, 5802.65184088956, 5365.651232669864, 5366.356037275552, 5385.919211211835, 5392.649477783932, 5396.595255355864, 6196.566240363221, 6249.343818899041, 6249.437550620375, 6256.092866534236, 6285.811425657238, 6083.422074902877, 6102.877942441215, 6142.3550527194, 6159.599626024294, 6166.290876549254, 6189.752409903734, 6291.811245662638, 6298.0, 6303.36966303366, 6320.494360451245, 6125.877482450415, 6126.816195514141, 6144.201253836973, 6153.753849845956, 6156.230471191101, 6115.480184184395, 6118.632882027093, 6131.866048757656, 6148.893199224182, 6161.568690191643, 5871.735771890189, 6126.2827622729355, 6141.631502109888, 6152.692365381802, 6160.260768707783, 5126.7180305083575, 6159.353267906805, 6176.66028368426, 6195.473384762166, 6206.565540412128, 5724.3703192647945, 5961.582689211714, 5962.16529685853, 6053.092036194606, 6090.665013424305, 6112.419320164578, 6115.449609534985, 6126.0, 6133.049377346519, 6170.7223174958135, 5825.679587622722, 6002.69986500682, 6013.30846952606, 6036.185115009417, 6042.3655516170165], "unit": "score", "name": "dojo.html", "value": 5652.544516551109}, {"lowerIsBetter": false, "replicates": [491.8524442667199, 541.1909195752348, 585.1602949767084, 657.6350125680246, 666.047552000639, 37812.43281350779, 38525.61474385255, 38596.22807543848, 38601.42097868527, 38633.613663863354, 40207.195856082864, 40355.0, 40633.39049914254, 40807.0, 41340.58659413405, 9341.252699784018, 9551.188149007343, 9558.139767420931, 9563.713088607354, 9602.471864047475, 9358.672446464376, 9578.664746733866, 9582.185514231298, 9590.904090959106, 9604.231661467065, 9593.760155996086, 9637.0, 9641.566129524188, 9680.225581953428, 9711.12599866012, 9617.374870633386, 9657.179139773129, 9661.613535458608, 9684.322097453181, 9707.514624268759, 9479.952600237024, 9575.425474471554, 9604.423734575948, 9623.90376096237, 9629.56666949982, 27347.726522734716, 39625.60374396248, 39753.0, 39910.401343979865, 39932.600673993176, 3062.127293721279, 3155.936881262362, 3326.8170250636203, 3402.35355282498, 3415.2315728961034, 27654.170374888785, 28226.29434264139, 28418.28954276139, 28456.0, 28667.13998580046, 2549.6048112542585, 2563.128536297649, 2602.37542989683, 2648.748368904951, 2649.483350746605, 340464.0, 711626.0, 716147.0, 725963.0, 727527.0, 71482.64258678674, 71750.64124679448, 72465.0, 72666.63666681632, 72822.63588682022, 74502.62748686221, 74612.62693686497, 74964.62517687486, 75394.0, 76390.0, 17964.550886227687, 18277.086145692927, 18302.267909283746, 18329.266829326676, 18330.541736456697, 13873.236971966437, 13927.442902283796, 14012.439502419993, 14017.0, 14147.151170929572, 22619.321420357417, 22948.88525557395, 22979.96590153447, 22995.770042299362, 23035.654465183037, 8165.918340816635, 8333.624986875606, 9312.90687093134, 9405.952970235105, 9620.422774633404, 898.2903506230135, 975.9219262459128, 992.2805965674972, 1018.5314755212685, 1122.0014187373245, 29544.704552954194, 30081.398372032436, 30452.84773576162, 30551.38897222043, 30611.540826887616, 1117.8430324613985, 1124.651358078998, 1153.4175241502987, 1167.9299242045504, 1172.9472173752201, 14628.853711462749, 14873.256337183098, 15271.465498707714, 15572.143532105843, 15689.215539222992, 507752.0, 521836.0, 527223.8194045103, 531265.0, 537085.0, 60703.08945365735, 60710.39289607047, 60877.69561152166, 61230.0, 61270.69364653148, 1516.6132636177704, 1550.100941453954, 1560.3446552448, 1564.7965764450776, 1571.591386239563, 28477.857610711813, 29101.272468188465, 29459.0, 29769.70230297756, 29920.850395747882, 44219.77890110529, 52271.73864130655, 52285.47714522958, 52442.73778631083, 52750.0, 396568.0, 408084.0, 411030.0, 485361.0, 488712.0, 33736.831315843265, 34672.653273466945, 34825.8258708715, 35004.299914001065, 35240.64759352374, 41769.37345939874, 43856.78071609622, 46632.53367466418, 46676.533234668575, 47041.05917881692, 34374.0, 37737.81131094327, 37891.673791418274, 38075.80962095172, 38634.0, 37613.435798462495, 37680.43479347757, 37819.62180378161, 37843.43234851534, 38181.0, 32857.34285314328, 33626.66373336333, 35509.40207690609, 36979.630203698696, 37676.623233768405, 29929.55105673373, 30576.040662868705, 31606.56391839955, 31866.044018679477, 32365.51451728179, 24902.50194996126, 24953.376165596004, 25118.12086576961, 25240.873795630905, 25345.366365841], "unit": "score", "name": "ext.html", "value": 20359.44139665926}, {"lowerIsBetter": false, "replicates": [358.63777584639524, 365.3460306052166, 367.89698884312395, 368.43813184893054, 373.01523976701515, 19855.602887942234, 19950.60098798025, 20792.48018799531, 21068.789312106874, 21101.788982110193, 21858.23496177634, 21895.233666821663, 22144.22495212668, 22173.0, 23338.883305583448, 5244.947550524503, 5677.858053548653, 6003.729832157542, 6007.549433792461, 6009.429104235091, 5554.1113421852515, 5810.1284807279035, 5922.3485416604135, 5925.081612350093, 5927.614705044159, 5838.9124163137585, 5875.823725288248, 5884.117382392633, 5889.263842019747, 5909.468147866691, 5502.697351645658, 5642.407547207526, 5675.262215911905, 5795.130730390433, 5796.44933731297, 5202.453742357074, 5457.181422786574, 5470.17947307903, 5535.778568857281, 5552.888942221133, 13476.326183690777, 13631.931840340834, 13644.386002629906, 18879.905600472048, 24693.87653061741, 2101.842361822854, 2142.239504975727, 2248.9887550562144, 2253.8422310438277, 2296.816254699637, 9572.95213523935, 9602.519874006342, 9842.65550705729, 10117.848232276521, 10137.341072830242, 1694.8644108471292, 1713.4345665930339, 1757.885737427063, 1763.8324359185856, 1775.973360399595, 73397.0, 94748.52625736895, 100506.49746751292, 100837.0, 110194.89805101926, 11456.770864582828, 11746.76506469883, 11804.645860624196, 11870.525178992744, 11876.228045177037, 14141.0, 14233.861291096688, 14234.718875301065, 14295.57113286603, 14365.497207597893, 8802.295816334678, 8849.823003540021, 8897.777555561162, 8908.554572271361, 8921.866172007425, 7491.6254187290415, 9002.369834111549, 9029.954850225708, 9088.681896133603, 9108.0, 3611.602723700391, 4488.4165058542185, 4556.407667003268, 4620.145273124483, 4625.190591646448, 1586.9285882135332, 1876.0713446843627, 1882.4187682346621, 1896.0993528074055, 1981.118402310958, 851.638053827121, 961.6489981156827, 979.3878825733916, 988.8763904511936, 1039.5941106247542, 19881.30415435453, 20971.056302466426, 21184.576308473745, 21220.469488262916, 21357.46606334854, 971.3589031239349, 971.7764914069662, 1001.9699409017741, 1003.2626019875386, 1027.7841653252751, 10956.28784129029, 11385.14611404148, 11420.0, 11422.086233101498, 11456.312621242585, 664506.0, 668160.0, 674358.0, 687790.0, 691534.5423272852, 83317.58341208255, 116986.0, 118778.0, 119463.40268298602, 120562.0, 881.6561540999015, 928.8699582058382, 1006.4816619440994, 1063.1335461598771, 1065.925385223027, 18027.0, 18059.367922122663, 18343.0, 18352.449426517225, 18464.169112389707, 38442.46230150874, 42237.78881105575, 46260.0, 46812.0, 50038.499615003384, 67696.98454523284, 67840.6607966957, 70692.0, 71287.64356178186, 71927.0, 7310.853782924418, 8057.705491361141, 8128.471649342774, 8161.020677518736, 8364.289035432057, 12538.49846006186, 13101.803472948104, 13130.73738525205, 13199.736005279648, 13571.38928748189, 11227.045701115496, 11411.372374519482, 11559.653210403701, 11592.536298547964, 11708.531658733555, 9508.239340852577, 11468.369239691898, 11512.30926144434, 11812.291262524275, 11827.112966527717, 11495.252808567417, 11693.415329233334, 11941.76116477683, 11978.820317695066, 12084.758304833678, 7855.5286682799215, 8124.22819832115, 8175.345972322319, 8180.427370084275, 8513.148685131437, 8590.742277731679, 11704.414779260833, 11771.47028383742, 11788.115891308358, 11818.586349477728], "unit": "score", "name": "jquery.html", "value": 9366.37154377307}, {"lowerIsBetter": false, "replicates": [1641.9261133249006, 1667.141137631541, 1997.7902320256374, 2027.8783273003621, 2095.947601309968, 8262.4629399089, 8815.250703690179, 9025.232855207307, 9262.981072082066, 9294.256459483233, 8957.0, 9004.954975225131, 9076.137766912148, 9080.137386948245, 9090.954540227876, 2521.8739063046864, 2531.164715643842, 2545.7199708032103, 2545.745425457458, 2545.809064320174, 2130.222468798885, 2139.775323591024, 2397.1729753235104, 2449.2040086971806, 2466.1738317663517, 2437.85372877627, 2450.3261603059123, 2535.911243106492, 2560.3471114865683, 2575.1759436980174, 2471.3327401601646, 2493.1024831060986, 2499.250224932513, 2499.487605040962, 2532.987335063313, 2487.3657217409627, 2499.850008999466, 2531.721480652277, 2539.263613552068, 2556.693196816394, 9180.862287065633, 9220.861687074632, 9228.446293222361, 9252.444853308823, 9279.21126704231, 5605.579581531401, 5613.7193140342815, 5684.090545512699, 5773.047447171211, 5778.5377169826315, 3548.432250839853, 3629.437437197212, 3645.0340659725193, 3675.558932928039, 3709.610490898444, 1017.7811770469384, 1025.953832077551, 1033.850091736694, 1040.5681642118511, 1040.9635662751768, 12252.44863981114, 12270.0, 12382.938085309515, 12396.008319334522, 12446.564370246997, 4953.083679519301, 4994.725290109079, 5047.798088076436, 5062.468440813662, 5077.543021128116, 10372.0, 10491.527881245362, 10548.525316360781, 10557.683269501927, 10561.155107591374, 5562.749676264497, 5697.230873832063, 5711.771529138871, 5727.8568035799435, 5734.197212390269, 3948.822302996372, 4959.107360675041, 5028.270900719375, 5075.441701412841, 5077.619178561623, 3978.1645854370327, 4011.638952494231, 4058.3100872851687, 4071.3689378146437, 4113.218488487178, 1359.7212571422835, 1368.6578355411148, 1372.0875617714107, 1378.9448422063206, 1379.482693989754, 1345.838499380061, 1377.869102435267, 1397.2175581674294, 1398.5384823008383, 1410.2596137027938, 8392.0, 8556.315494760403, 8586.31309495239, 8586.527740974307, 8609.053004169537, 506.3114164735956, 511.82342091978205, 513.2224679610393, 515.0548743056534, 520.3729505945327, 6286.49708023357, 6311.558190926591, 6331.27190373113, 6352.174217351807, 6393.328700486475, 10455.84316235257, 10854.620088296871, 10879.619213327496, 11174.553017879356, 11212.607558735404, 10649.041586257274, 10897.346159230472, 10987.121030317556, 11009.669709908716, 11092.889071109186, 8666.220040196413, 8668.219860212612, 8677.869831952527, 8736.213740763362, 8737.03892571804, 8113.985751781027, 8121.553314567519, 8133.186681331819, 8137.633806478604, 8274.172582741678, 7815.0, 8299.419040667095, 8447.704330348653, 9591.856122158313, 9904.306698531034, 9956.20350371983, 9993.250506211918, 10092.84860727075, 10094.192464602667, 10276.332038417479, 7036.964815175891, 7471.514351567131, 7685.0, 7731.188225236269, 7773.455858089879, 7122.893156602759, 8379.287760540423, 8439.493630382196, 8491.91508084936, 8502.914970850212, 7290.125184977836, 7450.96274518624, 7509.474336796372, 7512.474126811071, 7644.579548124907, 7246.058012458347, 7369.263073692588, 7406.296401841816, 7427.591482468516, 7517.361024312776, 7908.485948413335, 7923.84152316962, 7933.563653999086, 7949.483283586548, 7963.522188668699, 7133.85732285341, 7204.9913012177285, 7209.567425954459, 7221.638918054181, 7298.96350518244, 7285.890711639224, 7359.4480413968095, 7406.888896666446, 7439.8140046499275, 7445.994790703186], "unit": "score", "name": "mootools.html", "value": 4969.248384237427}, {"lowerIsBetter": false, "replicates": [314.02338726560396, 325.63366213010363, 329.83013747919813, 333.14215894072754, 335.69284105043886, 6720.73117075317, 6759.56062855914, 6782.626955517445, 6788.355106264909, 6839.794806155811, 6704.798856034315, 6741.292164322749, 6765.729370825174, 6781.050652908597, 6784.864302713942, 4741.834035808747, 4778.044391121773, 4875.536824001714, 4886.877828054292, 5017.096922553938, 4866.537678920497, 4868.294097355882, 4885.389326334208, 4893.975530122362, 4897.681650692712, 4819.445763737162, 4833.10587541306, 4835.516448355172, 4855.393075865517, 4865.951340486586, 4210.810513526868, 4846.515348465161, 4848.2727590861305, 4857.805687772485, 4885.682430642032, 4212.283911735012, 4223.0, 4223.070924396628, 4223.809928553223, 4245.893852653678, 6161.19904412428, 6375.4262116409745, 6427.710753016078, 6446.613203207776, 6468.676566171673, 5847.649141051551, 5867.383924687889, 5899.115132730082, 5899.76400943966, 5916.603775470941, 5107.719075450848, 7012.948057791321, 7079.61062141582, 7196.964015179943, 7208.49540532163, 4748.548887855648, 4749.957518267143, 4767.594754445876, 4771.403574553181, 4782.450018247911, 7614.657340419694, 7629.504082234637, 7639.579823109727, 7666.425018123663, 7675.0, 4661.976690116528, 4798.5441383068555, 4804.975975120102, 4833.927491087637, 4852.417709874838, 4887.608991280689, 4932.53140951609, 4942.654014218971, 4947.5794557462295, 4965.131102057126, 4812.735299558558, 5825.76696932118, 5840.88318233633, 5846.064629659234, 5862.970685146633, 6334.334894835976, 6840.726370945106, 7219.747308844166, 7237.819054523574, 7278.126624805057, 1409.5489443378021, 1703.190984282456, 1722.6554689062239, 1730.8788384813195, 1737.957225664591, 1070.2240875365453, 1110.3005106782675, 1120.781447617723, 1129.373197875187, 1131.2590253384046, 6632.872411690024, 6697.598144111369, 6721.22705888829, 6723.025161351675, 6767.323267673291, 6203.2245969253845, 7461.328480436679, 7468.477206595486, 7475.439342049368, 7513.737019204302, 6557.704903279364, 6566.868662626721, 6677.933220667731, 6686.83282917931, 6742.561733487308, 3947.782871942071, 3974.16542526067, 3985.4221137934837, 3996.3805610130476, 4010.0776821331256, 7734.961325193338, 7739.496932699357, 7754.030746156731, 7795.961020194863, 7887.84224315522, 6447.387498187759, 7456.3662088721985, 7840.843183136305, 7840.882386764203, 7854.725084622125, 7829.29536341732, 7830.138684744674, 7840.059192896889, 7864.9606751965875, 7867.331276841418, 7458.0, 7515.210902855121, 7539.434542409451, 7589.544627322378, 7612.505187162817, 6792.864142717019, 6889.379955804002, 7434.962825185839, 7470.0, 7490.588017659081, 6283.748650053946, 7494.98817659609, 7855.450118491879, 7857.410694197843, 7894.842103158019, 4693.624510039121, 4729.834455794031, 4762.190427627243, 4769.856904292877, 4790.9281360780315, 5491.533219676372, 6639.336066393322, 7382.003429536944, 7454.776356709307, 7553.357964572853, 6892.379685828299, 7280.890786638311, 7292.234315397019, 7313.524620899624, 7317.853642927005, 7033.9296607033275, 7278.0, 7286.1985181631035, 7287.890681639674, 7316.817079573053, 6506.991416330477, 7015.6842942066705, 7108.537945033556, 7116.46626503004, 7186.856262874818, 6539.934600654123, 6706.429953454011, 6731.158605174353, 7318.780436586911, 7339.155997060407, 6119.602225855305, 7100.431965442649, 7103.396211322096, 7137.964310178416, 7148.892766608401], "unit": "score", "name": "prototype.html", "value": 5232.676172333318}, {"lowerIsBetter": false, "replicates": [739.2053542441877, 743.7396911081121, 743.9739609113675, 769.5152054205853, 793.1434051224678, 14859.0, 15071.54785356441, 15218.39126434944, 15236.085834849902, 15251.542453726392, 13827.446902123927, 13863.653408664788, 14013.50952716655, 14026.859731402683, 14042.0, 746.4140649590087, 945.1399226703701, 958.4872093430016, 973.3819024919192, 976.6288810252095, 964.7105868239535, 971.1696499492938, 975.1711045611245, 975.8536219567051, 977.1742877268697, 994.6121012804975, 1000.8748906386702, 1003.1924300937742, 1003.4882210072842, 1006.4816619440957, 999.2655398282257, 1005.3867141043958, 1008.0725732326277, 1011.4184344002155, 1012.1953047327393, 747.5178509861164, 747.8466914282559, 753.9170691223961, 756.1493320014984, 757.3713817531491, 14636.121832689967, 15136.848631513654, 15188.772168417372, 15214.239288035664, 15237.61905952349, 13276.668083297898, 13294.534691285855, 13316.733665326736, 13441.596752097355, 13454.529091481849, 10372.066514013699, 10377.117944974687, 10387.013233742782, 10424.270301078928, 10462.0, 3104.627444706627, 3579.9081280209525, 3601.45978103284, 3652.7443078984484, 3655.067957670777, 83771.0, 85982.0, 86091.56954215189, 86444.0, 88015.67976480359, 9796.363236389612, 9863.654772082942, 9919.652812151684, 9955.701328960144, 10000.399976001463, 19528.31650892212, 21655.0, 21940.561188776133, 22929.968151433226, 23702.93336799848, 7508.924910750932, 7652.311291983637, 7768.184340644264, 7776.0, 7871.842563148705, 4121.258173528794, 4145.937810932838, 4192.350185721157, 4203.369494575777, 4367.71609845359, 3447.8620855165514, 3466.2201004773724, 3470.9479357809655, 3479.5476588043393, 3488.720902327808, 592.8321207221766, 606.4905479397328, 613.3406587917917, 617.3671986214076, 651.212033439534, 597.4025974025974, 606.1937622961451, 612.4090252905967, 613.6011592464844, 619.8512357034369, 8238.340932725368, 8425.4944703318, 8450.408471407065, 8519.787005324792, 8560.272376847914, 590.7961753195141, 594.0495207667697, 627.9843003924939, 628.1645411602558, 631.4916492223712, 3284.9392870155, 3565.2691198304287, 3653.6894363978827, 3910.608939106067, 3976.5228172619472, 91994.0, 92258.0, 92871.07128928624, 95215.0, 95560.0, 37197.0, 37470.43794343087, 37584.0, 54857.90284194294, 80572.0, 69484.0, 71069.28930710627, 72949.0, 75332.0, 76477.61761191269, 7671.309582137635, 8436.53599052058, 8490.617922193393, 8723.520206388455, 8806.930371362596, 39167.608323917535, 49436.50563494319, 49728.0, 51081.74459127681, 53331.73334133304, 48739.5126048735, 67548.6490270182, 71239.64380178066, 71889.0, 73291.26708733058, 11087.72280692989, 11116.277441966246, 11195.72010699739, 11301.943490282496, 11325.433728313386, 9933.552990115606, 11374.919382658634, 12108.636740897788, 12159.696007599881, 12752.617421477371, 10700.197485188486, 11413.942930285295, 11448.3703396314, 12029.75940481203, 12048.036157107586, 11626.0, 12068.336241506802, 12097.153199276318, 12156.635300940987, 12192.207506512048, 10814.405207713651, 11622.012128969132, 11623.0, 11901.404929753651, 11947.342896140795, 8079.927482749845, 9131.589078491617, 9289.117533834275, 9377.578008989476, 9787.314887957775, 9306.860397094184, 9394.483303418378, 9590.712278631652, 9708.708738737849, 9814.411135331902], "unit": "score", "name": "yui.html", "value": 6951.76455894576}], "name": "dromaeo_css", "value": 7614.142521900396}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [116.0, 114.0, 117.0, 123.0, 115.0, 130.0, 116.0, 115.0, 116.0, 116.0], "unit": "score", "name": "ai-astar", "value": 117.8}, {"lowerIsBetter": true, "replicates": [179.0, 151.0, 143.0, 148.0, 119.0, 143.0, 149.0, 149.0, 150.0, 119.0], "unit": "score", "name": "audio-beat-detection", "value": 145.0}, {"lowerIsBetter": true, "replicates": [188.0, 196.0, 200.0, 202.0, 197.0, 195.0, 201.0, 205.0, 205.0, 200.0], "unit": "score", "name": "audio-dft", "value": 198.9}, {"lowerIsBetter": true, "replicates": [65.0, 71.0, 74.0, 116.0, 57.0, 55.0, 58.0, 58.0, 127.0, 67.0], "unit": "score", "name": "audio-fft", "value": 74.8}, {"lowerIsBetter": true, "replicates": [84.0, 84.0, 121.0, 82.0, 82.0, 89.0, 114.0, 102.0, 110.0, 116.0], "unit": "score", "name": "audio-oscillator", "value": 98.4}, {"lowerIsBetter": true, "replicates": [90.0, 90.0, 78.0, 83.0, 120.0, 124.0, 129.0, 89.0, 128.0, 124.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 105.5}, {"lowerIsBetter": true, "replicates": [97.0, 97.0, 130.0, 96.0, 104.0, 128.0, 97.0, 128.0, 140.0, 121.0], "unit": "score", "name": "imaging-darkroom", "value": 113.8}, {"lowerIsBetter": true, "replicates": [79.0, 148.0, 99.0, 113.0, 104.0, 141.0, 141.0, 93.0, 129.0, 77.0], "unit": "score", "name": "imaging-desaturate", "value": 112.4}, {"lowerIsBetter": true, "replicates": [55.0, 61.0, 60.0, 59.0, 59.0, 61.0, 61.0, 68.0, 58.0, 55.0], "unit": "score", "name": "json-parse-financial", "value": 59.7}, {"lowerIsBetter": true, "replicates": [69.0, 61.0, 81.0, 96.0, 105.0, 94.0, 90.0, 67.0, 66.0, 65.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 79.4}, {"lowerIsBetter": true, "replicates": [83.0, 81.0, 83.0, 84.0, 80.0, 82.0, 82.0, 82.0, 84.0, 87.0], "unit": "score", "name": "stanford-crypto-aes", "value": 82.8}, {"lowerIsBetter": true, "replicates": [124.0, 168.0, 115.0, 110.0, 135.0, 132.0, 130.0, 137.0, 121.0, 120.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 129.2}, {"lowerIsBetter": true, "replicates": [126.0, 129.0, 138.0, 164.0, 171.0, 146.0, 132.0, 140.0, 134.0, 137.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 141.7}, {"lowerIsBetter": true, "replicates": [61.0, 97.0, 62.0, 68.0, 66.0, 68.0, 67.0, 65.0, 67.0, 69.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 69.0}], "name": "kraken", "value": 1528.4}]} 13:54:51 INFO - Return code: 0 13:54:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 13:54:51 ERROR - # TBPL SUCCESS # 13:54:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:54:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:54:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:54:51 INFO - 2016-04-19 13:54:51.369 screenresolution[1682:10420] starting screenresolution argv=screenresolution get 13:54:51 INFO - 2016-04-19 13:54:51.372 screenresolution[1682:10420] Display 0: 1600x1200x32@60 13:54:51 INFO - 2016-04-19 13:54:51.378 screenresolution[1683:10423] starting screenresolution argv=screenresolution list 13:54:51 INFO - Available Modes on Display 0 13:54:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:54:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:54:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:54:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:54:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:54:51 INFO - Intel Iris: 13:54:51 INFO - Chipset Model: Intel Iris 13:54:51 INFO - Type: GPU 13:54:51 INFO - Bus: Built-In 13:54:51 INFO - VRAM (Dynamic, Max): 1536 MB 13:54:51 INFO - Vendor: Intel (0x8086) 13:54:51 INFO - Device ID: 0x0a2e 13:54:51 INFO - Revision ID: 0x0009 13:54:51 INFO - Displays: 13:54:51 INFO - Display: 13:54:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:54:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:54:51 INFO - Display Serial Number: 1600x1200 60 13:54:51 INFO - Main Display: Yes 13:54:51 INFO - Mirror: Off 13:54:51 INFO - Online: Yes 13:54:51 INFO - Rotation: Supported 13:54:51 INFO - Return code: 0 13:54:51 INFO - Running post-action listener: _resource_record_post_action 13:54:51 INFO - Running post-run listener: _resource_record_post_run 13:54:52 INFO - Total resource usage - Wall time: 1204s; CPU: 54.0%; Read bytes: 31968768; Write bytes: 665161728; Read time: 324; Write time: 2064 13:54:52 INFO - install - Wall time: 33s; CPU: 55.0%; Read bytes: 352782848; Write bytes: 352929280; Read time: 28004; Write time: 654 13:54:52 INFO - run-tests - Wall time: 1172s; CPU: 53.0%; Read bytes: 31047168; Write bytes: 300209152; Read time: 311; Write time: 1325 13:54:52 INFO - Running post-run listener: _upload_blobber_files 13:54:52 INFO - Blob upload gear active. 13:54:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:54:52 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 13:54:52 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'] 13:54:52 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 13:54:53 INFO - (blobuploader) - INFO - Open directory for files ... 13:54:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 13:54:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:54:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:54:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 13:54:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:54:53 INFO - (blobuploader) - INFO - Done attempting. 13:54:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 13:54:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:54:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:54:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 13:54:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:54:54 INFO - (blobuploader) - INFO - Done attempting. 13:54:54 INFO - (blobuploader) - INFO - Iteration through files over. 13:54:54 INFO - Return code: 0 13:54:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:54:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:54:54 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cf1ad0032e1b9c54c515d902c155bb38b3fbf6ea32caa064a9e4f7d61584489c65bde09660054518171623942a6f6548bd3a9e28153414cc334355042cbb9929", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/64708eaea0c07e4228987106e5be41447370a8c196f391bf15b8bbf1835ef53bec3abcf4b817dabcdb3f9a7c02370b93bcd3eb734ffbd677ec062c1e755e1914"} 13:54:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:54:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:54:54 INFO - Contents: 13:54:54 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cf1ad0032e1b9c54c515d902c155bb38b3fbf6ea32caa064a9e4f7d61584489c65bde09660054518171623942a6f6548bd3a9e28153414cc334355042cbb9929", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/64708eaea0c07e4228987106e5be41447370a8c196f391bf15b8bbf1835ef53bec3abcf4b817dabcdb3f9a7c02370b93bcd3eb734ffbd677ec062c1e755e1914"} 13:54:54 INFO - Running post-run listener: copy_logs_to_upload_dir 13:54:54 INFO - Copying logs to upload dir... 13:54:54 INFO - mkdir: /builds/slave/test/build/upload/logs 13:54:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.929578 ========= master_lag: 3.44 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-04-19 13:54:58.434060) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-04-19 13:54:58.438015) ========= 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.UekTpTjc6r/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.7mJNBKECHZ/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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/cf1ad0032e1b9c54c515d902c155bb38b3fbf6ea32caa064a9e4f7d61584489c65bde09660054518171623942a6f6548bd3a9e28153414cc334355042cbb9929", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/64708eaea0c07e4228987106e5be41447370a8c196f391bf15b8bbf1835ef53bec3abcf4b817dabcdb3f9a7c02370b93bcd3eb734ffbd677ec062c1e755e1914"} build_url:https://queue.taskcluster.net/v1/task/TP9zI02fSdeM1EEkvIIWWw/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011095 build_url: 'https://queue.taskcluster.net/v1/task/TP9zI02fSdeM1EEkvIIWWw/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/cf1ad0032e1b9c54c515d902c155bb38b3fbf6ea32caa064a9e4f7d61584489c65bde09660054518171623942a6f6548bd3a9e28153414cc334355042cbb9929", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/64708eaea0c07e4228987106e5be41447370a8c196f391bf15b8bbf1835ef53bec3abcf4b817dabcdb3f9a7c02370b93bcd3eb734ffbd677ec062c1e755e1914"}' ========= master_lag: 1.46 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-04-19 13:54:59.912765) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 13:54:59.913092) ========= 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.UekTpTjc6r/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.7mJNBKECHZ/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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.005264 ========= master_lag: 0.13 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 13:55:00.047859) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-19 13:55:00.048174) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-19 13:55:00.048947) ========= ========= Total master_lag: 5.11 =========