builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0330 starttime: 1468279050.04 results: success (0) buildid: 20160711140226 builduid: ad9325d9648e43f7b958257a24d5ec57 revision: 88bebcaca249aeaca9197382e89d35b02be8292e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-11 16:17:30.038060) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-11 16:17:30.038533) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-11 16:17:30.038810) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-11 16:17:30.067677) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 16:17:30.068008) ========= 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.ugw5I97XBl/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.TcqwOv0hSR/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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-07-11 16:17:30-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 152M=0s 2016-07-11 16:17:30 (152 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.093197 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 16:17:30.185373) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 16:17:30.185768) ========= 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.ugw5I97XBl/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.TcqwOv0hSR/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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.035807 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 16:17:30.243443) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 16:17:30.243821) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 88bebcaca249aeaca9197382e89d35b02be8292e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 88bebcaca249aeaca9197382e89d35b02be8292e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ugw5I97XBl/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.TcqwOv0hSR/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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-07-11 16:17:30,325 truncating revision to first 12 chars 2016-07-11 16:17:30,325 Setting DEBUG logging. 2016-07-11 16:17:30,325 attempt 1/10 2016-07-11 16:17:30,326 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/88bebcaca249?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-11 16:17:31,013 unpacking tar archive at: mozilla-central-88bebcaca249/testing/mozharness/ program finished with exit code 0 elapsedTime=0.963243 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 16:17:31.228689) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-11 16:17:31.229044) ========= script_repo_revision: 88bebcaca249aeaca9197382e89d35b02be8292e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-11 16:17:31.229428) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-11 16:17:31.229693) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-11 16:17:31.247468) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-07-11 16:17:31.247839) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ugw5I97XBl/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.TcqwOv0hSR/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 16:17:31 INFO - MultiFileLogger online at 20160711 16:17:31 in /builds/slave/test 16:17:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 16:17:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:17:31 INFO - {'append_to_log': False, 16:17:31 INFO - 'base_work_dir': '/builds/slave/test', 16:17:31 INFO - 'blob_upload_branch': 'Firefox', 16:17:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:17:31 INFO - 'branch': 'Firefox', 16:17:31 INFO - 'buildbot_json_path': 'buildprops.json', 16:17:31 INFO - 'config_files': ('talos/mac_config.py',), 16:17:31 INFO - 'default_actions': ('clobber', 16:17:31 INFO - 'read-buildbot-config', 16:17:31 INFO - 'download-and-extract', 16:17:31 INFO - 'populate-webroot', 16:17:31 INFO - 'create-virtualenv', 16:17:31 INFO - 'install', 16:17:31 INFO - 'run-tests'), 16:17:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:17:31 INFO - 'download_minidump_stackwalk': True, 16:17:31 INFO - 'download_symbols': 'ondemand', 16:17:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:17:31 INFO - 'tooltool.py': '/tools/tooltool.py', 16:17:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:17:31 INFO - '/tools/misc-python/virtualenv.py')}, 16:17:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:17:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:17:31 INFO - 'installer_path': 'installer.exe', 16:17:31 INFO - 'log_level': 'info', 16:17:31 INFO - 'log_name': 'talos', 16:17:31 INFO - 'log_to_console': True, 16:17:31 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:17:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:17:31 INFO - 'opt_config_files': (), 16:17:31 INFO - 'pip_index': False, 16:17:31 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:17:31 INFO - 'cmd': ('bash', 16:17:31 INFO - '-c', 16:17:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:17:31 INFO - 'enabled': True, 16:17:31 INFO - 'halt_on_failure': False, 16:17:31 INFO - 'name': 'check_screen_resolution'},), 16:17:31 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:17:31 INFO - 'cmd': ('bash', 16:17:31 INFO - '-c', 16:17:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:17:31 INFO - 'enabled': True, 16:17:31 INFO - 'halt_on_failure': False, 16:17:31 INFO - 'name': 'check_screen_resolution'},), 16:17:31 INFO - 'run_cmd_checks_enabled': True, 16:17:31 INFO - 'sps_profile': False, 16:17:31 INFO - 'sps_profile_interval': 0, 16:17:31 INFO - 'suite': 'dromaeojs', 16:17:31 INFO - 'system_bits': '32', 16:17:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:17:31 INFO - 'title': 't-yosemite-r7-0330', 16:17:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:17:31 INFO - 'use_talos_json': True, 16:17:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:17:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:17:31 INFO - 'work_dir': 'build'} 16:17:31 INFO - ##### 16:17:31 INFO - ##### Running clobber step. 16:17:31 INFO - ##### 16:17:31 INFO - Running pre-action listener: _resource_record_pre_action 16:17:31 INFO - Running main action method: clobber 16:17:31 INFO - rmtree: /builds/slave/test/build 16:17:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:17:33 INFO - Running post-action listener: _resource_record_post_action 16:17:33 INFO - ##### 16:17:33 INFO - ##### Finished clobber step (success) 16:17:33 INFO - ##### 16:17:33 INFO - ##### 16:17:33 INFO - ##### Running read-buildbot-config step. 16:17:33 INFO - ##### 16:17:33 INFO - Running pre-action listener: _resource_record_pre_action 16:17:33 INFO - Running main action method: read_buildbot_config 16:17:33 INFO - Using buildbot properties: 16:17:33 INFO - { 16:17:33 INFO - "project": "", 16:17:33 INFO - "product": "firefox", 16:17:33 INFO - "script_repo_revision": "production", 16:17:33 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 16:17:33 INFO - "repository": "", 16:17:33 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 16:17:33 INFO - "buildid": "20160711140226", 16:17:33 INFO - "pgo_build": "False", 16:17:33 INFO - "basedir": "/builds/slave/test", 16:17:33 INFO - "buildnumber": 290, 16:17:33 INFO - "slavename": "t-yosemite-r7-0330", 16:17:33 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 16:17:33 INFO - "platform": "yosemite_r7", 16:17:33 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 16:17:33 INFO - "branch": "mozilla-central", 16:17:33 INFO - "repo_path": "mozilla-central", 16:17:33 INFO - "revision": "88bebcaca249aeaca9197382e89d35b02be8292e", 16:17:33 INFO - "stage_platform": "macosx64", 16:17:33 INFO - "builduid": "ad9325d9648e43f7b958257a24d5ec57", 16:17:33 INFO - "slavebuilddir": "test" 16:17:33 INFO - } 16:17:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/OxXFiZsaTDyi_O2A-X429w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 16:17:33 INFO - Running post-action listener: _resource_record_post_action 16:17:33 INFO - ##### 16:17:33 INFO - ##### Finished read-buildbot-config step (success) 16:17:33 INFO - ##### 16:17:33 INFO - ##### 16:17:33 INFO - ##### Running download-and-extract step. 16:17:33 INFO - ##### 16:17:33 INFO - Running pre-action listener: _resource_record_pre_action 16:17:33 INFO - Running main action method: download_and_extract 16:17:33 INFO - mkdir: /builds/slave/test/build/tests 16:17: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')]} 16:17:33 INFO - https://queue.taskcluster.net/v1/task/OxXFiZsaTDyi_O2A-X429w/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 16:17:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OxXFiZsaTDyi_O2A-X429w/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 16:17:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OxXFiZsaTDyi_O2A-X429w/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 16:17:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OxXFiZsaTDyi_O2A-X429w/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 16:17:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OxXFiZsaTDyi_O2A-X429w/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 16:17:34 INFO - Downloaded 1199 bytes. 16:17:34 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 16:17:34 INFO - Using the following test package requirements: 16:17:34 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 16:17:34 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:17:34 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 16:17:34 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:17:34 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 16:17:34 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:17:34 INFO - u'jsshell-mac.zip'], 16:17:34 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:17:34 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 16:17:34 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 16:17:34 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:17:34 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 16:17:34 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:17:34 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 16:17:34 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:17:34 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 16:17:34 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:17:34 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 16:17:34 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:17: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')]} 16:17:34 INFO - https://queue.taskcluster.net/v1/task/OxXFiZsaTDyi_O2A-X429w/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:17:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OxXFiZsaTDyi_O2A-X429w/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:17:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OxXFiZsaTDyi_O2A-X429w/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:17:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OxXFiZsaTDyi_O2A-X429w/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:17:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OxXFiZsaTDyi_O2A-X429w/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:17:35 INFO - Downloaded 18772141 bytes. 16:17:35 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:17:35 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:17:38 INFO - Return code: 0 16:17:38 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 16:17:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:38 INFO - https://queue.taskcluster.net/v1/task/OxXFiZsaTDyi_O2A-X429w/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:17:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OxXFiZsaTDyi_O2A-X429w/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:17:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OxXFiZsaTDyi_O2A-X429w/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:17:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OxXFiZsaTDyi_O2A-X429w/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:17:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OxXFiZsaTDyi_O2A-X429w/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:17:39 INFO - Downloaded 18772141 bytes. 16:17:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:17:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:17:42 INFO - Return code: 0 16:17:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:42 INFO - https://queue.taskcluster.net/v1/task/OxXFiZsaTDyi_O2A-X429w/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 16:17:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OxXFiZsaTDyi_O2A-X429w/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 16:17:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OxXFiZsaTDyi_O2A-X429w/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 16:17:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OxXFiZsaTDyi_O2A-X429w/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 16:17:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OxXFiZsaTDyi_O2A-X429w/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:17:43 INFO - Downloaded 14403333 bytes. 16:17:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:17:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 16:17:44 INFO - Return code: 0 16:17: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')]} 16:17:44 INFO - https://queue.taskcluster.net/v1/task/OxXFiZsaTDyi_O2A-X429w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 16:17:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OxXFiZsaTDyi_O2A-X429w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 16:17:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OxXFiZsaTDyi_O2A-X429w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 16:17:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OxXFiZsaTDyi_O2A-X429w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 16:17:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OxXFiZsaTDyi_O2A-X429w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 16:17:47 INFO - Downloaded 112449510 bytes. 16:17:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OxXFiZsaTDyi_O2A-X429w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 16:17:47 INFO - mkdir: /builds/slave/test/properties 16:17:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:17:47 INFO - Writing to file /builds/slave/test/properties/build_url 16:17:47 INFO - Contents: 16:17:47 INFO - build_url:https://queue.taskcluster.net/v1/task/OxXFiZsaTDyi_O2A-X429w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 16:17:47 INFO - Running post-action listener: _resource_record_post_action 16:17:47 INFO - Running post-action listener: set_extra_try_arguments 16:17:47 INFO - ##### 16:17:47 INFO - ##### Finished download-and-extract step (success) 16:17:47 INFO - ##### 16:17:47 INFO - ##### 16:17:47 INFO - ##### Running populate-webroot step. 16:17:47 INFO - ##### 16:17:47 INFO - Running pre-action listener: _resource_record_pre_action 16:17:47 INFO - Running main action method: populate_webroot 16:17:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:17:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:17:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:17:47 INFO - '--tppagecycles', 16:17:47 INFO - '7'], 16:17:47 INFO - 'tests': ['tsvgm']}}, 16:17:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:17:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:17:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:17:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:17:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:17:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:17:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:47 INFO - 'talos_options': ['--disable-e10s'], 16:17:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:17:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:17:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:47 INFO - 'talos_options': ['--disable-e10s'], 16:17:47 INFO - 'tests': ['damp', 'tps']}, 16:17:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:47 INFO - 'tests': ['damp', 'tps']}, 16:17:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:17:47 INFO - 'tests': ['dromaeo_dom']}, 16:17:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:17:47 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:17:47 INFO - 'tests': ['basic_compositor_video']}, 16:17:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:17:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:17:47 INFO - 'tests': ['a11yr', 16:17:47 INFO - 'ts_paint', 16:17:47 INFO - 'tpaint', 16:17:47 INFO - 'sessionrestore', 16:17:47 INFO - 'sessionrestore_no_auto_restore', 16:17:47 INFO - 'tabpaint']}, 16:17:47 INFO - 'other-e10s': {'tests': ['a11yr', 16:17:47 INFO - 'ts_paint', 16:17:47 INFO - 'tpaint', 16:17:47 INFO - 'sessionrestore', 16:17:47 INFO - 'sessionrestore_no_auto_restore', 16:17:47 INFO - 'tabpaint']}, 16:17:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:17:47 INFO - 'ts_paint', 16:17:47 INFO - 'tpaint', 16:17:47 INFO - 'sessionrestore', 16:17:47 INFO - 'sessionrestore_no_auto_restore', 16:17:47 INFO - 'tabpaint']}, 16:17:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:17:47 INFO - 'ts_paint', 16:17:47 INFO - 'tpaint', 16:17:47 INFO - 'sessionrestore', 16:17:47 INFO - 'sessionrestore_no_auto_restore', 16:17:47 INFO - 'tabpaint']}, 16:17:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:17:47 INFO - 'tests': ['a11yr', 16:17:47 INFO - 'ts_paint', 16:17:47 INFO - 'tpaint', 16:17:47 INFO - 'sessionrestore', 16:17:47 INFO - 'sessionrestore_no_auto_restore', 16:17:47 INFO - 'tabpaint']}, 16:17:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:17:47 INFO - 'tests': ['a11yr', 16:17:47 INFO - 'ts_paint', 16:17:47 INFO - 'tpaint', 16:17:47 INFO - 'sessionrestore', 16:17:47 INFO - 'sessionrestore_no_auto_restore', 16:17:47 INFO - 'tabpaint']}, 16:17:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:17:47 INFO - 'tests': ['tsvgx', 16:17:47 INFO - 'tsvgr_opacity', 16:17:47 INFO - 'tart', 16:17:47 INFO - 'tscrollx', 16:17:47 INFO - 'cart']}, 16:17:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:17:47 INFO - 'tsvgr_opacity', 16:17:47 INFO - 'tart', 16:17:47 INFO - 'tscrollx', 16:17:47 INFO - 'cart']}, 16:17:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:47 INFO - 'talos_options': ['--disable-e10s'], 16:17:47 INFO - 'tests': ['tp5o']}, 16:17:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:47 INFO - 'tests': ['tp5o']}, 16:17:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:47 INFO - 'talos_options': ['--disable-e10s', 16:17:47 INFO - '--xperf_path', 16:17:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:17:47 INFO - 'tests': ['tp5n']}, 16:17:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:47 INFO - 'talos_options': ['--xperf_path', 16:17:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:17:47 INFO - 'tests': ['tp5n']}}, 16:17:47 INFO - 'talos.zip': {'path': '', 16:17:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:17:47 INFO - Running post-action listener: _resource_record_post_action 16:17:47 INFO - ##### 16:17:47 INFO - ##### Finished populate-webroot step (success) 16:17:47 INFO - ##### 16:17:47 INFO - ##### 16:17:47 INFO - ##### Running create-virtualenv step. 16:17:47 INFO - ##### 16:17:47 INFO - Running pre-action listener: _resource_record_pre_action 16:17:47 INFO - Running main action method: create_virtualenv 16:17:47 INFO - Creating virtualenv /builds/slave/test/build/venv 16:17:47 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 16:17:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:17:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:17:47 INFO - Using real prefix '/tools/python27' 16:17:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:17:48 INFO - Installing distribute.............................................................................................................................................................................................done. 16:17:51 INFO - Installing pip.................done. 16:17:51 INFO - Return code: 0 16:17:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:17:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:51 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 0x102a4ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ae3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe79a588e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029b2d30>, '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 0x102717db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1029aab20>, '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.ugw5I97XBl/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.TcqwOv0hSR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 16:17:51 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 16:17:51 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 16:17:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ugw5I97XBl/Render', 16:17:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:17:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:17:51 INFO - 'HOME': '/Users/cltbld', 16:17:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:17:51 INFO - 'LOGNAME': 'cltbld', 16:17:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:51 INFO - 'MOZ_NO_REMOTE': '1', 16:17:51 INFO - 'NO_EM_RESTART': '1', 16:17:51 INFO - 'PAGER': '/bin/cat', 16:17:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:17:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:51 INFO - 'PWD': '/builds/slave/test', 16:17:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:17:51 INFO - 'SHELL': '/bin/bash', 16:17:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TcqwOv0hSR/Listeners', 16:17:51 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 16:17:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:17:51 INFO - 'USER': 'cltbld', 16:17:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:17:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:17:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:51 INFO - 'XPC_FLAGS': '0x0', 16:17:51 INFO - 'XPC_SERVICE_NAME': '0', 16:17:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:17:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:51 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 16:17:51 INFO - Cleaning up... 16:17:51 INFO - Return code: 0 16:17:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:17:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:51 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>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102a4ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ae3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe79a588e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029b2d30>, '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 0x102717db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1029aab20>, '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.ugw5I97XBl/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.TcqwOv0hSR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 16:17:51 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>=3.1.1'] in /builds/slave/test/build 16:17:51 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>=3.1.1 16:17:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ugw5I97XBl/Render', 16:17:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:17:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:17:51 INFO - 'HOME': '/Users/cltbld', 16:17:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:17:51 INFO - 'LOGNAME': 'cltbld', 16:17:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:51 INFO - 'MOZ_NO_REMOTE': '1', 16:17:51 INFO - 'NO_EM_RESTART': '1', 16:17:51 INFO - 'PAGER': '/bin/cat', 16:17:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:17:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:51 INFO - 'PWD': '/builds/slave/test', 16:17:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:17:51 INFO - 'SHELL': '/bin/bash', 16:17:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TcqwOv0hSR/Listeners', 16:17:51 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 16:17:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:17:51 INFO - 'USER': 'cltbld', 16:17:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:17:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:17:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:51 INFO - 'XPC_FLAGS': '0x0', 16:17:51 INFO - 'XPC_SERVICE_NAME': '0', 16:17:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:17:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:51 INFO - Downloading/unpacking psutil>=3.1.1 16:17:51 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 16:17:51 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 16:17:51 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 16:17:51 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 16:17:51 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 16:17:51 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 16:17:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:17:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:17:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:17:54 INFO - Installing collected packages: psutil 16:17:54 INFO - Running setup.py install for psutil 16:17:55 INFO - building 'psutil._psutil_osx' extension 16:17:55 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 16:17:55 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 16:17:55 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 16:17:55 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 16:17:55 INFO - building 'psutil._psutil_posix' extension 16:17:55 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 16:17:55 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:17:55 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:17:55 INFO - ^ 16:17:55 INFO - 1 warning generated. 16:17:55 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 16:17:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:17:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:17:56 INFO - Successfully installed psutil 16:17:56 INFO - Cleaning up... 16:17:56 INFO - Return code: 0 16:17:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:17:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102a4ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ae3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe79a588e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029b2d30>, '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 0x102717db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1029aab20>, '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.ugw5I97XBl/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.TcqwOv0hSR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 16:17:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:17:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:17:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ugw5I97XBl/Render', 16:17:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:17:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:17:56 INFO - 'HOME': '/Users/cltbld', 16:17:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:17:56 INFO - 'LOGNAME': 'cltbld', 16:17:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:56 INFO - 'MOZ_NO_REMOTE': '1', 16:17:56 INFO - 'NO_EM_RESTART': '1', 16:17:56 INFO - 'PAGER': '/bin/cat', 16:17:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:17:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:56 INFO - 'PWD': '/builds/slave/test', 16:17:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:17:56 INFO - 'SHELL': '/bin/bash', 16:17:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TcqwOv0hSR/Listeners', 16:17:56 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 16:17:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:17:56 INFO - 'USER': 'cltbld', 16:17:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:17:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:17:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:56 INFO - 'XPC_FLAGS': '0x0', 16:17:56 INFO - 'XPC_SERVICE_NAME': '0', 16:17:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:17:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:17:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:17:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:17:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:17:58 INFO - Installing collected packages: mozsystemmonitor 16:17:58 INFO - Running setup.py install for mozsystemmonitor 16:17:59 INFO - Successfully installed mozsystemmonitor 16:17:59 INFO - Cleaning up... 16:17:59 INFO - Return code: 0 16:17:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:17:59 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')]} 16:17:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:59 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')]} 16:17:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:59 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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102a4ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ae3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe79a588e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029b2d30>, '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 0x102717db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1029aab20>, '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.ugw5I97XBl/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.TcqwOv0hSR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 16:17:59 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==2.5.1'] in /builds/slave/test/build 16:17:59 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==2.5.1 16:17:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ugw5I97XBl/Render', 16:17:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:17:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:17:59 INFO - 'HOME': '/Users/cltbld', 16:17:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:17:59 INFO - 'LOGNAME': 'cltbld', 16:17:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:59 INFO - 'MOZ_NO_REMOTE': '1', 16:17:59 INFO - 'NO_EM_RESTART': '1', 16:17:59 INFO - 'PAGER': '/bin/cat', 16:17:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:17:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:59 INFO - 'PWD': '/builds/slave/test', 16:17:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:17:59 INFO - 'SHELL': '/bin/bash', 16:17:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TcqwOv0hSR/Listeners', 16:17:59 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 16:17:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:17:59 INFO - 'USER': 'cltbld', 16:17:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:17:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:17:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:59 INFO - 'XPC_FLAGS': '0x0', 16:17:59 INFO - 'XPC_SERVICE_NAME': '0', 16:17:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:17:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:59 INFO - Downloading/unpacking jsonschema==2.5.1 16:17:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:18:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:18:01 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 16:18:01 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 16:18:01 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 16:18:01 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 16:18:01 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 16:18:01 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 16:18:02 INFO - Downloading functools32-3.2.3-2.tar.gz 16:18:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:18:02 INFO - warning: no files found matching '*.txt' 16:18:02 INFO - no previously-included directories found matching 'build' 16:18:02 INFO - no previously-included directories found matching 'dist' 16:18:02 INFO - no previously-included directories found matching '.git*' 16:18:02 INFO - Installing collected packages: jsonschema, functools32 16:18:02 INFO - Running setup.py install for functools32 16:18:02 INFO - warning: no files found matching '*.txt' 16:18:02 INFO - no previously-included directories found matching 'build' 16:18:02 INFO - no previously-included directories found matching 'dist' 16:18:02 INFO - no previously-included directories found matching '.git*' 16:18:02 INFO - Successfully installed jsonschema functools32 16:18:02 INFO - Cleaning up... 16:18:02 INFO - Return code: 0 16:18:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:18:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:18:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:18:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:02 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 0x102a4ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ae3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe79a588e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029b2d30>, '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 0x102717db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1029aab20>, '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.ugw5I97XBl/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.TcqwOv0hSR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 16:18:02 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 16:18:02 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 16:18:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ugw5I97XBl/Render', 16:18:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:18:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:18:02 INFO - 'HOME': '/Users/cltbld', 16:18:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:18:02 INFO - 'LOGNAME': 'cltbld', 16:18:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:02 INFO - 'MOZ_NO_REMOTE': '1', 16:18:02 INFO - 'NO_EM_RESTART': '1', 16:18:02 INFO - 'PAGER': '/bin/cat', 16:18:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:18:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:02 INFO - 'PWD': '/builds/slave/test', 16:18:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:18:02 INFO - 'SHELL': '/bin/bash', 16:18:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TcqwOv0hSR/Listeners', 16:18:02 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 16:18:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:18:02 INFO - 'USER': 'cltbld', 16:18:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:18:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:18:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:02 INFO - 'XPC_FLAGS': '0x0', 16:18:02 INFO - 'XPC_SERVICE_NAME': '0', 16:18:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:18:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:02 INFO - Downloading/unpacking blobuploader==1.2.4 16:18:02 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 16:18:02 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 16:18:02 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 16:18:02 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 16:18:02 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 16:18:02 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 16:18:05 INFO - Downloading blobuploader-1.2.4.tar.gz 16:18:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:18:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:18:05 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 16:18:05 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 16:18:05 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 16:18:05 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 16:18:05 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 16:18:05 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 16:18:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:18:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:18:05 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 16:18:05 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 16:18:05 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 16:18:05 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 16:18:05 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 16:18:05 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 16:18:06 INFO - Downloading docopt-0.6.1.tar.gz 16:18:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:18:06 INFO - Installing collected packages: blobuploader, requests, docopt 16:18:06 INFO - Running setup.py install for blobuploader 16:18:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:18:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:18:06 INFO - Running setup.py install for requests 16:18:07 INFO - Running setup.py install for docopt 16:18:07 INFO - Successfully installed blobuploader requests docopt 16:18:07 INFO - Cleaning up... 16:18:07 INFO - Return code: 0 16:18:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:18:07 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')]} 16:18:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:07 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')]} 16:18:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:07 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 0x102a4ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ae3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe79a588e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029b2d30>, '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 0x102717db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1029aab20>, '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.ugw5I97XBl/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.TcqwOv0hSR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 16:18:07 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 16:18:07 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 16:18:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ugw5I97XBl/Render', 16:18:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:18:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:18:07 INFO - 'HOME': '/Users/cltbld', 16:18:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:18:07 INFO - 'LOGNAME': 'cltbld', 16:18:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:07 INFO - 'MOZ_NO_REMOTE': '1', 16:18:07 INFO - 'NO_EM_RESTART': '1', 16:18:07 INFO - 'PAGER': '/bin/cat', 16:18:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:18:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:07 INFO - 'PWD': '/builds/slave/test', 16:18:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:18:07 INFO - 'SHELL': '/bin/bash', 16:18:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TcqwOv0hSR/Listeners', 16:18:07 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 16:18:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:18:07 INFO - 'USER': 'cltbld', 16:18:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:18:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:18:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:07 INFO - 'XPC_FLAGS': '0x0', 16:18:07 INFO - 'XPC_SERVICE_NAME': '0', 16:18:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:18:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:18:07 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-LoYcJJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:18:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:18:07 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-MHIjgM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:18:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:18:07 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-T58J78-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:18:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:18:08 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-04RXbK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:18:08 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-hHM5Is-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:18:08 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-XarWHu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:18:08 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-MxBUNH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:18:08 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-MAOlvk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:18:08 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-V64Ty4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:18:08 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-xMFCw8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:18:08 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-XxKAe7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:18:08 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-6QgTuu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:18:08 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-IYKCXq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:18:09 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-OJnLSg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:18:09 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-2U9ZBo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:18:09 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-jvJ91w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:18:09 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-PTAFKa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:18:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:18:09 INFO - Running setup.py install for manifestparser 16:18:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:18:09 INFO - Running setup.py install for mozcrash 16:18:09 INFO - Running setup.py install for mozdebug 16:18:09 INFO - Running setup.py install for mozdevice 16:18:10 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:18:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:18:10 INFO - Running setup.py install for mozfile 16:18:10 INFO - Running setup.py install for mozhttpd 16:18:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:18:10 INFO - Running setup.py install for mozinfo 16:18:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:18:10 INFO - Running setup.py install for mozInstall 16:18:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:18:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:18:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:18:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:18:10 INFO - Running setup.py install for mozleak 16:18:10 INFO - Running setup.py install for mozlog 16:18:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:18:10 INFO - Running setup.py install for moznetwork 16:18:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:18:11 INFO - Running setup.py install for mozprocess 16:18:11 INFO - Running setup.py install for mozprofile 16:18:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:18:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:18:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:18:11 INFO - Running setup.py install for mozrunner 16:18:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:18:11 INFO - Running setup.py install for mozscreenshot 16:18:11 INFO - Running setup.py install for moztest 16:18:11 INFO - Running setup.py install for mozversion 16:18:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:18:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:18:11 INFO - Cleaning up... 16:18:12 INFO - Return code: 0 16:18:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:18:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:18:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:18:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102a4ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ae3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe79a588e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029b2d30>, '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 0x102717db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1029aab20>, '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.ugw5I97XBl/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.TcqwOv0hSR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 16:18:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:18:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:18:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ugw5I97XBl/Render', 16:18:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:18:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:18:12 INFO - 'HOME': '/Users/cltbld', 16:18:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:18:12 INFO - 'LOGNAME': 'cltbld', 16:18:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:12 INFO - 'MOZ_NO_REMOTE': '1', 16:18:12 INFO - 'NO_EM_RESTART': '1', 16:18:12 INFO - 'PAGER': '/bin/cat', 16:18:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:18:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:12 INFO - 'PWD': '/builds/slave/test', 16:18:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:18:12 INFO - 'SHELL': '/bin/bash', 16:18:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TcqwOv0hSR/Listeners', 16:18:12 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 16:18:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:18:12 INFO - 'USER': 'cltbld', 16:18:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:18:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:18:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:12 INFO - 'XPC_FLAGS': '0x0', 16:18:12 INFO - 'XPC_SERVICE_NAME': '0', 16:18:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:18:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:18:12 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-Z6M2nk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:18:12 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)) 16:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:18:12 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-L6l6_t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:18:12 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)) 16:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:18:12 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-zSjm3g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:18:12 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)) 16:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:18:12 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-X28hTN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:18:12 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)) 16:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:18:12 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-m_0ghv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:18:12 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)) 16:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:18:12 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-j_NKxj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:18:12 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)) 16:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:18:12 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-5yppu2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:18:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 16:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:18:13 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-AT4pnP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:18:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:18:13 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-z4JbFK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:18:13 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)) 16:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:18:13 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-6Wpi8G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:18:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 16:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:18:13 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-kVvMfu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:18:13 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)) 16:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:18:13 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-cJyHbY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:18:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 16:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:18:13 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-vQEnH2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:18:13 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)) 16:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:18:13 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-yLcL8r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:18:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 16:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:18:13 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-6qC2hx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:18:13 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)) 16:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:18:13 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-X6SMHx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:18:13 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)) 16:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:18:13 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-6acYVe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:18:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 16:18:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:18:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:18:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:18:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:18:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:18:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:18:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:16 INFO - Downloading blessings-1.6.tar.gz 16:18:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:18:16 INFO - Installing collected packages: blessings 16:18:16 INFO - Running setup.py install for blessings 16:18:16 INFO - Successfully installed blessings 16:18:16 INFO - Cleaning up... 16:18:16 INFO - Return code: 0 16:18:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:18:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:18:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:18:17 INFO - Reading from file tmpfile_stdout 16:18:17 INFO - Current package versions: 16:18:17 INFO - blessings == 1.6 16:18:17 INFO - blobuploader == 1.2.4 16:18:17 INFO - docopt == 0.6.1 16:18:17 INFO - functools32 == 3.2.3-2 16:18:17 INFO - jsonschema == 2.5.1 16:18:17 INFO - manifestparser == 1.1 16:18:17 INFO - mozInstall == 1.12 16:18:17 INFO - mozcrash == 0.17 16:18:17 INFO - mozdebug == 0.1 16:18:17 INFO - mozdevice == 0.48 16:18:17 INFO - mozfile == 1.2 16:18:17 INFO - mozhttpd == 0.7 16:18:17 INFO - mozinfo == 0.9 16:18:17 INFO - mozleak == 0.1 16:18:17 INFO - mozlog == 3.2 16:18:17 INFO - moznetwork == 0.27 16:18:17 INFO - mozprocess == 0.23 16:18:17 INFO - mozprofile == 0.28 16:18:17 INFO - mozrunner == 6.12 16:18:17 INFO - mozscreenshot == 0.1 16:18:17 INFO - mozsystemmonitor == 0.3 16:18:17 INFO - moztest == 0.7 16:18:17 INFO - mozversion == 1.4 16:18:17 INFO - psutil == 3.1.1 16:18:17 INFO - requests == 1.2.3 16:18:17 INFO - wsgiref == 0.1.2 16:18:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16: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')]} 16:18:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16: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')]} 16:18:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102a4ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ae3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe79a588e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029b2d30>, '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 0x102717db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1029aab20>, '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.ugw5I97XBl/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.TcqwOv0hSR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 16:18:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 16:18:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:18:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ugw5I97XBl/Render', 16:18:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:18:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:18:17 INFO - 'HOME': '/Users/cltbld', 16:18:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:18:17 INFO - 'LOGNAME': 'cltbld', 16:18:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:17 INFO - 'MOZ_NO_REMOTE': '1', 16:18:17 INFO - 'NO_EM_RESTART': '1', 16:18:17 INFO - 'PAGER': '/bin/cat', 16:18:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:18:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:17 INFO - 'PWD': '/builds/slave/test', 16:18:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:18:17 INFO - 'SHELL': '/bin/bash', 16:18:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TcqwOv0hSR/Listeners', 16:18:17 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 16:18:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:18:17 INFO - 'USER': 'cltbld', 16:18:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:18:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:18:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:17 INFO - 'XPC_FLAGS': '0x0', 16:18:17 INFO - 'XPC_SERVICE_NAME': '0', 16:18:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:18:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:17 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)) 16:18:17 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)) 16:18:17 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)) 16:18:17 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)) 16:18:17 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)) 16:18:17 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)) 16:18:17 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)) 16:18:17 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)) 16:18:17 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)) 16:18:17 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)) 16:18:17 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)) 16:18:17 INFO - Cleaning up... 16:18:17 INFO - Return code: 0 16:18:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16: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')]} 16:18:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16: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')]} 16:18:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:17 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 0x102a4ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ae3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe79a588e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029b2d30>, '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 0x102717db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1029aab20>, '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.ugw5I97XBl/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.TcqwOv0hSR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 16:18:17 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 16:18:17 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 16:18:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ugw5I97XBl/Render', 16:18:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:18:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:18:17 INFO - 'HOME': '/Users/cltbld', 16:18:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:18:17 INFO - 'LOGNAME': 'cltbld', 16:18:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:17 INFO - 'MOZ_NO_REMOTE': '1', 16:18:17 INFO - 'NO_EM_RESTART': '1', 16:18:17 INFO - 'PAGER': '/bin/cat', 16:18:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:18:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:17 INFO - 'PWD': '/builds/slave/test', 16:18:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:18:17 INFO - 'SHELL': '/bin/bash', 16:18:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TcqwOv0hSR/Listeners', 16:18:17 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 16:18:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:18:17 INFO - 'USER': 'cltbld', 16:18:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:18:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:18:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:17 INFO - 'XPC_FLAGS': '0x0', 16:18:17 INFO - 'XPC_SERVICE_NAME': '0', 16:18:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:18:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:18:17 INFO - Cleaning up... 16:18:17 INFO - Return code: 0 16:18:17 INFO - Running post-action listener: _resource_record_post_action 16:18:17 INFO - Running post-action listener: _start_resource_monitoring 16:18:17 INFO - Starting resource monitoring. 16:18:17 INFO - ##### 16:18:17 INFO - ##### Finished create-virtualenv step (success) 16:18:17 INFO - ##### 16:18:17 INFO - ##### 16:18:17 INFO - ##### Running install step. 16:18:17 INFO - ##### 16:18:17 INFO - Running pre-action listener: _resource_record_pre_action 16:18:17 INFO - Running main action method: install 16:18:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:18:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:18:17 INFO - Reading from file tmpfile_stdout 16:18:17 INFO - Detecting whether we're running mozinstall >=1.0... 16:18:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:18:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:18:18 INFO - Reading from file tmpfile_stdout 16:18:18 INFO - Output received: 16:18:18 INFO - Usage: mozinstall [options] installer 16:18:18 INFO - Options: 16:18:18 INFO - -h, --help show this help message and exit 16:18:18 INFO - -d DEST, --destination=DEST 16:18:18 INFO - Directory to install application into. [default: 16:18:18 INFO - "/builds/slave/test"] 16:18:18 INFO - --app=APP Application being installed. [default: firefox] 16:18:18 INFO - mkdir: /builds/slave/test/build/application 16:18:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:18:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 16:18:52 INFO - Reading from file tmpfile_stdout 16:18:52 INFO - Output received: 16:18:52 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:18:52 INFO - Running post-action listener: _resource_record_post_action 16:18:52 INFO - ##### 16:18:52 INFO - ##### Finished install step (success) 16:18:52 INFO - ##### 16:18:52 INFO - ##### 16:18:52 INFO - ##### Running run-tests step. 16:18:52 INFO - ##### 16:18:52 INFO - Running pre-action listener: _resource_record_pre_action 16:18:52 INFO - Running main action method: run_tests 16:18:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:18:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:18:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:18:52 INFO - 2016-07-11 16:18:52.235 screenresolution[1657:7645] starting screenresolution argv=screenresolution get 16:18:52 INFO - 2016-07-11 16:18:52.238 screenresolution[1657:7645] Display 0: 1600x1200x32@60 16:18:52 INFO - 2016-07-11 16:18:52.244 screenresolution[1658:7650] starting screenresolution argv=screenresolution list 16:18:52 INFO - Available Modes on Display 0 16:18:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:18:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:18:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:18:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:18:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:18:52 INFO - Intel Iris: 16:18:52 INFO - Chipset Model: Intel Iris 16:18:52 INFO - Type: GPU 16:18:52 INFO - Bus: Built-In 16:18:52 INFO - VRAM (Dynamic, Max): 1536 MB 16:18:52 INFO - Vendor: Intel (0x8086) 16:18:52 INFO - Device ID: 0x0a2e 16:18:52 INFO - Revision ID: 0x0009 16:18:52 INFO - Displays: 16:18:52 INFO - Display: 16:18:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:18:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:18:52 INFO - Display Serial Number: 1600x1200 60 16:18:52 INFO - Main Display: Yes 16:18:52 INFO - Mirror: Off 16:18:52 INFO - Online: Yes 16:18:52 INFO - Rotation: Supported 16:18:52 INFO - Return code: 0 16:18:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:18:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:18:52 INFO - Python 2.7.3 16:18:52 INFO - Return code: 0 16:18:52 INFO - grabbing minidump binary from tooltool 16:18:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:18:52 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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029b2d30>, '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 0x102717db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1029aab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:18:52 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 16:18:52 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 16:18:52 INFO - Calling ['/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'] with output_timeout 600 16:18:52 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:18:52 INFO - Return code: 0 16:18:52 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:18:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:18:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:18:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:18:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:18:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OxXFiZsaTDyi_O2A-X429w/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0330', '--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 16:18:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/OxXFiZsaTDyi_O2A-X429w/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0330 --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 16:18:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ugw5I97XBl/Render', 16:18:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:18:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:18:52 INFO - 'HOME': '/Users/cltbld', 16:18:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:18:52 INFO - 'LOGNAME': 'cltbld', 16:18:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:18:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:18:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:52 INFO - 'MOZ_NO_REMOTE': '1', 16:18:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:18:52 INFO - 'NO_EM_RESTART': '1', 16:18:52 INFO - 'PAGER': '/bin/cat', 16:18:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:18:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:52 INFO - 'PWD': '/builds/slave/test', 16:18:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:18:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:18:52 INFO - 'SHELL': '/bin/bash', 16:18:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TcqwOv0hSR/Listeners', 16:18:52 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 16:18:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:18:52 INFO - 'USER': 'cltbld', 16:18:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:18:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:18:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:52 INFO - 'XPC_FLAGS': '0x0', 16:18:52 INFO - 'XPC_SERVICE_NAME': '0', 16:18:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:18:52 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OxXFiZsaTDyi_O2A-X429w/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0330', '--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 16:18:52 INFO - mozversion application_buildid: 20160711140226 16:18:52 INFO - mozversion application_changeset: 88bebcaca249aeaca9197382e89d35b02be8292e 16:18:52 INFO - mozversion application_display_name: Nightly 16:18:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:18:52 INFO - mozversion application_name: Firefox 16:18:52 INFO - mozversion application_remotingname: firefox 16:18:52 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:18:52 INFO - mozversion application_vendor: Mozilla 16:18:52 INFO - mozversion application_version: 50.0a1 16:18:52 INFO - mozversion platform_buildid: 20160711140226 16:18:52 INFO - mozversion platform_changeset: 88bebcaca249aeaca9197382e89d35b02be8292e 16:18:52 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:18:52 INFO - mozversion platform_version: 50.0a1 16:18:52 INFO - using testdate: 1468279132 16:18:52 INFO - actual date: 1468279132 16:18:52 INFO - starting webserver on 'localhost:49272' 16:18:52 INFO - SUITE-START | Running 2 tests 16:18:52 INFO - TEST-START | dromaeo_css 16:18:52 INFO - operating with platform_type : mac_ 16:18:52 INFO - Initialising browser for dromaeo_css test... 16:18:52 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/tmp9VW6Th/profile http://localhost:49272/getInfo.html) 16:18:55 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 16:18:55 INFO - PROCESS | 1668 | colorDepth:24 16:18:55 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 16:18:55 INFO - PROCESS | 1668 | __metrics 16:18:55 INFO - PROCESS | 1668 | 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" 16:18:55 INFO - PROCESS | 1668 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:18:56 INFO - TEST-INFO | 1668: exit 0 16:18:56 INFO - Browser initialized. 16:18:56 INFO - Running cycle 1/1 for dromaeo_css test... 16:18:56 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/tmp9VW6Th/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:19:07 INFO - PROCESS | 1670 | RSS: Main: 243658752 16:19:07 INFO - PROCESS | 1670 | 16:19:59 INFO - PROCESS | 1670 | 1468279199190 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] 16:22:10 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 16:22:11 INFO - PROCESS | 1670 | RSS: Main: 387608576 16:22:11 INFO - PROCESS | 1670 | 16:25:15 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 16:25:15 INFO - PROCESS | 1670 | RSS: Main: 381251584 16:25:15 INFO - PROCESS | 1670 | 16:28:18 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 16:28:18 INFO - PROCESS | 1670 | RSS: Main: 373567488 16:28:18 INFO - PROCESS | 1670 | 16:31:21 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 16:31:21 INFO - PROCESS | 1670 | RSS: Main: 372801536 16:31:21 INFO - PROCESS | 1670 | 16:34:25 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 16:34:26 INFO - PROCESS | 1670 | RSS: Main: 342495232 16:34:26 INFO - PROCESS | 1670 | 16:37:29 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 16:37:29 INFO - PROCESS | 1670 | RSS: Main: 422629376 16:37:29 INFO - PROCESS | 1670 | 16:37:29 INFO - PROCESS | 1670 | __start_tp_report 16:37:29 INFO - PROCESS | 1670 | _x_x_mozilla_page_load 16:37:29 INFO - PROCESS | 1670 | _x_x_mozilla_page_load_details 16:37:29 INFO - PROCESS | 1670 | |i|pagename|runs| 16:37:29 INFO - PROCESS | 1670 | |0;dojo.html;3207.791493552919;3361.4621660534317;3380.729541636669;3414.2317978454835;3451.7583769136154;5618.297712785902;5656.858578535539;5710.885782284352;5720.399358067405;5802.622829516078;5172.741362931857;5529.474699903512;5740.626859254156;5760.0783874580075;5842.941570584293;5116.8976620467565;5149.536541711245;5200.5059519345605;5220;5298.523132918037;5263.6578622389425;5292.444293349201;5301.443348448415;5327.413984461706;5344.946550534483;5166.302549155854;5185.740712964374;5195.428502864682;5213.921791173136;5257.605679574009;5240.81657142002;5243.764030618631;5244.711540865251;5246.658967167122;5249.212618107276;5122.103631864409;5127.230915362688;5163.1480805667015;5209.244659524347;5212.9739351303;5647.265855438766;5671.234383358235;5701.857453563653;5787.421257874179;5819.214406055171;6203.689815509207;6226.750929962796;6257.906131407987;6289.308176100625;6303.936960630427;6131.018058544209;6142.86713775973;6270.028145637433;6276;6323.20959880015;4951.579115775127;5849.181114643953;5850.414958504116;5883.735231914574;5920.348761636174;6269.529785266032;6279.843003924939;6284.591501552384;6288.151099601587;6300.149479820166;5060.215666571614;6018.638881667114;6109.236345456818;6127.908081378783;6128;6154.323024467305;6205.379462053758;6207.441330280206;6232.906506402408;6257.687115644199;5422.8373148805595;6132.424381759672;6250.749970001149;6256.155419018465;6266.310705822356;6223.253209614784;6245.843853903689;6286.685665716787;6296.307406185316;6298.559100862987;4847.927281090787;4887.120318342661;4900.901981960341;4962.379702537183;5006.07387633289;4373.715708479002;4441.422615060022;4455.064436468313;4486.349479325545;4501.932471012938;5733.369329373765;5764.913526297109;5776.075827867521;5778.162166485836;5779.24869766936;6165;6206.007038873759;6237.220347456568;6251.843703907348;6260.123582698426;5561.610687251934;5831.329397119301;5833.82498525045;5836.124581312837;5838.766449341979;5392.811251606175;5394.190871369247;5427.538659213932;5428.375736790241;5432.076546987022;6134.038624681888;6216.129741836146;6290.370962903673;6302.401271879165;6322.209723784527;6266.216722909637;6268;6280.685965701605;6292.811215663537;6304.684765761602;6177.7837775677635;6327.810165695037;6328.0824280478155;6333.303336633059;6335.334789847;6088.634681918922;6105.520399749632;6106.855059071331;6147.323794382703;6156.753729850756;6229.719662615284;6230;6238.844028899314;6246.437820596168;6252.124702541557;5857.765689372548;5979.641221526722;6082.513398928164;6097.146399503984;6109.572329936862;6028.668423236764;6088.208532890696;6106.491003209229;6139.4781443577795;6172.722227499684;6031.276246850406;6055.394460553909;6074.564130623915;6082.635041897501;6106.236720409949;6019.217501724923;6120.16506959497;6181.567290289817;6191.752329906753;6201;5895.734691938787;5968.582199246011;6025.156478092983;6032.969835150796;6050.364711705383 16:37:29 INFO - PROCESS | 1670 | |1;ext.html;478.47845848025656;507.87049302427874;526.5603221310208;531.7447625139932;532.9680219186849;29407;29764.107076787677;31414.05757827269;31440.213994650145;31517.842410787915;41903.79048104755;42965.570344296546;43092;45480.77259613705;45511.77244113783;9711.805763884719;9865.802683946353;9872.111509964101;9875.259355548327;9900.851487227697;8733.606987685569;8848.380613357036;9701.805963880719;9819.26355523335;9824.1649459796;8949.418287811304;9002.909970900273;9011.954940225323;11876.643700688994;12141.45363458642;11997.040236781037;12038.939805301005;12063.095267854858;12067.456964436533;12084.214526055779;11875.94062029693;11960.461779219955;11964.581239656658;11979.760404791856;11993.64019079429;31333.529997050064;39798.801005994785;39823;40208.59791402107;40277.194456110716;3218.790778599407;3479.791212527231;4189.7695626740515;4200.096979149497;4213.346931225665;32104.839475802706;32327;32830.17924551881;32948.6705132948;33036.834815825765;3174.1112488503254;3409.437442821931;3422.024722953971;3440.3119376124623;3445.155936795501;372308;372599;373827;373957.1302143499;375233;57070.143947840814;58094;58095;58135.18648135153;58227;62459.06311405332;62541;62616.37383626196;62635.6868215656;62659.6867015662;16295.429659961845;16544.6691066178;16547.007179569264;16554.751678724828;16565.83434165667;12892.935535322264;12959.352032398343;12968.935155324163;12977;12977.183194352798;13746.381412836447;14449.49426770058;15662.530124096067;15679.294431750599;15714.057156570643;5651.632643878134;6383.946648802988;6642.43539299165;7032.683529241196;7075.433965282765;913.6939125392935;951.1297163095828;1019.6533178719271;1049.574922156528;1086.1690806533009;28980.710192898223;29773.404531909673;29828.403431931674;30262.54606180909;30420.54369184464;1044.102072217892;1073.2272763610188;1081.3728037738092;1099.4173088263233;1157.311399717167;14854.851451485347;15657.903946723847;15760.68478630421;15862.783584245846;16000.75998860018;595072.1231969235;602465;602601;602790;606352;51028.23457648138;51565.226521602206;51566.74216628968;56459.71770141123;58010;1288.4781663426327;1316.7498175346655;1317.3347459532874;1394.8256467941305;1420.9644758880697;23640.408989775395;30819.69180308168;31270.53094203543;31741.36517269688;32034.839825800722;48865.511344886094;56545.71727141502;57576.424235757106;57770.84458310894;61616.07575886455;609696;622798;623047;623485;624701;23287.650685240074;23878.044878204677;23890.522189555762;24616.261512154662;26040.34899127537;40510.39234411427;43197.352039720055;48718.5128148714;48827.779305517644;50376.74811625919;38960.610393895695;40415.797921010206;40759.59240407558;41345.1730965385;41422.75731728053;39445;39520.802395987834;39599;40716.592834072464;40849.1830163401;38166.6183338163;38503.807480962416;38817.805910970266;39130.80434597809;39189.608103918596;27267;33640.17318080684;34367.82816085903;34856.65143348635;37324.81337593294;25230.61231632278;25304.62043069392;25714;26116;26663 16:37:29 INFO - PROCESS | 1670 | |2;jquery.html;359.79311895660004;382.63076131533074;391.89222963684983;393.4668524149777;410.06300603121855;14185.50350737723;15474.845251547482;21073.68389474157;21098;21364.75670054645;17975;21099.6835047474;21419.464513387175;21587.784122158773;21813.127474900986;5652;5849.713063126105;5941.554383421249;6177.135201071831;6202.4417802397775;5967.313758917737;6016.308124565688;6083.847903802396;6086.360932102132;6091.78678746244;5695;5874.794382196624;5901.291844978608;5905.645661260317;6024.608400453978;5681.176229446707;5904.3800400957925;5937.970310148443;5950.226470558843;5967.85080372992;5127.2052831811125;5157.48425157485;5160.25176349431;5164.406093299243;5176.818811341623;25841;26014.089506867167;26122.216333510027;26739.33151671224;26764.330891727666;2202.0971401725214;2232.9776702232934;2267.353804165821;2505.3486093615793;2890.942181156386;15280.847191528053;15956.920215398848;16425.85019048667;16453.753193702105;16809.831901680947;1909.608530251309;1916.7124931260282;1964.0376215654226;1965.6461836869357;2045.2739277556402;80773;104080.47959760229;105048;116973.41513292464;118231.2265316021;11015.906977674216;11048.005679488724;11178.161637877192;11190.720231994264;11375.20373573842;13649.679554475762;13807.585772426843;13837.51568695111;13840.723185536433;13870.583882483541;8556.229939305367;8687.740277659705;8746.169113934166;8767.561621918878;8812.250958668457;8751.212390884852;8756.8686469703;8758.255548278468;8863.379563430626;8886.333524985652;6643.534952553371;6735.461163106939;6739.123913891162;6749.763758268536;6769.05233267343;1533.6625942292678;1743.5118166913285;1858.6189831084596;1895.4882181810833;1896.3647178195458;844.3456321351002;881;905.4612505559181;943.2265542255285;987.4766373821886;17239.22485967215;21963;23116.65325020126;23132.421689457897;23140.53718925612;956.9617215311449;1006.2101250518308;1033.1166852341291;1069.8341757027674;1080.114306268852;11464.713382165513;11541;11665;11902.464389102512;12169.33068681231;497531;593072;594279;602934;605021;104498;130531;140235;140561;142843.2857835704;982.2044144243185;1002.0981116994762;1015.4719545836124;1015.7866847961863;1023.9795204095876;19366.41658981592;19370.515737106685;19672.90163549173;19707.40877773669;19968.60062798765;58050.709746451;59788;60325.39674603198;60529.39470605238;60786;63265.36734632595;84292.36391998499;87973;88292.5585372069;90036.54981725311;7993.800154996172;8014.639341229776;8045.356371490384;8073.15231900642;8257.876131858147;9870.901290986998;12503.812442813183;12753.298568578817;12760.6809829755;12830.486780528674;11062.004419602274;11129.888701112885;11153.88846111561;11181.720456988314;11283.379414131974;8763.211310982042;10827.729306767395;10873.510692018719;11049.447527623426;11171.218014739216;8697.521636310064;11382.94308528452;11398.316101033965;11487.195896287514;11606.70983225426;7980.600969951595;8000.999875015623;8233.094359620318;8233.917660823316;8313.459625124347;10180.745481363025;10993.340399576051;11216.66350009501;11324.094072474347;11343.829842552203 16:37:29 INFO - PROCESS | 1670 | |3;mootools.html;1560.836112208218;1567.3182165757896;1575.2911189964516;1919.165163154029;1961.2449207055279;8397.748067557975;8650.26472749817;8827.779305517357;8832.690855820048;8835.823283534342;7733.0720313562315;7980.241877021674;8348.33213342931;8570.442921210117;8633.611487483062;2340.555294494045;2376.0733314007553;2380.2502211803226;2380.6310021946624;2380.690510233676;2331.440454290975;2335.9532809343887;2368.917087901924;2371.6679664846934;2376.489054853206;2068.979310206894;2147.1733382647717;2286.6798648189265;2356.1871154451774;2356.634721618152;2339.0409931927998;2346.061575369857;2367.005857539829;2368.0764501844296;2369.668246445499;2003.8196562309463;2329.603967325542;2361.563110824503;2364.5270945810908;2367.7040369953756;8728.258098061673;8821.691240806542;8833.690820821304;8842.46945183291;8846.292296616255;5138;5283.788648454057;5284.180951952453;5292.603054770869;5320.281761962125;3522.5244591980013;3542.91142721434;3578.982105089458;3611.4582812578324;3612.422012478017;894.932880034;1089.3028461784463;1092.2627226621998;1103.1395511501041;1110.6945589962745;11416.60041898538;11418;11422.371769552672;11441.427928603536;11480.885191148149;4515.119551687455;4516.593506584423;4715.292706094114;4716.127516409475;4724.149653062482;9722.027797220222;9733.853992190123;9736.464494452732;9743.317967742332;9749.9025009749;5246.160614301693;5248.580113590903;5252.317198764136;5255.894882102337;5265.9736701317015;4691.366665500113;4809.062232864558;4811.759412029384;4816.759162041884;4835.758212089381;3881.6118388160958;3901.4147877817986;3931.2137572485617;3982.203559288154;4000.9399859002137;1013.4881884648205;1075.9784804304027;1215.9574414895437;1245.872485400714;1264.3930913161735;683.2177157155129;897.9506127162938;934.8270569944582;938.9248860091176;969.4730798991617;5934.258217722785;5996.310424301174;6131.233595800525;6178.783742569079;6212.627242365382;920.8388532007006;957.3346524165638;961.5240605670263;961.918236949853;1011.1051719228388;3352.2122301259183;3395.1172695099453;3889.2221555688975;4049.97588708189;4105.753654780723;9949.602015919427;10689;10762.192835537366;10766.24636275469;10781.245312828027;9699.321047526606;9906.157976571927;10578.259521833552;10774.19193560486;10815.459227038775;7895.60521973899;8273.751787446387;8280.751577452686;8285.337173026264;8327.167283271625;6021.729022193924;6045.879082418415;6206.689665516616;6215.378462153749;6306.621602703853;9631.855522167032;9825.606975720892;10019.54912028975;10031.047050530187;10094.343867648578;9865.654702085392;10154;10156.238282129018;10197.541110650187;10220.540075696761;7030.578165310098;7264;7598.620068996417;7604.8479030420185;7685.807854803675;8369.581520924052;8410.663573456994;8477.660893564189;8487.957560212159;8507.276881464897;7245.058142441449;7300.56196628204;7421.666025028779;7484.438667100098;7627.580483073484;7310.268973102648;7395.075615548057;7416.3325300721035;7533.472656913963;7550.584717840571;7049.541779784297;7088.858222835617;7104.715811367488;7106.7157313706875;7120.501564890408;4759.571638552547;4959.280904268933;4989.700617962933;5000.524950129732;5007.549320561167;4908.141075311734;5097.439281678938;5107.489251074863;5158.871028224325;5252.763625636779 16:37:29 INFO - PROCESS | 1670 | |4;prototype.html;270.3498087100523;296.35542694639156;309.49861224815794;319.43938388128845;321.93883162199194;4942.777575009129;5106.4382917879;5290.814821481249;5427.837164885055;5566.666000040001;5430.67415955042;5464.890702185954;5466.480684334992;5475.262368815592;5503.834884953448;3392.508086327481;3793;3936.9015774605577;4133.3386658134705;4188.937165942513;3876.612338766129;4040.919181616366;4115.300398932174;4139.627433530982;4179.749215047092;3664.6518580734787;4045.5347635022054;4108.404281379198;4111.650509706678;4114.876553703393;3078.7844850860447;3459.6713312235297;3661.6338366163436;3881.4371916072155;4154.273002224599;3574.3566158091535;3597.8201089945396;3604.3512167809786;3617.8733744319084;3625.818709064536;4481.753503557303;4753.001869607387;4979.775910084055;5209.921851172236;5370.516653501165;3672.908177295589;4111.794410279504;4377.649788016951;4531.456225252958;4674.649401294916;4782.13921494131;4976.402831660188;5024.120778863684;5631.352394474647;5653.4911857932575;3270.3295824356187;3737.458068580068;3880.941785873214;3905.5703872573995;3910.882673519799;4984.626153038536;5738.938296415135;6003.519718422515;6030.612959019362;6041.697915104227;3362.6637336266667;3920.9019774505296;3939.9606003939593;3945.2504024235304;3959.5644479107277;4022.4368588397647;4022.61785130418;4028.2144981728284;4049.6962727795535;4052.4326594276827;4313.590208930146;4438.267685831866;4588.7246765194195;4729.881752956204;4766.475687674353;4622.65942876562;5031.4213865405945;5083.644144909821;5503.532199762985;5823.155642431823;1169.0647482014353;1336.8863646589955;1358.6875018745545;1378.800073989266;1380.536631170962;784.1413652050995;791.7743443118684;846.3863698818308;858.4634603372892;873.5806812729925;4811.559734789642;4872.756362181876;5021.874453138701;5208.801975545645;5228.503292187313;4801.839172372446;5900.055991041413;5902.055671092605;5904.232449781501;5919.477910643246;4945.604351651859;5361.731913404315;5371.946280537223;5387;5387.434319396485;2939.2277303681767;3096.256898344426;3101.984490077535;3110.73558747509;3121.7034381733697;5048.334049763569;5362.651427657268;5871.647701137946;6019.969900150471;6113.724882380304;5004.924926126111;5625.662460252316;5704.401037891044;5971.940280597139;5973.761049558056;4594.724316541018;5535.612507124543;5922.423745670647;5978.6548026693645;5993.400659933972;4972.975135124446;5561.610687251854;5776.711164441677;5857;5864.67744274069;5396.433374495621;5782.942170578409;5833.883322333445;5852.619579727304;5899.557533184942;5200.349956255468;6043.486303664238;6055.364186760503;6129.141920131275;6156.322804491413;3276.868925243059;3848.9415410761144;3861.478700375413;3899.7075219358094;3906.785126817939;4634.768261586974;5090.312807771052;5134.666246693953;5282.683039017671;5515.145152501369;4663.463701674215;5299.708516031501;5461.672299662033;5617.685565516365;5658.915116273177;5424.864378390572;5723.656580605177;5732.509547517759;5776.162456443876;5801.419858014165;4723.291506274087;4844.61243100544;5594.580406469449;5659.434056594308;5696.401877802937;5399.272232885439;5711.771529138788;5718.742656580381;5739.67543145027;5767.480926716616;5588.552915766811;5685.800996965087;5706.0898941540945;5708.172315014383;5713.457221563945 16:37:29 INFO - PROCESS | 1670 | |5;yui.html;638.9456896163825;740.796281022719;747.353539188602;814.8248126652772;843.5697794124998;15488.457903973363;15867.52397428079;16275.348986040544;16285.75571366429;16802.99182049078;14417.495387661407;14468.493602723907;14596.270186490687;14604.780928286058;14721.631959200999;705.2348202200618;730.167608925825;731.2650785960103;876.5529579914257;895.3598177303227;704.9823754406156;707.7593618169822;874.0822136756381;900.3427497926517;909.0318810466865;945.2674177512448;949.378157306965;951.2295041016764;951.3388195204301;953.2469349214147;946.1673727120091;947.6493697332031;949.1694767078806;949.5299826585849;950.1021534649759;552.1717423864204;568.6559631423012;569.8347479231018;571.9742611582489;686.8969654551807;12916.612501624966;15462.922685386613;15566;15575.143367114804;15590.766138507932;12506.81239781404;12881.871181288161;13329.86670133296;14249.931255155905;14415.711685766331;10642.893571064267;12339.876601233962;12489.812652810215;12683.746325073447;13091.410886510035;4539.568740969603;4709.905801883943;4797.511515224039;4809.254565542346;4811.085893680224;80164.59917700374;113906;114123.42938285338;114126.85873141246;115189.4240528792;11175.44122793857;11353.886461135447;11494.310341379376;11498.195126341243;11823.645290641294;22921;22933.082676692746;22939;22945;23017.424564386023;7936.16670249627;8253.587320633944;8270.503769773712;8319.459235149696;8350.457220280783;4481.148581769454;4502.414686090853;4639.884002899955;4648.581627653527;4650.86047418578;3503.2468019375447;3622.836972336251;3633.7456378053284;3637.727170462226;3653.5798383185743;645.4320198225531;653.4543656047254;660.4353277947382;678.7488629207224;678.8845896197658;497.8456678429626;620.7020630097578;622.7228883146956;629.408356145222;636.096742625473;7901.972743543302;8714.869276960851;8895.510746908982;8932.330075244383;9006.59470323837;602.4999250622061;625.1497962770628;629.515273239603;634.2230767310008;642.6433329502078;4119.505659320841;4323.891902702395;4343.5656434356315;4385;4402.185595664812;170749;172271;172814.13592931954;173060;173690;110672.44663776629;116007;124009.75990240162;147238.26380868026;150612.49387505985;84540;89501;93872;115510;116121;9637.277204209571;9794.314397992071;9902.257330700082;9924.801503970024;9988.450635214842;57985;63476;65215.34784652093;65817.67091164704;66147;92015;97292.0270797283;97845;98296;99358.5032074835;13179.538716144889;13335;13481.393337300042;13562.254076025913;14303.642408939444;14987.625309367353;15282.923585382003;15619.395864866387;15747.921260393625;15821.446249381217;13257.933710331386;13892.722145557234;14071.507497237548;14076;14082.366293517021;13328.133671311334;13595.660108497366;13885.722285554435;14038.578842634737;14103.647408814863;13695.589132326046;13939.233342166279;14173;14380.28098595087;14382.28088595587;10953.45232738344;11158.330500170016;11303.378314192469;11350.94324528372;11450.198486106226;10490.370577765358;10539.894601054199;10651;10762.300450470695;11583 16:37:29 INFO - PROCESS | 1670 | __end_tp_report 16:37:29 INFO - PROCESS | 1670 | __start_cc_report 16:37:29 INFO - PROCESS | 1670 | _x_x_mozilla_cycle_collect,1377 16:37:29 INFO - PROCESS | 1670 | __end_cc_report 16:37:29 INFO - PROCESS | 1670 | __startTimestamp1468280249417__endTimestamp 16:37:29 INFO - PROCESS | 1670 | 16:37:29 INFO - PROCESS | 1670 | ------- Summary: start ------- 16:37:29 INFO - PROCESS | 1670 | Number of tests: 6 16:37:29 INFO - PROCESS | 1670 | 16:37:29 INFO - PROCESS | 1670 | [#0] dojo.html Cycles:165 Average:5742.71 Median:6019.22 stddev:641.41 (10.7%) stddev-sans-first:611.77 16:37:29 INFO - PROCESS | 1670 | Values: 3207.8 3361.5 3380.7 3414.2 3451.8 5618.3 5656.9 5710.9 5720.4 5802.6 5172.7 5529.5 5740.6 5760.1 5842.9 5116.9 5149.5 5200.5 5220.0 5298.5 5263.7 5292.4 5301.4 5327.4 5344.9 5166.3 5185.7 5195.4 5213.9 5257.6 5240.8 5243.8 5244.7 5246.7 5249.2 5122.1 5127.2 5163.1 5209.2 5213.0 5647.3 5671.2 5701.9 5787.4 5819.2 6203.7 6226.8 6257.9 6289.3 6303.9 6131.0 6142.9 6270.0 6276.0 6323.2 4951.6 5849.2 5850.4 5883.7 5920.3 6269.5 6279.8 6284.6 6288.2 6300.1 5060.2 6018.6 6109.2 6127.9 6128.0 6154.3 6205.4 6207.4 6232.9 6257.7 5422.8 6132.4 6250.7 6256.2 6266.3 6223.3 6245.8 6286.7 6296.3 6298.6 4847.9 4887.1 4900.9 4962.4 5006.1 4373.7 4441.4 4455.1 4486.3 4501.9 5733.4 5764.9 5776.1 5778.2 5779.2 6165.0 6206.0 6237.2 6251.8 6260.1 5561.6 5831.3 5833.8 5836.1 5838.8 5392.8 5394.2 5427.5 5428.4 5432.1 6134.0 6216.1 6290.4 6302.4 6322.2 6266.2 6268.0 6280.7 6292.8 6304.7 6177.8 6327.8 6328.1 6333.3 6335.3 6088.6 6105.5 6106.9 6147.3 6156.8 6229.7 6230.0 6238.8 6246.4 6252.1 5857.8 5979.6 6082.5 6097.1 6109.6 6028.7 6088.2 6106.5 6139.5 6172.7 6031.3 6055.4 6074.6 6082.6 6106.2 6019.2 6120.2 6181.6 6191.8 6201.0 5895.7 5968.6 6025.2 6033.0 6050.4 16:37:29 INFO - PROCESS | 1670 | 16:37:29 INFO - PROCESS | 1670 | [#1] ext.html Cycles:180 Average:67020.56 Median:373892.07 stddev:145405.60 (38.9%) stddev-sans-first:145727.67 16:37:29 INFO - PROCESS | 1670 | Values: 478.5 507.9 526.6 531.7 533.0 29407.0 29764.1 31414.1 31440.2 31517.8 41903.8 42965.6 43092.0 45480.8 45511.8 9711.8 9865.8 9872.1 9875.3 9900.9 8733.6 8848.4 9701.8 9819.3 9824.2 8949.4 9002.9 9012.0 11876.6 12141.5 11997.0 12038.9 12063.1 12067.5 12084.2 11875.9 11960.5 11964.6 11979.8 11993.6 31333.5 39798.8 39823.0 40208.6 40277.2 3218.8 3479.8 4189.8 4200.1 4213.3 32104.8 32327.0 32830.2 32948.7 33036.8 3174.1 3409.4 3422.0 3440.3 3445.2 372308.0 372599.0 373827.0 373957.1 375233.0 57070.1 58094.0 58095.0 58135.2 58227.0 62459.1 62541.0 62616.4 62635.7 62659.7 16295.4 16544.7 16547.0 16554.8 16565.8 12892.9 12959.4 12968.9 12977.0 12977.2 13746.4 14449.5 15662.5 15679.3 15714.1 5651.6 6383.9 6642.4 7032.7 7075.4 913.7 951.1 1019.7 1049.6 1086.2 28980.7 29773.4 29828.4 30262.5 30420.5 1044.1 1073.2 1081.4 1099.4 1157.3 14854.9 15657.9 15760.7 15862.8 16000.8 595072.1 602465.0 602601.0 602790.0 606352.0 51028.2 51565.2 51566.7 56459.7 58010.0 1288.5 1316.7 1317.3 1394.8 1421.0 23640.4 30819.7 31270.5 31741.4 32034.8 48865.5 56545.7 57576.4 57770.8 61616.1 609696.0 622798.0 623047.0 623485.0 624701.0 23287.7 23878.0 23890.5 24616.3 26040.3 40510.4 43197.4 48718.5 48827.8 50376.7 38960.6 40415.8 40759.6 41345.2 41422.8 39445.0 39520.8 39599.0 40716.6 40849.2 38166.6 38503.8 38817.8 39130.8 39189.6 27267.0 33640.2 34367.8 34856.7 37324.8 25230.6 25304.6 25714.0 26116.0 26663.0 16:37:29 INFO - PROCESS | 1670 | 16:37:29 INFO - PROCESS | 1670 | [#2] jquery.html Cycles:180 Average:34795.07 Median:23136.48 stddev:96925.69 (418.9%) stddev-sans-first:97163.10 16:37:29 INFO - PROCESS | 1670 | Values: 359.8 382.6 391.9 393.5 410.1 14185.5 15474.8 21073.7 21098.0 21364.8 17975.0 21099.7 21419.5 21587.8 21813.1 5652.0 5849.7 5941.6 6177.1 6202.4 5967.3 6016.3 6083.8 6086.4 6091.8 5695.0 5874.8 5901.3 5905.6 6024.6 5681.2 5904.4 5938.0 5950.2 5967.9 5127.2 5157.5 5160.3 5164.4 5176.8 25841.0 26014.1 26122.2 26739.3 26764.3 2202.1 2233.0 2267.4 2505.3 2890.9 15280.8 15956.9 16425.9 16453.8 16809.8 1909.6 1916.7 1964.0 1965.6 2045.3 80773.0 104080.5 105048.0 116973.4 118231.2 11015.9 11048.0 11178.2 11190.7 11375.2 13649.7 13807.6 13837.5 13840.7 13870.6 8556.2 8687.7 8746.2 8767.6 8812.3 8751.2 8756.9 8758.3 8863.4 8886.3 6643.5 6735.5 6739.1 6749.8 6769.1 1533.7 1743.5 1858.6 1895.5 1896.4 844.3 881.0 905.5 943.2 987.5 17239.2 21963.0 23116.7 23132.4 23140.5 957.0 1006.2 1033.1 1069.8 1080.1 11464.7 11541.0 11665.0 11902.5 12169.3 497531.0 593072.0 594279.0 602934.0 605021.0 104498.0 130531.0 140235.0 140561.0 142843.3 982.2 1002.1 1015.5 1015.8 1024.0 19366.4 19370.5 19672.9 19707.4 19968.6 58050.7 59788.0 60325.4 60529.4 60786.0 63265.4 84292.4 87973.0 88292.6 90036.5 7993.8 8014.6 8045.4 8073.2 8257.9 9870.9 12503.8 12753.3 12760.7 12830.5 11062.0 11129.9 11153.9 11181.7 11283.4 8763.2 10827.7 10873.5 11049.4 11171.2 8697.5 11382.9 11398.3 11487.2 11606.7 7980.6 8001.0 8233.1 8233.9 8313.5 10180.7 10993.3 11216.7 11324.1 11343.8 16:37:29 INFO - PROCESS | 1670 | 16:37:29 INFO - PROCESS | 1670 | [#3] mootools.html Cycles:180 Average:5691.73 Median:5102.46 stddev:3165.68 (62.0%) stddev-sans-first:3159.34 16:37:29 INFO - PROCESS | 1670 | Values: 1560.8 1567.3 1575.3 1919.2 1961.2 8397.7 8650.3 8827.8 8832.7 8835.8 7733.1 7980.2 8348.3 8570.4 8633.6 2340.6 2376.1 2380.3 2380.6 2380.7 2331.4 2336.0 2368.9 2371.7 2376.5 2069.0 2147.2 2286.7 2356.2 2356.6 2339.0 2346.1 2367.0 2368.1 2369.7 2003.8 2329.6 2361.6 2364.5 2367.7 8728.3 8821.7 8833.7 8842.5 8846.3 5138.0 5283.8 5284.2 5292.6 5320.3 3522.5 3542.9 3579.0 3611.5 3612.4 894.9 1089.3 1092.3 1103.1 1110.7 11416.6 11418.0 11422.4 11441.4 11480.9 4515.1 4516.6 4715.3 4716.1 4724.1 9722.0 9733.9 9736.5 9743.3 9749.9 5246.2 5248.6 5252.3 5255.9 5266.0 4691.4 4809.1 4811.8 4816.8 4835.8 3881.6 3901.4 3931.2 3982.2 4000.9 1013.5 1076.0 1216.0 1245.9 1264.4 683.2 898.0 934.8 938.9 969.5 5934.3 5996.3 6131.2 6178.8 6212.6 920.8 957.3 961.5 961.9 1011.1 3352.2 3395.1 3889.2 4050.0 4105.8 9949.6 10689.0 10762.2 10766.2 10781.2 9699.3 9906.2 10578.3 10774.2 10815.5 7895.6 8273.8 8280.8 8285.3 8327.2 6021.7 6045.9 6206.7 6215.4 6306.6 9631.9 9825.6 10019.5 10031.0 10094.3 9865.7 10154.0 10156.2 10197.5 10220.5 7030.6 7264.0 7598.6 7604.8 7685.8 8369.6 8410.7 8477.7 8488.0 8507.3 7245.1 7300.6 7421.7 7484.4 7627.6 7310.3 7395.1 7416.3 7533.5 7550.6 7049.5 7088.9 7104.7 7106.7 7120.5 4759.6 4959.3 4989.7 5000.5 5007.5 4908.1 5097.4 5107.5 5158.9 5252.8 16:37:29 INFO - PROCESS | 1670 | 16:37:29 INFO - PROCESS | 1670 | [#4] prototype.html Cycles:180 Average:4552.10 Median:5027.77 stddev:1411.87 (28.1%) stddev-sans-first:1378.77 16:37:29 INFO - PROCESS | 1670 | Values: 270.3 296.4 309.5 319.4 321.9 4942.8 5106.4 5290.8 5427.8 5566.7 5430.7 5464.9 5466.5 5475.3 5503.8 3392.5 3793.0 3936.9 4133.3 4188.9 3876.6 4040.9 4115.3 4139.6 4179.7 3664.7 4045.5 4108.4 4111.7 4114.9 3078.8 3459.7 3661.6 3881.4 4154.3 3574.4 3597.8 3604.4 3617.9 3625.8 4481.8 4753.0 4979.8 5209.9 5370.5 3672.9 4111.8 4377.6 4531.5 4674.6 4782.1 4976.4 5024.1 5631.4 5653.5 3270.3 3737.5 3880.9 3905.6 3910.9 4984.6 5738.9 6003.5 6030.6 6041.7 3362.7 3920.9 3940.0 3945.3 3959.6 4022.4 4022.6 4028.2 4049.7 4052.4 4313.6 4438.3 4588.7 4729.9 4766.5 4622.7 5031.4 5083.6 5503.5 5823.2 1169.1 1336.9 1358.7 1378.8 1380.5 784.1 791.8 846.4 858.5 873.6 4811.6 4872.8 5021.9 5208.8 5228.5 4801.8 5900.1 5902.1 5904.2 5919.5 4945.6 5361.7 5371.9 5387.0 5387.4 2939.2 3096.3 3102.0 3110.7 3121.7 5048.3 5362.7 5871.6 6020.0 6113.7 5004.9 5625.7 5704.4 5971.9 5973.8 4594.7 5535.6 5922.4 5978.7 5993.4 4973.0 5561.6 5776.7 5857.0 5864.7 5396.4 5782.9 5833.9 5852.6 5899.6 5200.3 6043.5 6055.4 6129.1 6156.3 3276.9 3848.9 3861.5 3899.7 3906.8 4634.8 5090.3 5134.7 5282.7 5515.1 4663.5 5299.7 5461.7 5617.7 5658.9 5424.9 5723.7 5732.5 5776.2 5801.4 4723.3 4844.6 5594.6 5659.4 5696.4 5399.3 5711.8 5718.7 5739.7 5767.5 5588.6 5685.8 5706.1 5708.2 5713.5 16:37:29 INFO - PROCESS | 1670 | 16:37:29 INFO - PROCESS | 1670 | [#5] yui.html Cycles:180 Average:25551.28 Median:3886.54 stddev:41612.87 (1070.7%) stddev-sans-first:41687.57 16:37:29 INFO - PROCESS | 1670 | Values: 638.9 740.8 747.4 814.8 843.6 15488.5 15867.5 16275.3 16285.8 16803.0 14417.5 14468.5 14596.3 14604.8 14721.6 705.2 730.2 731.3 876.6 895.4 705.0 707.8 874.1 900.3 909.0 945.3 949.4 951.2 951.3 953.2 946.2 947.6 949.2 949.5 950.1 552.2 568.7 569.8 572.0 686.9 12916.6 15462.9 15566.0 15575.1 15590.8 12506.8 12881.9 13329.9 14249.9 14415.7 10642.9 12339.9 12489.8 12683.7 13091.4 4539.6 4709.9 4797.5 4809.3 4811.1 80164.6 113906.0 114123.4 114126.9 115189.4 11175.4 11353.9 11494.3 11498.2 11823.6 22921.0 22933.1 22939.0 22945.0 23017.4 7936.2 8253.6 8270.5 8319.5 8350.5 4481.1 4502.4 4639.9 4648.6 4650.9 3503.2 3622.8 3633.7 3637.7 3653.6 645.4 653.5 660.4 678.7 678.9 497.8 620.7 622.7 629.4 636.1 7902.0 8714.9 8895.5 8932.3 9006.6 602.5 625.1 629.5 634.2 642.6 4119.5 4323.9 4343.6 4385.0 4402.2 170749.0 172271.0 172814.1 173060.0 173690.0 110672.4 116007.0 124009.8 147238.3 150612.5 84540.0 89501.0 93872.0 115510.0 116121.0 9637.3 9794.3 9902.3 9924.8 9988.5 57985.0 63476.0 65215.3 65817.7 66147.0 92015.0 97292.0 97845.0 98296.0 99358.5 13179.5 13335.0 13481.4 13562.3 14303.6 14987.6 15282.9 15619.4 15747.9 15821.4 13257.9 13892.7 14071.5 14076.0 14082.4 13328.1 13595.7 13885.7 14038.6 14103.6 13695.6 13939.2 14173.0 14380.3 14382.3 10953.5 11158.3 11303.4 11350.9 11450.2 10490.4 10539.9 10651.0 10762.3 11583.0 16:37:29 INFO - PROCESS | 1670 | -------- Summary: end -------- 16:37:29 INFO - PROCESS | 1670 | 16:37:30 INFO - TEST-INFO | 1670: exit 0 16:37:30 INFO - TEST-OK | dromaeo_css | took 1117230ms 16:37:30 INFO - TEST-START | kraken 16:37:30 INFO - operating with platform_type : mac_ 16:37:30 INFO - Initialising browser for kraken test... 16:37:30 INFO - TEST-INFO | started process 1681 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/tmp4NdZO6/profile http://localhost:49272/getInfo.html) 16:37:32 INFO - PROCESS | 1681 | __metrics Screen width/height:1600/1200 16:37:32 INFO - PROCESS | 1681 | colorDepth:24 16:37:32 INFO - PROCESS | 1681 | Browser inner width/height: 1024/689 16:37:32 INFO - PROCESS | 1681 | __metrics 16:37:32 INFO - PROCESS | 1681 | 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" 16:37:32 INFO - PROCESS | 1681 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:37:32 INFO - TEST-INFO | 1681: exit 0 16:37:32 INFO - Browser initialized. 16:37:32 INFO - Running cycle 1/1 for kraken test... 16:37:32 INFO - TEST-INFO | started process 1682 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/tmp4NdZO6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:37:45 INFO - PROCESS | 1682 | RSS: Main: 243961856 16:37:45 INFO - PROCESS | 1682 | 16:37:47 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 16:37:48 INFO - PROCESS | 1682 | RSS: Main: 294105088 16:37:48 INFO - PROCESS | 1682 | 16:37:51 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 16:37:51 INFO - PROCESS | 1682 | RSS: Main: 304185344 16:37:51 INFO - PROCESS | 1682 | 16:37:55 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 16:37:56 INFO - PROCESS | 1682 | RSS: Main: 391974912 16:37:56 INFO - PROCESS | 1682 | 16:37:58 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 16:37:58 INFO - PROCESS | 1682 | RSS: Main: 292589568 16:37:58 INFO - PROCESS | 1682 | 16:38:00 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:38:01 INFO - PROCESS | 1682 | RSS: Main: 296960000 16:38:01 INFO - PROCESS | 1682 | 16:38:04 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 16:38:04 INFO - PROCESS | 1682 | RSS: Main: 331984896 16:38:04 INFO - PROCESS | 1682 | 16:38:07 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 16:38:08 INFO - PROCESS | 1682 | RSS: Main: 332767232 16:38:08 INFO - PROCESS | 1682 | 16:38:11 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 16:38:11 INFO - PROCESS | 1682 | RSS: Main: 335118336 16:38:11 INFO - PROCESS | 1682 | 16:38:13 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:38:14 INFO - PROCESS | 1682 | RSS: Main: 313151488 16:38:14 INFO - PROCESS | 1682 | 16:38:16 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:38:16 INFO - PROCESS | 1682 | RSS: Main: 319766528 16:38:16 INFO - PROCESS | 1682 | 16:38:18 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:38:19 INFO - PROCESS | 1682 | RSS: Main: 317251584 16:38:19 INFO - PROCESS | 1682 | 16:38:22 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:38:23 INFO - PROCESS | 1682 | RSS: Main: 326987776 16:38:23 INFO - PROCESS | 1682 | 16:38:25 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:38:26 INFO - PROCESS | 1682 | RSS: Main: 329445376 16:38:26 INFO - PROCESS | 1682 | 16:38:28 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 16:38:28 INFO - PROCESS | 1682 | RSS: Main: 373256192 16:38:28 INFO - PROCESS | 1682 | 16:38:28 INFO - PROCESS | 1682 | __start_tp_report 16:38:28 INFO - PROCESS | 1682 | _x_x_mozilla_page_load 16:38:28 INFO - PROCESS | 1682 | _x_x_mozilla_page_load_details 16:38:28 INFO - PROCESS | 1682 | |i|pagename|runs| 16:38:28 INFO - PROCESS | 1682 | |0;ai-astar;102;113;113;111;111;112;113;112;115;153 16:38:28 INFO - PROCESS | 1682 | |1;audio-beat-detection;164;135;92;185;135;138;109;127;143;107 16:38:28 INFO - PROCESS | 1682 | |2;audio-dft;185;186;183;185;182;182;186;180;183;182 16:38:28 INFO - PROCESS | 1682 | |3;audio-fft;77;74;64;125;78;78;65;66;84;57 16:38:28 INFO - PROCESS | 1682 | |4;audio-oscillator;86;89;92;72;69;68;97;70;67;101 16:38:28 INFO - PROCESS | 1682 | |5;imaging-gaussian-blur;89;88;87;90;88;83;87;83;86;84 16:38:28 INFO - PROCESS | 1682 | |6;imaging-darkroom;94;98;100;114;93;93;107;146;148;94 16:38:28 INFO - PROCESS | 1682 | |7;imaging-desaturate;85;84;82;86;85;86;83;84;83;85 16:38:28 INFO - PROCESS | 1682 | |8;json-parse-financial;52;55;53;55;55;54;54;55;54;53 16:38:28 INFO - PROCESS | 1682 | |9;json-stringify-tinderbox;67;71;71;75;75;72;71;70;71;72 16:38:28 INFO - PROCESS | 1682 | |10;stanford-crypto-aes;82;83;82;86;83;83;83;75;77;96 16:38:28 INFO - PROCESS | 1682 | |11;stanford-crypto-ccm;138;137;136;136;135;133;138;136;232;138 16:38:28 INFO - PROCESS | 1682 | |12;stanford-crypto-pbkdf2;164;164;165;166;163;168;164;164;163;161 16:38:28 INFO - PROCESS | 1682 | |13;stanford-crypto-sha256-iterative;67;68;67;68;67;65;66;67;67;66 16:38:28 INFO - PROCESS | 1682 | __end_tp_report 16:38:28 INFO - PROCESS | 1682 | __start_cc_report 16:38:28 INFO - PROCESS | 1682 | _x_x_mozilla_cycle_collect,2032 16:38:28 INFO - PROCESS | 1682 | __end_cc_report 16:38:28 INFO - PROCESS | 1682 | __startTimestamp1468280308459__endTimestamp 16:38:28 INFO - PROCESS | 1682 | 16:38:28 INFO - PROCESS | 1682 | ------- Summary: start ------- 16:38:28 INFO - PROCESS | 1682 | Number of tests: 14 16:38:28 INFO - PROCESS | 1682 | 16:38:28 INFO - PROCESS | 1682 | [#0] ai-astar Cycles:10 Average:115.50 Median:113.00 stddev:13.63 (12.1%) stddev-sans-first:13.56 16:38:28 INFO - PROCESS | 1682 | Values: 102.0 113.0 113.0 111.0 111.0 112.0 113.0 112.0 115.0 153.0 16:38:28 INFO - PROCESS | 1682 | 16:38:28 INFO - PROCESS | 1682 | [#1] audio-beat-detection Cycles:10 Average:133.50 Median:140.50 stddev:27.42 (19.5%) stddev-sans-first:26.76 16:38:28 INFO - PROCESS | 1682 | Values: 164.0 135.0 92.0 185.0 135.0 138.0 109.0 127.0 143.0 107.0 16:38:28 INFO - PROCESS | 1682 | 16:38:28 INFO - PROCESS | 1682 | [#2] audio-dft Cycles:10 Average:183.40 Median:184.00 stddev:2.01 (1.1%) stddev-sans-first:2.05 16:38:28 INFO - PROCESS | 1682 | Values: 185.0 186.0 183.0 185.0 182.0 182.0 186.0 180.0 183.0 182.0 16:38:28 INFO - PROCESS | 1682 | 16:38:28 INFO - PROCESS | 1682 | [#3] audio-fft Cycles:10 Average:76.80 Median:75.50 stddev:18.85 (25.0%) stddev-sans-first:19.99 16:38:28 INFO - PROCESS | 1682 | Values: 77.0 74.0 64.0 125.0 78.0 78.0 65.0 66.0 84.0 57.0 16:38:28 INFO - PROCESS | 1682 | 16:38:28 INFO - PROCESS | 1682 | [#4] audio-oscillator Cycles:10 Average:81.10 Median:79.00 stddev:13.24 (16.8%) stddev-sans-first:13.92 16:38:28 INFO - PROCESS | 1682 | Values: 86.0 89.0 92.0 72.0 69.0 68.0 97.0 70.0 67.0 101.0 16:38:28 INFO - PROCESS | 1682 | 16:38:28 INFO - PROCESS | 1682 | [#5] imaging-gaussian-blur Cycles:10 Average:86.50 Median:87.50 stddev:2.46 (2.8%) stddev-sans-first:2.44 16:38:28 INFO - PROCESS | 1682 | Values: 89.0 88.0 87.0 90.0 88.0 83.0 87.0 83.0 86.0 84.0 16:38:28 INFO - PROCESS | 1682 | 16:38:28 INFO - PROCESS | 1682 | [#6] imaging-darkroom Cycles:10 Average:108.70 Median:93.00 stddev:21.30 (22.9%) stddev-sans-first:21.91 16:38:28 INFO - PROCESS | 1682 | Values: 94.0 98.0 100.0 114.0 93.0 93.0 107.0 146.0 148.0 94.0 16:38:28 INFO - PROCESS | 1682 | 16:38:28 INFO - PROCESS | 1682 | [#7] imaging-desaturate Cycles:10 Average:84.30 Median:85.00 stddev:1.34 (1.6%) stddev-sans-first:1.39 16:38:28 INFO - PROCESS | 1682 | Values: 85.0 84.0 82.0 86.0 85.0 86.0 83.0 84.0 83.0 85.0 16:38:28 INFO - PROCESS | 1682 | 16:38:28 INFO - PROCESS | 1682 | [#8] json-parse-financial Cycles:10 Average:54.00 Median:54.50 stddev:1.05 (1.9%) stddev-sans-first:0.83 16:38:28 INFO - PROCESS | 1682 | Values: 52.0 55.0 53.0 55.0 55.0 54.0 54.0 55.0 54.0 53.0 16:38:28 INFO - PROCESS | 1682 | 16:38:28 INFO - PROCESS | 1682 | [#9] json-stringify-tinderbox Cycles:10 Average:71.50 Median:71.50 stddev:2.32 (3.2%) stddev-sans-first:1.80 16:38:28 INFO - PROCESS | 1682 | Values: 67.0 71.0 71.0 75.0 75.0 72.0 71.0 70.0 71.0 72.0 16:38:28 INFO - PROCESS | 1682 | 16:38:28 INFO - PROCESS | 1682 | [#10] stanford-crypto-aes Cycles:10 Average:83.00 Median:83.00 stddev:5.58 (6.7%) stddev-sans-first:5.90 16:38:28 INFO - PROCESS | 1682 | Values: 82.0 83.0 82.0 86.0 83.0 83.0 83.0 75.0 77.0 96.0 16:38:28 INFO - PROCESS | 1682 | 16:38:28 INFO - PROCESS | 1682 | [#11] stanford-crypto-ccm Cycles:10 Average:145.90 Median:137.50 stddev:30.29 (22.0%) stddev-sans-first:32.00 16:38:28 INFO - PROCESS | 1682 | Values: 138.0 137.0 136.0 136.0 135.0 133.0 138.0 136.0 232.0 138.0 16:38:28 INFO - PROCESS | 1682 | 16:38:28 INFO - PROCESS | 1682 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.20 Median:164.00 stddev:1.87 (1.1%) stddev-sans-first:1.99 16:38:28 INFO - PROCESS | 1682 | Values: 164.0 164.0 165.0 166.0 163.0 168.0 164.0 164.0 163.0 161.0 16:38:28 INFO - PROCESS | 1682 | 16:38:28 INFO - PROCESS | 1682 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:67.00 stddev:0.92 (1.4%) stddev-sans-first:0.97 16:38:28 INFO - PROCESS | 1682 | Values: 67.0 68.0 67.0 68.0 67.0 65.0 66.0 67.0 67.0 66.0 16:38:28 INFO - PROCESS | 1682 | -------- Summary: end -------- 16:38:28 INFO - PROCESS | 1682 | 16:38:29 INFO - TEST-INFO | 1682: exit 0 16:38:29 INFO - TEST-OK | kraken | took 59403ms 16:38:29 INFO - SUITE-END | took 1176s 16:38:29 INFO - Completed test suite (00:19:36) 16:38:29 INFO - javascript benchmark 16:38:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3207.791493552919, 3361.4621660534317, 3380.729541636669, 3414.2317978454835, 3451.7583769136154, 5618.297712785902, 5656.858578535539, 5710.885782284352, 5720.399358067405, 5802.622829516078, 5172.741362931857, 5529.474699903512, 5740.626859254156, 5760.0783874580075, 5842.941570584293, 5116.8976620467565, 5149.536541711245, 5200.5059519345605, 5220.0, 5298.523132918037, 5263.6578622389425, 5292.444293349201, 5301.443348448415, 5327.413984461706, 5344.946550534483, 5166.302549155854, 5185.740712964374, 5195.428502864682, 5213.921791173136, 5257.605679574009, 5240.81657142002, 5243.764030618631, 5244.711540865251, 5246.658967167122, 5249.212618107276, 5122.103631864409, 5127.230915362688, 5163.1480805667015, 5209.244659524347, 5212.9739351303, 5647.265855438766, 5671.234383358235, 5701.857453563653, 5787.421257874179, 5819.214406055171, 6203.689815509207, 6226.750929962796, 6257.906131407987, 6289.308176100625, 6303.936960630427, 6131.018058544209, 6142.86713775973, 6270.028145637433, 6276.0, 6323.20959880015, 4951.579115775127, 5849.181114643953, 5850.414958504116, 5883.735231914574, 5920.348761636174, 6269.529785266032, 6279.843003924939, 6284.591501552384, 6288.151099601587, 6300.149479820166, 5060.215666571614, 6018.638881667114, 6109.236345456818, 6127.908081378783, 6128.0, 6154.323024467305, 6205.379462053758, 6207.441330280206, 6232.906506402408, 6257.687115644199, 5422.8373148805595, 6132.424381759672, 6250.749970001149, 6256.155419018465, 6266.310705822356, 6223.253209614784, 6245.843853903689, 6286.685665716787, 6296.307406185316, 6298.559100862987, 4847.927281090787, 4887.120318342661, 4900.901981960341, 4962.379702537183, 5006.07387633289, 4373.715708479002, 4441.422615060022, 4455.064436468313, 4486.349479325545, 4501.932471012938, 5733.369329373765, 5764.913526297109, 5776.075827867521, 5778.162166485836, 5779.24869766936, 6165.0, 6206.007038873759, 6237.220347456568, 6251.843703907348, 6260.123582698426, 5561.610687251934, 5831.329397119301, 5833.82498525045, 5836.124581312837, 5838.766449341979, 5392.811251606175, 5394.190871369247, 5427.538659213932, 5428.375736790241, 5432.076546987022, 6134.038624681888, 6216.129741836146, 6290.370962903673, 6302.401271879165, 6322.209723784527, 6266.216722909637, 6268.0, 6280.685965701605, 6292.811215663537, 6304.684765761602, 6177.7837775677635, 6327.810165695037, 6328.0824280478155, 6333.303336633059, 6335.334789847, 6088.634681918922, 6105.520399749632, 6106.855059071331, 6147.323794382703, 6156.753729850756, 6229.719662615284, 6230.0, 6238.844028899314, 6246.437820596168, 6252.124702541557, 5857.765689372548, 5979.641221526722, 6082.513398928164, 6097.146399503984, 6109.572329936862, 6028.668423236764, 6088.208532890696, 6106.491003209229, 6139.4781443577795, 6172.722227499684, 6031.276246850406, 6055.394460553909, 6074.564130623915, 6082.635041897501, 6106.236720409949, 6019.217501724923, 6120.16506959497, 6181.567290289817, 6191.752329906753, 6201.0, 5895.734691938787, 5968.582199246011, 6025.156478092983, 6032.969835150796, 6050.364711705383], "value": 5701.903947975718, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [478.47845848025656, 507.87049302427874, 526.5603221310208, 531.7447625139932, 532.9680219186849, 29407.0, 29764.107076787677, 31414.05757827269, 31440.213994650145, 31517.842410787915, 41903.79048104755, 42965.570344296546, 43092.0, 45480.77259613705, 45511.77244113783, 9711.805763884719, 9865.802683946353, 9872.111509964101, 9875.259355548327, 9900.851487227697, 8733.606987685569, 8848.380613357036, 9701.805963880719, 9819.26355523335, 9824.1649459796, 8949.418287811304, 9002.909970900273, 9011.954940225323, 11876.643700688994, 12141.45363458642, 11997.040236781037, 12038.939805301005, 12063.095267854858, 12067.456964436533, 12084.214526055779, 11875.94062029693, 11960.461779219955, 11964.581239656658, 11979.760404791856, 11993.64019079429, 31333.529997050064, 39798.801005994785, 39823.0, 40208.59791402107, 40277.194456110716, 3218.790778599407, 3479.791212527231, 4189.7695626740515, 4200.096979149497, 4213.346931225665, 32104.839475802706, 32327.0, 32830.17924551881, 32948.6705132948, 33036.834815825765, 3174.1112488503254, 3409.437442821931, 3422.024722953971, 3440.3119376124623, 3445.155936795501, 372308.0, 372599.0, 373827.0, 373957.1302143499, 375233.0, 57070.143947840814, 58094.0, 58095.0, 58135.18648135153, 58227.0, 62459.06311405332, 62541.0, 62616.37383626196, 62635.6868215656, 62659.6867015662, 16295.429659961845, 16544.6691066178, 16547.007179569264, 16554.751678724828, 16565.83434165667, 12892.935535322264, 12959.352032398343, 12968.935155324163, 12977.0, 12977.183194352798, 13746.381412836447, 14449.49426770058, 15662.530124096067, 15679.294431750599, 15714.057156570643, 5651.632643878134, 6383.946648802988, 6642.43539299165, 7032.683529241196, 7075.433965282765, 913.6939125392935, 951.1297163095828, 1019.6533178719271, 1049.574922156528, 1086.1690806533009, 28980.710192898223, 29773.404531909673, 29828.403431931674, 30262.54606180909, 30420.54369184464, 1044.102072217892, 1073.2272763610188, 1081.3728037738092, 1099.4173088263233, 1157.311399717167, 14854.851451485347, 15657.903946723847, 15760.68478630421, 15862.783584245846, 16000.75998860018, 595072.1231969235, 602465.0, 602601.0, 602790.0, 606352.0, 51028.23457648138, 51565.226521602206, 51566.74216628968, 56459.71770141123, 58010.0, 1288.4781663426327, 1316.7498175346655, 1317.3347459532874, 1394.8256467941305, 1420.9644758880697, 23640.408989775395, 30819.69180308168, 31270.53094203543, 31741.36517269688, 32034.839825800722, 48865.511344886094, 56545.71727141502, 57576.424235757106, 57770.84458310894, 61616.07575886455, 609696.0, 622798.0, 623047.0, 623485.0, 624701.0, 23287.650685240074, 23878.044878204677, 23890.522189555762, 24616.261512154662, 26040.34899127537, 40510.39234411427, 43197.352039720055, 48718.5128148714, 48827.779305517644, 50376.74811625919, 38960.610393895695, 40415.797921010206, 40759.59240407558, 41345.1730965385, 41422.75731728053, 39445.0, 39520.802395987834, 39599.0, 40716.592834072464, 40849.1830163401, 38166.6183338163, 38503.807480962416, 38817.805910970266, 39130.80434597809, 39189.608103918596, 27267.0, 33640.17318080684, 34367.82816085903, 34856.65143348635, 37324.81337593294, 25230.61231632278, 25304.62043069392, 25714.0, 26116.0, 26663.0], "value": 19952.49862468544, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.79311895660004, 382.63076131533074, 391.89222963684983, 393.4668524149777, 410.06300603121855, 14185.50350737723, 15474.845251547482, 21073.68389474157, 21098.0, 21364.75670054645, 17975.0, 21099.6835047474, 21419.464513387175, 21587.784122158773, 21813.127474900986, 5652.0, 5849.713063126105, 5941.554383421249, 6177.135201071831, 6202.4417802397775, 5967.313758917737, 6016.308124565688, 6083.847903802396, 6086.360932102132, 6091.78678746244, 5695.0, 5874.794382196624, 5901.291844978608, 5905.645661260317, 6024.608400453978, 5681.176229446707, 5904.3800400957925, 5937.970310148443, 5950.226470558843, 5967.85080372992, 5127.2052831811125, 5157.48425157485, 5160.25176349431, 5164.406093299243, 5176.818811341623, 25841.0, 26014.089506867167, 26122.216333510027, 26739.33151671224, 26764.330891727666, 2202.0971401725214, 2232.9776702232934, 2267.353804165821, 2505.3486093615793, 2890.942181156386, 15280.847191528053, 15956.920215398848, 16425.85019048667, 16453.753193702105, 16809.831901680947, 1909.608530251309, 1916.7124931260282, 1964.0376215654226, 1965.6461836869357, 2045.2739277556402, 80773.0, 104080.47959760229, 105048.0, 116973.41513292464, 118231.2265316021, 11015.906977674216, 11048.005679488724, 11178.161637877192, 11190.720231994264, 11375.20373573842, 13649.679554475762, 13807.585772426843, 13837.51568695111, 13840.723185536433, 13870.583882483541, 8556.229939305367, 8687.740277659705, 8746.169113934166, 8767.561621918878, 8812.250958668457, 8751.212390884852, 8756.8686469703, 8758.255548278468, 8863.379563430626, 8886.333524985652, 6643.534952553371, 6735.461163106939, 6739.123913891162, 6749.763758268536, 6769.05233267343, 1533.6625942292678, 1743.5118166913285, 1858.6189831084596, 1895.4882181810833, 1896.3647178195458, 844.3456321351002, 881.0, 905.4612505559181, 943.2265542255285, 987.4766373821886, 17239.22485967215, 21963.0, 23116.65325020126, 23132.421689457897, 23140.53718925612, 956.9617215311449, 1006.2101250518308, 1033.1166852341291, 1069.8341757027674, 1080.114306268852, 11464.713382165513, 11541.0, 11665.0, 11902.464389102512, 12169.33068681231, 497531.0, 593072.0, 594279.0, 602934.0, 605021.0, 104498.0, 130531.0, 140235.0, 140561.0, 142843.2857835704, 982.2044144243185, 1002.0981116994762, 1015.4719545836124, 1015.7866847961863, 1023.9795204095876, 19366.41658981592, 19370.515737106685, 19672.90163549173, 19707.40877773669, 19968.60062798765, 58050.709746451, 59788.0, 60325.39674603198, 60529.39470605238, 60786.0, 63265.36734632595, 84292.36391998499, 87973.0, 88292.5585372069, 90036.54981725311, 7993.800154996172, 8014.639341229776, 8045.356371490384, 8073.15231900642, 8257.876131858147, 9870.901290986998, 12503.812442813183, 12753.298568578817, 12760.6809829755, 12830.486780528674, 11062.004419602274, 11129.888701112885, 11153.88846111561, 11181.720456988314, 11283.379414131974, 8763.211310982042, 10827.729306767395, 10873.510692018719, 11049.447527623426, 11171.218014739216, 8697.521636310064, 11382.94308528452, 11398.316101033965, 11487.195896287514, 11606.70983225426, 7980.600969951595, 8000.999875015623, 8233.094359620318, 8233.917660823316, 8313.459625124347, 10180.745481363025, 10993.340399576051, 11216.66350009501, 11324.094072474347, 11343.829842552203], "value": 9855.639866439189, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1560.836112208218, 1567.3182165757896, 1575.2911189964516, 1919.165163154029, 1961.2449207055279, 8397.748067557975, 8650.26472749817, 8827.779305517357, 8832.690855820048, 8835.823283534342, 7733.0720313562315, 7980.241877021674, 8348.33213342931, 8570.442921210117, 8633.611487483062, 2340.555294494045, 2376.0733314007553, 2380.2502211803226, 2380.6310021946624, 2380.690510233676, 2331.440454290975, 2335.9532809343887, 2368.917087901924, 2371.6679664846934, 2376.489054853206, 2068.979310206894, 2147.1733382647717, 2286.6798648189265, 2356.1871154451774, 2356.634721618152, 2339.0409931927998, 2346.061575369857, 2367.005857539829, 2368.0764501844296, 2369.668246445499, 2003.8196562309463, 2329.603967325542, 2361.563110824503, 2364.5270945810908, 2367.7040369953756, 8728.258098061673, 8821.691240806542, 8833.690820821304, 8842.46945183291, 8846.292296616255, 5138.0, 5283.788648454057, 5284.180951952453, 5292.603054770869, 5320.281761962125, 3522.5244591980013, 3542.91142721434, 3578.982105089458, 3611.4582812578324, 3612.422012478017, 894.932880034, 1089.3028461784463, 1092.2627226621998, 1103.1395511501041, 1110.6945589962745, 11416.60041898538, 11418.0, 11422.371769552672, 11441.427928603536, 11480.885191148149, 4515.119551687455, 4516.593506584423, 4715.292706094114, 4716.127516409475, 4724.149653062482, 9722.027797220222, 9733.853992190123, 9736.464494452732, 9743.317967742332, 9749.9025009749, 5246.160614301693, 5248.580113590903, 5252.317198764136, 5255.894882102337, 5265.9736701317015, 4691.366665500113, 4809.062232864558, 4811.759412029384, 4816.759162041884, 4835.758212089381, 3881.6118388160958, 3901.4147877817986, 3931.2137572485617, 3982.203559288154, 4000.9399859002137, 1013.4881884648205, 1075.9784804304027, 1215.9574414895437, 1245.872485400714, 1264.3930913161735, 683.2177157155129, 897.9506127162938, 934.8270569944582, 938.9248860091176, 969.4730798991617, 5934.258217722785, 5996.310424301174, 6131.233595800525, 6178.783742569079, 6212.627242365382, 920.8388532007006, 957.3346524165638, 961.5240605670263, 961.918236949853, 1011.1051719228388, 3352.2122301259183, 3395.1172695099453, 3889.2221555688975, 4049.97588708189, 4105.753654780723, 9949.602015919427, 10689.0, 10762.192835537366, 10766.24636275469, 10781.245312828027, 9699.321047526606, 9906.157976571927, 10578.259521833552, 10774.19193560486, 10815.459227038775, 7895.60521973899, 8273.751787446387, 8280.751577452686, 8285.337173026264, 8327.167283271625, 6021.729022193924, 6045.879082418415, 6206.689665516616, 6215.378462153749, 6306.621602703853, 9631.855522167032, 9825.606975720892, 10019.54912028975, 10031.047050530187, 10094.343867648578, 9865.654702085392, 10154.0, 10156.238282129018, 10197.541110650187, 10220.540075696761, 7030.578165310098, 7264.0, 7598.620068996417, 7604.8479030420185, 7685.807854803675, 8369.581520924052, 8410.663573456994, 8477.660893564189, 8487.957560212159, 8507.276881464897, 7245.058142441449, 7300.56196628204, 7421.666025028779, 7484.438667100098, 7627.580483073484, 7310.268973102648, 7395.075615548057, 7416.3325300721035, 7533.472656913963, 7550.584717840571, 7049.541779784297, 7088.858222835617, 7104.715811367488, 7106.7157313706875, 7120.501564890408, 4759.571638552547, 4959.280904268933, 4989.700617962933, 5000.524950129732, 5007.549320561167, 4908.141075311734, 5097.439281678938, 5107.489251074863, 5158.871028224325, 5252.763625636779], "value": 4573.859453194216, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [270.3498087100523, 296.35542694639156, 309.49861224815794, 319.43938388128845, 321.93883162199194, 4942.777575009129, 5106.4382917879, 5290.814821481249, 5427.837164885055, 5566.666000040001, 5430.67415955042, 5464.890702185954, 5466.480684334992, 5475.262368815592, 5503.834884953448, 3392.508086327481, 3793.0, 3936.9015774605577, 4133.3386658134705, 4188.937165942513, 3876.612338766129, 4040.919181616366, 4115.300398932174, 4139.627433530982, 4179.749215047092, 3664.6518580734787, 4045.5347635022054, 4108.404281379198, 4111.650509706678, 4114.876553703393, 3078.7844850860447, 3459.6713312235297, 3661.6338366163436, 3881.4371916072155, 4154.273002224599, 3574.3566158091535, 3597.8201089945396, 3604.3512167809786, 3617.8733744319084, 3625.818709064536, 4481.753503557303, 4753.001869607387, 4979.775910084055, 5209.921851172236, 5370.516653501165, 3672.908177295589, 4111.794410279504, 4377.649788016951, 4531.456225252958, 4674.649401294916, 4782.13921494131, 4976.402831660188, 5024.120778863684, 5631.352394474647, 5653.4911857932575, 3270.3295824356187, 3737.458068580068, 3880.941785873214, 3905.5703872573995, 3910.882673519799, 4984.626153038536, 5738.938296415135, 6003.519718422515, 6030.612959019362, 6041.697915104227, 3362.6637336266667, 3920.9019774505296, 3939.9606003939593, 3945.2504024235304, 3959.5644479107277, 4022.4368588397647, 4022.61785130418, 4028.2144981728284, 4049.6962727795535, 4052.4326594276827, 4313.590208930146, 4438.267685831866, 4588.7246765194195, 4729.881752956204, 4766.475687674353, 4622.65942876562, 5031.4213865405945, 5083.644144909821, 5503.532199762985, 5823.155642431823, 1169.0647482014353, 1336.8863646589955, 1358.6875018745545, 1378.800073989266, 1380.536631170962, 784.1413652050995, 791.7743443118684, 846.3863698818308, 858.4634603372892, 873.5806812729925, 4811.559734789642, 4872.756362181876, 5021.874453138701, 5208.801975545645, 5228.503292187313, 4801.839172372446, 5900.055991041413, 5902.055671092605, 5904.232449781501, 5919.477910643246, 4945.604351651859, 5361.731913404315, 5371.946280537223, 5387.0, 5387.434319396485, 2939.2277303681767, 3096.256898344426, 3101.984490077535, 3110.73558747509, 3121.7034381733697, 5048.334049763569, 5362.651427657268, 5871.647701137946, 6019.969900150471, 6113.724882380304, 5004.924926126111, 5625.662460252316, 5704.401037891044, 5971.940280597139, 5973.761049558056, 4594.724316541018, 5535.612507124543, 5922.423745670647, 5978.6548026693645, 5993.400659933972, 4972.975135124446, 5561.610687251854, 5776.711164441677, 5857.0, 5864.67744274069, 5396.433374495621, 5782.942170578409, 5833.883322333445, 5852.619579727304, 5899.557533184942, 5200.349956255468, 6043.486303664238, 6055.364186760503, 6129.141920131275, 6156.322804491413, 3276.868925243059, 3848.9415410761144, 3861.478700375413, 3899.7075219358094, 3906.785126817939, 4634.768261586974, 5090.312807771052, 5134.666246693953, 5282.683039017671, 5515.145152501369, 4663.463701674215, 5299.708516031501, 5461.672299662033, 5617.685565516365, 5658.915116273177, 5424.864378390572, 5723.656580605177, 5732.509547517759, 5776.162456443876, 5801.419858014165, 4723.291506274087, 4844.61243100544, 5594.580406469449, 5659.434056594308, 5696.401877802937, 5399.272232885439, 5711.771529138788, 5718.742656580381, 5739.67543145027, 5767.480926716616, 5588.552915766811, 5685.800996965087, 5706.0898941540945, 5708.172315014383, 5713.457221563945], "value": 4097.928955905551, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [638.9456896163825, 740.796281022719, 747.353539188602, 814.8248126652772, 843.5697794124998, 15488.457903973363, 15867.52397428079, 16275.348986040544, 16285.75571366429, 16802.99182049078, 14417.495387661407, 14468.493602723907, 14596.270186490687, 14604.780928286058, 14721.631959200999, 705.2348202200618, 730.167608925825, 731.2650785960103, 876.5529579914257, 895.3598177303227, 704.9823754406156, 707.7593618169822, 874.0822136756381, 900.3427497926517, 909.0318810466865, 945.2674177512448, 949.378157306965, 951.2295041016764, 951.3388195204301, 953.2469349214147, 946.1673727120091, 947.6493697332031, 949.1694767078806, 949.5299826585849, 950.1021534649759, 552.1717423864204, 568.6559631423012, 569.8347479231018, 571.9742611582489, 686.8969654551807, 12916.612501624966, 15462.922685386613, 15566.0, 15575.143367114804, 15590.766138507932, 12506.81239781404, 12881.871181288161, 13329.86670133296, 14249.931255155905, 14415.711685766331, 10642.893571064267, 12339.876601233962, 12489.812652810215, 12683.746325073447, 13091.410886510035, 4539.568740969603, 4709.905801883943, 4797.511515224039, 4809.254565542346, 4811.085893680224, 80164.59917700374, 113906.0, 114123.42938285338, 114126.85873141246, 115189.4240528792, 11175.44122793857, 11353.886461135447, 11494.310341379376, 11498.195126341243, 11823.645290641294, 22921.0, 22933.082676692746, 22939.0, 22945.0, 23017.424564386023, 7936.16670249627, 8253.587320633944, 8270.503769773712, 8319.459235149696, 8350.457220280783, 4481.148581769454, 4502.414686090853, 4639.884002899955, 4648.581627653527, 4650.86047418578, 3503.2468019375447, 3622.836972336251, 3633.7456378053284, 3637.727170462226, 3653.5798383185743, 645.4320198225531, 653.4543656047254, 660.4353277947382, 678.7488629207224, 678.8845896197658, 497.8456678429626, 620.7020630097578, 622.7228883146956, 629.408356145222, 636.096742625473, 7901.972743543302, 8714.869276960851, 8895.510746908982, 8932.330075244383, 9006.59470323837, 602.4999250622061, 625.1497962770628, 629.515273239603, 634.2230767310008, 642.6433329502078, 4119.505659320841, 4323.891902702395, 4343.5656434356315, 4385.0, 4402.185595664812, 170749.0, 172271.0, 172814.13592931954, 173060.0, 173690.0, 110672.44663776629, 116007.0, 124009.75990240162, 147238.26380868026, 150612.49387505985, 84540.0, 89501.0, 93872.0, 115510.0, 116121.0, 9637.277204209571, 9794.314397992071, 9902.257330700082, 9924.801503970024, 9988.450635214842, 57985.0, 63476.0, 65215.34784652093, 65817.67091164704, 66147.0, 92015.0, 97292.0270797283, 97845.0, 98296.0, 99358.5032074835, 13179.538716144889, 13335.0, 13481.393337300042, 13562.254076025913, 14303.642408939444, 14987.625309367353, 15282.923585382003, 15619.395864866387, 15747.921260393625, 15821.446249381217, 13257.933710331386, 13892.722145557234, 14071.507497237548, 14076.0, 14082.366293517021, 13328.133671311334, 13595.660108497366, 13885.722285554435, 14038.578842634737, 14103.647408814863, 13695.589132326046, 13939.233342166279, 14173.0, 14380.28098595087, 14382.28088595587, 10953.45232738344, 11158.330500170016, 11303.378314192469, 11350.94324528372, 11450.198486106226, 10490.370577765358, 10539.894601054199, 10651.0, 10762.300450470695, 11583.0], "value": 7938.2715459719075, "unit": "score"}], "name": "dromaeo_css", "value": 7419.710132400329, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 113.0, 113.0, 111.0, 111.0, 112.0, 113.0, 112.0, 115.0, 153.0], "value": 115.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 135.0, 92.0, 185.0, 135.0, 138.0, 109.0, 127.0, 143.0, 107.0], "value": 133.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 186.0, 183.0, 185.0, 182.0, 182.0, 186.0, 180.0, 183.0, 182.0], "value": 183.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 74.0, 64.0, 125.0, 78.0, 78.0, 65.0, 66.0, 84.0, 57.0], "value": 76.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 89.0, 92.0, 72.0, 69.0, 68.0, 97.0, 70.0, 67.0, 101.0], "value": 81.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 87.0, 90.0, 88.0, 83.0, 87.0, 83.0, 86.0, 84.0], "value": 86.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 98.0, 100.0, 114.0, 93.0, 93.0, 107.0, 146.0, 148.0, 94.0], "value": 108.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 82.0, 86.0, 85.0, 86.0, 83.0, 84.0, 83.0, 85.0], "value": 84.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 55.0, 53.0, 55.0, 55.0, 54.0, 54.0, 55.0, 54.0, 53.0], "value": 54.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 71.0, 71.0, 75.0, 75.0, 72.0, 71.0, 70.0, 71.0, 72.0], "value": 71.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 82.0, 86.0, 83.0, 83.0, 83.0, 75.0, 77.0, 96.0], "value": 83.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 137.0, 136.0, 136.0, 135.0, 133.0, 138.0, 136.0, 232.0, 138.0], "value": 145.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 164.0, 165.0, 166.0, 163.0, 168.0, 164.0, 164.0, 163.0, 161.0], "value": 164.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 68.0, 67.0, 65.0, 66.0, 67.0, 67.0, 66.0], "value": 66.8, "unit": "score"}], "name": "kraken", "value": 1455.2, "alertThreshold": 2.0}]} 16:38:29 INFO - Return code: 0 16:38:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:38:29 INFO - # TBPL SUCCESS # 16:38:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:38:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:38:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:38:29 INFO - 2016-07-11 16:38:29.781 screenresolution[1686:9861] starting screenresolution argv=screenresolution get 16:38:29 INFO - 2016-07-11 16:38:29.783 screenresolution[1686:9861] Display 0: 1600x1200x32@60 16:38:29 INFO - 2016-07-11 16:38:29.790 screenresolution[1687:9864] starting screenresolution argv=screenresolution list 16:38:29 INFO - Available Modes on Display 0 16:38:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:38:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:38:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:38:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:38:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:38:30 INFO - Intel Iris: 16:38:30 INFO - Chipset Model: Intel Iris 16:38:30 INFO - Type: GPU 16:38:30 INFO - Bus: Built-In 16:38:30 INFO - VRAM (Dynamic, Max): 1536 MB 16:38:30 INFO - Vendor: Intel (0x8086) 16:38:30 INFO - Device ID: 0x0a2e 16:38:30 INFO - Revision ID: 0x0009 16:38:30 INFO - Displays: 16:38:30 INFO - Display: 16:38:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:38:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:38:30 INFO - Display Serial Number: 1600x1200 60 16:38:30 INFO - Main Display: Yes 16:38:30 INFO - Mirror: Off 16:38:30 INFO - Online: Yes 16:38:30 INFO - Rotation: Supported 16:38:30 INFO - Return code: 0 16:38:30 INFO - Running post-action listener: _resource_record_post_action 16:38:30 INFO - ##### 16:38:30 INFO - ##### Finished run-tests step (success) 16:38:30 INFO - ##### 16:38:30 INFO - Running post-run listener: _resource_record_post_run 16:38:30 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 36935168; Write bytes: 701237760; Read time: 238; Write time: 1942 16:38:30 INFO - TinderboxPrint: CPU usage
53.4% 16:38:30 INFO - TinderboxPrint: I/O read bytes / time
36,935,168 / 238 16:38:30 INFO - TinderboxPrint: I/O write bytes / time
701,237,760 / 1,942 16:38:30 INFO - TinderboxPrint: CPU idle
2,257.3 (46.6%) 16:38:30 INFO - TinderboxPrint: CPU system
964.0 (19.9%) 16:38:30 INFO - TinderboxPrint: CPU user
1,624.3 (33.5%) 16:38:30 INFO - TinderboxPrint: Swap in / out
442,863,616 / 0 16:38:30 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 398123520; Write bytes: 389940736; Read time: 29801; Write time: 821 16:38:30 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 34969088; Write bytes: 291611648; Read time: 220; Write time: 1072 16:38:30 INFO - Running post-run listener: _upload_blobber_files 16:38:30 INFO - Blob upload gear active. 16:38:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:38:30 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 16:38:30 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:38:30 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:38:30 INFO - (blobuploader) - INFO - Open directory for files ... 16:38:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:38:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:38:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:38:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:38:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:38:31 INFO - (blobuploader) - INFO - Done attempting. 16:38:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:38:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:38:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:38:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:38:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:38:32 INFO - (blobuploader) - INFO - Done attempting. 16:38:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:38:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:38:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:38:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:38:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:38:35 INFO - (blobuploader) - INFO - Done attempting. 16:38:35 INFO - (blobuploader) - INFO - Iteration through files over. 16:38:35 INFO - Return code: 0 16:38:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:38:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:38:35 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6bd12a1172b7142e5930e79e75c60a2b5b309502d3e0f94181f5d9ae3a7c1e556ab2c61c6601b8135006805d3c16cc646ed9d0ba15f9f61dc312660eafddfeb6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f6220d0624b78d8de0ce25f3013b043496b1ce0fdfa8e97b27f11fa2606cf81202ae127ffc5c598ff974f0ba0ced83a253933a61a81e3152568aca1f6f05d4f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d0191f6499508e4775ba3aa7f67027fdf1c0a402c69fb979c88dbe4a1353f4687e677786a4d9e3dab3ec57383a14f58acdf080321228bdf7886da2201e6ab1e7"} 16:38:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:38:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:38:35 INFO - Contents: 16:38:35 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6bd12a1172b7142e5930e79e75c60a2b5b309502d3e0f94181f5d9ae3a7c1e556ab2c61c6601b8135006805d3c16cc646ed9d0ba15f9f61dc312660eafddfeb6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f6220d0624b78d8de0ce25f3013b043496b1ce0fdfa8e97b27f11fa2606cf81202ae127ffc5c598ff974f0ba0ced83a253933a61a81e3152568aca1f6f05d4f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d0191f6499508e4775ba3aa7f67027fdf1c0a402c69fb979c88dbe4a1353f4687e677786a4d9e3dab3ec57383a14f58acdf080321228bdf7886da2201e6ab1e7"} 16:38:35 INFO - Running post-run listener: copy_logs_to_upload_dir 16:38:35 INFO - Copying logs to upload dir... 16:38:35 INFO - mkdir: /builds/slave/test/build/upload/logs 16:38:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.035892 ========= master_lag: 0.14 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-07-11 16:38:35.422430) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-11 16:38:35.425439) ========= 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.ugw5I97XBl/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.TcqwOv0hSR/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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/Firefox/sha512/6bd12a1172b7142e5930e79e75c60a2b5b309502d3e0f94181f5d9ae3a7c1e556ab2c61c6601b8135006805d3c16cc646ed9d0ba15f9f61dc312660eafddfeb6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f6220d0624b78d8de0ce25f3013b043496b1ce0fdfa8e97b27f11fa2606cf81202ae127ffc5c598ff974f0ba0ced83a253933a61a81e3152568aca1f6f05d4f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d0191f6499508e4775ba3aa7f67027fdf1c0a402c69fb979c88dbe4a1353f4687e677786a4d9e3dab3ec57383a14f58acdf080321228bdf7886da2201e6ab1e7"} build_url:https://queue.taskcluster.net/v1/task/OxXFiZsaTDyi_O2A-X429w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011108 build_url: 'https://queue.taskcluster.net/v1/task/OxXFiZsaTDyi_O2A-X429w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6bd12a1172b7142e5930e79e75c60a2b5b309502d3e0f94181f5d9ae3a7c1e556ab2c61c6601b8135006805d3c16cc646ed9d0ba15f9f61dc312660eafddfeb6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f6220d0624b78d8de0ce25f3013b043496b1ce0fdfa8e97b27f11fa2606cf81202ae127ffc5c598ff974f0ba0ced83a253933a61a81e3152568aca1f6f05d4f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d0191f6499508e4775ba3aa7f67027fdf1c0a402c69fb979c88dbe4a1353f4687e677786a4d9e3dab3ec57383a14f58acdf080321228bdf7886da2201e6ab1e7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-11 16:38:35.458228) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 16:38:35.458533) ========= 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.ugw5I97XBl/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.TcqwOv0hSR/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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.004976 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 16:38:35.506901) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-11 16:38:35.507222) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-11 16:38:35.507524) ========= ========= Total master_lag: 0.27 =========