builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0310 starttime: 1470020159.61 results: success (0) buildid: 20160708030201 builduid: e85acada7d724f7a8e21fcd91df4f8ad revision: 45682df2d2d45e5a8385fd842579e661a4b60bc5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-31 19:55:59.611817) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-31 19:55:59.612178) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-31 19:55:59.612458) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-31 19:55:59.702937) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-31 19:55:59.703286) ========= 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.bCLqLExQ0F/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.SeZntOGvul/Listeners TMPDIR=/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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-31 19:55:59-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 152M=0s 2016-07-31 19:55:59 (152 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.189288 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-31 19:55:59.926387) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-31 19:55:59.926694) ========= 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.bCLqLExQ0F/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.SeZntOGvul/Listeners TMPDIR=/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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.024875 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-31 19:55:59.975260) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-31 19:55:59.975583) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 45682df2d2d45e5a8385fd842579e661a4b60bc5 --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 45682df2d2d45e5a8385fd842579e661a4b60bc5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bCLqLExQ0F/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.SeZntOGvul/Listeners TMPDIR=/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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-31 19:56:00,059 truncating revision to first 12 chars 2016-07-31 19:56:00,059 Setting DEBUG logging. 2016-07-31 19:56:00,059 attempt 1/10 2016-07-31 19:56:00,059 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/45682df2d2d4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-31 19:56:00,424 unpacking tar archive at: mozilla-central-45682df2d2d4/testing/mozharness/ program finished with exit code 0 elapsedTime=0.631862 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-31 19:56:00.635545) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-31 19:56:00.635817) ========= script_repo_revision: 45682df2d2d45e5a8385fd842579e661a4b60bc5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-31 19:56:00.636171) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-31 19:56:00.636451) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-31 19:56:00.658460) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2016-07-31 19:56:00.658764) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.bCLqLExQ0F/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.SeZntOGvul/Listeners TMPDIR=/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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 19:56:00 INFO - MultiFileLogger online at 20160731 19:56:00 in /builds/slave/test 19:56:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 19:56:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:56:00 INFO - {'append_to_log': False, 19:56:00 INFO - 'base_work_dir': '/builds/slave/test', 19:56:00 INFO - 'blob_upload_branch': 'Firefox', 19:56:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:56:00 INFO - 'branch': 'Firefox', 19:56:00 INFO - 'buildbot_json_path': 'buildprops.json', 19:56:00 INFO - 'config_files': ('talos/mac_config.py',), 19:56:00 INFO - 'default_actions': ('clobber', 19:56:00 INFO - 'read-buildbot-config', 19:56:00 INFO - 'download-and-extract', 19:56:00 INFO - 'populate-webroot', 19:56:00 INFO - 'create-virtualenv', 19:56:00 INFO - 'install', 19:56:00 INFO - 'run-tests'), 19:56:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:56:00 INFO - 'download_minidump_stackwalk': True, 19:56:00 INFO - 'download_symbols': 'ondemand', 19:56:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:56:00 INFO - 'tooltool.py': '/tools/tooltool.py', 19:56:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:56:00 INFO - '/tools/misc-python/virtualenv.py')}, 19:56:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:56:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:56:00 INFO - 'installer_path': 'installer.exe', 19:56:00 INFO - 'log_level': 'info', 19:56:00 INFO - 'log_name': 'talos', 19:56:00 INFO - 'log_to_console': True, 19:56:00 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:56:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:56:00 INFO - 'opt_config_files': (), 19:56:00 INFO - 'pip_index': False, 19:56:00 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:56:00 INFO - 'cmd': ('bash', 19:56:00 INFO - '-c', 19:56:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:56:00 INFO - 'enabled': True, 19:56:00 INFO - 'halt_on_failure': False, 19:56:00 INFO - 'name': 'check_screen_resolution'},), 19:56:00 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:56:00 INFO - 'cmd': ('bash', 19:56:00 INFO - '-c', 19:56:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:56:00 INFO - 'enabled': True, 19:56:00 INFO - 'halt_on_failure': False, 19:56:00 INFO - 'name': 'check_screen_resolution'},), 19:56:00 INFO - 'run_cmd_checks_enabled': True, 19:56:00 INFO - 'sps_profile': False, 19:56:00 INFO - 'sps_profile_interval': 0, 19:56:00 INFO - 'suite': 'dromaeojs-e10s', 19:56:00 INFO - 'system_bits': '32', 19:56:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:56:00 INFO - 'title': 't-yosemite-r7-0310', 19:56:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:56:00 INFO - 'use_talos_json': True, 19:56:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:56:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:56:00 INFO - 'work_dir': 'build'} 19:56:00 INFO - ##### 19:56:00 INFO - ##### Running clobber step. 19:56:00 INFO - ##### 19:56:00 INFO - Running pre-action listener: _resource_record_pre_action 19:56:00 INFO - Running main action method: clobber 19:56:00 INFO - rmtree: /builds/slave/test/build 19:56:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:56:02 INFO - Running post-action listener: _resource_record_post_action 19:56:02 INFO - ##### 19:56:02 INFO - ##### Finished clobber step (success) 19:56:02 INFO - ##### 19:56:02 INFO - ##### 19:56:02 INFO - ##### Running read-buildbot-config step. 19:56:02 INFO - ##### 19:56:02 INFO - Running pre-action listener: _resource_record_pre_action 19:56:02 INFO - Running main action method: read_buildbot_config 19:56:02 INFO - Using buildbot properties: 19:56:02 INFO - { 19:56:02 INFO - "project": "", 19:56:02 INFO - "product": "firefox", 19:56:02 INFO - "script_repo_revision": "production", 19:56:02 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 19:56:02 INFO - "repository": "", 19:56:02 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 19:56:02 INFO - "buildid": "20160708030201", 19:56:02 INFO - "stage_platform": "macosx64", 19:56:02 INFO - "basedir": "/builds/slave/test", 19:56:02 INFO - "nightly_build": "True", 19:56:02 INFO - "buildnumber": 14, 19:56:02 INFO - "slavename": "t-yosemite-r7-0310", 19:56:02 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 19:56:02 INFO - "platform": "yosemite_r7", 19:56:02 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 19:56:02 INFO - "branch": "mozilla-central", 19:56:02 INFO - "repo_path": "mozilla-central", 19:56:02 INFO - "revision": "45682df2d2d45e5a8385fd842579e661a4b60bc5", 19:56:02 INFO - "pgo_build": "False", 19:56:02 INFO - "builduid": "e85acada7d724f7a8e21fcd91df4f8ad", 19:56:02 INFO - "slavebuilddir": "test" 19:56:02 INFO - } 19:56:02 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467972121/firefox-50.0a1.en-US.mac.dmg. 19:56:02 INFO - Running post-action listener: _resource_record_post_action 19:56:02 INFO - ##### 19:56:02 INFO - ##### Finished read-buildbot-config step (success) 19:56:02 INFO - ##### 19:56:02 INFO - ##### 19:56:02 INFO - ##### Running download-and-extract step. 19:56:02 INFO - ##### 19:56:02 INFO - Running pre-action listener: _resource_record_pre_action 19:56:02 INFO - Running main action method: download_and_extract 19:56:02 INFO - mkdir: /builds/slave/test/build/tests 19:56: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')]} 19:56:02 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467972121/firefox-50.0a1.en-US.mac.test_packages.json 19:56:02 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467972121/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 19:56:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467972121/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 19:56:03 INFO - Downloaded 1199 bytes. 19:56:03 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 19:56:03 INFO - Using the following test package requirements: 19:56:03 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 19:56:03 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 19:56:03 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 19:56:03 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 19:56:03 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 19:56:03 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 19:56:03 INFO - u'jsshell-mac.zip'], 19:56:03 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 19:56:03 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 19:56:03 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 19:56:03 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 19:56:03 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 19:56:03 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 19:56:03 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 19:56:03 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 19:56:03 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 19:56:03 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 19:56:03 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 19:56:03 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:56:03 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')]} 19:56:03 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467972121/firefox-50.0a1.en-US.mac.common.tests.zip 19:56:03 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467972121/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 19:56:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467972121/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 19:56:03 INFO - Downloaded 18806412 bytes. 19:56:03 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'] 19:56:03 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 19:56:06 INFO - Return code: 0 19:56:06 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 19:56:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:56:06 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467972121/firefox-50.0a1.en-US.mac.common.tests.zip 19:56:06 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467972121/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 19:56:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467972121/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 19:56:07 INFO - Downloaded 18806412 bytes. 19:56:07 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'] 19:56:07 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 19:56:10 INFO - Return code: 0 19:56:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:56:10 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467972121/firefox-50.0a1.en-US.mac.talos.tests.zip 19:56:10 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467972121/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 19:56:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467972121/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 19:56:11 INFO - Downloaded 14403333 bytes. 19:56:11 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'] 19:56:11 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 19:56:12 INFO - Return code: 0 19:56: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')]} 19:56:12 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467972121/firefox-50.0a1.en-US.mac.dmg 19:56:12 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467972121/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 19:56:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467972121/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 19:56:13 INFO - Downloaded 112613152 bytes. 19:56:13 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467972121/firefox-50.0a1.en-US.mac.dmg 19:56:13 INFO - mkdir: /builds/slave/test/properties 19:56:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:56:13 INFO - Writing to file /builds/slave/test/properties/build_url 19:56:13 INFO - Contents: 19:56:13 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467972121/firefox-50.0a1.en-US.mac.dmg 19:56:13 INFO - Running post-action listener: _resource_record_post_action 19:56:13 INFO - Running post-action listener: set_extra_try_arguments 19:56:13 INFO - ##### 19:56:13 INFO - ##### Finished download-and-extract step (success) 19:56:13 INFO - ##### 19:56:13 INFO - ##### 19:56:13 INFO - ##### Running populate-webroot step. 19:56:13 INFO - ##### 19:56:13 INFO - Running pre-action listener: _resource_record_pre_action 19:56:13 INFO - Running main action method: populate_webroot 19:56:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:56:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:56:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:56:13 INFO - '--tppagecycles', 19:56:13 INFO - '7'], 19:56:13 INFO - 'tests': ['tsvgm']}}, 19:56:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:56:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:56:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:56:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:56:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:56:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:56:13 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:56:13 INFO - 'talos_options': ['--disable-e10s'], 19:56:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:56:13 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:56:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:56:13 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:56:13 INFO - 'talos_options': ['--disable-e10s'], 19:56:13 INFO - 'tests': ['damp', 'tps']}, 19:56:13 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:56:13 INFO - 'tests': ['damp', 'tps']}, 19:56:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:56:13 INFO - 'tests': ['dromaeo_dom']}, 19:56:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:56:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:56:13 INFO - 'tests': ['basic_compositor_video']}, 19:56:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:56:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:56:13 INFO - 'tests': ['a11yr', 19:56:13 INFO - 'ts_paint', 19:56:13 INFO - 'tpaint', 19:56:13 INFO - 'sessionrestore', 19:56:13 INFO - 'sessionrestore_no_auto_restore', 19:56:13 INFO - 'tabpaint']}, 19:56:13 INFO - 'other-e10s': {'tests': ['a11yr', 19:56:13 INFO - 'ts_paint', 19:56:13 INFO - 'tpaint', 19:56:13 INFO - 'sessionrestore', 19:56:13 INFO - 'sessionrestore_no_auto_restore', 19:56:13 INFO - 'tabpaint']}, 19:56:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:56:13 INFO - 'ts_paint', 19:56:13 INFO - 'tpaint', 19:56:13 INFO - 'sessionrestore', 19:56:13 INFO - 'sessionrestore_no_auto_restore', 19:56:13 INFO - 'tabpaint']}, 19:56:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:56:13 INFO - 'ts_paint', 19:56:13 INFO - 'tpaint', 19:56:13 INFO - 'sessionrestore', 19:56:13 INFO - 'sessionrestore_no_auto_restore', 19:56:13 INFO - 'tabpaint']}, 19:56:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:56:13 INFO - 'tests': ['a11yr', 19:56:13 INFO - 'ts_paint', 19:56:13 INFO - 'tpaint', 19:56:13 INFO - 'sessionrestore', 19:56:13 INFO - 'sessionrestore_no_auto_restore', 19:56:13 INFO - 'tabpaint']}, 19:56:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:56:13 INFO - 'tests': ['a11yr', 19:56:13 INFO - 'ts_paint', 19:56:13 INFO - 'tpaint', 19:56:13 INFO - 'sessionrestore', 19:56:13 INFO - 'sessionrestore_no_auto_restore', 19:56:13 INFO - 'tabpaint']}, 19:56:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:56:13 INFO - 'tests': ['tsvgx', 19:56:13 INFO - 'tsvgr_opacity', 19:56:13 INFO - 'tart', 19:56:13 INFO - 'tscrollx', 19:56:13 INFO - 'cart']}, 19:56:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:56:13 INFO - 'tsvgr_opacity', 19:56:13 INFO - 'tart', 19:56:13 INFO - 'tscrollx', 19:56:13 INFO - 'cart']}, 19:56:13 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:56:13 INFO - 'talos_options': ['--disable-e10s'], 19:56:13 INFO - 'tests': ['tp5o']}, 19:56:13 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:56:13 INFO - 'tests': ['tp5o']}, 19:56:13 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:56:13 INFO - 'talos_options': ['--disable-e10s', 19:56:13 INFO - '--xperf_path', 19:56:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:56:13 INFO - 'tests': ['tp5n']}, 19:56:13 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:56:13 INFO - 'talos_options': ['--xperf_path', 19:56:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:56:13 INFO - 'tests': ['tp5n']}}, 19:56:13 INFO - 'talos.zip': {'path': '', 19:56:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:56:13 INFO - Running post-action listener: _resource_record_post_action 19:56:13 INFO - ##### 19:56:13 INFO - ##### Finished populate-webroot step (success) 19:56:13 INFO - ##### 19:56:13 INFO - ##### 19:56:13 INFO - ##### Running create-virtualenv step. 19:56:13 INFO - ##### 19:56:13 INFO - Running pre-action listener: _resource_record_pre_action 19:56:13 INFO - Running main action method: create_virtualenv 19:56:13 INFO - Creating virtualenv /builds/slave/test/build/venv 19:56:13 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 19:56:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:56:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:56:13 INFO - Using real prefix '/tools/python27' 19:56:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:56:14 INFO - Installing distribute.............................................................................................................................................................................................done. 19:56:17 INFO - Installing pip.................done. 19:56:17 INFO - Return code: 0 19:56:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:56: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')]} 19:56:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:56:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:56: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')]} 19:56:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:56:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:56: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e972a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea07618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6a2d6dad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8d6d30>, '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 0x10e63bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e8ceb20>, '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.bCLqLExQ0F/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.SeZntOGvul/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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 19:56: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', 'pip>=1.5'] in /builds/slave/test/build 19:56: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 pip>=1.5 19:56:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bCLqLExQ0F/Render', 19:56:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:56:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:56:17 INFO - 'HOME': '/Users/cltbld', 19:56:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:56:17 INFO - 'LOGNAME': 'cltbld', 19:56:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:56:17 INFO - 'MOZ_NO_REMOTE': '1', 19:56:17 INFO - 'NO_EM_RESTART': '1', 19:56:17 INFO - 'PAGER': '/bin/cat', 19:56:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:56:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:56:17 INFO - 'PWD': '/builds/slave/test', 19:56:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:56:17 INFO - 'SHELL': '/bin/bash', 19:56:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SeZntOGvul/Listeners', 19:56:17 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 19:56:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:56:17 INFO - 'USER': 'cltbld', 19:56:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:56:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:56:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:56:17 INFO - 'XPC_FLAGS': '0x0', 19:56:17 INFO - 'XPC_SERVICE_NAME': '0', 19:56:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:56:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:56:17 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 19:56:17 INFO - Cleaning up... 19:56:17 INFO - Return code: 0 19:56:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:56: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')]} 19:56:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:56:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:56: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')]} 19:56:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:56:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:56: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', '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 0x10e972a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea07618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6a2d6dad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8d6d30>, '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 0x10e63bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e8ceb20>, '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.bCLqLExQ0F/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.SeZntOGvul/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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 19:56: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', 'psutil>=3.1.1'] in /builds/slave/test/build 19:56: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 psutil>=3.1.1 19:56:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bCLqLExQ0F/Render', 19:56:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:56:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:56:17 INFO - 'HOME': '/Users/cltbld', 19:56:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:56:17 INFO - 'LOGNAME': 'cltbld', 19:56:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:56:17 INFO - 'MOZ_NO_REMOTE': '1', 19:56:17 INFO - 'NO_EM_RESTART': '1', 19:56:17 INFO - 'PAGER': '/bin/cat', 19:56:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:56:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:56:17 INFO - 'PWD': '/builds/slave/test', 19:56:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:56:17 INFO - 'SHELL': '/bin/bash', 19:56:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SeZntOGvul/Listeners', 19:56:17 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 19:56:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:56:17 INFO - 'USER': 'cltbld', 19:56:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:56:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:56:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:56:17 INFO - 'XPC_FLAGS': '0x0', 19:56:17 INFO - 'XPC_SERVICE_NAME': '0', 19:56:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:56:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:56:17 INFO - Downloading/unpacking psutil>=3.1.1 19:56:17 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 19:56:17 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 19:56:17 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 19:56:17 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 19:56:17 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 19:56:17 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 19:56:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:56:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:56:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:56:21 INFO - Installing collected packages: psutil 19:56:21 INFO - Running setup.py install for psutil 19:56:21 INFO - building 'psutil._psutil_osx' extension 19:56:21 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 19:56:22 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 19:56:22 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 19:56:22 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 19:56:22 INFO - building 'psutil._psutil_posix' extension 19:56:22 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 19:56:22 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:56:22 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:56:22 INFO - ^ 19:56:22 INFO - 1 warning generated. 19:56:22 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 19:56:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:56:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:56:22 INFO - Successfully installed psutil 19:56:22 INFO - Cleaning up... 19:56:22 INFO - Return code: 0 19:56:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:56:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:56:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:56:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:56:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:56:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:56:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:56:22 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 0x10e972a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea07618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6a2d6dad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8d6d30>, '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 0x10e63bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e8ceb20>, '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.bCLqLExQ0F/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.SeZntOGvul/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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 19:56:22 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 19:56:22 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 19:56:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bCLqLExQ0F/Render', 19:56:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:56:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:56:22 INFO - 'HOME': '/Users/cltbld', 19:56:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:56:22 INFO - 'LOGNAME': 'cltbld', 19:56:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:56:22 INFO - 'MOZ_NO_REMOTE': '1', 19:56:22 INFO - 'NO_EM_RESTART': '1', 19:56:22 INFO - 'PAGER': '/bin/cat', 19:56:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:56:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:56:22 INFO - 'PWD': '/builds/slave/test', 19:56:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:56:22 INFO - 'SHELL': '/bin/bash', 19:56:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SeZntOGvul/Listeners', 19:56:22 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 19:56:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:56:22 INFO - 'USER': 'cltbld', 19:56:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:56:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:56:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:56:22 INFO - 'XPC_FLAGS': '0x0', 19:56:22 INFO - 'XPC_SERVICE_NAME': '0', 19:56:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:56:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:56:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:56:23 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 19:56:23 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 19:56:23 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 19:56:23 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 19:56:23 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 19:56:23 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 19:56:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:56:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:56:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:56:25 INFO - Installing collected packages: mozsystemmonitor 19:56:25 INFO - Running setup.py install for mozsystemmonitor 19:56:25 INFO - Successfully installed mozsystemmonitor 19:56:25 INFO - Cleaning up... 19:56:25 INFO - Return code: 0 19:56:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:56:25 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')]} 19:56:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:56:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:56:25 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')]} 19:56:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:56:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:56:25 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 0x10e972a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea07618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6a2d6dad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8d6d30>, '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 0x10e63bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e8ceb20>, '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.bCLqLExQ0F/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.SeZntOGvul/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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 19:56:25 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 19:56:25 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 19:56:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bCLqLExQ0F/Render', 19:56:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:56:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:56:25 INFO - 'HOME': '/Users/cltbld', 19:56:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:56:25 INFO - 'LOGNAME': 'cltbld', 19:56:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:56:25 INFO - 'MOZ_NO_REMOTE': '1', 19:56:25 INFO - 'NO_EM_RESTART': '1', 19:56:25 INFO - 'PAGER': '/bin/cat', 19:56:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:56:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:56:25 INFO - 'PWD': '/builds/slave/test', 19:56:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:56:25 INFO - 'SHELL': '/bin/bash', 19:56:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SeZntOGvul/Listeners', 19:56:25 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 19:56:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:56:25 INFO - 'USER': 'cltbld', 19:56:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:56:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:56:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:56:25 INFO - 'XPC_FLAGS': '0x0', 19:56:25 INFO - 'XPC_SERVICE_NAME': '0', 19:56:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:56:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:56:26 INFO - Downloading/unpacking jsonschema==2.5.1 19:56:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:56:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:56:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:56:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:56:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:56:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:56:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:56:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:56:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:56:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:56:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:56:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:56:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:56:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:56:28 INFO - Downloading functools32-3.2.3-2.tar.gz 19:56:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:56:28 INFO - warning: no files found matching '*.txt' 19:56:28 INFO - no previously-included directories found matching 'build' 19:56:28 INFO - no previously-included directories found matching 'dist' 19:56:28 INFO - no previously-included directories found matching '.git*' 19:56:29 INFO - Installing collected packages: jsonschema, functools32 19:56:29 INFO - Running setup.py install for functools32 19:56:29 INFO - warning: no files found matching '*.txt' 19:56:29 INFO - no previously-included directories found matching 'build' 19:56:29 INFO - no previously-included directories found matching 'dist' 19:56:29 INFO - no previously-included directories found matching '.git*' 19:56:29 INFO - Successfully installed jsonschema functools32 19:56:29 INFO - Cleaning up... 19:56:29 INFO - Return code: 0 19:56:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:56:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:56:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:56:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:56:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:56:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:56:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:56:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e972a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea07618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6a2d6dad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8d6d30>, '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 0x10e63bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e8ceb20>, '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.bCLqLExQ0F/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.SeZntOGvul/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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 19:56:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:56:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:56:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bCLqLExQ0F/Render', 19:56:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:56:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:56:29 INFO - 'HOME': '/Users/cltbld', 19:56:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:56:29 INFO - 'LOGNAME': 'cltbld', 19:56:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:56:29 INFO - 'MOZ_NO_REMOTE': '1', 19:56:29 INFO - 'NO_EM_RESTART': '1', 19:56:29 INFO - 'PAGER': '/bin/cat', 19:56:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:56:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:56:29 INFO - 'PWD': '/builds/slave/test', 19:56:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:56:29 INFO - 'SHELL': '/bin/bash', 19:56:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SeZntOGvul/Listeners', 19:56:29 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 19:56:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:56:29 INFO - 'USER': 'cltbld', 19:56:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:56:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:56:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:56:29 INFO - 'XPC_FLAGS': '0x0', 19:56:29 INFO - 'XPC_SERVICE_NAME': '0', 19:56:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:56:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:56:29 INFO - Downloading/unpacking blobuploader==1.2.4 19:56:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:56:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:56:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:56:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:56:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:56:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:56:32 INFO - Downloading blobuploader-1.2.4.tar.gz 19:56:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:56:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:56:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:56:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:56:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:56:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:56:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:56:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:56:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:56:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:56:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:56:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:56:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:56:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:56:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:56:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:56:33 INFO - Downloading docopt-0.6.1.tar.gz 19:56:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:56:33 INFO - Installing collected packages: blobuploader, requests, docopt 19:56:33 INFO - Running setup.py install for blobuploader 19:56:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:56:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:56:33 INFO - Running setup.py install for requests 19:56:33 INFO - Running setup.py install for docopt 19:56:34 INFO - Successfully installed blobuploader requests docopt 19:56:34 INFO - Cleaning up... 19:56:34 INFO - Return code: 0 19:56:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:56: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')]} 19:56:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:56:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:56: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')]} 19:56:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:56:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:56:34 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 0x10e972a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea07618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6a2d6dad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8d6d30>, '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 0x10e63bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e8ceb20>, '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.bCLqLExQ0F/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.SeZntOGvul/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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 19:56:34 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 19:56:34 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 19:56:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bCLqLExQ0F/Render', 19:56:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:56:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:56:34 INFO - 'HOME': '/Users/cltbld', 19:56:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:56:34 INFO - 'LOGNAME': 'cltbld', 19:56:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:56:34 INFO - 'MOZ_NO_REMOTE': '1', 19:56:34 INFO - 'NO_EM_RESTART': '1', 19:56:34 INFO - 'PAGER': '/bin/cat', 19:56:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:56:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:56:34 INFO - 'PWD': '/builds/slave/test', 19:56:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:56:34 INFO - 'SHELL': '/bin/bash', 19:56:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SeZntOGvul/Listeners', 19:56:34 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 19:56:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:56:34 INFO - 'USER': 'cltbld', 19:56:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:56:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:56:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:56:34 INFO - 'XPC_FLAGS': '0x0', 19:56:34 INFO - 'XPC_SERVICE_NAME': '0', 19:56:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:56:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:56:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:56:34 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-ZbfFyQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:56:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:56:34 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-J3AaLo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:56:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:56:34 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-cxDsa2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:56:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:56:34 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-y11EqA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:56:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:56:34 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-tJIYnL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:56:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:56:34 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-1v2lXg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:56:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:56:35 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-3VAtTN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:56:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:56:35 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-huaiFn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:56:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:56:35 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-ZKaAxA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:56:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:56:35 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-2Gfz3Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:56:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:56:35 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-W1eF8H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:56:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:56:35 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-vsZQxN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:56:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:56:35 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-cCuXOT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:56:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:56:35 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-ppIxb9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:56:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:56:35 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-lcpJ_I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:56:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:56:35 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-nfbE9O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:56:36 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-hXuDl0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:56:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:56:36 INFO - Running setup.py install for manifestparser 19:56:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:56:36 INFO - Running setup.py install for mozcrash 19:56:36 INFO - Running setup.py install for mozdebug 19:56:36 INFO - Running setup.py install for mozdevice 19:56:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:56:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:56:36 INFO - Running setup.py install for mozfile 19:56:36 INFO - Running setup.py install for mozhttpd 19:56:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:56:37 INFO - Running setup.py install for mozinfo 19:56:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:56:37 INFO - Running setup.py install for mozInstall 19:56:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:56:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:56:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:56:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:56:37 INFO - Running setup.py install for mozleak 19:56:37 INFO - Running setup.py install for mozlog 19:56:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:56:37 INFO - Running setup.py install for moznetwork 19:56:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:56:37 INFO - Running setup.py install for mozprocess 19:56:37 INFO - Running setup.py install for mozprofile 19:56:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:56:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:56:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:56:38 INFO - Running setup.py install for mozrunner 19:56:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:56:38 INFO - Running setup.py install for mozscreenshot 19:56:38 INFO - Running setup.py install for moztest 19:56:38 INFO - Running setup.py install for mozversion 19:56:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:56:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:56:38 INFO - Cleaning up... 19:56:38 INFO - Return code: 0 19:56:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:56: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')]} 19:56:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:56:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:56: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')]} 19:56:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:56:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:56:38 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 0x10e972a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea07618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6a2d6dad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8d6d30>, '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 0x10e63bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e8ceb20>, '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.bCLqLExQ0F/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.SeZntOGvul/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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 19:56:38 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 19:56:38 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 19:56:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bCLqLExQ0F/Render', 19:56:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:56:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:56:38 INFO - 'HOME': '/Users/cltbld', 19:56:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:56:38 INFO - 'LOGNAME': 'cltbld', 19:56:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:56:38 INFO - 'MOZ_NO_REMOTE': '1', 19:56:38 INFO - 'NO_EM_RESTART': '1', 19:56:38 INFO - 'PAGER': '/bin/cat', 19:56:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:56:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:56:38 INFO - 'PWD': '/builds/slave/test', 19:56:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:56:38 INFO - 'SHELL': '/bin/bash', 19:56:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SeZntOGvul/Listeners', 19:56:38 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 19:56:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:56:38 INFO - 'USER': 'cltbld', 19:56:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:56:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:56:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:56:38 INFO - 'XPC_FLAGS': '0x0', 19:56:38 INFO - 'XPC_SERVICE_NAME': '0', 19:56:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:56:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:56:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:56:39 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-QUXfMi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:56:39 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)) 19:56:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:56:39 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-GUdA4j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:56:39 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)) 19:56:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:56:39 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-kyCM5L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:56:39 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)) 19:56:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:56:39 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-6bVKMW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:56:39 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)) 19:56:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:56:39 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-859YLV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:56:39 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)) 19:56:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:56:39 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-vfrO8o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:56:39 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)) 19:56:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:56:39 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-b1aATM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:56:39 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)) 19:56:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:56:39 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-iHAN4q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:56:39 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)) 19:56:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:56:39 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-d4Rcna-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:56:39 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)) 19:56:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:56:39 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-5L7777-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:56:40 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)) 19:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:56:40 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-wKptU3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:56:40 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)) 19:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:56:40 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-w0IFH1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:56:40 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)) 19:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:56:40 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-m4kvI8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:56:40 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)) 19:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:56:40 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-wp05NH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:56:40 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)) 19:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:56:40 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-rTb0he-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:56:40 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)) 19:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:56:40 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-VZqmp5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:56:40 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)) 19:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:56:40 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-wOndoB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:56:40 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)) 19:56:40 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)) 19:56:40 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)) 19:56:40 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)) 19:56:40 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)) 19:56:40 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)) 19:56:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:56:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:56:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:56:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:56:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:56:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:56:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:56:43 INFO - Downloading blessings-1.6.tar.gz 19:56:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:56:43 INFO - Installing collected packages: blessings 19:56:43 INFO - Running setup.py install for blessings 19:56:43 INFO - Successfully installed blessings 19:56:43 INFO - Cleaning up... 19:56:43 INFO - Return code: 0 19:56:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:56:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:56:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:56:43 INFO - Reading from file tmpfile_stdout 19:56:43 INFO - Current package versions: 19:56:43 INFO - blessings == 1.6 19:56:43 INFO - blobuploader == 1.2.4 19:56:43 INFO - docopt == 0.6.1 19:56:43 INFO - functools32 == 3.2.3-2 19:56:43 INFO - jsonschema == 2.5.1 19:56:43 INFO - manifestparser == 1.1 19:56:43 INFO - mozInstall == 1.12 19:56:43 INFO - mozcrash == 0.17 19:56:43 INFO - mozdebug == 0.1 19:56:43 INFO - mozdevice == 0.48 19:56:43 INFO - mozfile == 1.2 19:56:43 INFO - mozhttpd == 0.7 19:56:43 INFO - mozinfo == 0.9 19:56:43 INFO - mozleak == 0.1 19:56:43 INFO - mozlog == 3.2 19:56:43 INFO - moznetwork == 0.27 19:56:43 INFO - mozprocess == 0.23 19:56:43 INFO - mozprofile == 0.28 19:56:43 INFO - mozrunner == 6.12 19:56:43 INFO - mozscreenshot == 0.1 19:56:43 INFO - mozsystemmonitor == 0.3 19:56:43 INFO - moztest == 0.7 19:56:43 INFO - mozversion == 1.4 19:56:43 INFO - psutil == 3.1.1 19:56:43 INFO - requests == 1.2.3 19:56:43 INFO - wsgiref == 0.1.2 19:56:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:56:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:56:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:56:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:56:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:56:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:56:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:56:43 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 0x10e972a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea07618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6a2d6dad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8d6d30>, '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 0x10e63bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e8ceb20>, '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.bCLqLExQ0F/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.SeZntOGvul/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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 19:56:43 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 19:56:43 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 19:56:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bCLqLExQ0F/Render', 19:56:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:56:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:56:43 INFO - 'HOME': '/Users/cltbld', 19:56:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:56:43 INFO - 'LOGNAME': 'cltbld', 19:56:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:56:43 INFO - 'MOZ_NO_REMOTE': '1', 19:56:43 INFO - 'NO_EM_RESTART': '1', 19:56:43 INFO - 'PAGER': '/bin/cat', 19:56:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:56:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:56:43 INFO - 'PWD': '/builds/slave/test', 19:56:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:56:43 INFO - 'SHELL': '/bin/bash', 19:56:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SeZntOGvul/Listeners', 19:56:43 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 19:56:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:56:43 INFO - 'USER': 'cltbld', 19:56:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:56:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:56:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:56:43 INFO - 'XPC_FLAGS': '0x0', 19:56:43 INFO - 'XPC_SERVICE_NAME': '0', 19:56:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:56:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:56:44 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)) 19:56:44 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)) 19:56:44 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)) 19:56:44 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)) 19:56:44 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)) 19:56:44 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)) 19:56:44 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)) 19:56:44 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)) 19:56:44 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)) 19:56:44 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)) 19:56:44 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)) 19:56:44 INFO - Cleaning up... 19:56:44 INFO - Return code: 0 19:56:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:56: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')]} 19:56:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:56:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:56: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')]} 19:56:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:56:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:56:44 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 0x10e972a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea07618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6a2d6dad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8d6d30>, '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 0x10e63bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e8ceb20>, '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.bCLqLExQ0F/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.SeZntOGvul/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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 19:56:44 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 19:56:44 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 19:56:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bCLqLExQ0F/Render', 19:56:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:56:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:56:44 INFO - 'HOME': '/Users/cltbld', 19:56:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:56:44 INFO - 'LOGNAME': 'cltbld', 19:56:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:56:44 INFO - 'MOZ_NO_REMOTE': '1', 19:56:44 INFO - 'NO_EM_RESTART': '1', 19:56:44 INFO - 'PAGER': '/bin/cat', 19:56:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:56:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:56:44 INFO - 'PWD': '/builds/slave/test', 19:56:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:56:44 INFO - 'SHELL': '/bin/bash', 19:56:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SeZntOGvul/Listeners', 19:56:44 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 19:56:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:56:44 INFO - 'USER': 'cltbld', 19:56:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:56:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:56:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:56:44 INFO - 'XPC_FLAGS': '0x0', 19:56:44 INFO - 'XPC_SERVICE_NAME': '0', 19:56:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:56:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:56:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:56:44 INFO - Cleaning up... 19:56:44 INFO - Return code: 0 19:56:44 INFO - Running post-action listener: _resource_record_post_action 19:56:44 INFO - Running post-action listener: _start_resource_monitoring 19:56:44 INFO - Starting resource monitoring. 19:56:44 INFO - ##### 19:56:44 INFO - ##### Finished create-virtualenv step (success) 19:56:44 INFO - ##### 19:56:44 INFO - ##### 19:56:44 INFO - ##### Running install step. 19:56:44 INFO - ##### 19:56:44 INFO - Running pre-action listener: _resource_record_pre_action 19:56:44 INFO - Running main action method: install 19:56:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:56:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:56:44 INFO - Reading from file tmpfile_stdout 19:56:44 INFO - Detecting whether we're running mozinstall >=1.0... 19:56:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:56:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:56:44 INFO - Reading from file tmpfile_stdout 19:56:44 INFO - Output received: 19:56:44 INFO - Usage: mozinstall [options] installer 19:56:44 INFO - Options: 19:56:44 INFO - -h, --help show this help message and exit 19:56:44 INFO - -d DEST, --destination=DEST 19:56:44 INFO - Directory to install application into. [default: 19:56:44 INFO - "/builds/slave/test"] 19:56:44 INFO - --app=APP Application being installed. [default: firefox] 19:56:44 INFO - mkdir: /builds/slave/test/build/application 19:56:44 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'] 19:56:44 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 19:57:19 INFO - Reading from file tmpfile_stdout 19:57:19 INFO - Output received: 19:57:19 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 19:57:19 INFO - Running post-action listener: _resource_record_post_action 19:57:19 INFO - ##### 19:57:19 INFO - ##### Finished install step (success) 19:57:19 INFO - ##### 19:57:19 INFO - ##### 19:57:19 INFO - ##### Running run-tests step. 19:57:19 INFO - ##### 19:57:19 INFO - Running pre-action listener: _resource_record_pre_action 19:57:19 INFO - Running main action method: run_tests 19:57:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:57:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:57:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:57:19 INFO - 2016-07-31 19:57:19.509 screenresolution[1643:8438] starting screenresolution argv=screenresolution get 19:57:19 INFO - 2016-07-31 19:57:19.512 screenresolution[1643:8438] Display 0: 1600x1200x32@60 19:57:19 INFO - 2016-07-31 19:57:19.518 screenresolution[1644:8440] starting screenresolution argv=screenresolution list 19:57:19 INFO - Available Modes on Display 0 19:57:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:57:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:57:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:57:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:57:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:57:19 INFO - Intel Iris: 19:57:19 INFO - Chipset Model: Intel Iris 19:57:19 INFO - Type: GPU 19:57:19 INFO - Bus: Built-In 19:57:19 INFO - VRAM (Dynamic, Max): 1536 MB 19:57:19 INFO - Vendor: Intel (0x8086) 19:57:19 INFO - Device ID: 0x0a2e 19:57:19 INFO - Revision ID: 0x0009 19:57:19 INFO - Displays: 19:57:19 INFO - Display: 19:57:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:57:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:57:19 INFO - Display Serial Number: 1600x1200 60 19:57:19 INFO - Main Display: Yes 19:57:19 INFO - Mirror: Off 19:57:19 INFO - Online: Yes 19:57:19 INFO - Rotation: Supported 19:57:19 INFO - Return code: 0 19:57:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:57:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:57:19 INFO - Python 2.7.3 19:57:19 INFO - Return code: 0 19:57:19 INFO - grabbing minidump binary from tooltool 19:57:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:57:19 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 0x10e8d6d30>, '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 0x10e63bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e8ceb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:57:19 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 19:57:19 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 19:57:19 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 19:57:19 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:57:19 INFO - Return code: 0 19:57:19 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:57:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:57:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:57:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:57:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:57:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467972121/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0310', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 19:57:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467972121/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0310 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 19:57:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bCLqLExQ0F/Render', 19:57:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:57:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:57:19 INFO - 'HOME': '/Users/cltbld', 19:57:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:57:19 INFO - 'LOGNAME': 'cltbld', 19:57:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:57:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:57:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:57:19 INFO - 'MOZ_NO_REMOTE': '1', 19:57:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:57:19 INFO - 'NO_EM_RESTART': '1', 19:57:19 INFO - 'PAGER': '/bin/cat', 19:57:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:57:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:57:19 INFO - 'PWD': '/builds/slave/test', 19:57:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:57:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:57:19 INFO - 'SHELL': '/bin/bash', 19:57:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SeZntOGvul/Listeners', 19:57:19 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 19:57:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:57:19 INFO - 'USER': 'cltbld', 19:57:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:57:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:57:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:57:19 INFO - 'XPC_FLAGS': '0x0', 19:57:19 INFO - 'XPC_SERVICE_NAME': '0', 19:57:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:57:19 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467972121/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0310', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 19:57:20 INFO - mozversion application_buildid: 20160708030201 19:57:20 INFO - mozversion application_changeset: 45682df2d2d45e5a8385fd842579e661a4b60bc5 19:57:20 INFO - mozversion application_display_name: Nightly 19:57:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:57:20 INFO - mozversion application_name: Firefox 19:57:20 INFO - mozversion application_remotingname: firefox 19:57:20 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:57:20 INFO - mozversion application_vendor: Mozilla 19:57:20 INFO - mozversion application_version: 50.0a1 19:57:20 INFO - mozversion platform_buildid: 20160708030201 19:57:20 INFO - mozversion platform_changeset: 45682df2d2d45e5a8385fd842579e661a4b60bc5 19:57:20 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:57:20 INFO - mozversion platform_version: 50.0a1 19:57:20 INFO - using testdate: 1470020240 19:57:20 INFO - actual date: 1470020240 19:57:20 INFO - starting webserver on 'localhost:49271' 19:57:20 INFO - SUITE-START | Running 2 tests 19:57:20 INFO - TEST-START | dromaeo_css 19:57:20 INFO - operating with platform_type : mac_ 19:57:20 INFO - Initialising browser for dromaeo_css test... 19:57:20 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/tmpqQxJrD/profile http://localhost:49271/getInfo.html) 19:57:23 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 19:57:23 INFO - PROCESS | 1654 | colorDepth:24 19:57:23 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 19:57:23 INFO - PROCESS | 1654 | __metrics 19:57:23 ERROR - PROCESS | 1654 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2957: TypeError: this.tabs is undefined 19:57:24 INFO - TEST-INFO | 1654: exit 0 19:57:24 INFO - Browser initialized. 19:57:24 INFO - Running cycle 1/1 for dromaeo_css test... 19:57:24 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/tmpqQxJrD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:57:36 INFO - PROCESS | 1659 | RSS: Main: 254373888 19:57:36 INFO - PROCESS | 1659 | 19:58:26 INFO - PROCESS | 1659 | 1470020306197 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] 20:00:37 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 20:00:38 INFO - PROCESS | 1659 | RSS: Main: 267362304 20:00:38 INFO - PROCESS | 1659 | 20:03:41 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 20:03:41 INFO - PROCESS | 1659 | RSS: Main: 275968000 20:03:41 INFO - PROCESS | 1659 | 20:06:43 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 20:06:44 INFO - PROCESS | 1659 | RSS: Main: 280350720 20:06:44 INFO - PROCESS | 1659 | 20:09:46 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 20:09:46 INFO - PROCESS | 1659 | RSS: Main: 279732224 20:09:46 INFO - PROCESS | 1659 | 20:12:49 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 20:12:50 INFO - PROCESS | 1659 | RSS: Main: 283115520 20:12:50 INFO - PROCESS | 1659 | 20:15:53 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 20:15:53 INFO - PROCESS | 1659 | RSS: Main: 283295744 20:15:53 INFO - PROCESS | 1659 | 20:15:53 INFO - PROCESS | 1659 | __start_tp_report 20:15:53 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 20:15:53 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 20:15:53 INFO - PROCESS | 1659 | |i|pagename|runs| 20:15:53 INFO - PROCESS | 1659 | |0;dojo.html;2841.70162132976;3094.7060029297218;3370.123767820366;3444.569428821397;3444.9999999999986;5209.426963034068;5430.782768689252;5832.854178645536;5882.235309409782;5935.851603709899;5714.599978001542;5900.468957793798;5932.050871860503;5955.315138759044;6045.183900173475;5314.229436731672;5324.440933701963;5340.279062326575;5392.002479541277;5397.136458166675;5462.672239665613;5495.120780675093;5535.778568857241;5546.500814926656;5550.4449555044575;5223.451537588537;5385.2999110115825;5411;5461.317335333083;5480.068388373966;5245;5341.67949923002;5350.491703288181;5363.168708850135;5386.5152136307925;5283.841484755463;5293.9735301323635;5316.282301889234;5356.678599284055;5359.196120581905;5094.465081166498;5607.812952948353;5643.548516118701;5726.770929162829;5794.5074668653215;6243.687815609202;6285.528585356071;6298.842528936813;6327.114204011442;6337.6197428154455;6322.683865806691;6324.462420694246;6334.809955701337;6352.650604216767;6356.141920840673;5800.593958422912;5887.941120588825;5939.70301484924;5952.315483719384;5959.821205363846;6424.550281480251;6465.870682586322;6468.741250349934;6469.159009328757;6484.578502397329;5088.414832294334;6136.325004249618;6329.3354197809485;6330.8100756977365;6354.745810167541;6092.598375241016;6228.03465462854;6240.7503699851495;6290.4653104485715;6326.778562750374;6097.664628445478;6200.3179650238435;6315.652639104894;6316.115743795872;6333.588316759396;6251.4061164189325;6277.78027769035;6332.4934005279465;6340.778072767431;6352.4918006559365;4044.8786536403954;4994.825181118644;5016.573591244712;5053.747312634354;5120.948790512121;3985.8405663773706;4269.082147338338;4456.398386217819;4504.3919070925;4506;5832.620879642895;5925.792597259076;5931;5938.168656388109;5956;6182.072689096672;6210.658413787195;6229.3770622937345;6267;6313.747450101944;4934.605231581465;5408.702521361363;5891.4403131703275;5897.380775018647;5934.258217722785;5388.003219404422;5563.998480273509;5566.526845218121;5566.693831839288;5579.2746942897165;6271.874562508724;6355.269144048401;6404.967975160094;6510.869782604416;6554.442872355903;6354.078658594571;6358.809235722936;6361.268454127649;6367.140436041261;6371.681415929278;6242.406971337716;6535.183102112236;6544.574602651003;6554.344565543408;6566.507511936528;6197.194364732556;6209.937900620936;6295.842603934938;6324.209473815773;6339.873202536016;6355.52333574993;6373.139626150411;6382.648954307558;6389.393007664265;6401.775937842153;6137.110119032626;6158.3841615838055;6226.688665566794;6314.463270622048;6371.872562548816;6182.196314479089;6296.905546416716;6320.968395157995;6321.620702757849;6327.11420401135;5979.372165922689;6122.602030867979;6129.969350153221;6154;6181.5363847710705;5243.68537887728;6109.389061093855;6129.478994285536;6309.589876658002;6318.62088274705;5406.324209473816;6109.989758682861;6138.882085680433;6139.017757158833;6150.846228844314 20:15:53 INFO - PROCESS | 1659 | |1;ext.html;490.7668857292786;523.3693399453662;528.7462018231249;539.8905249711842;569.8888716700245;31880;43138;43211.78394108033;43213.35179972303;43434.565654343445;43725;44061;44399.778001109946;44476.777616111955;44553;9631.37396069255;9857.063578960024;9859.852102218472;9868.407895526292;9882.851757223647;9755.41467511948;9849.950750246238;9850.40897546146;9856.704298871009;9919.404835709844;9757.365771224848;9866.358686685378;9880.950595247014;9882.456464894429;9926.354786938862;9821.41071535703;9822.312438129335;9847.50762461874;9860.161886239679;9862.605495780124;9721.562529686182;9737.07497787709;9801.16690081344;9820.950895245549;9870.309078364517;37335;37427.62572374268;37650.623493764986;37850.242995140215;37864.62135378666;3110.1136176189902;3380.492926061086;3487.2153765402836;3503.6846683798326;3575.0841850736497;28522.714772852214;30332.331721755294;33845.83077084624;33946.83026584876;34568.30863382718;3238.3037646906027;3425.1608355952703;3431.3137372525593;3445.5176275321473;3456;521987;529501.3524932389;532363;533889;534155.9999999962;60550.183494495235;63196.052059219146;63821.680891596174;64035.35964640387;64200.357996420375;61232.71371003987;64800;66290;67295.66352168306;68002.65998669976;16583.50249492517;16954.982701037978;17088.23102960345;17128.828711712973;17225.7416138758;13020;13085.803712944313;13164.934175329063;13206;13228.470861165639;14445.205513696637;17748.55628609295;17798.733019004725;17804.931704097795;17948;7137.321954414322;7147.821304467326;7150.3922166615375;7229.132504099542;7267.110551256711;868.5917618719192;969.5733877094056;1018.9847152292722;1019.9898001020043;1022.6574097677212;25519.362015949377;31481.898133565217;31689.366212675617;31886.202844928597;32353;1055.894410558938;1066.9010918753695;1071.7320669832543;1101.3832253937821;1203.7351782607811;16335.26491307894;16447.342106315613;16464.341426343053;16503.33986640545;16750.497485075466;670779.6461017664;680269;685593;687266;688682;62914.37085629085;63264;63285.683571581845;63331.683341583;63671.04493432602;1372.3549931532164;1377.9734098096983;1434.2900264369205;1436.289036926728;1437.7196446693004;32456.83771581127;32588.674113258563;32768.85309014173;32882.50676239906;33380.833095834365;45675;55060.89878202333;55389.89220215492;55446;55468.72265638646;545752;546499;550028;551099;553681;26177.476450471266;26891.86554067217;27509.587356190073;27929;28112;51070.74464627653;51657.483425165265;52238.73880630572;52280;52875.73562132165;41554.58445415507;41589.58410415978;42382;43337;44077;42305.94235144146;42648;42666.78666606647;42847.14305713931;43312.318452930595;32421.837890811337;39777.2044559113;41530.58469415267;41691;43222.13555728931;34250.48624270588;35974.280514389044;37175.442368363954;37556.624433756406;37614.811925940194;24493.877530612233;26651.600225997012;26969.865150674123;27311.45377092487;27523.724762752117 20:15:53 INFO - PROCESS | 1659 | |2;jquery.html;378.22652675279056;380.6003696119074;387.3763241181698;400.49937578027465;406.7722075637643;18579.349722759707;20360.98195090243;20498.795012049875;20646.48383790404;20816.375508734724;11920.821187682192;12402.317872517007;12441.440135193916;12443.191192572476;21265.787342126576;5393.973030134844;5526.392096869341;5549.889002219953;5566.749496272678;5574.554035677136;3939.2712348215528;6552.541322107454;6886.862262754742;6915.239323674392;6946;5601.775928962837;5978.22283103196;6903.585784852925;6919.757808476705;6924.653767311639;5895.941040589625;5936.762529498815;5978.790742324019;6046.606970546923;6450.41946224842;5727.198192253088;5764.423557644244;5815.273090863642;5847.590668653196;5854.560907931922;13280.86719132806;13466.057375983684;13854.238016909067;13891.79162312566;13896.722065558632;2480.7147178074574;2580.367809886571;2732.1666891598056;3068.4936985397608;3141.858616362269;8228.547429891354;10330.7417314567;10632.202584806171;10637.308574942681;14428.495002674961;1681.218233521406;1744.659791340689;1816.2099486723187;1854.8608854335978;1932.6134773045446;96592;108471.45764271208;110462;116282;116517.41741291239;9703.223742100616;11351.80806015373;11485.540578376771;11649.47577359021;11768.823467647991;14523.419063237563;14576.63558411027;14690.485832996008;14704.705905881823;14742.484013059491;8627.525486098199;8639.308855291561;8701.825963480695;8716.738497845075;8833.823323533621;8710.172533609297;8713.695020674373;8751.299896008304;8812.16284452976;8850.468971861708;4169.291220492523;4517.435320584927;4614.700044497097;4623.398958135421;4639.744814035261;2901.8839246429907;3343.18092067445;3922.156736301709;3963.841446342114;4120.713004282652;845.695549602155;847.6355167278007;878.3939082033377;950.8906475755239;1015.6292953072076;21603.459913502287;22142.778572214072;22180;22183.334499965025;22321.44196395103;955.617752898846;977.3256453047375;992.1368409483796;1018.3279035836313;1077.1490522487304;10548;11497.310161390176;11588.594399196156;11610.361430121255;11618.709532261591;566089;571998;577355.1132244312;583607;584255;126844;128172;128348;130008;130435.04347434796;985.7929834734642;993.7267251505893;1000.3247807729899;1017.3336464615688;1034.017683200947;19108.426747197605;19319.034048297246;19457;19467.415977520697;19608.705869412257;52341.7382913083;52596;52941;53030.469695302556;53577.464225357246;75573;75988;76481;76687.46625067356;76927;7954.482958607672;7982.840343192987;8112.959435202786;8180.222878826502;8369.204925532065;11401.600943967253;12519.374031298217;12585.622431327074;12628.179168354027;12710.745785084431;10799.67600971972;10840.295380800222;10935.015848573667;11100.000999910048;11121.388323642277;8840;10129.848052279369;10493.422861742372;10573.889741577332;10897.019268265894;10833.67498975032;10982.670519884416;11148.386838723947;11262.662120136409;11297.152713546353;7143.392811611071;7360.96319518399;7369.63151842395;7748;8132.471389359673;10890.019898209199;10927;11013.118950484102;11079.390633515233;11121 20:15:53 INFO - PROCESS | 1659 | |3;mootools.html;1369.9452021919124;1506.6835964447473;1548.97676534852;1644.3833562414095;1752.3516298969382;8090.757277281677;8726.389152759293;8826.205641492264;9257.398269112511;9264.166225039753;8837.690680826172;8945.194932456077;9052.54737263136;9055.954720226406;9061.864072038908;2397.928062158134;2428.6721292625534;2428.8056955443612;2430.368104292879;2442.780149786519;2141.967870481944;2257.679257634286;2272.0343853862123;2406.494636126407;2434.087217293515;2174.6411842046045;2203.394066631681;2225.087714037245;2392.8444651097625;2418.5404773093055;2369.8104151667826;2383.809295256384;2385.60637494814;2397.508510755291;2397.676313697646;2358.1392791630933;2372.1341710275797;2388.97611023891;2390.8804559772116;2394.2936833633944;9048.276137908952;9086.091390860927;9092.363534552584;9183;9205.401648892868;5281.392639846428;5358.732063396815;5443.59173062018;5446.2919820423485;5455.399906010336;3618.8371523281517;3633.7819730816236;3692.8153592320277;3695.1870588470492;3713.851445942159;1089.782043591277;1100.779844031197;1100.8513850630143;1102.922795404322;1121.6635009497118;11765.470553825098;11992.460339284666;12004.879951200464;12027.97762190215;12033.037357011506;4703.2004559225015;4789.338173639374;4803.735794531264;4908.116539022939;4921.630877684116;10196.235282353853;10203;10206.693799186036;10221.74445638865;10238.334508257089;5438.129899216106;5459.2629994950175;5471.699056551848;5472.34331880169;5473.480019398151;4367.67242456817;4821.9035619287415;4963.982383611351;4979.551840334388;4984.302197692254;3989.7406668567032;4040;4092.5907409258834;4140.710150289511;4151.667866570667;1295.2228662802243;1309.6987692830703;1310.960671179866;1319.0502837956656;1322.4247452358254;917.495377542349;927.9443233406017;929.9816700712709;937.6671281695036;984.8818141823027;4841.975790121097;5369.838904832861;5628.634138780966;5677.83604361105;5683.914741278884;968.8788901387327;986.7434467038622;991.4893829677721;998.7403275148514;1035.3529044347283;3898.161895192547;3975.8807235782974;4113.526944401432;4119.155573107506;4135.31767258405;10624;10977.945110274397;11142;11150.275232109887;11163.665090047312;10855.294405863593;11031.448427578749;11057.336559806277;11114.166437517219;11117.332960022262;8009.118996910335;8316.792080198044;8343.874841877378;8369;8481.151884811592;6033.547483938634;6106.72519736604;6147.692615369303;6217.285012223652;6224.1597384352535;8532.274756645505;10067.29528932997;10083.042110999444;10171.694849154537;10370.896291036994;10210.642627508001;10222.693319200436;10434.321769084985;10503.737406564896;10516.68449946503;7145.749898753519;7583.203763604742;7703.537787732754;7799.259070388304;7824.64789084504;8640.26557742599;8664.610092545976;8726.476411415335;8728.825423491622;8743.475391476532;7588;7789.688412463438;7806.765797026098;7830.92169078302;7847.686092556234;7644.694212231449;7704.152543220353;7710.614469276626;7727.343175830118;7869.527828330319;7202.279772022755;7250.166230883516;7257.383122434441;7257.564546127249;7260.346568808833;4833.492483289345;4937.9012419752125;4959.752012399438;4971.577415919687;5019.648624596243;5077.093111429401;5089.447718445909;5166.819161329336;5247.055530004636;5274.129768588294 20:15:53 INFO - PROCESS | 1659 | |4;prototype.html;311.48604802076574;315.575550884061;318.89157686386625;318.9282411457422;323.3516798818369;4993.875153121174;5378.650387724795;5725.656460612367;5741.19623252745;5753.194552762606;4539.90920181597;5608.915866262009;5610.551155907528;5621.213030175779;5644.520215781664;3551.964480355189;4083.815693448904;4198.097409057053;4305.806238719249;4313;4217.7891105444605;4290.270653988814;4299.247631664462;4335.501417336999;4344.89137771555;3861.775417526369;3945.171513982067;4087.386891966199;4248.511421186572;4276.909388106029;4077.2864748668862;4264.978675106605;4276.893077673052;4282.550332215135;4294.957050429487;3132.9216769580717;3529.5940966788635;3705.5923848376656;3721.888343349704;3722.8831350594983;4664.440267167927;5363.383210930754;5447.645903016291;5462.754176062087;5479.6164268501225;4681.689127044399;4791.568758811689;4853.830115945961;4944.629152813553;4946.208606622923;5369.382521010095;5897.882042359129;5905.1950323596375;5917.792877249319;5929.851753706192;4093.6520395766397;4095.9180816383505;4104.589541045902;4111.5477297497255;4122.072533679928;5927.673977931212;6262.749490020394;6308.337624549402;6335.904961425628;6369.299377068519;3718;4031.91936161281;4046.3323551613653;4062;4073.9796301018896;3589.3000864831106;4092.3656833190903;4117.5882411758585;4120.979395103065;4122.237386083584;4443.355713421601;4508.81964721414;4824.793801549613;4838.467768545457;4849.636277279218;5617.354004289477;5982.312034116046;5991.191189189491;5992.531829701751;6024.367441418588;1383.2599559235962;1403.5789263220993;1403.9649008774863;1407.4651632379835;1408.7393832141086;816.4774544291658;855.8930133733284;858.536390349217;869.4305230074311;871.1984973824098;4423.07115505741;4691.9912218873105;5430.5927055471;5454.8909021819345;5477.698726569997;5317.601179911522;5830.921279563209;6002.058991094333;6070.330659068703;6113.908291375633;4730.408698912636;5201.531862132426;5409.864753381118;5438.945610543844;5471.50756431923;2697.163879197455;2949.024153816905;3154.7003034711665;3175.7297081167717;3197.9680203198136;5059.595232381401;5069.543741063322;5730.34101078373;6308.265227062595;6317.3366796486625;5107.821226257063;5881.911771323434;5983.401659833982;6184.041473571604;6201.224846894138;6059.545534084961;6249.0317099377435;6276;6299.748010079546;6299.937000629935;5707.372189059283;6040.885690008462;6051.667158306335;6057.333693293646;6115.602485838406;4899.975500122619;5700.432381095232;5905.763769449174;5932.864168899279;5974.982633769919;5565.30433695788;5634;5911.615744976562;5979.760809567742;6320.336364681642;3991.960080399159;3996.2407142642805;4000.839756470591;4017.3170561004113;4037;5238.825887256746;5292.444293349143;5969.313528944227;5990.281166260077;6051.364606716407;4394.53857344987;4839.491853355347;4936.703797772145;5663.9716801415725;5804.94195058044;4690.695104818176;5509.953108909294;5805.883882322414;5843.100992177644;5843.211166492469;5484.862878428071;5682.011296046516;5687.9431205687415;5732.942670573241;5760.971195143998;5737.500124923709;5907.202527658883;5922.082077278029;5938.287405511366;5969.403059694169;5080.745962701776;5761.884762304814;5785.739641716049;5808.141394753638;5816.82549523515 20:15:53 INFO - PROCESS | 1659 | |5;yui.html;715.4812760748458;731.2906480713707;745.6458182363375;778.175134357581;848.3806821020656;15642.374305027799;16267.674646507063;16449.09529975851;16465.176741162955;16486.7526987095;14916.105033697986;14986.475473358407;15039.473618423357;15051.172185529791;15070.246487675628;707.8088915992679;707.8088915992679;890.2477406591436;905.1355955062932;906.6781292641116;826.6073615032872;914.3736540469799;917.7476194046657;920.9447433154032;924.2744445610198;816.4244207833493;970.3062310448029;980.7842274699555;982.8132654795602;984.9212063034976;812.2811311988903;867.7136544940155;971.4608392342247;974.2157563161722;974.9951250243739;626.9561130720851;708.8905862325444;713.3722324354586;726.8364617960916;735.9328972989188;15978.360865565364;16007.27967241477;16119.758203626954;16138.435154769644;16171.83828161715;13515.189088654613;15001.624959375993;15095.849041509555;15134.848651513454;15155.242237888062;10338.586456541729;11193.49629266685;12195.024398048135;12396;12635.74728505434;3286.7346071762304;3847.403652433877;3907.7069219808627;3965.0682089708894;4019.577944315863;114999;116037;117399;117710;118790.8120918797;11653.6503904883;11659.242149260272;12107.697307567205;12157.878421215852;12179.512819487298;24081.277561673178;24585.877070614533;24858.005679772607;24861.627075593882;24951.875240624046;8150.510969341858;8320.37597180214;8393.866827978145;8445.915540844635;8577.871331930026;4469.3072274112055;4601.217792975202;4602.068401081349;4618.445786505641;4760.45254795696;3712.7215458840697;3829.5596006459054;3830.2531006454033;3836.060165259529;3848.191879705293;589.2428229724869;648.3030741952326;670.3062330487921;675.8749631318221;677.1772296659484;565.2566874559941;605.9727312270959;673.182083768224;673.4545018535002;687.5771400588602;7311.853762924712;9582.568784404719;9604.9039509604;9635.759106022266;9921.65274215399;515.7292421478679;547.4686984277528;573.2690819205546;656.1273506236694;686.4336922039238;4358.542353052947;4715.339852420664;4864.8783780405065;4916.975415122902;4948.975255123702;143308;145983;147447.2627636855;148032;151154;131519;131932.14712455246;134360;134614;135027;102776.45835312476;110110.34834477489;115502.42248788702;115577.42211289058;119034;9340.112689294507;9346.579403926704;9366.812663746823;9421.623135074795;9437.553389716028;55439;56831;61696.38303616906;62006.68996654988;62598.37401625925;80905.59547202227;82021.76967345613;85203.14796851952;87747.56126219328;88782.55608721916;11936.462859171532;13540.322983850965;13683.24742139192;13761.243131627856;14206.147631142165;15310.234488275764;15320.923395382952;15463.690726185638;15797.605059873595;15889.205539723198;14037;14112;14136.867053229487;14465.710685786436;14502.419903203754;13996.090254133449;14121.435142594592;14220.288985550887;14387.064840785315;14820.481283155037;10285.640002599874;13645.522406715718;14136.929315353358;14611.634709132357;14613.269336532918;10849.915008499087;11309.547618095185;11370.658880233606;11618.753425340177;11705.531778728755;9434.348988926773;11093.778124437305;11642.825357619811;11910.880891191324;11956 20:15:53 INFO - PROCESS | 1659 | __end_tp_report 20:15:53 INFO - PROCESS | 1659 | __start_cc_report 20:15:53 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,440 20:15:53 INFO - PROCESS | 1659 | __end_cc_report 20:15:53 INFO - PROCESS | 1659 | __startTimestamp1470021353417__endTimestamp 20:15:53 INFO - PROCESS | 1659 | 20:15:53 INFO - PROCESS | 1659 | ------- Summary: start ------- 20:15:53 INFO - PROCESS | 1659 | Number of tests: 6 20:15:53 INFO - PROCESS | 1659 | 20:15:53 INFO - PROCESS | 1659 | [#0] dojo.html Cycles:165 Average:5825.25 Median:6109.99 stddev:699.46 (11.4%) stddev-sans-first:661.29 20:15:53 INFO - PROCESS | 1659 | Values: 2841.7 3094.7 3370.1 3444.6 3445.0 5209.4 5430.8 5832.9 5882.2 5935.9 5714.6 5900.5 5932.1 5955.3 6045.2 5314.2 5324.4 5340.3 5392.0 5397.1 5462.7 5495.1 5535.8 5546.5 5550.4 5223.5 5385.3 5411.0 5461.3 5480.1 5245.0 5341.7 5350.5 5363.2 5386.5 5283.8 5294.0 5316.3 5356.7 5359.2 5094.5 5607.8 5643.5 5726.8 5794.5 6243.7 6285.5 6298.8 6327.1 6337.6 6322.7 6324.5 6334.8 6352.7 6356.1 5800.6 5887.9 5939.7 5952.3 5959.8 6424.6 6465.9 6468.7 6469.2 6484.6 5088.4 6136.3 6329.3 6330.8 6354.7 6092.6 6228.0 6240.8 6290.5 6326.8 6097.7 6200.3 6315.7 6316.1 6333.6 6251.4 6277.8 6332.5 6340.8 6352.5 4044.9 4994.8 5016.6 5053.7 5120.9 3985.8 4269.1 4456.4 4504.4 4506.0 5832.6 5925.8 5931.0 5938.2 5956.0 6182.1 6210.7 6229.4 6267.0 6313.7 4934.6 5408.7 5891.4 5897.4 5934.3 5388.0 5564.0 5566.5 5566.7 5579.3 6271.9 6355.3 6405.0 6510.9 6554.4 6354.1 6358.8 6361.3 6367.1 6371.7 6242.4 6535.2 6544.6 6554.3 6566.5 6197.2 6209.9 6295.8 6324.2 6339.9 6355.5 6373.1 6382.6 6389.4 6401.8 6137.1 6158.4 6226.7 6314.5 6371.9 6182.2 6296.9 6321.0 6321.6 6327.1 5979.4 6122.6 6130.0 6154.0 6181.5 5243.7 6109.4 6129.5 6309.6 6318.6 5406.3 6110.0 6138.9 6139.0 6150.8 20:15:53 INFO - PROCESS | 1659 | 20:15:53 INFO - PROCESS | 1659 | [#1] ext.html Cycles:180 Average:72907.71 Median:43174.89 stddev:157965.87 (365.9%) stddev-sans-first:158315.43 20:15:53 INFO - PROCESS | 1659 | Values: 490.8 523.4 528.7 539.9 569.9 31880.0 43138.0 43211.8 43213.4 43434.6 43725.0 44061.0 44399.8 44476.8 44553.0 9631.4 9857.1 9859.9 9868.4 9882.9 9755.4 9850.0 9850.4 9856.7 9919.4 9757.4 9866.4 9881.0 9882.5 9926.4 9821.4 9822.3 9847.5 9860.2 9862.6 9721.6 9737.1 9801.2 9821.0 9870.3 37335.0 37427.6 37650.6 37850.2 37864.6 3110.1 3380.5 3487.2 3503.7 3575.1 28522.7 30332.3 33845.8 33946.8 34568.3 3238.3 3425.2 3431.3 3445.5 3456.0 521987.0 529501.4 532363.0 533889.0 534156.0 60550.2 63196.1 63821.7 64035.4 64200.4 61232.7 64800.0 66290.0 67295.7 68002.7 16583.5 16955.0 17088.2 17128.8 17225.7 13020.0 13085.8 13164.9 13206.0 13228.5 14445.2 17748.6 17798.7 17804.9 17948.0 7137.3 7147.8 7150.4 7229.1 7267.1 868.6 969.6 1019.0 1020.0 1022.7 25519.4 31481.9 31689.4 31886.2 32353.0 1055.9 1066.9 1071.7 1101.4 1203.7 16335.3 16447.3 16464.3 16503.3 16750.5 670779.6 680269.0 685593.0 687266.0 688682.0 62914.4 63264.0 63285.7 63331.7 63671.0 1372.4 1378.0 1434.3 1436.3 1437.7 32456.8 32588.7 32768.9 32882.5 33380.8 45675.0 55060.9 55389.9 55446.0 55468.7 545752.0 546499.0 550028.0 551099.0 553681.0 26177.5 26891.9 27509.6 27929.0 28112.0 51070.7 51657.5 52238.7 52280.0 52875.7 41554.6 41589.6 42382.0 43337.0 44077.0 42305.9 42648.0 42666.8 42847.1 43312.3 32421.8 39777.2 41530.6 41691.0 43222.1 34250.5 35974.3 37175.4 37556.6 37614.8 24493.9 26651.6 26969.9 27311.5 27523.7 20:15:53 INFO - PROCESS | 1659 | 20:15:53 INFO - PROCESS | 1659 | [#2] jquery.html Cycles:180 Average:33798.81 Median:2985.19 stddev:96320.26 (3226.6%) stddev-sans-first:96557.77 20:15:53 INFO - PROCESS | 1659 | Values: 378.2 380.6 387.4 400.5 406.8 18579.3 20361.0 20498.8 20646.5 20816.4 11920.8 12402.3 12441.4 12443.2 21265.8 5394.0 5526.4 5549.9 5566.7 5574.6 3939.3 6552.5 6886.9 6915.2 6946.0 5601.8 5978.2 6903.6 6919.8 6924.7 5895.9 5936.8 5978.8 6046.6 6450.4 5727.2 5764.4 5815.3 5847.6 5854.6 13280.9 13466.1 13854.2 13891.8 13896.7 2480.7 2580.4 2732.2 3068.5 3141.9 8228.5 10330.7 10632.2 10637.3 14428.5 1681.2 1744.7 1816.2 1854.9 1932.6 96592.0 108471.5 110462.0 116282.0 116517.4 9703.2 11351.8 11485.5 11649.5 11768.8 14523.4 14576.6 14690.5 14704.7 14742.5 8627.5 8639.3 8701.8 8716.7 8833.8 8710.2 8713.7 8751.3 8812.2 8850.5 4169.3 4517.4 4614.7 4623.4 4639.7 2901.9 3343.2 3922.2 3963.8 4120.7 845.7 847.6 878.4 950.9 1015.6 21603.5 22142.8 22180.0 22183.3 22321.4 955.6 977.3 992.1 1018.3 1077.1 10548.0 11497.3 11588.6 11610.4 11618.7 566089.0 571998.0 577355.1 583607.0 584255.0 126844.0 128172.0 128348.0 130008.0 130435.0 985.8 993.7 1000.3 1017.3 1034.0 19108.4 19319.0 19457.0 19467.4 19608.7 52341.7 52596.0 52941.0 53030.5 53577.5 75573.0 75988.0 76481.0 76687.5 76927.0 7954.5 7982.8 8113.0 8180.2 8369.2 11401.6 12519.4 12585.6 12628.2 12710.7 10799.7 10840.3 10935.0 11100.0 11121.4 8840.0 10129.8 10493.4 10573.9 10897.0 10833.7 10982.7 11148.4 11262.7 11297.2 7143.4 7361.0 7369.6 7748.0 8132.5 10890.0 10927.0 11013.1 11079.4 11121.0 20:15:53 INFO - PROCESS | 1659 | 20:15:53 INFO - PROCESS | 1659 | [#3] mootools.html Cycles:180 Average:5846.28 Median:4837.73 stddev:3295.19 (68.1%) stddev-sans-first:3287.26 20:15:53 INFO - PROCESS | 1659 | Values: 1369.9 1506.7 1549.0 1644.4 1752.4 8090.8 8726.4 8826.2 9257.4 9264.2 8837.7 8945.2 9052.5 9056.0 9061.9 2397.9 2428.7 2428.8 2430.4 2442.8 2142.0 2257.7 2272.0 2406.5 2434.1 2174.6 2203.4 2225.1 2392.8 2418.5 2369.8 2383.8 2385.6 2397.5 2397.7 2358.1 2372.1 2389.0 2390.9 2394.3 9048.3 9086.1 9092.4 9183.0 9205.4 5281.4 5358.7 5443.6 5446.3 5455.4 3618.8 3633.8 3692.8 3695.2 3713.9 1089.8 1100.8 1100.9 1102.9 1121.7 11765.5 11992.5 12004.9 12028.0 12033.0 4703.2 4789.3 4803.7 4908.1 4921.6 10196.2 10203.0 10206.7 10221.7 10238.3 5438.1 5459.3 5471.7 5472.3 5473.5 4367.7 4821.9 4964.0 4979.6 4984.3 3989.7 4040.0 4092.6 4140.7 4151.7 1295.2 1309.7 1311.0 1319.1 1322.4 917.5 927.9 930.0 937.7 984.9 4842.0 5369.8 5628.6 5677.8 5683.9 968.9 986.7 991.5 998.7 1035.4 3898.2 3975.9 4113.5 4119.2 4135.3 10624.0 10977.9 11142.0 11150.3 11163.7 10855.3 11031.4 11057.3 11114.2 11117.3 8009.1 8316.8 8343.9 8369.0 8481.2 6033.5 6106.7 6147.7 6217.3 6224.2 8532.3 10067.3 10083.0 10171.7 10370.9 10210.6 10222.7 10434.3 10503.7 10516.7 7145.7 7583.2 7703.5 7799.3 7824.6 8640.3 8664.6 8726.5 8728.8 8743.5 7588.0 7789.7 7806.8 7830.9 7847.7 7644.7 7704.2 7710.6 7727.3 7869.5 7202.3 7250.2 7257.4 7257.6 7260.3 4833.5 4937.9 4959.8 4971.6 5019.6 5077.1 5089.4 5166.8 5247.1 5274.1 20:15:53 INFO - PROCESS | 1659 | 20:15:53 INFO - PROCESS | 1659 | [#4] prototype.html Cycles:180 Average:4746.39 Median:5374.02 stddev:1464.35 (27.2%) stddev-sans-first:1430.13 20:15:53 INFO - PROCESS | 1659 | Values: 311.5 315.6 318.9 318.9 323.4 4993.9 5378.7 5725.7 5741.2 5753.2 4539.9 5608.9 5610.6 5621.2 5644.5 3552.0 4083.8 4198.1 4305.8 4313.0 4217.8 4290.3 4299.2 4335.5 4344.9 3861.8 3945.2 4087.4 4248.5 4276.9 4077.3 4265.0 4276.9 4282.6 4295.0 3132.9 3529.6 3705.6 3721.9 3722.9 4664.4 5363.4 5447.6 5462.8 5479.6 4681.7 4791.6 4853.8 4944.6 4946.2 5369.4 5897.9 5905.2 5917.8 5929.9 4093.7 4095.9 4104.6 4111.5 4122.1 5927.7 6262.7 6308.3 6335.9 6369.3 3718.0 4031.9 4046.3 4062.0 4074.0 3589.3 4092.4 4117.6 4121.0 4122.2 4443.4 4508.8 4824.8 4838.5 4849.6 5617.4 5982.3 5991.2 5992.5 6024.4 1383.3 1403.6 1404.0 1407.5 1408.7 816.5 855.9 858.5 869.4 871.2 4423.1 4692.0 5430.6 5454.9 5477.7 5317.6 5830.9 6002.1 6070.3 6113.9 4730.4 5201.5 5409.9 5438.9 5471.5 2697.2 2949.0 3154.7 3175.7 3198.0 5059.6 5069.5 5730.3 6308.3 6317.3 5107.8 5881.9 5983.4 6184.0 6201.2 6059.5 6249.0 6276.0 6299.7 6299.9 5707.4 6040.9 6051.7 6057.3 6115.6 4900.0 5700.4 5905.8 5932.9 5975.0 5565.3 5634.0 5911.6 5979.8 6320.3 3992.0 3996.2 4000.8 4017.3 4037.0 5238.8 5292.4 5969.3 5990.3 6051.4 4394.5 4839.5 4936.7 5664.0 5804.9 4690.7 5510.0 5805.9 5843.1 5843.2 5484.9 5682.0 5687.9 5732.9 5761.0 5737.5 5907.2 5922.1 5938.3 5969.4 5080.7 5761.9 5785.7 5808.1 5816.8 20:15:53 INFO - PROCESS | 1659 | 20:15:53 INFO - PROCESS | 1659 | [#5] yui.html Cycles:180 Average:25275.10 Median:3771.14 stddev:39951.53 (1059.4%) stddev-sans-first:40021.04 20:15:53 INFO - PROCESS | 1659 | Values: 715.5 731.3 745.6 778.2 848.4 15642.4 16267.7 16449.1 16465.2 16486.8 14916.1 14986.5 15039.5 15051.2 15070.2 707.8 707.8 890.2 905.1 906.7 826.6 914.4 917.7 920.9 924.3 816.4 970.3 980.8 982.8 984.9 812.3 867.7 971.5 974.2 975.0 627.0 708.9 713.4 726.8 735.9 15978.4 16007.3 16119.8 16138.4 16171.8 13515.2 15001.6 15095.8 15134.8 15155.2 10338.6 11193.5 12195.0 12396.0 12635.7 3286.7 3847.4 3907.7 3965.1 4019.6 114999.0 116037.0 117399.0 117710.0 118790.8 11653.7 11659.2 12107.7 12157.9 12179.5 24081.3 24585.9 24858.0 24861.6 24951.9 8150.5 8320.4 8393.9 8445.9 8577.9 4469.3 4601.2 4602.1 4618.4 4760.5 3712.7 3829.6 3830.3 3836.1 3848.2 589.2 648.3 670.3 675.9 677.2 565.3 606.0 673.2 673.5 687.6 7311.9 9582.6 9604.9 9635.8 9921.7 515.7 547.5 573.3 656.1 686.4 4358.5 4715.3 4864.9 4917.0 4949.0 143308.0 145983.0 147447.3 148032.0 151154.0 131519.0 131932.1 134360.0 134614.0 135027.0 102776.5 110110.3 115502.4 115577.4 119034.0 9340.1 9346.6 9366.8 9421.6 9437.6 55439.0 56831.0 61696.4 62006.7 62598.4 80905.6 82021.8 85203.1 87747.6 88782.6 11936.5 13540.3 13683.2 13761.2 14206.1 15310.2 15320.9 15463.7 15797.6 15889.2 14037.0 14112.0 14136.9 14465.7 14502.4 13996.1 14121.4 14220.3 14387.1 14820.5 10285.6 13645.5 14136.9 14611.6 14613.3 10849.9 11309.5 11370.7 11618.8 11705.5 9434.3 11093.8 11642.8 11910.9 11956.0 20:15:53 INFO - PROCESS | 1659 | -------- Summary: end -------- 20:15:53 INFO - PROCESS | 1659 | 20:15:54 INFO - TEST-INFO | 1659: exit 0 20:15:54 INFO - TEST-OK | dromaeo_css | took 1114007ms 20:15:54 INFO - TEST-START | kraken 20:15:54 INFO - operating with platform_type : mac_ 20:15:54 INFO - Initialising browser for kraken test... 20:15:54 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/tmp_IbFcM/profile http://localhost:49271/getInfo.html) 20:15:56 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 20:15:56 INFO - PROCESS | 1668 | colorDepth:24 20:15:56 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 20:15:56 INFO - PROCESS | 1668 | __metrics 20:15:57 ERROR - PROCESS | 1668 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2957: TypeError: this.tabs is undefined 20:15:57 INFO - TEST-INFO | 1668: exit 0 20:15:57 INFO - Browser initialized. 20:15:57 INFO - Running cycle 1/1 for kraken test... 20:15:57 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/tmp_IbFcM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:16:09 INFO - PROCESS | 1671 | RSS: Main: 254476288 20:16:09 INFO - PROCESS | 1671 | 20:16:12 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 20:16:13 INFO - PROCESS | 1671 | RSS: Main: 267423744 20:16:13 INFO - PROCESS | 1671 | 20:16:15 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 20:16:16 INFO - PROCESS | 1671 | RSS: Main: 271802368 20:16:16 INFO - PROCESS | 1671 | 20:16:19 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 20:16:20 INFO - PROCESS | 1671 | RSS: Main: 271704064 20:16:20 INFO - PROCESS | 1671 | 20:16:22 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 20:16:22 INFO - PROCESS | 1671 | RSS: Main: 272621568 20:16:22 INFO - PROCESS | 1671 | 20:16:25 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:16:25 INFO - PROCESS | 1671 | RSS: Main: 273293312 20:16:25 INFO - PROCESS | 1671 | 20:16:28 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 20:16:29 INFO - PROCESS | 1671 | RSS: Main: 274157568 20:16:29 INFO - PROCESS | 1671 | 20:16:32 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 20:16:32 INFO - PROCESS | 1671 | RSS: Main: 273911808 20:16:32 INFO - PROCESS | 1671 | 20:16:36 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 20:16:36 INFO - PROCESS | 1671 | RSS: Main: 274059264 20:16:36 INFO - PROCESS | 1671 | 20:16:38 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:16:38 INFO - PROCESS | 1671 | RSS: Main: 273620992 20:16:38 INFO - PROCESS | 1671 | 20:16:40 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:16:41 INFO - PROCESS | 1671 | RSS: Main: 273686528 20:16:41 INFO - PROCESS | 1671 | 20:16:43 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:16:44 INFO - PROCESS | 1671 | RSS: Main: 274079744 20:16:44 INFO - PROCESS | 1671 | 20:16:47 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:16:47 INFO - PROCESS | 1671 | RSS: Main: 274219008 20:16:47 INFO - PROCESS | 1671 | 20:16:50 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:16:51 INFO - PROCESS | 1671 | RSS: Main: 274337792 20:16:51 INFO - PROCESS | 1671 | 20:16:53 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 20:16:53 INFO - PROCESS | 1671 | RSS: Main: 275439616 20:16:53 INFO - PROCESS | 1671 | 20:16:53 INFO - PROCESS | 1671 | __start_tp_report 20:16:53 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 20:16:53 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 20:16:53 INFO - PROCESS | 1671 | |i|pagename|runs| 20:16:53 INFO - PROCESS | 1671 | |0;ai-astar;104;116;106;116;117;116;114;116;117;117 20:16:53 INFO - PROCESS | 1671 | |1;audio-beat-detection;143;114;136;153;111;130;111;146;142;109 20:16:53 INFO - PROCESS | 1671 | |2;audio-dft;285;214;198;200;219;202;195;201;197;198 20:16:53 INFO - PROCESS | 1671 | |3;audio-fft;66;78;77;111;62;76;86;85;148;58 20:16:53 INFO - PROCESS | 1671 | |4;audio-oscillator;71;71;113;70;94;101;143;100;72;117 20:16:53 INFO - PROCESS | 1671 | |5;imaging-gaussian-blur;130;131;89;88;89;85;89;86;89;86 20:16:53 INFO - PROCESS | 1671 | |6;imaging-darkroom;95;115;95;133;98;147;153;95;95;95 20:16:53 INFO - PROCESS | 1671 | |7;imaging-desaturate;90;96;85;145;75;150;82;143;100;145 20:16:53 INFO - PROCESS | 1671 | |8;json-parse-financial;50;60;58;56;57;56;57;55;55;57 20:16:53 INFO - PROCESS | 1671 | |9;json-stringify-tinderbox;65;74;73;74;91;107;70;70;63;97 20:16:53 INFO - PROCESS | 1671 | |10;stanford-crypto-aes;83;84;85;72;89;107;99;80;78;84 20:16:53 INFO - PROCESS | 1671 | |11;stanford-crypto-ccm;150;129;154;147;153;156;150;156;225;135 20:16:53 INFO - PROCESS | 1671 | |12;stanford-crypto-pbkdf2;168;165;170;163;166;171;168;162;165;165 20:16:53 INFO - PROCESS | 1671 | |13;stanford-crypto-sha256-iterative;67;67;65;69;73;74;87;65;68;72 20:16:53 INFO - PROCESS | 1671 | __end_tp_report 20:16:53 INFO - PROCESS | 1671 | __start_cc_report 20:16:53 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,1129 20:16:53 INFO - PROCESS | 1671 | __end_cc_report 20:16:53 INFO - PROCESS | 1671 | __startTimestamp1470021413343__endTimestamp 20:16:53 INFO - PROCESS | 1671 | 20:16:53 INFO - PROCESS | 1671 | ------- Summary: start ------- 20:16:53 INFO - PROCESS | 1671 | Number of tests: 14 20:16:53 INFO - PROCESS | 1671 | 20:16:53 INFO - PROCESS | 1671 | [#0] ai-astar Cycles:10 Average:113.90 Median:116.00 stddev:4.79 (4.1%) stddev-sans-first:3.50 20:16:53 INFO - PROCESS | 1671 | Values: 104.0 116.0 106.0 116.0 117.0 116.0 114.0 116.0 117.0 117.0 20:16:53 INFO - PROCESS | 1671 | 20:16:53 INFO - PROCESS | 1671 | [#1] audio-beat-detection Cycles:10 Average:129.50 Median:139.00 stddev:16.83 (12.1%) stddev-sans-first:17.13 20:16:53 INFO - PROCESS | 1671 | Values: 143.0 114.0 136.0 153.0 111.0 130.0 111.0 146.0 142.0 109.0 20:16:53 INFO - PROCESS | 1671 | 20:16:53 INFO - PROCESS | 1671 | [#2] audio-dft Cycles:10 Average:210.90 Median:201.50 stddev:27.16 (13.5%) stddev-sans-first:8.22 20:16:53 INFO - PROCESS | 1671 | Values: 285.0 214.0 198.0 200.0 219.0 202.0 195.0 201.0 197.0 198.0 20:16:53 INFO - PROCESS | 1671 | 20:16:53 INFO - PROCESS | 1671 | [#3] audio-fft Cycles:10 Average:84.70 Median:76.50 stddev:26.79 (35.0%) stddev-sans-first:27.54 20:16:53 INFO - PROCESS | 1671 | Values: 66.0 78.0 77.0 111.0 62.0 76.0 86.0 85.0 148.0 58.0 20:16:53 INFO - PROCESS | 1671 | 20:16:53 INFO - PROCESS | 1671 | [#4] audio-oscillator Cycles:10 Average:95.20 Median:70.50 stddev:24.67 (35.0%) stddev-sans-first:24.57 20:16:53 INFO - PROCESS | 1671 | Values: 71.0 71.0 113.0 70.0 94.0 101.0 143.0 100.0 72.0 117.0 20:16:53 INFO - PROCESS | 1671 | 20:16:53 INFO - PROCESS | 1671 | [#5] imaging-gaussian-blur Cycles:10 Average:96.20 Median:88.50 stddev:18.14 (20.5%) stddev-sans-first:14.54 20:16:53 INFO - PROCESS | 1671 | Values: 130.0 131.0 89.0 88.0 89.0 85.0 89.0 86.0 89.0 86.0 20:16:53 INFO - PROCESS | 1671 | 20:16:53 INFO - PROCESS | 1671 | [#6] imaging-darkroom Cycles:10 Average:112.10 Median:95.00 stddev:23.56 (24.8%) stddev-sans-first:24.17 20:16:53 INFO - PROCESS | 1671 | Values: 95.0 115.0 95.0 133.0 98.0 147.0 153.0 95.0 95.0 95.0 20:16:53 INFO - PROCESS | 1671 | 20:16:53 INFO - PROCESS | 1671 | [#7] imaging-desaturate Cycles:10 Average:111.10 Median:78.50 stddev:30.65 (39.0%) stddev-sans-first:31.55 20:16:53 INFO - PROCESS | 1671 | Values: 90.0 96.0 85.0 145.0 75.0 150.0 82.0 143.0 100.0 145.0 20:16:53 INFO - PROCESS | 1671 | 20:16:53 INFO - PROCESS | 1671 | [#8] json-parse-financial Cycles:10 Average:56.10 Median:57.00 stddev:2.60 (4.6%) stddev-sans-first:1.56 20:16:53 INFO - PROCESS | 1671 | Values: 50.0 60.0 58.0 56.0 57.0 56.0 57.0 55.0 55.0 57.0 20:16:53 INFO - PROCESS | 1671 | 20:16:53 INFO - PROCESS | 1671 | [#9] json-stringify-tinderbox Cycles:10 Average:78.40 Median:73.50 stddev:14.71 (20.0%) stddev-sans-first:14.79 20:16:53 INFO - PROCESS | 1671 | Values: 65.0 74.0 73.0 74.0 91.0 107.0 70.0 70.0 63.0 97.0 20:16:53 INFO - PROCESS | 1671 | 20:16:53 INFO - PROCESS | 1671 | [#10] stanford-crypto-aes Cycles:10 Average:86.10 Median:84.00 stddev:10.18 (12.1%) stddev-sans-first:10.74 20:16:53 INFO - PROCESS | 1671 | Values: 83.0 84.0 85.0 72.0 89.0 107.0 99.0 80.0 78.0 84.0 20:16:53 INFO - PROCESS | 1671 | 20:16:53 INFO - PROCESS | 1671 | [#11] stanford-crypto-ccm Cycles:10 Average:155.50 Median:153.50 stddev:26.02 (17.0%) stddev-sans-first:27.52 20:16:53 INFO - PROCESS | 1671 | Values: 150.0 129.0 154.0 147.0 153.0 156.0 150.0 156.0 225.0 135.0 20:16:53 INFO - PROCESS | 1671 | 20:16:53 INFO - PROCESS | 1671 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.30 Median:167.00 stddev:2.91 (1.7%) stddev-sans-first:3.02 20:16:53 INFO - PROCESS | 1671 | Values: 168.0 165.0 170.0 163.0 166.0 171.0 168.0 162.0 165.0 165.0 20:16:53 INFO - PROCESS | 1671 | 20:16:53 INFO - PROCESS | 1671 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.70 Median:70.50 stddev:6.55 (9.3%) stddev-sans-first:6.81 20:16:53 INFO - PROCESS | 1671 | Values: 67.0 67.0 65.0 69.0 73.0 74.0 87.0 65.0 68.0 72.0 20:16:53 INFO - PROCESS | 1671 | -------- Summary: end -------- 20:16:53 INFO - PROCESS | 1671 | 20:16:54 INFO - TEST-INFO | 1671: exit 0 20:16:54 INFO - TEST-OK | kraken | took 60007ms 20:16:54 INFO - SUITE-END | took 1174s 20:16:54 INFO - Completed test suite (00:19:34) 20:16:54 INFO - javascript benchmark 20:16:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7545.084370236402, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2841.70162132976, 3094.7060029297218, 3370.123767820366, 3444.569428821397, 3444.9999999999986, 5209.426963034068, 5430.782768689252, 5832.854178645536, 5882.235309409782, 5935.851603709899, 5714.599978001542, 5900.468957793798, 5932.050871860503, 5955.315138759044, 6045.183900173475, 5314.229436731672, 5324.440933701963, 5340.279062326575, 5392.002479541277, 5397.136458166675, 5462.672239665613, 5495.120780675093, 5535.778568857241, 5546.500814926656, 5550.4449555044575, 5223.451537588537, 5385.2999110115825, 5411.0, 5461.317335333083, 5480.068388373966, 5245.0, 5341.67949923002, 5350.491703288181, 5363.168708850135, 5386.5152136307925, 5283.841484755463, 5293.9735301323635, 5316.282301889234, 5356.678599284055, 5359.196120581905, 5094.465081166498, 5607.812952948353, 5643.548516118701, 5726.770929162829, 5794.5074668653215, 6243.687815609202, 6285.528585356071, 6298.842528936813, 6327.114204011442, 6337.6197428154455, 6322.683865806691, 6324.462420694246, 6334.809955701337, 6352.650604216767, 6356.141920840673, 5800.593958422912, 5887.941120588825, 5939.70301484924, 5952.315483719384, 5959.821205363846, 6424.550281480251, 6465.870682586322, 6468.741250349934, 6469.159009328757, 6484.578502397329, 5088.414832294334, 6136.325004249618, 6329.3354197809485, 6330.8100756977365, 6354.745810167541, 6092.598375241016, 6228.03465462854, 6240.7503699851495, 6290.4653104485715, 6326.778562750374, 6097.664628445478, 6200.3179650238435, 6315.652639104894, 6316.115743795872, 6333.588316759396, 6251.4061164189325, 6277.78027769035, 6332.4934005279465, 6340.778072767431, 6352.4918006559365, 4044.8786536403954, 4994.825181118644, 5016.573591244712, 5053.747312634354, 5120.948790512121, 3985.8405663773706, 4269.082147338338, 4456.398386217819, 4504.3919070925, 4506.0, 5832.620879642895, 5925.792597259076, 5931.0, 5938.168656388109, 5956.0, 6182.072689096672, 6210.658413787195, 6229.3770622937345, 6267.0, 6313.747450101944, 4934.605231581465, 5408.702521361363, 5891.4403131703275, 5897.380775018647, 5934.258217722785, 5388.003219404422, 5563.998480273509, 5566.526845218121, 5566.693831839288, 5579.2746942897165, 6271.874562508724, 6355.269144048401, 6404.967975160094, 6510.869782604416, 6554.442872355903, 6354.078658594571, 6358.809235722936, 6361.268454127649, 6367.140436041261, 6371.681415929278, 6242.406971337716, 6535.183102112236, 6544.574602651003, 6554.344565543408, 6566.507511936528, 6197.194364732556, 6209.937900620936, 6295.842603934938, 6324.209473815773, 6339.873202536016, 6355.52333574993, 6373.139626150411, 6382.648954307558, 6389.393007664265, 6401.775937842153, 6137.110119032626, 6158.3841615838055, 6226.688665566794, 6314.463270622048, 6371.872562548816, 6182.196314479089, 6296.905546416716, 6320.968395157995, 6321.620702757849, 6327.11420401135, 5979.372165922689, 6122.602030867979, 6129.969350153221, 6154.0, 6181.5363847710705, 5243.68537887728, 6109.389061093855, 6129.478994285536, 6309.589876658002, 6318.62088274705, 5406.324209473816, 6109.989758682861, 6138.882085680433, 6139.017757158833, 6150.846228844314], "value": 5777.70116757289, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [490.7668857292786, 523.3693399453662, 528.7462018231249, 539.8905249711842, 569.8888716700245, 31880.0, 43138.0, 43211.78394108033, 43213.35179972303, 43434.565654343445, 43725.0, 44061.0, 44399.778001109946, 44476.777616111955, 44553.0, 9631.37396069255, 9857.063578960024, 9859.852102218472, 9868.407895526292, 9882.851757223647, 9755.41467511948, 9849.950750246238, 9850.40897546146, 9856.704298871009, 9919.404835709844, 9757.365771224848, 9866.358686685378, 9880.950595247014, 9882.456464894429, 9926.354786938862, 9821.41071535703, 9822.312438129335, 9847.50762461874, 9860.161886239679, 9862.605495780124, 9721.562529686182, 9737.07497787709, 9801.16690081344, 9820.950895245549, 9870.309078364517, 37335.0, 37427.62572374268, 37650.623493764986, 37850.242995140215, 37864.62135378666, 3110.1136176189902, 3380.492926061086, 3487.2153765402836, 3503.6846683798326, 3575.0841850736497, 28522.714772852214, 30332.331721755294, 33845.83077084624, 33946.83026584876, 34568.30863382718, 3238.3037646906027, 3425.1608355952703, 3431.3137372525593, 3445.5176275321473, 3456.0, 521987.0, 529501.3524932389, 532363.0, 533889.0, 534155.9999999962, 60550.183494495235, 63196.052059219146, 63821.680891596174, 64035.35964640387, 64200.357996420375, 61232.71371003987, 64800.0, 66290.0, 67295.66352168306, 68002.65998669976, 16583.50249492517, 16954.982701037978, 17088.23102960345, 17128.828711712973, 17225.7416138758, 13020.0, 13085.803712944313, 13164.934175329063, 13206.0, 13228.470861165639, 14445.205513696637, 17748.55628609295, 17798.733019004725, 17804.931704097795, 17948.0, 7137.321954414322, 7147.821304467326, 7150.3922166615375, 7229.132504099542, 7267.110551256711, 868.5917618719192, 969.5733877094056, 1018.9847152292722, 1019.9898001020043, 1022.6574097677212, 25519.362015949377, 31481.898133565217, 31689.366212675617, 31886.202844928597, 32353.0, 1055.894410558938, 1066.9010918753695, 1071.7320669832543, 1101.3832253937821, 1203.7351782607811, 16335.26491307894, 16447.342106315613, 16464.341426343053, 16503.33986640545, 16750.497485075466, 670779.6461017664, 680269.0, 685593.0, 687266.0, 688682.0, 62914.37085629085, 63264.0, 63285.683571581845, 63331.683341583, 63671.04493432602, 1372.3549931532164, 1377.9734098096983, 1434.2900264369205, 1436.289036926728, 1437.7196446693004, 32456.83771581127, 32588.674113258563, 32768.85309014173, 32882.50676239906, 33380.833095834365, 45675.0, 55060.89878202333, 55389.89220215492, 55446.0, 55468.72265638646, 545752.0, 546499.0, 550028.0, 551099.0, 553681.0, 26177.476450471266, 26891.86554067217, 27509.587356190073, 27929.0, 28112.0, 51070.74464627653, 51657.483425165265, 52238.73880630572, 52280.0, 52875.73562132165, 41554.58445415507, 41589.58410415978, 42382.0, 43337.0, 44077.0, 42305.94235144146, 42648.0, 42666.78666606647, 42847.14305713931, 43312.318452930595, 32421.837890811337, 39777.2044559113, 41530.58469415267, 41691.0, 43222.13555728931, 34250.48624270588, 35974.280514389044, 37175.442368363954, 37556.624433756406, 37614.811925940194, 24493.877530612233, 26651.600225997012, 26969.865150674123, 27311.45377092487, 27523.724762752117], "value": 20708.73008940174, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.22652675279056, 380.6003696119074, 387.3763241181698, 400.49937578027465, 406.7722075637643, 18579.349722759707, 20360.98195090243, 20498.795012049875, 20646.48383790404, 20816.375508734724, 11920.821187682192, 12402.317872517007, 12441.440135193916, 12443.191192572476, 21265.787342126576, 5393.973030134844, 5526.392096869341, 5549.889002219953, 5566.749496272678, 5574.554035677136, 3939.2712348215528, 6552.541322107454, 6886.862262754742, 6915.239323674392, 6946.0, 5601.775928962837, 5978.22283103196, 6903.585784852925, 6919.757808476705, 6924.653767311639, 5895.941040589625, 5936.762529498815, 5978.790742324019, 6046.606970546923, 6450.41946224842, 5727.198192253088, 5764.423557644244, 5815.273090863642, 5847.590668653196, 5854.560907931922, 13280.86719132806, 13466.057375983684, 13854.238016909067, 13891.79162312566, 13896.722065558632, 2480.7147178074574, 2580.367809886571, 2732.1666891598056, 3068.4936985397608, 3141.858616362269, 8228.547429891354, 10330.7417314567, 10632.202584806171, 10637.308574942681, 14428.495002674961, 1681.218233521406, 1744.659791340689, 1816.2099486723187, 1854.8608854335978, 1932.6134773045446, 96592.0, 108471.45764271208, 110462.0, 116282.0, 116517.41741291239, 9703.223742100616, 11351.80806015373, 11485.540578376771, 11649.47577359021, 11768.823467647991, 14523.419063237563, 14576.63558411027, 14690.485832996008, 14704.705905881823, 14742.484013059491, 8627.525486098199, 8639.308855291561, 8701.825963480695, 8716.738497845075, 8833.823323533621, 8710.172533609297, 8713.695020674373, 8751.299896008304, 8812.16284452976, 8850.468971861708, 4169.291220492523, 4517.435320584927, 4614.700044497097, 4623.398958135421, 4639.744814035261, 2901.8839246429907, 3343.18092067445, 3922.156736301709, 3963.841446342114, 4120.713004282652, 845.695549602155, 847.6355167278007, 878.3939082033377, 950.8906475755239, 1015.6292953072076, 21603.459913502287, 22142.778572214072, 22180.0, 22183.334499965025, 22321.44196395103, 955.617752898846, 977.3256453047375, 992.1368409483796, 1018.3279035836313, 1077.1490522487304, 10548.0, 11497.310161390176, 11588.594399196156, 11610.361430121255, 11618.709532261591, 566089.0, 571998.0, 577355.1132244312, 583607.0, 584255.0, 126844.0, 128172.0, 128348.0, 130008.0, 130435.04347434796, 985.7929834734642, 993.7267251505893, 1000.3247807729899, 1017.3336464615688, 1034.017683200947, 19108.426747197605, 19319.034048297246, 19457.0, 19467.415977520697, 19608.705869412257, 52341.7382913083, 52596.0, 52941.0, 53030.469695302556, 53577.464225357246, 75573.0, 75988.0, 76481.0, 76687.46625067356, 76927.0, 7954.482958607672, 7982.840343192987, 8112.959435202786, 8180.222878826502, 8369.204925532065, 11401.600943967253, 12519.374031298217, 12585.622431327074, 12628.179168354027, 12710.745785084431, 10799.67600971972, 10840.295380800222, 10935.015848573667, 11100.000999910048, 11121.388323642277, 8840.0, 10129.848052279369, 10493.422861742372, 10573.889741577332, 10897.019268265894, 10833.67498975032, 10982.670519884416, 11148.386838723947, 11262.662120136409, 11297.152713546353, 7143.392811611071, 7360.96319518399, 7369.63151842395, 7748.0, 8132.471389359673, 10890.019898209199, 10927.0, 11013.118950484102, 11079.390633515233, 11121.0], "value": 9570.864036314719, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1369.9452021919124, 1506.6835964447473, 1548.97676534852, 1644.3833562414095, 1752.3516298969382, 8090.757277281677, 8726.389152759293, 8826.205641492264, 9257.398269112511, 9264.166225039753, 8837.690680826172, 8945.194932456077, 9052.54737263136, 9055.954720226406, 9061.864072038908, 2397.928062158134, 2428.6721292625534, 2428.8056955443612, 2430.368104292879, 2442.780149786519, 2141.967870481944, 2257.679257634286, 2272.0343853862123, 2406.494636126407, 2434.087217293515, 2174.6411842046045, 2203.394066631681, 2225.087714037245, 2392.8444651097625, 2418.5404773093055, 2369.8104151667826, 2383.809295256384, 2385.60637494814, 2397.508510755291, 2397.676313697646, 2358.1392791630933, 2372.1341710275797, 2388.97611023891, 2390.8804559772116, 2394.2936833633944, 9048.276137908952, 9086.091390860927, 9092.363534552584, 9183.0, 9205.401648892868, 5281.392639846428, 5358.732063396815, 5443.59173062018, 5446.2919820423485, 5455.399906010336, 3618.8371523281517, 3633.7819730816236, 3692.8153592320277, 3695.1870588470492, 3713.851445942159, 1089.782043591277, 1100.779844031197, 1100.8513850630143, 1102.922795404322, 1121.6635009497118, 11765.470553825098, 11992.460339284666, 12004.879951200464, 12027.97762190215, 12033.037357011506, 4703.2004559225015, 4789.338173639374, 4803.735794531264, 4908.116539022939, 4921.630877684116, 10196.235282353853, 10203.0, 10206.693799186036, 10221.74445638865, 10238.334508257089, 5438.129899216106, 5459.2629994950175, 5471.699056551848, 5472.34331880169, 5473.480019398151, 4367.67242456817, 4821.9035619287415, 4963.982383611351, 4979.551840334388, 4984.302197692254, 3989.7406668567032, 4040.0, 4092.5907409258834, 4140.710150289511, 4151.667866570667, 1295.2228662802243, 1309.6987692830703, 1310.960671179866, 1319.0502837956656, 1322.4247452358254, 917.495377542349, 927.9443233406017, 929.9816700712709, 937.6671281695036, 984.8818141823027, 4841.975790121097, 5369.838904832861, 5628.634138780966, 5677.83604361105, 5683.914741278884, 968.8788901387327, 986.7434467038622, 991.4893829677721, 998.7403275148514, 1035.3529044347283, 3898.161895192547, 3975.8807235782974, 4113.526944401432, 4119.155573107506, 4135.31767258405, 10624.0, 10977.945110274397, 11142.0, 11150.275232109887, 11163.665090047312, 10855.294405863593, 11031.448427578749, 11057.336559806277, 11114.166437517219, 11117.332960022262, 8009.118996910335, 8316.792080198044, 8343.874841877378, 8369.0, 8481.151884811592, 6033.547483938634, 6106.72519736604, 6147.692615369303, 6217.285012223652, 6224.1597384352535, 8532.274756645505, 10067.29528932997, 10083.042110999444, 10171.694849154537, 10370.896291036994, 10210.642627508001, 10222.693319200436, 10434.321769084985, 10503.737406564896, 10516.68449946503, 7145.749898753519, 7583.203763604742, 7703.537787732754, 7799.259070388304, 7824.64789084504, 8640.26557742599, 8664.610092545976, 8726.476411415335, 8728.825423491622, 8743.475391476532, 7588.0, 7789.688412463438, 7806.765797026098, 7830.92169078302, 7847.686092556234, 7644.694212231449, 7704.152543220353, 7710.614469276626, 7727.343175830118, 7869.527828330319, 7202.279772022755, 7250.166230883516, 7257.383122434441, 7257.564546127249, 7260.346568808833, 4833.492483289345, 4937.9012419752125, 4959.752012399438, 4971.577415919687, 5019.648624596243, 5077.093111429401, 5089.447718445909, 5166.819161329336, 5247.055530004636, 5274.129768588294], "value": 4689.035467133991, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [311.48604802076574, 315.575550884061, 318.89157686386625, 318.9282411457422, 323.3516798818369, 4993.875153121174, 5378.650387724795, 5725.656460612367, 5741.19623252745, 5753.194552762606, 4539.90920181597, 5608.915866262009, 5610.551155907528, 5621.213030175779, 5644.520215781664, 3551.964480355189, 4083.815693448904, 4198.097409057053, 4305.806238719249, 4313.0, 4217.7891105444605, 4290.270653988814, 4299.247631664462, 4335.501417336999, 4344.89137771555, 3861.775417526369, 3945.171513982067, 4087.386891966199, 4248.511421186572, 4276.909388106029, 4077.2864748668862, 4264.978675106605, 4276.893077673052, 4282.550332215135, 4294.957050429487, 3132.9216769580717, 3529.5940966788635, 3705.5923848376656, 3721.888343349704, 3722.8831350594983, 4664.440267167927, 5363.383210930754, 5447.645903016291, 5462.754176062087, 5479.6164268501225, 4681.689127044399, 4791.568758811689, 4853.830115945961, 4944.629152813553, 4946.208606622923, 5369.382521010095, 5897.882042359129, 5905.1950323596375, 5917.792877249319, 5929.851753706192, 4093.6520395766397, 4095.9180816383505, 4104.589541045902, 4111.5477297497255, 4122.072533679928, 5927.673977931212, 6262.749490020394, 6308.337624549402, 6335.904961425628, 6369.299377068519, 3718.0, 4031.91936161281, 4046.3323551613653, 4062.0, 4073.9796301018896, 3589.3000864831106, 4092.3656833190903, 4117.5882411758585, 4120.979395103065, 4122.237386083584, 4443.355713421601, 4508.81964721414, 4824.793801549613, 4838.467768545457, 4849.636277279218, 5617.354004289477, 5982.312034116046, 5991.191189189491, 5992.531829701751, 6024.367441418588, 1383.2599559235962, 1403.5789263220993, 1403.9649008774863, 1407.4651632379835, 1408.7393832141086, 816.4774544291658, 855.8930133733284, 858.536390349217, 869.4305230074311, 871.1984973824098, 4423.07115505741, 4691.9912218873105, 5430.5927055471, 5454.8909021819345, 5477.698726569997, 5317.601179911522, 5830.921279563209, 6002.058991094333, 6070.330659068703, 6113.908291375633, 4730.408698912636, 5201.531862132426, 5409.864753381118, 5438.945610543844, 5471.50756431923, 2697.163879197455, 2949.024153816905, 3154.7003034711665, 3175.7297081167717, 3197.9680203198136, 5059.595232381401, 5069.543741063322, 5730.34101078373, 6308.265227062595, 6317.3366796486625, 5107.821226257063, 5881.911771323434, 5983.401659833982, 6184.041473571604, 6201.224846894138, 6059.545534084961, 6249.0317099377435, 6276.0, 6299.748010079546, 6299.937000629935, 5707.372189059283, 6040.885690008462, 6051.667158306335, 6057.333693293646, 6115.602485838406, 4899.975500122619, 5700.432381095232, 5905.763769449174, 5932.864168899279, 5974.982633769919, 5565.30433695788, 5634.0, 5911.615744976562, 5979.760809567742, 6320.336364681642, 3991.960080399159, 3996.2407142642805, 4000.839756470591, 4017.3170561004113, 4037.0, 5238.825887256746, 5292.444293349143, 5969.313528944227, 5990.281166260077, 6051.364606716407, 4394.53857344987, 4839.491853355347, 4936.703797772145, 5663.9716801415725, 5804.94195058044, 4690.695104818176, 5509.953108909294, 5805.883882322414, 5843.100992177644, 5843.211166492469, 5484.862878428071, 5682.011296046516, 5687.9431205687415, 5732.942670573241, 5760.971195143998, 5737.500124923709, 5907.202527658883, 5922.082077278029, 5938.287405511366, 5969.403059694169, 5080.745962701776, 5761.884762304814, 5785.739641716049, 5808.141394753638, 5816.82549523515], "value": 4273.27530311569, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [715.4812760748458, 731.2906480713707, 745.6458182363375, 778.175134357581, 848.3806821020656, 15642.374305027799, 16267.674646507063, 16449.09529975851, 16465.176741162955, 16486.7526987095, 14916.105033697986, 14986.475473358407, 15039.473618423357, 15051.172185529791, 15070.246487675628, 707.8088915992679, 707.8088915992679, 890.2477406591436, 905.1355955062932, 906.6781292641116, 826.6073615032872, 914.3736540469799, 917.7476194046657, 920.9447433154032, 924.2744445610198, 816.4244207833493, 970.3062310448029, 980.7842274699555, 982.8132654795602, 984.9212063034976, 812.2811311988903, 867.7136544940155, 971.4608392342247, 974.2157563161722, 974.9951250243739, 626.9561130720851, 708.8905862325444, 713.3722324354586, 726.8364617960916, 735.9328972989188, 15978.360865565364, 16007.27967241477, 16119.758203626954, 16138.435154769644, 16171.83828161715, 13515.189088654613, 15001.624959375993, 15095.849041509555, 15134.848651513454, 15155.242237888062, 10338.586456541729, 11193.49629266685, 12195.024398048135, 12396.0, 12635.74728505434, 3286.7346071762304, 3847.403652433877, 3907.7069219808627, 3965.0682089708894, 4019.577944315863, 114999.0, 116037.0, 117399.0, 117710.0, 118790.8120918797, 11653.6503904883, 11659.242149260272, 12107.697307567205, 12157.878421215852, 12179.512819487298, 24081.277561673178, 24585.877070614533, 24858.005679772607, 24861.627075593882, 24951.875240624046, 8150.510969341858, 8320.37597180214, 8393.866827978145, 8445.915540844635, 8577.871331930026, 4469.3072274112055, 4601.217792975202, 4602.068401081349, 4618.445786505641, 4760.45254795696, 3712.7215458840697, 3829.5596006459054, 3830.2531006454033, 3836.060165259529, 3848.191879705293, 589.2428229724869, 648.3030741952326, 670.3062330487921, 675.8749631318221, 677.1772296659484, 565.2566874559941, 605.9727312270959, 673.182083768224, 673.4545018535002, 687.5771400588602, 7311.853762924712, 9582.568784404719, 9604.9039509604, 9635.759106022266, 9921.65274215399, 515.7292421478679, 547.4686984277528, 573.2690819205546, 656.1273506236694, 686.4336922039238, 4358.542353052947, 4715.339852420664, 4864.8783780405065, 4916.975415122902, 4948.975255123702, 143308.0, 145983.0, 147447.2627636855, 148032.0, 151154.0, 131519.0, 131932.14712455246, 134360.0, 134614.0, 135027.0, 102776.45835312476, 110110.34834477489, 115502.42248788702, 115577.42211289058, 119034.0, 9340.112689294507, 9346.579403926704, 9366.812663746823, 9421.623135074795, 9437.553389716028, 55439.0, 56831.0, 61696.38303616906, 62006.68996654988, 62598.37401625925, 80905.59547202227, 82021.76967345613, 85203.14796851952, 87747.56126219328, 88782.55608721916, 11936.462859171532, 13540.322983850965, 13683.24742139192, 13761.243131627856, 14206.147631142165, 15310.234488275764, 15320.923395382952, 15463.690726185638, 15797.605059873595, 15889.205539723198, 14037.0, 14112.0, 14136.867053229487, 14465.710685786436, 14502.419903203754, 13996.090254133449, 14121.435142594592, 14220.288985550887, 14387.064840785315, 14820.481283155037, 10285.640002599874, 13645.522406715718, 14136.929315353358, 14611.634709132357, 14613.269336532918, 10849.915008499087, 11309.547618095185, 11370.658880233606, 11618.753425340177, 11705.531778728755, 9434.348988926773, 11093.778124437305, 11642.825357619811, 11910.880891191324, 11956.0], "value": 8039.630870431729, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1566.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 116.0, 106.0, 116.0, 117.0, 116.0, 114.0, 116.0, 117.0, 117.0], "value": 113.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 114.0, 136.0, 153.0, 111.0, 130.0, 111.0, 146.0, 142.0, 109.0], "value": 129.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [285.0, 214.0, 198.0, 200.0, 219.0, 202.0, 195.0, 201.0, 197.0, 198.0], "value": 210.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 78.0, 77.0, 111.0, 62.0, 76.0, 86.0, 85.0, 148.0, 58.0], "value": 84.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 71.0, 113.0, 70.0, 94.0, 101.0, 143.0, 100.0, 72.0, 117.0], "value": 95.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 131.0, 89.0, 88.0, 89.0, 85.0, 89.0, 86.0, 89.0, 86.0], "value": 96.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 115.0, 95.0, 133.0, 98.0, 147.0, 153.0, 95.0, 95.0, 95.0], "value": 112.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 96.0, 85.0, 145.0, 75.0, 150.0, 82.0, 143.0, 100.0, 145.0], "value": 111.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 60.0, 58.0, 56.0, 57.0, 56.0, 57.0, 55.0, 55.0, 57.0], "value": 56.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 74.0, 73.0, 74.0, 91.0, 107.0, 70.0, 70.0, 63.0, 97.0], "value": 78.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 85.0, 72.0, 89.0, 107.0, 99.0, 80.0, 78.0, 84.0], "value": 86.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 129.0, 154.0, 147.0, 153.0, 156.0, 150.0, 156.0, 225.0, 135.0], "value": 155.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 165.0, 170.0, 163.0, 166.0, 171.0, 168.0, 162.0, 165.0, 165.0], "value": 166.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 65.0, 69.0, 73.0, 74.0, 87.0, 65.0, 68.0, 72.0], "value": 70.7, "unit": "score"}]}]} 20:16:54 INFO - Return code: 0 20:16:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:16:54 ERROR - # TBPL SUCCESS # 20:16:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:16:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:16:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:16:54 INFO - 2016-07-31 20:16:54.676 screenresolution[1676:11313] starting screenresolution argv=screenresolution get 20:16:54 INFO - 2016-07-31 20:16:54.679 screenresolution[1676:11313] Display 0: 1600x1200x32@60 20:16:54 INFO - 2016-07-31 20:16:54.685 screenresolution[1677:11316] starting screenresolution argv=screenresolution list 20:16:54 INFO - Available Modes on Display 0 20:16:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:16:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:16:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:16:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:16:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:16:54 INFO - Intel Iris: 20:16:54 INFO - Chipset Model: Intel Iris 20:16:54 INFO - Type: GPU 20:16:54 INFO - Bus: Built-In 20:16:54 INFO - VRAM (Dynamic, Max): 1536 MB 20:16:54 INFO - Vendor: Intel (0x8086) 20:16:54 INFO - Device ID: 0x0a2e 20:16:54 INFO - Revision ID: 0x0009 20:16:54 INFO - Displays: 20:16:54 INFO - Display: 20:16:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:16:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:16:54 INFO - Display Serial Number: 1600x1200 60 20:16:54 INFO - Main Display: Yes 20:16:54 INFO - Mirror: Off 20:16:54 INFO - Online: Yes 20:16:54 INFO - Rotation: Supported 20:16:54 INFO - Return code: 0 20:16:54 INFO - Running post-action listener: _resource_record_post_action 20:16:54 INFO - ##### 20:16:54 INFO - ##### Finished run-tests step (success) 20:16:54 INFO - ##### 20:16:54 INFO - Running post-run listener: _resource_record_post_run 20:16:54 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 32730624; Write bytes: 708100608; Read time: 204; Write time: 1991 20:16:54 INFO - TinderboxPrint: CPU usage
54.2% 20:16:54 INFO - TinderboxPrint: I/O read bytes / time
32,730,624 / 204 20:16:54 INFO - TinderboxPrint: I/O write bytes / time
708,100,608 / 1,991 20:16:54 INFO - TinderboxPrint: CPU idle
2,214.5 (45.8%) 20:16:54 INFO - TinderboxPrint: CPU system
975.2 (20.2%) 20:16:54 INFO - TinderboxPrint: CPU user
1,647.8 (34.1%) 20:16:54 INFO - TinderboxPrint: Swap in / out
436,473,856 / 0 20:16:54 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 386165248; Write bytes: 380565504; Read time: 28985; Write time: 805 20:16:54 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 31800832; Write bytes: 298211840; Read time: 191; Write time: 1122 20:16:55 INFO - Running post-run listener: _upload_blobber_files 20:16:55 INFO - Blob upload gear active. 20:16:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:16:55 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 20:16:55 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'] 20:16:55 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 20:16:55 INFO - (blobuploader) - INFO - Open directory for files ... 20:16:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 20:16:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:16:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:16:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 20:16:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:16:56 INFO - (blobuploader) - INFO - Done attempting. 20:16:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 20:16:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:16:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:16:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 20:16:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:16:57 INFO - (blobuploader) - INFO - Done attempting. 20:16:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:16:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:16:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:16:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:16:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:16:59 INFO - (blobuploader) - INFO - Done attempting. 20:16:59 INFO - (blobuploader) - INFO - Iteration through files over. 20:16:59 INFO - Return code: 0 20:16:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:16:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:16:59 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fb5cd5e1dbdebd2bccd1fe910533f9136e08aa60615228d49b410aac8766204d9f7684fd10912e393f51b0666e26a6fa8f1dbef469cf52f7646a4f98c0435a59", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d7ffb4796353ff73f550d872de5882918fa1d13b9f839eb3f7ad18405cf24fea6c4e6104ae7a730dd9e9f81530ffa6123e5427a43c62f03058061b1a2d13588", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1bc2e2d5019dddc48eb09417115578deea517e4bdda46a025739e152f5b88a069c1de537565e43eac33e42613e568214bd828354683f64a1fe1a632639c1fe16"} 20:16:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:16:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:16:59 INFO - Contents: 20:16:59 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fb5cd5e1dbdebd2bccd1fe910533f9136e08aa60615228d49b410aac8766204d9f7684fd10912e393f51b0666e26a6fa8f1dbef469cf52f7646a4f98c0435a59", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d7ffb4796353ff73f550d872de5882918fa1d13b9f839eb3f7ad18405cf24fea6c4e6104ae7a730dd9e9f81530ffa6123e5427a43c62f03058061b1a2d13588", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1bc2e2d5019dddc48eb09417115578deea517e4bdda46a025739e152f5b88a069c1de537565e43eac33e42613e568214bd828354683f64a1fe1a632639c1fe16"} 20:16:59 INFO - Running post-run listener: copy_logs_to_upload_dir 20:16:59 INFO - Copying logs to upload dir... 20:16:59 INFO - mkdir: /builds/slave/test/build/upload/logs 20:16:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1259.242114 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2016-07-31 20:16:59.948168) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-31 20:16:59.951849) ========= 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.bCLqLExQ0F/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.SeZntOGvul/Listeners TMPDIR=/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fb5cd5e1dbdebd2bccd1fe910533f9136e08aa60615228d49b410aac8766204d9f7684fd10912e393f51b0666e26a6fa8f1dbef469cf52f7646a4f98c0435a59", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d7ffb4796353ff73f550d872de5882918fa1d13b9f839eb3f7ad18405cf24fea6c4e6104ae7a730dd9e9f81530ffa6123e5427a43c62f03058061b1a2d13588", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1bc2e2d5019dddc48eb09417115578deea517e4bdda46a025739e152f5b88a069c1de537565e43eac33e42613e568214bd828354683f64a1fe1a632639c1fe16"} build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467972121/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011096 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467972121/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fb5cd5e1dbdebd2bccd1fe910533f9136e08aa60615228d49b410aac8766204d9f7684fd10912e393f51b0666e26a6fa8f1dbef469cf52f7646a4f98c0435a59", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d7ffb4796353ff73f550d872de5882918fa1d13b9f839eb3f7ad18405cf24fea6c4e6104ae7a730dd9e9f81530ffa6123e5427a43c62f03058061b1a2d13588", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1bc2e2d5019dddc48eb09417115578deea517e4bdda46a025739e152f5b88a069c1de537565e43eac33e42613e568214bd828354683f64a1fe1a632639c1fe16"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-31 20:16:59.989323) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-31 20:16:59.989678) ========= 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.bCLqLExQ0F/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.SeZntOGvul/Listeners TMPDIR=/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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.005258 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-31 20:17:00.038622) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-31 20:17:00.038935) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-31 20:17:00.039394) ========= ========= Total master_lag: 0.20 =========