builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0390 starttime: 1469442318.51 results: success (0) buildid: 20160704040257 builduid: 3be2057788644f939f1fd300407b5d07 revision: 4191df8e4b8e1e2dd0593132dac25915f987f797 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-25 03:25:18.508878) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-25 03:25:18.509537) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-25 03:25:18.509889) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-25 03:25:18.536284) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 03:25:18.536822) ========= 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.YydfphVY5f/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.BObuhvW3rz/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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-25 03:25:18-- 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% 165M=0s 2016-07-25 03:25:18 (165 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.384031 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 03:25:18.948514) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 03:25:18.948904) ========= 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.YydfphVY5f/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.BObuhvW3rz/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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.025388 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 03:25:18.997731) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-25 03:25:18.998132) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 4191df8e4b8e1e2dd0593132dac25915f987f797 --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 4191df8e4b8e1e2dd0593132dac25915f987f797 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.YydfphVY5f/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.BObuhvW3rz/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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-25 03:25:19,081 truncating revision to first 12 chars 2016-07-25 03:25:19,081 Setting DEBUG logging. 2016-07-25 03:25:19,081 attempt 1/10 2016-07-25 03:25:19,081 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/4191df8e4b8e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-25 03:25:20,593 unpacking tar archive at: mozilla-central-4191df8e4b8e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.790292 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-25 03:25:20.811156) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-25 03:25:20.811564) ========= script_repo_revision: 4191df8e4b8e1e2dd0593132dac25915f987f797 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-25 03:25:20.812073) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-25 03:25:20.812394) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-25 03:25:20.831955) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-07-25 03:25:20.832633) ========= /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.YydfphVY5f/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.BObuhvW3rz/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 03:25:21 INFO - MultiFileLogger online at 20160725 03:25:21 in /builds/slave/test 03:25:21 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 03:25:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:25:21 INFO - {'append_to_log': False, 03:25:21 INFO - 'base_work_dir': '/builds/slave/test', 03:25:21 INFO - 'blob_upload_branch': 'Firefox', 03:25:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:25:21 INFO - 'branch': 'Firefox', 03:25:21 INFO - 'buildbot_json_path': 'buildprops.json', 03:25:21 INFO - 'config_files': ('talos/mac_config.py',), 03:25:21 INFO - 'default_actions': ('clobber', 03:25:21 INFO - 'read-buildbot-config', 03:25:21 INFO - 'download-and-extract', 03:25:21 INFO - 'populate-webroot', 03:25:21 INFO - 'create-virtualenv', 03:25:21 INFO - 'install', 03:25:21 INFO - 'run-tests'), 03:25:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:25:21 INFO - 'download_minidump_stackwalk': True, 03:25:21 INFO - 'download_symbols': 'ondemand', 03:25:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:25:21 INFO - 'tooltool.py': '/tools/tooltool.py', 03:25:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:25:21 INFO - '/tools/misc-python/virtualenv.py')}, 03:25:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:25:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:25:21 INFO - 'installer_path': 'installer.exe', 03:25:21 INFO - 'log_level': 'info', 03:25:21 INFO - 'log_name': 'talos', 03:25:21 INFO - 'log_to_console': True, 03:25:21 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:25:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:25:21 INFO - 'opt_config_files': (), 03:25:21 INFO - 'pip_index': False, 03:25:21 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:25:21 INFO - 'cmd': ('bash', 03:25:21 INFO - '-c', 03:25:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:25:21 INFO - 'enabled': True, 03:25:21 INFO - 'halt_on_failure': False, 03:25:21 INFO - 'name': 'check_screen_resolution'},), 03:25:21 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:25:21 INFO - 'cmd': ('bash', 03:25:21 INFO - '-c', 03:25:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:25:21 INFO - 'enabled': True, 03:25:21 INFO - 'halt_on_failure': False, 03:25:21 INFO - 'name': 'check_screen_resolution'},), 03:25:21 INFO - 'run_cmd_checks_enabled': True, 03:25:21 INFO - 'sps_profile': False, 03:25:21 INFO - 'sps_profile_interval': 0, 03:25:21 INFO - 'suite': 'dromaeojs-e10s', 03:25:21 INFO - 'system_bits': '32', 03:25:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:25:21 INFO - 'title': 't-yosemite-r7-0390', 03:25:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:25:21 INFO - 'use_talos_json': True, 03:25:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:25:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:25:21 INFO - 'work_dir': 'build'} 03:25:21 INFO - ##### 03:25:21 INFO - ##### Running clobber step. 03:25:21 INFO - ##### 03:25:21 INFO - Running pre-action listener: _resource_record_pre_action 03:25:21 INFO - Running main action method: clobber 03:25:21 INFO - rmtree: /builds/slave/test/build 03:25:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:25:23 INFO - Running post-action listener: _resource_record_post_action 03:25:23 INFO - ##### 03:25:23 INFO - ##### Finished clobber step (success) 03:25:23 INFO - ##### 03:25:23 INFO - ##### 03:25:23 INFO - ##### Running read-buildbot-config step. 03:25:23 INFO - ##### 03:25:23 INFO - Running pre-action listener: _resource_record_pre_action 03:25:23 INFO - Running main action method: read_buildbot_config 03:25:23 INFO - Using buildbot properties: 03:25:23 INFO - { 03:25:23 INFO - "project": "", 03:25:23 INFO - "product": "firefox", 03:25:23 INFO - "script_repo_revision": "production", 03:25:23 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 03:25:23 INFO - "repository": "", 03:25:23 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 03:25:23 INFO - "buildid": "20160704040257", 03:25:23 INFO - "pgo_build": "False", 03:25:23 INFO - "basedir": "/builds/slave/test", 03:25:23 INFO - "buildnumber": 76, 03:25:23 INFO - "slavename": "t-yosemite-r7-0390", 03:25:23 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 03:25:23 INFO - "platform": "yosemite_r7", 03:25:23 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 03:25:23 INFO - "branch": "mozilla-central", 03:25:23 INFO - "repo_path": "mozilla-central", 03:25:23 INFO - "revision": "4191df8e4b8e1e2dd0593132dac25915f987f797", 03:25:23 INFO - "stage_platform": "macosx64", 03:25:23 INFO - "builduid": "3be2057788644f939f1fd300407b5d07", 03:25:23 INFO - "slavebuilddir": "test" 03:25:23 INFO - } 03:25:23 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630177/firefox-50.0a1.en-US.mac.dmg. 03:25:23 INFO - Running post-action listener: _resource_record_post_action 03:25:23 INFO - ##### 03:25:23 INFO - ##### Finished read-buildbot-config step (success) 03:25:23 INFO - ##### 03:25:23 INFO - ##### 03:25:23 INFO - ##### Running download-and-extract step. 03:25:23 INFO - ##### 03:25:23 INFO - Running pre-action listener: _resource_record_pre_action 03:25:23 INFO - Running main action method: download_and_extract 03:25:23 INFO - mkdir: /builds/slave/test/build/tests 03:25:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:25:23 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630177/firefox-50.0a1.en-US.mac.test_packages.json 03:25:23 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630177/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 03:25:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630177/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 03:25:23 INFO - Downloaded 1199 bytes. 03:25:23 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 03:25:23 INFO - Using the following test package requirements: 03:25:23 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 03:25:23 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:25:23 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 03:25:23 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:25:23 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 03:25:23 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:25:23 INFO - u'jsshell-mac.zip'], 03:25:23 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:25:23 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 03:25:23 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 03:25:23 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:25:23 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 03:25:23 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:25:23 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 03:25:23 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:25:23 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 03:25:23 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:25:23 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 03:25:23 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:25:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:25:23 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630177/firefox-50.0a1.en-US.mac.common.tests.zip 03:25:23 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630177/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 03:25:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630177/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 03:25:23 INFO - Downloaded 18795151 bytes. 03:25:23 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'] 03:25:23 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 03:25:26 INFO - Return code: 0 03:25:26 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 03:25:26 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')]} 03:25:26 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630177/firefox-50.0a1.en-US.mac.common.tests.zip 03:25:26 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630177/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 03:25:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630177/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 03:25:27 INFO - Downloaded 18795151 bytes. 03:25:27 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'] 03:25:27 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 03:25:30 INFO - Return code: 0 03:25:30 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')]} 03:25:30 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630177/firefox-50.0a1.en-US.mac.talos.tests.zip 03:25:30 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630177/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 03:25:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630177/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 03:25:32 INFO - Downloaded 14403333 bytes. 03:25:32 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'] 03:25:32 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 03:25:32 INFO - Return code: 0 03:25:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:25:32 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630177/firefox-50.0a1.en-US.mac.dmg 03:25:32 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630177/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 03:25:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630177/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 03:25:35 INFO - Downloaded 112415553 bytes. 03:25:35 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630177/firefox-50.0a1.en-US.mac.dmg 03:25:35 INFO - mkdir: /builds/slave/test/properties 03:25:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:25:35 INFO - Writing to file /builds/slave/test/properties/build_url 03:25:35 INFO - Contents: 03:25:35 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630177/firefox-50.0a1.en-US.mac.dmg 03:25:36 INFO - Running post-action listener: _resource_record_post_action 03:25:36 INFO - Running post-action listener: set_extra_try_arguments 03:25:36 INFO - ##### 03:25:36 INFO - ##### Finished download-and-extract step (success) 03:25:36 INFO - ##### 03:25:36 INFO - ##### 03:25:36 INFO - ##### Running populate-webroot step. 03:25:36 INFO - ##### 03:25:36 INFO - Running pre-action listener: _resource_record_pre_action 03:25:36 INFO - Running main action method: populate_webroot 03:25:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:25:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:25:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:25:36 INFO - '--tppagecycles', 03:25:36 INFO - '7'], 03:25:36 INFO - 'tests': ['tsvgm']}}, 03:25:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:25:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:25:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:25:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:25:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:25:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:25:36 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:25:36 INFO - 'talos_options': ['--disable-e10s'], 03:25:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:25:36 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:25:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:25:36 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:25:36 INFO - 'talos_options': ['--disable-e10s'], 03:25:36 INFO - 'tests': ['damp', 'tps']}, 03:25:36 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:25:36 INFO - 'tests': ['damp', 'tps']}, 03:25:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:25:36 INFO - 'tests': ['dromaeo_dom']}, 03:25:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:25:36 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:25:36 INFO - 'tests': ['basic_compositor_video']}, 03:25:36 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:25:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:25:36 INFO - 'tests': ['a11yr', 03:25:36 INFO - 'ts_paint', 03:25:36 INFO - 'tpaint', 03:25:36 INFO - 'sessionrestore', 03:25:36 INFO - 'sessionrestore_no_auto_restore', 03:25:36 INFO - 'tabpaint']}, 03:25:36 INFO - 'other-e10s': {'tests': ['a11yr', 03:25:36 INFO - 'ts_paint', 03:25:36 INFO - 'tpaint', 03:25:36 INFO - 'sessionrestore', 03:25:36 INFO - 'sessionrestore_no_auto_restore', 03:25:36 INFO - 'tabpaint']}, 03:25:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:25:36 INFO - 'ts_paint', 03:25:36 INFO - 'tpaint', 03:25:36 INFO - 'sessionrestore', 03:25:36 INFO - 'sessionrestore_no_auto_restore', 03:25:36 INFO - 'tabpaint']}, 03:25:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:25:36 INFO - 'ts_paint', 03:25:36 INFO - 'tpaint', 03:25:36 INFO - 'sessionrestore', 03:25:36 INFO - 'sessionrestore_no_auto_restore', 03:25:36 INFO - 'tabpaint']}, 03:25:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:25:36 INFO - 'tests': ['a11yr', 03:25:36 INFO - 'ts_paint', 03:25:36 INFO - 'tpaint', 03:25:36 INFO - 'sessionrestore', 03:25:36 INFO - 'sessionrestore_no_auto_restore', 03:25:36 INFO - 'tabpaint']}, 03:25:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:25:36 INFO - 'tests': ['a11yr', 03:25:36 INFO - 'ts_paint', 03:25:36 INFO - 'tpaint', 03:25:36 INFO - 'sessionrestore', 03:25:36 INFO - 'sessionrestore_no_auto_restore', 03:25:36 INFO - 'tabpaint']}, 03:25:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:25:36 INFO - 'tests': ['tsvgx', 03:25:36 INFO - 'tsvgr_opacity', 03:25:36 INFO - 'tart', 03:25:36 INFO - 'tscrollx', 03:25:36 INFO - 'cart']}, 03:25:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:25:36 INFO - 'tsvgr_opacity', 03:25:36 INFO - 'tart', 03:25:36 INFO - 'tscrollx', 03:25:36 INFO - 'cart']}, 03:25:36 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:25:36 INFO - 'talos_options': ['--disable-e10s'], 03:25:36 INFO - 'tests': ['tp5o']}, 03:25:36 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:25:36 INFO - 'tests': ['tp5o']}, 03:25:36 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:25:36 INFO - 'talos_options': ['--disable-e10s', 03:25:36 INFO - '--xperf_path', 03:25:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:25:36 INFO - 'tests': ['tp5n']}, 03:25:36 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:25:36 INFO - 'talos_options': ['--xperf_path', 03:25:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:25:36 INFO - 'tests': ['tp5n']}}, 03:25:36 INFO - 'talos.zip': {'path': '', 03:25:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:25:36 INFO - Running post-action listener: _resource_record_post_action 03:25:36 INFO - ##### 03:25:36 INFO - ##### Finished populate-webroot step (success) 03:25:36 INFO - ##### 03:25:36 INFO - ##### 03:25:36 INFO - ##### Running create-virtualenv step. 03:25:36 INFO - ##### 03:25:36 INFO - Running pre-action listener: _resource_record_pre_action 03:25:36 INFO - Running main action method: create_virtualenv 03:25:36 INFO - Creating virtualenv /builds/slave/test/build/venv 03:25:36 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 03:25:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:25:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:25:36 INFO - Using real prefix '/tools/python27' 03:25:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:25:37 INFO - Installing distribute.............................................................................................................................................................................................done. 03:25:39 INFO - Installing pip.................done. 03:25:39 INFO - Return code: 0 03:25:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:25:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:25:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:25:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:25:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:25:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:39 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 0x10d85ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8f0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb720da48c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7bfd30>, '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 0x10d524db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d7b7b20>, '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.YydfphVY5f/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.BObuhvW3rz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 03:25:39 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 03:25:39 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 03:25:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YydfphVY5f/Render', 03:25:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:25:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:25:39 INFO - 'HOME': '/Users/cltbld', 03:25:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:25:39 INFO - 'LOGNAME': 'cltbld', 03:25:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:39 INFO - 'MOZ_NO_REMOTE': '1', 03:25:39 INFO - 'NO_EM_RESTART': '1', 03:25:39 INFO - 'PAGER': '/bin/cat', 03:25:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:25:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:39 INFO - 'PWD': '/builds/slave/test', 03:25:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:25:39 INFO - 'SHELL': '/bin/bash', 03:25:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BObuhvW3rz/Listeners', 03:25:39 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 03:25:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:25:39 INFO - 'USER': 'cltbld', 03:25:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:25:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:25:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:39 INFO - 'XPC_FLAGS': '0x0', 03:25:39 INFO - 'XPC_SERVICE_NAME': '0', 03:25:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:25:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:25:40 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 03:25:40 INFO - Cleaning up... 03:25:40 INFO - Return code: 0 03:25:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:25:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:25:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:25:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:25:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:25:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:40 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 0x10d85ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8f0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb720da48c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7bfd30>, '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 0x10d524db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d7b7b20>, '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.YydfphVY5f/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.BObuhvW3rz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 03:25:40 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 03:25:40 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 03:25:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YydfphVY5f/Render', 03:25:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:25:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:25:40 INFO - 'HOME': '/Users/cltbld', 03:25:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:25:40 INFO - 'LOGNAME': 'cltbld', 03:25:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:40 INFO - 'MOZ_NO_REMOTE': '1', 03:25:40 INFO - 'NO_EM_RESTART': '1', 03:25:40 INFO - 'PAGER': '/bin/cat', 03:25:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:25:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:40 INFO - 'PWD': '/builds/slave/test', 03:25:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:25:40 INFO - 'SHELL': '/bin/bash', 03:25:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BObuhvW3rz/Listeners', 03:25:40 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 03:25:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:25:40 INFO - 'USER': 'cltbld', 03:25:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:25:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:25:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:40 INFO - 'XPC_FLAGS': '0x0', 03:25:40 INFO - 'XPC_SERVICE_NAME': '0', 03:25:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:25:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:25:40 INFO - Downloading/unpacking psutil>=3.1.1 03:25: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 03:25: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 03:25: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 03:25: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 03:25: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 03:25: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 03:25:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:25:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:25:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:25:43 INFO - Installing collected packages: psutil 03:25:43 INFO - Running setup.py install for psutil 03:25:43 INFO - building 'psutil._psutil_osx' extension 03:25:43 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 03:25:44 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 03:25:44 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 03:25:44 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 03:25:44 INFO - building 'psutil._psutil_posix' extension 03:25:44 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 03:25:44 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:25:44 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:25:44 INFO - ^ 03:25:44 INFO - 1 warning generated. 03:25:44 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 03:25:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:25:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:25:44 INFO - Successfully installed psutil 03:25:44 INFO - Cleaning up... 03:25:44 INFO - Return code: 0 03:25:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:25: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')]} 03:25:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:25:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25: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')]} 03:25:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:25:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d85ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8f0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb720da48c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7bfd30>, '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 0x10d524db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d7b7b20>, '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.YydfphVY5f/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.BObuhvW3rz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 03:25: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:25: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 mozsystemmonitor==0.3 03:25:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YydfphVY5f/Render', 03:25:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:25:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:25:44 INFO - 'HOME': '/Users/cltbld', 03:25:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:25:44 INFO - 'LOGNAME': 'cltbld', 03:25:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:44 INFO - 'MOZ_NO_REMOTE': '1', 03:25:44 INFO - 'NO_EM_RESTART': '1', 03:25:44 INFO - 'PAGER': '/bin/cat', 03:25:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:25:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:44 INFO - 'PWD': '/builds/slave/test', 03:25:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:25:44 INFO - 'SHELL': '/bin/bash', 03:25:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BObuhvW3rz/Listeners', 03:25:44 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 03:25:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:25:44 INFO - 'USER': 'cltbld', 03:25:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:25:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:25:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:44 INFO - 'XPC_FLAGS': '0x0', 03:25:44 INFO - 'XPC_SERVICE_NAME': '0', 03:25:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:25:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:25:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:25:44 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 03:25:44 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 03:25:44 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 03:25:44 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 03:25:44 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 03:25:44 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 03:25:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:25:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:25:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:25:47 INFO - Installing collected packages: mozsystemmonitor 03:25:47 INFO - Running setup.py install for mozsystemmonitor 03:25:47 INFO - Successfully installed mozsystemmonitor 03:25:47 INFO - Cleaning up... 03:25:47 INFO - Return code: 0 03:25:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:25:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:25:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:25:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:25:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:25:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d85ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8f0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb720da48c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7bfd30>, '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 0x10d524db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d7b7b20>, '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.YydfphVY5f/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.BObuhvW3rz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 03:25:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:25:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:25:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YydfphVY5f/Render', 03:25:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:25:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:25:47 INFO - 'HOME': '/Users/cltbld', 03:25:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:25:47 INFO - 'LOGNAME': 'cltbld', 03:25:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:47 INFO - 'MOZ_NO_REMOTE': '1', 03:25:47 INFO - 'NO_EM_RESTART': '1', 03:25:47 INFO - 'PAGER': '/bin/cat', 03:25:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:25:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:47 INFO - 'PWD': '/builds/slave/test', 03:25:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:25:47 INFO - 'SHELL': '/bin/bash', 03:25:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BObuhvW3rz/Listeners', 03:25:47 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 03:25:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:25:47 INFO - 'USER': 'cltbld', 03:25:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:25:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:25:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:47 INFO - 'XPC_FLAGS': '0x0', 03:25:47 INFO - 'XPC_SERVICE_NAME': '0', 03:25:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:25:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:25:47 INFO - Downloading/unpacking jsonschema==2.5.1 03:25:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:25:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:25:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:25:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:25:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:25:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:25:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:25:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:25:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:25:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:25:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:25:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:25:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:25:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:25:50 INFO - Downloading functools32-3.2.3-2.tar.gz 03:25:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:25:50 INFO - warning: no files found matching '*.txt' 03:25:50 INFO - no previously-included directories found matching 'build' 03:25:50 INFO - no previously-included directories found matching 'dist' 03:25:50 INFO - no previously-included directories found matching '.git*' 03:25:50 INFO - Installing collected packages: jsonschema, functools32 03:25:50 INFO - Running setup.py install for functools32 03:25:51 INFO - warning: no files found matching '*.txt' 03:25:51 INFO - no previously-included directories found matching 'build' 03:25:51 INFO - no previously-included directories found matching 'dist' 03:25:51 INFO - no previously-included directories found matching '.git*' 03:25:51 INFO - Successfully installed jsonschema functools32 03:25:51 INFO - Cleaning up... 03:25:51 INFO - Return code: 0 03:25:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:25:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:25:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:25:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:25:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:25:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d85ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8f0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb720da48c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7bfd30>, '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 0x10d524db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d7b7b20>, '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.YydfphVY5f/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.BObuhvW3rz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 03:25:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:25:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:25:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YydfphVY5f/Render', 03:25:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:25:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:25:51 INFO - 'HOME': '/Users/cltbld', 03:25:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:25:51 INFO - 'LOGNAME': 'cltbld', 03:25:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:51 INFO - 'MOZ_NO_REMOTE': '1', 03:25:51 INFO - 'NO_EM_RESTART': '1', 03:25:51 INFO - 'PAGER': '/bin/cat', 03:25:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:25:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:51 INFO - 'PWD': '/builds/slave/test', 03:25:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:25:51 INFO - 'SHELL': '/bin/bash', 03:25:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BObuhvW3rz/Listeners', 03:25:51 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 03:25:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:25:51 INFO - 'USER': 'cltbld', 03:25:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:25:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:25:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:51 INFO - 'XPC_FLAGS': '0x0', 03:25:51 INFO - 'XPC_SERVICE_NAME': '0', 03:25:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:25:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:25:51 INFO - Downloading/unpacking blobuploader==1.2.4 03:25:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:25:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:25:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:25:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:25:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:25:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:25:53 INFO - Downloading blobuploader-1.2.4.tar.gz 03:25:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:25:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:25:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:25:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:25:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:25:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:25:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:25:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:25:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:25:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:25:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:25:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:25:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:25:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:25:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:25:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:25:55 INFO - Downloading docopt-0.6.1.tar.gz 03:25:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:25:55 INFO - Installing collected packages: blobuploader, requests, docopt 03:25:55 INFO - Running setup.py install for blobuploader 03:25:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:25:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:25:55 INFO - Running setup.py install for requests 03:25:55 INFO - Running setup.py install for docopt 03:25:55 INFO - Successfully installed blobuploader requests docopt 03:25:55 INFO - Cleaning up... 03:25:56 INFO - Return code: 0 03:25:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:25:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:25:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:25:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:25:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:25:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:56 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 0x10d85ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8f0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb720da48c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7bfd30>, '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 0x10d524db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d7b7b20>, '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.YydfphVY5f/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.BObuhvW3rz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 03:25:56 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 03:25:56 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 03:25:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YydfphVY5f/Render', 03:25:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:25:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:25:56 INFO - 'HOME': '/Users/cltbld', 03:25:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:25:56 INFO - 'LOGNAME': 'cltbld', 03:25:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:56 INFO - 'MOZ_NO_REMOTE': '1', 03:25:56 INFO - 'NO_EM_RESTART': '1', 03:25:56 INFO - 'PAGER': '/bin/cat', 03:25:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:25:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:56 INFO - 'PWD': '/builds/slave/test', 03:25:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:25:56 INFO - 'SHELL': '/bin/bash', 03:25:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BObuhvW3rz/Listeners', 03:25:56 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 03:25:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:25:56 INFO - 'USER': 'cltbld', 03:25:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:25:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:25:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:56 INFO - 'XPC_FLAGS': '0x0', 03:25:56 INFO - 'XPC_SERVICE_NAME': '0', 03:25:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:25:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:25:56 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-lQ_KV0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:25:56 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-x8ChQb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:25:56 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-a5Dhe6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:25:56 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-iLJISK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:25:56 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-MTMPh7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:25:56 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-3lbRt5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:25:56 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-eGJUx0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:25:57 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-CFZlzV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:25:57 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-xCcWwE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:25:57 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-jtqaaI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:25:57 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-MU4Ics-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:25:57 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-rWSYbs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:25:57 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-0M36vc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:25:57 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-Qua07e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:25:57 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-YwwI5x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:25:57 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-6ByYXi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:25:57 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-IKBx7L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:25:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:25:58 INFO - Running setup.py install for manifestparser 03:25:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:25:58 INFO - Running setup.py install for mozcrash 03:25:58 INFO - Running setup.py install for mozdebug 03:25:58 INFO - Running setup.py install for mozdevice 03:25:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:25:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:25:58 INFO - Running setup.py install for mozfile 03:25:58 INFO - Running setup.py install for mozhttpd 03:25:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:25:58 INFO - Running setup.py install for mozinfo 03:25:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:25:59 INFO - Running setup.py install for mozInstall 03:25:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:25:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:25:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:25:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:25:59 INFO - Running setup.py install for mozleak 03:25:59 INFO - Running setup.py install for mozlog 03:25:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:25:59 INFO - Running setup.py install for moznetwork 03:25:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:25:59 INFO - Running setup.py install for mozprocess 03:25:59 INFO - Running setup.py install for mozprofile 03:26:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:26:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:26:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:26:00 INFO - Running setup.py install for mozrunner 03:26:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:26:00 INFO - Running setup.py install for mozscreenshot 03:26:00 INFO - Running setup.py install for moztest 03:26:00 INFO - Running setup.py install for mozversion 03:26:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:26:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:26:00 INFO - Cleaning up... 03:26:00 INFO - Return code: 0 03:26:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:26:00 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')]} 03:26:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:26:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:01 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')]} 03:26:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:26:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:01 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 0x10d85ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8f0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb720da48c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7bfd30>, '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 0x10d524db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d7b7b20>, '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.YydfphVY5f/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.BObuhvW3rz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 03:26:01 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 03:26:01 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 03:26:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YydfphVY5f/Render', 03:26:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:26:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:26:01 INFO - 'HOME': '/Users/cltbld', 03:26:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:26:01 INFO - 'LOGNAME': 'cltbld', 03:26:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:26:01 INFO - 'MOZ_NO_REMOTE': '1', 03:26:01 INFO - 'NO_EM_RESTART': '1', 03:26:01 INFO - 'PAGER': '/bin/cat', 03:26:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:26:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:26:01 INFO - 'PWD': '/builds/slave/test', 03:26:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:26:01 INFO - 'SHELL': '/bin/bash', 03:26:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BObuhvW3rz/Listeners', 03:26:01 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 03:26:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:26:01 INFO - 'USER': 'cltbld', 03:26:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:26:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:26:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:26:01 INFO - 'XPC_FLAGS': '0x0', 03:26:01 INFO - 'XPC_SERVICE_NAME': '0', 03:26:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:26:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:26:01 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-r_h0V3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:26:01 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)) 03:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:26:01 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-xq7FFP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:26:01 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)) 03:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:26:01 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-njad08-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:26:01 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)) 03:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:26:01 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-xFlVAC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:26:01 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)) 03:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:26:01 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-WJqpaz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:26:01 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)) 03:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:26:01 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-vCTEIc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:26:01 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)) 03:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:26:01 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-HssxIb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:26:02 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)) 03:26:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:26:02 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-OVSiBF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:26:02 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)) 03:26:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:26:02 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-wygIjk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:26:02 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)) 03:26:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:26:02 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-mxGAWD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:26:02 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)) 03:26:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:26:02 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-km8SyG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:26:02 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)) 03:26:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:26:02 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-dy3kMT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:26:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:26:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:26:02 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-3eTUaL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:26:02 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)) 03:26:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:26:02 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-JO3G8X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:26:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:26:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:26:02 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-RyDZ6G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:26:02 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)) 03:26:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:26:02 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-j69udH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:26:03 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)) 03:26:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:26:03 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-8MPSVY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:26:03 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)) 03:26:03 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)) 03:26:03 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)) 03:26:03 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)) 03:26:03 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)) 03:26:03 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)) 03:26:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:26:03 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 03:26:03 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 03:26:03 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 03:26:03 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 03:26:03 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 03:26:03 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 03:26:05 INFO - Downloading blessings-1.6.tar.gz 03:26:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:26:06 INFO - Installing collected packages: blessings 03:26:06 INFO - Running setup.py install for blessings 03:26:06 INFO - Successfully installed blessings 03:26:06 INFO - Cleaning up... 03:26:06 INFO - Return code: 0 03:26:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:26:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:26:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:26:06 INFO - Reading from file tmpfile_stdout 03:26:06 INFO - Current package versions: 03:26:06 INFO - blessings == 1.6 03:26:06 INFO - blobuploader == 1.2.4 03:26:06 INFO - docopt == 0.6.1 03:26:06 INFO - functools32 == 3.2.3-2 03:26:06 INFO - jsonschema == 2.5.1 03:26:06 INFO - manifestparser == 1.1 03:26:06 INFO - mozInstall == 1.12 03:26:06 INFO - mozcrash == 0.17 03:26:06 INFO - mozdebug == 0.1 03:26:06 INFO - mozdevice == 0.48 03:26:06 INFO - mozfile == 1.2 03:26:06 INFO - mozhttpd == 0.7 03:26:06 INFO - mozinfo == 0.9 03:26:06 INFO - mozleak == 0.1 03:26:06 INFO - mozlog == 3.2 03:26:06 INFO - moznetwork == 0.27 03:26:06 INFO - mozprocess == 0.22 03:26:06 INFO - mozprofile == 0.28 03:26:06 INFO - mozrunner == 6.11 03:26:06 INFO - mozscreenshot == 0.1 03:26:06 INFO - mozsystemmonitor == 0.3 03:26:06 INFO - moztest == 0.7 03:26:06 INFO - mozversion == 1.4 03:26:06 INFO - psutil == 3.1.1 03:26:06 INFO - requests == 1.2.3 03:26:06 INFO - wsgiref == 0.1.2 03:26:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:26: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')]} 03:26:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:26:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26: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')]} 03:26:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:26:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:06 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 0x10d85ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8f0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb720da48c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7bfd30>, '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 0x10d524db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d7b7b20>, '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.YydfphVY5f/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.BObuhvW3rz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 03:26:06 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 03:26:06 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 03:26:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YydfphVY5f/Render', 03:26:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:26:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:26:06 INFO - 'HOME': '/Users/cltbld', 03:26:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:26:06 INFO - 'LOGNAME': 'cltbld', 03:26:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:26:06 INFO - 'MOZ_NO_REMOTE': '1', 03:26:06 INFO - 'NO_EM_RESTART': '1', 03:26:06 INFO - 'PAGER': '/bin/cat', 03:26:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:26:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:26:06 INFO - 'PWD': '/builds/slave/test', 03:26:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:26:06 INFO - 'SHELL': '/bin/bash', 03:26:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BObuhvW3rz/Listeners', 03:26:06 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 03:26:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:26:06 INFO - 'USER': 'cltbld', 03:26:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:26:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:26:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:26:06 INFO - 'XPC_FLAGS': '0x0', 03:26:06 INFO - 'XPC_SERVICE_NAME': '0', 03:26:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:26:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:26:06 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)) 03:26:06 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)) 03:26:06 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)) 03:26:06 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)) 03:26:06 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)) 03:26:06 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)) 03:26:06 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)) 03:26:06 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)) 03:26:06 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)) 03:26:06 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)) 03:26:06 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)) 03:26:06 INFO - Cleaning up... 03:26:06 INFO - Return code: 0 03:26:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:26: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')]} 03:26:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:26:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26: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')]} 03:26:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:26:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d85ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8f0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb720da48c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7bfd30>, '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 0x10d524db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d7b7b20>, '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.YydfphVY5f/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.BObuhvW3rz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 03:26:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:26:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:26:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YydfphVY5f/Render', 03:26:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:26:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:26:06 INFO - 'HOME': '/Users/cltbld', 03:26:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:26:06 INFO - 'LOGNAME': 'cltbld', 03:26:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:26:06 INFO - 'MOZ_NO_REMOTE': '1', 03:26:06 INFO - 'NO_EM_RESTART': '1', 03:26:06 INFO - 'PAGER': '/bin/cat', 03:26:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:26:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:26:06 INFO - 'PWD': '/builds/slave/test', 03:26:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:26:06 INFO - 'SHELL': '/bin/bash', 03:26:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BObuhvW3rz/Listeners', 03:26:06 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 03:26:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:26:06 INFO - 'USER': 'cltbld', 03:26:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:26:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:26:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:26:06 INFO - 'XPC_FLAGS': '0x0', 03:26:06 INFO - 'XPC_SERVICE_NAME': '0', 03:26:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:26:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:26:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:26:07 INFO - Cleaning up... 03:26:07 INFO - Return code: 0 03:26:07 INFO - Running post-action listener: _resource_record_post_action 03:26:07 INFO - Running post-action listener: _start_resource_monitoring 03:26:07 INFO - Starting resource monitoring. 03:26:07 INFO - ##### 03:26:07 INFO - ##### Finished create-virtualenv step (success) 03:26:07 INFO - ##### 03:26:07 INFO - ##### 03:26:07 INFO - ##### Running install step. 03:26:07 INFO - ##### 03:26:07 INFO - Running pre-action listener: _resource_record_pre_action 03:26:07 INFO - Running main action method: install 03:26:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:26:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:26:07 INFO - Reading from file tmpfile_stdout 03:26:07 INFO - Detecting whether we're running mozinstall >=1.0... 03:26:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:26:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:26:07 INFO - Reading from file tmpfile_stdout 03:26:07 INFO - Output received: 03:26:07 INFO - Usage: mozinstall [options] installer 03:26:07 INFO - Options: 03:26:07 INFO - -h, --help show this help message and exit 03:26:07 INFO - -d DEST, --destination=DEST 03:26:07 INFO - Directory to install application into. [default: 03:26:07 INFO - "/builds/slave/test"] 03:26:07 INFO - --app=APP Application being installed. [default: firefox] 03:26:07 INFO - mkdir: /builds/slave/test/build/application 03:26:07 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'] 03:26:07 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 03:26:41 INFO - Reading from file tmpfile_stdout 03:26:41 INFO - Output received: 03:26:41 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:26:41 INFO - Running post-action listener: _resource_record_post_action 03:26:41 INFO - ##### 03:26:41 INFO - ##### Finished install step (success) 03:26:41 INFO - ##### 03:26:41 INFO - ##### 03:26:41 INFO - ##### Running run-tests step. 03:26:41 INFO - ##### 03:26:41 INFO - Running pre-action listener: _resource_record_pre_action 03:26:41 INFO - Running main action method: run_tests 03:26:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:26:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:26:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:26:42 INFO - 2016-07-25 03:26:42.011 screenresolution[1657:7768] starting screenresolution argv=screenresolution get 03:26:42 INFO - 2016-07-25 03:26:42.019 screenresolution[1657:7768] Display 0: 1600x1200x32@60 03:26:42 INFO - 2016-07-25 03:26:42.025 screenresolution[1658:7778] starting screenresolution argv=screenresolution list 03:26:42 INFO - Available Modes on Display 0 03:26:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:26:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:26:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:26:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:26:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:26:42 INFO - Intel Iris: 03:26:42 INFO - Chipset Model: Intel Iris 03:26:42 INFO - Type: GPU 03:26:42 INFO - Bus: Built-In 03:26:42 INFO - VRAM (Dynamic, Max): 1536 MB 03:26:42 INFO - Vendor: Intel (0x8086) 03:26:42 INFO - Device ID: 0x0a2e 03:26:42 INFO - Revision ID: 0x0009 03:26:42 INFO - Displays: 03:26:42 INFO - Display: 03:26:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:26:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:26:42 INFO - Display Serial Number: 1600x1200 60 03:26:42 INFO - Main Display: Yes 03:26:42 INFO - Mirror: Off 03:26:42 INFO - Online: Yes 03:26:42 INFO - Rotation: Supported 03:26:42 INFO - Return code: 0 03:26:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:26:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:26:42 INFO - Python 2.7.3 03:26:42 INFO - Return code: 0 03:26:42 INFO - grabbing minidump binary from tooltool 03:26:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:26:42 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 0x10d7bfd30>, '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 0x10d524db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d7b7b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:26:42 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 03:26:42 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 03:26:42 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 03:26:42 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:26:42 INFO - Return code: 0 03:26:42 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:26:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:26:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:26:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:26:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:26:42 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/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630177/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0390', '--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 03:26:42 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/Nightly.app/Contents/MacOS/firefox --symbolsPath http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630177/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0390 --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 03:26:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YydfphVY5f/Render', 03:26:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:26:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:26:42 INFO - 'HOME': '/Users/cltbld', 03:26:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:26:42 INFO - 'LOGNAME': 'cltbld', 03:26:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:26:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:26:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:26:42 INFO - 'MOZ_NO_REMOTE': '1', 03:26:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:26:42 INFO - 'NO_EM_RESTART': '1', 03:26:42 INFO - 'PAGER': '/bin/cat', 03:26:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:26:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:26:42 INFO - 'PWD': '/builds/slave/test', 03:26:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:26:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:26:42 INFO - 'SHELL': '/bin/bash', 03:26:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BObuhvW3rz/Listeners', 03:26:42 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 03:26:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:26:42 INFO - 'USER': 'cltbld', 03:26:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:26:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:26:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:26:42 INFO - 'XPC_FLAGS': '0x0', 03:26:42 INFO - 'XPC_SERVICE_NAME': '0', 03:26:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:26:42 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/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630177/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0390', '--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 03:26:42 INFO - mozversion application_buildid: 20160704040257 03:26:42 INFO - mozversion application_changeset: 4191df8e4b8e1e2dd0593132dac25915f987f797 03:26:42 INFO - mozversion application_display_name: Nightly 03:26:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:26:42 INFO - mozversion application_name: Firefox 03:26:42 INFO - mozversion application_remotingname: firefox 03:26:42 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:26:42 INFO - mozversion application_vendor: Mozilla 03:26:42 INFO - mozversion application_version: 50.0a1 03:26:42 INFO - mozversion platform_buildid: 20160704040257 03:26:42 INFO - mozversion platform_changeset: 4191df8e4b8e1e2dd0593132dac25915f987f797 03:26:42 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:26:42 INFO - mozversion platform_version: 50.0a1 03:26:42 INFO - using testdate: 1469442402 03:26:42 INFO - actual date: 1469442402 03:26:42 INFO - starting webserver on 'localhost:49265' 03:26:42 INFO - SUITE-START | Running 2 tests 03:26:42 INFO - TEST-START | dromaeo_css 03:26:42 INFO - operating with platform_type : mac_ 03:26:42 INFO - Initialising browser for dromaeo_css test... 03:26:42 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/tmpaTv5O3/profile http://localhost:49265/getInfo.html) 03:26:45 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 03:26:45 INFO - PROCESS | 1668 | colorDepth:24 03:26:45 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 03:26:45 INFO - PROCESS | 1668 | __metrics 03:26:45 ERROR - PROCESS | 1668 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2968: TypeError: this.tabs is undefined 03:26:46 INFO - TEST-INFO | 1668: exit 0 03:26:46 INFO - Browser initialized. 03:26:46 INFO - Running cycle 1/1 for dromaeo_css test... 03:26:46 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/tmpaTv5O3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:26:58 INFO - PROCESS | 1671 | RSS: Main: 255737856 03:26:58 INFO - PROCESS | 1671 | 03:27:48 INFO - PROCESS | 1671 | 1469442468411 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] 03:29:59 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49265/tests/dromaeo/cssquery-ext.html) 03:30:00 INFO - PROCESS | 1671 | RSS: Main: 269787136 03:30:00 INFO - PROCESS | 1671 | 03:33:02 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-ext.html (next: http://localhost:49265/tests/dromaeo/cssquery-jquery.html) 03:33:03 INFO - PROCESS | 1671 | RSS: Main: 278454272 03:33:03 INFO - PROCESS | 1671 | 03:36:05 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49265/tests/dromaeo/cssquery-mootools.html) 03:36:06 INFO - PROCESS | 1671 | RSS: Main: 283074560 03:36:06 INFO - PROCESS | 1671 | 03:39:07 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49265/tests/dromaeo/cssquery-prototype.html) 03:39:08 INFO - PROCESS | 1671 | RSS: Main: 282062848 03:39:08 INFO - PROCESS | 1671 | 03:42:11 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49265/tests/dromaeo/cssquery-yui.html) 03:42:12 INFO - PROCESS | 1671 | RSS: Main: 285884416 03:42:12 INFO - PROCESS | 1671 | 03:45:14 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-yui.html (next: http://localhost:49265/tests/dromaeo/cssquery-dojo.html) 03:45:15 INFO - PROCESS | 1671 | RSS: Main: 286052352 03:45:15 INFO - PROCESS | 1671 | 03:45:15 INFO - PROCESS | 1671 | __start_tp_report 03:45:15 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 03:45:15 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 03:45:15 INFO - PROCESS | 1671 | |i|pagename|runs| 03:45:15 INFO - PROCESS | 1671 | |0;dojo.html;2480.6403071554614;2831.801773875828;2991.581178634991;2993.161914663894;3402.387570237356;5971.552133589977;5986.161937328772;6055.878882422349;6057.757689692407;6071.423214794593;5786.9710651446685;5974.8207553773345;6002.189704389906;6006.519478441725;6050.42520960508;4507.5943165115295;5266.368035835695;5317.813876514323;5365.490278423563;5397.62216644835;5397.379301380332;5427.0773968425465;5444.809431669893;5453.127499600065;5468.26178465908;5161.587073034186;5171.043356978933;5226.895462090775;5301.3108295921475;5312;5243.4232234454175;5244.737763111867;5244.977229440261;5254.973725131388;5257.106291930381;5163.302954101186;5188.844334669966;5225.059489291926;5272.789088436458;5273.789048438058;5723.656580605177;5788.594798364116;5890.528757699374;5918.526517878559;6005.429484198994;6175.950088484969;6222.52719931583;6243.531735119885;6256.15541901842;6259.405356491126;5667.838093190927;6002.519798416116;6319.905201421982;6328.936710632881;6329.208848893888;5936.821895343147;5985.16207730918;5998.430149135868;6003.369646187175;6025.698715064317;6414.839629009312;6456.257530383972;6458.870822583616;6471.514636402195;6482.222133344029;6146.446819786241;6210.968945155336;6299.842503937438;6302.621842689453;6306.936930630635;6210.254769427697;6264.310925798159;6324.810255692337;6327.525435592349;6333.809985700436;5671.262735844396;5767.192593036978;6193.256809182927;6225.097360882737;6325.399087086719;6224.906626400608;6247.875042499125;6263.279722831841;6291.874162516816;6291.930371836707;4987.426445958688;5023.19628859388;5029;5029.698218106925;5071.163258062452;4476.865694029184;4500.9099818004115;4526.524714904954;4535.342375355555;4547.636189104864;5851.64890106595;6001.969990150109;6079.08813677943;6136.048912418582;6145.016797312498;5289.973550132225;6330.715117819709;6338.904916426257;6347.650879201688;6350.7459701611415;6107;6109.419605137505;6112;6126.509879209652;6129.295131059896;5486.561075114061;5559;5561.527270181999;5568.387477377485;5588.469095435927;6457.5479716419395;6476;6483.351664833479;6489.837754056186;6568.343165683394;6272.905906411499;6306.400891915261;6310.716017779303;6336.081268216175;6353.8729225416155;6210.472109870711;6357.682115894094;6389.041643753474;6431.099646049651;6528.347165283433;6287.842803929939;6315.905261421174;6316.62100273985;6325.4939604832425;6343.9682801585695;6231.657258850807;6319.05214217871;6334.651594162366;6339.429451349401;6342;6022.939770602238;6189.381061893955;6210.285817130907;6222.408871157233;6263.185785847811;6210.782622608187;6234.127222188807;6298.433141017331;6302.905456418249;6313.55805093639;6095.451409373178;6100.725467354068;6143.6928153593035;6174.487250623626;6197.442230199304;6150.169727086786;6172.105044768573;6205.844853878689;6218.315985241529;6319.146915166578;6099.2189903441595;6190.3190649029475;6193.256809182927;6197.194364732556;6198.659073750987 03:45:15 INFO - PROCESS | 1671 | |1;ext.html;485.5266115537354;516.5841497594437;517.8342930262316;540.4352451687986;564.9209110724497;39444.80277598615;42308;42335.15329693412;42345.99999999996;42356.78821605888;43215.78392108035;43779.34330985038;43830.56169438305;43841.12317753643;43854.780726096404;9631.85552216719;9719.319647624669;9799.314048016642;9807.460589667566;9823.115919567237;9735.659251926185;9796.70609881701;9813.509324533781;9816.950915245414;9840.409575425498;9822.214222862154;9857.162141218005;9862.45756483393;9868.555914983806;9870.358426702242;9794.755131121708;9807.215422766161;9828.361156524803;9851.852222216601;9868.703938881808;9667;9756.317057805956;9803;9803.705888823346;9822.65620703272;41168.176636467106;41550.58449415497;41558.37662435066;41648.58351416478;41660.95847603804;3567.5362202913457;3663.6519530644814;3689.317476266899;3805.315043292206;4292.1415716856445;27754.722452775415;28967.56548651772;31189;31421;33394.99815005554;3231.789933654305;3435.2957643682985;3442.7934323940644;3447.258839349552;3447.3967055765393;540594;545167;553133;554151;554266.2286688581;71143;71832.28167718256;71973.64013179901;72055.39723013829;72143.2785672147;73617;74294;74332;74409;74736;17423.041732704824;17629.382971596187;17883.642327153644;17905.925644461375;17926.282948681906;13411.530596429273;13513.256770877697;13521;13543.796843047363;13547.932260338635;17650.303893773766;21529.784702152778;21545.56908861814;21614.243501477373;21671.34985950424;3350.6816852398983;7898;8741.30069594431;8763.737087887373;9033.81932361349;874.8600223964136;984.6061575369766;1063.9361638301727;1067.6743593203992;1120.3389999900098;30975.380492390024;31201;31287.530687039256;31803;31870.521942170886;959.2421986630484;1036.3056751976194;1064.6220591689994;1086.0497065068066;1096.4517741129434;15071.54785356441;15434.691306173814;16587.170641467877;16610.83389166093;16637.41769038078;625189;677301;696532;701946;702985;62738.0589291161;62841;63531.36468635347;63659;63686.6815665928;1389.8193234879602;1400.6918477934837;1426.2726009734902;1429.756941319978;1432.4485073246874;23361.649575256724;24753.00413864709;30313.393732124794;30943.535846962764;31583.052508423865;55192.44807551873;55449.89100217893;56059.719701402864;56151;56159;524563;611815;613117;614114;615317;28081.157565273075;28275.293117672223;28506.57240141441;28629.42741145207;28684.996025139855;43129.9217519552;51095;51367.48632513627;52002.73998629983;52043.47956520538;31910.361792764477;37287.81356093202;40649.39025914673;42732;43498.56501434945;41102.1779564413;41521.37717934294;42112.3683144747;42699.57300426956;42986.35520467258;40272.99317517086;40312.79843600763;40730.59269407268;40895;41313.1737365257;35116.64883351134;36061;36397.636023639425;36900.630993689716;37037.44443833291;22165.337599679766;25275.736213189633;25968.091116811578;26152.34619134537;26520.071797486995 03:45:15 INFO - PROCESS | 1671 | |2;jquery.html;302.2232861545827;350.87543921907724;382.48364707644686;402.12938987092946;411.8517333759848;21151.894240528796;21827.563448731016;21957.890210548943;22372.55254894901;22375.32185086119;20616.484587885312;20822.479438014056;21325.040373183205;21508.247211347607;21588.568228635457;4859.562639362457;4870.756462176894;4951;5447.836564903049;5623.212750214973;5479.643823151482;5543.390227075019;5556.333240011204;5584;5603.887922241553;4770.904581908359;5417.51242388185;5468;5479.780808767645;5499.395066542677;5571.415001424853;5576.804811831586;5577.721113944327;5585.916211256835;5587.944120558804;3989.501312335958;5294.205869119624;5351.170568561879;5366.9463305366835;5410;13302.068855180141;13482.528111516149;13853.792193117111;13856.030077894551;13869.306534673226;1984.960300793976;2105.8525903186783;2242.439390152462;2444.352246654638;2932.017774045697;10127.898721012843;10419;10829.945850270777;15876.12681302528;15986.600334991603;1717.1929193279138;1728.3259528783658;1778.5375802291496;1800.2169056460482;1813.5919418130948;84529.99999999939;92623;124242;125548;128800.35599822034;11488.023518000897;11682.824757628643;11749.88250117505;11791.823122653168;11968.162228644085;14403.567892963229;14532.564023079325;14600.634984125269;14720.043197192148;14732.189729564763;8800.691975780945;8814.338924580683;8853.822923541493;8944.642214311485;8974.192322690858;8804.427712198687;8814.162654547808;8861.778455538533;8892.911070889339;8961.865572016424;3826.674732647756;4556.088782243564;4591.747453890068;4617.8152873884665;4714.363560919301;1798.7841459025;1927.4795805132535;1947.3476385410763;1982.692682634194;2009.437357539891;865.8571335729534;925.9305552083621;997.7206382212992;1018.3745864721615;1042.8018676451602;18562.72155917662;21041.368758937257;21509.569808604054;22091.558168836535;22467;866.9306455483672;876.9342299327576;966.3090890013674;1021.1881554164308;1027.5735569738474;8915.331350148765;9705.417674939385;11570.537178512934;11786.528538858522;11793;621098;646656;649003;650252.7487362532;653352;91841.62237566442;137754;147698;147821;148269;951.4529145741227;992.6277645882794;993.6025589764153;997.4962643864802;1027.686555600549;19887.60224795525;20240.898795505927;20322.187112515334;20538.69191962092;20539.58920821605;56308;58447.032002280284;60009;60341.698291510016;60966;84178.15821841703;90099;90168.64747028668;90815;90938.54530727305;6284.717187726472;8036.718714844952;8176.591170441335;8256.669733210605;8342.499450033018;11717.296962182296;13087.41106650222;13812.792808108088;13860.237686927316;14081.859181408465;11750.823737643772;12012.579559715368;12045.939770301093;12048.75902481928;12105.878941210474;11830.290182589073;11912.642620721392;11914.046876250053;11993.64019079429;12084;10335.534900929326;12042.939785301018;12109.818352724893;12157.939210303892;12203.450844711831;7715.57564333967;7717.80705482345;7775.028121484815;7780.377569794516;7858.135605083322;11699;11965.641030769091;12115.394230288273;12185.451654675739;12205.084618653458 03:45:15 INFO - PROCESS | 1671 | |3;mootools.html;1572.237464829558;1621.959451013725;1872.485066606683;1942.893140877252;1955.7359756432877;8024.638891249893;8580.91419085809;8616;9034.68378606748;9062.093790620942;8726.91273087269;8739.563021848899;8850.690225842096;8881.245094166987;8911.866322005175;2348.1429278313376;2358.681577986971;2381.071382160959;2393.6289875069306;2397.7482364351754;2338.684277622525;2381.6427535869584;2398.70016247969;2406.0736616402723;2412.6501657259687;2386.152915714922;2393.246127469841;2399.1722855614785;2400.051979468109;2407.2296865003204;2347.7065366829147;2347.929562113139;2352.6823878776318;2354.5997180479358;2383.7616238376195;2341.6253399456004;2349.3304408243566;2362;2368.846025008369;2370.3126093432884;8967.148120928501;8983.056779038237;9002.369834111614;9039.050899655507;9041.77395565116;5246.921296180561;5340.519353258225;5394.352677678665;5407.053765591006;5410.513053825135;3568.750187486851;3599.2981368633127;3649.4525821126776;3654.2326111516613;3657.7988210648405;1060.3796778884362;1068.64200492835;1080.827067669169;1083.0685610375072;1099.7470581766238;11232.157588180919;11313.773724525463;11398.601048963246;11400.144989125849;11402.71493212668;4620.491745907947;4623.653226008063;4806.711597304103;4807.471178170398;4810.831620893252;9852.014798520235;9860.013998600227;9861.753456163653;9876;9899.950500247453;5319.654222475527;5346.449529636362;5353.544948679328;5365.436629153961;5373.35519737634;4416.734995900256;4798.928016079762;4834.903301934012;4921;4963.10664080469;3963.9297389705193;4034.757914525138;4079.082206503513;4121.855735049319;4150.294449943517;1293.3921057103335;1301.804729290595;1315.4869600855675;1316.4207748590588;1329.0829327763813;887.2281115429618;932.1191474056951;963.2438535749403;983.1397527163732;1019.9286049976579;6041.18444010053;6090.177825993434;6140.8771824563255;6163.599366041193;6197.504199664016;428.4366058632888;435.77775334579593;437.68049323993665;1021.8620486234412;1060.3584831177182;4079.653229475528;4138.193052354762;4144.79276036197;4186.602272784081;4196.265653510623;10609.257351985441;10845.349279043283;10926.945365273281;10943.671689849318;10945.781084378268;10540.472976351151;10661.573537058586;10675.199360048027;10720.839187412039;10867;8119.147489513634;8137.918620813834;8267;8277.917220827836;8315.542645154575;5600.887982240414;5910.940890591039;6141.570090093651;6171.691415429301;6229.2524897012645;8540.530270834915;9599.952000239955;9827.656032038843;10020.64927727526;10170.796584068425;9859.260555458226;10226.948865255627;10231.437270949871;10314.48427578603;10350.844737328796;6505.544611877123;7400;7476.140243872024;7552.9244707552225;7560.017197764256;7209;7241.384482319062;8481.770143329059;8536.31709463229;8543.829123417621;6478.967605161944;7166.89249661266;7658.310752032344;7670.309672129535;7881.133075361591;7456.291652293024;7542;7633.69465221385;7638.885416718643;7652.540847549165;7139.714411423693;7205.819854503679;7247.202807691045;7253.3834624057545;7264.818379540342;5028.446870844277;5132.666376685504;5221.060209162387;5227.503387178364;5237.978594174099;4808.014357056865;4836.371271734793;5129.999650068201;5190.143626301769;5231.267622532924 03:45:15 INFO - PROCESS | 1671 | |4;prototype.html;295.1499680918956;310.6567243196268;313.78662509493535;316.37515906085486;318.0617179321003;5679.375268720442;5695.971520142393;5721.370649228582;5745.138229265607;5748.25272714547;4881.267809828528;5567;5578.9163162552595;5585;5648.74580643871;4057.2899742545083;4154.273002224613;4239.236937351276;4250.787460626972;4277.465316835395;4176.164767046587;4232.3228283474655;4237.7457352558795;4246.9150616987645;4249.19265339586;3849;4109.876703698894;4212.283911734997;4227.936580951273;4228.281192197333;3491;3898.688104951597;4091.713580049398;4177.707560470768;4232.407462955173;2713.6743590769233;3364.637321884616;3540.8937731868086;3569.839357228931;3582.5880023797336;4056.817443215062;4520.570545798144;4807.975960120212;5232.450592687751;5333.2000199969925;4273.636740877029;4581.511008922081;4820.180569303227;4838.419389673227;4866.270059491069;4615.26155815068;4790.724405907201;4965.379327584052;5156.510131537498;5896.233489646362;3585.73697934878;3694.9445758313645;3946.4017073085447;3978.2839088963974;4008.959910400917;5515.503604675559;5902.3507414184405;6157.384261573851;6166.290876549209;6221.688915554249;3794.3739283018544;3835.846566137379;3995.9810248387057;4018.1963607278076;4032.274190645653;3532.2405682778326;3799.544054713452;3981.7013723970817;4000.599940005976;4082.510098788164;4036.777977211223;4157.835805974332;4475.664325175625;4619.514950930104;4820.794171875734;5792.94207057924;5893.469587737124;5934.703264836741;5948.10778383246;5963.403659634002;1259.571745606498;1302.218668798732;1392.8439395301875;1393.3869097597026;1397.189630014589;723.0311382747144;848.5163456829548;854.8589482735279;896.7175339767953;933.7572231219796;4902;4925.310456536088;5145.945081258408;5395.300480348677;5524.58565607573;5324.231399672226;5638.464345887134;5955.255593050869;5996.970015149984;5999.040153575407;4581.496035436099;5231.267622532849;5298.470152984671;5314.176302673091;5397.70312632809;2693.683432248265;2859.2851787053237;2963.97742778741;2968.8718287051215;3053.328267781085;5023;5524.4199359066715;5544.870994330228;5913.876363490923;6243.8126856194385;5282.969820884968;5680.829575112753;6106.419890110433;6198.938010619836;6215.694704112097;6205.410486003822;6262.624242545371;6263.217097862767;6269.153664255358;6321.367863213642;5309.946900531099;5467.9453205467435;5741.569382296261;6026.668533230539;6197.03945888388;5215.243789650403;5853.94146058534;6158.567365890359;6171.074338849208;6184.226971628546;4836.661433699607;6093.299270583762;6120.724567394389;6433.806985790434;6439.291677915519;3992.2215168041953;4016.927050806077;4030.6170913763144;4039.91920161601;4071.328230841878;5104.948950510596;5179.792808287626;6197.628142311476;6249.25008998923;6259.561830671992;5296.602754793329;5949.464548190684;5970.910181436462;6039.154518367344;6063.363346848518;6015.759369625166;6017.097435384728;6020.969895150496;6028.698565071817;6041.939580604314;5604.0491453524355;5612.607117501736;5875.764969401176;5901.586888917906;5923.830750548436;5940.79207227745;5963.855120691078;5964.403559644001;5975.282966044103;6009.579329446897;5781.981522841753;5857.47282744555;5858.384869588632;5860.706964651665;5866.765329386777 03:45:15 INFO - PROCESS | 1671 | |5;yui.html;687.4706476013469;731.38198222502;785.1638005524117;823.3618945317378;829.7884039569909;14158.575242742709;15977.360905563792;16038.198090095508;16110.35558577657;16571.917140414294;14612.926935365309;14718.484853030146;14724.411023559045;14740.262986850641;14783;703.8521910398822;870.7561882672861;890.3189060368823;910.2444970674358;914.4604683236889;894.6197865906996;896.2337201692524;907.2333877873186;911.0889110889111;912.589334799343;973.6543527047902;974.9756256093583;975.0541974284932;975.5024937282002;976.511744127936;961.6105477281712;966.2415004221723;969.4425705219492;970.4807927758686;972.7033254857266;684.9965750171267;720.4416577152696;721.5021635071784;722.6314579564407;724.7137380734633;15003.699926001527;15020;15155.545333640008;15415.768763468557;15459.458918937895;13422.86577134226;14161.150330980174;14843.109413435228;14906.478273260384;14912.70174596502;10442.164626829835;11908.880911190863;11983.820242696367;12067.275963442135;12125;3912.374020156761;3912.960870391288;4013.3779264214095;4102.487189101363;4122.464079669654;112647;113140.43429782799;113182.43408783068;114363;114474.42762786215;11484.368359740294;11540.538378464935;11557.537698491966;11655.76688466226;11731.706707332214;23498.94254758506;23800.04799808023;23835.165769197996;23840.64239036416;23846.284611461684;8372.41393102477;8414.6213420395;8534.658613655509;8630.78423039429;8667.349948753868;4654.581087702055;4661.813527458864;4670.789814458358;4697.530246975275;4751.786169622375;3625.8549658013385;3733.197362567061;3742.7754334740002;3743.7192210584317;3744.64425879541;655.5673255651343;655.9836004099935;667.4460198035619;685.6537448588431;687.4740823270203;619.4394073363613;673.1182151381705;685.530411668009;702.8032150997728;705.3651713457826;8007.359411247086;8352.788845617473;9337.225927073834;9769.120779129771;10622.256442048982;580.4862696513532;617.7035023188895;672.6334147889412;695.6904177641003;696.7073828991836;4449.888752781207;4617.584417402382;4637.7449240291435;4759.9524004759505;4760.714357138583;91042.08957910468;92394.76618318327;106487;147142;150310.24844875705;39698.404523932164;44231.89420264519;127503.362483187;129694;131655.34172329077;67663;101612;110806.44596776964;115802;117224.41387793007;9180.760597319277;9663.758406039906;10018.499075046364;10068.748281293027;10290.434026128634;48886;57357.71321143368;62743.6862815683;63251;63309.683451582445;91055;92341.07658923324;93323.53338233265;94797;95732.0426795751;11577.726450090315;13531.701753080832;13602;13804.447822087406;13838.4464621414;14110.717785644434;14399.92800036035;14535.345909434312;14982.325795339453;15124.016938898936;11488.310701357612;14079;14231.786523201954;14549.92725036368;14584.708305833612;14050.648733781738;14306.713865722419;14367.658837752286;14451.710211655587;15099.39602415935;13433.865661343652;13616.319184040956;13905.70955689227;14277.785833212718;14348.928255358656;11511.049531005567;11655.941720291345;11741.354225517678;11804.645860624196;11818.40907954616;10367.740806479898;10711.25021248505;10727.195460340661;10773.24587278883;10845.566177352817 03:45:15 INFO - PROCESS | 1671 | __end_tp_report 03:45:15 INFO - PROCESS | 1671 | __start_cc_report 03:45:15 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,435 03:45:15 INFO - PROCESS | 1671 | __end_cc_report 03:45:15 INFO - PROCESS | 1671 | __startTimestamp1469443515014__endTimestamp 03:45:15 INFO - PROCESS | 1671 | 03:45:15 INFO - PROCESS | 1671 | ------- Summary: start ------- 03:45:15 INFO - PROCESS | 1671 | Number of tests: 6 03:45:15 INFO - PROCESS | 1671 | 03:45:15 INFO - PROCESS | 1671 | [#0] dojo.html Cycles:165 Average:5847.55 Median:6136.05 stddev:714.35 (11.6%) stddev-sans-first:665.93 03:45:15 INFO - PROCESS | 1671 | Values: 2480.6 2831.8 2991.6 2993.2 3402.4 5971.6 5986.2 6055.9 6057.8 6071.4 5787.0 5974.8 6002.2 6006.5 6050.4 4507.6 5266.4 5317.8 5365.5 5397.6 5397.4 5427.1 5444.8 5453.1 5468.3 5161.6 5171.0 5226.9 5301.3 5312.0 5243.4 5244.7 5245.0 5255.0 5257.1 5163.3 5188.8 5225.1 5272.8 5273.8 5723.7 5788.6 5890.5 5918.5 6005.4 6176.0 6222.5 6243.5 6256.2 6259.4 5667.8 6002.5 6319.9 6328.9 6329.2 5936.8 5985.2 5998.4 6003.4 6025.7 6414.8 6456.3 6458.9 6471.5 6482.2 6146.4 6211.0 6299.8 6302.6 6306.9 6210.3 6264.3 6324.8 6327.5 6333.8 5671.3 5767.2 6193.3 6225.1 6325.4 6224.9 6247.9 6263.3 6291.9 6291.9 4987.4 5023.2 5029.0 5029.7 5071.2 4476.9 4500.9 4526.5 4535.3 4547.6 5851.6 6002.0 6079.1 6136.0 6145.0 5290.0 6330.7 6338.9 6347.7 6350.7 6107.0 6109.4 6112.0 6126.5 6129.3 5486.6 5559.0 5561.5 5568.4 5588.5 6457.5 6476.0 6483.4 6489.8 6568.3 6272.9 6306.4 6310.7 6336.1 6353.9 6210.5 6357.7 6389.0 6431.1 6528.3 6287.8 6315.9 6316.6 6325.5 6344.0 6231.7 6319.1 6334.7 6339.4 6342.0 6022.9 6189.4 6210.3 6222.4 6263.2 6210.8 6234.1 6298.4 6302.9 6313.6 6095.5 6100.7 6143.7 6174.5 6197.4 6150.2 6172.1 6205.8 6218.3 6319.1 6099.2 6190.3 6193.3 6197.2 6198.7 03:45:15 INFO - PROCESS | 1671 | 03:45:15 INFO - PROCESS | 1671 | [#1] ext.html Cycles:180 Average:75171.91 Median:42340.58 stddev:163498.74 (386.2%) stddev-sans-first:163861.23 03:45:15 INFO - PROCESS | 1671 | Values: 485.5 516.6 517.8 540.4 564.9 39444.8 42308.0 42335.2 42346.0 42356.8 43215.8 43779.3 43830.6 43841.1 43854.8 9631.9 9719.3 9799.3 9807.5 9823.1 9735.7 9796.7 9813.5 9817.0 9840.4 9822.2 9857.2 9862.5 9868.6 9870.4 9794.8 9807.2 9828.4 9851.9 9868.7 9667.0 9756.3 9803.0 9803.7 9822.7 41168.2 41550.6 41558.4 41648.6 41661.0 3567.5 3663.7 3689.3 3805.3 4292.1 27754.7 28967.6 31189.0 31421.0 33395.0 3231.8 3435.3 3442.8 3447.3 3447.4 540594.0 545167.0 553133.0 554151.0 554266.2 71143.0 71832.3 71973.6 72055.4 72143.3 73617.0 74294.0 74332.0 74409.0 74736.0 17423.0 17629.4 17883.6 17905.9 17926.3 13411.5 13513.3 13521.0 13543.8 13547.9 17650.3 21529.8 21545.6 21614.2 21671.3 3350.7 7898.0 8741.3 8763.7 9033.8 874.9 984.6 1063.9 1067.7 1120.3 30975.4 31201.0 31287.5 31803.0 31870.5 959.2 1036.3 1064.6 1086.0 1096.5 15071.5 15434.7 16587.2 16610.8 16637.4 625189.0 677301.0 696532.0 701946.0 702985.0 62738.1 62841.0 63531.4 63659.0 63686.7 1389.8 1400.7 1426.3 1429.8 1432.4 23361.6 24753.0 30313.4 30943.5 31583.1 55192.4 55449.9 56059.7 56151.0 56159.0 524563.0 611815.0 613117.0 614114.0 615317.0 28081.2 28275.3 28506.6 28629.4 28685.0 43129.9 51095.0 51367.5 52002.7 52043.5 31910.4 37287.8 40649.4 42732.0 43498.6 41102.2 41521.4 42112.4 42699.6 42986.4 40273.0 40312.8 40730.6 40895.0 41313.2 35116.6 36061.0 36397.6 36900.6 37037.4 22165.3 25275.7 25968.1 26152.3 26520.1 03:45:15 INFO - PROCESS | 1671 | 03:45:15 INFO - PROCESS | 1671 | [#2] jquery.html Cycles:180 Average:36699.47 Median:12308.88 stddev:107419.29 (872.7%) stddev-sans-first:107685.86 03:45:15 INFO - PROCESS | 1671 | Values: 302.2 350.9 382.5 402.1 411.9 21151.9 21827.6 21957.9 22372.6 22375.3 20616.5 20822.5 21325.0 21508.2 21588.6 4859.6 4870.8 4951.0 5447.8 5623.2 5479.6 5543.4 5556.3 5584.0 5603.9 4770.9 5417.5 5468.0 5479.8 5499.4 5571.4 5576.8 5577.7 5585.9 5587.9 3989.5 5294.2 5351.2 5366.9 5410.0 13302.1 13482.5 13853.8 13856.0 13869.3 1985.0 2105.9 2242.4 2444.4 2932.0 10127.9 10419.0 10829.9 15876.1 15986.6 1717.2 1728.3 1778.5 1800.2 1813.6 84530.0 92623.0 124242.0 125548.0 128800.4 11488.0 11682.8 11749.9 11791.8 11968.2 14403.6 14532.6 14600.6 14720.0 14732.2 8800.7 8814.3 8853.8 8944.6 8974.2 8804.4 8814.2 8861.8 8892.9 8961.9 3826.7 4556.1 4591.7 4617.8 4714.4 1798.8 1927.5 1947.3 1982.7 2009.4 865.9 925.9 997.7 1018.4 1042.8 18562.7 21041.4 21509.6 22091.6 22467.0 866.9 876.9 966.3 1021.2 1027.6 8915.3 9705.4 11570.5 11786.5 11793.0 621098.0 646656.0 649003.0 650252.7 653352.0 91841.6 137754.0 147698.0 147821.0 148269.0 951.5 992.6 993.6 997.5 1027.7 19887.6 20240.9 20322.2 20538.7 20539.6 56308.0 58447.0 60009.0 60341.7 60966.0 84178.2 90099.0 90168.6 90815.0 90938.5 6284.7 8036.7 8176.6 8256.7 8342.5 11717.3 13087.4 13812.8 13860.2 14081.9 11750.8 12012.6 12045.9 12048.8 12105.9 11830.3 11912.6 11914.0 11993.6 12084.0 10335.5 12042.9 12109.8 12157.9 12203.5 7715.6 7717.8 7775.0 7780.4 7858.1 11699.0 11965.6 12115.4 12185.5 12205.1 03:45:15 INFO - PROCESS | 1671 | 03:45:15 INFO - PROCESS | 1671 | [#3] mootools.html Cycles:180 Average:5747.42 Median:4835.64 stddev:3178.35 (65.7%) stddev-sans-first:3171.78 03:45:15 INFO - PROCESS | 1671 | Values: 1572.2 1622.0 1872.5 1942.9 1955.7 8024.6 8580.9 8616.0 9034.7 9062.1 8726.9 8739.6 8850.7 8881.2 8911.9 2348.1 2358.7 2381.1 2393.6 2397.7 2338.7 2381.6 2398.7 2406.1 2412.7 2386.2 2393.2 2399.2 2400.1 2407.2 2347.7 2347.9 2352.7 2354.6 2383.8 2341.6 2349.3 2362.0 2368.8 2370.3 8967.1 8983.1 9002.4 9039.1 9041.8 5246.9 5340.5 5394.4 5407.1 5410.5 3568.8 3599.3 3649.5 3654.2 3657.8 1060.4 1068.6 1080.8 1083.1 1099.7 11232.2 11313.8 11398.6 11400.1 11402.7 4620.5 4623.7 4806.7 4807.5 4810.8 9852.0 9860.0 9861.8 9876.0 9900.0 5319.7 5346.4 5353.5 5365.4 5373.4 4416.7 4798.9 4834.9 4921.0 4963.1 3963.9 4034.8 4079.1 4121.9 4150.3 1293.4 1301.8 1315.5 1316.4 1329.1 887.2 932.1 963.2 983.1 1019.9 6041.2 6090.2 6140.9 6163.6 6197.5 428.4 435.8 437.7 1021.9 1060.4 4079.7 4138.2 4144.8 4186.6 4196.3 10609.3 10845.3 10926.9 10943.7 10945.8 10540.5 10661.6 10675.2 10720.8 10867.0 8119.1 8137.9 8267.0 8277.9 8315.5 5600.9 5910.9 6141.6 6171.7 6229.3 8540.5 9600.0 9827.7 10020.6 10170.8 9859.3 10226.9 10231.4 10314.5 10350.8 6505.5 7400.0 7476.1 7552.9 7560.0 7209.0 7241.4 8481.8 8536.3 8543.8 6479.0 7166.9 7658.3 7670.3 7881.1 7456.3 7542.0 7633.7 7638.9 7652.5 7139.7 7205.8 7247.2 7253.4 7264.8 5028.4 5132.7 5221.1 5227.5 5238.0 4808.0 4836.4 5130.0 5190.1 5231.3 03:45:15 INFO - PROCESS | 1671 | 03:45:15 INFO - PROCESS | 1671 | [#4] prototype.html Cycles:180 Average:4719.60 Median:5297.54 stddev:1500.71 (28.3%) stddev-sans-first:1467.71 03:45:15 INFO - PROCESS | 1671 | Values: 295.1 310.7 313.8 316.4 318.1 5679.4 5696.0 5721.4 5745.1 5748.3 4881.3 5567.0 5578.9 5585.0 5648.7 4057.3 4154.3 4239.2 4250.8 4277.5 4176.2 4232.3 4237.7 4246.9 4249.2 3849.0 4109.9 4212.3 4227.9 4228.3 3491.0 3898.7 4091.7 4177.7 4232.4 2713.7 3364.6 3540.9 3569.8 3582.6 4056.8 4520.6 4808.0 5232.5 5333.2 4273.6 4581.5 4820.2 4838.4 4866.3 4615.3 4790.7 4965.4 5156.5 5896.2 3585.7 3694.9 3946.4 3978.3 4009.0 5515.5 5902.4 6157.4 6166.3 6221.7 3794.4 3835.8 3996.0 4018.2 4032.3 3532.2 3799.5 3981.7 4000.6 4082.5 4036.8 4157.8 4475.7 4619.5 4820.8 5792.9 5893.5 5934.7 5948.1 5963.4 1259.6 1302.2 1392.8 1393.4 1397.2 723.0 848.5 854.9 896.7 933.8 4902.0 4925.3 5145.9 5395.3 5524.6 5324.2 5638.5 5955.3 5997.0 5999.0 4581.5 5231.3 5298.5 5314.2 5397.7 2693.7 2859.3 2964.0 2968.9 3053.3 5023.0 5524.4 5544.9 5913.9 6243.8 5283.0 5680.8 6106.4 6198.9 6215.7 6205.4 6262.6 6263.2 6269.2 6321.4 5309.9 5467.9 5741.6 6026.7 6197.0 5215.2 5853.9 6158.6 6171.1 6184.2 4836.7 6093.3 6120.7 6433.8 6439.3 3992.2 4016.9 4030.6 4039.9 4071.3 5104.9 5179.8 6197.6 6249.3 6259.6 5296.6 5949.5 5970.9 6039.2 6063.4 6015.8 6017.1 6021.0 6028.7 6041.9 5604.0 5612.6 5875.8 5901.6 5923.8 5940.8 5963.9 5964.4 5975.3 6009.6 5782.0 5857.5 5858.4 5860.7 5866.8 03:45:15 INFO - PROCESS | 1671 | 03:45:15 INFO - PROCESS | 1671 | [#5] yui.html Cycles:180 Average:23118.83 Median:3737.99 stddev:35598.59 (952.3%) stddev-sans-first:35658.61 03:45:15 INFO - PROCESS | 1671 | Values: 687.5 731.4 785.2 823.4 829.8 14158.6 15977.4 16038.2 16110.4 16571.9 14612.9 14718.5 14724.4 14740.3 14783.0 703.9 870.8 890.3 910.2 914.5 894.6 896.2 907.2 911.1 912.6 973.7 975.0 975.1 975.5 976.5 961.6 966.2 969.4 970.5 972.7 685.0 720.4 721.5 722.6 724.7 15003.7 15020.0 15155.5 15415.8 15459.5 13422.9 14161.2 14843.1 14906.5 14912.7 10442.2 11908.9 11983.8 12067.3 12125.0 3912.4 3913.0 4013.4 4102.5 4122.5 112647.0 113140.4 113182.4 114363.0 114474.4 11484.4 11540.5 11557.5 11655.8 11731.7 23498.9 23800.0 23835.2 23840.6 23846.3 8372.4 8414.6 8534.7 8630.8 8667.3 4654.6 4661.8 4670.8 4697.5 4751.8 3625.9 3733.2 3742.8 3743.7 3744.6 655.6 656.0 667.4 685.7 687.5 619.4 673.1 685.5 702.8 705.4 8007.4 8352.8 9337.2 9769.1 10622.3 580.5 617.7 672.6 695.7 696.7 4449.9 4617.6 4637.7 4760.0 4760.7 91042.1 92394.8 106487.0 147142.0 150310.2 39698.4 44231.9 127503.4 129694.0 131655.3 67663.0 101612.0 110806.4 115802.0 117224.4 9180.8 9663.8 10018.5 10068.7 10290.4 48886.0 57357.7 62743.7 63251.0 63309.7 91055.0 92341.1 93323.5 94797.0 95732.0 11577.7 13531.7 13602.0 13804.4 13838.4 14110.7 14399.9 14535.3 14982.3 15124.0 11488.3 14079.0 14231.8 14549.9 14584.7 14050.6 14306.7 14367.7 14451.7 15099.4 13433.9 13616.3 13905.7 14277.8 14348.9 11511.0 11655.9 11741.4 11804.6 11818.4 10367.7 10711.3 10727.2 10773.2 10845.6 03:45:15 INFO - PROCESS | 1671 | -------- Summary: end -------- 03:45:15 INFO - PROCESS | 1671 | 03:45:15 INFO - TEST-INFO | 1671: exit 0 03:45:15 INFO - TEST-OK | dromaeo_css | took 1113004ms 03:45:15 INFO - TEST-START | kraken 03:45:15 INFO - operating with platform_type : mac_ 03:45:15 INFO - Initialising browser for kraken test... 03:45:15 INFO - TEST-INFO | started process 1702 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/tmpYQP4o9/profile http://localhost:49265/getInfo.html) 03:45:18 INFO - PROCESS | 1702 | __metrics Screen width/height:1600/1200 03:45:18 INFO - PROCESS | 1702 | colorDepth:24 03:45:18 INFO - PROCESS | 1702 | Browser inner width/height: 1024/689 03:45:18 INFO - PROCESS | 1702 | __metrics 03:45:18 ERROR - PROCESS | 1702 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2968: TypeError: this.tabs is undefined 03:45:19 INFO - TEST-INFO | 1702: exit 0 03:45:19 INFO - Browser initialized. 03:45:19 INFO - Running cycle 1/1 for kraken test... 03:45:19 INFO - TEST-INFO | started process 1704 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/tmpYQP4o9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:45:31 INFO - PROCESS | 1704 | RSS: Main: 255893504 03:45:31 INFO - PROCESS | 1704 | 03:45:34 INFO - PROCESS | 1704 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-beat-detection) 03:45:35 INFO - PROCESS | 1704 | RSS: Main: 269127680 03:45:35 INFO - PROCESS | 1704 | 03:45:38 INFO - PROCESS | 1704 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-dft) 03:45:38 INFO - PROCESS | 1704 | RSS: Main: 273203200 03:45:38 INFO - PROCESS | 1704 | 03:45:42 INFO - PROCESS | 1704 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-fft) 03:45:42 INFO - PROCESS | 1704 | RSS: Main: 273260544 03:45:42 INFO - PROCESS | 1704 | 03:45:44 INFO - PROCESS | 1704 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-oscillator) 03:45:45 INFO - PROCESS | 1704 | RSS: Main: 274145280 03:45:45 INFO - PROCESS | 1704 | 03:45:47 INFO - PROCESS | 1704 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49265/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:45:48 INFO - PROCESS | 1704 | RSS: Main: 274591744 03:45:48 INFO - PROCESS | 1704 | 03:45:51 INFO - PROCESS | 1704 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49265/tests/kraken/driver.html?testName=imaging-darkroom) 03:45:51 INFO - PROCESS | 1704 | RSS: Main: 275406848 03:45:51 INFO - PROCESS | 1704 | 03:45:54 INFO - PROCESS | 1704 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49265/tests/kraken/driver.html?testName=imaging-desaturate) 03:45:55 INFO - PROCESS | 1704 | RSS: Main: 275689472 03:45:55 INFO - PROCESS | 1704 | 03:45:58 INFO - PROCESS | 1704 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49265/tests/kraken/driver.html?testName=json-parse-financial) 03:45:59 INFO - PROCESS | 1704 | RSS: Main: 275980288 03:45:59 INFO - PROCESS | 1704 | 03:46:00 INFO - PROCESS | 1704 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49265/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:46:01 INFO - PROCESS | 1704 | RSS: Main: 275570688 03:46:01 INFO - PROCESS | 1704 | 03:46:03 INFO - PROCESS | 1704 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:46:03 INFO - PROCESS | 1704 | RSS: Main: 275038208 03:46:03 INFO - PROCESS | 1704 | 03:46:05 INFO - PROCESS | 1704 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:46:06 INFO - PROCESS | 1704 | RSS: Main: 275423232 03:46:06 INFO - PROCESS | 1704 | 03:46:09 INFO - PROCESS | 1704 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:46:10 INFO - PROCESS | 1704 | RSS: Main: 275509248 03:46:10 INFO - PROCESS | 1704 | 03:46:13 INFO - PROCESS | 1704 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:46:13 INFO - PROCESS | 1704 | RSS: Main: 275423232 03:46:13 INFO - PROCESS | 1704 | 03:46:15 INFO - PROCESS | 1704 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49265/tests/kraken/driver.html?testName=ai-astar) 03:46:15 INFO - PROCESS | 1704 | RSS: Main: 276185088 03:46:15 INFO - PROCESS | 1704 | 03:46:15 INFO - PROCESS | 1704 | __start_tp_report 03:46:15 INFO - PROCESS | 1704 | _x_x_mozilla_page_load 03:46:15 INFO - PROCESS | 1704 | _x_x_mozilla_page_load_details 03:46:15 INFO - PROCESS | 1704 | |i|pagename|runs| 03:46:15 INFO - PROCESS | 1704 | |0;ai-astar;120;121;107;164;148;171;152;118;115;115 03:46:15 INFO - PROCESS | 1704 | |1;audio-beat-detection;141;129;132;161;137;166;130;114;152;130 03:46:15 INFO - PROCESS | 1704 | |2;audio-dft;200;235;198;198;194;195;271;275;198;192 03:46:15 INFO - PROCESS | 1704 | |3;audio-fft;74;81;66;158;84;80;82;62;132;81 03:46:15 INFO - PROCESS | 1704 | |4;audio-oscillator;70;72;121;71;73;84;115;71;74;117 03:46:15 INFO - PROCESS | 1704 | |5;imaging-gaussian-blur;89;87;88;85;89;86;109;112;87;129 03:46:15 INFO - PROCESS | 1704 | |6;imaging-darkroom;94;95;95;98;96;96;98;156;150;102 03:46:15 INFO - PROCESS | 1704 | |7;imaging-desaturate;111;149;120;93;133;142;89;92;91;75 03:46:15 INFO - PROCESS | 1704 | |8;json-parse-financial;56;56;59;58;72;70;57;56;57;58 03:46:15 INFO - PROCESS | 1704 | |9;json-stringify-tinderbox;66;72;72;70;77;78;81;71;69;75 03:46:15 INFO - PROCESS | 1704 | |10;stanford-crypto-aes;88;83;85;86;82;93;93;106;69;96 03:46:15 INFO - PROCESS | 1704 | |11;stanford-crypto-ccm;137;140;139;151;126;150;148;158;224;160 03:46:15 INFO - PROCESS | 1704 | |12;stanford-crypto-pbkdf2;223;224;182;163;166;170;162;163;162;162 03:46:15 INFO - PROCESS | 1704 | |13;stanford-crypto-sha256-iterative;64;68;65;66;66;65;63;68;68;64 03:46:15 INFO - PROCESS | 1704 | __end_tp_report 03:46:15 INFO - PROCESS | 1704 | __start_cc_report 03:46:15 INFO - PROCESS | 1704 | _x_x_mozilla_cycle_collect,1116 03:46:15 INFO - PROCESS | 1704 | __end_cc_report 03:46:15 INFO - PROCESS | 1704 | __startTimestamp1469443575799__endTimestamp 03:46:15 INFO - PROCESS | 1704 | 03:46:15 INFO - PROCESS | 1704 | ------- Summary: start ------- 03:46:15 INFO - PROCESS | 1704 | Number of tests: 14 03:46:15 INFO - PROCESS | 1704 | 03:46:15 INFO - PROCESS | 1704 | [#0] ai-astar Cycles:10 Average:133.10 Median:134.50 stddev:23.22 (17.3%) stddev-sans-first:24.14 03:46:15 INFO - PROCESS | 1704 | Values: 120.0 121.0 107.0 164.0 148.0 171.0 152.0 118.0 115.0 115.0 03:46:15 INFO - PROCESS | 1704 | 03:46:15 INFO - PROCESS | 1704 | [#1] audio-beat-detection Cycles:10 Average:139.20 Median:139.00 stddev:16.07 (11.6%) stddev-sans-first:17.04 03:46:15 INFO - PROCESS | 1704 | Values: 141.0 129.0 132.0 161.0 137.0 166.0 130.0 114.0 152.0 130.0 03:46:15 INFO - PROCESS | 1704 | 03:46:15 INFO - PROCESS | 1704 | [#2] audio-dft Cycles:10 Average:215.60 Median:199.00 stddev:32.65 (16.4%) stddev-sans-first:34.14 03:46:15 INFO - PROCESS | 1704 | Values: 200.0 235.0 198.0 198.0 194.0 195.0 271.0 275.0 198.0 192.0 03:46:15 INFO - PROCESS | 1704 | 03:46:15 INFO - PROCESS | 1704 | [#3] audio-fft Cycles:10 Average:90.00 Median:80.50 stddev:30.49 (37.9%) stddev-sans-first:31.78 03:46:15 INFO - PROCESS | 1704 | Values: 74.0 81.0 66.0 158.0 84.0 80.0 82.0 62.0 132.0 81.0 03:46:15 INFO - PROCESS | 1704 | 03:46:15 INFO - PROCESS | 1704 | [#4] audio-oscillator Cycles:10 Average:86.80 Median:71.50 stddev:21.70 (30.4%) stddev-sans-first:22.15 03:46:15 INFO - PROCESS | 1704 | Values: 70.0 72.0 121.0 71.0 73.0 84.0 115.0 71.0 74.0 117.0 03:46:15 INFO - PROCESS | 1704 | 03:46:15 INFO - PROCESS | 1704 | [#5] imaging-gaussian-blur Cycles:10 Average:96.10 Median:87.00 stddev:15.13 (17.4%) stddev-sans-first:15.82 03:46:15 INFO - PROCESS | 1704 | Values: 89.0 87.0 88.0 85.0 89.0 86.0 109.0 112.0 87.0 129.0 03:46:15 INFO - PROCESS | 1704 | 03:46:15 INFO - PROCESS | 1704 | [#6] imaging-darkroom Cycles:10 Average:108.00 Median:95.50 stddev:23.87 (25.0%) stddev-sans-first:24.77 03:46:15 INFO - PROCESS | 1704 | Values: 94.0 95.0 95.0 98.0 96.0 96.0 98.0 156.0 150.0 102.0 03:46:15 INFO - PROCESS | 1704 | 03:46:15 INFO - PROCESS | 1704 | [#7] imaging-desaturate Cycles:10 Average:109.50 Median:82.00 stddev:25.41 (31.0%) stddev-sans-first:26.95 03:46:15 INFO - PROCESS | 1704 | Values: 111.0 149.0 120.0 93.0 133.0 142.0 89.0 92.0 91.0 75.0 03:46:15 INFO - PROCESS | 1704 | 03:46:15 INFO - PROCESS | 1704 | [#8] json-parse-financial Cycles:10 Average:59.90 Median:58.00 stddev:5.95 (10.3%) stddev-sans-first:6.14 03:46:15 INFO - PROCESS | 1704 | Values: 56.0 56.0 59.0 58.0 72.0 70.0 57.0 56.0 57.0 58.0 03:46:15 INFO - PROCESS | 1704 | 03:46:15 INFO - PROCESS | 1704 | [#9] json-stringify-tinderbox Cycles:10 Average:73.10 Median:73.50 stddev:4.58 (6.2%) stddev-sans-first:4.08 03:46:15 INFO - PROCESS | 1704 | Values: 66.0 72.0 72.0 70.0 77.0 78.0 81.0 71.0 69.0 75.0 03:46:15 INFO - PROCESS | 1704 | 03:46:15 INFO - PROCESS | 1704 | [#10] stanford-crypto-aes Cycles:10 Average:88.10 Median:87.00 stddev:9.85 (11.3%) stddev-sans-first:10.45 03:46:15 INFO - PROCESS | 1704 | Values: 88.0 83.0 85.0 86.0 82.0 93.0 93.0 106.0 69.0 96.0 03:46:15 INFO - PROCESS | 1704 | 03:46:15 INFO - PROCESS | 1704 | [#11] stanford-crypto-ccm Cycles:10 Average:153.30 Median:150.50 stddev:26.88 (17.9%) stddev-sans-first:27.85 03:46:15 INFO - PROCESS | 1704 | Values: 137.0 140.0 139.0 151.0 126.0 150.0 148.0 158.0 224.0 160.0 03:46:15 INFO - PROCESS | 1704 | 03:46:15 INFO - PROCESS | 1704 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.70 Median:168.00 stddev:24.90 (14.8%) stddev-sans-first:20.32 03:46:15 INFO - PROCESS | 1704 | Values: 223.0 224.0 182.0 163.0 166.0 170.0 162.0 163.0 162.0 162.0 03:46:15 INFO - PROCESS | 1704 | 03:46:15 INFO - PROCESS | 1704 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.70 Median:66.00 stddev:1.83 (2.8%) stddev-sans-first:1.83 03:46:15 INFO - PROCESS | 1704 | Values: 64.0 68.0 65.0 66.0 66.0 65.0 63.0 68.0 68.0 64.0 03:46:15 INFO - PROCESS | 1704 | -------- Summary: end -------- 03:46:15 INFO - PROCESS | 1704 | 03:46:16 INFO - TEST-INFO | 1704: exit 0 03:46:16 INFO - TEST-OK | kraken | took 61082ms 03:46:16 INFO - SUITE-END | took 1174s 03:46:16 INFO - Completed test suite (00:19:34) 03:46:16 INFO - javascript benchmark 03:46:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7522.141915158164, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2480.6403071554614, 2831.801773875828, 2991.581178634991, 2993.161914663894, 3402.387570237356, 5971.552133589977, 5986.161937328772, 6055.878882422349, 6057.757689692407, 6071.423214794593, 5786.9710651446685, 5974.8207553773345, 6002.189704389906, 6006.519478441725, 6050.42520960508, 4507.5943165115295, 5266.368035835695, 5317.813876514323, 5365.490278423563, 5397.62216644835, 5397.379301380332, 5427.0773968425465, 5444.809431669893, 5453.127499600065, 5468.26178465908, 5161.587073034186, 5171.043356978933, 5226.895462090775, 5301.3108295921475, 5312.0, 5243.4232234454175, 5244.737763111867, 5244.977229440261, 5254.973725131388, 5257.106291930381, 5163.302954101186, 5188.844334669966, 5225.059489291926, 5272.789088436458, 5273.789048438058, 5723.656580605177, 5788.594798364116, 5890.528757699374, 5918.526517878559, 6005.429484198994, 6175.950088484969, 6222.52719931583, 6243.531735119885, 6256.15541901842, 6259.405356491126, 5667.838093190927, 6002.519798416116, 6319.905201421982, 6328.936710632881, 6329.208848893888, 5936.821895343147, 5985.16207730918, 5998.430149135868, 6003.369646187175, 6025.698715064317, 6414.839629009312, 6456.257530383972, 6458.870822583616, 6471.514636402195, 6482.222133344029, 6146.446819786241, 6210.968945155336, 6299.842503937438, 6302.621842689453, 6306.936930630635, 6210.254769427697, 6264.310925798159, 6324.810255692337, 6327.525435592349, 6333.809985700436, 5671.262735844396, 5767.192593036978, 6193.256809182927, 6225.097360882737, 6325.399087086719, 6224.906626400608, 6247.875042499125, 6263.279722831841, 6291.874162516816, 6291.930371836707, 4987.426445958688, 5023.19628859388, 5029.0, 5029.698218106925, 5071.163258062452, 4476.865694029184, 4500.9099818004115, 4526.524714904954, 4535.342375355555, 4547.636189104864, 5851.64890106595, 6001.969990150109, 6079.08813677943, 6136.048912418582, 6145.016797312498, 5289.973550132225, 6330.715117819709, 6338.904916426257, 6347.650879201688, 6350.7459701611415, 6107.0, 6109.419605137505, 6112.0, 6126.509879209652, 6129.295131059896, 5486.561075114061, 5559.0, 5561.527270181999, 5568.387477377485, 5588.469095435927, 6457.5479716419395, 6476.0, 6483.351664833479, 6489.837754056186, 6568.343165683394, 6272.905906411499, 6306.400891915261, 6310.716017779303, 6336.081268216175, 6353.8729225416155, 6210.472109870711, 6357.682115894094, 6389.041643753474, 6431.099646049651, 6528.347165283433, 6287.842803929939, 6315.905261421174, 6316.62100273985, 6325.4939604832425, 6343.9682801585695, 6231.657258850807, 6319.05214217871, 6334.651594162366, 6339.429451349401, 6342.0, 6022.939770602238, 6189.381061893955, 6210.285817130907, 6222.408871157233, 6263.185785847811, 6210.782622608187, 6234.127222188807, 6298.433141017331, 6302.905456418249, 6313.55805093639, 6095.451409373178, 6100.725467354068, 6143.6928153593035, 6174.487250623626, 6197.442230199304, 6150.169727086786, 6172.105044768573, 6205.844853878689, 6218.315985241529, 6319.146915166578, 6099.2189903441595, 6190.3190649029475, 6193.256809182927, 6197.194364732556, 6198.659073750987], "value": 5793.224920949095, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [485.5266115537354, 516.5841497594437, 517.8342930262316, 540.4352451687986, 564.9209110724497, 39444.80277598615, 42308.0, 42335.15329693412, 42345.99999999996, 42356.78821605888, 43215.78392108035, 43779.34330985038, 43830.56169438305, 43841.12317753643, 43854.780726096404, 9631.85552216719, 9719.319647624669, 9799.314048016642, 9807.460589667566, 9823.115919567237, 9735.659251926185, 9796.70609881701, 9813.509324533781, 9816.950915245414, 9840.409575425498, 9822.214222862154, 9857.162141218005, 9862.45756483393, 9868.555914983806, 9870.358426702242, 9794.755131121708, 9807.215422766161, 9828.361156524803, 9851.852222216601, 9868.703938881808, 9667.0, 9756.317057805956, 9803.0, 9803.705888823346, 9822.65620703272, 41168.176636467106, 41550.58449415497, 41558.37662435066, 41648.58351416478, 41660.95847603804, 3567.5362202913457, 3663.6519530644814, 3689.317476266899, 3805.315043292206, 4292.1415716856445, 27754.722452775415, 28967.56548651772, 31189.0, 31421.0, 33394.99815005554, 3231.789933654305, 3435.2957643682985, 3442.7934323940644, 3447.258839349552, 3447.3967055765393, 540594.0, 545167.0, 553133.0, 554151.0, 554266.2286688581, 71143.0, 71832.28167718256, 71973.64013179901, 72055.39723013829, 72143.2785672147, 73617.0, 74294.0, 74332.0, 74409.0, 74736.0, 17423.041732704824, 17629.382971596187, 17883.642327153644, 17905.925644461375, 17926.282948681906, 13411.530596429273, 13513.256770877697, 13521.0, 13543.796843047363, 13547.932260338635, 17650.303893773766, 21529.784702152778, 21545.56908861814, 21614.243501477373, 21671.34985950424, 3350.6816852398983, 7898.0, 8741.30069594431, 8763.737087887373, 9033.81932361349, 874.8600223964136, 984.6061575369766, 1063.9361638301727, 1067.6743593203992, 1120.3389999900098, 30975.380492390024, 31201.0, 31287.530687039256, 31803.0, 31870.521942170886, 959.2421986630484, 1036.3056751976194, 1064.6220591689994, 1086.0497065068066, 1096.4517741129434, 15071.54785356441, 15434.691306173814, 16587.170641467877, 16610.83389166093, 16637.41769038078, 625189.0, 677301.0, 696532.0, 701946.0, 702985.0, 62738.0589291161, 62841.0, 63531.36468635347, 63659.0, 63686.6815665928, 1389.8193234879602, 1400.6918477934837, 1426.2726009734902, 1429.756941319978, 1432.4485073246874, 23361.649575256724, 24753.00413864709, 30313.393732124794, 30943.535846962764, 31583.052508423865, 55192.44807551873, 55449.89100217893, 56059.719701402864, 56151.0, 56159.0, 524563.0, 611815.0, 613117.0, 614114.0, 615317.0, 28081.157565273075, 28275.293117672223, 28506.57240141441, 28629.42741145207, 28684.996025139855, 43129.9217519552, 51095.0, 51367.48632513627, 52002.73998629983, 52043.47956520538, 31910.361792764477, 37287.81356093202, 40649.39025914673, 42732.0, 43498.56501434945, 41102.1779564413, 41521.37717934294, 42112.3683144747, 42699.57300426956, 42986.35520467258, 40272.99317517086, 40312.79843600763, 40730.59269407268, 40895.0, 41313.1737365257, 35116.64883351134, 36061.0, 36397.636023639425, 36900.630993689716, 37037.44443833291, 22165.337599679766, 25275.736213189633, 25968.091116811578, 26152.34619134537, 26520.071797486995], "value": 21040.94577935196, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.2232861545827, 350.87543921907724, 382.48364707644686, 402.12938987092946, 411.8517333759848, 21151.894240528796, 21827.563448731016, 21957.890210548943, 22372.55254894901, 22375.32185086119, 20616.484587885312, 20822.479438014056, 21325.040373183205, 21508.247211347607, 21588.568228635457, 4859.562639362457, 4870.756462176894, 4951.0, 5447.836564903049, 5623.212750214973, 5479.643823151482, 5543.390227075019, 5556.333240011204, 5584.0, 5603.887922241553, 4770.904581908359, 5417.51242388185, 5468.0, 5479.780808767645, 5499.395066542677, 5571.415001424853, 5576.804811831586, 5577.721113944327, 5585.916211256835, 5587.944120558804, 3989.501312335958, 5294.205869119624, 5351.170568561879, 5366.9463305366835, 5410.0, 13302.068855180141, 13482.528111516149, 13853.792193117111, 13856.030077894551, 13869.306534673226, 1984.960300793976, 2105.8525903186783, 2242.439390152462, 2444.352246654638, 2932.017774045697, 10127.898721012843, 10419.0, 10829.945850270777, 15876.12681302528, 15986.600334991603, 1717.1929193279138, 1728.3259528783658, 1778.5375802291496, 1800.2169056460482, 1813.5919418130948, 84529.99999999939, 92623.0, 124242.0, 125548.0, 128800.35599822034, 11488.023518000897, 11682.824757628643, 11749.88250117505, 11791.823122653168, 11968.162228644085, 14403.567892963229, 14532.564023079325, 14600.634984125269, 14720.043197192148, 14732.189729564763, 8800.691975780945, 8814.338924580683, 8853.822923541493, 8944.642214311485, 8974.192322690858, 8804.427712198687, 8814.162654547808, 8861.778455538533, 8892.911070889339, 8961.865572016424, 3826.674732647756, 4556.088782243564, 4591.747453890068, 4617.8152873884665, 4714.363560919301, 1798.7841459025, 1927.4795805132535, 1947.3476385410763, 1982.692682634194, 2009.437357539891, 865.8571335729534, 925.9305552083621, 997.7206382212992, 1018.3745864721615, 1042.8018676451602, 18562.72155917662, 21041.368758937257, 21509.569808604054, 22091.558168836535, 22467.0, 866.9306455483672, 876.9342299327576, 966.3090890013674, 1021.1881554164308, 1027.5735569738474, 8915.331350148765, 9705.417674939385, 11570.537178512934, 11786.528538858522, 11793.0, 621098.0, 646656.0, 649003.0, 650252.7487362532, 653352.0, 91841.62237566442, 137754.0, 147698.0, 147821.0, 148269.0, 951.4529145741227, 992.6277645882794, 993.6025589764153, 997.4962643864802, 1027.686555600549, 19887.60224795525, 20240.898795505927, 20322.187112515334, 20538.69191962092, 20539.58920821605, 56308.0, 58447.032002280284, 60009.0, 60341.698291510016, 60966.0, 84178.15821841703, 90099.0, 90168.64747028668, 90815.0, 90938.54530727305, 6284.717187726472, 8036.718714844952, 8176.591170441335, 8256.669733210605, 8342.499450033018, 11717.296962182296, 13087.41106650222, 13812.792808108088, 13860.237686927316, 14081.859181408465, 11750.823737643772, 12012.579559715368, 12045.939770301093, 12048.75902481928, 12105.878941210474, 11830.290182589073, 11912.642620721392, 11914.046876250053, 11993.64019079429, 12084.0, 10335.534900929326, 12042.939785301018, 12109.818352724893, 12157.939210303892, 12203.450844711831, 7715.57564333967, 7717.80705482345, 7775.028121484815, 7780.377569794516, 7858.135605083322, 11699.0, 11965.641030769091, 12115.394230288273, 12185.451654675739, 12205.084618653458], "value": 9588.11385494365, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1572.237464829558, 1621.959451013725, 1872.485066606683, 1942.893140877252, 1955.7359756432877, 8024.638891249893, 8580.91419085809, 8616.0, 9034.68378606748, 9062.093790620942, 8726.91273087269, 8739.563021848899, 8850.690225842096, 8881.245094166987, 8911.866322005175, 2348.1429278313376, 2358.681577986971, 2381.071382160959, 2393.6289875069306, 2397.7482364351754, 2338.684277622525, 2381.6427535869584, 2398.70016247969, 2406.0736616402723, 2412.6501657259687, 2386.152915714922, 2393.246127469841, 2399.1722855614785, 2400.051979468109, 2407.2296865003204, 2347.7065366829147, 2347.929562113139, 2352.6823878776318, 2354.5997180479358, 2383.7616238376195, 2341.6253399456004, 2349.3304408243566, 2362.0, 2368.846025008369, 2370.3126093432884, 8967.148120928501, 8983.056779038237, 9002.369834111614, 9039.050899655507, 9041.77395565116, 5246.921296180561, 5340.519353258225, 5394.352677678665, 5407.053765591006, 5410.513053825135, 3568.750187486851, 3599.2981368633127, 3649.4525821126776, 3654.2326111516613, 3657.7988210648405, 1060.3796778884362, 1068.64200492835, 1080.827067669169, 1083.0685610375072, 1099.7470581766238, 11232.157588180919, 11313.773724525463, 11398.601048963246, 11400.144989125849, 11402.71493212668, 4620.491745907947, 4623.653226008063, 4806.711597304103, 4807.471178170398, 4810.831620893252, 9852.014798520235, 9860.013998600227, 9861.753456163653, 9876.0, 9899.950500247453, 5319.654222475527, 5346.449529636362, 5353.544948679328, 5365.436629153961, 5373.35519737634, 4416.734995900256, 4798.928016079762, 4834.903301934012, 4921.0, 4963.10664080469, 3963.9297389705193, 4034.757914525138, 4079.082206503513, 4121.855735049319, 4150.294449943517, 1293.3921057103335, 1301.804729290595, 1315.4869600855675, 1316.4207748590588, 1329.0829327763813, 887.2281115429618, 932.1191474056951, 963.2438535749403, 983.1397527163732, 1019.9286049976579, 6041.18444010053, 6090.177825993434, 6140.8771824563255, 6163.599366041193, 6197.504199664016, 428.4366058632888, 435.77775334579593, 437.68049323993665, 1021.8620486234412, 1060.3584831177182, 4079.653229475528, 4138.193052354762, 4144.79276036197, 4186.602272784081, 4196.265653510623, 10609.257351985441, 10845.349279043283, 10926.945365273281, 10943.671689849318, 10945.781084378268, 10540.472976351151, 10661.573537058586, 10675.199360048027, 10720.839187412039, 10867.0, 8119.147489513634, 8137.918620813834, 8267.0, 8277.917220827836, 8315.542645154575, 5600.887982240414, 5910.940890591039, 6141.570090093651, 6171.691415429301, 6229.2524897012645, 8540.530270834915, 9599.952000239955, 9827.656032038843, 10020.64927727526, 10170.796584068425, 9859.260555458226, 10226.948865255627, 10231.437270949871, 10314.48427578603, 10350.844737328796, 6505.544611877123, 7400.0, 7476.140243872024, 7552.9244707552225, 7560.017197764256, 7209.0, 7241.384482319062, 8481.770143329059, 8536.31709463229, 8543.829123417621, 6478.967605161944, 7166.89249661266, 7658.310752032344, 7670.309672129535, 7881.133075361591, 7456.291652293024, 7542.0, 7633.69465221385, 7638.885416718643, 7652.540847549165, 7139.714411423693, 7205.819854503679, 7247.202807691045, 7253.3834624057545, 7264.818379540342, 5028.446870844277, 5132.666376685504, 5221.060209162387, 5227.503387178364, 5237.978594174099, 4808.014357056865, 4836.371271734793, 5129.999650068201, 5190.143626301769, 5231.267622532924], "value": 4614.627756246692, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [295.1499680918956, 310.6567243196268, 313.78662509493535, 316.37515906085486, 318.0617179321003, 5679.375268720442, 5695.971520142393, 5721.370649228582, 5745.138229265607, 5748.25272714547, 4881.267809828528, 5567.0, 5578.9163162552595, 5585.0, 5648.74580643871, 4057.2899742545083, 4154.273002224613, 4239.236937351276, 4250.787460626972, 4277.465316835395, 4176.164767046587, 4232.3228283474655, 4237.7457352558795, 4246.9150616987645, 4249.19265339586, 3849.0, 4109.876703698894, 4212.283911734997, 4227.936580951273, 4228.281192197333, 3491.0, 3898.688104951597, 4091.713580049398, 4177.707560470768, 4232.407462955173, 2713.6743590769233, 3364.637321884616, 3540.8937731868086, 3569.839357228931, 3582.5880023797336, 4056.817443215062, 4520.570545798144, 4807.975960120212, 5232.450592687751, 5333.2000199969925, 4273.636740877029, 4581.511008922081, 4820.180569303227, 4838.419389673227, 4866.270059491069, 4615.26155815068, 4790.724405907201, 4965.379327584052, 5156.510131537498, 5896.233489646362, 3585.73697934878, 3694.9445758313645, 3946.4017073085447, 3978.2839088963974, 4008.959910400917, 5515.503604675559, 5902.3507414184405, 6157.384261573851, 6166.290876549209, 6221.688915554249, 3794.3739283018544, 3835.846566137379, 3995.9810248387057, 4018.1963607278076, 4032.274190645653, 3532.2405682778326, 3799.544054713452, 3981.7013723970817, 4000.599940005976, 4082.510098788164, 4036.777977211223, 4157.835805974332, 4475.664325175625, 4619.514950930104, 4820.794171875734, 5792.94207057924, 5893.469587737124, 5934.703264836741, 5948.10778383246, 5963.403659634002, 1259.571745606498, 1302.218668798732, 1392.8439395301875, 1393.3869097597026, 1397.189630014589, 723.0311382747144, 848.5163456829548, 854.8589482735279, 896.7175339767953, 933.7572231219796, 4902.0, 4925.310456536088, 5145.945081258408, 5395.300480348677, 5524.58565607573, 5324.231399672226, 5638.464345887134, 5955.255593050869, 5996.970015149984, 5999.040153575407, 4581.496035436099, 5231.267622532849, 5298.470152984671, 5314.176302673091, 5397.70312632809, 2693.683432248265, 2859.2851787053237, 2963.97742778741, 2968.8718287051215, 3053.328267781085, 5023.0, 5524.4199359066715, 5544.870994330228, 5913.876363490923, 6243.8126856194385, 5282.969820884968, 5680.829575112753, 6106.419890110433, 6198.938010619836, 6215.694704112097, 6205.410486003822, 6262.624242545371, 6263.217097862767, 6269.153664255358, 6321.367863213642, 5309.946900531099, 5467.9453205467435, 5741.569382296261, 6026.668533230539, 6197.03945888388, 5215.243789650403, 5853.94146058534, 6158.567365890359, 6171.074338849208, 6184.226971628546, 4836.661433699607, 6093.299270583762, 6120.724567394389, 6433.806985790434, 6439.291677915519, 3992.2215168041953, 4016.927050806077, 4030.6170913763144, 4039.91920161601, 4071.328230841878, 5104.948950510596, 5179.792808287626, 6197.628142311476, 6249.25008998923, 6259.561830671992, 5296.602754793329, 5949.464548190684, 5970.910181436462, 6039.154518367344, 6063.363346848518, 6015.759369625166, 6017.097435384728, 6020.969895150496, 6028.698565071817, 6041.939580604314, 5604.0491453524355, 5612.607117501736, 5875.764969401176, 5901.586888917906, 5923.830750548436, 5940.79207227745, 5963.855120691078, 5964.403559644001, 5975.282966044103, 6009.579329446897, 5781.981522841753, 5857.47282744555, 5858.384869588632, 5860.706964651665, 5866.765329386777], "value": 4233.220337048306, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [687.4706476013469, 731.38198222502, 785.1638005524117, 823.3618945317378, 829.7884039569909, 14158.575242742709, 15977.360905563792, 16038.198090095508, 16110.35558577657, 16571.917140414294, 14612.926935365309, 14718.484853030146, 14724.411023559045, 14740.262986850641, 14783.0, 703.8521910398822, 870.7561882672861, 890.3189060368823, 910.2444970674358, 914.4604683236889, 894.6197865906996, 896.2337201692524, 907.2333877873186, 911.0889110889111, 912.589334799343, 973.6543527047902, 974.9756256093583, 975.0541974284932, 975.5024937282002, 976.511744127936, 961.6105477281712, 966.2415004221723, 969.4425705219492, 970.4807927758686, 972.7033254857266, 684.9965750171267, 720.4416577152696, 721.5021635071784, 722.6314579564407, 724.7137380734633, 15003.699926001527, 15020.0, 15155.545333640008, 15415.768763468557, 15459.458918937895, 13422.86577134226, 14161.150330980174, 14843.109413435228, 14906.478273260384, 14912.70174596502, 10442.164626829835, 11908.880911190863, 11983.820242696367, 12067.275963442135, 12125.0, 3912.374020156761, 3912.960870391288, 4013.3779264214095, 4102.487189101363, 4122.464079669654, 112647.0, 113140.43429782799, 113182.43408783068, 114363.0, 114474.42762786215, 11484.368359740294, 11540.538378464935, 11557.537698491966, 11655.76688466226, 11731.706707332214, 23498.94254758506, 23800.04799808023, 23835.165769197996, 23840.64239036416, 23846.284611461684, 8372.41393102477, 8414.6213420395, 8534.658613655509, 8630.78423039429, 8667.349948753868, 4654.581087702055, 4661.813527458864, 4670.789814458358, 4697.530246975275, 4751.786169622375, 3625.8549658013385, 3733.197362567061, 3742.7754334740002, 3743.7192210584317, 3744.64425879541, 655.5673255651343, 655.9836004099935, 667.4460198035619, 685.6537448588431, 687.4740823270203, 619.4394073363613, 673.1182151381705, 685.530411668009, 702.8032150997728, 705.3651713457826, 8007.359411247086, 8352.788845617473, 9337.225927073834, 9769.120779129771, 10622.256442048982, 580.4862696513532, 617.7035023188895, 672.6334147889412, 695.6904177641003, 696.7073828991836, 4449.888752781207, 4617.584417402382, 4637.7449240291435, 4759.9524004759505, 4760.714357138583, 91042.08957910468, 92394.76618318327, 106487.0, 147142.0, 150310.24844875705, 39698.404523932164, 44231.89420264519, 127503.362483187, 129694.0, 131655.34172329077, 67663.0, 101612.0, 110806.44596776964, 115802.0, 117224.41387793007, 9180.760597319277, 9663.758406039906, 10018.499075046364, 10068.748281293027, 10290.434026128634, 48886.0, 57357.71321143368, 62743.6862815683, 63251.0, 63309.683451582445, 91055.0, 92341.07658923324, 93323.53338233265, 94797.0, 95732.0426795751, 11577.726450090315, 13531.701753080832, 13602.0, 13804.447822087406, 13838.4464621414, 14110.717785644434, 14399.92800036035, 14535.345909434312, 14982.325795339453, 15124.016938898936, 11488.310701357612, 14079.0, 14231.786523201954, 14549.92725036368, 14584.708305833612, 14050.648733781738, 14306.713865722419, 14367.658837752286, 14451.710211655587, 15099.39602415935, 13433.865661343652, 13616.319184040956, 13905.70955689227, 14277.785833212718, 14348.928255358656, 11511.049531005567, 11655.941720291345, 11741.354225517678, 11804.645860624196, 11818.40907954616, 10367.740806479898, 10711.25021248505, 10727.195460340661, 10773.24587278883, 10845.566177352817], "value": 7933.719414216058, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1596.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 121.0, 107.0, 164.0, 148.0, 171.0, 152.0, 118.0, 115.0, 115.0], "value": 133.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 129.0, 132.0, 161.0, 137.0, 166.0, 130.0, 114.0, 152.0, 130.0], "value": 139.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 235.0, 198.0, 198.0, 194.0, 195.0, 271.0, 275.0, 198.0, 192.0], "value": 215.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 81.0, 66.0, 158.0, 84.0, 80.0, 82.0, 62.0, 132.0, 81.0], "value": 90.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 72.0, 121.0, 71.0, 73.0, 84.0, 115.0, 71.0, 74.0, 117.0], "value": 86.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 88.0, 85.0, 89.0, 86.0, 109.0, 112.0, 87.0, 129.0], "value": 96.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 98.0, 96.0, 96.0, 98.0, 156.0, 150.0, 102.0], "value": 108.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 149.0, 120.0, 93.0, 133.0, 142.0, 89.0, 92.0, 91.0, 75.0], "value": 109.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 59.0, 58.0, 72.0, 70.0, 57.0, 56.0, 57.0, 58.0], "value": 59.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 72.0, 72.0, 70.0, 77.0, 78.0, 81.0, 71.0, 69.0, 75.0], "value": 73.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 83.0, 85.0, 86.0, 82.0, 93.0, 93.0, 106.0, 69.0, 96.0], "value": 88.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 140.0, 139.0, 151.0, 126.0, 150.0, 148.0, 158.0, 224.0, 160.0], "value": 153.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [223.0, 224.0, 182.0, 163.0, 166.0, 170.0, 162.0, 163.0, 162.0, 162.0], "value": 177.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 68.0, 65.0, 66.0, 66.0, 65.0, 63.0, 68.0, 68.0, 64.0], "value": 65.7, "unit": "score"}]}]} 03:46:17 INFO - Return code: 0 03:46:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:46:17 ERROR - # TBPL SUCCESS # 03:46:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:46:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:46:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:46:17 INFO - 2016-07-25 03:46:17.117 screenresolution[1709:10544] starting screenresolution argv=screenresolution get 03:46:17 INFO - 2016-07-25 03:46:17.119 screenresolution[1709:10544] Display 0: 1600x1200x32@60 03:46:17 INFO - 2016-07-25 03:46:17.125 screenresolution[1710:10547] starting screenresolution argv=screenresolution list 03:46:17 INFO - Available Modes on Display 0 03:46:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:46:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:46:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:46:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:46:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:46:17 INFO - Intel Iris: 03:46:17 INFO - Chipset Model: Intel Iris 03:46:17 INFO - Type: GPU 03:46:17 INFO - Bus: Built-In 03:46:17 INFO - VRAM (Dynamic, Max): 1536 MB 03:46:17 INFO - Vendor: Intel (0x8086) 03:46:17 INFO - Device ID: 0x0a2e 03:46:17 INFO - Revision ID: 0x0009 03:46:17 INFO - Displays: 03:46:17 INFO - Display: 03:46:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:46:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:46:17 INFO - Display Serial Number: 1600x1200 60 03:46:17 INFO - Main Display: Yes 03:46:17 INFO - Mirror: Off 03:46:17 INFO - Online: Yes 03:46:17 INFO - Rotation: Supported 03:46:17 INFO - Return code: 0 03:46:17 INFO - Running post-action listener: _resource_record_post_action 03:46:17 INFO - ##### 03:46:17 INFO - ##### Finished run-tests step (success) 03:46:17 INFO - ##### 03:46:17 INFO - Running post-run listener: _resource_record_post_run 03:46:17 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 36718080; Write bytes: 707179520; Read time: 233; Write time: 2035 03:46:17 INFO - TinderboxPrint: CPU usage
54.2% 03:46:17 INFO - TinderboxPrint: I/O read bytes / time
36,718,080 / 233 03:46:17 INFO - TinderboxPrint: I/O write bytes / time
707,179,520 / 2,035 03:46:17 INFO - TinderboxPrint: CPU idle
2,216.5 (45.8%) 03:46:17 INFO - TinderboxPrint: CPU system
977.6 (20.2%) 03:46:17 INFO - TinderboxPrint: CPU user
1,643.6 (34.0%) 03:46:17 INFO - TinderboxPrint: Swap in / out
443,482,112 / 0 03:46:17 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 395459072; Write bytes: 383522304; Read time: 28931; Write time: 845 03:46:17 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 30481920; Write bytes: 298348032; Read time: 181; Write time: 1132 03:46:17 INFO - Running post-run listener: _upload_blobber_files 03:46:17 INFO - Blob upload gear active. 03:46:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:46:17 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 03:46:17 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'] 03:46:17 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 03:46:18 INFO - (blobuploader) - INFO - Open directory for files ... 03:46:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:46:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:46:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:46:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:46:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:46:20 INFO - (blobuploader) - INFO - Done attempting. 03:46:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:46:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:46:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:46:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:46:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:46:21 INFO - (blobuploader) - INFO - Done attempting. 03:46:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:46:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:46:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:46:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:46:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:46:30 INFO - (blobuploader) - INFO - Done attempting. 03:46:30 INFO - (blobuploader) - INFO - Iteration through files over. 03:46:30 INFO - Return code: 0 03:46:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:46:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:46:30 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/24048231538c270081499d8eb9b05a7cbb466bfad6a136d6939cdafcfbe5534c4b4c9db27fe41413e0c47422d8a2fa05328598e35301b62c3a162b70e3d1f844", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/66b3dd927a47b7f71deb87674abc85426612da6cbd8f0ad365a609dfa74d9baa0ab51ced55046700b62abc9b87cdc63f838e492e34c5c09547b0d134e293e30c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/22e44bae16de0adef38f4140dc7214018a463a232b29c95f71c2937443031ab9276b3d79946bdaea5dc356b2d705e39b381d7bdf7c2066c028d56659c00ed352"} 03:46:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:46:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:46:30 INFO - Contents: 03:46:30 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/24048231538c270081499d8eb9b05a7cbb466bfad6a136d6939cdafcfbe5534c4b4c9db27fe41413e0c47422d8a2fa05328598e35301b62c3a162b70e3d1f844", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/66b3dd927a47b7f71deb87674abc85426612da6cbd8f0ad365a609dfa74d9baa0ab51ced55046700b62abc9b87cdc63f838e492e34c5c09547b0d134e293e30c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/22e44bae16de0adef38f4140dc7214018a463a232b29c95f71c2937443031ab9276b3d79946bdaea5dc356b2d705e39b381d7bdf7c2066c028d56659c00ed352"} 03:46:30 INFO - Running post-run listener: copy_logs_to_upload_dir 03:46:30 INFO - Copying logs to upload dir... 03:46:30 INFO - mkdir: /builds/slave/test/build/upload/logs 03:46:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.018381 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-07-25 03:46:30.902352) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-25 03:46:30.906114) ========= 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.YydfphVY5f/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.BObuhvW3rz/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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/24048231538c270081499d8eb9b05a7cbb466bfad6a136d6939cdafcfbe5534c4b4c9db27fe41413e0c47422d8a2fa05328598e35301b62c3a162b70e3d1f844", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/66b3dd927a47b7f71deb87674abc85426612da6cbd8f0ad365a609dfa74d9baa0ab51ced55046700b62abc9b87cdc63f838e492e34c5c09547b0d134e293e30c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/22e44bae16de0adef38f4140dc7214018a463a232b29c95f71c2937443031ab9276b3d79946bdaea5dc356b2d705e39b381d7bdf7c2066c028d56659c00ed352"} build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630177/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011042 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630177/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/24048231538c270081499d8eb9b05a7cbb466bfad6a136d6939cdafcfbe5534c4b4c9db27fe41413e0c47422d8a2fa05328598e35301b62c3a162b70e3d1f844", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/66b3dd927a47b7f71deb87674abc85426612da6cbd8f0ad365a609dfa74d9baa0ab51ced55046700b62abc9b87cdc63f838e492e34c5c09547b0d134e293e30c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/22e44bae16de0adef38f4140dc7214018a463a232b29c95f71c2937443031ab9276b3d79946bdaea5dc356b2d705e39b381d7bdf7c2066c028d56659c00ed352"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-25 03:46:30.946298) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 03:46:30.946821) ========= 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.YydfphVY5f/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.BObuhvW3rz/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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.005281 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 03:46:30.997072) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-25 03:46:30.997360) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-25 03:46:30.997662) ========= ========= Total master_lag: 0.20 =========