builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0141 starttime: 1461701610.21 results: success (0) buildid: 20160425205003 builduid: 68a02e46d6d349f3b74437b25b685305 revision: 5bbf2e7c2fc6ff9010b1948e2f11477f48ee36e2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-26 13:13:30.206284) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-26 13:13:30.206717) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-26 13:13:30.206990) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-26 13:13:30.228185) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 13:13:30.228566) ========= 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.AUndnj64N3/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.Lqz3YLrgY9/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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-26 13:13:30-- 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% 170M=0s 2016-04-26 13:13:30 (170 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.079254 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 13:13:30.327502) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 13:13:30.327812) ========= 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.AUndnj64N3/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.Lqz3YLrgY9/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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.021189 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 13:13:30.375960) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 13:13:30.379369) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 5bbf2e7c2fc6ff9010b1948e2f11477f48ee36e2 --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-beta --rev 5bbf2e7c2fc6ff9010b1948e2f11477f48ee36e2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.AUndnj64N3/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.Lqz3YLrgY9/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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-26 13:13:30,457 truncating revision to first 12 chars 2016-04-26 13:13:30,457 Setting DEBUG logging. 2016-04-26 13:13:30,457 attempt 1/10 2016-04-26 13:13:30,457 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/5bbf2e7c2fc6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-26 13:13:31,046 unpacking tar archive at: mozilla-beta-5bbf2e7c2fc6/testing/mozharness/ program finished with exit code 0 elapsedTime=0.838073 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 13:13:31.234599) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-26 13:13:31.234919) ========= script_repo_revision: 5bbf2e7c2fc6ff9010b1948e2f11477f48ee36e2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-26 13:13:31.238318) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-26 13:13:31.238582) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-26 13:13:31.255845) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 24 mins, 19 secs) (at 2016-04-26 13:13:31.256155) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.AUndnj64N3/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.Lqz3YLrgY9/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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:13:31 INFO - MultiFileLogger online at 20160426 13:13:31 in /builds/slave/test 13:13:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 13:13:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:13:31 INFO - {'append_to_log': False, 13:13:31 INFO - 'base_work_dir': '/builds/slave/test', 13:13:31 INFO - 'blob_upload_branch': 'Mozilla-Beta', 13:13:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:13:31 INFO - 'branch': 'Mozilla-Beta', 13:13:31 INFO - 'buildbot_json_path': 'buildprops.json', 13:13:31 INFO - 'config_files': ('talos/mac_config.py',), 13:13:31 INFO - 'default_actions': ('clobber', 13:13:31 INFO - 'read-buildbot-config', 13:13:31 INFO - 'download-and-extract', 13:13:31 INFO - 'populate-webroot', 13:13:31 INFO - 'create-virtualenv', 13:13:31 INFO - 'install', 13:13:31 INFO - 'run-tests'), 13:13:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:13:31 INFO - 'download_minidump_stackwalk': True, 13:13:31 INFO - 'download_symbols': 'ondemand', 13:13:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:13:31 INFO - 'tooltool.py': '/tools/tooltool.py', 13:13:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:13:31 INFO - '/tools/misc-python/virtualenv.py')}, 13:13:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:13:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:13:31 INFO - 'installer_path': 'installer.exe', 13:13:31 INFO - 'log_level': 'info', 13:13:31 INFO - 'log_name': 'talos', 13:13:31 INFO - 'log_to_console': True, 13:13:31 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:13:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:13:31 INFO - 'opt_config_files': (), 13:13:31 INFO - 'pip_index': False, 13:13:31 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:13:31 INFO - 'cmd': ('bash', 13:13:31 INFO - '-c', 13:13:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:13:31 INFO - 'enabled': True, 13:13:31 INFO - 'halt_on_failure': False, 13:13:31 INFO - 'name': 'check_screen_resolution'},), 13:13:31 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:13:31 INFO - 'cmd': ('bash', 13:13:31 INFO - '-c', 13:13:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:13:31 INFO - 'enabled': True, 13:13:31 INFO - 'halt_on_failure': False, 13:13:31 INFO - 'name': 'check_screen_resolution'},), 13:13:31 INFO - 'run_cmd_checks_enabled': True, 13:13:31 INFO - 'sps_profile': False, 13:13:31 INFO - 'sps_profile_interval': 0, 13:13:31 INFO - 'suite': 'dromaeojs', 13:13:31 INFO - 'system_bits': '32', 13:13:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:13:31 INFO - 'title': 't-yosemite-r7-0141', 13:13:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:13:31 INFO - 'use_talos_json': True, 13:13:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:13:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:13:31 INFO - 'work_dir': 'build'} 13:13:31 INFO - ##### 13:13:31 INFO - ##### Running clobber step. 13:13:31 INFO - ##### 13:13:31 INFO - Running pre-action listener: _resource_record_pre_action 13:13:31 INFO - Running main action method: clobber 13:13:31 INFO - rmtree: /builds/slave/test/build 13:13:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:13:33 INFO - Running post-action listener: _resource_record_post_action 13:13:33 INFO - ##### 13:13:33 INFO - ##### Running read-buildbot-config step. 13:13:33 INFO - ##### 13:13:33 INFO - Running pre-action listener: _resource_record_pre_action 13:13:33 INFO - Running main action method: read_buildbot_config 13:13:33 INFO - Using buildbot properties: 13:13:33 INFO - { 13:13:33 INFO - "project": "", 13:13:33 INFO - "product": "firefox", 13:13:33 INFO - "script_repo_revision": "production", 13:13:33 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 13:13:33 INFO - "repository": "", 13:13:33 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 13:13:33 INFO - "buildid": "20160425205003", 13:13:33 INFO - "pgo_build": "False", 13:13:33 INFO - "basedir": "/builds/slave/test", 13:13:33 INFO - "buildnumber": 160, 13:13:33 INFO - "slavename": "t-yosemite-r7-0141", 13:13:33 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 13:13:33 INFO - "platform": "yosemite_r7", 13:13:33 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 13:13:33 INFO - "branch": "mozilla-beta", 13:13:33 INFO - "repo_path": "releases/mozilla-beta", 13:13:33 INFO - "revision": "5bbf2e7c2fc6ff9010b1948e2f11477f48ee36e2", 13:13:33 INFO - "stage_platform": "macosx64", 13:13:33 INFO - "builduid": "68a02e46d6d349f3b74437b25b685305", 13:13:33 INFO - "slavebuilddir": "test" 13:13:33 INFO - } 13:13:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/eJGkIHzISVSyqPt0Ruevrw/artifacts/public/build/firefox-47.0.en-US.mac.dmg. 13:13:33 INFO - Running post-action listener: _resource_record_post_action 13:13:33 INFO - ##### 13:13:33 INFO - ##### Running download-and-extract step. 13:13:33 INFO - ##### 13:13:33 INFO - Running pre-action listener: _resource_record_pre_action 13:13:33 INFO - Running main action method: download_and_extract 13:13:33 INFO - mkdir: /builds/slave/test/build/tests 13:13: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:13:33 INFO - https://queue.taskcluster.net/v1/task/eJGkIHzISVSyqPt0Ruevrw/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 13:13:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eJGkIHzISVSyqPt0Ruevrw/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 13:13:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eJGkIHzISVSyqPt0Ruevrw/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 13:13:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eJGkIHzISVSyqPt0Ruevrw/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json 13:13:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eJGkIHzISVSyqPt0Ruevrw/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json'}, attempt #1 13:13:34 INFO - Downloaded 1241 bytes. 13:13:34 INFO - Reading from file /builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json 13:13:34 INFO - Using the following test package requirements: 13:13:34 INFO - {u'common': [u'firefox-47.0.en-US.mac.common.tests.zip'], 13:13:34 INFO - u'cppunittest': [u'firefox-47.0.en-US.mac.common.tests.zip', 13:13:34 INFO - u'firefox-47.0.en-US.mac.cppunittest.tests.zip'], 13:13:34 INFO - u'gtest': [u'firefox-47.0.en-US.mac.common.tests.zip', 13:13:34 INFO - u'firefox-47.0.en-US.mac.gtest.tests.zip'], 13:13:34 INFO - u'jittest': [u'firefox-47.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 13:13:34 INFO - u'mochitest': [u'firefox-47.0.en-US.mac.common.tests.zip', 13:13:34 INFO - u'firefox-47.0.en-US.mac.mochitest.tests.zip'], 13:13:34 INFO - u'mozbase': [u'firefox-47.0.en-US.mac.common.tests.zip'], 13:13:34 INFO - u'reftest': [u'firefox-47.0.en-US.mac.common.tests.zip', 13:13:34 INFO - u'firefox-47.0.en-US.mac.reftest.tests.zip'], 13:13:34 INFO - u'talos': [u'firefox-47.0.en-US.mac.common.tests.zip', 13:13:34 INFO - u'firefox-47.0.en-US.mac.talos.tests.zip'], 13:13:34 INFO - u'web-platform': [u'firefox-47.0.en-US.mac.common.tests.zip', 13:13:34 INFO - u'firefox-47.0.en-US.mac.web-platform.tests.zip'], 13:13:34 INFO - u'webapprt': [u'firefox-47.0.en-US.mac.common.tests.zip'], 13:13:34 INFO - u'xpcshell': [u'firefox-47.0.en-US.mac.common.tests.zip', 13:13:34 INFO - u'firefox-47.0.en-US.mac.xpcshell.tests.zip']} 13:13:34 INFO - Downloading packages: [u'firefox-47.0.en-US.mac.common.tests.zip'] for test suite category: common 13:13:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:13:34 INFO - https://queue.taskcluster.net/v1/task/eJGkIHzISVSyqPt0Ruevrw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:13:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eJGkIHzISVSyqPt0Ruevrw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 13:13:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eJGkIHzISVSyqPt0Ruevrw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 13:13:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eJGkIHzISVSyqPt0Ruevrw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip 13:13:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eJGkIHzISVSyqPt0Ruevrw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip'}, attempt #1 13:14:08 INFO - Downloaded 17745664 bytes. 13:14:08 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:14:08 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 13:14:11 INFO - Return code: 0 13:14:11 INFO - Downloading packages: [u'firefox-47.0.en-US.mac.common.tests.zip', u'firefox-47.0.en-US.mac.talos.tests.zip'] for test suite category: talos 13:14:11 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:14:11 INFO - https://queue.taskcluster.net/v1/task/eJGkIHzISVSyqPt0Ruevrw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:14:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eJGkIHzISVSyqPt0Ruevrw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 13:14:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eJGkIHzISVSyqPt0Ruevrw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 13:14:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eJGkIHzISVSyqPt0Ruevrw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip 13:14:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eJGkIHzISVSyqPt0Ruevrw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip'}, attempt #1 13:14:39 INFO - Downloaded 17745664 bytes. 13:14:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:14:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 13:14:43 INFO - Return code: 0 13:14:43 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:14:43 INFO - https://queue.taskcluster.net/v1/task/eJGkIHzISVSyqPt0Ruevrw/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 13:14:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eJGkIHzISVSyqPt0Ruevrw/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 13:14:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eJGkIHzISVSyqPt0Ruevrw/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 13:14:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eJGkIHzISVSyqPt0Ruevrw/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip 13:14:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eJGkIHzISVSyqPt0Ruevrw/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip'}, attempt #1 13:14:44 INFO - Downloaded 10845194 bytes. 13:14:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:14:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 13:14:44 INFO - Return code: 0 13:14:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:14:44 INFO - https://queue.taskcluster.net/v1/task/eJGkIHzISVSyqPt0Ruevrw/artifacts/public/build/firefox-47.0.en-US.mac.dmg matches https://queue.taskcluster.net 13:14:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eJGkIHzISVSyqPt0Ruevrw/artifacts/public/build/firefox-47.0.en-US.mac.dmg 13:14:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eJGkIHzISVSyqPt0Ruevrw/artifacts/public/build/firefox-47.0.en-US.mac.dmg 13:14:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eJGkIHzISVSyqPt0Ruevrw/artifacts/public/build/firefox-47.0.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0.en-US.mac.dmg 13:14:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eJGkIHzISVSyqPt0Ruevrw/artifacts/public/build/firefox-47.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.dmg'}, attempt #1 13:17:18 INFO - Downloaded 88299541 bytes. 13:17:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eJGkIHzISVSyqPt0Ruevrw/artifacts/public/build/firefox-47.0.en-US.mac.dmg 13:17:18 INFO - mkdir: /builds/slave/test/properties 13:17:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:17:18 INFO - Writing to file /builds/slave/test/properties/build_url 13:17:18 INFO - Contents: 13:17:18 INFO - build_url:https://queue.taskcluster.net/v1/task/eJGkIHzISVSyqPt0Ruevrw/artifacts/public/build/firefox-47.0.en-US.mac.dmg 13:17:20 INFO - Running post-action listener: _resource_record_post_action 13:17:20 INFO - Running post-action listener: set_extra_try_arguments 13:17:20 INFO - ##### 13:17:20 INFO - ##### Running populate-webroot step. 13:17:20 INFO - ##### 13:17:20 INFO - Running pre-action listener: _resource_record_pre_action 13:17:20 INFO - Running main action method: populate_webroot 13:17:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:17:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:17:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:17:20 INFO - '--tppagecycles', 13:17:20 INFO - '7'], 13:17:20 INFO - 'tests': ['tsvgm']}}, 13:17:20 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 13:17:20 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 13:17:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:17:20 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 13:17:20 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 13:17:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:17:20 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:17:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:17:20 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:17:20 INFO - 'talos_options': ['--e10s'], 13:17:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:17:20 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:17:20 INFO - 'tests': ['damp', 'tps']}, 13:17:20 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:17:20 INFO - 'talos_options': ['--e10s'], 13:17:20 INFO - 'tests': ['damp', 'tps']}, 13:17:20 INFO - 'g3': {'tests': ['dromaeo_dom']}, 13:17:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:17:20 INFO - 'other': {'tests': ['a11yr', 13:17:20 INFO - 'ts_paint', 13:17:20 INFO - 'tpaint', 13:17:20 INFO - 'sessionrestore', 13:17:20 INFO - 'sessionrestore_no_auto_restore', 13:17:20 INFO - 'tabpaint']}, 13:17:20 INFO - 'other-e10s': {'talos_options': ['--e10s'], 13:17:20 INFO - 'tests': ['a11yr', 13:17:20 INFO - 'ts_paint', 13:17:20 INFO - 'tpaint', 13:17:20 INFO - 'sessionrestore', 13:17:20 INFO - 'sessionrestore_no_auto_restore', 13:17:20 INFO - 'tabpaint']}, 13:17:20 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 13:17:20 INFO - 'tests': ['a11yr', 13:17:20 INFO - 'ts_paint', 13:17:20 INFO - 'tpaint', 13:17:20 INFO - 'sessionrestore', 13:17:20 INFO - 'sessionrestore_no_auto_restore', 13:17:20 INFO - 'tabpaint']}, 13:17:20 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 13:17:20 INFO - 'tests': ['a11yr', 13:17:20 INFO - 'ts_paint', 13:17:20 INFO - 'tpaint', 13:17:20 INFO - 'sessionrestore', 13:17:20 INFO - 'sessionrestore_no_auto_restore', 13:17:20 INFO - 'tabpaint']}, 13:17:20 INFO - 'other_l64': {'tests': ['a11yr', 13:17:20 INFO - 'ts_paint', 13:17:20 INFO - 'tpaint', 13:17:20 INFO - 'sessionrestore', 13:17:20 INFO - 'sessionrestore_no_auto_restore', 13:17:20 INFO - 'tabpaint']}, 13:17:20 INFO - 'other_nol64': {'tests': ['a11yr', 13:17:20 INFO - 'ts_paint', 13:17:20 INFO - 'tpaint', 13:17:20 INFO - 'sessionrestore', 13:17:20 INFO - 'sessionrestore_no_auto_restore', 13:17:20 INFO - 'tabpaint']}, 13:17:20 INFO - 'svgr': {'tests': ['tsvgx', 13:17:20 INFO - 'tsvgr_opacity', 13:17:20 INFO - 'tart', 13:17:20 INFO - 'tscrollx', 13:17:20 INFO - 'cart']}, 13:17:20 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 13:17:20 INFO - 'tests': ['tsvgx', 13:17:20 INFO - 'tsvgr_opacity', 13:17:20 INFO - 'tart', 13:17:20 INFO - 'tscrollx', 13:17:20 INFO - 'cart']}, 13:17:20 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:17:20 INFO - 'tests': ['tp5o']}, 13:17:20 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:17:20 INFO - 'talos_options': ['--e10s'], 13:17:20 INFO - 'tests': ['tp5o']}, 13:17:20 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:17:20 INFO - 'talos_options': ['--xperf_path', 13:17:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:17:20 INFO - 'tests': ['tp5n']}, 13:17:20 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:17:20 INFO - 'talos_options': ['--e10s', 13:17:20 INFO - '--xperf_path', 13:17:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:17:20 INFO - 'tests': ['tp5n']}}, 13:17:20 INFO - 'talos.zip': {'path': '', 13:17:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:17:20 INFO - Running post-action listener: _resource_record_post_action 13:17:20 INFO - ##### 13:17:20 INFO - ##### Running create-virtualenv step. 13:17:20 INFO - ##### 13:17:20 INFO - Running pre-action listener: _resource_record_pre_action 13:17:20 INFO - Running main action method: create_virtualenv 13:17:20 INFO - Creating virtualenv /builds/slave/test/build/venv 13:17:20 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:17:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:17:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:17:21 INFO - Using real prefix '/tools/python27' 13:17:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:17:21 INFO - Installing distribute.............................................................................................................................................................................................done. 13:17:24 INFO - Installing pip.................done. 13:17:24 INFO - Return code: 0 13:17:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:17:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:17:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:17:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:17:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:17:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a622a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6294c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd049dacc40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4c6d30>, '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 0x10a32adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a4bdb20>, '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.AUndnj64N3/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.Lqz3YLrgY9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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:17:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 13:17:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 13:17:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AUndnj64N3/Render', 13:17:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:17:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:17:24 INFO - 'HOME': '/Users/cltbld', 13:17:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:17:24 INFO - 'LOGNAME': 'cltbld', 13:17:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:17:24 INFO - 'MOZ_NO_REMOTE': '1', 13:17:24 INFO - 'NO_EM_RESTART': '1', 13:17:24 INFO - 'PAGER': '/bin/cat', 13:17:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:17:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:17:24 INFO - 'PWD': '/builds/slave/test', 13:17:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:17:24 INFO - 'SHELL': '/bin/bash', 13:17:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lqz3YLrgY9/Listeners', 13:17:24 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 13:17:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:17:24 INFO - 'USER': 'cltbld', 13:17:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:17:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:17:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:17:24 INFO - 'XPC_FLAGS': '0x0', 13:17:24 INFO - 'XPC_SERVICE_NAME': '0', 13:17:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:17:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:17:24 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:17:24 INFO - Cleaning up... 13:17:24 INFO - Return code: 0 13:17:24 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 13:17:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:17:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:17:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:17:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:17:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a622a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6294c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd049dacc40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4c6d30>, '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 0x10a32adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a4bdb20>, '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.AUndnj64N3/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.Lqz3YLrgY9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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:17:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 13:17:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 13:17:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AUndnj64N3/Render', 13:17:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:17:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:17:24 INFO - 'HOME': '/Users/cltbld', 13:17:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:17:24 INFO - 'LOGNAME': 'cltbld', 13:17:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:17:24 INFO - 'MOZ_NO_REMOTE': '1', 13:17:24 INFO - 'NO_EM_RESTART': '1', 13:17:24 INFO - 'PAGER': '/bin/cat', 13:17:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:17:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:17:24 INFO - 'PWD': '/builds/slave/test', 13:17:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:17:24 INFO - 'SHELL': '/bin/bash', 13:17:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lqz3YLrgY9/Listeners', 13:17:24 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 13:17:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:17:24 INFO - 'USER': 'cltbld', 13:17:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:17:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:17:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:17:24 INFO - 'XPC_FLAGS': '0x0', 13:17:24 INFO - 'XPC_SERVICE_NAME': '0', 13:17:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:17:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:17:25 INFO - Downloading/unpacking psutil>=0.7.1 13:17:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:17:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:17:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:17:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:17:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:17:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:17:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:17:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:17:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:17:28 INFO - Installing collected packages: psutil 13:17:28 INFO - Running setup.py install for psutil 13:17:28 INFO - building 'psutil._psutil_osx' extension 13:17:28 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:17:28 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:17:28 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:17:28 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:17:28 INFO - building 'psutil._psutil_posix' extension 13:17:28 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:17:29 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:17:29 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:17:29 INFO - ^ 13:17:29 INFO - 1 warning generated. 13:17:29 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:17:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:17:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:17:29 INFO - Successfully installed psutil 13:17:29 INFO - Cleaning up... 13:17:29 INFO - Return code: 0 13:17:29 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 13:17:29 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:17:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:17:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17:29 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:17:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:17:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17:29 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 0x10a622a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6294c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd049dacc40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4c6d30>, '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 0x10a32adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a4bdb20>, '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.AUndnj64N3/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.Lqz3YLrgY9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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:17:29 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:17:29 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:17:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AUndnj64N3/Render', 13:17:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:17:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:17:29 INFO - 'HOME': '/Users/cltbld', 13:17:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:17:29 INFO - 'LOGNAME': 'cltbld', 13:17:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:17:29 INFO - 'MOZ_NO_REMOTE': '1', 13:17:29 INFO - 'NO_EM_RESTART': '1', 13:17:29 INFO - 'PAGER': '/bin/cat', 13:17:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:17:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:17:29 INFO - 'PWD': '/builds/slave/test', 13:17:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:17:29 INFO - 'SHELL': '/bin/bash', 13:17:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lqz3YLrgY9/Listeners', 13:17:29 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 13:17:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:17:29 INFO - 'USER': 'cltbld', 13:17:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:17:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:17:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:17:29 INFO - 'XPC_FLAGS': '0x0', 13:17:29 INFO - 'XPC_SERVICE_NAME': '0', 13:17:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:17:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:17:29 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 13:17:29 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:17:29 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:17:29 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:17:29 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:17:29 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:17:29 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:17:31 INFO - Downloading mozsystemmonitor-0.0.tar.gz 13:17:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:17:32 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:17:32 INFO - Installing collected packages: mozsystemmonitor 13:17:32 INFO - Running setup.py install for mozsystemmonitor 13:17:32 INFO - Successfully installed mozsystemmonitor 13:17:32 INFO - Cleaning up... 13:17:32 INFO - Return code: 0 13:17:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:17:32 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:17:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:17:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17:32 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:17:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:17:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17:32 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 0x10a622a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6294c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd049dacc40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4c6d30>, '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 0x10a32adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a4bdb20>, '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.AUndnj64N3/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.Lqz3YLrgY9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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:17:32 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:17:32 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:17:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AUndnj64N3/Render', 13:17:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:17:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:17:32 INFO - 'HOME': '/Users/cltbld', 13:17:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:17:32 INFO - 'LOGNAME': 'cltbld', 13:17:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:17:32 INFO - 'MOZ_NO_REMOTE': '1', 13:17:32 INFO - 'NO_EM_RESTART': '1', 13:17:32 INFO - 'PAGER': '/bin/cat', 13:17:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:17:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:17:32 INFO - 'PWD': '/builds/slave/test', 13:17:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:17:32 INFO - 'SHELL': '/bin/bash', 13:17:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lqz3YLrgY9/Listeners', 13:17:32 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 13:17:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:17:32 INFO - 'USER': 'cltbld', 13:17:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:17:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:17:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:17:32 INFO - 'XPC_FLAGS': '0x0', 13:17:32 INFO - 'XPC_SERVICE_NAME': '0', 13:17:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:17:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:17:32 INFO - Downloading/unpacking blobuploader==1.2.4 13:17:32 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:17:32 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:17:32 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:17:32 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:17:32 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:17:32 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:17:34 INFO - Downloading blobuploader-1.2.4.tar.gz 13:17:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:17:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:17: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:17: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:17: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:17: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:17: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:17: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:17:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:17:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:17: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:17: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:17: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:17: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:17: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:17: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:17:36 INFO - Downloading docopt-0.6.1.tar.gz 13:17:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:17:36 INFO - Installing collected packages: blobuploader, requests, docopt 13:17:36 INFO - Running setup.py install for blobuploader 13:17:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:17:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:17:36 INFO - Running setup.py install for requests 13:17:36 INFO - Running setup.py install for docopt 13:17:37 INFO - Successfully installed blobuploader requests docopt 13:17:37 INFO - Cleaning up... 13:17:37 INFO - Return code: 0 13:17:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:17:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:17:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:17:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:17:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:17:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17:37 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 0x10a622a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6294c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd049dacc40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4c6d30>, '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 0x10a32adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a4bdb20>, '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.AUndnj64N3/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.Lqz3YLrgY9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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:17:37 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:17:37 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:17:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AUndnj64N3/Render', 13:17:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:17:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:17:37 INFO - 'HOME': '/Users/cltbld', 13:17:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:17:37 INFO - 'LOGNAME': 'cltbld', 13:17:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:17:37 INFO - 'MOZ_NO_REMOTE': '1', 13:17:37 INFO - 'NO_EM_RESTART': '1', 13:17:37 INFO - 'PAGER': '/bin/cat', 13:17:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:17:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:17:37 INFO - 'PWD': '/builds/slave/test', 13:17:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:17:37 INFO - 'SHELL': '/bin/bash', 13:17:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lqz3YLrgY9/Listeners', 13:17:37 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 13:17:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:17:37 INFO - 'USER': 'cltbld', 13:17:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:17:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:17:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:17:37 INFO - 'XPC_FLAGS': '0x0', 13:17:37 INFO - 'XPC_SERVICE_NAME': '0', 13:17:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:17:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:17:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:17:37 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-tjuMzi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:17:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:17:37 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-ohevfJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:17:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:17:37 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-okfjq4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:17:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:17:37 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-VwJ2BV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:17:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:17:37 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-JTRiOU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:17:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:17:37 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-IqyJbS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:17:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:17:38 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-WbT8Vs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:17:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:17:38 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-MIx42N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:17:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:17:38 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-u0NjSx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:17:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:17:38 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-X8tIBY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:17:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:17:38 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-M36pIS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:17:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:17:38 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-U8ToF0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:17:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:17:38 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-dLQB2w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:17:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:17:38 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-hlqzTI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:17:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:17:38 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-dJfKRi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:17:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:17:38 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-n1oiBg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:17:39 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-yetTfk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:17:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:17:39 INFO - Running setup.py install for manifestparser 13:17:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:17:39 INFO - Running setup.py install for mozcrash 13:17:39 INFO - Running setup.py install for mozdebug 13:17:39 INFO - Running setup.py install for mozdevice 13:17:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:17:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:17:39 INFO - Running setup.py install for mozfile 13:17:39 INFO - Running setup.py install for mozhttpd 13:17:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:17:40 INFO - Running setup.py install for mozinfo 13:17:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:17:40 INFO - Running setup.py install for mozInstall 13:17:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:17:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:17:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:17:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:17:40 INFO - Running setup.py install for mozleak 13:17:40 INFO - Running setup.py install for mozlog 13:17:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:17:40 INFO - Running setup.py install for moznetwork 13:17:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:17:40 INFO - Running setup.py install for mozprocess 13:17:40 INFO - Running setup.py install for mozprofile 13:17:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:17:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:17:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:17:41 INFO - Running setup.py install for mozrunner 13:17:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:17:41 INFO - Running setup.py install for mozscreenshot 13:17:41 INFO - Running setup.py install for moztest 13:17:41 INFO - Running setup.py install for mozversion 13:17:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:17:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:17:41 INFO - Cleaning up... 13:17:41 INFO - Return code: 0 13:17:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:17:41 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:17:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:17:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17:41 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:17:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:17:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17:41 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 0x10a622a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6294c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd049dacc40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4c6d30>, '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 0x10a32adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a4bdb20>, '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.AUndnj64N3/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.Lqz3YLrgY9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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:17:41 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:17:41 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:17:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AUndnj64N3/Render', 13:17:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:17:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:17:41 INFO - 'HOME': '/Users/cltbld', 13:17:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:17:41 INFO - 'LOGNAME': 'cltbld', 13:17:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:17:41 INFO - 'MOZ_NO_REMOTE': '1', 13:17:41 INFO - 'NO_EM_RESTART': '1', 13:17:41 INFO - 'PAGER': '/bin/cat', 13:17:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:17:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:17:41 INFO - 'PWD': '/builds/slave/test', 13:17:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:17:41 INFO - 'SHELL': '/bin/bash', 13:17:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lqz3YLrgY9/Listeners', 13:17:41 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 13:17:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:17:41 INFO - 'USER': 'cltbld', 13:17:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:17:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:17:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:17:41 INFO - 'XPC_FLAGS': '0x0', 13:17:41 INFO - 'XPC_SERVICE_NAME': '0', 13:17:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:17:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:17:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:17:42 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-5vdUhu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:17:42 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:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:17:42 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-fkRthY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:17:42 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:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:17:42 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-dBQ9QX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:17:42 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:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:17:42 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-WuCtad-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:17:42 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:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:17:42 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-eYVoK3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:17:42 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:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:17:42 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-f6p69d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:17:42 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:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:17:42 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-QL7e0T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:17:42 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:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:17:42 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-Lpwnqg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:17:42 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:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:17:42 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-5tVqCC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:17:42 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:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:17:42 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-BRMwvQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:17:43 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:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:17:43 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-lOSXZ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:17:43 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:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:17:43 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-8Gc00D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:17:43 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:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:17:43 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-RpoJgP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:17:43 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:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:17:43 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-ebfedR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:17:43 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:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:17:43 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-qc6tnd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:17:43 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:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:17:43 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-32VLPF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:17:43 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:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:17:43 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-kqQ__t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:17:43 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:17:43 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:17:43 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:17:43 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:17:43 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:17:43 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:17:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:17:43 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:17:43 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:17:43 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:17:43 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:17:43 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:17:43 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:17:46 INFO - Downloading blessings-1.6.tar.gz 13:17:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:17:46 INFO - Installing collected packages: blessings 13:17:46 INFO - Running setup.py install for blessings 13:17:46 INFO - Successfully installed blessings 13:17:46 INFO - Cleaning up... 13:17:46 INFO - Return code: 0 13:17:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:17:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:17:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:17:46 INFO - Reading from file tmpfile_stdout 13:17:46 INFO - Current package versions: 13:17:46 INFO - blessings == 1.6 13:17:46 INFO - blobuploader == 1.2.4 13:17:46 INFO - docopt == 0.6.1 13:17:46 INFO - manifestparser == 1.1 13:17:46 INFO - mozInstall == 1.12 13:17:46 INFO - mozcrash == 0.17 13:17:46 INFO - mozdebug == 0.1 13:17:46 INFO - mozdevice == 0.48 13:17:46 INFO - mozfile == 1.2 13:17:46 INFO - mozhttpd == 0.7 13:17:46 INFO - mozinfo == 0.9 13:17:46 INFO - mozleak == 0.1 13:17:46 INFO - mozlog == 3.1 13:17:46 INFO - moznetwork == 0.27 13:17:46 INFO - mozprocess == 0.22 13:17:46 INFO - mozprofile == 0.28 13:17:46 INFO - mozrunner == 6.11 13:17:46 INFO - mozscreenshot == 0.1 13:17:46 INFO - mozsystemmonitor == 0.0 13:17:46 INFO - moztest == 0.7 13:17:46 INFO - mozversion == 1.4 13:17:46 INFO - psutil == 3.1.1 13:17:46 INFO - requests == 1.2.3 13:17:46 INFO - wsgiref == 0.1.2 13:17:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:17:46 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:17:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:17:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17:46 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:17:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:17:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17:46 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 0x10a622a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6294c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd049dacc40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4c6d30>, '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 0x10a32adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a4bdb20>, '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.AUndnj64N3/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.Lqz3YLrgY9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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:17:46 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:17:46 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:17:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AUndnj64N3/Render', 13:17:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:17:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:17:46 INFO - 'HOME': '/Users/cltbld', 13:17:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:17:46 INFO - 'LOGNAME': 'cltbld', 13:17:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:17:46 INFO - 'MOZ_NO_REMOTE': '1', 13:17:46 INFO - 'NO_EM_RESTART': '1', 13:17:46 INFO - 'PAGER': '/bin/cat', 13:17:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:17:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:17:46 INFO - 'PWD': '/builds/slave/test', 13:17:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:17:46 INFO - 'SHELL': '/bin/bash', 13:17:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lqz3YLrgY9/Listeners', 13:17:46 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 13:17:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:17:46 INFO - 'USER': 'cltbld', 13:17:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:17:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:17:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:17:46 INFO - 'XPC_FLAGS': '0x0', 13:17:46 INFO - 'XPC_SERVICE_NAME': '0', 13:17:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:17:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:17:47 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:17:47 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:17:47 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:17:47 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:17:47 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:17:47 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:17:47 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:17:47 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:17:47 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:17:47 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:17:47 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:17:47 INFO - Cleaning up... 13:17:47 INFO - Return code: 0 13:17:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:17:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:17:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:17:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:17:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:17:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17:47 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 0x10a622a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6294c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd049dacc40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4c6d30>, '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 0x10a32adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a4bdb20>, '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.AUndnj64N3/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.Lqz3YLrgY9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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:17:47 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:17:47 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:17:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AUndnj64N3/Render', 13:17:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:17:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:17:47 INFO - 'HOME': '/Users/cltbld', 13:17:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:17:47 INFO - 'LOGNAME': 'cltbld', 13:17:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:17:47 INFO - 'MOZ_NO_REMOTE': '1', 13:17:47 INFO - 'NO_EM_RESTART': '1', 13:17:47 INFO - 'PAGER': '/bin/cat', 13:17:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:17:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:17:47 INFO - 'PWD': '/builds/slave/test', 13:17:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:17:47 INFO - 'SHELL': '/bin/bash', 13:17:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lqz3YLrgY9/Listeners', 13:17:47 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 13:17:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:17:47 INFO - 'USER': 'cltbld', 13:17:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:17:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:17:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:17:47 INFO - 'XPC_FLAGS': '0x0', 13:17:47 INFO - 'XPC_SERVICE_NAME': '0', 13:17:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:17:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:17:47 INFO - Downloading/unpacking jsonschema 13:17:47 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:17:47 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:17:47 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:17:47 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:17:47 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:17:47 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:17:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:17:49 INFO - Downloading/unpacking functools32 (from jsonschema) 13:17:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:17:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:17:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:17:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:17:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:17:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:17:50 INFO - Downloading functools32-3.2.3-2.tar.gz 13:17:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:17:50 INFO - warning: no files found matching '*.txt' 13:17:50 INFO - no previously-included directories found matching 'build' 13:17:50 INFO - no previously-included directories found matching 'dist' 13:17:50 INFO - no previously-included directories found matching '.git*' 13:17:50 INFO - Installing collected packages: jsonschema, functools32 13:17:50 INFO - Running setup.py install for functools32 13:17:50 INFO - warning: no files found matching '*.txt' 13:17:50 INFO - no previously-included directories found matching 'build' 13:17:50 INFO - no previously-included directories found matching 'dist' 13:17:50 INFO - no previously-included directories found matching '.git*' 13:17:50 INFO - Successfully installed jsonschema functools32 13:17:50 INFO - Cleaning up... 13:17:50 INFO - Return code: 0 13:17:50 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 13:17:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:17:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:17:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:17:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:17:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17:50 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 0x10a622a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6294c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd049dacc40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4c6d30>, '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 0x10a32adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a4bdb20>, '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.AUndnj64N3/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.Lqz3YLrgY9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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:17:50 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:17:50 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:17:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AUndnj64N3/Render', 13:17:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:17:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:17:50 INFO - 'HOME': '/Users/cltbld', 13:17:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:17:50 INFO - 'LOGNAME': 'cltbld', 13:17:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:17:50 INFO - 'MOZ_NO_REMOTE': '1', 13:17:50 INFO - 'NO_EM_RESTART': '1', 13:17:50 INFO - 'PAGER': '/bin/cat', 13:17:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:17:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:17:50 INFO - 'PWD': '/builds/slave/test', 13:17:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:17:50 INFO - 'SHELL': '/bin/bash', 13:17:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lqz3YLrgY9/Listeners', 13:17:50 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 13:17:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:17:50 INFO - 'USER': 'cltbld', 13:17:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:17:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:17:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:17:50 INFO - 'XPC_FLAGS': '0x0', 13:17:50 INFO - 'XPC_SERVICE_NAME': '0', 13:17:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:17:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:17:50 INFO - Downloading/unpacking flake8 13:17:50 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:17:50 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:17:50 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:17:50 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:17:50 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:17:50 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:17:53 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 13:17:53 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 13:17:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:17:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:17:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:17:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:17:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:17:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:17:53 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 13:17:53 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 13:17:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:17:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:17:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:17:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:17:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:17:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:17:54 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 13:17:54 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:17:54 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:17:54 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:17:54 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:17:54 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:17:54 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:17:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 13:17:54 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:17:54 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:17:54 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:17:54 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:17:54 INFO - no previously-included directories found matching 'docs/_build' 13:17:54 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 13:17:54 INFO - Running setup.py install for pep8 13:17:54 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:17:54 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:17:54 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:17:54 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:17:54 INFO - no previously-included directories found matching 'docs/_build' 13:17:54 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 13:17:54 INFO - Successfully installed flake8 mccabe pyflakes pep8 13:17:54 INFO - Cleaning up... 13:17:54 INFO - Return code: 0 13:17:54 INFO - Running post-action listener: _resource_record_post_action 13:17:54 INFO - Running post-action listener: _start_resource_monitoring 13:17:55 INFO - Starting resource monitoring. 13:17:55 INFO - ##### 13:17:55 INFO - ##### Running install step. 13:17:55 INFO - ##### 13:17:55 INFO - Running pre-action listener: _resource_record_pre_action 13:17:55 INFO - Running main action method: install 13:17:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:17:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:17:55 INFO - Reading from file tmpfile_stdout 13:17:55 INFO - Detecting whether we're running mozinstall >=1.0... 13:17:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:17:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:17:55 INFO - Reading from file tmpfile_stdout 13:17:55 INFO - Output received: 13:17:55 INFO - Usage: mozinstall [options] installer 13:17:55 INFO - Options: 13:17:55 INFO - -h, --help show this help message and exit 13:17:55 INFO - -d DEST, --destination=DEST 13:17:55 INFO - Directory to install application into. [default: 13:17:55 INFO - "/builds/slave/test"] 13:17:55 INFO - --app=APP Application being installed. [default: firefox] 13:17:55 INFO - mkdir: /builds/slave/test/build/application 13:17:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 13:17:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0.en-US.mac.dmg --destination /builds/slave/test/build/application 13:18:17 INFO - Reading from file tmpfile_stdout 13:18:17 INFO - Output received: 13:18:17 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 13:18:17 INFO - Running post-action listener: _resource_record_post_action 13:18:17 INFO - ##### 13:18:17 INFO - ##### Running run-tests step. 13:18:17 INFO - ##### 13:18:17 INFO - Running pre-action listener: _resource_record_pre_action 13:18:17 INFO - Running main action method: run_tests 13:18:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:18:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:18:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:18:17 INFO - 2016-04-26 13:18:17.616 screenresolution[2210:19154] starting screenresolution argv=screenresolution get 13:18:17 INFO - 2016-04-26 13:18:17.618 screenresolution[2210:19154] Display 0: 1600x1200x32@60 13:18:17 INFO - 2016-04-26 13:18:17.624 screenresolution[2211:19156] starting screenresolution argv=screenresolution list 13:18:17 INFO - Available Modes on Display 0 13:18:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:18:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:18:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:18:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:18:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:18:17 INFO - Intel Iris: 13:18:17 INFO - Chipset Model: Intel Iris 13:18:17 INFO - Type: GPU 13:18:17 INFO - Bus: Built-In 13:18:17 INFO - VRAM (Dynamic, Max): 1536 MB 13:18:17 INFO - Vendor: Intel (0x8086) 13:18:17 INFO - Device ID: 0x0a2e 13:18:17 INFO - Revision ID: 0x0009 13:18:17 INFO - Displays: 13:18:17 INFO - Display: 13:18:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:18:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:18:17 INFO - Display Serial Number: 1600x1200 60 13:18:17 INFO - Main Display: Yes 13:18:17 INFO - Mirror: Off 13:18:17 INFO - Online: Yes 13:18:17 INFO - Rotation: Supported 13:18:17 INFO - Return code: 0 13:18:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:18:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:18:17 INFO - Python 2.7.3 13:18:17 INFO - Return code: 0 13:18:17 INFO - grabbing minidump binary from tooltool 13:18:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:18:17 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 0x10a4c6d30>, '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 0x10a32adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a4bdb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:18:17 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:18:17 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:18:17 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:18:17 INFO - Return code: 0 13:18:17 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:18:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:18:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:18:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:18:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:18:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 13:18:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 13:18:19 INFO - Return code: 0 13:18:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eJGkIHzISVSyqPt0Ruevrw/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0141', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 13:18:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/eJGkIHzISVSyqPt0Ruevrw/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0141 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 13:18:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AUndnj64N3/Render', 13:18:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:18:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:18:19 INFO - 'HOME': '/Users/cltbld', 13:18:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:18:19 INFO - 'LOGNAME': 'cltbld', 13:18:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:18:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:18:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:18:19 INFO - 'MOZ_NO_REMOTE': '1', 13:18:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:18:19 INFO - 'NO_EM_RESTART': '1', 13:18:19 INFO - 'PAGER': '/bin/cat', 13:18:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:18:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:18:19 INFO - 'PWD': '/builds/slave/test', 13:18:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:18:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:18:19 INFO - 'SHELL': '/bin/bash', 13:18:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lqz3YLrgY9/Listeners', 13:18:19 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 13:18:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:18:19 INFO - 'USER': 'cltbld', 13:18:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:18:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:18:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:18:19 INFO - 'XPC_FLAGS': '0x0', 13:18:19 INFO - 'XPC_SERVICE_NAME': '0', 13:18:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:18:19 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eJGkIHzISVSyqPt0Ruevrw/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0141', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 13:18:19 INFO - mozversion application_buildid: 20160425205003 13:18:19 INFO - mozversion application_changeset: 5bbf2e7c2fc6ff9010b1948e2f11477f48ee36e2 13:18:19 INFO - mozversion application_display_name: Firefox 13:18:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:18:19 INFO - mozversion application_name: Firefox 13:18:19 INFO - mozversion application_remotingname: firefox 13:18:19 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 13:18:19 INFO - mozversion application_vendor: Mozilla 13:18:19 INFO - mozversion application_version: 47.0 13:18:19 INFO - mozversion platform_buildid: 20160425205003 13:18:19 INFO - mozversion platform_changeset: 5bbf2e7c2fc6ff9010b1948e2f11477f48ee36e2 13:18:19 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 13:18:19 INFO - mozversion platform_version: 47.0 13:18:19 INFO - using testdate: 1461701899 13:18:19 INFO - actual date: 1461701899 13:18:19 INFO - starting webserver on 'localhost:56544' 13:18:19 INFO - SUITE-START | Running 2 tests 13:18:19 INFO - TEST-START | dromaeo_css 13:18:19 INFO - operating with platform_type : mac_ 13:18:19 INFO - Initialising browser for dromaeo_css test... 13:18:19 INFO - TEST-INFO | started process 2229 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/tmpBKKtLk/profile http://localhost:56544/getInfo.html) 13:18:21 INFO - PROCESS | 2229 | __metrics Screen width/height:1600/1200 13:18:21 INFO - PROCESS | 2229 | colorDepth:24 13:18:21 INFO - PROCESS | 2229 | Browser inner width/height: 1024/689 13:18:21 INFO - PROCESS | 2229 | __metrics 13:18:21 INFO - PROCESS | 2229 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 13:18:21 INFO - PROCESS | 2229 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:18:22 INFO - TEST-INFO | 2229: exit 0 13:18:22 INFO - Browser initialized. 13:18:22 INFO - Running cycle 1/1 for dromaeo_css test... 13:18:22 INFO - TEST-INFO | started process 2232 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/tmpBKKtLk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:18:34 INFO - PROCESS | 2232 | RSS: Main: 156606464 13:18:34 INFO - PROCESS | 2232 | 13:19:25 INFO - PROCESS | 2232 | 1461701965703 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:21:34 INFO - PROCESS | 2232 | Cycle 1(1): loaded http://localhost:56544/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56544/tests/dromaeo/cssquery-ext.html) 13:21:35 INFO - PROCESS | 2232 | RSS: Main: 278429696 13:21:35 INFO - PROCESS | 2232 | 13:24:37 INFO - PROCESS | 2232 | Cycle 1(1): loaded http://localhost:56544/tests/dromaeo/cssquery-ext.html (next: http://localhost:56544/tests/dromaeo/cssquery-jquery.html) 13:24:38 INFO - PROCESS | 2232 | RSS: Main: 291872768 13:24:38 INFO - PROCESS | 2232 | 13:27:40 INFO - PROCESS | 2232 | Cycle 1(1): loaded http://localhost:56544/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56544/tests/dromaeo/cssquery-mootools.html) 13:27:40 INFO - PROCESS | 2232 | RSS: Main: 293519360 13:27:40 INFO - PROCESS | 2232 | 13:30:42 INFO - PROCESS | 2232 | Cycle 1(1): loaded http://localhost:56544/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56544/tests/dromaeo/cssquery-prototype.html) 13:30:43 INFO - PROCESS | 2232 | RSS: Main: 253808640 13:30:43 INFO - PROCESS | 2232 | 13:33:46 INFO - PROCESS | 2232 | Cycle 1(1): loaded http://localhost:56544/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56544/tests/dromaeo/cssquery-yui.html) 13:33:47 INFO - PROCESS | 2232 | RSS: Main: 254963712 13:33:47 INFO - PROCESS | 2232 | 13:36:49 INFO - PROCESS | 2232 | Cycle 1(1): loaded http://localhost:56544/tests/dromaeo/cssquery-yui.html (next: http://localhost:56544/tests/dromaeo/cssquery-dojo.html) 13:36:49 INFO - PROCESS | 2232 | RSS: Main: 301879296 13:36:49 INFO - PROCESS | 2232 | 13:36:49 INFO - PROCESS | 2232 | __start_tp_report 13:36:49 INFO - PROCESS | 2232 | _x_x_mozilla_page_load 13:36:49 INFO - PROCESS | 2232 | _x_x_mozilla_page_load_details 13:36:49 INFO - PROCESS | 2232 | |i|pagename|runs| 13:36:49 INFO - PROCESS | 2232 | |0;dojo.html;2926.4732348177326;3574.0528759878634;3589.3718599245126;3603.9639603603955;3654.1960768630893;5805.361410244875;5830.271216097988;5844.152597873316;5858.970705146479;5899.292084949807;5824.912626310609;5867.501262392691;5874.206982057421;5891.263592050994;5903.114532820079;5253.9737301313635;5284.8150314739;5325.281087053247;5338.3060202173665;5391.433899440561;5351.143816989283;5356.91964620531;5361.812336568221;5362.034833729927;5362.570994320465;5283.762230699608;5359.866003349928;5365.839024829242;5368.785248590071;5377.085895397772;4493.842715504941;5359.758810853501;5369.892602147955;5392.703401312926;5403.594730395197;5129.435762066169;5333.280007199056;5338.27933229013;5340.973295133538;5354.544863686591;4781.2111001685025;5699.914501282443;5742.6841523716175;5752.28096487939;5767.36558978512;6176.7838125665385;6279.560430769848;6281.277653069864;6285.528585356071;6300.905486417708;6257.374262573751;6263.968680156616;6305.117283580303;6314.62112273265;6350.301466838644;5994.8501287467725;6004.519638428916;6011.699415029231;6015.75936962521;6018.15745795589;6280.623162610258;6319.083732858801;6410.455111315498;6416.7433302668305;6427.678616069178;6250.531210159256;6258.186435763321;6270.498360131179;6271.749130034838;6289.213848268966;6319.368063193644;6336.112944187817;6340.3342649022115;6354.52341074412;6357.586756860789;6134.222293197073;6185.721642526097;6185.752569897154;6267.592606480564;6278.780242691484;6217.720202590804;6256.092866534327;6264.37356264379;6282.717277722513;6285.151504546919;4959.156943319645;4970.055689418999;4971.254311853251;4972.5276098770555;5008.499150084962;3729.738918275749;3860.864869729546;4400.603945644843;4540.931886021713;4564.1556312082375;6008.098785182257;6063.787767428119;6065.75736970525;6085.08723691441;6095.359987201368;5249.632525723163;6279.152314437584;6284.308726040132;6287.779927702509;6303.905441418382;5873.383294754075;6006.909896351558;6049.606775559574;6080.2399700037495;6135.754569817246;4670.579647831712;5452.468461309244;5475.67145971243;5482.506574408243;5484.561235101182;6452.193475815523;6474.352564743582;6500.674966251763;6505.447037001719;6523.771667991692;6170.290416601969;6201.914664933528;6243.43809057187;6257.43683068526;6302.369763023661;6471.449926756279;6486.967565162333;6499.28507864144;6519.054737063005;6522.641254731036;6195.566310358232;6278.905816412666;6291;6302.369763023661;6306.684665766602;6225.8132256032395;6254.936660767771;6256.405641464054;6280.842978925563;6286.937130628635;6238.6880655967925;6270.090836828543;6275.6548389839;6282.874342513215;6287.559870808999;6022.879542409215;6130.264368275835;6239.812805615838;6266.436020758154;6275.090111933653;6139.3246742857355;6150.077488376779;6150.661713605795;6164.47601953839;6175.59858609189;6253.280872699698;6270.2475702915945;6283.8114856554375;6289.685515724287;6290.654014029273;5169.81905633301;6024.8795024100145;6026.367231440636;6132.110843927517;6166.969165154145 13:36:49 INFO - PROCESS | 2232 | |1;ext.html;467.5277969251055;541.7863984674331;544.4038777538595;623.6778030575181;665.4510029225888;26524.867375663118;32615.836920815393;40447.98880027997;40648.59351406485;40743.388849167284;44013.559864401344;44280.892977675576;44407.66776996687;44417.7779111104;44474.55525444745;9898.40609563425;10121.392716437;10143.34068285559;10148.238882083873;10182.490875456235;9210.585523651453;9841.21270298378;9912.959139290379;9919.404835709844;9942.751431214205;9954.104130628242;10044.54799534019;10125.696229113139;10172.694819155437;10176.796464070714;10083.294169408144;10112;10121.34211276272;10133.695989120339;10147.238957078249;9873.358231714916;10025.899741002642;10031.448270345129;10036.397816130982;10040.79918401628;43059.35410968838;43445.69662910118;43450;43458;43463.78268108671;3224.1294850390263;3456.1186897341017;3484.6689564491335;3699.7965111918834;3942.231264903345;29046.27384315409;29920.850395747882;29945.251368715737;29990.400191996257;30390;2805.130409573039;2827.1801177658454;2848.4445533121047;2868.6557613086356;2904.2158617173454;446963.99999999674;457419;459642;459890;460832;66687.99968000484;67010.66494667495;67083.32916670771;67119.65760684856;67205.32794672088;76945;77869.83195252076;77870.6106469464;77965.61017194991;77967.61016194883;18339.449816505527;18494.722579161324;18503.81496185021;18573.535661608297;18577.628447430976;14111.71776564463;14116.082454640415;14122.152670839783;14144.787828182585;14367.640808979859;18840.623187536446;22270.66594001059;22627.20804771825;22637.773622263896;22658.773412265997;7738.806529836799;8914;9154.359194856295;9163.77090572741;9206.309526785384;813.6460639621783;945.9716208513755;1025.9538320775582;1085.8914108589079;1118.3681220110611;30780.53829192564;30910.690893091232;31095.378092438026;31444.213894652818;31494.37011259762;1034.8085604163255;1099.1097211258907;1200.33981310279;1200.3698058519346;1249.6628607390005;14809.92595037018;14906.776398354034;14979.400823967137;15090.924545377204;15102;671054;672717;676120;679244;680113;59968.80062398728;60822.39177608256;60846.478838029405;60892.39107608956;60946.39053609496;1374.3334482775845;1421.9004669673232;1553.9844601554066;1568.1453607783785;1582.841715828408;29716.10851674453;29766;29888.551671725378;30517.389652207276;30538.847305763327;50222.49777502178;50953;51008.48991510038;51059.23411148761;51069.23396148987;361566;362226.18886905396;362256;363402;363502;33233.501497477075;34538.82730586432;34594.308113837076;34679.30641387209;34719.65280347164;43971;45022.77488612536;46664;46701.29948050714;49695;37247.88256352314;37504;37611;37927.24145517129;38623.227535449696;36724.81637591795;37100;37163.442548362334;37769.81115094407;37807.05482362963;38329.6167038326;38486.2302753949;40172;40286.597134029456;41436.27073563394;30871.073867784;32597.837010814794;32687.67312326846;32762.180945476553;32811.17972050718;24835.25494235176;25008.249752507454;25274.425750179937;25471.872640636677;25578.488430231664 13:36:49 INFO - PROCESS | 2232 | |2;jquery.html;386.4203694458312;388.1209061475757;392.51328352842467;398.7348413305152;399.38295333709414;20386.388408347753;21669.891650541744;21767.45581360463;22210.555788884252;22217.333479995606;20482.692759608617;20532.281370152046;21189.364319070413;21791.67312490314;21868.671969920426;5008.746064271074;5567.554595632339;5985.371535988724;6149.446549810516;6183.474404675608;6138.17134686816;6173.722182501798;6189.721462534197;6191.318954914956;6197.97733373995;6011.098335249726;6093.664848433335;6141;6158.476529494998;6158.969205153968;5307.946920530784;5940.910886336708;6068.605540639888;6155.692215389213;6156.138140660333;5316.3354580677415;5342.332208473941;5347.144456886918;5521.613487055908;5797.652140871519;14109.929450352785;14308.57074287773;15842.445514407056;25545.361365966;25990.87004564984;2066.782987786276;2216.0360243294217;2240.3950933248093;2277.9316620501245;2385.6183010718373;8483;10277.640282590073;10535.73660658482;10742.194335424874;14252.572422827332;1786.3747688309118;1805.7201133824278;1826.1417133947023;1840.2730921286018;1862.4878158506383;95715.04284957131;100015.49976750281;101792.49103754509;104202.47898760534;106192.46903765509;9844.212463002943;11267;11500.654980350602;11567.537298507965;11648.475818588184;14683.412663493555;14688.192149431883;14693.55919322422;14740.41038358475;14755.77866332006;8877.06790786971;9006.774830629287;9035.367524273237;9079.137481939206;9105.77235569116;9183;9186.08139186076;9198.908010919806;9241.21449676785;9257.722268331961;4469.910601787946;4570.611498022638;4579.725216486955;4610.74640894754;4617.722936623813;1576.533823544115;1903.8572107092023;1919.9232030718615;1942.660034493958;1948.6297603455298;948.6489998700525;959.9616015359308;1019.9439030853226;1041.3439533094254;1054.3410368519676;19865.702014469796;20680.689789653166;20790.58418831615;20916.4116126346;20973.370798876058;994.6170724271063;1001.1790331927749;1030.644427672452;1039.5270152080936;1076.8115579773507;10769.515371808287;11116.943890330405;11159.107271418266;11175.44122793857;11185.608503702331;672729;674391;675779;679217.2078279154;688993;85091;114343.42828285805;116912;117059.41470292595;121521;1078.2667785905503;1084.1814430105221;1121.9831702524468;1144.6279959013355;1147.3804145761362;15050.096994180385;18096.366627167987;18177.09114544294;18322.17550210217;18806.623867522845;41460.792696036326;51645.48354516407;52601.7369913148;53748.46251537435;54419.45580544302;72449;75113.62443187748;77202.45595087955;78490.6075469619;78690;7790.259925307087;8240;8313.085560588452;8416.15838416178;8431.747047588582;9868.654597089067;13084.803727943898;13200.801987969997;13245.668858278621;13665.316734163052;11451.770964580495;11537.538498459968;11550.249233799776;11782.52869885195;11800.645980620595;10815.513301901276;11427;11460.541578337106;11500.82748758786;11769.764604707687;9300.860487092563;9911.900880991097;10332.586696532055;11701.356425396345;11787.11596630239;8203.835923281382;8206.753797386087;8237.46456480327;8322.417430779788;8624.568771561522;8919.375643705138;11257.71855703614;11904.047676185712;12032.014717571852;12081.214721043105 13:36:49 INFO - PROCESS | 2232 | |3;mootools.html;1526.3055309834026;1681.0838093239183;1700.430355830797;2065.4733043074016;2104.5370018595913;8363.91636083639;9023.142801433862;9199.310051746112;9200.447973121618;9201.815963680723;8835.911640883589;8946.865797013033;9079.455232686043;9098.181163695266;9107.271418286538;2595.0787470448004;2625.7899368050607;2625.8949642014295;2632.9078482253126;2637.3011152044724;2559.1938539360135;2645.642838216845;2647.6160956661274;2648.827826191301;2650.8807103680283;2332.880217495603;2484.3789052736815;2638.261286839678;2649.8410095394242;2657.4950759355756;2594.5070436616984;2616.1235985944636;2651.827631203975;2652.1115426332203;2653.5356312645404;2593.5850263957677;2615.163147792707;2621.5674413721713;2644.048142668638;2648.9337766555795;7660.425468089916;7693.999780028616;9146.085391460867;9206.355555111146;9227.169554740038;5717.914231286534;5803.361630220672;5824.388439213864;5882.294124705063;5900.085486749561;3693.2613477304376;3746.550413950343;3887.222555488885;3895.0651843557625;3895.104126050996;1084.5770149641646;1088.9346639201674;1092.6776600902754;1130.440431986164;1135.0805847263744;12257.080718946025;12339.69150771238;12364.196327238791;12366;12372.938135309356;5087.135187018216;5132.923006154911;5155.536001759785;5195.6622819516615;5231.241469986907;10606.310589811637;10648.094911932418;10656.20078494116;10661.573537058586;10675.359478431164;5868.119782032644;5952.940470595326;5953.047512397996;5959.761609535657;5965.373635768182;4725.976370118127;4774.928376074362;5052.5705315048635;5245.606579506552;5249.370075590953;4133.23535145999;4188.371744238389;4225.514065882401;4244.299690551086;4259.446271984684;1384.732969333049;1402.730528871373;1403.073971179017;1409.0277708381184;1414.915105093677;1429.171080773149;1454.6508837879044;1465.230753854235;1486.8364479907202;1489.6722721001363;8579.142085791496;8630.913690863012;8644.3948923576;8679.479231246145;8693.565321734015;480.57949294367427;538.733327003136;546.5572885962383;550.1967127993174;551.6276513353471;6411.422971932455;6420.1974753155855;6474.611523308617;6477.449416799531;6501.414872661483;10822.56709731602;11198.832017519744;11326.660200194008;11327.546898123983;11337.943310283395;10533.789324213472;10897.727556811144;11034;11100.666979990614;11112.722181945515;8656.091110433314;8762.518061506551;8798.824023519493;8802.03177650458;8954.641814327353;8449.60581504045;8497.490150590984;8497.87253191215;8557.743267701979;8592.011918629438;9840.60637574489;9841;9968.003199679973;9995.70012899614;10146.289759717045;10113.494325283853;10487.108595769445;10513.9474302628;10638.042576168182;10677.466126693478;7174.892376614459;7713.575753333621;7766.728164514215;7910.446268761131;8053.033635963722;8645.870311945451;8652.394332396672;8716.564171791259;8719.869201961848;8721.694740684046;7658.617069146632;7785.260400261966;7846;7883.763487095396;7910.604469776603;7461.253874612712;7535.434842386952;7582.165961744094;7649.732259370895;7683.9231607683205;8151.551664658264;8220;8232.670693172206;8249.711260105867;8260.545669988209;7290.5625662460425;7443;7452.031235939293;7457.2542745724995;7464.477486575887;7502.587357695379;7543.811404714926;7566.962165189138;7568.167501574712;7571.772846814604 13:36:49 INFO - PROCESS | 2232 | |4;prototype.html;339.76046886944704;347.9930401391972;351.54299410766;353.48038383576136;354.9041758725146;6725.092112564802;6784.559003664758;6795.626240556768;6813.386795188432;6828.283030281824;6742.797716068526;6777.593344399327;6880.2431732509385;6896.413804826596;6909.06727591775;4856.0773453043985;4941.950580494203;4998.175301075336;5076.46697096805;5089.872753181172;5041.72270525121;5053.166227572447;5056.823011194609;5067.39191297043;5067.74661266937;4422.226110430662;4628.953710462886;4990.3762029746285;5002.349694539723;5003.349564556603;4285.721428107163;4786.401699787527;4863.978564501441;4987.977464619744;4994.725290109042;4289.656827453796;4301.032267739764;4317.244482215599;4317.8773518885;4325.480942286946;6451.161349024644;6467.288598254188;6500.219973603198;6518.445932095778;6596.934030659728;5911.871726178572;5916.645001299936;5919.763209471616;5920.496757775594;5930.288365396137;6888.586684798928;7174.677139528734;7179.461540384492;7179.784606461762;7196.856062878765;4677.158111539921;4708.387909571734;4712.0811441768865;4721.961168542915;4726.503717109723;7512.887306690404;7613.657385417614;7614.885776713354;7615.390768738488;7632.236776322379;4769.713817170981;4770.069836381872;4774.092922344744;4789.712617242976;4799.448063472675;4845.5881250093435;4850.6362022848425;4855.004724031565;4867.148249056401;4879.804807807648;5589.720513974285;5830.38780928005;5831.125331200269;5846.853828654318;5862.736176872051;7184.209736928934;7188.209296977328;7199.424046076301;7200.207977122513;7248.782536523912;1447.232966527721;1552.7826104345402;1690.3069741405964;1693.5004173768768;1693.7713408689915;1008.2639673038576;1041.8280983637615;1065.1159537583787;1068.262898599979;1098.2916019167592;6775.62734049632;6938.687759050855;7002.75490357835;7022.5435346703325;7026.92973070273;6406.0390941359165;7372.857207132903;7492.70029198826;7553.509021913558;7556.58438785872;6038.154658347835;6699.39705426514;6990.720371185095;6994.93005069953;7010.474214433938;3852.768833869921;3950.6444420001762;3999.140184860269;3999.2201520703766;3999.980000100039;7873.488223265469;7881.172476889844;7891.881621775678;7914;7934.20657934202;6296.716647750863;7749.225077492206;7779.611019449005;7787.883181752279;7798.376129909594;7861.410394220571;7865.960670196612;7866.528008319405;7871.173526779606;7875.212478752079;7252.963735181291;7504.737334193277;7520.360769334668;7523.0972283326355;7544.170141284345;6496.480281577557;6933.4106600939485;7318;7365.889511657437;7385.483016188815;7140.6072666003865;7848.11645205158;7849.843003139791;7862;7871.409644276588;4438.556144385536;4921.483244259444;4935.111679897653;4935.827246046371;4937.802487900443;7139.964300178466;7351.228121047213;7376.262373762581;7405.407567394488;7413.369863561657;5774.797882074107;7071.681774320271;7100.502964792415;7114.359707626131;7120.928790712233;7076.858462830817;7094.184168820445;7099.574025558483;7100.715971361295;7105.9289407107335;6957.8956315654295;6965.930340696528;6965.965170174117;6969.2682268361095;6983.615901125487;6812.73964316603;7122.323379278961;7134.179569349383;7161.856762864818;7173.6054516999975;6796.558223715443;6969.58182509051;6976.895346569704;6981;6990.370866622028 13:36:49 INFO - PROCESS | 2232 | |5;yui.html;605.4308949587388;688.5765254368562;740.5334639177318;767.1714548287849;796.4345314826473;15387.615309617251;15695.921520392381;15761.763573546392;15768.211589420513;15773.369065237392;13566.728665426686;14576.635584110403;14654.853451465482;14666.486672966448;14672.486462973799;987.9209663226925;1024.8565200871883;1025.0979138358234;1025.2823023883275;1027.3784360461925;1010.2120346130031;1019.5819713917296;1026.1893104447477;1027.748201690587;1028.198005555664;856.5117882806802;867.2628265973934;1061.0821639282003;1067.8291473364263;1068.1988508618535;856.4818284937617;1046.6493724602267;1067.7010437077631;1073.613499140309;1077.7144056824948;797.6609940775147;797.9521228726295;799.0491315334791;799.3046049936593;800.087899794235;15434.07395556259;15495.83781216402;15509.99185052968;15511.922440387838;15521.30154143055;12902.35488225585;12956.935215323863;13096.738065238642;13179;13613.455461781517;10366.207234934696;11212.495437705242;11258.66224013281;11441.713957151054;11686;3550.715942724576;3589.300086483137;3596.856125754967;3639.090227443139;3834.789086600236;80140.79788803172;80918.19081809165;81177.18822811755;81759.5912020442;81850.59074704647;9380.484073375894;9654.469004204695;9672;9817.852732209023;9867.259955503367;22300.77699222987;22324.888375558017;22338.88305584747;22352.77647223507;22372.664410033864;7574.280443357872;7628.961855190689;7843.37253019757;7916.485428447132;8063.5968201589685;4173.812178451977;4253.532111467736;4268.40242366069;4363.716358436692;4580.88547786308;3739.8878033659034;3784.4890939723336;3785.3943369061367;3794.6015668354976;3802.4866643002842;661.9238787539495;664.139938779282;675.2605896543278;684.1824020295799;700.6671830880291;621.2638023941644;621.7077973352517;635.7647670361996;636.1634450697411;638.814743724324;7931.325837303779;8560.143985601515;8576.571171441528;8681.56592170389;8764.430312029825;519.8518422249641;565.3526711914834;601.8164459839703;604.4650484321407;639.9200099987502;3298.290867463507;3481.912952176165;3935.74417662851;3940.5468371137113;4001.659858912025;95255.52372238094;95387.32288739721;96533;96885;97909.51045244871;82254.58872705598;82649;82924.58537707273;83452.33095338059;83722.74415883767;68497.31502685009;68589;69389.30610693828;76059.23940760632;76180.61909690527;8533.701320453754;8732.34507411934;8787;8904.109589041043;8939.508327041814;50807.491925081755;51665.48334516607;52664.47335526596;52825;53006.73496632492;66725.99911001242;67162;67283.66358168177;67735;67984.6600766993;11013.063889569476;11170.106391488824;11256.380899050631;11302.773944521228;11483.655490335303;11629.476673549541;12404.81392779127;12526.561570344995;12623.747525049632;12665.873341266837;11831.40842957866;11872;11898.405079746151;12191.756164876475;12636.178648387826;9850.802983940424;11995.460204290654;12002.099842512021;12024.519019239133;12555.87444125547;9002;11652;11791.823122652995;11884;12013.339266340436;7974.960125199337;9925.950370248103;10122.190224781853;10146.746331341776;10523.263371563917;10008.09927106564;10062.849057264;10074.44590547527;10080.84878726805;10096.596136154472 13:36:49 INFO - PROCESS | 2232 | __end_tp_report 13:36:49 INFO - PROCESS | 2232 | __start_cc_report 13:36:49 INFO - PROCESS | 2232 | _x_x_mozilla_cycle_collect,1198 13:36:49 INFO - PROCESS | 2232 | __end_cc_report 13:36:49 INFO - PROCESS | 2232 | __startTimestamp1461703009677__endTimestamp 13:36:49 INFO - PROCESS | 2232 | 13:36:49 INFO - PROCESS | 2232 | ------- Summary: start ------- 13:36:49 INFO - PROCESS | 2232 | Number of tests: 6 13:36:49 INFO - PROCESS | 2232 | 13:36:49 INFO - PROCESS | 2232 | [#0] dojo.html Cycles:165 Average:5834.38 Median:6135.75 stddev:683.11 (11.1%) stddev-sans-first:645.99 13:36:49 INFO - PROCESS | 2232 | Values: 2926.5 3574.1 3589.4 3604.0 3654.2 5805.4 5830.3 5844.2 5859.0 5899.3 5824.9 5867.5 5874.2 5891.3 5903.1 5254.0 5284.8 5325.3 5338.3 5391.4 5351.1 5356.9 5361.8 5362.0 5362.6 5283.8 5359.9 5365.8 5368.8 5377.1 4493.8 5359.8 5369.9 5392.7 5403.6 5129.4 5333.3 5338.3 5341.0 5354.5 4781.2 5699.9 5742.7 5752.3 5767.4 6176.8 6279.6 6281.3 6285.5 6300.9 6257.4 6264.0 6305.1 6314.6 6350.3 5994.9 6004.5 6011.7 6015.8 6018.2 6280.6 6319.1 6410.5 6416.7 6427.7 6250.5 6258.2 6270.5 6271.7 6289.2 6319.4 6336.1 6340.3 6354.5 6357.6 6134.2 6185.7 6185.8 6267.6 6278.8 6217.7 6256.1 6264.4 6282.7 6285.2 4959.2 4970.1 4971.3 4972.5 5008.5 3729.7 3860.9 4400.6 4540.9 4564.2 6008.1 6063.8 6065.8 6085.1 6095.4 5249.6 6279.2 6284.3 6287.8 6303.9 5873.4 6006.9 6049.6 6080.2 6135.8 4670.6 5452.5 5475.7 5482.5 5484.6 6452.2 6474.4 6500.7 6505.4 6523.8 6170.3 6201.9 6243.4 6257.4 6302.4 6471.4 6487.0 6499.3 6519.1 6522.6 6195.6 6278.9 6291.0 6302.4 6306.7 6225.8 6254.9 6256.4 6280.8 6286.9 6238.7 6270.1 6275.7 6282.9 6287.6 6022.9 6130.3 6239.8 6266.4 6275.1 6139.3 6150.1 6150.7 6164.5 6175.6 6253.3 6270.2 6283.8 6289.7 6290.7 5169.8 6024.9 6026.4 6132.1 6167.0 13:36:49 INFO - PROCESS | 2232 | 13:36:49 INFO - PROCESS | 2232 | [#1] ext.html Cycles:180 Average:65523.87 Median:361896.09 stddev:137674.11 (38.0%) stddev-sans-first:137973.67 13:36:49 INFO - PROCESS | 2232 | Values: 467.5 541.8 544.4 623.7 665.5 26524.9 32615.8 40448.0 40648.6 40743.4 44013.6 44280.9 44407.7 44417.8 44474.6 9898.4 10121.4 10143.3 10148.2 10182.5 9210.6 9841.2 9913.0 9919.4 9942.8 9954.1 10044.5 10125.7 10172.7 10176.8 10083.3 10112.0 10121.3 10133.7 10147.2 9873.4 10025.9 10031.4 10036.4 10040.8 43059.4 43445.7 43450.0 43458.0 43463.8 3224.1 3456.1 3484.7 3699.8 3942.2 29046.3 29920.9 29945.3 29990.4 30390.0 2805.1 2827.2 2848.4 2868.7 2904.2 446964.0 457419.0 459642.0 459890.0 460832.0 66688.0 67010.7 67083.3 67119.7 67205.3 76945.0 77869.8 77870.6 77965.6 77967.6 18339.4 18494.7 18503.8 18573.5 18577.6 14111.7 14116.1 14122.2 14144.8 14367.6 18840.6 22270.7 22627.2 22637.8 22658.8 7738.8 8914.0 9154.4 9163.8 9206.3 813.6 946.0 1026.0 1085.9 1118.4 30780.5 30910.7 31095.4 31444.2 31494.4 1034.8 1099.1 1200.3 1200.4 1249.7 14809.9 14906.8 14979.4 15090.9 15102.0 671054.0 672717.0 676120.0 679244.0 680113.0 59968.8 60822.4 60846.5 60892.4 60946.4 1374.3 1421.9 1554.0 1568.1 1582.8 29716.1 29766.0 29888.6 30517.4 30538.8 50222.5 50953.0 51008.5 51059.2 51069.2 361566.0 362226.2 362256.0 363402.0 363502.0 33233.5 34538.8 34594.3 34679.3 34719.7 43971.0 45022.8 46664.0 46701.3 49695.0 37247.9 37504.0 37611.0 37927.2 38623.2 36724.8 37100.0 37163.4 37769.8 37807.1 38329.6 38486.2 40172.0 40286.6 41436.3 30871.1 32597.8 32687.7 32762.2 32811.2 24835.3 25008.2 25274.4 25471.9 25578.5 13:36:49 INFO - PROCESS | 2232 | 13:36:49 INFO - PROCESS | 2232 | [#2] jquery.html Cycles:180 Average:36162.57 Median:12042.35 stddev:111869.23 (929.0%) stddev-sans-first:112150.79 13:36:49 INFO - PROCESS | 2232 | Values: 386.4 388.1 392.5 398.7 399.4 20386.4 21669.9 21767.5 22210.6 22217.3 20482.7 20532.3 21189.4 21791.7 21868.7 5008.7 5567.6 5985.4 6149.4 6183.5 6138.2 6173.7 6189.7 6191.3 6198.0 6011.1 6093.7 6141.0 6158.5 6159.0 5307.9 5940.9 6068.6 6155.7 6156.1 5316.3 5342.3 5347.1 5521.6 5797.7 14109.9 14308.6 15842.4 25545.4 25990.9 2066.8 2216.0 2240.4 2277.9 2385.6 8483.0 10277.6 10535.7 10742.2 14252.6 1786.4 1805.7 1826.1 1840.3 1862.5 95715.0 100015.5 101792.5 104202.5 106192.5 9844.2 11267.0 11500.7 11567.5 11648.5 14683.4 14688.2 14693.6 14740.4 14755.8 8877.1 9006.8 9035.4 9079.1 9105.8 9183.0 9186.1 9198.9 9241.2 9257.7 4469.9 4570.6 4579.7 4610.7 4617.7 1576.5 1903.9 1919.9 1942.7 1948.6 948.6 960.0 1019.9 1041.3 1054.3 19865.7 20680.7 20790.6 20916.4 20973.4 994.6 1001.2 1030.6 1039.5 1076.8 10769.5 11116.9 11159.1 11175.4 11185.6 672729.0 674391.0 675779.0 679217.2 688993.0 85091.0 114343.4 116912.0 117059.4 121521.0 1078.3 1084.2 1122.0 1144.6 1147.4 15050.1 18096.4 18177.1 18322.2 18806.6 41460.8 51645.5 52601.7 53748.5 54419.5 72449.0 75113.6 77202.5 78490.6 78690.0 7790.3 8240.0 8313.1 8416.2 8431.7 9868.7 13084.8 13200.8 13245.7 13665.3 11451.8 11537.5 11550.2 11782.5 11800.6 10815.5 11427.0 11460.5 11500.8 11769.8 9300.9 9911.9 10332.6 11701.4 11787.1 8203.8 8206.8 8237.5 8322.4 8624.6 8919.4 11257.7 11904.0 12032.0 12081.2 13:36:49 INFO - PROCESS | 2232 | 13:36:49 INFO - PROCESS | 2232 | [#3] mootools.html Cycles:180 Average:6359.82 Median:5247.49 stddev:3330.17 (63.5%) stddev-sans-first:3319.69 13:36:49 INFO - PROCESS | 2232 | Values: 1526.3 1681.1 1700.4 2065.5 2104.5 8363.9 9023.1 9199.3 9200.4 9201.8 8835.9 8946.9 9079.5 9098.2 9107.3 2595.1 2625.8 2625.9 2632.9 2637.3 2559.2 2645.6 2647.6 2648.8 2650.9 2332.9 2484.4 2638.3 2649.8 2657.5 2594.5 2616.1 2651.8 2652.1 2653.5 2593.6 2615.2 2621.6 2644.0 2648.9 7660.4 7694.0 9146.1 9206.4 9227.2 5717.9 5803.4 5824.4 5882.3 5900.1 3693.3 3746.6 3887.2 3895.1 3895.1 1084.6 1088.9 1092.7 1130.4 1135.1 12257.1 12339.7 12364.2 12366.0 12372.9 5087.1 5132.9 5155.5 5195.7 5231.2 10606.3 10648.1 10656.2 10661.6 10675.4 5868.1 5952.9 5953.0 5959.8 5965.4 4726.0 4774.9 5052.6 5245.6 5249.4 4133.2 4188.4 4225.5 4244.3 4259.4 1384.7 1402.7 1403.1 1409.0 1414.9 1429.2 1454.7 1465.2 1486.8 1489.7 8579.1 8630.9 8644.4 8679.5 8693.6 480.6 538.7 546.6 550.2 551.6 6411.4 6420.2 6474.6 6477.4 6501.4 10822.6 11198.8 11326.7 11327.5 11337.9 10533.8 10897.7 11034.0 11100.7 11112.7 8656.1 8762.5 8798.8 8802.0 8954.6 8449.6 8497.5 8497.9 8557.7 8592.0 9840.6 9841.0 9968.0 9995.7 10146.3 10113.5 10487.1 10513.9 10638.0 10677.5 7174.9 7713.6 7766.7 7910.4 8053.0 8645.9 8652.4 8716.6 8719.9 8721.7 7658.6 7785.3 7846.0 7883.8 7910.6 7461.3 7535.4 7582.2 7649.7 7683.9 8151.6 8220.0 8232.7 8249.7 8260.5 7290.6 7443.0 7452.0 7457.3 7464.5 7502.6 7543.8 7567.0 7568.2 7571.8 13:36:49 INFO - PROCESS | 2232 | 13:36:49 INFO - PROCESS | 2232 | [#4] prototype.html Cycles:180 Average:5907.34 Median:6790.09 stddev:1880.80 (27.7%) stddev-sans-first:1839.06 13:36:49 INFO - PROCESS | 2232 | Values: 339.8 348.0 351.5 353.5 354.9 6725.1 6784.6 6795.6 6813.4 6828.3 6742.8 6777.6 6880.2 6896.4 6909.1 4856.1 4942.0 4998.2 5076.5 5089.9 5041.7 5053.2 5056.8 5067.4 5067.7 4422.2 4629.0 4990.4 5002.3 5003.3 4285.7 4786.4 4864.0 4988.0 4994.7 4289.7 4301.0 4317.2 4317.9 4325.5 6451.2 6467.3 6500.2 6518.4 6596.9 5911.9 5916.6 5919.8 5920.5 5930.3 6888.6 7174.7 7179.5 7179.8 7196.9 4677.2 4708.4 4712.1 4722.0 4726.5 7512.9 7613.7 7614.9 7615.4 7632.2 4769.7 4770.1 4774.1 4789.7 4799.4 4845.6 4850.6 4855.0 4867.1 4879.8 5589.7 5830.4 5831.1 5846.9 5862.7 7184.2 7188.2 7199.4 7200.2 7248.8 1447.2 1552.8 1690.3 1693.5 1693.8 1008.3 1041.8 1065.1 1068.3 1098.3 6775.6 6938.7 7002.8 7022.5 7026.9 6406.0 7372.9 7492.7 7553.5 7556.6 6038.2 6699.4 6990.7 6994.9 7010.5 3852.8 3950.6 3999.1 3999.2 4000.0 7873.5 7881.2 7891.9 7914.0 7934.2 6296.7 7749.2 7779.6 7787.9 7798.4 7861.4 7866.0 7866.5 7871.2 7875.2 7253.0 7504.7 7520.4 7523.1 7544.2 6496.5 6933.4 7318.0 7365.9 7385.5 7140.6 7848.1 7849.8 7862.0 7871.4 4438.6 4921.5 4935.1 4935.8 4937.8 7140.0 7351.2 7376.3 7405.4 7413.4 5774.8 7071.7 7100.5 7114.4 7120.9 7076.9 7094.2 7099.6 7100.7 7105.9 6957.9 6965.9 6966.0 6969.3 6983.6 6812.7 7122.3 7134.2 7161.9 7173.6 6796.6 6969.6 6976.9 6981.0 6990.4 13:36:49 INFO - PROCESS | 2232 | 13:36:49 INFO - PROCESS | 2232 | [#5] yui.html Cycles:180 Average:18898.03 Median:3971.10 stddev:26473.40 (666.7%) stddev-sans-first:26512.03 13:36:49 INFO - PROCESS | 2232 | Values: 605.4 688.6 740.5 767.2 796.4 15387.6 15695.9 15761.8 15768.2 15773.4 13566.7 14576.6 14654.9 14666.5 14672.5 987.9 1024.9 1025.1 1025.3 1027.4 1010.2 1019.6 1026.2 1027.7 1028.2 856.5 867.3 1061.1 1067.8 1068.2 856.5 1046.6 1067.7 1073.6 1077.7 797.7 798.0 799.0 799.3 800.1 15434.1 15495.8 15510.0 15511.9 15521.3 12902.4 12956.9 13096.7 13179.0 13613.5 10366.2 11212.5 11258.7 11441.7 11686.0 3550.7 3589.3 3596.9 3639.1 3834.8 80140.8 80918.2 81177.2 81759.6 81850.6 9380.5 9654.5 9672.0 9817.9 9867.3 22300.8 22324.9 22338.9 22352.8 22372.7 7574.3 7629.0 7843.4 7916.5 8063.6 4173.8 4253.5 4268.4 4363.7 4580.9 3739.9 3784.5 3785.4 3794.6 3802.5 661.9 664.1 675.3 684.2 700.7 621.3 621.7 635.8 636.2 638.8 7931.3 8560.1 8576.6 8681.6 8764.4 519.9 565.4 601.8 604.5 639.9 3298.3 3481.9 3935.7 3940.5 4001.7 95255.5 95387.3 96533.0 96885.0 97909.5 82254.6 82649.0 82924.6 83452.3 83722.7 68497.3 68589.0 69389.3 76059.2 76180.6 8533.7 8732.3 8787.0 8904.1 8939.5 50807.5 51665.5 52664.5 52825.0 53006.7 66726.0 67162.0 67283.7 67735.0 67984.7 11013.1 11170.1 11256.4 11302.8 11483.7 11629.5 12404.8 12526.6 12623.7 12665.9 11831.4 11872.0 11898.4 12191.8 12636.2 9850.8 11995.5 12002.1 12024.5 12555.9 9002.0 11652.0 11791.8 11884.0 12013.3 7975.0 9926.0 10122.2 10146.7 10523.3 10008.1 10062.8 10074.4 10080.8 10096.6 13:36:49 INFO - PROCESS | 2232 | -------- Summary: end -------- 13:36:49 INFO - PROCESS | 2232 | 13:36:50 INFO - TEST-INFO | 2232: exit 0 13:36:50 INFO - TEST-OK | dromaeo_css | took 1110835ms 13:36:50 INFO - TEST-START | kraken 13:36:50 INFO - operating with platform_type : mac_ 13:36:50 INFO - Initialising browser for kraken test... 13:36:50 INFO - TEST-INFO | started process 2237 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/tmpJFZ_t7/profile http://localhost:56544/getInfo.html) 13:36:52 INFO - PROCESS | 2237 | __metrics Screen width/height:1600/1200 13:36:52 INFO - PROCESS | 2237 | colorDepth:24 13:36:52 INFO - PROCESS | 2237 | Browser inner width/height: 1024/689 13:36:52 INFO - PROCESS | 2237 | __metrics 13:36:52 INFO - PROCESS | 2237 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 13:36:52 INFO - PROCESS | 2237 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:36:52 INFO - TEST-INFO | 2237: exit 0 13:36:52 INFO - Browser initialized. 13:36:52 INFO - Running cycle 1/1 for kraken test... 13:36:52 INFO - TEST-INFO | started process 2240 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/tmpJFZ_t7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:37:04 INFO - PROCESS | 2240 | RSS: Main: 157192192 13:37:04 INFO - PROCESS | 2240 | 13:37:07 INFO - PROCESS | 2240 | Cycle 1(1): loaded http://localhost:56544/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56544/tests/kraken/driver.html?testName=audio-beat-detection) 13:37:07 INFO - PROCESS | 2240 | RSS: Main: 212455424 13:37:07 INFO - PROCESS | 2240 | 13:37:10 INFO - PROCESS | 2240 | Cycle 1(1): loaded http://localhost:56544/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56544/tests/kraken/driver.html?testName=audio-dft) 13:37:11 INFO - PROCESS | 2240 | RSS: Main: 223232000 13:37:11 INFO - PROCESS | 2240 | 13:37:14 INFO - PROCESS | 2240 | Cycle 1(1): loaded http://localhost:56544/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56544/tests/kraken/driver.html?testName=audio-fft) 13:37:15 INFO - PROCESS | 2240 | RSS: Main: 213680128 13:37:15 INFO - PROCESS | 2240 | 13:37:16 INFO - PROCESS | 2240 | Cycle 1(1): loaded http://localhost:56544/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56544/tests/kraken/driver.html?testName=audio-oscillator) 13:37:17 INFO - PROCESS | 2240 | RSS: Main: 215527424 13:37:17 INFO - PROCESS | 2240 | 13:37:19 INFO - PROCESS | 2240 | Cycle 1(1): loaded http://localhost:56544/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56544/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:37:20 INFO - PROCESS | 2240 | RSS: Main: 215781376 13:37:20 INFO - PROCESS | 2240 | 13:37:22 INFO - PROCESS | 2240 | Cycle 1(1): loaded http://localhost:56544/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56544/tests/kraken/driver.html?testName=imaging-darkroom) 13:37:23 INFO - PROCESS | 2240 | RSS: Main: 216535040 13:37:23 INFO - PROCESS | 2240 | 13:37:26 INFO - PROCESS | 2240 | Cycle 1(1): loaded http://localhost:56544/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56544/tests/kraken/driver.html?testName=imaging-desaturate) 13:37:26 INFO - PROCESS | 2240 | RSS: Main: 215666688 13:37:26 INFO - PROCESS | 2240 | 13:37:29 INFO - PROCESS | 2240 | Cycle 1(1): loaded http://localhost:56544/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56544/tests/kraken/driver.html?testName=json-parse-financial) 13:37:30 INFO - PROCESS | 2240 | RSS: Main: 215900160 13:37:30 INFO - PROCESS | 2240 | 13:37:31 INFO - PROCESS | 2240 | Cycle 1(1): loaded http://localhost:56544/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56544/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:37:32 INFO - PROCESS | 2240 | RSS: Main: 248844288 13:37:32 INFO - PROCESS | 2240 | 13:37:33 INFO - PROCESS | 2240 | Cycle 1(1): loaded http://localhost:56544/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56544/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:37:34 INFO - PROCESS | 2240 | RSS: Main: 248664064 13:37:34 INFO - PROCESS | 2240 | 13:37:36 INFO - PROCESS | 2240 | Cycle 1(1): loaded http://localhost:56544/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56544/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:37:37 INFO - PROCESS | 2240 | RSS: Main: 253837312 13:37:37 INFO - PROCESS | 2240 | 13:37:39 INFO - PROCESS | 2240 | Cycle 1(1): loaded http://localhost:56544/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56544/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:37:40 INFO - PROCESS | 2240 | RSS: Main: 255127552 13:37:40 INFO - PROCESS | 2240 | 13:37:42 INFO - PROCESS | 2240 | Cycle 1(1): loaded http://localhost:56544/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56544/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:37:43 INFO - PROCESS | 2240 | RSS: Main: 255221760 13:37:43 INFO - PROCESS | 2240 | 13:37:45 INFO - PROCESS | 2240 | Cycle 1(1): loaded http://localhost:56544/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56544/tests/kraken/driver.html?testName=ai-astar) 13:37:45 INFO - PROCESS | 2240 | RSS: Main: 318599168 13:37:45 INFO - PROCESS | 2240 | 13:37:45 INFO - PROCESS | 2240 | __start_tp_report 13:37:45 INFO - PROCESS | 2240 | _x_x_mozilla_page_load 13:37:45 INFO - PROCESS | 2240 | _x_x_mozilla_page_load_details 13:37:45 INFO - PROCESS | 2240 | |i|pagename|runs| 13:37:45 INFO - PROCESS | 2240 | |0;ai-astar;112;109;111;109;113;113;120;111;112;111 13:37:45 INFO - PROCESS | 2240 | |1;audio-beat-detection;155;121;158;166;150;128;138;185;179;118 13:37:45 INFO - PROCESS | 2240 | |2;audio-dft;184;182;186;185;187;186;187;183;186;184 13:37:45 INFO - PROCESS | 2240 | |3;audio-fft;74;75;73;118;55;57;58;57;120;55 13:37:45 INFO - PROCESS | 2240 | |4;audio-oscillator;92;92;129;79;80;82;114;75;78;116 13:37:45 INFO - PROCESS | 2240 | |5;imaging-gaussian-blur;87;84;88;84;89;84;88;84;88;84 13:37:45 INFO - PROCESS | 2240 | |6;imaging-darkroom;92;92;93;92;93;91;93;92;92;93 13:37:45 INFO - PROCESS | 2240 | |7;imaging-desaturate;85;84;84;84;86;87;86;85;86;84 13:37:45 INFO - PROCESS | 2240 | |8;json-parse-financial;53;59;58;59;60;60;58;57;54;54 13:37:45 INFO - PROCESS | 2240 | |9;json-stringify-tinderbox;62;62;70;76;71;67;67;63;66;67 13:37:45 INFO - PROCESS | 2240 | |10;stanford-crypto-aes;80;82;81;80;80;80;80;79;83;84 13:37:45 INFO - PROCESS | 2240 | |11;stanford-crypto-ccm;133;117;125;131;139;117;117;118;128;118 13:37:45 INFO - PROCESS | 2240 | |12;stanford-crypto-pbkdf2;134;123;134;133;136;123;134;134;132;130 13:37:45 INFO - PROCESS | 2240 | |13;stanford-crypto-sha256-iterative;67;65;67;65;67;66;68;66;63;64 13:37:45 INFO - PROCESS | 2240 | __end_tp_report 13:37:45 INFO - PROCESS | 2240 | __start_cc_report 13:37:45 INFO - PROCESS | 2240 | _x_x_mozilla_cycle_collect,1180 13:37:45 INFO - PROCESS | 2240 | __end_cc_report 13:37:45 INFO - PROCESS | 2240 | __startTimestamp1461703065284__endTimestamp 13:37:45 INFO - PROCESS | 2240 | 13:37:45 INFO - PROCESS | 2240 | ------- Summary: start ------- 13:37:45 INFO - PROCESS | 2240 | Number of tests: 14 13:37:45 INFO - PROCESS | 2240 | 13:37:45 INFO - PROCESS | 2240 | [#0] ai-astar Cycles:10 Average:112.10 Median:112.00 stddev:3.11 (2.8%) stddev-sans-first:3.30 13:37:45 INFO - PROCESS | 2240 | Values: 112.0 109.0 111.0 109.0 113.0 113.0 120.0 111.0 112.0 111.0 13:37:45 INFO - PROCESS | 2240 | 13:37:45 INFO - PROCESS | 2240 | [#1] audio-beat-detection Cycles:10 Average:149.80 Median:156.50 stddev:23.34 (14.9%) stddev-sans-first:24.68 13:37:45 INFO - PROCESS | 2240 | Values: 155.0 121.0 158.0 166.0 150.0 128.0 138.0 185.0 179.0 118.0 13:37:45 INFO - PROCESS | 2240 | 13:37:45 INFO - PROCESS | 2240 | [#2] audio-dft Cycles:10 Average:185.00 Median:186.00 stddev:1.70 (0.9%) stddev-sans-first:1.76 13:37:45 INFO - PROCESS | 2240 | Values: 184.0 182.0 186.0 185.0 187.0 186.0 187.0 183.0 186.0 184.0 13:37:45 INFO - PROCESS | 2240 | 13:37:45 INFO - PROCESS | 2240 | [#3] audio-fft Cycles:10 Average:74.20 Median:57.50 stddev:24.97 (43.4%) stddev-sans-first:26.48 13:37:45 INFO - PROCESS | 2240 | Values: 74.0 75.0 73.0 118.0 55.0 57.0 58.0 57.0 120.0 55.0 13:37:45 INFO - PROCESS | 2240 | 13:37:45 INFO - PROCESS | 2240 | [#4] audio-oscillator Cycles:10 Average:93.70 Median:79.50 stddev:19.14 (24.1%) stddev-sans-first:20.29 13:37:45 INFO - PROCESS | 2240 | Values: 92.0 92.0 129.0 79.0 80.0 82.0 114.0 75.0 78.0 116.0 13:37:45 INFO - PROCESS | 2240 | 13:37:45 INFO - PROCESS | 2240 | [#5] imaging-gaussian-blur Cycles:10 Average:86.00 Median:87.50 stddev:2.16 (2.5%) stddev-sans-first:2.26 13:37:45 INFO - PROCESS | 2240 | Values: 87.0 84.0 88.0 84.0 89.0 84.0 88.0 84.0 88.0 84.0 13:37:45 INFO - PROCESS | 2240 | 13:37:45 INFO - PROCESS | 2240 | [#6] imaging-darkroom Cycles:10 Average:92.30 Median:92.50 stddev:0.67 (0.7%) stddev-sans-first:0.71 13:37:45 INFO - PROCESS | 2240 | Values: 92.0 92.0 93.0 92.0 93.0 91.0 93.0 92.0 92.0 93.0 13:37:45 INFO - PROCESS | 2240 | 13:37:45 INFO - PROCESS | 2240 | [#7] imaging-desaturate Cycles:10 Average:85.10 Median:85.50 stddev:1.10 (1.3%) stddev-sans-first:1.17 13:37:45 INFO - PROCESS | 2240 | Values: 85.0 84.0 84.0 84.0 86.0 87.0 86.0 85.0 86.0 84.0 13:37:45 INFO - PROCESS | 2240 | 13:37:45 INFO - PROCESS | 2240 | [#8] json-parse-financial Cycles:10 Average:57.20 Median:58.50 stddev:2.62 (4.5%) stddev-sans-first:2.29 13:37:45 INFO - PROCESS | 2240 | Values: 53.0 59.0 58.0 59.0 60.0 60.0 58.0 57.0 54.0 54.0 13:37:45 INFO - PROCESS | 2240 | 13:37:45 INFO - PROCESS | 2240 | [#9] json-stringify-tinderbox Cycles:10 Average:67.10 Median:67.00 stddev:4.38 (6.5%) stddev-sans-first:4.24 13:37:45 INFO - PROCESS | 2240 | Values: 62.0 62.0 70.0 76.0 71.0 67.0 67.0 63.0 66.0 67.0 13:37:45 INFO - PROCESS | 2240 | 13:37:45 INFO - PROCESS | 2240 | [#10] stanford-crypto-aes Cycles:10 Average:80.90 Median:80.50 stddev:1.60 (2.0%) stddev-sans-first:1.66 13:37:45 INFO - PROCESS | 2240 | Values: 80.0 82.0 81.0 80.0 80.0 80.0 80.0 79.0 83.0 84.0 13:37:45 INFO - PROCESS | 2240 | 13:37:45 INFO - PROCESS | 2240 | [#11] stanford-crypto-ccm Cycles:10 Average:124.30 Median:126.50 stddev:8.10 (6.4%) stddev-sans-first:7.95 13:37:45 INFO - PROCESS | 2240 | Values: 133.0 117.0 125.0 131.0 139.0 117.0 117.0 118.0 128.0 118.0 13:37:45 INFO - PROCESS | 2240 | 13:37:45 INFO - PROCESS | 2240 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:131.30 Median:134.00 stddev:4.64 (3.5%) stddev-sans-first:4.82 13:37:45 INFO - PROCESS | 2240 | Values: 134.0 123.0 134.0 133.0 136.0 123.0 134.0 134.0 132.0 130.0 13:37:45 INFO - PROCESS | 2240 | 13:37:45 INFO - PROCESS | 2240 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.80 Median:66.50 stddev:1.55 (2.3%) stddev-sans-first:1.58 13:37:45 INFO - PROCESS | 2240 | Values: 67.0 65.0 67.0 65.0 67.0 66.0 68.0 66.0 63.0 64.0 13:37:45 INFO - PROCESS | 2240 | -------- Summary: end -------- 13:37:45 INFO - PROCESS | 2240 | 13:37:45 INFO - TEST-INFO | 2240: exit 0 13:37:46 INFO - TEST-OK | kraken | took 55837ms 13:37:46 INFO - SUITE-END | took 1166s 13:37:46 INFO - Completed test suite (00:19:27) 13:37:46 INFO - javascript benchmark 13:37:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2926.4732348177326, 3574.0528759878634, 3589.3718599245126, 3603.9639603603955, 3654.1960768630893, 5805.361410244875, 5830.271216097988, 5844.152597873316, 5858.970705146479, 5899.292084949807, 5824.912626310609, 5867.501262392691, 5874.206982057421, 5891.263592050994, 5903.114532820079, 5253.9737301313635, 5284.8150314739, 5325.281087053247, 5338.3060202173665, 5391.433899440561, 5351.143816989283, 5356.91964620531, 5361.812336568221, 5362.034833729927, 5362.570994320465, 5283.762230699608, 5359.866003349928, 5365.839024829242, 5368.785248590071, 5377.085895397772, 4493.842715504941, 5359.758810853501, 5369.892602147955, 5392.703401312926, 5403.594730395197, 5129.435762066169, 5333.280007199056, 5338.27933229013, 5340.973295133538, 5354.544863686591, 4781.2111001685025, 5699.914501282443, 5742.6841523716175, 5752.28096487939, 5767.36558978512, 6176.7838125665385, 6279.560430769848, 6281.277653069864, 6285.528585356071, 6300.905486417708, 6257.374262573751, 6263.968680156616, 6305.117283580303, 6314.62112273265, 6350.301466838644, 5994.8501287467725, 6004.519638428916, 6011.699415029231, 6015.75936962521, 6018.15745795589, 6280.623162610258, 6319.083732858801, 6410.455111315498, 6416.7433302668305, 6427.678616069178, 6250.531210159256, 6258.186435763321, 6270.498360131179, 6271.749130034838, 6289.213848268966, 6319.368063193644, 6336.112944187817, 6340.3342649022115, 6354.52341074412, 6357.586756860789, 6134.222293197073, 6185.721642526097, 6185.752569897154, 6267.592606480564, 6278.780242691484, 6217.720202590804, 6256.092866534327, 6264.37356264379, 6282.717277722513, 6285.151504546919, 4959.156943319645, 4970.055689418999, 4971.254311853251, 4972.5276098770555, 5008.499150084962, 3729.738918275749, 3860.864869729546, 4400.603945644843, 4540.931886021713, 4564.1556312082375, 6008.098785182257, 6063.787767428119, 6065.75736970525, 6085.08723691441, 6095.359987201368, 5249.632525723163, 6279.152314437584, 6284.308726040132, 6287.779927702509, 6303.905441418382, 5873.383294754075, 6006.909896351558, 6049.606775559574, 6080.2399700037495, 6135.754569817246, 4670.579647831712, 5452.468461309244, 5475.67145971243, 5482.506574408243, 5484.561235101182, 6452.193475815523, 6474.352564743582, 6500.674966251763, 6505.447037001719, 6523.771667991692, 6170.290416601969, 6201.914664933528, 6243.43809057187, 6257.43683068526, 6302.369763023661, 6471.449926756279, 6486.967565162333, 6499.28507864144, 6519.054737063005, 6522.641254731036, 6195.566310358232, 6278.905816412666, 6291.0, 6302.369763023661, 6306.684665766602, 6225.8132256032395, 6254.936660767771, 6256.405641464054, 6280.842978925563, 6286.937130628635, 6238.6880655967925, 6270.090836828543, 6275.6548389839, 6282.874342513215, 6287.559870808999, 6022.879542409215, 6130.264368275835, 6239.812805615838, 6266.436020758154, 6275.090111933653, 6139.3246742857355, 6150.077488376779, 6150.661713605795, 6164.47601953839, 6175.59858609189, 6253.280872699698, 6270.2475702915945, 6283.8114856554375, 6289.685515724287, 6290.654014029273, 5169.81905633301, 6024.8795024100145, 6026.367231440636, 6132.110843927517, 6166.969165154145], "unit": "score", "name": "dojo.html", "value": 5789.932958055286}, {"lowerIsBetter": false, "replicates": [467.5277969251055, 541.7863984674331, 544.4038777538595, 623.6778030575181, 665.4510029225888, 26524.867375663118, 32615.836920815393, 40447.98880027997, 40648.59351406485, 40743.388849167284, 44013.559864401344, 44280.892977675576, 44407.66776996687, 44417.7779111104, 44474.55525444745, 9898.40609563425, 10121.392716437, 10143.34068285559, 10148.238882083873, 10182.490875456235, 9210.585523651453, 9841.21270298378, 9912.959139290379, 9919.404835709844, 9942.751431214205, 9954.104130628242, 10044.54799534019, 10125.696229113139, 10172.694819155437, 10176.796464070714, 10083.294169408144, 10112.0, 10121.34211276272, 10133.695989120339, 10147.238957078249, 9873.358231714916, 10025.899741002642, 10031.448270345129, 10036.397816130982, 10040.79918401628, 43059.35410968838, 43445.69662910118, 43450.0, 43458.0, 43463.78268108671, 3224.1294850390263, 3456.1186897341017, 3484.6689564491335, 3699.7965111918834, 3942.231264903345, 29046.27384315409, 29920.850395747882, 29945.251368715737, 29990.400191996257, 30390.0, 2805.130409573039, 2827.1801177658454, 2848.4445533121047, 2868.6557613086356, 2904.2158617173454, 446963.99999999674, 457419.0, 459642.0, 459890.0, 460832.0, 66687.99968000484, 67010.66494667495, 67083.32916670771, 67119.65760684856, 67205.32794672088, 76945.0, 77869.83195252076, 77870.6106469464, 77965.61017194991, 77967.61016194883, 18339.449816505527, 18494.722579161324, 18503.81496185021, 18573.535661608297, 18577.628447430976, 14111.71776564463, 14116.082454640415, 14122.152670839783, 14144.787828182585, 14367.640808979859, 18840.623187536446, 22270.66594001059, 22627.20804771825, 22637.773622263896, 22658.773412265997, 7738.806529836799, 8914.0, 9154.359194856295, 9163.77090572741, 9206.309526785384, 813.6460639621783, 945.9716208513755, 1025.9538320775582, 1085.8914108589079, 1118.3681220110611, 30780.53829192564, 30910.690893091232, 31095.378092438026, 31444.213894652818, 31494.37011259762, 1034.8085604163255, 1099.1097211258907, 1200.33981310279, 1200.3698058519346, 1249.6628607390005, 14809.92595037018, 14906.776398354034, 14979.400823967137, 15090.924545377204, 15102.0, 671054.0, 672717.0, 676120.0, 679244.0, 680113.0, 59968.80062398728, 60822.39177608256, 60846.478838029405, 60892.39107608956, 60946.39053609496, 1374.3334482775845, 1421.9004669673232, 1553.9844601554066, 1568.1453607783785, 1582.841715828408, 29716.10851674453, 29766.0, 29888.551671725378, 30517.389652207276, 30538.847305763327, 50222.49777502178, 50953.0, 51008.48991510038, 51059.23411148761, 51069.23396148987, 361566.0, 362226.18886905396, 362256.0, 363402.0, 363502.0, 33233.501497477075, 34538.82730586432, 34594.308113837076, 34679.30641387209, 34719.65280347164, 43971.0, 45022.77488612536, 46664.0, 46701.29948050714, 49695.0, 37247.88256352314, 37504.0, 37611.0, 37927.24145517129, 38623.227535449696, 36724.81637591795, 37100.0, 37163.442548362334, 37769.81115094407, 37807.05482362963, 38329.6167038326, 38486.2302753949, 40172.0, 40286.597134029456, 41436.27073563394, 30871.073867784, 32597.837010814794, 32687.67312326846, 32762.180945476553, 32811.17972050718, 24835.25494235176, 25008.249752507454, 25274.425750179937, 25471.872640636677, 25578.488430231664], "unit": "score", "name": "ext.html", "value": 20589.867696964706}, {"lowerIsBetter": false, "replicates": [386.4203694458312, 388.1209061475757, 392.51328352842467, 398.7348413305152, 399.38295333709414, 20386.388408347753, 21669.891650541744, 21767.45581360463, 22210.555788884252, 22217.333479995606, 20482.692759608617, 20532.281370152046, 21189.364319070413, 21791.67312490314, 21868.671969920426, 5008.746064271074, 5567.554595632339, 5985.371535988724, 6149.446549810516, 6183.474404675608, 6138.17134686816, 6173.722182501798, 6189.721462534197, 6191.318954914956, 6197.97733373995, 6011.098335249726, 6093.664848433335, 6141.0, 6158.476529494998, 6158.969205153968, 5307.946920530784, 5940.910886336708, 6068.605540639888, 6155.692215389213, 6156.138140660333, 5316.3354580677415, 5342.332208473941, 5347.144456886918, 5521.613487055908, 5797.652140871519, 14109.929450352785, 14308.57074287773, 15842.445514407056, 25545.361365966, 25990.87004564984, 2066.782987786276, 2216.0360243294217, 2240.3950933248093, 2277.9316620501245, 2385.6183010718373, 8483.0, 10277.640282590073, 10535.73660658482, 10742.194335424874, 14252.572422827332, 1786.3747688309118, 1805.7201133824278, 1826.1417133947023, 1840.2730921286018, 1862.4878158506383, 95715.04284957131, 100015.49976750281, 101792.49103754509, 104202.47898760534, 106192.46903765509, 9844.212463002943, 11267.0, 11500.654980350602, 11567.537298507965, 11648.475818588184, 14683.412663493555, 14688.192149431883, 14693.55919322422, 14740.41038358475, 14755.77866332006, 8877.06790786971, 9006.774830629287, 9035.367524273237, 9079.137481939206, 9105.77235569116, 9183.0, 9186.08139186076, 9198.908010919806, 9241.21449676785, 9257.722268331961, 4469.910601787946, 4570.611498022638, 4579.725216486955, 4610.74640894754, 4617.722936623813, 1576.533823544115, 1903.8572107092023, 1919.9232030718615, 1942.660034493958, 1948.6297603455298, 948.6489998700525, 959.9616015359308, 1019.9439030853226, 1041.3439533094254, 1054.3410368519676, 19865.702014469796, 20680.689789653166, 20790.58418831615, 20916.4116126346, 20973.370798876058, 994.6170724271063, 1001.1790331927749, 1030.644427672452, 1039.5270152080936, 1076.8115579773507, 10769.515371808287, 11116.943890330405, 11159.107271418266, 11175.44122793857, 11185.608503702331, 672729.0, 674391.0, 675779.0, 679217.2078279154, 688993.0, 85091.0, 114343.42828285805, 116912.0, 117059.41470292595, 121521.0, 1078.2667785905503, 1084.1814430105221, 1121.9831702524468, 1144.6279959013355, 1147.3804145761362, 15050.096994180385, 18096.366627167987, 18177.09114544294, 18322.17550210217, 18806.623867522845, 41460.792696036326, 51645.48354516407, 52601.7369913148, 53748.46251537435, 54419.45580544302, 72449.0, 75113.62443187748, 77202.45595087955, 78490.6075469619, 78690.0, 7790.259925307087, 8240.0, 8313.085560588452, 8416.15838416178, 8431.747047588582, 9868.654597089067, 13084.803727943898, 13200.801987969997, 13245.668858278621, 13665.316734163052, 11451.770964580495, 11537.538498459968, 11550.249233799776, 11782.52869885195, 11800.645980620595, 10815.513301901276, 11427.0, 11460.541578337106, 11500.82748758786, 11769.764604707687, 9300.860487092563, 9911.900880991097, 10332.586696532055, 11701.356425396345, 11787.11596630239, 8203.835923281382, 8206.753797386087, 8237.46456480327, 8322.417430779788, 8624.568771561522, 8919.375643705138, 11257.71855703614, 11904.047676185712, 12032.014717571852, 12081.214721043105], "unit": "score", "name": "jquery.html", "value": 9609.228330132739}, {"lowerIsBetter": false, "replicates": [1526.3055309834026, 1681.0838093239183, 1700.430355830797, 2065.4733043074016, 2104.5370018595913, 8363.91636083639, 9023.142801433862, 9199.310051746112, 9200.447973121618, 9201.815963680723, 8835.911640883589, 8946.865797013033, 9079.455232686043, 9098.181163695266, 9107.271418286538, 2595.0787470448004, 2625.7899368050607, 2625.8949642014295, 2632.9078482253126, 2637.3011152044724, 2559.1938539360135, 2645.642838216845, 2647.6160956661274, 2648.827826191301, 2650.8807103680283, 2332.880217495603, 2484.3789052736815, 2638.261286839678, 2649.8410095394242, 2657.4950759355756, 2594.5070436616984, 2616.1235985944636, 2651.827631203975, 2652.1115426332203, 2653.5356312645404, 2593.5850263957677, 2615.163147792707, 2621.5674413721713, 2644.048142668638, 2648.9337766555795, 7660.425468089916, 7693.999780028616, 9146.085391460867, 9206.355555111146, 9227.169554740038, 5717.914231286534, 5803.361630220672, 5824.388439213864, 5882.294124705063, 5900.085486749561, 3693.2613477304376, 3746.550413950343, 3887.222555488885, 3895.0651843557625, 3895.104126050996, 1084.5770149641646, 1088.9346639201674, 1092.6776600902754, 1130.440431986164, 1135.0805847263744, 12257.080718946025, 12339.69150771238, 12364.196327238791, 12366.0, 12372.938135309356, 5087.135187018216, 5132.923006154911, 5155.536001759785, 5195.6622819516615, 5231.241469986907, 10606.310589811637, 10648.094911932418, 10656.20078494116, 10661.573537058586, 10675.359478431164, 5868.119782032644, 5952.940470595326, 5953.047512397996, 5959.761609535657, 5965.373635768182, 4725.976370118127, 4774.928376074362, 5052.5705315048635, 5245.606579506552, 5249.370075590953, 4133.23535145999, 4188.371744238389, 4225.514065882401, 4244.299690551086, 4259.446271984684, 1384.732969333049, 1402.730528871373, 1403.073971179017, 1409.0277708381184, 1414.915105093677, 1429.171080773149, 1454.6508837879044, 1465.230753854235, 1486.8364479907202, 1489.6722721001363, 8579.142085791496, 8630.913690863012, 8644.3948923576, 8679.479231246145, 8693.565321734015, 480.57949294367427, 538.733327003136, 546.5572885962383, 550.1967127993174, 551.6276513353471, 6411.422971932455, 6420.1974753155855, 6474.611523308617, 6477.449416799531, 6501.414872661483, 10822.56709731602, 11198.832017519744, 11326.660200194008, 11327.546898123983, 11337.943310283395, 10533.789324213472, 10897.727556811144, 11034.0, 11100.666979990614, 11112.722181945515, 8656.091110433314, 8762.518061506551, 8798.824023519493, 8802.03177650458, 8954.641814327353, 8449.60581504045, 8497.490150590984, 8497.87253191215, 8557.743267701979, 8592.011918629438, 9840.60637574489, 9841.0, 9968.003199679973, 9995.70012899614, 10146.289759717045, 10113.494325283853, 10487.108595769445, 10513.9474302628, 10638.042576168182, 10677.466126693478, 7174.892376614459, 7713.575753333621, 7766.728164514215, 7910.446268761131, 8053.033635963722, 8645.870311945451, 8652.394332396672, 8716.564171791259, 8719.869201961848, 8721.694740684046, 7658.617069146632, 7785.260400261966, 7846.0, 7883.763487095396, 7910.604469776603, 7461.253874612712, 7535.434842386952, 7582.165961744094, 7649.732259370895, 7683.9231607683205, 8151.551664658264, 8220.0, 8232.670693172206, 8249.711260105867, 8260.545669988209, 7290.5625662460425, 7443.0, 7452.031235939293, 7457.2542745724995, 7464.477486575887, 7502.587357695379, 7543.811404714926, 7566.962165189138, 7568.167501574712, 7571.772846814604], "unit": "score", "name": "mootools.html", "value": 5122.500499107026}, {"lowerIsBetter": false, "replicates": [339.76046886944704, 347.9930401391972, 351.54299410766, 353.48038383576136, 354.9041758725146, 6725.092112564802, 6784.559003664758, 6795.626240556768, 6813.386795188432, 6828.283030281824, 6742.797716068526, 6777.593344399327, 6880.2431732509385, 6896.413804826596, 6909.06727591775, 4856.0773453043985, 4941.950580494203, 4998.175301075336, 5076.46697096805, 5089.872753181172, 5041.72270525121, 5053.166227572447, 5056.823011194609, 5067.39191297043, 5067.74661266937, 4422.226110430662, 4628.953710462886, 4990.3762029746285, 5002.349694539723, 5003.349564556603, 4285.721428107163, 4786.401699787527, 4863.978564501441, 4987.977464619744, 4994.725290109042, 4289.656827453796, 4301.032267739764, 4317.244482215599, 4317.8773518885, 4325.480942286946, 6451.161349024644, 6467.288598254188, 6500.219973603198, 6518.445932095778, 6596.934030659728, 5911.871726178572, 5916.645001299936, 5919.763209471616, 5920.496757775594, 5930.288365396137, 6888.586684798928, 7174.677139528734, 7179.461540384492, 7179.784606461762, 7196.856062878765, 4677.158111539921, 4708.387909571734, 4712.0811441768865, 4721.961168542915, 4726.503717109723, 7512.887306690404, 7613.657385417614, 7614.885776713354, 7615.390768738488, 7632.236776322379, 4769.713817170981, 4770.069836381872, 4774.092922344744, 4789.712617242976, 4799.448063472675, 4845.5881250093435, 4850.6362022848425, 4855.004724031565, 4867.148249056401, 4879.804807807648, 5589.720513974285, 5830.38780928005, 5831.125331200269, 5846.853828654318, 5862.736176872051, 7184.209736928934, 7188.209296977328, 7199.424046076301, 7200.207977122513, 7248.782536523912, 1447.232966527721, 1552.7826104345402, 1690.3069741405964, 1693.5004173768768, 1693.7713408689915, 1008.2639673038576, 1041.8280983637615, 1065.1159537583787, 1068.262898599979, 1098.2916019167592, 6775.62734049632, 6938.687759050855, 7002.75490357835, 7022.5435346703325, 7026.92973070273, 6406.0390941359165, 7372.857207132903, 7492.70029198826, 7553.509021913558, 7556.58438785872, 6038.154658347835, 6699.39705426514, 6990.720371185095, 6994.93005069953, 7010.474214433938, 3852.768833869921, 3950.6444420001762, 3999.140184860269, 3999.2201520703766, 3999.980000100039, 7873.488223265469, 7881.172476889844, 7891.881621775678, 7914.0, 7934.20657934202, 6296.716647750863, 7749.225077492206, 7779.611019449005, 7787.883181752279, 7798.376129909594, 7861.410394220571, 7865.960670196612, 7866.528008319405, 7871.173526779606, 7875.212478752079, 7252.963735181291, 7504.737334193277, 7520.360769334668, 7523.0972283326355, 7544.170141284345, 6496.480281577557, 6933.4106600939485, 7318.0, 7365.889511657437, 7385.483016188815, 7140.6072666003865, 7848.11645205158, 7849.843003139791, 7862.0, 7871.409644276588, 4438.556144385536, 4921.483244259444, 4935.111679897653, 4935.827246046371, 4937.802487900443, 7139.964300178466, 7351.228121047213, 7376.262373762581, 7405.407567394488, 7413.369863561657, 5774.797882074107, 7071.681774320271, 7100.502964792415, 7114.359707626131, 7120.928790712233, 7076.858462830817, 7094.184168820445, 7099.574025558483, 7100.715971361295, 7105.9289407107335, 6957.8956315654295, 6965.930340696528, 6965.965170174117, 6969.2682268361095, 6983.615901125487, 6812.73964316603, 7122.323379278961, 7134.179569349383, 7161.856762864818, 7173.6054516999975, 6796.558223715443, 6969.58182509051, 6976.895346569704, 6981.0, 6990.370866622028], "unit": "score", "name": "prototype.html", "value": 5275.5576152514295}, {"lowerIsBetter": false, "replicates": [605.4308949587388, 688.5765254368562, 740.5334639177318, 767.1714548287849, 796.4345314826473, 15387.615309617251, 15695.921520392381, 15761.763573546392, 15768.211589420513, 15773.369065237392, 13566.728665426686, 14576.635584110403, 14654.853451465482, 14666.486672966448, 14672.486462973799, 987.9209663226925, 1024.8565200871883, 1025.0979138358234, 1025.2823023883275, 1027.3784360461925, 1010.2120346130031, 1019.5819713917296, 1026.1893104447477, 1027.748201690587, 1028.198005555664, 856.5117882806802, 867.2628265973934, 1061.0821639282003, 1067.8291473364263, 1068.1988508618535, 856.4818284937617, 1046.6493724602267, 1067.7010437077631, 1073.613499140309, 1077.7144056824948, 797.6609940775147, 797.9521228726295, 799.0491315334791, 799.3046049936593, 800.087899794235, 15434.07395556259, 15495.83781216402, 15509.99185052968, 15511.922440387838, 15521.30154143055, 12902.35488225585, 12956.935215323863, 13096.738065238642, 13179.0, 13613.455461781517, 10366.207234934696, 11212.495437705242, 11258.66224013281, 11441.713957151054, 11686.0, 3550.715942724576, 3589.300086483137, 3596.856125754967, 3639.090227443139, 3834.789086600236, 80140.79788803172, 80918.19081809165, 81177.18822811755, 81759.5912020442, 81850.59074704647, 9380.484073375894, 9654.469004204695, 9672.0, 9817.852732209023, 9867.259955503367, 22300.77699222987, 22324.888375558017, 22338.88305584747, 22352.77647223507, 22372.664410033864, 7574.280443357872, 7628.961855190689, 7843.37253019757, 7916.485428447132, 8063.5968201589685, 4173.812178451977, 4253.532111467736, 4268.40242366069, 4363.716358436692, 4580.88547786308, 3739.8878033659034, 3784.4890939723336, 3785.3943369061367, 3794.6015668354976, 3802.4866643002842, 661.9238787539495, 664.139938779282, 675.2605896543278, 684.1824020295799, 700.6671830880291, 621.2638023941644, 621.7077973352517, 635.7647670361996, 636.1634450697411, 638.814743724324, 7931.325837303779, 8560.143985601515, 8576.571171441528, 8681.56592170389, 8764.430312029825, 519.8518422249641, 565.3526711914834, 601.8164459839703, 604.4650484321407, 639.9200099987502, 3298.290867463507, 3481.912952176165, 3935.74417662851, 3940.5468371137113, 4001.659858912025, 95255.52372238094, 95387.32288739721, 96533.0, 96885.0, 97909.51045244871, 82254.58872705598, 82649.0, 82924.58537707273, 83452.33095338059, 83722.74415883767, 68497.31502685009, 68589.0, 69389.30610693828, 76059.23940760632, 76180.61909690527, 8533.701320453754, 8732.34507411934, 8787.0, 8904.109589041043, 8939.508327041814, 50807.491925081755, 51665.48334516607, 52664.47335526596, 52825.0, 53006.73496632492, 66725.99911001242, 67162.0, 67283.66358168177, 67735.0, 67984.6600766993, 11013.063889569476, 11170.106391488824, 11256.380899050631, 11302.773944521228, 11483.655490335303, 11629.476673549541, 12404.81392779127, 12526.561570344995, 12623.747525049632, 12665.873341266837, 11831.40842957866, 11872.0, 11898.405079746151, 12191.756164876475, 12636.178648387826, 9850.802983940424, 11995.460204290654, 12002.099842512021, 12024.519019239133, 12555.87444125547, 9002.0, 11652.0, 11791.823122652995, 11884.0, 12013.339266340436, 7974.960125199337, 9925.950370248103, 10122.190224781853, 10146.746331341776, 10523.263371563917, 10008.09927106564, 10062.849057264, 10074.44590547527, 10080.84878726805, 10096.596136154472], "unit": "score", "name": "yui.html", "value": 7213.829654071509}], "name": "dromaeo_css", "value": 7789.2262937738415}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [112.0, 109.0, 111.0, 109.0, 113.0, 113.0, 120.0, 111.0, 112.0, 111.0], "unit": "score", "name": "ai-astar", "value": 112.1}, {"lowerIsBetter": true, "replicates": [155.0, 121.0, 158.0, 166.0, 150.0, 128.0, 138.0, 185.0, 179.0, 118.0], "unit": "score", "name": "audio-beat-detection", "value": 149.8}, {"lowerIsBetter": true, "replicates": [184.0, 182.0, 186.0, 185.0, 187.0, 186.0, 187.0, 183.0, 186.0, 184.0], "unit": "score", "name": "audio-dft", "value": 185.0}, {"lowerIsBetter": true, "replicates": [74.0, 75.0, 73.0, 118.0, 55.0, 57.0, 58.0, 57.0, 120.0, 55.0], "unit": "score", "name": "audio-fft", "value": 74.2}, {"lowerIsBetter": true, "replicates": [92.0, 92.0, 129.0, 79.0, 80.0, 82.0, 114.0, 75.0, 78.0, 116.0], "unit": "score", "name": "audio-oscillator", "value": 93.7}, {"lowerIsBetter": true, "replicates": [87.0, 84.0, 88.0, 84.0, 89.0, 84.0, 88.0, 84.0, 88.0, 84.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 86.0}, {"lowerIsBetter": true, "replicates": [92.0, 92.0, 93.0, 92.0, 93.0, 91.0, 93.0, 92.0, 92.0, 93.0], "unit": "score", "name": "imaging-darkroom", "value": 92.3}, {"lowerIsBetter": true, "replicates": [85.0, 84.0, 84.0, 84.0, 86.0, 87.0, 86.0, 85.0, 86.0, 84.0], "unit": "score", "name": "imaging-desaturate", "value": 85.1}, {"lowerIsBetter": true, "replicates": [53.0, 59.0, 58.0, 59.0, 60.0, 60.0, 58.0, 57.0, 54.0, 54.0], "unit": "score", "name": "json-parse-financial", "value": 57.2}, {"lowerIsBetter": true, "replicates": [62.0, 62.0, 70.0, 76.0, 71.0, 67.0, 67.0, 63.0, 66.0, 67.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.1}, {"lowerIsBetter": true, "replicates": [80.0, 82.0, 81.0, 80.0, 80.0, 80.0, 80.0, 79.0, 83.0, 84.0], "unit": "score", "name": "stanford-crypto-aes", "value": 80.9}, {"lowerIsBetter": true, "replicates": [133.0, 117.0, 125.0, 131.0, 139.0, 117.0, 117.0, 118.0, 128.0, 118.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 124.3}, {"lowerIsBetter": true, "replicates": [134.0, 123.0, 134.0, 133.0, 136.0, 123.0, 134.0, 134.0, 132.0, 130.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 131.3}, {"lowerIsBetter": true, "replicates": [67.0, 65.0, 67.0, 65.0, 67.0, 66.0, 68.0, 66.0, 63.0, 64.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 65.8}], "name": "kraken", "value": 1404.8}]} 13:37:46 INFO - Return code: 0 13:37:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 13:37:46 INFO - # TBPL SUCCESS # 13:37:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:37:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:37:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:37:46 INFO - 2016-04-26 13:37:46.584 screenresolution[2244:21144] starting screenresolution argv=screenresolution get 13:37:46 INFO - 2016-04-26 13:37:46.587 screenresolution[2244:21144] Display 0: 1600x1200x32@60 13:37:46 INFO - 2016-04-26 13:37:46.593 screenresolution[2245:21148] starting screenresolution argv=screenresolution list 13:37:46 INFO - Available Modes on Display 0 13:37:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:37:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:37:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:37:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:37:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:37:46 INFO - Intel Iris: 13:37:46 INFO - Chipset Model: Intel Iris 13:37:46 INFO - Type: GPU 13:37:46 INFO - Bus: Built-In 13:37:46 INFO - VRAM (Dynamic, Max): 1536 MB 13:37:46 INFO - Vendor: Intel (0x8086) 13:37:46 INFO - Device ID: 0x0a2e 13:37:46 INFO - Revision ID: 0x0009 13:37:46 INFO - Displays: 13:37:46 INFO - Display: 13:37:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:37:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:37:46 INFO - Display Serial Number: 1600x1200 60 13:37:46 INFO - Main Display: Yes 13:37:46 INFO - Mirror: Off 13:37:46 INFO - Online: Yes 13:37:46 INFO - Rotation: Supported 13:37:46 INFO - Return code: 0 13:37:46 INFO - Running post-action listener: _resource_record_post_action 13:37:46 INFO - Running post-run listener: _resource_record_post_run 13:37:47 INFO - Total resource usage - Wall time: 1191s; CPU: 53.0%; Read bytes: 253952; Write bytes: 510424064; Read time: 5; Write time: 1555 13:37:47 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 198328832; Write bytes: 197826048; Read time: 17874; Write time: 389 13:37:47 INFO - run-tests - Wall time: 1169s; CPU: 53.0%; Read bytes: 253952; Write bytes: 301805056; Read time: 5; Write time: 1136 13:37:47 INFO - Running post-run listener: _upload_blobber_files 13:37:47 INFO - Blob upload gear active. 13:37:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:37:47 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:37:47 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-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:37:47 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-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:37:48 INFO - (blobuploader) - INFO - Open directory for files ... 13:37:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:37:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:37:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:37:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:37:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:37:49 INFO - (blobuploader) - INFO - Done attempting. 13:37:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:37:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:37:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:37:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:37:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:37:50 INFO - (blobuploader) - INFO - Done attempting. 13:37:50 INFO - (blobuploader) - INFO - Iteration through files over. 13:37:50 INFO - Return code: 0 13:37:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:37:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:37:50 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/45f4315d352a24c35d85ed859c9722c350bd2cdeabeffb691a2e7362951e15d8ed02e21f2c3fb7adc987d7bf2f2a5c03e8ef32b082568a867bcfc156bbef5e4b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/96c3419ee9bdd75442339aeee05c0c8e45db6ca851835646ead5d06d35b5e35be469c4e30d60016fc103ca8c3abd309a65109c722c7dd3e7dce50472ebc984f8"} 13:37:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:37:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:37:50 INFO - Contents: 13:37:50 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/45f4315d352a24c35d85ed859c9722c350bd2cdeabeffb691a2e7362951e15d8ed02e21f2c3fb7adc987d7bf2f2a5c03e8ef32b082568a867bcfc156bbef5e4b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/96c3419ee9bdd75442339aeee05c0c8e45db6ca851835646ead5d06d35b5e35be469c4e30d60016fc103ca8c3abd309a65109c722c7dd3e7dce50472ebc984f8"} 13:37:50 INFO - Running post-run listener: copy_logs_to_upload_dir 13:37:50 INFO - Copying logs to upload dir... 13:37:50 INFO - mkdir: /builds/slave/test/build/upload/logs 13:37:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1458.981277 ========= master_lag: 0.26 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 24 mins, 19 secs) (at 2016-04-26 13:37:50.498851) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-26 13:37:50.499875) ========= 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.AUndnj64N3/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.Lqz3YLrgY9/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/45f4315d352a24c35d85ed859c9722c350bd2cdeabeffb691a2e7362951e15d8ed02e21f2c3fb7adc987d7bf2f2a5c03e8ef32b082568a867bcfc156bbef5e4b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/96c3419ee9bdd75442339aeee05c0c8e45db6ca851835646ead5d06d35b5e35be469c4e30d60016fc103ca8c3abd309a65109c722c7dd3e7dce50472ebc984f8"} build_url:https://queue.taskcluster.net/v1/task/eJGkIHzISVSyqPt0Ruevrw/artifacts/public/build/firefox-47.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011189 build_url: 'https://queue.taskcluster.net/v1/task/eJGkIHzISVSyqPt0Ruevrw/artifacts/public/build/firefox-47.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/45f4315d352a24c35d85ed859c9722c350bd2cdeabeffb691a2e7362951e15d8ed02e21f2c3fb7adc987d7bf2f2a5c03e8ef32b082568a867bcfc156bbef5e4b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/96c3419ee9bdd75442339aeee05c0c8e45db6ca851835646ead5d06d35b5e35be469c4e30d60016fc103ca8c3abd309a65109c722c7dd3e7dce50472ebc984f8"}' ========= master_lag: 0.42 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-26 13:37:50.929371) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 6 secs) (at 2016-04-26 13:37:50.929697) ========= 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.AUndnj64N3/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.Lqz3YLrgY9/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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.005290 ========= master_lag: 6.13 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 6 secs) (at 2016-04-26 13:37:57.068872) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-26 13:37:57.069163) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-26 13:37:57.127063) ========= ========= Total master_lag: 6.88 =========