builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0324 starttime: 1494609622.48 results: success (0) revision: 1e2fe13035e13b7b4001ade3b48f226957cef5fc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-12 10:20:22.483350) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-12 10:20:22.483809) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-12 10:20:22.484082) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-12 10:20:22.509481) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 10:20:22.509890) ========= 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.ZULDNzoTqJ/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.5Pp4C9dS1E/Listeners TMPDIR=/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 --2017-05-12 10:20:22-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 166M=0s 2017-05-12 10:20:22 (166 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.085325 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 10:20:22.618216) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 10:20:22.618535) ========= 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.ZULDNzoTqJ/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.5Pp4C9dS1E/Listeners TMPDIR=/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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.039102 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 10:20:22.678218) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-12 10:20:22.678550) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1e2fe13035e13b7b4001ade3b48f226957cef5fc --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 1e2fe13035e13b7b4001ade3b48f226957cef5fc --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZULDNzoTqJ/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.5Pp4C9dS1E/Listeners TMPDIR=/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 2017-05-12 10:20:22,785 truncating revision to first 12 chars 2017-05-12 10:20:22,785 Setting DEBUG logging. 2017-05-12 10:20:22,785 attempt 1/10 2017-05-12 10:20:22,785 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1e2fe13035e1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-12 10:20:24,048 unpacking tar archive at: mozilla-central-1e2fe13035e1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.534738 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-12 10:20:24.235793) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-12 10:20:24.236110) ========= script_repo_revision: 1e2fe13035e13b7b4001ade3b48f226957cef5fc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-12 10:20:24.236489) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-12 10:20:24.236766) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-12 10:20:24.255776) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-05-12 10:20:24.256162) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZULDNzoTqJ/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.5Pp4C9dS1E/Listeners TMPDIR=/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 10:20:24 INFO - MultiFileLogger online at 20170512 10:20:24 in /builds/slave/test 10:20:24 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 10:20:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:20:24 INFO - {'append_to_log': False, 10:20:24 INFO - 'base_work_dir': '/builds/slave/test', 10:20:24 INFO - 'blob_upload_branch': 'Firefox', 10:20:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:20:24 INFO - 'branch': 'Firefox', 10:20:24 INFO - 'buildbot_json_path': 'buildprops.json', 10:20:24 INFO - 'config_files': ('talos/mac_config.py',), 10:20:24 INFO - 'default_actions': ('clobber', 10:20:24 INFO - 'read-buildbot-config', 10:20:24 INFO - 'download-and-extract', 10:20:24 INFO - 'populate-webroot', 10:20:24 INFO - 'create-virtualenv', 10:20:24 INFO - 'install', 10:20:24 INFO - 'run-tests'), 10:20:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:20:24 INFO - 'download_minidump_stackwalk': True, 10:20:24 INFO - 'download_symbols': 'ondemand', 10:20:24 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:20:24 INFO - 'tooltool.py': '/tools/tooltool.py', 10:20:24 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:20:24 INFO - '/tools/misc-python/virtualenv.py')}, 10:20:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:20:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:20:24 INFO - 'gecko_profile': False, 10:20:24 INFO - 'gecko_profile_interval': 0, 10:20:24 INFO - 'installer_path': 'installer.exe', 10:20:24 INFO - 'log_level': 'info', 10:20:24 INFO - 'log_name': 'talos', 10:20:24 INFO - 'log_to_console': True, 10:20:24 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:20:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:20:24 INFO - 'opt_config_files': (), 10:20:24 INFO - 'pip_index': False, 10:20:24 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:20:24 INFO - 'cmd': ('bash', 10:20:24 INFO - '-c', 10:20:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:20:24 INFO - 'enabled': True, 10:20:24 INFO - 'halt_on_failure': False, 10:20:24 INFO - 'name': 'check_screen_resolution'},), 10:20:24 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:20:24 INFO - 'cmd': ('bash', 10:20:24 INFO - '-c', 10:20:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:20:24 INFO - 'enabled': True, 10:20:24 INFO - 'halt_on_failure': False, 10:20:24 INFO - 'name': 'check_screen_resolution'},), 10:20:24 INFO - 'run_cmd_checks_enabled': True, 10:20:24 INFO - 'suite': 'dromaeojs', 10:20:24 INFO - 'system_bits': '32', 10:20:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:20:24 INFO - 'title': 't-yosemite-r7-0324', 10:20:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:20:24 INFO - 'use_talos_json': True, 10:20:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:20:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:20:24 INFO - 'work_dir': 'build'} 10:20:24 INFO - [mozharness: 2017-05-12 17:20:24.469897Z] Running clobber step. 10:20:24 INFO - Running pre-action listener: _resource_record_pre_action 10:20:24 INFO - Running main action method: clobber 10:20:24 INFO - rmtree: /builds/slave/test/build 10:20:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:20:26 INFO - Running post-action listener: _resource_record_post_action 10:20:26 INFO - [mozharness: 2017-05-12 17:20:26.865358Z] Finished clobber step (success) 10:20:26 INFO - [mozharness: 2017-05-12 17:20:26.865497Z] Running read-buildbot-config step. 10:20:26 INFO - Running pre-action listener: _resource_record_pre_action 10:20:26 INFO - Running main action method: read_buildbot_config 10:20:26 INFO - Using buildbot properties: 10:20:26 INFO - { 10:20:26 INFO - "project": "", 10:20:26 INFO - "product": "firefox", 10:20:26 INFO - "who": "ryanvm@gmail.com", 10:20:26 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 10:20:26 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 10:20:26 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 10:20:26 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 10:20:26 INFO - "stage_platform": "macosx64", 10:20:26 INFO - "basedir": "/builds/slave/test", 10:20:26 INFO - "buildnumber": 35, 10:20:26 INFO - "platform": "yosemite_r7", 10:20:26 INFO - "parent_task_id": "TSuTwOIoR9epgWsUd-p85w", 10:20:26 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 10:20:26 INFO - "slavebuilddir": "test", 10:20:26 INFO - "taskId": "SmVtE4sCRfCjmDn0XTll9w", 10:20:26 INFO - "branch": "mozilla-central", 10:20:26 INFO - "script_repo_revision": "production", 10:20:26 INFO - "revision": "1e2fe13035e13b7b4001ade3b48f226957cef5fc", 10:20:26 INFO - "slavename": "t-yosemite-r7-0324", 10:20:26 INFO - "repo_path": "mozilla-central" 10:20:26 INFO - } 10:20:26 INFO - Finding installer, test and symbols from parent task. 10:20:26 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SmVtE4sCRfCjmDn0XTll9w'}, attempt #1 10:20:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SmVtE4sCRfCjmDn0XTll9w'}, attempt #1 10:20:28 INFO - Task dependencies: VuLvxllzQpeRHA5BIGyEeQ 10:20:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TSuTwOIoR9epgWsUd-p85w'}, attempt #1 10:20:28 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/TSuTwOIoR9epgWsUd-p85w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:20:28 INFO - Set test_url: https://queue.taskcluster.net/v1/task/TSuTwOIoR9epgWsUd-p85w/artifacts/public/build/test_packages.json 10:20:28 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/TSuTwOIoR9epgWsUd-p85w/artifacts/public/build/target.crashreporter-symbols.zip 10:20:28 INFO - Running post-action listener: _resource_record_post_action 10:20:28 INFO - [mozharness: 2017-05-12 17:20:28.633502Z] Finished read-buildbot-config step (success) 10:20:28 INFO - [mozharness: 2017-05-12 17:20:28.633627Z] Running download-and-extract step. 10:20:28 INFO - Running pre-action listener: _resource_record_pre_action 10:20:28 INFO - Running main action method: download_and_extract 10:20:28 INFO - mkdir: /builds/slave/test/build/tests 10:20:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:28 INFO - trying https://queue.taskcluster.net/v1/task/TSuTwOIoR9epgWsUd-p85w/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 10:20:28 INFO - Downloading https://queue.taskcluster.net/v1/task/TSuTwOIoR9epgWsUd-p85w/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 10:20:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TSuTwOIoR9epgWsUd-p85w/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 10:20:29 INFO - Downloaded 1325 bytes. 10:20:29 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 10:20:29 INFO - Using the following test package requirements: 10:20:29 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:20:29 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 10:20:29 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 10:20:29 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:20:29 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 10:20:29 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:20:29 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 10:20:29 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:20:29 INFO - u'jsshell-mac.zip'], 10:20:29 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:20:29 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 10:20:29 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 10:20:29 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:20:29 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 10:20:29 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:20:29 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 10:20:29 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:20:29 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 10:20:29 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:20:29 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 10:20:29 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:20:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TSuTwOIoR9epgWsUd-p85w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 10:20:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TSuTwOIoR9epgWsUd-p85w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:20:29 INFO - Fetch https://queue.taskcluster.net/v1/task/TSuTwOIoR9epgWsUd-p85w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 10:20:30 INFO - Content-Length response header: 56563229 10:20:30 INFO - Bytes received: 56563229 10:20:48 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 10:20:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TSuTwOIoR9epgWsUd-p85w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 10:20:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TSuTwOIoR9epgWsUd-p85w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:20:48 INFO - Fetch https://queue.taskcluster.net/v1/task/TSuTwOIoR9epgWsUd-p85w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 10:20:50 INFO - Content-Length response header: 56563229 10:20:50 INFO - Bytes received: 56563229 10:21:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TSuTwOIoR9epgWsUd-p85w/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 10:21:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TSuTwOIoR9epgWsUd-p85w/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 10:21:07 INFO - Fetch https://queue.taskcluster.net/v1/task/TSuTwOIoR9epgWsUd-p85w/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 10:21:08 INFO - Content-Length response header: 14409411 10:21:08 INFO - Bytes received: 14409411 10:21:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:08 INFO - trying https://queue.taskcluster.net/v1/task/TSuTwOIoR9epgWsUd-p85w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:21:08 INFO - Downloading https://queue.taskcluster.net/v1/task/TSuTwOIoR9epgWsUd-p85w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 10:21:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TSuTwOIoR9epgWsUd-p85w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 10:21:11 INFO - Downloaded 75903814 bytes. 10:21:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TSuTwOIoR9epgWsUd-p85w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:21:11 INFO - mkdir: /builds/slave/test/properties 10:21:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:21:11 INFO - Writing to file /builds/slave/test/properties/build_url 10:21:11 INFO - Contents: 10:21:11 INFO - build_url:https://queue.taskcluster.net/v1/task/TSuTwOIoR9epgWsUd-p85w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:21:11 INFO - Running post-action listener: _resource_record_post_action 10:21:11 INFO - Running post-action listener: set_extra_try_arguments 10:21:11 INFO - [mozharness: 2017-05-12 17:21:11.294662Z] Finished download-and-extract step (success) 10:21:11 INFO - [mozharness: 2017-05-12 17:21:11.294769Z] Running populate-webroot step. 10:21:11 INFO - Running pre-action listener: _resource_record_pre_action 10:21:11 INFO - Running main action method: populate_webroot 10:21:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:21:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:21:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:21:11 INFO - '--tppagecycles', 10:21:11 INFO - '7'], 10:21:11 INFO - 'tests': ['tsvgm']}}, 10:21:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:21:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:21:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:21:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:21:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:21:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:21:11 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:21:11 INFO - 'talos_options': ['--disable-e10s'], 10:21:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:21:11 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:21:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:21:11 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:21:11 INFO - 'talos_options': ['--disable-e10s'], 10:21:11 INFO - 'tests': ['damp', 'tps']}, 10:21:11 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:21:11 INFO - 'tests': ['damp', 'tps']}, 10:21:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:21:11 INFO - 'tests': ['dromaeo_dom']}, 10:21:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:21:11 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:21:11 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 10:21:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 10:21:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:21:11 INFO - 'tests': ['a11yr', 10:21:11 INFO - 'ts_paint', 10:21:11 INFO - 'tpaint', 10:21:11 INFO - 'sessionrestore', 10:21:11 INFO - 'sessionrestore_no_auto_restore', 10:21:11 INFO - 'tabpaint']}, 10:21:11 INFO - 'other-e10s': {'tests': ['a11yr', 10:21:11 INFO - 'ts_paint', 10:21:11 INFO - 'tpaint', 10:21:11 INFO - 'sessionrestore', 10:21:11 INFO - 'sessionrestore_no_auto_restore', 10:21:11 INFO - 'tabpaint']}, 10:21:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:21:11 INFO - 'ts_paint', 10:21:11 INFO - 'tpaint', 10:21:11 INFO - 'sessionrestore', 10:21:11 INFO - 'sessionrestore_no_auto_restore', 10:21:11 INFO - 'tabpaint']}, 10:21:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:21:11 INFO - 'ts_paint', 10:21:11 INFO - 'tpaint', 10:21:11 INFO - 'sessionrestore', 10:21:11 INFO - 'sessionrestore_no_auto_restore', 10:21:11 INFO - 'tabpaint']}, 10:21:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:21:11 INFO - 'tests': ['a11yr', 10:21:11 INFO - 'ts_paint', 10:21:11 INFO - 'tpaint', 10:21:11 INFO - 'sessionrestore', 10:21:11 INFO - 'sessionrestore_no_auto_restore', 10:21:11 INFO - 'tabpaint']}, 10:21:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:21:11 INFO - 'tests': ['a11yr', 10:21:11 INFO - 'ts_paint', 10:21:11 INFO - 'tpaint', 10:21:11 INFO - 'sessionrestore', 10:21:11 INFO - 'sessionrestore_no_auto_restore', 10:21:11 INFO - 'tabpaint']}, 10:21:11 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 10:21:11 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 10:21:11 INFO - 'bloom_basic_ref']}, 10:21:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:21:11 INFO - 'tests': ['tsvgx', 10:21:11 INFO - 'tsvgr_opacity', 10:21:11 INFO - 'tart', 10:21:11 INFO - 'tscrollx', 10:21:11 INFO - 'cart', 10:21:11 INFO - 'tsvg_static']}, 10:21:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:21:11 INFO - 'tsvgr_opacity', 10:21:11 INFO - 'tart', 10:21:11 INFO - 'tscrollx', 10:21:11 INFO - 'cart', 10:21:11 INFO - 'tsvg_static']}, 10:21:11 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:21:11 INFO - 'talos_options': ['--disable-e10s'], 10:21:11 INFO - 'tests': ['tp5o']}, 10:21:11 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:21:11 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:21:11 INFO - 'talos_options': ['--disable-e10s', 10:21:11 INFO - '--xperf_path', 10:21:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:21:11 INFO - 'tests': ['tp5n']}, 10:21:11 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:21:11 INFO - 'talos_options': ['--xperf_path', 10:21:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:21:11 INFO - 'tests': ['tp5n']}}, 10:21:11 INFO - 'talos.zip': {'path': '', 10:21:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:21:11 INFO - Running post-action listener: _resource_record_post_action 10:21:11 INFO - [mozharness: 2017-05-12 17:21:11.316126Z] Finished populate-webroot step (success) 10:21:11 INFO - [mozharness: 2017-05-12 17:21:11.316285Z] Running create-virtualenv step. 10:21:11 INFO - Running pre-action listener: _resource_record_pre_action 10:21:11 INFO - Running main action method: create_virtualenv 10:21:11 INFO - Creating virtualenv /builds/slave/test/build/venv 10:21:11 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 10:21:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:21:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:21:11 INFO - Using real prefix '/tools/python27' 10:21:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:21:12 INFO - Installing distribute.............................................................................................................................................................................................done. 10:21:15 INFO - Installing pip.................done. 10:21:15 INFO - Return code: 0 10:21:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:21:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:21:15 INFO - Reading from file tmpfile_stdout 10:21:15 INFO - Output received: 10:21:15 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 10:21:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:21:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:15 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/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 0x10b7f8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7fb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96085b64e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b509d30>, '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 0x10b4bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b4fdbe8>, '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.ZULDNzoTqJ/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.5Pp4C9dS1E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 10:21:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 10:21:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:21:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZULDNzoTqJ/Render', 10:21:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:21:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:21:15 INFO - 'HOME': '/Users/cltbld', 10:21:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:21:15 INFO - 'LOGNAME': 'cltbld', 10:21:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:15 INFO - 'MOZ_NO_REMOTE': '1', 10:21:15 INFO - 'NO_EM_RESTART': '1', 10:21:15 INFO - 'PAGER': '/bin/cat', 10:21:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:21:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:21:15 INFO - 'PWD': '/builds/slave/test', 10:21:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:21:15 INFO - 'SHELL': '/bin/bash', 10:21:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5Pp4C9dS1E/Listeners', 10:21:15 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 10:21:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:21:15 INFO - 'USER': 'cltbld', 10:21:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:21:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:21:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:21:15 INFO - 'XPC_FLAGS': '0x0', 10:21:15 INFO - 'XPC_SERVICE_NAME': '0', 10:21:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:21:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:15 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 10:21:15 INFO - Cleaning up... 10:21:15 INFO - Return code: 0 10:21:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:21:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:15 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/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 0x10b7f8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7fb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96085b64e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b509d30>, '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 0x10b4bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b4fdbe8>, '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.ZULDNzoTqJ/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.5Pp4C9dS1E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 10:21:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 10:21:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:21:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZULDNzoTqJ/Render', 10:21:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:21:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:21:15 INFO - 'HOME': '/Users/cltbld', 10:21:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:21:15 INFO - 'LOGNAME': 'cltbld', 10:21:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:15 INFO - 'MOZ_NO_REMOTE': '1', 10:21:15 INFO - 'NO_EM_RESTART': '1', 10:21:15 INFO - 'PAGER': '/bin/cat', 10:21:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:21:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:21:15 INFO - 'PWD': '/builds/slave/test', 10:21:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:21:15 INFO - 'SHELL': '/bin/bash', 10:21:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5Pp4C9dS1E/Listeners', 10:21:15 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 10:21:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:21:15 INFO - 'USER': 'cltbld', 10:21:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:21:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:21:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:21:15 INFO - 'XPC_FLAGS': '0x0', 10:21:15 INFO - 'XPC_SERVICE_NAME': '0', 10:21:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:21:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:15 INFO - Downloading/unpacking psutil>=3.1.1 10:21:15 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 10:21:15 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 10:21:15 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 10:21:15 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 10:21:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:21:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:21:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:21:17 INFO - Installing collected packages: psutil 10:21:17 INFO - Running setup.py install for psutil 10:21:17 INFO - building 'psutil._psutil_osx' extension 10:21:17 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 10:21:18 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 10:21:18 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 10:21:18 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 10:21:18 INFO - building 'psutil._psutil_posix' extension 10:21:18 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 10:21:18 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:21:18 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:21:18 INFO - ^ 10:21:18 INFO - 1 warning generated. 10:21:18 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 10:21:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:21:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:21:18 INFO - Successfully installed psutil 10:21:18 INFO - Cleaning up... 10:21:18 INFO - Return code: 0 10:21:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:21:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:19 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/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 0x10b7f8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7fb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96085b64e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b509d30>, '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 0x10b4bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b4fdbe8>, '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.ZULDNzoTqJ/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.5Pp4C9dS1E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 10:21:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 10:21:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:21:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZULDNzoTqJ/Render', 10:21:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:21:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:21:19 INFO - 'HOME': '/Users/cltbld', 10:21:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:21:19 INFO - 'LOGNAME': 'cltbld', 10:21:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:19 INFO - 'MOZ_NO_REMOTE': '1', 10:21:19 INFO - 'NO_EM_RESTART': '1', 10:21:19 INFO - 'PAGER': '/bin/cat', 10:21:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:21:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:21:19 INFO - 'PWD': '/builds/slave/test', 10:21:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:21:19 INFO - 'SHELL': '/bin/bash', 10:21:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5Pp4C9dS1E/Listeners', 10:21:19 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 10:21:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:21:19 INFO - 'USER': 'cltbld', 10:21:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:21:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:21:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:21:19 INFO - 'XPC_FLAGS': '0x0', 10:21:19 INFO - 'XPC_SERVICE_NAME': '0', 10:21:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:21:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:21:20 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 10:21:20 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 10:21:20 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 10:21:20 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 10:21:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:21:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:21:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:21:22 INFO - Installing collected packages: mozsystemmonitor 10:21:22 INFO - Running setup.py install for mozsystemmonitor 10:21:22 INFO - Successfully installed mozsystemmonitor 10:21:22 INFO - Cleaning up... 10:21:22 INFO - Return code: 0 10:21:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:21:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10b7f8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7fb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96085b64e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b509d30>, '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 0x10b4bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b4fdbe8>, '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.ZULDNzoTqJ/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.5Pp4C9dS1E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 10:21:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 10:21:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:21:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZULDNzoTqJ/Render', 10:21:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:21:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:21:22 INFO - 'HOME': '/Users/cltbld', 10:21:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:21:22 INFO - 'LOGNAME': 'cltbld', 10:21:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:22 INFO - 'MOZ_NO_REMOTE': '1', 10:21:22 INFO - 'NO_EM_RESTART': '1', 10:21:22 INFO - 'PAGER': '/bin/cat', 10:21:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:21:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:21:22 INFO - 'PWD': '/builds/slave/test', 10:21:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:21:22 INFO - 'SHELL': '/bin/bash', 10:21:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5Pp4C9dS1E/Listeners', 10:21:22 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 10:21:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:21:22 INFO - 'USER': 'cltbld', 10:21:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:21:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:21:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:21:22 INFO - 'XPC_FLAGS': '0x0', 10:21:22 INFO - 'XPC_SERVICE_NAME': '0', 10:21:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:21:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:22 INFO - Downloading/unpacking jsonschema==2.5.1 10:21:22 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 10:21:22 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 10:21:22 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 10:21:22 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 10:21:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:21:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:21:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:21:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:21:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:21:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:21:24 INFO - Downloading functools32-3.2.3-2.tar.gz 10:21:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:21:24 INFO - warning: no files found matching '*.txt' 10:21:24 INFO - no previously-included directories found matching 'build' 10:21:24 INFO - no previously-included directories found matching 'dist' 10:21:24 INFO - no previously-included directories found matching '.git*' 10:21:24 INFO - Installing collected packages: jsonschema, functools32 10:21:24 INFO - Running setup.py install for functools32 10:21:24 INFO - warning: no files found matching '*.txt' 10:21:24 INFO - no previously-included directories found matching 'build' 10:21:24 INFO - no previously-included directories found matching 'dist' 10:21:24 INFO - no previously-included directories found matching '.git*' 10:21:24 INFO - Successfully installed jsonschema functools32 10:21:24 INFO - Cleaning up... 10:21:24 INFO - Return code: 0 10:21:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:21:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b7f8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7fb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96085b64e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b509d30>, '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 0x10b4bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b4fdbe8>, '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.ZULDNzoTqJ/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.5Pp4C9dS1E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 10:21:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:21:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:21:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZULDNzoTqJ/Render', 10:21:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:21:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:21:24 INFO - 'HOME': '/Users/cltbld', 10:21:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:21:24 INFO - 'LOGNAME': 'cltbld', 10:21:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:24 INFO - 'MOZ_NO_REMOTE': '1', 10:21:24 INFO - 'NO_EM_RESTART': '1', 10:21:24 INFO - 'PAGER': '/bin/cat', 10:21:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:21:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:21:24 INFO - 'PWD': '/builds/slave/test', 10:21:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:21:24 INFO - 'SHELL': '/bin/bash', 10:21:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5Pp4C9dS1E/Listeners', 10:21:24 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 10:21:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:21:24 INFO - 'USER': 'cltbld', 10:21:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:21:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:21:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:21:24 INFO - 'XPC_FLAGS': '0x0', 10:21:24 INFO - 'XPC_SERVICE_NAME': '0', 10:21:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:21:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:21:24 INFO - Cleaning up... 10:21:24 INFO - Return code: 0 10:21:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:21:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10b7f8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7fb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96085b64e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b509d30>, '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 0x10b4bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b4fdbe8>, '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.ZULDNzoTqJ/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.5Pp4C9dS1E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 10:21:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 10:21:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:21:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZULDNzoTqJ/Render', 10:21:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:21:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:21:24 INFO - 'HOME': '/Users/cltbld', 10:21:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:21:24 INFO - 'LOGNAME': 'cltbld', 10:21:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:24 INFO - 'MOZ_NO_REMOTE': '1', 10:21:24 INFO - 'NO_EM_RESTART': '1', 10:21:24 INFO - 'PAGER': '/bin/cat', 10:21:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:21:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:21:24 INFO - 'PWD': '/builds/slave/test', 10:21:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:21:24 INFO - 'SHELL': '/bin/bash', 10:21:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5Pp4C9dS1E/Listeners', 10:21:24 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 10:21:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:21:24 INFO - 'USER': 'cltbld', 10:21:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:21:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:21:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:21:24 INFO - 'XPC_FLAGS': '0x0', 10:21:24 INFO - 'XPC_SERVICE_NAME': '0', 10:21:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:21:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:24 INFO - Downloading/unpacking blobuploader==1.2.4 10:21:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:21:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:21:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:21:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:21:26 INFO - Downloading blobuploader-1.2.4.tar.gz 10:21:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:21:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:21:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:21:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:21:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:21:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:21:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:21:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:21:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:21:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:21:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:21:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:21:27 INFO - Downloading docopt-0.6.1.tar.gz 10:21:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:21:27 INFO - Installing collected packages: blobuploader, requests, docopt 10:21:27 INFO - Running setup.py install for blobuploader 10:21:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:21:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:21:27 INFO - Running setup.py install for requests 10:21:27 INFO - Running setup.py install for docopt 10:21:28 INFO - Successfully installed blobuploader requests docopt 10:21:28 INFO - Cleaning up... 10:21:28 INFO - Return code: 0 10:21:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:21:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10b7f8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7fb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96085b64e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b509d30>, '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 0x10b4bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b4fdbe8>, '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.ZULDNzoTqJ/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.5Pp4C9dS1E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 10:21:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:21:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:21:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZULDNzoTqJ/Render', 10:21:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:21:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:21:28 INFO - 'HOME': '/Users/cltbld', 10:21:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:21:28 INFO - 'LOGNAME': 'cltbld', 10:21:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:28 INFO - 'MOZ_NO_REMOTE': '1', 10:21:28 INFO - 'NO_EM_RESTART': '1', 10:21:28 INFO - 'PAGER': '/bin/cat', 10:21:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:21:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:21:28 INFO - 'PWD': '/builds/slave/test', 10:21:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:21:28 INFO - 'SHELL': '/bin/bash', 10:21:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5Pp4C9dS1E/Listeners', 10:21:28 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 10:21:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:21:28 INFO - 'USER': 'cltbld', 10:21:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:21:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:21:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:21:28 INFO - 'XPC_FLAGS': '0x0', 10:21:28 INFO - 'XPC_SERVICE_NAME': '0', 10:21:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:21:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:21:28 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-RiqqJf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:21:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:21:28 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-q1lcsn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:21:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:21:28 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-IIeBNb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:21:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:21:28 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-N4Piab-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:21:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:21:28 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-SLJg3R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:21:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:21:28 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-14VPeP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:21:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:21:29 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-g9tmmV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:21:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:21:29 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-m5u2ga-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:21:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:21:29 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-WW4cxi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:21:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:21:29 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-FZ4DO0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:21:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:21:29 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-3v_txb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:21:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:21:29 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-1j4tIs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:21:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:21:29 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-LFbXfe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:21:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:21:29 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-K7OTTn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:21:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:21:29 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-5TqHx9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:21:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:21:29 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-K6pwqV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:21:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:21:30 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-TFcRvU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:21:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:21:30 INFO - Running setup.py install for manifestparser 10:21:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:21:30 INFO - Running setup.py install for mozcrash 10:21:30 INFO - Running setup.py install for mozdebug 10:21:30 INFO - Running setup.py install for mozdevice 10:21:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:21:30 INFO - Running setup.py install for mozfile 10:21:30 INFO - Running setup.py install for mozhttpd 10:21:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:21:31 INFO - Running setup.py install for mozinfo 10:21:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:21:31 INFO - Running setup.py install for mozInstall 10:21:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:21:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:21:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:21:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:21:31 INFO - Running setup.py install for mozleak 10:21:31 INFO - Running setup.py install for mozlog 10:21:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:21:31 INFO - Running setup.py install for moznetwork 10:21:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:21:31 INFO - Running setup.py install for mozprocess 10:21:31 INFO - Running setup.py install for mozprofile 10:21:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:21:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:21:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:21:32 INFO - Running setup.py install for mozrunner 10:21:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:21:32 INFO - Running setup.py install for mozscreenshot 10:21:32 INFO - Running setup.py install for moztest 10:21:32 INFO - Running setup.py install for mozversion 10:21:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:21:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:21:32 INFO - Cleaning up... 10:21:32 INFO - Return code: 0 10:21:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:21:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:32 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/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 0x10b7f8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7fb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96085b64e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b509d30>, '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 0x10b4bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b4fdbe8>, '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.ZULDNzoTqJ/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.5Pp4C9dS1E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 10:21:32 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:21:32 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:21:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZULDNzoTqJ/Render', 10:21:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:21:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:21:32 INFO - 'HOME': '/Users/cltbld', 10:21:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:21:32 INFO - 'LOGNAME': 'cltbld', 10:21:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:32 INFO - 'MOZ_NO_REMOTE': '1', 10:21:32 INFO - 'NO_EM_RESTART': '1', 10:21:32 INFO - 'PAGER': '/bin/cat', 10:21:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:21:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:21:32 INFO - 'PWD': '/builds/slave/test', 10:21:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:21:32 INFO - 'SHELL': '/bin/bash', 10:21:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5Pp4C9dS1E/Listeners', 10:21:32 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 10:21:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:21:32 INFO - 'USER': 'cltbld', 10:21:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:21:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:21:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:21:32 INFO - 'XPC_FLAGS': '0x0', 10:21:32 INFO - 'XPC_SERVICE_NAME': '0', 10:21:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:21:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:21:33 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-M62mW0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:21:33 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 10:21:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:21:33 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-Msfray-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:21:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 10:21:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:21:33 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-zs6HxZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:21:33 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)) 10:21:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:21:33 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-LTkS2o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:21:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 10:21:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:21:33 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-n8Hoxu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:21:33 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)) 10:21:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:21:33 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-GJEfGr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:21:33 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)) 10:21:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:21:33 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-WTByT3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:21:33 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)) 10:21:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:21:33 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-hhsfvz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:21:33 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)) 10:21:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:21:33 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-tBxw1k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:21:33 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)) 10:21:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:21:33 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-SiRvwY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:21:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 10:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:21:34 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-xWOM4m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:21:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 10:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:21:34 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-LB2FPG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:21:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 10:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:21:34 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-0qlZkh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:21:34 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)) 10:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:21:34 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-pEDp6a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:21:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 10:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:21:34 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-sk5vlS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:21:34 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)) 10:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:21:34 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-biVifm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:21:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 10:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:21:34 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-kMnccM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:21:34 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)) 10:21:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:21:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:21:34 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)) 10:21:34 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:21:34 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:21:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:21:34 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 10:21:34 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 10:21:34 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 10:21:34 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 10:21:36 INFO - Downloading blessings-1.6.tar.gz 10:21:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:21:36 INFO - Installing collected packages: blessings 10:21:36 INFO - Running setup.py install for blessings 10:21:36 INFO - Successfully installed blessings 10:21:36 INFO - Cleaning up... 10:21:36 INFO - Return code: 0 10:21:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:21:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:21:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:21:36 INFO - Reading from file tmpfile_stdout 10:21:36 INFO - Current package versions: 10:21:36 INFO - blessings == 1.6 10:21:36 INFO - blobuploader == 1.2.4 10:21:36 INFO - docopt == 0.6.1 10:21:36 INFO - functools32 == 3.2.3-2 10:21:36 INFO - jsonschema == 2.5.1 10:21:36 INFO - manifestparser == 1.1 10:21:36 INFO - mozInstall == 1.12 10:21:36 INFO - mozcrash == 1.0 10:21:36 INFO - mozdebug == 0.1 10:21:36 INFO - mozdevice == 0.50 10:21:36 INFO - mozfile == 1.2 10:21:36 INFO - mozhttpd == 0.7 10:21:36 INFO - mozinfo == 0.9 10:21:36 INFO - mozleak == 0.1 10:21:36 INFO - mozlog == 3.4 10:21:36 INFO - moznetwork == 0.27 10:21:36 INFO - mozprocess == 0.25 10:21:36 INFO - mozprofile == 0.28 10:21:36 INFO - mozrunner == 6.13 10:21:36 INFO - mozscreenshot == 0.1 10:21:36 INFO - mozsystemmonitor == 0.3 10:21:36 INFO - moztest == 0.8 10:21:36 INFO - mozversion == 1.4 10:21:36 INFO - psutil == 3.1.1 10:21:36 INFO - requests == 1.2.3 10:21:36 INFO - wsgiref == 0.1.2 10:21:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:21:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:36 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/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 0x10b7f8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7fb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96085b64e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b509d30>, '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 0x10b4bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b4fdbe8>, '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.ZULDNzoTqJ/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.5Pp4C9dS1E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 10:21:36 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 10:21:36 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:21:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZULDNzoTqJ/Render', 10:21:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:21:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:21:36 INFO - 'HOME': '/Users/cltbld', 10:21:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:21:36 INFO - 'LOGNAME': 'cltbld', 10:21:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:36 INFO - 'MOZ_NO_REMOTE': '1', 10:21:36 INFO - 'NO_EM_RESTART': '1', 10:21:36 INFO - 'PAGER': '/bin/cat', 10:21:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:21:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:21:36 INFO - 'PWD': '/builds/slave/test', 10:21:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:21:36 INFO - 'SHELL': '/bin/bash', 10:21:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5Pp4C9dS1E/Listeners', 10:21:36 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 10:21:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:21:36 INFO - 'USER': 'cltbld', 10:21:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:21:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:21:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:21:36 INFO - 'XPC_FLAGS': '0x0', 10:21:36 INFO - 'XPC_SERVICE_NAME': '0', 10:21:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:21:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:36 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)) 10:21:36 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)) 10:21:36 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)) 10:21:36 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)) 10:21:36 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)) 10:21:36 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)) 10:21:36 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)) 10:21:36 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)) 10:21:36 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)) 10:21:36 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)) 10:21:36 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)) 10:21:36 INFO - Cleaning up... 10:21:37 INFO - Return code: 0 10:21:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:21:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:37 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/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 0x10b7f8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7fb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96085b64e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b509d30>, '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 0x10b4bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b4fdbe8>, '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.ZULDNzoTqJ/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.5Pp4C9dS1E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 10:21:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:21:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:21:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZULDNzoTqJ/Render', 10:21:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:21:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:21:37 INFO - 'HOME': '/Users/cltbld', 10:21:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:21:37 INFO - 'LOGNAME': 'cltbld', 10:21:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:37 INFO - 'MOZ_NO_REMOTE': '1', 10:21:37 INFO - 'NO_EM_RESTART': '1', 10:21:37 INFO - 'PAGER': '/bin/cat', 10:21:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:21:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:21:37 INFO - 'PWD': '/builds/slave/test', 10:21:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:21:37 INFO - 'SHELL': '/bin/bash', 10:21:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5Pp4C9dS1E/Listeners', 10:21:37 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 10:21:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:21:37 INFO - 'USER': 'cltbld', 10:21:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:21:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:21:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:21:37 INFO - 'XPC_FLAGS': '0x0', 10:21:37 INFO - 'XPC_SERVICE_NAME': '0', 10:21:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:21:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:21:37 INFO - Cleaning up... 10:21:37 INFO - Return code: 0 10:21:37 INFO - Running post-action listener: _resource_record_post_action 10:21:37 INFO - Running post-action listener: _start_resource_monitoring 10:21:37 INFO - Starting resource monitoring. 10:21:37 INFO - [mozharness: 2017-05-12 17:21:37.292980Z] Finished create-virtualenv step (success) 10:21:37 INFO - [mozharness: 2017-05-12 17:21:37.293936Z] Running install step. 10:21:37 INFO - Running pre-action listener: _resource_record_pre_action 10:21:37 INFO - Running main action method: install 10:21:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:21:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:21:37 INFO - Reading from file tmpfile_stdout 10:21:37 INFO - Detecting whether we're running mozinstall >=1.0... 10:21:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:21:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:21:37 INFO - Reading from file tmpfile_stdout 10:21:37 INFO - Output received: 10:21:37 INFO - Usage: mozinstall [options] installer 10:21:37 INFO - Options: 10:21:37 INFO - -h, --help show this help message and exit 10:21:37 INFO - -d DEST, --destination=DEST 10:21:37 INFO - Directory to install application into. [default: 10:21:37 INFO - "/builds/slave/test"] 10:21:37 INFO - --app=APP Application being installed. [default: firefox] 10:21:37 INFO - mkdir: /builds/slave/test/build/application 10:21:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:21:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 10:21:58 INFO - Reading from file tmpfile_stdout 10:21:58 INFO - Output received: 10:21:58 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:21:58 INFO - Running post-action listener: _resource_record_post_action 10:21:58 INFO - [mozharness: 2017-05-12 17:21:58.145952Z] Finished install step (success) 10:21:58 INFO - [mozharness: 2017-05-12 17:21:58.146078Z] Running run-tests step. 10:21:58 INFO - Running pre-action listener: _resource_record_pre_action 10:21:58 INFO - Running main action method: run_tests 10:21:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:21:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:21:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:21:58 INFO - 2017-05-12 10:21:58.159 screenresolution[1633:7535] starting screenresolution argv=screenresolution get 10:21:58 INFO - 2017-05-12 10:21:58.161 screenresolution[1633:7535] Display 0: 1600x1200x32@60 10:21:58 INFO - 2017-05-12 10:21:58.166 screenresolution[1634:7537] starting screenresolution argv=screenresolution list 10:21:58 INFO - Available Modes on Display 0 10:21:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:21:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:21:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:21:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:21:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:21:58 INFO - Intel Iris: 10:21:58 INFO - Chipset Model: Intel Iris 10:21:58 INFO - Type: GPU 10:21:58 INFO - Bus: Built-In 10:21:58 INFO - VRAM (Dynamic, Max): 1536 MB 10:21:58 INFO - Vendor: Intel (0x8086) 10:21:58 INFO - Device ID: 0x0a2e 10:21:58 INFO - Revision ID: 0x0009 10:21:58 INFO - Displays: 10:21:58 INFO - Display: 10:21:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:21:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:21:58 INFO - Display Serial Number: 1600x1200 60 10:21:58 INFO - Main Display: Yes 10:21:58 INFO - Mirror: Off 10:21:58 INFO - Online: Yes 10:21:58 INFO - Rotation: Supported 10:21:58 INFO - Return code: 0 10:21:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:21:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:21:58 INFO - Python 2.7.3 10:21:58 INFO - Return code: 0 10:21:58 INFO - grabbing minidump binary from tooltool 10:21:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:58 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 0x10b509d30>, '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 0x10b4bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b4fdbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:21:58 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 10:21:58 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 10:21:58 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 10:21:58 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:21:58 INFO - Return code: 0 10:21:58 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:21:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:21:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:21:58 INFO - ENV: RUST_BACKTRACE is now 1 10:21:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:21:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:21:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TSuTwOIoR9epgWsUd-p85w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0324', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 10:21:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/TSuTwOIoR9epgWsUd-p85w/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0324 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 10:21:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZULDNzoTqJ/Render', 10:21:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:21:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:21:58 INFO - 'HOME': '/Users/cltbld', 10:21:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:21:58 INFO - 'LOGNAME': 'cltbld', 10:21:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:21:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:21:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:58 INFO - 'MOZ_NO_REMOTE': '1', 10:21:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:21:58 INFO - 'NO_EM_RESTART': '1', 10:21:58 INFO - 'PAGER': '/bin/cat', 10:21:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:21:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:21:58 INFO - 'PWD': '/builds/slave/test', 10:21:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:21:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:21:58 INFO - 'RUST_BACKTRACE': '1', 10:21:58 INFO - 'SHELL': '/bin/bash', 10:21:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5Pp4C9dS1E/Listeners', 10:21:58 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 10:21:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:21:58 INFO - 'USER': 'cltbld', 10:21:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:21:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:21:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:21:58 INFO - 'XPC_FLAGS': '0x0', 10:21:58 INFO - 'XPC_SERVICE_NAME': '0', 10:21:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:21:58 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TSuTwOIoR9epgWsUd-p85w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0324', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 10:21:58 INFO - mozversion application_buildid: 20170512085216 10:21:58 INFO - mozversion application_changeset: 1e2fe13035e13b7b4001ade3b48f226957cef5fc 10:21:58 INFO - mozversion application_display_name: Nightly 10:21:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:21:58 INFO - mozversion application_name: Firefox 10:21:58 INFO - mozversion application_remotingname: firefox 10:21:58 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:21:58 INFO - mozversion application_vendor: Mozilla 10:21:58 INFO - mozversion application_version: 55.0a1 10:21:58 INFO - mozversion platform_buildid: 20170512085216 10:21:58 INFO - mozversion platform_changeset: 1e2fe13035e13b7b4001ade3b48f226957cef5fc 10:21:58 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:21:58 INFO - mozversion platform_version: 55.0a1 10:21:58 INFO - using testdate: 1494609718 10:21:58 INFO - actual date: 1494609718 10:21:58 INFO - starting webserver on 'localhost:49226' 10:21:58 INFO - SUITE-START | Running 2 tests 10:21:58 INFO - TEST-START | dromaeo_css 10:21:58 INFO - operating with platform_type : mac_ 10:21:58 INFO - Initialising browser for dromaeo_css test... 10:21:58 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/tmpaSeScY/profile http://localhost:49226/getInfo.html) 10:22:01 INFO - PID 1644 | __metrics Screen width/height:1600/1200 10:22:01 INFO - PID 1644 | colorDepth:24 10:22:01 INFO - PID 1644 | Browser inner width/height: 1024/691 10:22:01 INFO - PID 1644 | __metrics 10:22:01 INFO - PID 1644 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:22:01 INFO - PID 1644 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:22:02 INFO - TEST-INFO | 1644: exit 0 10:22:02 INFO - Browser initialized. 10:22:02 INFO - Running cycle 1/1 for dromaeo_css test... 10:22:02 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/tmpaSeScY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:22:14 INFO - PID 1646 | RSS: Main: 249200640 10:22:14 INFO - PID 1646 | 10:25:18 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49226/tests/dromaeo/cssquery-ext.html) 10:25:20 INFO - PID 1646 | RSS: Main: 416768000 10:25:20 INFO - PID 1646 | 10:28:23 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-ext.html (next: http://localhost:49226/tests/dromaeo/cssquery-jquery.html) 10:28:23 INFO - PID 1646 | RSS: Main: 380809216 10:28:23 INFO - PID 1646 | 10:31:25 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49226/tests/dromaeo/cssquery-mootools.html) 10:31:26 INFO - PID 1646 | RSS: Main: 347312128 10:31:26 INFO - PID 1646 | 10:34:28 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49226/tests/dromaeo/cssquery-prototype.html) 10:34:29 INFO - PID 1646 | RSS: Main: 353357824 10:34:29 INFO - PID 1646 | 10:37:32 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49226/tests/dromaeo/cssquery-yui.html) 10:37:33 INFO - PID 1646 | RSS: Main: 335994880 10:37:33 INFO - PID 1646 | 10:40:35 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-yui.html (next: http://localhost:49226/tests/dromaeo/cssquery-dojo.html) 10:40:35 INFO - PID 1646 | RSS: Main: 387330048 10:40:35 INFO - PID 1646 | 10:40:35 INFO - PID 1646 | __start_tp_report 10:40:35 INFO - PID 1646 | _x_x_mozilla_page_load 10:40:35 INFO - PID 1646 | _x_x_mozilla_page_load_details 10:40:35 INFO - PID 1646 | |i|pagename|runs| 10:40:35 INFO - PID 1646 | |0;dojo.html;3350.0954742219587;3389.966100338996;3432.1419645088727;3440.415129427997;3453.9309213815723;5942.673152976596;6083.756649734005;6122.9387706122925;6138.815835524931;6152.753889844406;6086.178365920599;6090.939090609093;6107.613648220932;6141.148492690769;6147.538934579913;5144.331236939192;5202.505761952609;5288.2596436498925;5320.973395133019;5359.222912677659;5414.972925135369;5424.755885985121;5437.755301011464;5437.945620543784;5493.725313734317;5212.40057393397;5289.127293996525;5313.681179129265;5327.866803329909;5349.251104845324;5309.283246761676;5360.276362691065;5384.111621582434;5392.164214546712;5406.945930540684;5332.813351532716;5344.251804747339;5345.224942383372;5354.785808567653;5386.757595908193;6084.6045007074845;6100.542459315525;6130.325664176937;6136.23297087864;6149.661768602725;5252.553532949704;6232.781852635136;6320.778772742932;6458.6770661466735;6462.612243265372;6009.489193418521;6427;6446.613203207822;6533.379328963741;6542.378474044958;6133.44798968095;6180.351063138351;6188.597741146811;6200.038993955944;6209.658468784261;6462.192225971754;6690.0968369270495;6691.832704182434;6702.36327548882;6710.899336509956;6261;6265.906011409833;6340.936590634127;6358;6373.872522549523;6508.967455162694;6525.575837570543;6527.608343499405;6532.216134063848;6533.509986751013;6306.337834527309;6430.421262086435;6439.452646525004;6443.548951573345;6453.322401147811;6230.158928544679;6257;6369.681515924185;6438;6445.033245013192;5141.974290128525;5150.974245128826;5156.587473002151;5162.380514338303;5193.636445448782;4512.503624601291;4599.954000460019;4601.217792975202;4627.375304333872;4636.44362676481;6022.277326720734;6029.306629737549;6097.420745029215;6134.926457743815;6161.44546990773;6474.708638111297;6500.772472963424;6503.512236582275;6522.086907832907;6525.086487891699;6131.202943617302;6161.876762464815;6179.721912513947;6182.938170618326;6185.041318595625;5486.561075113981;5654.6889921054735;5676.063449530864;5683.318001839806;5733.627314224562;6485.092087107809;6491.091247225298;6496.642684652201;6509.674516274073;6514.218293804774;6369.363063693779;6372.617642941438;6373.521985851173;6395.360463953567;6404.103425520524;6009.699515024318;6332.398422149889;6600.504962127764;6637.369449902251;6695.631740254138;6383.585066970818;6403.615783053032;6405.295417503978;6410.262819775786;6411.743530258737;6279.340669229665;6398;6425.228972523327;6440.2915679276175;6453.870572649861;6217.595856269328;6265.467435267871;6281.748730050746;6312.147860038836;6350.206224221973;6267.467265282502;6298.244210694746;6321.304656487875;6324.3991820776955;6345.333739957238;6275.37246275387;6286.119943207863;6287.96856015717;6296.307406185408;6297.905531417124;6202.534809889186;6305.653189074645;6327.3988971047675;6332.620042797447;6339.080833279057;5168.302279192411;6188.9690551546955;6208.689565521796;6210.441060304594;6273.090401891792 10:40:35 INFO - PID 1646 | |1;ext.html;748.659359991204;793.7182300283399;802.0295442514557;1120.2158489057656;1129.5764088466824;27505;38354.80822595886;38417;38463.42304865422;38479.230415391714;41265.17469650605;41600.37599436011;41603;41698;41737.373939390855;5989.970050149765;8383.413161078712;8505.404621676485;8511.829763404729;8519.3610479214;5124.641275110744;7432.7026918923175;8373.41386102973;8376.790580235482;8394.076651568323;8454.196851299117;8482.491050536957;8500.10748871369;8506.234438900497;8585;8419.368547358941;8475;8475.110113438062;8487;8510.531920744357;8084.6766129354755;8104.473209241381;8193.590320484012;8266.421350505467;8344.916550834474;37336.25327493435;37382.43926341107;37430.81284593587;37469.62530374716;37567.436488452695;3599.8200089995657;3625.2205775758075;3626.1659818241687;3630.7458477906557;3635.0730563706084;30631.92788252423;30649.233769155726;30715;30727.078187654406;30741.846290768404;3061.2499937515227;3118.766092543068;3136.466800643896;3144.512600546896;3162.3675264946874;301151;301238;301390.4930475334;301513;301794;49799.779032040155;49877.0024599506;49903.25145122826;49914.001719965396;49918.75040624774;52287.47712522826;52361.42915712535;52371.47628523742;52387;52526.47473525292;13825.377857996415;13909.930450347683;13912.860871391156;13917.373718182705;13919.23444210578;9240.907590924138;11255.43722813856;11271.211015228855;11272.887271127347;11293.491792869341;9789.021097890154;10723.302985306087;11339.886601134049;12248.081393895494;12696;4636.374089497942;5122.001209764135;5290.5238528531845;5369.624126311121;5370.785168593291;889.2619126125301;976.3360914578155;1013.7313611892854;1033.2405681823852;1036.7144740521699;19980.50048748793;24048.15831445891;24542.263732088068;24565.017399304186;24591.13931012406;968.3028219681818;1102.9669109926715;1126.132877684195;1149.1496292743477;1162.3781277016756;12144.632342363375;12424.012158054667;12433.440495177738;12482.313472759086;12495.562655307021;245260;308685;309279;309358;310456;54098.72950635222;54202;54221.72889135608;54275.72862135664;54323.18515222196;1499.535144105309;1517.1655589425773;1541.992290038565;1549.5196489088419;1550.6278493161785;24075.398115046562;24115.63826542568;24118.51762964766;24159.879200603886;24329.905154267748;38787;38797.41803873001;38845.02887427724;38850.80574597109;38884.61115388923;268292;269039;269357;270034.9494757541;271359;33945.32109357848;35061.649383505835;35173.94478165659;35206.47190292199;35275.82362088173;36682.44976325304;36841;36883;42863.9284017902;44212.11575768531;27672;30208.5468717965;30272.84863575668;30618.08145755631;30976.84511577428;24658.383540411633;27347;29511.557326640548;30488;30860.537091943188;35852.82073589615;36527.81736091409;36881;38189.80905095568;38425.42361864518;24672.50654986926;29227.97702080417;30446.391072178874;31169.84415078001;31759.84120079385;20469;23108.537829243658;24009.79951002478;24215.51568968646;24596.754032459445 10:40:35 INFO - PID 1646 | |2;jquery.html;391.9196564704236;435.6449493662665;437.20407990898207;443.6140557714788;451.17660270007246;18855.811441885577;19576.608467830654;21151.365459036217;21431.357059288246;21433.785662143375;21529.892350538266;22145.44636384091;22165.66751498729;22300.8884955575;22461.887690561565;5735.4551317624755;6017.518598512109;6022.819315420523;6024.518038556905;6030.33666296707;5643.153526970967;5813.709314534278;5877.9118313225335;5887.528997680175;5895.557833162508;5844.970775146118;5846.941530584703;5857.062869940811;5864.44287792659;5867.4719275265215;5506.944930550683;5815.767369305223;5833.504152147073;5857.414258574151;5866.911996320037;5122.76947537358;5224.555912747383;5225.085610018232;5231.869203269911;5236.476352364771;25984.350391240183;26342.20973370802;26347.473050538883;26722.19833405001;26827.731722682718;2160.4706846822473;2199.3182113544694;2213.656883183093;2243.730752309717;2246.9438264043365;17792.644147116986;17924.731129033076;18036.54908627282;18182;18253.452396427994;2058.835293176542;2126.6916297136827;2141.421816109642;2159.5680863827297;2163.383435720812;163854;165291;165494.9999999988;167024;168947;11958.940205299092;12057.638270851889;12060.155789094855;12111.697207569881;12413.220909949734;16118.194090295437;16132;16142.273597687898;16157.59606009859;16160.353585856668;8610.225079742853;8830.161134692195;8865.955670221607;8871.512066836385;8979.326550508738;8429.114942931026;8434.704785332484;8443.662253509914;8605.483670979762;8620.784480387914;7522.398208143335;7650.923490765021;7665.655045522964;7685.271728536401;7701.537907725555;1754.298280687735;2084.1455003448514;2087.630539337832;2098.790120987889;2279.4271952353156;922.3036607361399;926.346925417579;932.1098351074708;950.8811398575178;969.413504829568;17741.870975483023;20546.69179962302;20982;21052.47368815792;21287.148514059263;962.5235508423375;968.1673760565908;996.835522138839;997.5959736306808;998.8701468809008;10232.386056836614;10502.05481506668;10604.363738175734;10791.514381852836;10809.621663241749;545866;547828;552331;557571;560368;123717.38141309236;127436;127521.99999999814;128307.35846320709;129309.35345323401;995.1690338567232;1113.8496302999154;1165.0097417195316;1165.7875809158381;1172.806486929664;16734.4142954996;17819.46541603754;17962.5509362267;17974.281028759226;18129.546761331072;54745.17882231849;55737;56731.149032763715;56754.9056367954;57610.71194644;75136;75247;75330.62334688292;75367.24632753822;76416.23583764091;8294.54380009081;8425.70510032146;8474.533900635524;8543.145685431407;8641.481511109354;13097.934510327768;13275.86724132785;13509.59471215865;13664.316784160554;13689.315534223448;11312.490937907649;11396.803335649969;11513.869934062226;11578.36319002463;11602.70993225176;10697.893021069689;10717.9464102679;10942.781144377228;11052.723681908017;11122.61070862516;11620.360880151673;11642.708932276422;11748.236364636272;11874.406279686154;11890.28658280506;8218.137095604876;8373.413861029669;8376.12050734664;8512.191341822518;8566.78583035429;11713.648590542298;11775.76448471043;11971.401429928643;12021.158518903592;12037.157398981988 10:40:35 INFO - PID 1646 | |3;mootools.html;1161.4657257661474;1365.7405093032323;1404.8524904884987;1415.129695237429;1422.8577142285774;9586.99999999999;9872.703818885428;10326.483675816207;12016.21894576854;12068.396580171;11612.477438515267;11693.298402095881;11720.238184518;11724.296542207474;11725.648230553075;2524.3058159006237;2558.5138823623547;2561.897524099043;2569.11365578875;2571.6014017827265;2142.892855357226;2226.5101677630896;2417.2506522977847;2574.9227523174336;2576.446064096219;2520.5085008423366;2544.8727563621837;2548.8598127103;2552.221572420411;2558.7952963762855;2506.7994560435122;2546.287039628888;2552.834065785718;2553.080890879282;2556.642070110186;2128.9461716450455;2399.808015358767;2470.629405589158;2493.13986674597;2546.2488565872927;11432.485538150802;11548.653540393801;11551.76896462066;11562.364069976147;11603.419829008515;6448.903266451007;6684.298148694365;6688.197416310074;6703.9329606703795;6713.093732346119;3972.82122304497;4144.792760362;4148.211839750468;4333.068390296102;4333.71830830995;1211.54567037361;1232.9260244385366;1252.473960936409;1280.475005247844;1280.903932205079;16367.59081022972;16377.918110409491;16395.934264272903;16402.589935251595;16412.83587164125;5601.607887447921;5896.528277737771;6028.608140470856;6034.24571928509;6041.969790151109;13682.657933551742;13689;13690.38393272305;13706.52027179044;13730.038897277094;6752.864942701119;6762.932370676329;6768.83077923046;6772.085768421299;6778.288279730656;6033.607815491979;6099.969500152471;6128.9693551531955;6201.875962480725;6212.875742485216;4111.301078816608;4232.470941132358;4239.851605193803;4621.95378046222;4681.227597446451;1446.9131852088908;1464.106894794196;1464.4727897956727;1480.6076389756722;1485.8365579786214;746.358132006474;754.3663322808867;756.9583672897934;764.3579393309647;767.1676231289114;5250.238715386247;5716.828495145152;5930.703464826742;6118.20463339763;6280.371962803683;803.7260941407817;841.869510225916;871.3884221326706;924.8289066522715;936.6674830435234;3872.6320999505;3907.1013666856784;4040.4545386372465;4067;4070.4911886014247;12180.451879665436;14233.359498822578;15029.398824046915;15214.391424342903;15230.46693365727;14615;15095.01882377664;15103.622409439851;15115.697686046438;15122.017068890487;8624.094470080676;8642.265407440313;8699.521526315986;8747.343949203836;8790.42862213954;6173.59871608362;6246.344133865879;6310.242770867525;6361.2048493938255;6458.1281526995135;13132.934335328262;13441.529546465827;13526.79709804334;13546.796798048234;13674.589762307147;13520.59438216855;13781.242031688353;13928.930355348159;14026.929865350608;14048.78926816119;9139.994600593796;9247.167754902091;9265.53672316368;9266.397684150508;9302.488363140092;10510.369377837354;10995.395253261147;11030.558777648805;11101.278416902875;11127.165462590176;9010;9113.772155695895;9117;9238.168564829197;9295.349325547148;8880.955595221982;8956.328275379503;9035;9060.592273347847;9074.183323500916;7971.123176450702;8194.34445244367;8249.092599814136;8266.83466330658;8297.377696672895;4666.160091183619;5551.722413879371;5568.638038527482;5575.721213939206;5739.9139012915675;4454.799534020912;5095.363079615048;5241.371035475768;5439.102548079522;5502.284702988586 10:40:35 INFO - PID 1646 | |4;prototype.html;376.992460150797;377.1401205252026;396.40935006839817;401.5622970961649;401.9115794525205;5215.374155101384;5382.919256211155;5685.772569097231;6001.119462657922;6024.969875150624;5690.743916523756;5772.297172334172;5798.768049278024;5799.18811366409;5807.361190269067;3544.101488362806;3970.5814755228616;4388.341748737683;4461.219286624844;4480.686351955364;4396.450443694538;4489.371487991683;4489.865304040884;4493.438320209973;4513.14250292445;4460.442444694413;4463.397441345409;4476.440444944382;4483.346072950535;4487.439070116236;3781.943270850953;4413.823447062114;4455.910881782346;4457.907812585905;4478;3961.6632586230203;3976.3836605325932;3978.8607398741196;3979.900502487432;3987.202559488085;4798.944232268896;4882.266795287693;5736.196932429463;5769.596128271022;5808;4403;4444.044530425942;4686.367340409035;4897.926531102036;4999.400071991348;4913.06651736169;5297.311349524575;5596.916046259269;6137.7724455109155;6156;3531.7351198660203;3793.412021136701;4057.6753859691153;4108.609682080198;4111.198316328318;6345.428911397997;6465.575409221542;6475.017864013645;6521.217453905561;6553.508486863504;3816.2939856126704;3858.9614103858603;4142.627163555295;4147.453926892464;4154.127633197002;4225.239456897788;4227.408162857202;4244.3209086546;4248.320268756984;4254.829806807693;4673.672842901033;4860.562549370574;4894.657373983858;4930.137225985438;4956;5021.472745361758;5041.949580504148;6165.938340616536;6173;6200.255969283715;1238.2879844089684;1439.784032395149;1445.1401416157369;1446.168453139428;1447.4006223123508;939.8402271613842;940.6425558287807;949.9170945122565;953.6519170502717;961.1541843177959;5122.385313762373;5777.711114444261;5833.154192642043;5874.500667443315;5888.911666325009;5040.445550989388;5267.36631684158;5540.722963851791;6397.296297407282;6410.775622853178;5750.827475175752;5792.247007888947;5860.091685788624;5866.208061911588;5905.763769449174;2788.442311537701;2897.9855100724785;3306.487494438366;3327.983360083184;3336.866525338959;6254.6247225166635;6278.277998030194;6454.964089142857;6457.902156633384;6520;5794.580327819711;6245.251329627711;6390.9360906391275;6508.381703738138;6547.443467305237;5031.094403007493;5565.332160140728;5681.602287839812;6361.363863613694;6388.616682999035;5980.205938218604;6209.205857364114;6296.401841825019;6311.382869246283;6333.050042493663;5295.708736019402;6064.329851588778;6229.657368844576;6236.469900058546;6267.749290028531;6236.407541283571;6469.77355792545;6532.987386955029;6639.804835129723;6645.435138013323;3472.461768425898;4157.334826427757;4159.771212583337;4179.623833854967;4187.560306167808;6347.809565713036;6464.612123272619;6510.316416776171;6534.411902928759;6541.6075035498025;6244.313125556276;6247.219097612799;6261.405166509175;6271.216097987752;6291.245050593775;5939.138824870283;6254.7482906154655;6257;6282.403171698681;6298.338674439118;4965.900681986413;5785.537157027344;6151.907721384272;6155.907661385172;6174.536909731698;6241.875162496815;6250.343713909974;6260.843478913063;6274.592151510137;6275.717592708431;5925.808638340472;5942.405759424196;6058.153881504193;6126.264848218242;6131.601445906002 10:40:35 INFO - PID 1646 | |5;yui.html;828.1056459024254;846.6528723223478;848.359488586117;850;867.5488745852155;16351.410043282285;16534;16890.408835690752;16894.070826104573;16908;15521.379144834193;15624.2969066392;15749.055056696607;15784.605384865356;15804.525864224091;958.319593088906;961.480800367804;965.3194497879014;966.130482565693;968.3173362779223;961.620160036785;962.1629182611103;963.6338191487225;964.0937518732404;992.5186284651938;1044.1542350695922;1045.8849526552074;1046.304207701881;1047.9214058945608;1052.3790963331633;1042.5412818359898;1042.6194439029775;1045.4354648489807;1046.4244665434019;1053.2574534952878;775.255754475704;777.8172129549552;779.3103103753219;781.2656103262915;781.3749000799395;15721.921390393089;15845.68308633821;15848.92075539615;16822.579435514086;17083.743743843854;13964.650883727887;16793.244304006348;17016.234269457906;17252.39616613425;17391.217395217245;14211.21838298893;14346.92826535871;14436.639084022878;14514.346854391471;14582.416703331854;3541.6812486876047;3569.37535931211;3570.0539357070397;3571.5535558055244;3581.158427769472;115775;118346;118747;123108;130041;12872.22766634005;13059.542915998085;13408.932955335162;13856.722865542633;13861.584152475441;24850.5029899401;24915.377115571893;25297;25657.97368105255;25983.61024584633;7714;7788.844223115506;7927.563983980936;7941.761747147595;7971.681132754625;3847.5382954045695;3958.5051868516434;3967.26605577969;3967.940480892789;3969.186316805106;4054.716169868081;4084.7753373564738;4150.190712811033;4152.712659075696;4169.854055108057;911.3256190419174;915.6566287642135;926.7590426489027;933.3746389918771;933.407286373158;635.9332270111664;641.3618449642636;651.0364660302779;651.91199188109;674.8818956682561;10923.453827308762;11340.376279304717;11810.822837657443;12038.4582693779;12172.695682608006;594.5124997502007;600.1867469578746;600.6366148480193;606.9302030266488;621.155228888711;4724.2205036169335;5138.640295179302;5330.987112448623;5372.811951581754;5572.860678482989;95097.40697970898;101921.98078019125;102379;102449;102875;90915.54542227379;91866.16267674608;92675.53662231646;93212.53393732988;94926;47696.76151619289;78587;80674.19325806784;80788;80979.5951020253;11890.227135236182;12517.436715348013;12580.559680411141;12598.811017834556;12665.81001284963;38053.61946380501;38234.85295441141;38317;38486.42270366003;38732;48233.75883120562;48445.75777121092;48838;48839.511604884916;49119.26321105258;15585.922070389575;15993.360265589246;16048.679026419173;16258.593535161715;16309.59226019359;17663.470095897144;17743.467695969142;17909;18128.546786330448;18237.270509179485;12494.750104998031;15281.46514871974;16169.272382742569;16636.58408539796;16781.66436671284;15869.206539673201;15989.920050399674;16654.417095401604;16686.49940501787;16742.246598902835;14888.62778430548;15348.693026139637;15515.612109697347;15860.52418427449;16111.516654500383;11088.889111108785;11324.433778310886;11367.602133925273;11512.30926144434;11536.365499897585;11637.476313565741;12553.372331383212;12553.62339129791;12589.118761686594;12659.177153484994 10:40:35 INFO - PID 1646 | __end_tp_report 10:40:35 INFO - PID 1646 | __start_cc_report 10:40:35 INFO - PID 1646 | _x_x_mozilla_cycle_collect,1879 10:40:35 INFO - PID 1646 | __end_cc_report 10:40:35 INFO - PID 1646 | __startTimestamp1494610835730__endTimestamp 10:40:35 INFO - PID 1646 | 10:40:35 INFO - PID 1646 | ------- Summary: start ------- 10:40:35 INFO - PID 1646 | Number of tests: 6 10:40:35 INFO - PID 1646 | 10:40:35 INFO - PID 1646 | [#0] dojo.html Cycles:165 Average:5968.03 Median:6210.44 stddev:678.10 (10.9%) stddev-sans-first:648.34 10:40:35 INFO - PID 1646 | Values: 3350.1 3390.0 3432.1 3440.4 3453.9 5942.7 6083.8 6122.9 6138.8 6152.8 6086.2 6090.9 6107.6 6141.1 6147.5 5144.3 5202.5 5288.3 5321.0 5359.2 5415.0 5424.8 5437.8 5437.9 5493.7 5212.4 5289.1 5313.7 5327.9 5349.3 5309.3 5360.3 5384.1 5392.2 5406.9 5332.8 5344.3 5345.2 5354.8 5386.8 6084.6 6100.5 6130.3 6136.2 6149.7 5252.6 6232.8 6320.8 6458.7 6462.6 6009.5 6427.0 6446.6 6533.4 6542.4 6133.4 6180.4 6188.6 6200.0 6209.7 6462.2 6690.1 6691.8 6702.4 6710.9 6261.0 6265.9 6340.9 6358.0 6373.9 6509.0 6525.6 6527.6 6532.2 6533.5 6306.3 6430.4 6439.5 6443.5 6453.3 6230.2 6257.0 6369.7 6438.0 6445.0 5142.0 5151.0 5156.6 5162.4 5193.6 4512.5 4600.0 4601.2 4627.4 4636.4 6022.3 6029.3 6097.4 6134.9 6161.4 6474.7 6500.8 6503.5 6522.1 6525.1 6131.2 6161.9 6179.7 6182.9 6185.0 5486.6 5654.7 5676.1 5683.3 5733.6 6485.1 6491.1 6496.6 6509.7 6514.2 6369.4 6372.6 6373.5 6395.4 6404.1 6009.7 6332.4 6600.5 6637.4 6695.6 6383.6 6403.6 6405.3 6410.3 6411.7 6279.3 6398.0 6425.2 6440.3 6453.9 6217.6 6265.5 6281.7 6312.1 6350.2 6267.5 6298.2 6321.3 6324.4 6345.3 6275.4 6286.1 6288.0 6296.3 6297.9 6202.5 6305.7 6327.4 6332.6 6339.1 5168.3 6189.0 6208.7 6210.4 6273.1 10:40:35 INFO - PID 1646 | 10:40:35 INFO - PID 1646 | [#1] ext.html Cycles:180 Average:44032.17 Median:35190.21 stddev:76023.69 (216.0%) stddev-sans-first:76167.50 10:40:35 INFO - PID 1646 | Values: 748.7 793.7 802.0 1120.2 1129.6 27505.0 38354.8 38417.0 38463.4 38479.2 41265.2 41600.4 41603.0 41698.0 41737.4 5990.0 8383.4 8505.4 8511.8 8519.4 5124.6 7432.7 8373.4 8376.8 8394.1 8454.2 8482.5 8500.1 8506.2 8585.0 8419.4 8475.0 8475.1 8487.0 8510.5 8084.7 8104.5 8193.6 8266.4 8344.9 37336.3 37382.4 37430.8 37469.6 37567.4 3599.8 3625.2 3626.2 3630.7 3635.1 30631.9 30649.2 30715.0 30727.1 30741.8 3061.2 3118.8 3136.5 3144.5 3162.4 301151.0 301238.0 301390.5 301513.0 301794.0 49799.8 49877.0 49903.3 49914.0 49918.8 52287.5 52361.4 52371.5 52387.0 52526.5 13825.4 13909.9 13912.9 13917.4 13919.2 9240.9 11255.4 11271.2 11272.9 11293.5 9789.0 10723.3 11339.9 12248.1 12696.0 4636.4 5122.0 5290.5 5369.6 5370.8 889.3 976.3 1013.7 1033.2 1036.7 19980.5 24048.2 24542.3 24565.0 24591.1 968.3 1103.0 1126.1 1149.1 1162.4 12144.6 12424.0 12433.4 12482.3 12495.6 245260.0 308685.0 309279.0 309358.0 310456.0 54098.7 54202.0 54221.7 54275.7 54323.2 1499.5 1517.2 1542.0 1549.5 1550.6 24075.4 24115.6 24118.5 24159.9 24329.9 38787.0 38797.4 38845.0 38850.8 38884.6 268292.0 269039.0 269357.0 270034.9 271359.0 33945.3 35061.6 35173.9 35206.5 35275.8 36682.4 36841.0 36883.0 42863.9 44212.1 27672.0 30208.5 30272.8 30618.1 30976.8 24658.4 27347.0 29511.6 30488.0 30860.5 35852.8 36527.8 36881.0 38189.8 38425.4 24672.5 29228.0 30446.4 31169.8 31759.8 20469.0 23108.5 24009.8 24215.5 24596.8 10:40:35 INFO - PID 1646 | 10:40:35 INFO - PID 1646 | [#2] jquery.html Cycles:180 Average:35607.06 Median:2245.34 stddev:94109.54 (4191.3%) stddev-sans-first:94336.40 10:40:35 INFO - PID 1646 | Values: 391.9 435.6 437.2 443.6 451.2 18855.8 19576.6 21151.4 21431.4 21433.8 21529.9 22145.4 22165.7 22300.9 22461.9 5735.5 6017.5 6022.8 6024.5 6030.3 5643.2 5813.7 5877.9 5887.5 5895.6 5845.0 5846.9 5857.1 5864.4 5867.5 5506.9 5815.8 5833.5 5857.4 5866.9 5122.8 5224.6 5225.1 5231.9 5236.5 25984.4 26342.2 26347.5 26722.2 26827.7 2160.5 2199.3 2213.7 2243.7 2246.9 17792.6 17924.7 18036.5 18182.0 18253.5 2058.8 2126.7 2141.4 2159.6 2163.4 163854.0 165291.0 165495.0 167024.0 168947.0 11958.9 12057.6 12060.2 12111.7 12413.2 16118.2 16132.0 16142.3 16157.6 16160.4 8610.2 8830.2 8866.0 8871.5 8979.3 8429.1 8434.7 8443.7 8605.5 8620.8 7522.4 7650.9 7665.7 7685.3 7701.5 1754.3 2084.1 2087.6 2098.8 2279.4 922.3 926.3 932.1 950.9 969.4 17741.9 20546.7 20982.0 21052.5 21287.1 962.5 968.2 996.8 997.6 998.9 10232.4 10502.1 10604.4 10791.5 10809.6 545866.0 547828.0 552331.0 557571.0 560368.0 123717.4 127436.0 127522.0 128307.4 129309.4 995.2 1113.8 1165.0 1165.8 1172.8 16734.4 17819.5 17962.6 17974.3 18129.5 54745.2 55737.0 56731.1 56754.9 57610.7 75136.0 75247.0 75330.6 75367.2 76416.2 8294.5 8425.7 8474.5 8543.1 8641.5 13097.9 13275.9 13509.6 13664.3 13689.3 11312.5 11396.8 11513.9 11578.4 11602.7 10697.9 10717.9 10942.8 11052.7 11122.6 11620.4 11642.7 11748.2 11874.4 11890.3 8218.1 8373.4 8376.1 8512.2 8566.8 11713.6 11775.8 11971.4 12021.2 12037.2 10:40:35 INFO - PID 1646 | 10:40:35 INFO - PID 1646 | [#3] mootools.html Cycles:180 Average:7082.62 Median:4006.64 stddev:4551.27 (113.6%) stddev-sans-first:4542.29 10:40:35 INFO - PID 1646 | Values: 1161.5 1365.7 1404.9 1415.1 1422.9 9587.0 9872.7 10326.5 12016.2 12068.4 11612.5 11693.3 11720.2 11724.3 11725.6 2524.3 2558.5 2561.9 2569.1 2571.6 2142.9 2226.5 2417.3 2574.9 2576.4 2520.5 2544.9 2548.9 2552.2 2558.8 2506.8 2546.3 2552.8 2553.1 2556.6 2128.9 2399.8 2470.6 2493.1 2546.2 11432.5 11548.7 11551.8 11562.4 11603.4 6448.9 6684.3 6688.2 6703.9 6713.1 3972.8 4144.8 4148.2 4333.1 4333.7 1211.5 1232.9 1252.5 1280.5 1280.9 16367.6 16377.9 16395.9 16402.6 16412.8 5601.6 5896.5 6028.6 6034.2 6042.0 13682.7 13689.0 13690.4 13706.5 13730.0 6752.9 6762.9 6768.8 6772.1 6778.3 6033.6 6100.0 6129.0 6201.9 6212.9 4111.3 4232.5 4239.9 4622.0 4681.2 1446.9 1464.1 1464.5 1480.6 1485.8 746.4 754.4 757.0 764.4 767.2 5250.2 5716.8 5930.7 6118.2 6280.4 803.7 841.9 871.4 924.8 936.7 3872.6 3907.1 4040.5 4067.0 4070.5 12180.5 14233.4 15029.4 15214.4 15230.5 14615.0 15095.0 15103.6 15115.7 15122.0 8624.1 8642.3 8699.5 8747.3 8790.4 6173.6 6246.3 6310.2 6361.2 6458.1 13132.9 13441.5 13526.8 13546.8 13674.6 13520.6 13781.2 13928.9 14026.9 14048.8 9140.0 9247.2 9265.5 9266.4 9302.5 10510.4 10995.4 11030.6 11101.3 11127.2 9010.0 9113.8 9117.0 9238.2 9295.3 8881.0 8956.3 9035.0 9060.6 9074.2 7971.1 8194.3 8249.1 8266.8 8297.4 4666.2 5551.7 5568.6 5575.7 5739.9 4454.8 5095.4 5241.4 5439.1 5502.3 10:40:35 INFO - PID 1646 | 10:40:35 INFO - PID 1646 | [#4] prototype.html Cycles:180 Average:4980.73 Median:5743.51 stddev:1557.17 (27.1%) stddev-sans-first:1522.72 10:40:35 INFO - PID 1646 | Values: 377.0 377.1 396.4 401.6 401.9 5215.4 5382.9 5685.8 6001.1 6025.0 5690.7 5772.3 5798.8 5799.2 5807.4 3544.1 3970.6 4388.3 4461.2 4480.7 4396.5 4489.4 4489.9 4493.4 4513.1 4460.4 4463.4 4476.4 4483.3 4487.4 3781.9 4413.8 4455.9 4457.9 4478.0 3961.7 3976.4 3978.9 3979.9 3987.2 4798.9 4882.3 5736.2 5769.6 5808.0 4403.0 4444.0 4686.4 4897.9 4999.4 4913.1 5297.3 5596.9 6137.8 6156.0 3531.7 3793.4 4057.7 4108.6 4111.2 6345.4 6465.6 6475.0 6521.2 6553.5 3816.3 3859.0 4142.6 4147.5 4154.1 4225.2 4227.4 4244.3 4248.3 4254.8 4673.7 4860.6 4894.7 4930.1 4956.0 5021.5 5041.9 6165.9 6173.0 6200.3 1238.3 1439.8 1445.1 1446.2 1447.4 939.8 940.6 949.9 953.7 961.2 5122.4 5777.7 5833.2 5874.5 5888.9 5040.4 5267.4 5540.7 6397.3 6410.8 5750.8 5792.2 5860.1 5866.2 5905.8 2788.4 2898.0 3306.5 3328.0 3336.9 6254.6 6278.3 6455.0 6457.9 6520.0 5794.6 6245.3 6390.9 6508.4 6547.4 5031.1 5565.3 5681.6 6361.4 6388.6 5980.2 6209.2 6296.4 6311.4 6333.1 5295.7 6064.3 6229.7 6236.5 6267.7 6236.4 6469.8 6533.0 6639.8 6645.4 3472.5 4157.3 4159.8 4179.6 4187.6 6347.8 6464.6 6510.3 6534.4 6541.6 6244.3 6247.2 6261.4 6271.2 6291.2 5939.1 6254.7 6257.0 6282.4 6298.3 4965.9 5785.5 6151.9 6155.9 6174.5 6241.9 6250.3 6260.8 6274.6 6275.7 5925.8 5942.4 6058.2 6126.3 6131.6 10:40:35 INFO - PID 1646 | 10:40:35 INFO - PID 1646 | [#5] yui.html Cycles:180 Average:20994.96 Median:17826.23 stddev:29539.20 (165.7%) stddev-sans-first:29583.25 10:40:35 INFO - PID 1646 | Values: 828.1 846.7 848.4 850.0 867.5 16351.4 16534.0 16890.4 16894.1 16908.0 15521.4 15624.3 15749.1 15784.6 15804.5 958.3 961.5 965.3 966.1 968.3 961.6 962.2 963.6 964.1 992.5 1044.2 1045.9 1046.3 1047.9 1052.4 1042.5 1042.6 1045.4 1046.4 1053.3 775.3 777.8 779.3 781.3 781.4 15721.9 15845.7 15848.9 16822.6 17083.7 13964.7 16793.2 17016.2 17252.4 17391.2 14211.2 14346.9 14436.6 14514.3 14582.4 3541.7 3569.4 3570.1 3571.6 3581.2 115775.0 118346.0 118747.0 123108.0 130041.0 12872.2 13059.5 13408.9 13856.7 13861.6 24850.5 24915.4 25297.0 25658.0 25983.6 7714.0 7788.8 7927.6 7941.8 7971.7 3847.5 3958.5 3967.3 3967.9 3969.2 4054.7 4084.8 4150.2 4152.7 4169.9 911.3 915.7 926.8 933.4 933.4 635.9 641.4 651.0 651.9 674.9 10923.5 11340.4 11810.8 12038.5 12172.7 594.5 600.2 600.6 606.9 621.2 4724.2 5138.6 5331.0 5372.8 5572.9 95097.4 101922.0 102379.0 102449.0 102875.0 90915.5 91866.2 92675.5 93212.5 94926.0 47696.8 78587.0 80674.2 80788.0 80979.6 11890.2 12517.4 12580.6 12598.8 12665.8 38053.6 38234.9 38317.0 38486.4 38732.0 48233.8 48445.8 48838.0 48839.5 49119.3 15585.9 15993.4 16048.7 16258.6 16309.6 17663.5 17743.5 17909.0 18128.5 18237.3 12494.8 15281.5 16169.3 16636.6 16781.7 15869.2 15989.9 16654.4 16686.5 16742.2 14888.6 15348.7 15515.6 15860.5 16111.5 11088.9 11324.4 11367.6 11512.3 11536.4 11637.5 12553.4 12553.6 12589.1 12659.2 10:40:35 INFO - PID 1646 | -------- Summary: end -------- 10:40:35 INFO - PID 1646 | 10:40:36 INFO - TEST-INFO | 1646: exit 0 10:40:36 INFO - TEST-OK | dromaeo_css | took 1117617ms 10:40:36 INFO - TEST-START | kraken 10:40:36 INFO - operating with platform_type : mac_ 10:40:36 INFO - Initialising browser for kraken test... 10:40:36 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/tmp0lO0OU/profile http://localhost:49226/getInfo.html) 10:40:38 INFO - PID 1658 | __metrics Screen width/height:1600/1200 10:40:38 INFO - PID 1658 | colorDepth:24 10:40:38 INFO - PID 1658 | Browser inner width/height: 1024/691 10:40:38 INFO - PID 1658 | __metrics 10:40:38 INFO - PID 1658 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:40:38 INFO - PID 1658 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:40:39 INFO - TEST-INFO | 1658: exit 0 10:40:39 INFO - Browser initialized. 10:40:39 INFO - Running cycle 1/1 for kraken test... 10:40:39 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/tmp0lO0OU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:40:51 INFO - PID 1659 | RSS: Main: 251629568 10:40:51 INFO - PID 1659 | 10:40:54 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection) 10:40:55 INFO - PID 1659 | RSS: Main: 321052672 10:40:55 INFO - PID 1659 | 10:40:57 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-dft) 10:40:58 INFO - PID 1659 | RSS: Main: 303185920 10:40:58 INFO - PID 1659 | 10:41:01 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-fft) 10:41:02 INFO - PID 1659 | RSS: Main: 392683520 10:41:02 INFO - PID 1659 | 10:41:03 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator) 10:41:04 INFO - PID 1659 | RSS: Main: 298004480 10:41:04 INFO - PID 1659 | 10:41:06 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:41:07 INFO - PID 1659 | RSS: Main: 300953600 10:41:07 INFO - PID 1659 | 10:41:09 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom) 10:41:10 INFO - PID 1659 | RSS: Main: 365010944 10:41:10 INFO - PID 1659 | 10:41:13 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate) 10:41:14 INFO - PID 1659 | RSS: Main: 386252800 10:41:14 INFO - PID 1659 | 10:41:16 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial) 10:41:17 INFO - PID 1659 | RSS: Main: 401473536 10:41:17 INFO - PID 1659 | 10:41:18 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:41:19 INFO - PID 1659 | RSS: Main: 337199104 10:41:19 INFO - PID 1659 | 10:41:21 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:41:21 INFO - PID 1659 | RSS: Main: 339431424 10:41:21 INFO - PID 1659 | 10:41:24 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:41:24 INFO - PID 1659 | RSS: Main: 341901312 10:41:24 INFO - PID 1659 | 10:41:27 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:41:28 INFO - PID 1659 | RSS: Main: 359653376 10:41:28 INFO - PID 1659 | 10:41:31 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:41:32 INFO - PID 1659 | RSS: Main: 352702464 10:41:32 INFO - PID 1659 | 10:41:33 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49226/tests/kraken/driver.html?testName=ai-astar) 10:41:34 INFO - PID 1659 | RSS: Main: 395661312 10:41:34 INFO - PID 1659 | 10:41:34 INFO - PID 1659 | __start_tp_report 10:41:34 INFO - PID 1659 | _x_x_mozilla_page_load 10:41:34 INFO - PID 1659 | _x_x_mozilla_page_load_details 10:41:34 INFO - PID 1659 | |i|pagename|runs| 10:41:34 INFO - PID 1659 | |0;ai-astar;111;111;110;109;109;110;110;110;110;110 10:41:34 INFO - PID 1659 | |1;audio-beat-detection;139;112;108;148;109;108;107;107;133;109 10:41:34 INFO - PID 1659 | |2;audio-dft;183;185;183;184;187;186;185;184;184;183 10:41:34 INFO - PID 1659 | |3;audio-fft;83;59;57;88;64;58;60;58;81;57 10:41:34 INFO - PID 1659 | |4;audio-oscillator;87;69;84;69;67;69;88;68;67;89 10:41:34 INFO - PID 1659 | |5;imaging-gaussian-blur;89;87;89;88;87;88;89;88;89;88 10:41:34 INFO - PID 1659 | |6;imaging-darkroom;95;95;94;95;96;95;96;96;96;95 10:41:34 INFO - PID 1659 | |7;imaging-desaturate;82;83;86;86;85;91;84;83;85;82 10:41:34 INFO - PID 1659 | |8;json-parse-financial;47;50;47;46;48;48;48;47;49;48 10:41:34 INFO - PID 1659 | |9;json-stringify-tinderbox;54;53;54;64;59;55;57;58;56;57 10:41:34 INFO - PID 1659 | |10;stanford-crypto-aes;81;82;82;83;82;83;81;82;82;81 10:41:34 INFO - PID 1659 | |11;stanford-crypto-ccm;151;145;128;145;146;147;136;134;175;156 10:41:34 INFO - PID 1659 | |12;stanford-crypto-pbkdf2;183;186;185;216;184;184;183;183;185;184 10:41:34 INFO - PID 1659 | |13;stanford-crypto-sha256-iterative;60;82;83;91;91;91;92;67;67;67 10:41:34 INFO - PID 1659 | __end_tp_report 10:41:34 INFO - PID 1659 | __start_cc_report 10:41:34 INFO - PID 1659 | _x_x_mozilla_cycle_collect,2155 10:41:34 INFO - PID 1659 | __end_cc_report 10:41:34 INFO - PID 1659 | __startTimestamp1494610894187__endTimestamp 10:41:34 INFO - PID 1659 | 10:41:34 INFO - PID 1659 | ------- Summary: start ------- 10:41:34 INFO - PID 1659 | Number of tests: 14 10:41:34 INFO - PID 1659 | 10:41:34 INFO - PID 1659 | [#0] ai-astar Cycles:10 Average:110.00 Median:110.00 stddev:0.67 (0.6%) stddev-sans-first:0.60 10:41:34 INFO - PID 1659 | Values: 111.0 111.0 110.0 109.0 109.0 110.0 110.0 110.0 110.0 110.0 10:41:34 INFO - PID 1659 | 10:41:34 INFO - PID 1659 | [#1] audio-beat-detection Cycles:10 Average:118.00 Median:110.50 stddev:15.66 (14.2%) stddev-sans-first:14.65 10:41:34 INFO - PID 1659 | Values: 139.0 112.0 108.0 148.0 109.0 108.0 107.0 107.0 133.0 109.0 10:41:34 INFO - PID 1659 | 10:41:34 INFO - PID 1659 | [#2] audio-dft Cycles:10 Average:184.40 Median:184.50 stddev:1.35 (0.7%) stddev-sans-first:1.33 10:41:34 INFO - PID 1659 | Values: 183.0 185.0 183.0 184.0 187.0 186.0 185.0 184.0 184.0 183.0 10:41:34 INFO - PID 1659 | 10:41:34 INFO - PID 1659 | [#3] audio-fft Cycles:10 Average:66.50 Median:62.00 stddev:12.36 (19.9%) stddev-sans-first:11.58 10:41:34 INFO - PID 1659 | Values: 83.0 59.0 57.0 88.0 64.0 58.0 60.0 58.0 81.0 57.0 10:41:34 INFO - PID 1659 | 10:41:34 INFO - PID 1659 | [#4] audio-oscillator Cycles:10 Average:75.70 Median:76.50 stddev:9.83 (12.9%) stddev-sans-first:9.54 10:41:34 INFO - PID 1659 | Values: 87.0 69.0 84.0 69.0 67.0 69.0 88.0 68.0 67.0 89.0 10:41:34 INFO - PID 1659 | 10:41:34 INFO - PID 1659 | [#5] imaging-gaussian-blur Cycles:10 Average:88.20 Median:88.50 stddev:0.79 (0.9%) stddev-sans-first:0.78 10:41:34 INFO - PID 1659 | Values: 89.0 87.0 89.0 88.0 87.0 88.0 89.0 88.0 89.0 88.0 10:41:34 INFO - PID 1659 | 10:41:34 INFO - PID 1659 | [#6] imaging-darkroom Cycles:10 Average:95.30 Median:95.50 stddev:0.67 (0.7%) stddev-sans-first:0.71 10:41:34 INFO - PID 1659 | Values: 95.0 95.0 94.0 95.0 96.0 95.0 96.0 96.0 96.0 95.0 10:41:34 INFO - PID 1659 | 10:41:34 INFO - PID 1659 | [#7] imaging-desaturate Cycles:10 Average:84.70 Median:85.00 stddev:2.67 (3.1%) stddev-sans-first:2.65 10:41:34 INFO - PID 1659 | Values: 82.0 83.0 86.0 86.0 85.0 91.0 84.0 83.0 85.0 82.0 10:41:34 INFO - PID 1659 | 10:41:34 INFO - PID 1659 | [#8] json-parse-financial Cycles:10 Average:47.80 Median:48.00 stddev:1.14 (2.4%) stddev-sans-first:1.17 10:41:34 INFO - PID 1659 | Values: 47.0 50.0 47.0 46.0 48.0 48.0 48.0 47.0 49.0 48.0 10:41:34 INFO - PID 1659 | 10:41:34 INFO - PID 1659 | [#9] json-stringify-tinderbox Cycles:10 Average:56.70 Median:57.00 stddev:3.20 (5.6%) stddev-sans-first:3.24 10:41:34 INFO - PID 1659 | Values: 54.0 53.0 54.0 64.0 59.0 55.0 57.0 58.0 56.0 57.0 10:41:34 INFO - PID 1659 | 10:41:34 INFO - PID 1659 | [#10] stanford-crypto-aes Cycles:10 Average:81.90 Median:82.00 stddev:0.74 (0.9%) stddev-sans-first:0.71 10:41:34 INFO - PID 1659 | Values: 81.0 82.0 82.0 83.0 82.0 83.0 81.0 82.0 82.0 81.0 10:41:34 INFO - PID 1659 | 10:41:34 INFO - PID 1659 | [#11] stanford-crypto-ccm Cycles:10 Average:146.30 Median:146.50 stddev:13.06 (8.9%) stddev-sans-first:13.75 10:41:34 INFO - PID 1659 | Values: 151.0 145.0 128.0 145.0 146.0 147.0 136.0 134.0 175.0 156.0 10:41:34 INFO - PID 1659 | 10:41:34 INFO - PID 1659 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.30 Median:184.50 stddev:10.13 (5.5%) stddev-sans-first:10.63 10:41:34 INFO - PID 1659 | Values: 183.0 186.0 185.0 216.0 184.0 184.0 183.0 183.0 185.0 184.0 10:41:34 INFO - PID 1659 | 10:41:34 INFO - PID 1659 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:79.10 Median:87.00 stddev:12.56 (14.4%) stddev-sans-first:11.26 10:41:34 INFO - PID 1659 | Values: 60.0 82.0 83.0 91.0 91.0 91.0 92.0 67.0 67.0 67.0 10:41:34 INFO - PID 1659 | -------- Summary: end -------- 10:41:34 INFO - PID 1659 | 10:41:35 INFO - TEST-INFO | 1659: exit 0 10:41:35 INFO - TEST-OK | kraken | took 58764ms 10:41:35 INFO - SUITE-END | took 1176s 10:41:35 INFO - Completed test suite (00:19:36) 10:41:35 INFO - javascript benchmark 10:41:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7669.789684960458, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3350.0954742219587, 3389.966100338996, 3432.1419645088727, 3440.415129427997, 3453.9309213815723, 5942.673152976596, 6083.756649734005, 6122.9387706122925, 6138.815835524931, 6152.753889844406, 6086.178365920599, 6090.939090609093, 6107.613648220932, 6141.148492690769, 6147.538934579913, 5144.331236939192, 5202.505761952609, 5288.2596436498925, 5320.973395133019, 5359.222912677659, 5414.972925135369, 5424.755885985121, 5437.755301011464, 5437.945620543784, 5493.725313734317, 5212.40057393397, 5289.127293996525, 5313.681179129265, 5327.866803329909, 5349.251104845324, 5309.283246761676, 5360.276362691065, 5384.111621582434, 5392.164214546712, 5406.945930540684, 5332.813351532716, 5344.251804747339, 5345.224942383372, 5354.785808567653, 5386.757595908193, 6084.6045007074845, 6100.542459315525, 6130.325664176937, 6136.23297087864, 6149.661768602725, 5252.553532949704, 6232.781852635136, 6320.778772742932, 6458.6770661466735, 6462.612243265372, 6009.489193418521, 6427.0, 6446.613203207822, 6533.379328963741, 6542.378474044958, 6133.44798968095, 6180.351063138351, 6188.597741146811, 6200.038993955944, 6209.658468784261, 6462.192225971754, 6690.0968369270495, 6691.832704182434, 6702.36327548882, 6710.899336509956, 6261.0, 6265.906011409833, 6340.936590634127, 6358.0, 6373.872522549523, 6508.967455162694, 6525.575837570543, 6527.608343499405, 6532.216134063848, 6533.509986751013, 6306.337834527309, 6430.421262086435, 6439.452646525004, 6443.548951573345, 6453.322401147811, 6230.158928544679, 6257.0, 6369.681515924185, 6438.0, 6445.033245013192, 5141.974290128525, 5150.974245128826, 5156.587473002151, 5162.380514338303, 5193.636445448782, 4512.503624601291, 4599.954000460019, 4601.217792975202, 4627.375304333872, 4636.44362676481, 6022.277326720734, 6029.306629737549, 6097.420745029215, 6134.926457743815, 6161.44546990773, 6474.708638111297, 6500.772472963424, 6503.512236582275, 6522.086907832907, 6525.086487891699, 6131.202943617302, 6161.876762464815, 6179.721912513947, 6182.938170618326, 6185.041318595625, 5486.561075113981, 5654.6889921054735, 5676.063449530864, 5683.318001839806, 5733.627314224562, 6485.092087107809, 6491.091247225298, 6496.642684652201, 6509.674516274073, 6514.218293804774, 6369.363063693779, 6372.617642941438, 6373.521985851173, 6395.360463953567, 6404.103425520524, 6009.699515024318, 6332.398422149889, 6600.504962127764, 6637.369449902251, 6695.631740254138, 6383.585066970818, 6403.615783053032, 6405.295417503978, 6410.262819775786, 6411.743530258737, 6279.340669229665, 6398.0, 6425.228972523327, 6440.2915679276175, 6453.870572649861, 6217.595856269328, 6265.467435267871, 6281.748730050746, 6312.147860038836, 6350.206224221973, 6267.467265282502, 6298.244210694746, 6321.304656487875, 6324.3991820776955, 6345.333739957238, 6275.37246275387, 6286.119943207863, 6287.96856015717, 6296.307406185408, 6297.905531417124, 6202.534809889186, 6305.653189074645, 6327.3988971047675, 6332.620042797447, 6339.080833279057, 5168.302279192411, 6188.9690551546955, 6208.689565521796, 6210.441060304594, 6273.090401891792], "value": 5923.234773180876, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [748.659359991204, 793.7182300283399, 802.0295442514557, 1120.2158489057656, 1129.5764088466824, 27505.0, 38354.80822595886, 38417.0, 38463.42304865422, 38479.230415391714, 41265.17469650605, 41600.37599436011, 41603.0, 41698.0, 41737.373939390855, 5989.970050149765, 8383.413161078712, 8505.404621676485, 8511.829763404729, 8519.3610479214, 5124.641275110744, 7432.7026918923175, 8373.41386102973, 8376.790580235482, 8394.076651568323, 8454.196851299117, 8482.491050536957, 8500.10748871369, 8506.234438900497, 8585.0, 8419.368547358941, 8475.0, 8475.110113438062, 8487.0, 8510.531920744357, 8084.6766129354755, 8104.473209241381, 8193.590320484012, 8266.421350505467, 8344.916550834474, 37336.25327493435, 37382.43926341107, 37430.81284593587, 37469.62530374716, 37567.436488452695, 3599.8200089995657, 3625.2205775758075, 3626.1659818241687, 3630.7458477906557, 3635.0730563706084, 30631.92788252423, 30649.233769155726, 30715.0, 30727.078187654406, 30741.846290768404, 3061.2499937515227, 3118.766092543068, 3136.466800643896, 3144.512600546896, 3162.3675264946874, 301151.0, 301238.0, 301390.4930475334, 301513.0, 301794.0, 49799.779032040155, 49877.0024599506, 49903.25145122826, 49914.001719965396, 49918.75040624774, 52287.47712522826, 52361.42915712535, 52371.47628523742, 52387.0, 52526.47473525292, 13825.377857996415, 13909.930450347683, 13912.860871391156, 13917.373718182705, 13919.23444210578, 9240.907590924138, 11255.43722813856, 11271.211015228855, 11272.887271127347, 11293.491792869341, 9789.021097890154, 10723.302985306087, 11339.886601134049, 12248.081393895494, 12696.0, 4636.374089497942, 5122.001209764135, 5290.5238528531845, 5369.624126311121, 5370.785168593291, 889.2619126125301, 976.3360914578155, 1013.7313611892854, 1033.2405681823852, 1036.7144740521699, 19980.50048748793, 24048.15831445891, 24542.263732088068, 24565.017399304186, 24591.13931012406, 968.3028219681818, 1102.9669109926715, 1126.132877684195, 1149.1496292743477, 1162.3781277016756, 12144.632342363375, 12424.012158054667, 12433.440495177738, 12482.313472759086, 12495.562655307021, 245260.0, 308685.0, 309279.0, 309358.0, 310456.0, 54098.72950635222, 54202.0, 54221.72889135608, 54275.72862135664, 54323.18515222196, 1499.535144105309, 1517.1655589425773, 1541.992290038565, 1549.5196489088419, 1550.6278493161785, 24075.398115046562, 24115.63826542568, 24118.51762964766, 24159.879200603886, 24329.905154267748, 38787.0, 38797.41803873001, 38845.02887427724, 38850.80574597109, 38884.61115388923, 268292.0, 269039.0, 269357.0, 270034.9494757541, 271359.0, 33945.32109357848, 35061.649383505835, 35173.94478165659, 35206.47190292199, 35275.82362088173, 36682.44976325304, 36841.0, 36883.0, 42863.9284017902, 44212.11575768531, 27672.0, 30208.5468717965, 30272.84863575668, 30618.08145755631, 30976.84511577428, 24658.383540411633, 27347.0, 29511.557326640548, 30488.0, 30860.537091943188, 35852.82073589615, 36527.81736091409, 36881.0, 38189.80905095568, 38425.42361864518, 24672.50654986926, 29227.97702080417, 30446.391072178874, 31169.84415078001, 31759.84120079385, 20469.0, 23108.537829243658, 24009.79951002478, 24215.51568968646, 24596.754032459445], "value": 17143.399520712428, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [391.9196564704236, 435.6449493662665, 437.20407990898207, 443.6140557714788, 451.17660270007246, 18855.811441885577, 19576.608467830654, 21151.365459036217, 21431.357059288246, 21433.785662143375, 21529.892350538266, 22145.44636384091, 22165.66751498729, 22300.8884955575, 22461.887690561565, 5735.4551317624755, 6017.518598512109, 6022.819315420523, 6024.518038556905, 6030.33666296707, 5643.153526970967, 5813.709314534278, 5877.9118313225335, 5887.528997680175, 5895.557833162508, 5844.970775146118, 5846.941530584703, 5857.062869940811, 5864.44287792659, 5867.4719275265215, 5506.944930550683, 5815.767369305223, 5833.504152147073, 5857.414258574151, 5866.911996320037, 5122.76947537358, 5224.555912747383, 5225.085610018232, 5231.869203269911, 5236.476352364771, 25984.350391240183, 26342.20973370802, 26347.473050538883, 26722.19833405001, 26827.731722682718, 2160.4706846822473, 2199.3182113544694, 2213.656883183093, 2243.730752309717, 2246.9438264043365, 17792.644147116986, 17924.731129033076, 18036.54908627282, 18182.0, 18253.452396427994, 2058.835293176542, 2126.6916297136827, 2141.421816109642, 2159.5680863827297, 2163.383435720812, 163854.0, 165291.0, 165494.9999999988, 167024.0, 168947.0, 11958.940205299092, 12057.638270851889, 12060.155789094855, 12111.697207569881, 12413.220909949734, 16118.194090295437, 16132.0, 16142.273597687898, 16157.59606009859, 16160.353585856668, 8610.225079742853, 8830.161134692195, 8865.955670221607, 8871.512066836385, 8979.326550508738, 8429.114942931026, 8434.704785332484, 8443.662253509914, 8605.483670979762, 8620.784480387914, 7522.398208143335, 7650.923490765021, 7665.655045522964, 7685.271728536401, 7701.537907725555, 1754.298280687735, 2084.1455003448514, 2087.630539337832, 2098.790120987889, 2279.4271952353156, 922.3036607361399, 926.346925417579, 932.1098351074708, 950.8811398575178, 969.413504829568, 17741.870975483023, 20546.69179962302, 20982.0, 21052.47368815792, 21287.148514059263, 962.5235508423375, 968.1673760565908, 996.835522138839, 997.5959736306808, 998.8701468809008, 10232.386056836614, 10502.05481506668, 10604.363738175734, 10791.514381852836, 10809.621663241749, 545866.0, 547828.0, 552331.0, 557571.0, 560368.0, 123717.38141309236, 127436.0, 127521.99999999814, 128307.35846320709, 129309.35345323401, 995.1690338567232, 1113.8496302999154, 1165.0097417195316, 1165.7875809158381, 1172.806486929664, 16734.4142954996, 17819.46541603754, 17962.5509362267, 17974.281028759226, 18129.546761331072, 54745.17882231849, 55737.0, 56731.149032763715, 56754.9056367954, 57610.71194644, 75136.0, 75247.0, 75330.62334688292, 75367.24632753822, 76416.23583764091, 8294.54380009081, 8425.70510032146, 8474.533900635524, 8543.145685431407, 8641.481511109354, 13097.934510327768, 13275.86724132785, 13509.59471215865, 13664.316784160554, 13689.315534223448, 11312.490937907649, 11396.803335649969, 11513.869934062226, 11578.36319002463, 11602.70993225176, 10697.893021069689, 10717.9464102679, 10942.781144377228, 11052.723681908017, 11122.61070862516, 11620.360880151673, 11642.708932276422, 11748.236364636272, 11874.406279686154, 11890.28658280506, 8218.137095604876, 8373.413861029669, 8376.12050734664, 8512.191341822518, 8566.78583035429, 11713.648590542298, 11775.76448471043, 11971.401429928643, 12021.158518903592, 12037.157398981988], "value": 10204.877876469847, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1161.4657257661474, 1365.7405093032323, 1404.8524904884987, 1415.129695237429, 1422.8577142285774, 9586.99999999999, 9872.703818885428, 10326.483675816207, 12016.21894576854, 12068.396580171, 11612.477438515267, 11693.298402095881, 11720.238184518, 11724.296542207474, 11725.648230553075, 2524.3058159006237, 2558.5138823623547, 2561.897524099043, 2569.11365578875, 2571.6014017827265, 2142.892855357226, 2226.5101677630896, 2417.2506522977847, 2574.9227523174336, 2576.446064096219, 2520.5085008423366, 2544.8727563621837, 2548.8598127103, 2552.221572420411, 2558.7952963762855, 2506.7994560435122, 2546.287039628888, 2552.834065785718, 2553.080890879282, 2556.642070110186, 2128.9461716450455, 2399.808015358767, 2470.629405589158, 2493.13986674597, 2546.2488565872927, 11432.485538150802, 11548.653540393801, 11551.76896462066, 11562.364069976147, 11603.419829008515, 6448.903266451007, 6684.298148694365, 6688.197416310074, 6703.9329606703795, 6713.093732346119, 3972.82122304497, 4144.792760362, 4148.211839750468, 4333.068390296102, 4333.71830830995, 1211.54567037361, 1232.9260244385366, 1252.473960936409, 1280.475005247844, 1280.903932205079, 16367.59081022972, 16377.918110409491, 16395.934264272903, 16402.589935251595, 16412.83587164125, 5601.607887447921, 5896.528277737771, 6028.608140470856, 6034.24571928509, 6041.969790151109, 13682.657933551742, 13689.0, 13690.38393272305, 13706.52027179044, 13730.038897277094, 6752.864942701119, 6762.932370676329, 6768.83077923046, 6772.085768421299, 6778.288279730656, 6033.607815491979, 6099.969500152471, 6128.9693551531955, 6201.875962480725, 6212.875742485216, 4111.301078816608, 4232.470941132358, 4239.851605193803, 4621.95378046222, 4681.227597446451, 1446.9131852088908, 1464.106894794196, 1464.4727897956727, 1480.6076389756722, 1485.8365579786214, 746.358132006474, 754.3663322808867, 756.9583672897934, 764.3579393309647, 767.1676231289114, 5250.238715386247, 5716.828495145152, 5930.703464826742, 6118.20463339763, 6280.371962803683, 803.7260941407817, 841.869510225916, 871.3884221326706, 924.8289066522715, 936.6674830435234, 3872.6320999505, 3907.1013666856784, 4040.4545386372465, 4067.0, 4070.4911886014247, 12180.451879665436, 14233.359498822578, 15029.398824046915, 15214.391424342903, 15230.46693365727, 14615.0, 15095.01882377664, 15103.622409439851, 15115.697686046438, 15122.017068890487, 8624.094470080676, 8642.265407440313, 8699.521526315986, 8747.343949203836, 8790.42862213954, 6173.59871608362, 6246.344133865879, 6310.242770867525, 6361.2048493938255, 6458.1281526995135, 13132.934335328262, 13441.529546465827, 13526.79709804334, 13546.796798048234, 13674.589762307147, 13520.59438216855, 13781.242031688353, 13928.930355348159, 14026.929865350608, 14048.78926816119, 9139.994600593796, 9247.167754902091, 9265.53672316368, 9266.397684150508, 9302.488363140092, 10510.369377837354, 10995.395253261147, 11030.558777648805, 11101.278416902875, 11127.165462590176, 9010.0, 9113.772155695895, 9117.0, 9238.168564829197, 9295.349325547148, 8880.955595221982, 8956.328275379503, 9035.0, 9060.592273347847, 9074.183323500916, 7971.123176450702, 8194.34445244367, 8249.092599814136, 8266.83466330658, 8297.377696672895, 4666.160091183619, 5551.722413879371, 5568.638038527482, 5575.721213939206, 5739.9139012915675, 4454.799534020912, 5095.363079615048, 5241.371035475768, 5439.102548079522, 5502.284702988586], "value": 5336.80146441697, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.992460150797, 377.1401205252026, 396.40935006839817, 401.5622970961649, 401.9115794525205, 5215.374155101384, 5382.919256211155, 5685.772569097231, 6001.119462657922, 6024.969875150624, 5690.743916523756, 5772.297172334172, 5798.768049278024, 5799.18811366409, 5807.361190269067, 3544.101488362806, 3970.5814755228616, 4388.341748737683, 4461.219286624844, 4480.686351955364, 4396.450443694538, 4489.371487991683, 4489.865304040884, 4493.438320209973, 4513.14250292445, 4460.442444694413, 4463.397441345409, 4476.440444944382, 4483.346072950535, 4487.439070116236, 3781.943270850953, 4413.823447062114, 4455.910881782346, 4457.907812585905, 4478.0, 3961.6632586230203, 3976.3836605325932, 3978.8607398741196, 3979.900502487432, 3987.202559488085, 4798.944232268896, 4882.266795287693, 5736.196932429463, 5769.596128271022, 5808.0, 4403.0, 4444.044530425942, 4686.367340409035, 4897.926531102036, 4999.400071991348, 4913.06651736169, 5297.311349524575, 5596.916046259269, 6137.7724455109155, 6156.0, 3531.7351198660203, 3793.412021136701, 4057.6753859691153, 4108.609682080198, 4111.198316328318, 6345.428911397997, 6465.575409221542, 6475.017864013645, 6521.217453905561, 6553.508486863504, 3816.2939856126704, 3858.9614103858603, 4142.627163555295, 4147.453926892464, 4154.127633197002, 4225.239456897788, 4227.408162857202, 4244.3209086546, 4248.320268756984, 4254.829806807693, 4673.672842901033, 4860.562549370574, 4894.657373983858, 4930.137225985438, 4956.0, 5021.472745361758, 5041.949580504148, 6165.938340616536, 6173.0, 6200.255969283715, 1238.2879844089684, 1439.784032395149, 1445.1401416157369, 1446.168453139428, 1447.4006223123508, 939.8402271613842, 940.6425558287807, 949.9170945122565, 953.6519170502717, 961.1541843177959, 5122.385313762373, 5777.711114444261, 5833.154192642043, 5874.500667443315, 5888.911666325009, 5040.445550989388, 5267.36631684158, 5540.722963851791, 6397.296297407282, 6410.775622853178, 5750.827475175752, 5792.247007888947, 5860.091685788624, 5866.208061911588, 5905.763769449174, 2788.442311537701, 2897.9855100724785, 3306.487494438366, 3327.983360083184, 3336.866525338959, 6254.6247225166635, 6278.277998030194, 6454.964089142857, 6457.902156633384, 6520.0, 5794.580327819711, 6245.251329627711, 6390.9360906391275, 6508.381703738138, 6547.443467305237, 5031.094403007493, 5565.332160140728, 5681.602287839812, 6361.363863613694, 6388.616682999035, 5980.205938218604, 6209.205857364114, 6296.401841825019, 6311.382869246283, 6333.050042493663, 5295.708736019402, 6064.329851588778, 6229.657368844576, 6236.469900058546, 6267.749290028531, 6236.407541283571, 6469.77355792545, 6532.987386955029, 6639.804835129723, 6645.435138013323, 3472.461768425898, 4157.334826427757, 4159.771212583337, 4179.623833854967, 4187.560306167808, 6347.809565713036, 6464.612123272619, 6510.316416776171, 6534.411902928759, 6541.6075035498025, 6244.313125556276, 6247.219097612799, 6261.405166509175, 6271.216097987752, 6291.245050593775, 5939.138824870283, 6254.7482906154655, 6257.0, 6282.403171698681, 6298.338674439118, 4965.900681986413, 5785.537157027344, 6151.907721384272, 6155.907661385172, 6174.536909731698, 6241.875162496815, 6250.343713909974, 6260.843478913063, 6274.592151510137, 6275.717592708431, 5925.808638340472, 5942.405759424196, 6058.153881504193, 6126.264848218242, 6131.601445906002], "value": 4496.829675494643, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [828.1056459024254, 846.6528723223478, 848.359488586117, 850.0, 867.5488745852155, 16351.410043282285, 16534.0, 16890.408835690752, 16894.070826104573, 16908.0, 15521.379144834193, 15624.2969066392, 15749.055056696607, 15784.605384865356, 15804.525864224091, 958.319593088906, 961.480800367804, 965.3194497879014, 966.130482565693, 968.3173362779223, 961.620160036785, 962.1629182611103, 963.6338191487225, 964.0937518732404, 992.5186284651938, 1044.1542350695922, 1045.8849526552074, 1046.304207701881, 1047.9214058945608, 1052.3790963331633, 1042.5412818359898, 1042.6194439029775, 1045.4354648489807, 1046.4244665434019, 1053.2574534952878, 775.255754475704, 777.8172129549552, 779.3103103753219, 781.2656103262915, 781.3749000799395, 15721.921390393089, 15845.68308633821, 15848.92075539615, 16822.579435514086, 17083.743743843854, 13964.650883727887, 16793.244304006348, 17016.234269457906, 17252.39616613425, 17391.217395217245, 14211.21838298893, 14346.92826535871, 14436.639084022878, 14514.346854391471, 14582.416703331854, 3541.6812486876047, 3569.37535931211, 3570.0539357070397, 3571.5535558055244, 3581.158427769472, 115775.0, 118346.0, 118747.0, 123108.0, 130041.0, 12872.22766634005, 13059.542915998085, 13408.932955335162, 13856.722865542633, 13861.584152475441, 24850.5029899401, 24915.377115571893, 25297.0, 25657.97368105255, 25983.61024584633, 7714.0, 7788.844223115506, 7927.563983980936, 7941.761747147595, 7971.681132754625, 3847.5382954045695, 3958.5051868516434, 3967.26605577969, 3967.940480892789, 3969.186316805106, 4054.716169868081, 4084.7753373564738, 4150.190712811033, 4152.712659075696, 4169.854055108057, 911.3256190419174, 915.6566287642135, 926.7590426489027, 933.3746389918771, 933.407286373158, 635.9332270111664, 641.3618449642636, 651.0364660302779, 651.91199188109, 674.8818956682561, 10923.453827308762, 11340.376279304717, 11810.822837657443, 12038.4582693779, 12172.695682608006, 594.5124997502007, 600.1867469578746, 600.6366148480193, 606.9302030266488, 621.155228888711, 4724.2205036169335, 5138.640295179302, 5330.987112448623, 5372.811951581754, 5572.860678482989, 95097.40697970898, 101921.98078019125, 102379.0, 102449.0, 102875.0, 90915.54542227379, 91866.16267674608, 92675.53662231646, 93212.53393732988, 94926.0, 47696.76151619289, 78587.0, 80674.19325806784, 80788.0, 80979.5951020253, 11890.227135236182, 12517.436715348013, 12580.559680411141, 12598.811017834556, 12665.81001284963, 38053.61946380501, 38234.85295441141, 38317.0, 38486.42270366003, 38732.0, 48233.75883120562, 48445.75777121092, 48838.0, 48839.511604884916, 49119.26321105258, 15585.922070389575, 15993.360265589246, 16048.679026419173, 16258.593535161715, 16309.59226019359, 17663.470095897144, 17743.467695969142, 17909.0, 18128.546786330448, 18237.270509179485, 12494.750104998031, 15281.46514871974, 16169.272382742569, 16636.58408539796, 16781.66436671284, 15869.206539673201, 15989.920050399674, 16654.417095401604, 16686.49940501787, 16742.246598902835, 14888.62778430548, 15348.693026139637, 15515.612109697347, 15860.52418427449, 16111.516654500383, 11088.889111108785, 11324.433778310886, 11367.602133925273, 11512.30926144434, 11536.365499897585, 11637.476313565741, 12553.372331383212, 12553.62339129791, 12589.118761686594, 12659.177153484994], "value": 8184.969545807327, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1421.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 111.0, 110.0, 109.0, 109.0, 110.0, 110.0, 110.0, 110.0, 110.0], "value": 110.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 112.0, 108.0, 148.0, 109.0, 108.0, 107.0, 107.0, 133.0, 109.0], "value": 118.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 185.0, 183.0, 184.0, 187.0, 186.0, 185.0, 184.0, 184.0, 183.0], "value": 184.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 59.0, 57.0, 88.0, 64.0, 58.0, 60.0, 58.0, 81.0, 57.0], "value": 66.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 69.0, 84.0, 69.0, 67.0, 69.0, 88.0, 68.0, 67.0, 89.0], "value": 75.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 89.0, 88.0, 87.0, 88.0, 89.0, 88.0, 89.0, 88.0], "value": 88.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 96.0, 95.0, 96.0, 96.0, 96.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 86.0, 86.0, 85.0, 91.0, 84.0, 83.0, 85.0, 82.0], "value": 84.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [47.0, 50.0, 47.0, 46.0, 48.0, 48.0, 48.0, 47.0, 49.0, 48.0], "value": 47.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 54.0, 64.0, 59.0, 55.0, 57.0, 58.0, 56.0, 57.0], "value": 56.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 82.0, 83.0, 82.0, 83.0, 81.0, 82.0, 82.0, 81.0], "value": 81.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 145.0, 128.0, 145.0, 146.0, 147.0, 136.0, 134.0, 175.0, 156.0], "value": 146.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 186.0, 185.0, 216.0, 184.0, 184.0, 183.0, 183.0, 185.0, 184.0], "value": 187.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 82.0, 83.0, 91.0, 91.0, 91.0, 92.0, 67.0, 67.0, 67.0], "value": 79.1, "unit": "score"}]}]} 10:41:35 INFO - Return code: 0 10:41:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:41:35 INFO - # TBPL SUCCESS # 10:41:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:41:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:41:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:41:35 INFO - 2017-05-12 10:41:35.492 screenresolution[1664:9913] starting screenresolution argv=screenresolution get 10:41:35 INFO - 2017-05-12 10:41:35.494 screenresolution[1664:9913] Display 0: 1600x1200x32@60 10:41:35 INFO - 2017-05-12 10:41:35.501 screenresolution[1665:9916] starting screenresolution argv=screenresolution list 10:41:35 INFO - Available Modes on Display 0 10:41:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:41:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:41:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:41:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:41:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:41:35 INFO - Intel Iris: 10:41:35 INFO - Chipset Model: Intel Iris 10:41:35 INFO - Type: GPU 10:41:35 INFO - Bus: Built-In 10:41:35 INFO - VRAM (Dynamic, Max): 1536 MB 10:41:35 INFO - Vendor: Intel (0x8086) 10:41:35 INFO - Device ID: 0x0a2e 10:41:35 INFO - Revision ID: 0x0009 10:41:35 INFO - Displays: 10:41:35 INFO - Display: 10:41:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:41:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:41:35 INFO - Display Serial Number: 1600x1200 60 10:41:35 INFO - Main Display: Yes 10:41:35 INFO - Mirror: Off 10:41:35 INFO - Online: Yes 10:41:35 INFO - Rotation: Supported 10:41:35 INFO - Return code: 0 10:41:35 INFO - Running post-action listener: _resource_record_post_action 10:41:35 INFO - [mozharness: 2017-05-12 17:41:35.745800Z] Finished run-tests step (success) 10:41:35 INFO - Running post-run listener: _resource_record_post_run 10:41:35 INFO - Total resource usage - Wall time: 1197s; CPU: 53.0%; Read bytes: 37705216; Write bytes: 527952896; Read time: 246; Write time: 1570 10:41:35 INFO - TinderboxPrint: CPU usage
53.2% 10:41:35 INFO - TinderboxPrint: I/O read bytes / time
37,705,216 / 246 10:41:35 INFO - TinderboxPrint: I/O write bytes / time
527,952,896 / 1,570 10:41:35 INFO - TinderboxPrint: CPU idle
2,240.3 (46.8%) 10:41:35 INFO - TinderboxPrint: CPU system
949.2 (19.8%) 10:41:35 INFO - TinderboxPrint: CPU user
1,600.1 (33.4%) 10:41:35 INFO - TinderboxPrint: Swap in / out
251,613,184 / 0 10:41:35 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 206285312; Write bytes: 209295360; Read time: 15829; Write time: 449 10:41:35 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 36742656; Write bytes: 308782080; Read time: 233; Write time: 1086 10:41:36 INFO - Running post-run listener: _upload_blobber_files 10:41:36 INFO - Blob upload gear active. 10:41:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:41:36 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 10:41:36 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'] 10:41:36 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 10:41:36 INFO - (blobuploader) - INFO - Open directory for files ... 10:41:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:41:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:41:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:41:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:41:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:41:37 INFO - (blobuploader) - INFO - Done attempting. 10:41:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:41:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:41:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:41:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:41:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:41:38 INFO - (blobuploader) - INFO - Done attempting. 10:41:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:41:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:41:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:41:39 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:41:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:41:39 INFO - (blobuploader) - INFO - Done attempting. 10:41:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:41:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:41:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:41:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:41:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:41:41 INFO - (blobuploader) - INFO - Done attempting. 10:41:41 INFO - (blobuploader) - INFO - Iteration through files over. 10:41:41 INFO - Return code: 0 10:41:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:41:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:41:41 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/422c11b4f6f192d1be0e31ac94c3d8f4cd1c2f9e4eb511370cf2724ac58eac075f5c7fff825c0b263f7182fe5d79210f990b8111c27a1601295d3892b4f12690", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c319267e548ca7af7340a01e0c79b0798faf6667bbebd2a30eb73971a6741d57374308085f38258eb90ffcd1326657ee00b0d5a8100aa354b4070dc6676eabd9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/44e8e250434b7ce2b1417af16c477866637a046e0b183ecba204b991dfbcff7d8137a710ac62c205aa684c396aee18f6a620b194e82e17e7b7741f7ec906ba17", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/52b01e0bff0f7a92c0d617487e955de17a148164abf739aac795d20cea66938458c714aa22d44daa7cdd37090b5fae63fae899a22d56b4aba45dc5dd5b8455d4"} 10:41:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:41:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:41:41 INFO - Contents: 10:41:41 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/422c11b4f6f192d1be0e31ac94c3d8f4cd1c2f9e4eb511370cf2724ac58eac075f5c7fff825c0b263f7182fe5d79210f990b8111c27a1601295d3892b4f12690", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c319267e548ca7af7340a01e0c79b0798faf6667bbebd2a30eb73971a6741d57374308085f38258eb90ffcd1326657ee00b0d5a8100aa354b4070dc6676eabd9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/44e8e250434b7ce2b1417af16c477866637a046e0b183ecba204b991dfbcff7d8137a710ac62c205aa684c396aee18f6a620b194e82e17e7b7741f7ec906ba17", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/52b01e0bff0f7a92c0d617487e955de17a148164abf739aac795d20cea66938458c714aa22d44daa7cdd37090b5fae63fae899a22d56b4aba45dc5dd5b8455d4"} 10:41:41 INFO - Running post-run listener: copy_logs_to_upload_dir 10:41:41 INFO - Copying logs to upload dir... 10:41:41 INFO - mkdir: /builds/slave/test/build/upload/logs 10:41:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.883676 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-05-12 10:41:41.177119) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-12 10:41:41.181193) ========= 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.ZULDNzoTqJ/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.5Pp4C9dS1E/Listeners TMPDIR=/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/422c11b4f6f192d1be0e31ac94c3d8f4cd1c2f9e4eb511370cf2724ac58eac075f5c7fff825c0b263f7182fe5d79210f990b8111c27a1601295d3892b4f12690", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c319267e548ca7af7340a01e0c79b0798faf6667bbebd2a30eb73971a6741d57374308085f38258eb90ffcd1326657ee00b0d5a8100aa354b4070dc6676eabd9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/44e8e250434b7ce2b1417af16c477866637a046e0b183ecba204b991dfbcff7d8137a710ac62c205aa684c396aee18f6a620b194e82e17e7b7741f7ec906ba17", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/52b01e0bff0f7a92c0d617487e955de17a148164abf739aac795d20cea66938458c714aa22d44daa7cdd37090b5fae63fae899a22d56b4aba45dc5dd5b8455d4"} build_url:https://queue.taskcluster.net/v1/task/TSuTwOIoR9epgWsUd-p85w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011185 build_url: 'https://queue.taskcluster.net/v1/task/TSuTwOIoR9epgWsUd-p85w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/422c11b4f6f192d1be0e31ac94c3d8f4cd1c2f9e4eb511370cf2724ac58eac075f5c7fff825c0b263f7182fe5d79210f990b8111c27a1601295d3892b4f12690", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c319267e548ca7af7340a01e0c79b0798faf6667bbebd2a30eb73971a6741d57374308085f38258eb90ffcd1326657ee00b0d5a8100aa354b4070dc6676eabd9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/44e8e250434b7ce2b1417af16c477866637a046e0b183ecba204b991dfbcff7d8137a710ac62c205aa684c396aee18f6a620b194e82e17e7b7741f7ec906ba17", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/52b01e0bff0f7a92c0d617487e955de17a148164abf739aac795d20cea66938458c714aa22d44daa7cdd37090b5fae63fae899a22d56b4aba45dc5dd5b8455d4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-12 10:41:41.215178) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 10:41:41.215508) ========= 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.ZULDNzoTqJ/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.5Pp4C9dS1E/Listeners TMPDIR=/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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.005208 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 10:41:41.263612) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-12 10:41:41.263954) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-12 10:41:41.264282) ========= ========= Total master_lag: 0.17 =========