builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0358 starttime: 1494606848.39 results: success (0) revision: 8a73de724f644192973e88b7685689b048023676 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-12 09:34:08.390912) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-12 09:34:08.391378) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-12 09:34:08.391654) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-12 09:34:08.418382) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 09:34:08.418762) ========= 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.yGbrCQgnxZ/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.x5kKNhbZMr/Listeners TMPDIR=/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 09:34:08-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 23.0M=0.001s 2017-05-12 09:34:08 (23.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.124521 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 09:34:08.568128) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 09:34:08.568711) ========= 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.yGbrCQgnxZ/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.x5kKNhbZMr/Listeners TMPDIR=/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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.024986 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 09:34:08.617990) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-12 09:34:08.618326) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8a73de724f644192973e88b7685689b048023676 --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 8a73de724f644192973e88b7685689b048023676 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.yGbrCQgnxZ/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.x5kKNhbZMr/Listeners TMPDIR=/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 09:34:08,724 truncating revision to first 12 chars 2017-05-12 09:34:08,724 Setting DEBUG logging. 2017-05-12 09:34:08,724 attempt 1/10 2017-05-12 09:34:08,724 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8a73de724f64?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-12 09:34:09,606 unpacking tar archive at: mozilla-central-8a73de724f64/testing/mozharness/ program finished with exit code 0 elapsedTime=1.151672 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-12 09:34:09.792203) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-12 09:34:09.792725) ========= script_repo_revision: 8a73de724f644192973e88b7685689b048023676 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-12 09:34:09.793327) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-12 09:34:09.793735) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-12 09:34:09.813615) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-05-12 09:34:09.814020) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.yGbrCQgnxZ/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.x5kKNhbZMr/Listeners TMPDIR=/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 09:34:10 INFO - MultiFileLogger online at 20170512 09:34:10 in /builds/slave/test 09:34:10 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:34:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:34:10 INFO - {'append_to_log': False, 09:34:10 INFO - 'base_work_dir': '/builds/slave/test', 09:34:10 INFO - 'blob_upload_branch': 'Firefox', 09:34:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:34:10 INFO - 'branch': 'Firefox', 09:34:10 INFO - 'buildbot_json_path': 'buildprops.json', 09:34:10 INFO - 'config_files': ('talos/mac_config.py',), 09:34:10 INFO - 'default_actions': ('clobber', 09:34:10 INFO - 'read-buildbot-config', 09:34:10 INFO - 'download-and-extract', 09:34:10 INFO - 'populate-webroot', 09:34:10 INFO - 'create-virtualenv', 09:34:10 INFO - 'install', 09:34:10 INFO - 'run-tests'), 09:34:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:34:10 INFO - 'download_minidump_stackwalk': True, 09:34:10 INFO - 'download_symbols': 'ondemand', 09:34:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:34:10 INFO - 'tooltool.py': '/tools/tooltool.py', 09:34:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:34:10 INFO - '/tools/misc-python/virtualenv.py')}, 09:34:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:34:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:34:10 INFO - 'gecko_profile': False, 09:34:10 INFO - 'gecko_profile_interval': 0, 09:34:10 INFO - 'installer_path': 'installer.exe', 09:34:10 INFO - 'log_level': 'info', 09:34:10 INFO - 'log_name': 'talos', 09:34:10 INFO - 'log_to_console': True, 09:34:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:34:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:34:10 INFO - 'opt_config_files': (), 09:34:10 INFO - 'pip_index': False, 09:34:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:34:10 INFO - 'cmd': ('bash', 09:34:10 INFO - '-c', 09:34:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:34:10 INFO - 'enabled': True, 09:34:10 INFO - 'halt_on_failure': False, 09:34:10 INFO - 'name': 'check_screen_resolution'},), 09:34:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:34:10 INFO - 'cmd': ('bash', 09:34:10 INFO - '-c', 09:34:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:34:10 INFO - 'enabled': True, 09:34:10 INFO - 'halt_on_failure': False, 09:34:10 INFO - 'name': 'check_screen_resolution'},), 09:34:10 INFO - 'run_cmd_checks_enabled': True, 09:34:10 INFO - 'suite': 'dromaeojs-e10s', 09:34:10 INFO - 'system_bits': '32', 09:34:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:34:10 INFO - 'title': 't-yosemite-r7-0358', 09:34:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:34:10 INFO - 'use_talos_json': True, 09:34:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:34:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:34:10 INFO - 'work_dir': 'build'} 09:34:10 INFO - [mozharness: 2017-05-12 16:34:10.023214Z] Running clobber step. 09:34:10 INFO - Running pre-action listener: _resource_record_pre_action 09:34:10 INFO - Running main action method: clobber 09:34:10 INFO - rmtree: /builds/slave/test/build 09:34:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:34:12 INFO - Running post-action listener: _resource_record_post_action 09:34:12 INFO - [mozharness: 2017-05-12 16:34:12.445431Z] Finished clobber step (success) 09:34:12 INFO - [mozharness: 2017-05-12 16:34:12.445654Z] Running read-buildbot-config step. 09:34:12 INFO - Running pre-action listener: _resource_record_pre_action 09:34:12 INFO - Running main action method: read_buildbot_config 09:34:12 INFO - Using buildbot properties: 09:34:12 INFO - { 09:34:12 INFO - "project": "", 09:34:12 INFO - "product": "firefox", 09:34:12 INFO - "who": "ffxbld@noreply.mozilla.org", 09:34:12 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 09:34:12 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 09:34:12 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 09:34:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 09:34:12 INFO - "stage_platform": "macosx64", 09:34:12 INFO - "basedir": "/builds/slave/test", 09:34:12 INFO - "buildnumber": 155, 09:34:12 INFO - "platform": "yosemite_r7", 09:34:12 INFO - "parent_task_id": "dlnsPqIIRw-eRDfwXc_Bhw", 09:34:12 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 09:34:12 INFO - "slavebuilddir": "test", 09:34:12 INFO - "taskId": "OqlZ-M2CQu2-rOZu1ZMq3g", 09:34:12 INFO - "branch": "mozilla-central", 09:34:12 INFO - "script_repo_revision": "production", 09:34:12 INFO - "revision": "8a73de724f644192973e88b7685689b048023676", 09:34:12 INFO - "slavename": "t-yosemite-r7-0358", 09:34:12 INFO - "repo_path": "mozilla-central" 09:34:12 INFO - } 09:34:12 INFO - Finding installer, test and symbols from parent task. 09:34:12 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OqlZ-M2CQu2-rOZu1ZMq3g'}, attempt #1 09:34:13 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OqlZ-M2CQu2-rOZu1ZMq3g'}, attempt #1 09:34:13 INFO - Task dependencies: B6aMncXwRV2P96DDgyqrsw 09:34:13 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dlnsPqIIRw-eRDfwXc_Bhw'}, attempt #1 09:34:14 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/dlnsPqIIRw-eRDfwXc_Bhw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:34:14 INFO - Set test_url: https://queue.taskcluster.net/v1/task/dlnsPqIIRw-eRDfwXc_Bhw/artifacts/public/build/test_packages.json 09:34:14 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/dlnsPqIIRw-eRDfwXc_Bhw/artifacts/public/build/target.crashreporter-symbols.zip 09:34:14 INFO - Running post-action listener: _resource_record_post_action 09:34:14 INFO - [mozharness: 2017-05-12 16:34:14.203952Z] Finished read-buildbot-config step (success) 09:34:14 INFO - [mozharness: 2017-05-12 16:34:14.204096Z] Running download-and-extract step. 09:34:14 INFO - Running pre-action listener: _resource_record_pre_action 09:34:14 INFO - Running main action method: download_and_extract 09:34:14 INFO - mkdir: /builds/slave/test/build/tests 09:34:14 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')]} 09:34:14 INFO - trying https://queue.taskcluster.net/v1/task/dlnsPqIIRw-eRDfwXc_Bhw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 09:34:14 INFO - Downloading https://queue.taskcluster.net/v1/task/dlnsPqIIRw-eRDfwXc_Bhw/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 09:34:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dlnsPqIIRw-eRDfwXc_Bhw/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 09:34:15 INFO - Downloaded 1325 bytes. 09:34:15 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 09:34:15 INFO - Using the following test package requirements: 09:34:15 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:34:15 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 09:34:15 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:34:15 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:34:15 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 09:34:15 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:34:15 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 09:34:15 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:34:15 INFO - u'jsshell-mac.zip'], 09:34:15 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:34:15 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 09:34:15 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:34:15 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:34:15 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 09:34:15 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:34:15 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 09:34:15 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:34:15 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 09:34:15 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:34:15 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 09:34:15 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:34:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dlnsPqIIRw-eRDfwXc_Bhw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:34:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dlnsPqIIRw-eRDfwXc_Bhw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:34:15 INFO - Fetch https://queue.taskcluster.net/v1/task/dlnsPqIIRw-eRDfwXc_Bhw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:34:16 INFO - Content-Length response header: 56563261 09:34:16 INFO - Bytes received: 56563261 09:34:34 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 09:34:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dlnsPqIIRw-eRDfwXc_Bhw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:34:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dlnsPqIIRw-eRDfwXc_Bhw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:34:34 INFO - Fetch https://queue.taskcluster.net/v1/task/dlnsPqIIRw-eRDfwXc_Bhw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:34:36 INFO - Content-Length response header: 56563261 09:34:36 INFO - Bytes received: 56563261 09:34:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dlnsPqIIRw-eRDfwXc_Bhw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 09:34:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dlnsPqIIRw-eRDfwXc_Bhw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:34:53 INFO - Fetch https://queue.taskcluster.net/v1/task/dlnsPqIIRw-eRDfwXc_Bhw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 09:34:54 INFO - Content-Length response header: 14409411 09:34:54 INFO - Bytes received: 14409411 09:34:54 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')]} 09:34:54 INFO - trying https://queue.taskcluster.net/v1/task/dlnsPqIIRw-eRDfwXc_Bhw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:34:54 INFO - Downloading https://queue.taskcluster.net/v1/task/dlnsPqIIRw-eRDfwXc_Bhw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 09:34:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dlnsPqIIRw-eRDfwXc_Bhw/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 09:34:56 INFO - Downloaded 75903454 bytes. 09:34:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dlnsPqIIRw-eRDfwXc_Bhw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:34:56 INFO - mkdir: /builds/slave/test/properties 09:34:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:34:56 INFO - Writing to file /builds/slave/test/properties/build_url 09:34:56 INFO - Contents: 09:34:56 INFO - build_url:https://queue.taskcluster.net/v1/task/dlnsPqIIRw-eRDfwXc_Bhw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:34:56 INFO - Running post-action listener: _resource_record_post_action 09:34:56 INFO - Running post-action listener: set_extra_try_arguments 09:34:56 INFO - [mozharness: 2017-05-12 16:34:56.575968Z] Finished download-and-extract step (success) 09:34:56 INFO - [mozharness: 2017-05-12 16:34:56.576085Z] Running populate-webroot step. 09:34:56 INFO - Running pre-action listener: _resource_record_pre_action 09:34:56 INFO - Running main action method: populate_webroot 09:34:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:34:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:34:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:34:56 INFO - '--tppagecycles', 09:34:56 INFO - '7'], 09:34:56 INFO - 'tests': ['tsvgm']}}, 09:34:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:34:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:34:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:34:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:34:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:34:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:34:56 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:34:56 INFO - 'talos_options': ['--disable-e10s'], 09:34:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:34:56 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:34:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:34:56 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:34:56 INFO - 'talos_options': ['--disable-e10s'], 09:34:56 INFO - 'tests': ['damp', 'tps']}, 09:34:56 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:34:56 INFO - 'tests': ['damp', 'tps']}, 09:34:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:34:56 INFO - 'tests': ['dromaeo_dom']}, 09:34:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:34:56 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:34:56 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 09:34:56 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 09:34:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:34:56 INFO - 'tests': ['a11yr', 09:34:56 INFO - 'ts_paint', 09:34:56 INFO - 'tpaint', 09:34:56 INFO - 'sessionrestore', 09:34:56 INFO - 'sessionrestore_no_auto_restore', 09:34:56 INFO - 'tabpaint']}, 09:34:56 INFO - 'other-e10s': {'tests': ['a11yr', 09:34:56 INFO - 'ts_paint', 09:34:56 INFO - 'tpaint', 09:34:56 INFO - 'sessionrestore', 09:34:56 INFO - 'sessionrestore_no_auto_restore', 09:34:56 INFO - 'tabpaint']}, 09:34:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:34:56 INFO - 'ts_paint', 09:34:56 INFO - 'tpaint', 09:34:56 INFO - 'sessionrestore', 09:34:56 INFO - 'sessionrestore_no_auto_restore', 09:34:56 INFO - 'tabpaint']}, 09:34:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:34:56 INFO - 'ts_paint', 09:34:56 INFO - 'tpaint', 09:34:56 INFO - 'sessionrestore', 09:34:56 INFO - 'sessionrestore_no_auto_restore', 09:34:56 INFO - 'tabpaint']}, 09:34:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:34:56 INFO - 'tests': ['a11yr', 09:34:56 INFO - 'ts_paint', 09:34:56 INFO - 'tpaint', 09:34:56 INFO - 'sessionrestore', 09:34:56 INFO - 'sessionrestore_no_auto_restore', 09:34:56 INFO - 'tabpaint']}, 09:34:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:34:56 INFO - 'tests': ['a11yr', 09:34:56 INFO - 'ts_paint', 09:34:56 INFO - 'tpaint', 09:34:56 INFO - 'sessionrestore', 09:34:56 INFO - 'sessionrestore_no_auto_restore', 09:34:56 INFO - 'tabpaint']}, 09:34:56 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 09:34:56 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 09:34:56 INFO - 'bloom_basic_ref']}, 09:34:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:34:56 INFO - 'tests': ['tsvgx', 09:34:56 INFO - 'tsvgr_opacity', 09:34:56 INFO - 'tart', 09:34:56 INFO - 'tscrollx', 09:34:56 INFO - 'cart', 09:34:56 INFO - 'tsvg_static']}, 09:34:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:34:56 INFO - 'tsvgr_opacity', 09:34:56 INFO - 'tart', 09:34:56 INFO - 'tscrollx', 09:34:56 INFO - 'cart', 09:34:56 INFO - 'tsvg_static']}, 09:34:56 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:34:56 INFO - 'talos_options': ['--disable-e10s'], 09:34:56 INFO - 'tests': ['tp5o']}, 09:34:56 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:34:56 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:34:56 INFO - 'talos_options': ['--disable-e10s', 09:34:56 INFO - '--xperf_path', 09:34:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:34:56 INFO - 'tests': ['tp5n']}, 09:34:56 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:34:56 INFO - 'talos_options': ['--xperf_path', 09:34:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:34:56 INFO - 'tests': ['tp5n']}}, 09:34:56 INFO - 'talos.zip': {'path': '', 09:34:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:34:56 INFO - Running post-action listener: _resource_record_post_action 09:34:56 INFO - [mozharness: 2017-05-12 16:34:56.597216Z] Finished populate-webroot step (success) 09:34:56 INFO - [mozharness: 2017-05-12 16:34:56.597348Z] Running create-virtualenv step. 09:34:56 INFO - Running pre-action listener: _resource_record_pre_action 09:34:56 INFO - Running main action method: create_virtualenv 09:34:56 INFO - Creating virtualenv /builds/slave/test/build/venv 09:34:56 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 09:34:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:34:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:34:56 INFO - Using real prefix '/tools/python27' 09:34:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:34:57 INFO - Installing distribute.............................................................................................................................................................................................done. 09:35:00 INFO - Installing pip.................done. 09:35:00 INFO - Return code: 0 09:35:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:35:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:35:00 INFO - Reading from file tmpfile_stdout 09:35:00 INFO - Output received: 09:35:00 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) 09:35:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:35:00 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')]} 09:35:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:00 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')]} 09:35:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:00 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 0x10a220b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a223768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda3156ee80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f31d30>, '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 0x109ee4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109f25be8>, '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.yGbrCQgnxZ/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.x5kKNhbZMr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 09:35:00 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 09:35:00 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 09:35:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yGbrCQgnxZ/Render', 09:35:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:35:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:35:00 INFO - 'HOME': '/Users/cltbld', 09:35:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:35:00 INFO - 'LOGNAME': 'cltbld', 09:35:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:00 INFO - 'MOZ_NO_REMOTE': '1', 09:35:00 INFO - 'NO_EM_RESTART': '1', 09:35:00 INFO - 'PAGER': '/bin/cat', 09:35:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:35:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:00 INFO - 'PWD': '/builds/slave/test', 09:35:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:35:00 INFO - 'SHELL': '/bin/bash', 09:35:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x5kKNhbZMr/Listeners', 09:35:00 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 09:35:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:35:00 INFO - 'USER': 'cltbld', 09:35:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:35:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:35:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:00 INFO - 'XPC_FLAGS': '0x0', 09:35:00 INFO - 'XPC_SERVICE_NAME': '0', 09:35:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:35:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:00 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 09:35:00 INFO - Cleaning up... 09:35:00 INFO - Return code: 0 09:35:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:35:00 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')]} 09:35:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:00 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')]} 09:35:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:00 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 0x10a220b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a223768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda3156ee80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f31d30>, '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 0x109ee4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109f25be8>, '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.yGbrCQgnxZ/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.x5kKNhbZMr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 09:35:00 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 09:35:00 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 09:35:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yGbrCQgnxZ/Render', 09:35:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:35:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:35:00 INFO - 'HOME': '/Users/cltbld', 09:35:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:35:00 INFO - 'LOGNAME': 'cltbld', 09:35:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:00 INFO - 'MOZ_NO_REMOTE': '1', 09:35:00 INFO - 'NO_EM_RESTART': '1', 09:35:00 INFO - 'PAGER': '/bin/cat', 09:35:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:35:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:00 INFO - 'PWD': '/builds/slave/test', 09:35:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:35:00 INFO - 'SHELL': '/bin/bash', 09:35:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x5kKNhbZMr/Listeners', 09:35:00 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 09:35:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:35:00 INFO - 'USER': 'cltbld', 09:35:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:35:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:35:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:00 INFO - 'XPC_FLAGS': '0x0', 09:35:00 INFO - 'XPC_SERVICE_NAME': '0', 09:35:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:35:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:01 INFO - Downloading/unpacking psutil>=3.1.1 09:35:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:35:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:35:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:35:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:35:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:35:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:35:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:35:03 INFO - Installing collected packages: psutil 09:35:03 INFO - Running setup.py install for psutil 09:35:03 INFO - building 'psutil._psutil_osx' extension 09:35:03 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 09:35:03 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 09:35:03 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 09:35:03 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 09:35:03 INFO - building 'psutil._psutil_posix' extension 09:35:03 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 09:35:03 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:35:03 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:35:03 INFO - ^ 09:35:04 INFO - 1 warning generated. 09:35:04 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 09:35:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:35:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:35:04 INFO - Successfully installed psutil 09:35:04 INFO - Cleaning up... 09:35:04 INFO - Return code: 0 09:35:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:35:04 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')]} 09:35:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:05 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')]} 09:35:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:05 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 0x10a220b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a223768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda3156ee80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f31d30>, '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 0x109ee4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109f25be8>, '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.yGbrCQgnxZ/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.x5kKNhbZMr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 09:35:05 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 09:35:05 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 09:35:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yGbrCQgnxZ/Render', 09:35:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:35:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:35:05 INFO - 'HOME': '/Users/cltbld', 09:35:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:35:05 INFO - 'LOGNAME': 'cltbld', 09:35:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:05 INFO - 'MOZ_NO_REMOTE': '1', 09:35:05 INFO - 'NO_EM_RESTART': '1', 09:35:05 INFO - 'PAGER': '/bin/cat', 09:35:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:35:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:05 INFO - 'PWD': '/builds/slave/test', 09:35:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:35:05 INFO - 'SHELL': '/bin/bash', 09:35:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x5kKNhbZMr/Listeners', 09:35:05 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 09:35:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:35:05 INFO - 'USER': 'cltbld', 09:35:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:35:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:35:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:05 INFO - 'XPC_FLAGS': '0x0', 09:35:05 INFO - 'XPC_SERVICE_NAME': '0', 09:35:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:35:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:35:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:35:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:35:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:35:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:35:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:35:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:35:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:35:07 INFO - Installing collected packages: mozsystemmonitor 09:35:07 INFO - Running setup.py install for mozsystemmonitor 09:35:07 INFO - Successfully installed mozsystemmonitor 09:35:07 INFO - Cleaning up... 09:35:07 INFO - Return code: 0 09:35:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:35:07 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')]} 09:35:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:07 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')]} 09:35:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:07 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 0x10a220b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a223768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda3156ee80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f31d30>, '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 0x109ee4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109f25be8>, '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.yGbrCQgnxZ/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.x5kKNhbZMr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 09:35:07 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 09:35:07 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 09:35:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yGbrCQgnxZ/Render', 09:35:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:35:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:35:07 INFO - 'HOME': '/Users/cltbld', 09:35:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:35:07 INFO - 'LOGNAME': 'cltbld', 09:35:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:07 INFO - 'MOZ_NO_REMOTE': '1', 09:35:07 INFO - 'NO_EM_RESTART': '1', 09:35:07 INFO - 'PAGER': '/bin/cat', 09:35:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:35:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:07 INFO - 'PWD': '/builds/slave/test', 09:35:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:35:07 INFO - 'SHELL': '/bin/bash', 09:35:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x5kKNhbZMr/Listeners', 09:35:07 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 09:35:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:35:07 INFO - 'USER': 'cltbld', 09:35:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:35:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:35:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:07 INFO - 'XPC_FLAGS': '0x0', 09:35:07 INFO - 'XPC_SERVICE_NAME': '0', 09:35:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:35:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:07 INFO - Downloading/unpacking jsonschema==2.5.1 09:35:07 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 09:35:07 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 09:35:07 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 09:35:07 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 09:35:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:35:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:35:09 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 09:35:09 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 09:35:09 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 09:35:09 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 09:35:09 INFO - Downloading functools32-3.2.3-2.tar.gz 09:35:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:35:09 INFO - warning: no files found matching '*.txt' 09:35:09 INFO - no previously-included directories found matching 'build' 09:35:09 INFO - no previously-included directories found matching 'dist' 09:35:09 INFO - no previously-included directories found matching '.git*' 09:35:09 INFO - Installing collected packages: jsonschema, functools32 09:35:09 INFO - Running setup.py install for functools32 09:35:09 INFO - warning: no files found matching '*.txt' 09:35:09 INFO - no previously-included directories found matching 'build' 09:35:09 INFO - no previously-included directories found matching 'dist' 09:35:09 INFO - no previously-included directories found matching '.git*' 09:35:09 INFO - Successfully installed jsonschema functools32 09:35:09 INFO - Cleaning up... 09:35:09 INFO - Return code: 0 09:35:09 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:35:09 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')]} 09:35:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:09 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')]} 09:35:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:09 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 0x10a220b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a223768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda3156ee80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f31d30>, '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 0x109ee4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109f25be8>, '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.yGbrCQgnxZ/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.x5kKNhbZMr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 09:35:09 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 09:35:09 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 09:35:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yGbrCQgnxZ/Render', 09:35:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:35:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:35:09 INFO - 'HOME': '/Users/cltbld', 09:35:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:35:09 INFO - 'LOGNAME': 'cltbld', 09:35:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:09 INFO - 'MOZ_NO_REMOTE': '1', 09:35:09 INFO - 'NO_EM_RESTART': '1', 09:35:09 INFO - 'PAGER': '/bin/cat', 09:35:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:35:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:09 INFO - 'PWD': '/builds/slave/test', 09:35:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:35:09 INFO - 'SHELL': '/bin/bash', 09:35:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x5kKNhbZMr/Listeners', 09:35:09 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 09:35:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:35:09 INFO - 'USER': 'cltbld', 09:35:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:35:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:35:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:09 INFO - 'XPC_FLAGS': '0x0', 09:35:09 INFO - 'XPC_SERVICE_NAME': '0', 09:35:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:35:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:35:10 INFO - Cleaning up... 09:35:10 INFO - Return code: 0 09:35:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:35:10 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')]} 09:35:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:10 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')]} 09:35:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:10 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 0x10a220b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a223768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda3156ee80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f31d30>, '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 0x109ee4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109f25be8>, '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.yGbrCQgnxZ/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.x5kKNhbZMr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 09:35:10 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 09:35:10 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 09:35:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yGbrCQgnxZ/Render', 09:35:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:35:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:35:10 INFO - 'HOME': '/Users/cltbld', 09:35:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:35:10 INFO - 'LOGNAME': 'cltbld', 09:35:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:10 INFO - 'MOZ_NO_REMOTE': '1', 09:35:10 INFO - 'NO_EM_RESTART': '1', 09:35:10 INFO - 'PAGER': '/bin/cat', 09:35:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:35:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:10 INFO - 'PWD': '/builds/slave/test', 09:35:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:35:10 INFO - 'SHELL': '/bin/bash', 09:35:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x5kKNhbZMr/Listeners', 09:35:10 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 09:35:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:35:10 INFO - 'USER': 'cltbld', 09:35:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:35:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:35:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:10 INFO - 'XPC_FLAGS': '0x0', 09:35:10 INFO - 'XPC_SERVICE_NAME': '0', 09:35:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:35:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:10 INFO - Downloading/unpacking blobuploader==1.2.4 09:35:10 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 09:35:10 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 09:35:10 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 09:35:10 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 09:35:11 INFO - Downloading blobuploader-1.2.4.tar.gz 09:35:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:35:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:35:11 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 09:35:11 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 09:35:11 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 09:35:11 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 09:35:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:35:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:35:12 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 09:35:12 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 09:35:12 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 09:35:12 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 09:35:12 INFO - Downloading docopt-0.6.1.tar.gz 09:35:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:35:12 INFO - Installing collected packages: blobuploader, requests, docopt 09:35:12 INFO - Running setup.py install for blobuploader 09:35:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:35:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:35:12 INFO - Running setup.py install for requests 09:35:13 INFO - Running setup.py install for docopt 09:35:13 INFO - Successfully installed blobuploader requests docopt 09:35:13 INFO - Cleaning up... 09:35:13 INFO - Return code: 0 09:35:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:35:13 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')]} 09:35:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:13 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')]} 09:35:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:13 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 0x10a220b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a223768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda3156ee80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f31d30>, '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 0x109ee4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109f25be8>, '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.yGbrCQgnxZ/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.x5kKNhbZMr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 09:35:13 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 09:35:13 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 09:35:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yGbrCQgnxZ/Render', 09:35:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:35:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:35:13 INFO - 'HOME': '/Users/cltbld', 09:35:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:35:13 INFO - 'LOGNAME': 'cltbld', 09:35:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:13 INFO - 'MOZ_NO_REMOTE': '1', 09:35:13 INFO - 'NO_EM_RESTART': '1', 09:35:13 INFO - 'PAGER': '/bin/cat', 09:35:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:35:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:13 INFO - 'PWD': '/builds/slave/test', 09:35:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:35:13 INFO - 'SHELL': '/bin/bash', 09:35:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x5kKNhbZMr/Listeners', 09:35:13 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 09:35:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:35:13 INFO - 'USER': 'cltbld', 09:35:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:35:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:35:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:13 INFO - 'XPC_FLAGS': '0x0', 09:35:13 INFO - 'XPC_SERVICE_NAME': '0', 09:35:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:35:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:35:13 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-GlHziF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:35:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:35:14 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-C8g7Fb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:35:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:35:14 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-qg7BDK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:35:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:35:14 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-JGcOdI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:35:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:35:14 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-pq0Pwi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:35:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:35:14 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-Ing2QQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:35:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:35:14 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-23JCJG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:35:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:35:14 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-oJ_7On-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:35:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:35:14 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-iq7eQl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:35:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:35:14 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-wgUOes-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:35:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:35:14 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-ePswTF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:35:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:35:15 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-AyTMeZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:35:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:35:15 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-uucjFC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:35:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:35:15 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-RbOzyp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:35:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:35:15 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-F8njPT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:35:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:35:15 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-udiBv7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:35:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:35:15 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-Ejjmtd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:35:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:35:15 INFO - Running setup.py install for manifestparser 09:35:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:35:15 INFO - Running setup.py install for mozcrash 09:35:15 INFO - Running setup.py install for mozdebug 09:35:16 INFO - Running setup.py install for mozdevice 09:35:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:35:16 INFO - Running setup.py install for mozfile 09:35:16 INFO - Running setup.py install for mozhttpd 09:35:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:35:16 INFO - Running setup.py install for mozinfo 09:35:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:35:16 INFO - Running setup.py install for mozInstall 09:35:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:35:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:35:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:35:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:35:16 INFO - Running setup.py install for mozleak 09:35:16 INFO - Running setup.py install for mozlog 09:35:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:35:17 INFO - Running setup.py install for moznetwork 09:35:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:35:17 INFO - Running setup.py install for mozprocess 09:35:17 INFO - Running setup.py install for mozprofile 09:35:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:35:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:35:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:35:17 INFO - Running setup.py install for mozrunner 09:35:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:35:17 INFO - Running setup.py install for mozscreenshot 09:35:17 INFO - Running setup.py install for moztest 09:35:18 INFO - Running setup.py install for mozversion 09:35:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:35:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:35:18 INFO - Cleaning up... 09:35:18 INFO - Return code: 0 09:35:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:35: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')]} 09:35:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35: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')]} 09:35:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:18 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 0x10a220b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a223768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda3156ee80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f31d30>, '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 0x109ee4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109f25be8>, '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.yGbrCQgnxZ/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.x5kKNhbZMr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 09:35:18 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 09:35:18 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 09:35:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yGbrCQgnxZ/Render', 09:35:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:35:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:35:18 INFO - 'HOME': '/Users/cltbld', 09:35:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:35:18 INFO - 'LOGNAME': 'cltbld', 09:35:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:18 INFO - 'MOZ_NO_REMOTE': '1', 09:35:18 INFO - 'NO_EM_RESTART': '1', 09:35:18 INFO - 'PAGER': '/bin/cat', 09:35:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:35:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:18 INFO - 'PWD': '/builds/slave/test', 09:35:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:35:18 INFO - 'SHELL': '/bin/bash', 09:35:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x5kKNhbZMr/Listeners', 09:35:18 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 09:35:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:35:18 INFO - 'USER': 'cltbld', 09:35:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:35:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:35:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:18 INFO - 'XPC_FLAGS': '0x0', 09:35:18 INFO - 'XPC_SERVICE_NAME': '0', 09:35:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:35:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:35:18 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-hDvhmE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:35:18 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)) 09:35:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:35:18 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-AEiSSD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:35:18 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)) 09:35:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:35:18 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-p3TpjK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:35:18 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)) 09:35:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:35:18 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-9Xp8bp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:35:18 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)) 09:35:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:35:19 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-6aqryJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:35:19 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)) 09:35:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:35:19 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-cUxgx_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:35:19 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)) 09:35:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:35:19 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-GyPHLN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:35:19 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)) 09:35:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:35:19 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-PhrjSE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:35:19 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)) 09:35:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:35:19 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-Qf6uPb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:35:19 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)) 09:35:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:35:19 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-dqK_Rh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:35:19 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)) 09:35:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:35:19 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-1Dnr3N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:35:19 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)) 09:35:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:35:19 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-9hkcQd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:35:19 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)) 09:35:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:35:19 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-AaS_R9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:35:19 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)) 09:35:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:35:19 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-k4QcCI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:35:20 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)) 09:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:35:20 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-RW5flH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:35:20 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)) 09:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:35:20 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-ae6IZ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:35:20 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)) 09:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:35:20 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-l__6rE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:35:20 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)) 09:35:20 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)) 09:35:20 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)) 09:35:20 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)) 09:35:20 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)) 09:35:20 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)) 09:35:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:35: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 09:35: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 09:35: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 09:35: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 09:35:21 INFO - Downloading blessings-1.6.tar.gz 09:35:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:35:21 INFO - Installing collected packages: blessings 09:35:21 INFO - Running setup.py install for blessings 09:35:21 INFO - Successfully installed blessings 09:35:21 INFO - Cleaning up... 09:35:22 INFO - Return code: 0 09:35:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:35:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:35:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:35:22 INFO - Reading from file tmpfile_stdout 09:35:22 INFO - Current package versions: 09:35:22 INFO - blessings == 1.6 09:35:22 INFO - blobuploader == 1.2.4 09:35:22 INFO - docopt == 0.6.1 09:35:22 INFO - functools32 == 3.2.3-2 09:35:22 INFO - jsonschema == 2.5.1 09:35:22 INFO - manifestparser == 1.1 09:35:22 INFO - mozInstall == 1.12 09:35:22 INFO - mozcrash == 1.0 09:35:22 INFO - mozdebug == 0.1 09:35:22 INFO - mozdevice == 0.50 09:35:22 INFO - mozfile == 1.2 09:35:22 INFO - mozhttpd == 0.7 09:35:22 INFO - mozinfo == 0.9 09:35:22 INFO - mozleak == 0.1 09:35:22 INFO - mozlog == 3.4 09:35:22 INFO - moznetwork == 0.27 09:35:22 INFO - mozprocess == 0.25 09:35:22 INFO - mozprofile == 0.28 09:35:22 INFO - mozrunner == 6.13 09:35:22 INFO - mozscreenshot == 0.1 09:35:22 INFO - mozsystemmonitor == 0.3 09:35:22 INFO - moztest == 0.8 09:35:22 INFO - mozversion == 1.4 09:35:22 INFO - psutil == 3.1.1 09:35:22 INFO - requests == 1.2.3 09:35:22 INFO - wsgiref == 0.1.2 09:35:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:35: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')]} 09:35:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35: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')]} 09:35:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:22 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 0x10a220b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a223768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda3156ee80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f31d30>, '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 0x109ee4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109f25be8>, '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.yGbrCQgnxZ/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.x5kKNhbZMr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 09:35:22 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 09:35:22 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 09:35:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yGbrCQgnxZ/Render', 09:35:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:35:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:35:22 INFO - 'HOME': '/Users/cltbld', 09:35:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:35:22 INFO - 'LOGNAME': 'cltbld', 09:35:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:22 INFO - 'MOZ_NO_REMOTE': '1', 09:35:22 INFO - 'NO_EM_RESTART': '1', 09:35:22 INFO - 'PAGER': '/bin/cat', 09:35:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:35:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:22 INFO - 'PWD': '/builds/slave/test', 09:35:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:35:22 INFO - 'SHELL': '/bin/bash', 09:35:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x5kKNhbZMr/Listeners', 09:35:22 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 09:35:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:35:22 INFO - 'USER': 'cltbld', 09:35:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:35:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:35:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:22 INFO - 'XPC_FLAGS': '0x0', 09:35:22 INFO - 'XPC_SERVICE_NAME': '0', 09:35:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:35:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:22 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)) 09:35:22 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)) 09:35:22 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)) 09:35:22 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)) 09:35:22 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)) 09:35:22 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)) 09:35:22 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)) 09:35:22 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)) 09:35:22 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)) 09:35:22 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)) 09:35:22 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)) 09:35:22 INFO - Cleaning up... 09:35:22 INFO - Return code: 0 09:35:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:35: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')]} 09:35:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35: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')]} 09:35:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a220b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a223768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda3156ee80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f31d30>, '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 0x109ee4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109f25be8>, '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.yGbrCQgnxZ/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.x5kKNhbZMr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 09:35: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'] in /builds/slave/test/build 09:35: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 09:35:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yGbrCQgnxZ/Render', 09:35:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:35:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:35:22 INFO - 'HOME': '/Users/cltbld', 09:35:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:35:22 INFO - 'LOGNAME': 'cltbld', 09:35:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:22 INFO - 'MOZ_NO_REMOTE': '1', 09:35:22 INFO - 'NO_EM_RESTART': '1', 09:35:22 INFO - 'PAGER': '/bin/cat', 09:35:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:35:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:22 INFO - 'PWD': '/builds/slave/test', 09:35:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:35:22 INFO - 'SHELL': '/bin/bash', 09:35:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x5kKNhbZMr/Listeners', 09:35:22 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 09:35:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:35:22 INFO - 'USER': 'cltbld', 09:35:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:35:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:35:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:22 INFO - 'XPC_FLAGS': '0x0', 09:35:22 INFO - 'XPC_SERVICE_NAME': '0', 09:35:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:35:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:35:22 INFO - Cleaning up... 09:35:22 INFO - Return code: 0 09:35:22 INFO - Running post-action listener: _resource_record_post_action 09:35:22 INFO - Running post-action listener: _start_resource_monitoring 09:35:22 INFO - Starting resource monitoring. 09:35:22 INFO - [mozharness: 2017-05-12 16:35:22.879391Z] Finished create-virtualenv step (success) 09:35:22 INFO - [mozharness: 2017-05-12 16:35:22.880387Z] Running install step. 09:35:22 INFO - Running pre-action listener: _resource_record_pre_action 09:35:22 INFO - Running main action method: install 09:35:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:35:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:35:23 INFO - Reading from file tmpfile_stdout 09:35:23 INFO - Detecting whether we're running mozinstall >=1.0... 09:35:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:35:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:35:23 INFO - Reading from file tmpfile_stdout 09:35:23 INFO - Output received: 09:35:23 INFO - Usage: mozinstall [options] installer 09:35:23 INFO - Options: 09:35:23 INFO - -h, --help show this help message and exit 09:35:23 INFO - -d DEST, --destination=DEST 09:35:23 INFO - Directory to install application into. [default: 09:35:23 INFO - "/builds/slave/test"] 09:35:23 INFO - --app=APP Application being installed. [default: firefox] 09:35:23 INFO - mkdir: /builds/slave/test/build/application 09:35:23 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'] 09:35:23 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 09:35:43 INFO - Reading from file tmpfile_stdout 09:35:43 INFO - Output received: 09:35:43 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:35:43 INFO - Running post-action listener: _resource_record_post_action 09:35:43 INFO - [mozharness: 2017-05-12 16:35:43.837391Z] Finished install step (success) 09:35:43 INFO - [mozharness: 2017-05-12 16:35:43.837522Z] Running run-tests step. 09:35:43 INFO - Running pre-action listener: _resource_record_pre_action 09:35:43 INFO - Running main action method: run_tests 09:35:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:35:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:35:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:35:43 INFO - 2017-05-12 09:35:43.852 screenresolution[1620:7245] starting screenresolution argv=screenresolution get 09:35:43 INFO - 2017-05-12 09:35:43.854 screenresolution[1620:7245] Display 0: 1600x1200x32@60 09:35:43 INFO - 2017-05-12 09:35:43.860 screenresolution[1621:7247] starting screenresolution argv=screenresolution list 09:35:43 INFO - Available Modes on Display 0 09:35:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:35:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:35:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:35:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:35:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:35:44 INFO - Intel Iris: 09:35:44 INFO - Chipset Model: Intel Iris 09:35:44 INFO - Type: GPU 09:35:44 INFO - Bus: Built-In 09:35:44 INFO - VRAM (Dynamic, Max): 1536 MB 09:35:44 INFO - Vendor: Intel (0x8086) 09:35:44 INFO - Device ID: 0x0a2e 09:35:44 INFO - Revision ID: 0x0009 09:35:44 INFO - Displays: 09:35:44 INFO - Display: 09:35:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:35:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:35:44 INFO - Display Serial Number: 1600x1200 60 09:35:44 INFO - Main Display: Yes 09:35:44 INFO - Mirror: Off 09:35:44 INFO - Online: Yes 09:35:44 INFO - Rotation: Supported 09:35:44 INFO - Return code: 0 09:35:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:35:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:35:44 INFO - Python 2.7.3 09:35:44 INFO - Return code: 0 09:35:44 INFO - grabbing minidump binary from tooltool 09:35:44 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')]} 09:35:44 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 0x109f31d30>, '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 0x109ee4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109f25be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:35:44 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 09:35:44 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 09:35:44 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 09:35:44 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:35:44 INFO - Return code: 0 09:35:44 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:35:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:35:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:35:44 INFO - ENV: RUST_BACKTRACE is now 1 09:35:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:35:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:35:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dlnsPqIIRw-eRDfwXc_Bhw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0358', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:35:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dlnsPqIIRw-eRDfwXc_Bhw/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0358 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:35:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yGbrCQgnxZ/Render', 09:35:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:35:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:35:44 INFO - 'HOME': '/Users/cltbld', 09:35:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:35:44 INFO - 'LOGNAME': 'cltbld', 09:35:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:35:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:35:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:44 INFO - 'MOZ_NO_REMOTE': '1', 09:35:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:35:44 INFO - 'NO_EM_RESTART': '1', 09:35:44 INFO - 'PAGER': '/bin/cat', 09:35:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:35:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:44 INFO - 'PWD': '/builds/slave/test', 09:35:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:35:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:35:44 INFO - 'RUST_BACKTRACE': '1', 09:35:44 INFO - 'SHELL': '/bin/bash', 09:35:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x5kKNhbZMr/Listeners', 09:35:44 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 09:35:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:35:44 INFO - 'USER': 'cltbld', 09:35:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:35:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:35:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:44 INFO - 'XPC_FLAGS': '0x0', 09:35:44 INFO - 'XPC_SERVICE_NAME': '0', 09:35:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:35:44 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dlnsPqIIRw-eRDfwXc_Bhw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0358', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:35:44 INFO - mozversion application_buildid: 20170512081708 09:35:44 INFO - mozversion application_changeset: 8a73de724f644192973e88b7685689b048023676 09:35:44 INFO - mozversion application_display_name: Nightly 09:35:44 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:35:44 INFO - mozversion application_name: Firefox 09:35:44 INFO - mozversion application_remotingname: firefox 09:35:44 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:35:44 INFO - mozversion application_vendor: Mozilla 09:35:44 INFO - mozversion application_version: 55.0a1 09:35:44 INFO - mozversion platform_buildid: 20170512081708 09:35:44 INFO - mozversion platform_changeset: 8a73de724f644192973e88b7685689b048023676 09:35:44 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:35:44 INFO - mozversion platform_version: 55.0a1 09:35:44 INFO - using testdate: 1494606944 09:35:44 INFO - actual date: 1494606944 09:35:44 INFO - starting webserver on 'localhost:49219' 09:35:44 INFO - SUITE-START | Running 2 tests 09:35:44 INFO - TEST-START | dromaeo_css 09:35:44 INFO - operating with platform_type : mac_ 09:35:44 INFO - Initialising browser for dromaeo_css test... 09:35:44 INFO - TEST-INFO | started process 1631 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/tmp56pZA8/profile http://localhost:49219/getInfo.html) 09:35:46 INFO - PID 1631 | 2017-05-12 09:35:46.866 plugin-container[1633:7426] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c47, name = 'com.apple.tsm.portname' 09:35:46 INFO - PID 1631 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:35:46 INFO - PID 1631 | 2017-05-12 09:35:46.869 plugin-container[1633:7426] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f0b, name = 'com.apple.CFPasteboardClient' 09:35:46 INFO - PID 1631 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:35:46 INFO - PID 1631 | 2017-05-12 09:35:46.869 plugin-container[1633:7426] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:35:46 INFO - PID 1631 | __metrics Screen width/height:1600/1200 09:35:46 INFO - PID 1631 | colorDepth:24 09:35:46 INFO - PID 1631 | Browser inner width/height: 1024/691 09:35:46 INFO - PID 1631 | __metrics 09:35:48 INFO - TEST-INFO | 1631: exit 0 09:35:48 INFO - Browser initialized. 09:35:48 INFO - Running cycle 1/1 for dromaeo_css test... 09:35:48 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/tmp56pZA8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:35:50 INFO - PID 1634 | 2017-05-12 09:35:50.229 plugin-container[1635:7556] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9343, name = 'com.apple.tsm.portname' 09:35:50 INFO - PID 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:35:50 INFO - PID 1634 | 2017-05-12 09:35:50.230 plugin-container[1635:7556] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x924b, name = 'com.apple.CFPasteboardClient' 09:35:50 INFO - PID 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:35:50 INFO - PID 1634 | 2017-05-12 09:35:50.230 plugin-container[1635:7556] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:35:50 INFO - PID 1634 | 2017-05-12 09:35:50.696 plugin-container[1636:7600] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 09:35:50 INFO - PID 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:35:50 INFO - PID 1634 | 2017-05-12 09:35:50.696 plugin-container[1636:7600] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980b, name = 'com.apple.CFPasteboardClient' 09:35:50 INFO - PID 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:35:50 INFO - PID 1634 | 2017-05-12 09:35:50.696 plugin-container[1636:7600] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:36:00 INFO - PID 1634 | RSS: Main: 243879936 09:36:00 INFO - PID 1634 | 09:39:03 INFO - PID 1634 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49219/tests/dromaeo/cssquery-ext.html) 09:39:05 INFO - PID 1634 | RSS: Main: 256528384 09:39:05 INFO - PID 1634 | 09:42:07 INFO - PID 1634 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-ext.html (next: http://localhost:49219/tests/dromaeo/cssquery-jquery.html) 09:42:07 INFO - PID 1634 | RSS: Main: 261857280 09:42:07 INFO - PID 1634 | 09:45:09 INFO - PID 1634 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49219/tests/dromaeo/cssquery-mootools.html) 09:45:10 INFO - PID 1634 | RSS: Main: 271462400 09:45:10 INFO - PID 1634 | 09:48:11 INFO - PID 1634 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49219/tests/dromaeo/cssquery-prototype.html) 09:48:12 INFO - PID 1634 | RSS: Main: 264212480 09:48:12 INFO - PID 1634 | 09:51:15 INFO - PID 1634 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49219/tests/dromaeo/cssquery-yui.html) 09:51:15 INFO - PID 1634 | RSS: Main: 268255232 09:51:15 INFO - PID 1634 | 09:54:17 INFO - PID 1634 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-yui.html (next: http://localhost:49219/tests/dromaeo/cssquery-dojo.html) 09:54:17 INFO - PID 1634 | RSS: Main: 268038144 09:54:17 INFO - PID 1634 | 09:54:17 INFO - PID 1634 | __start_tp_report 09:54:17 INFO - PID 1634 | _x_x_mozilla_page_load 09:54:17 INFO - PID 1634 | _x_x_mozilla_page_load_details 09:54:17 INFO - PID 1634 | |i|pagename|runs| 09:54:17 INFO - PID 1634 | |0;dojo.html;2958.7041295870404;3480.6867381935604;3521.225330427306;3582.373084710175;3590.2281009582935;4924.950750492494;5377.542908852747;6054.697265136748;6154.138420621117;6237.28271248806;4886.364772579568;6102.156009074801;6143.880361275353;6170.62976221427;6254.249490061187;4412.955870441303;5396;5418.241446197526;5420.4850539198715;5491.313585801775;5345.866353341178;5496.917546236789;5505.256790333294;5516;5528.778848846061;5426.321709786277;5446.400895901447;5477.863053423656;5481.260029895953;5512.614117011811;5413.242146099549;5423.728813559345;5426.430224826376;5444.101723215665;5459.508644222039;5366.651167674088;5385.380681221671;5404.648697834628;5449.073657478199;5487.149491828773;5294.311739473882;5489.64317319373;6133.51829027926;6138.449706860586;6250.6874656267;5506.834794956118;5543.916841247385;6584.76953306632;6601.6699165041555;6668.599884006975;6678.265390807007;6723.462123030145;6736.46108311334;6758.729650813962;6760.357766012221;6127.403330890814;6191.473724733448;6224.719887605069;6310.274318453345;6338.904916426257;6824.385805277449;6866.793996180123;6873.690683919235;6879.036934829128;6899.724011039602;5080.314157588752;5615;6317.46301564363;6389.872202555923;6391.201099862517;6168.259808822881;6500.512461565307;6563.737450501926;6682.465402767767;6705.2624211336715;5846.970765146147;6335.904961425582;6340.936590634127;6561.212654481397;6634.502412319095;6440.194975628046;6441.64570948593;6500.154979852589;6534.673266336664;6592.80221593353;5147.176451767774;5260.342457192851;5265.104932161542;5298.072837253465;5309.044372012998;4655.906881862344;4678.719276843401;4684.601808846286;4708.8351907683755;4739.004808990151;6145.539084568675;6235.345288744707;6244.843878903063;6265.592736472115;6298.653574053471;5388.488093631099;6690.866182676319;6712.798616041527;6714.832129196711;6727.226368967631;5912.674802885796;6230.719617617218;6263.342349053375;6267.467265282411;6299.937000630027;5140.871478213;5733.512651424592;5771.163181338682;5796.188533605298;5799.768009279666;6198.721057552511;6256.217972753406;6741.898871516931;6793.490488213502;6851.554648947803;6726.3609957054005;6760.695768690519;6764.154480689914;6773.796786096425;6777.186737591521;6599.7360105595235;6814.795556133324;6827.761028363983;6848.452123830181;6887.139107611549;6457.935420645734;6529.314421985623;6583.967080164569;6613.173353330833;6628.237752658313;6718.93281067183;6731.461483081243;6732.528722989344;6737.629430381376;6739.59562426256;6276.560640755103;6344.270408903035;6371.74513019474;6386.16979792624;6430.582012169194;6499;6508.9349106508325;6520.902186467301;6522.739090436329;6534.803955881332;6106.053561697944;6277.466415354746;6289.622622642656;6295.5907865988565;6375;6428.067930150196;6509.544331896911;6525.738970441129;6526.836829079121;6530.47756179514;5393.298871146726;6373.075903993988;6379.298277189413;6381.3937675920715;6412.198475190601 09:54:17 INFO - PID 1634 | |1;ext.html;761.8914479432423;793.1434051224678;815.2418251026546;1216.4769149265812;1288.3429450980009;39687.60312396875;40183.99999999996;40589;40843;41064.79467602661;33212.83393583035;33296.83351583239;41379.58620413795;41644.75065748033;41699.79150104253;7769;7936.960315198445;8269.21442462965;8436.915630843705;8513.404061715682;8453;8552.828943421127;8563.95718021409;8568.828623427527;8570.27152692022;8516.78708032301;8589.699360522383;8591.570421478933;8617.310615150804;8631.266342360906;8447.704330348439;8538.359623028267;8561.571921403967;8564.400491965565;8571.14288571141;7007.509474336798;8264.297534709556;8320.916790832074;8324.542150181798;8371.455855369382;37788.81105594454;37880.242395151945;38234.80882595597;38240.235195295936;38266.80866595677;3653.0319465341704;3659.8170091495585;3697.5378077740284;3702.4816525686424;3703.055720791208;30888.53667194994;30889;31085.844570777226;31115;31307.686923130706;3123.8281894495794;3139.5290706394226;3159.842007899596;3164.303853152303;3167.319026409333;298847;299159;312332.4383378091;319395.9999999977;322337;48824.511754882704;48865.02269954652;48940.51059489431;48944;49030.26454603184;46610.067798644515;51360;51689.483105169216;52540;56048.439515604325;14435.35040923161;14448.566543003726;14450.349734261983;14467.638309042148;14480.565583032527;11305.095592352593;11476.598319058792;11567.248128871597;11569.305841649528;11576.073914086854;12852.678683032998;13932.442702291997;14203.715925681636;14209.289535523183;14327.713445731028;5649.542449034683;6246.3753624637175;6250.468710159687;6288.025356069817;6305.274893387319;985.7880555680563;1009.3489699144158;1026.4457193115784;1056.3239526702916;1063.967951087444;23784.524309513712;24711.25866224016;24922.5015499689;24970.5005899881;25032.249032529053;1042.9061384475433;1125.6285425809463;1152.2567943676281;1157.386585109893;1162.511745067074;10665.573377064986;12005.879941200476;12466.75066498665;12626.873731262569;12801.743965120831;274225;343894;347601;347811;348774;55455;55599.61000975008;55729;55940.44059559352;56025.7198714012;1280.3214296422912;1364.836219653628;1549.1170033081034;1560.531840447861;1586.7937168168064;20717.482062948548;23355.299341019796;23623.763762362156;24375.634365484886;24502.26493205207;38671;38691.613083868804;38961;39005.80497097496;39027;286449.7615309996;286607;287126;287587;288161;35839.82080089687;36184.63815361918;36368.09079773027;36398.81800591086;36413.725519607746;44898.77550612226;45596.08807823758;46449.83875403007;46515.76742116268;46725.065498690514;24284.635730463702;29779.55330670085;30957.380852383278;31660.525092123175;32172.83913580417;30595.623196955745;31235.843820780752;31455.213619658774;31598.684013159575;31963.36073278568;37576.43635345417;37929.43105853359;38221.80889095537;38267.808660956514;38276.42585361166;30305.69694303029;32188.517172241965;32452.83773581117;32954;33339.66660333366;19738.901305493382;20688.17247310091;21522.461938451663;23392;25076.62385064262 09:54:17 INFO - PID 1634 | |2;jquery.html;345.0889651320513;422.9492460904692;423.7457525484709;432.54150600363636;453.5668436643004;16022;19489.902550487244;21876.671849922262;22899.31302060941;23806.64290035651;22782.97476613552;22911.312660620166;23199;23600.645990310117;23625.527489450244;5525.226468294442;6202.78290259841;6215.316315205324;6278.6232826030355;6285.748570057215;5152.484751524837;5892.322382925954;6132.724027418777;6177.907331390033;6191.907121393183;5977.282726072877;6044.546659000571;6109.938900611004;6117.143599896018;6117.694115294217;5979.850503737398;6081.878362432749;6087.421694938974;6097.176881121059;6102.9694851525455;5370.677759334413;5392.272043274147;5439.347278326626;5455.263539422167;5456.072467680504;28037;28060.71939280622;28248.85875570609;28725.712742872514;29166;2154.407537927067;2215.5679642469727;2219.1789038055863;2219.2343641443676;2223.5997520446313;17711.025893575847;17917.283308667636;18125.18436670353;18534.351297704514;18730.250789968384;2133.4453042208984;2136.134865379508;2159.8164156046755;2163.5132095278595;2180.9890950545305;128320;146003;164336;167733;169260.3073969257;11052.065888469486;12576.937115314548;12958.509771376224;13165.934170329087;13194.736105277841;16677.999320040835;16954.91522542404;16978;17064.57338566546;17070.23183956725;7346.6693998769115;8754.299656027502;8830;9033.728988130366;9044;8429.957850210709;8435.11431299717;8456.704015359555;8597.850537365659;8617.827643447095;6595.70319335631;8007.559584222924;8081.434299598972;8096.392770542233;8155.714549990722;2172.891355432222;2196.5826492966285;2200.8129309008596;2203.5262418580082;2381.4284571702665;911.6900253913568;937.7340590203171;953.952302384878;989.1196834817018;1017.6183931025865;15526.689466210613;16152.353905843634;20738.274160404315;21435.035423405985;21835.563288734455;874.790050387915;995.9352642078387;1002.5338217728792;1019.7501612105082;1022.1924679503257;10759.78480430387;11125.332480051062;11164.051055660197;11177.32936023825;11177.329360238411;563977;568707;569400;576541;577885;135321;139796.31120288483;142395;143466;146399.26800366142;1153.5674122204173;1173.9941300293444;1205.9879401206051;1262.0471543984233;1266.1326991011188;18301;21140.365789026353;22520.324390268317;22647.433814154778;22728.65907011363;48155.75922120507;51848.96302074013;56047.15929260983;56336.71831640816;56579.71710141423;67728.98406524005;67733.66133169303;68787.65606171938;69975;71205;8976.640934362553;9076.863847042432;9128;9143.908560914306;9197.712320275301;13972.720545589234;14131.434742610592;14486.927565362106;14541.41834326615;14678.412863485768;11891.524339026688;12042.397880106135;12106;12224.327661978321;12342.691432714253;9666.565004574952;11291.604793832177;11307.434628268718;11330;11456.026237769549;11790;12079.094067945114;12221.755564888475;12388.955822289221;12508.186967847061;8449.324054075782;8812.691555795516;8815.162559556831;8890.911090889267;8921.286297096087;11878.762424751629;11954.701132471759;12067.939660301643;12086.093542984136;12158.331291779037 09:54:17 INFO - PID 1634 | |3;mootools.html;1408.3873515020964;1460.0874453466577;1461.7120924358885;1477.1727832413849;1492.0749135535968;11764.647060588175;12144.028477721784;12183.025357971363;12201.206921550103;12475.064370172251;10150.593976240942;12198.756024879496;12378.319192444413;12401.945834604046;12459.377031148451;2228.799408053275;2589.650397196378;2632.078772429644;2637.9076732314375;2640.498305321992;2554.0932968796087;2579.3551612096976;2608.3870290481723;2608.595667671514;2616;2477.6531285620026;2556.194798638423;2565.961510577343;2611.7518835710575;2615.163147792707;2260.954780904381;2460.3480077779404;2534.0623969131448;2559.846409215453;2579.6775403074616;2550.923472295834;2554.2592648132018;2584.806139539542;2589.1973488218523;2589.7021842488166;11573.305601663842;11814.940925295405;11819.704507387383;11821.34982575958;11824.645260642195;6683.298253683391;6899.206591242021;6924.446044316443;6929.896051559231;6945.72217111315;4192.842117313733;4305.246581848164;4333.9133217335475;4454.153710794939;4469.284914413711;1228.686684895346;1237.257645412754;1288.239938436327;1288.375138058041;1295.436485128972;16515.17424128789;16598.917005415016;16771.664566708598;16846.578835529086;16960.3215871365;5619.381867994517;5700.173474846124;5796.94203057964;5832.3292821326095;5881;11935.462904169159;12053.63839084829;12056.819147712791;12064.879351206375;12090.972267357198;6437.452816510556;6588.802335929834;6703.4972377070935;6704.698288577026;6712.228093769181;6108.877822443526;6148.323684394713;6221.751129954841;6302.65335406557;6314.084457753692;4448.043670610834;4490.416245888079;4504.09918016398;4512.571305725951;4562.954370456319;1295.844498660167;1312.783390740517;1321.0950498908278;1330.2949436798513;1336.9197848129143;785.7721260834294;794.5533368757202;802.7752229375667;805.6777289084413;822.198356602308;4605.65457590682;5696.689761354829;5729.7708091676695;6183.938160618336;6461.095446637474;524.9291345668362;592.4608606168453;594.4352864778477;603.6378173096107;611.9204503414617;3724.5530536335814;4158.688098392572;4322.589354011364;4374.803133858984;4512.977435112869;14925.402983880524;15241.618959525878;15286.617834554225;15355.155466449229;15400.383984640714;11318.641762988495;12090.758184836253;15385.692286154439;15386.692266154838;15410.460633877985;7130.786076417716;7949.841003179904;8459.619317130744;8568;8696.869546956801;6831.9658401707675;6967.338102880219;7153.535020223669;7175.605341706257;7353.154387245535;13331.733365332833;13602.52391166344;13686.589402317946;14004.229767362893;14030.649233769238;13793.655158621115;13812.378442970292;13991.16053036821;14014.299285035911;14211.64470888236;9306.534673266175;9433.24534037289;9442.38624489406;9458.054194580762;9518.190953769008;10858.945705271422;11390.601328953448;11478.713032174262;11484.655460336204;11547.884521154681;9207.723768286962;9218.9539052307;9366.2038726709;9459.810803784023;9476.194523465583;8100.513969161869;9164.129407706258;9244.075592440702;9518.381305215138;9524.809503810024;8222.054463736507;8325.834383186238;8345.374096942633;8429.87355189685;8443.620037098468;5281.287026251407;6193.194884665145;6194.318624951162;6222.128901953821;6333.461655759312;6144.38556144382;6151.878241578064;6251.812445626639;6384.489240860813;6451.032345148265 09:54:17 INFO - PID 1634 | |4;prototype.html;374.7863717680923;393.80506649208644;399.3510545363784;401.13755425834455;401.9397090436434;4730.959188978423;5994.490468310193;6112.541559383042;6174.259088909332;6394.337472257212;5183.108165519587;6139.907901381483;6187.907181392283;6211.9068213976825;6239.34486878878;4523.231050721377;4523.909521809578;4529.501754806968;4538.568835960578;4559.863204103882;3855.664849904826;4363.781810909441;4395.692301538894;4469.486009108945;4496.505384407713;4447.798339821793;4451.844185453494;4464.441944756905;4483.237849565582;4484.372187893698;4459.843905463309;4495.280755079171;4499.932501012471;4501.482329532113;4513.142502924434;3127.56214130022;3580.6751501944186;3826.9425958610364;3857.24783667185;3864.768113913174;4788;4943.813484763631;5668.716564171775;6083.783243351348;6115.663638499881;4421.624161946238;4865.537773911473;5124.692518448867;5391.353037635469;5425.3489581250105;6489.805305840832;6533.314002029766;6569.93430065698;6605.240397354318;6629.900551491732;4324.545922678105;4334.5882141196535;4334.804933777956;4340.370646256307;4348.434703488558;6797.320267973212;6967.780638388262;7007.964960175166;7071.1868135164605;7128.501004929605;3804.6575808177395;4269.7224680395675;4340.956590434118;4380.233459144701;4421.491528474202;3727.254549090193;3803.733738638324;4002.7198096132993;4277.807498662568;4523.615492683093;4461.152381047591;4594.747288899076;4914.65597408178;5297.8145764898045;5308.203769434538;6632.502562307846;6634.436072933758;6641.103451034145;6668.366505182;6706.765263215764;1349.3537883781771;1514.6364872430759;1522.3453914816514;1526.114853385034;1532.632168279605;870.0472982084607;905.542700936023;996.8685541909839;1002.7292630989592;1003.7239759066315;4805.327254184417;5287.788488460418;6296.590721603081;6349.174607301022;6358.395952384516;5994.610350327215;6655.43428808547;6697.6986035628515;6782.796516104525;6970.895436568456;5379.901838283073;6358.173437453194;6381.298057213703;6435.227772667216;6502.739890404331;2890.985545072261;3135.263213144909;3305.1406634275263;3524.3656141894194;3536.8231588420476;6538.901916471256;6824.771541122645;6847.349501797321;7123.323284287984;7220.350168484756;6798.762043328558;7197.604131772803;7252.461638426508;7253.274672532704;7273.745418910313;6873.690683919235;7065.611391373421;7159.243691178829;7167.713291468387;7173.605451700207;6964.756233531802;6966.5471744336455;6973.755918542826;7002.544834585736;7007.40437062835;6456.515761317826;6459.321771213955;6645.069690243273;6662.56693314933;6932.861342773015;5737.799177028951;6971.128608923885;7174.533655312597;7188.784336469915;7216.097987751531;3662.3957047086933;3785.2240290739783;4384.386185933908;4469.240229160985;4470.776461176863;6429.774957876639;6686.431653309329;6751.852185128638;6996.0905082341005;7047.647617618996;6677;6682.298358672269;6684.665766711548;6697.856685860673;6705.765298214539;6510.21877374718;6605.53761236728;6638.269790323157;6654.73381064752;6695.12963314766;5828.825135245949;6369.172007639163;6519.869602608016;6524.706388212637;6589.670516474253;6525.3800888915475;6578.828986434561;6681.365270299314;6683.966580167068;6710.530262881747;5877.147813567094;5960.314563825215;6498.415142637185;6502.479801615954;6544.447665410398 09:54:17 INFO - PID 1634 | |5;yui.html;698.3191388396314;809.134226377776;819.5492479136477;845.7631863078336;849.2187187787237;14416.279186040681;15030.17334046627;16230.594235144112;16660.66678666426;16772.58068548287;16353.182340882968;16456.094914779682;16950.660986780258;17597.648047039085;17621.383251586198;983.0464449483989;1000.0799264676479;1001.3691374434096;1001.529281237817;1004.3672486333636;1020.1175982774884;1024.1857723110109;1025.0467065628961;1025.2208321675505;1027.6146445083093;932.9533523323806;1049.716576524338;1055.171690223175;1057.989619912985;1064.669952314785;1030.3354336452983;1049.0506091986765;1052.2844465763278;1052.3159946035062;1055.3140458701828;755.6599530211427;771.4137255685663;777.0481160578246;777.3780975219858;780.75406247032;16880.240389182516;17045.82954170455;17220.82779172217;17380.913095434444;17615.207315670697;17100.31598736049;17262.56843578908;17609;17620.559486012826;17759.28962841485;14156.716865662629;14379.424823007068;14471.276436178148;14590.854091459161;14600.561983140522;3541.628129046439;3542.557180352456;3544.131687736495;3564.554430696163;3566.696830769388;132990.6700932988;134803.32598337045;136581.3170934139;136814.31592842072;137280.31359843237;10499.422531760827;11520.481578328996;11862.347570883685;13094.14888032275;13232;24736.25791226266;24737.5052498949;25155.62266566003;25258.873705631355;25400.618990715153;7660.387169026464;7883.093444253869;7908.683652653829;7935.523868567904;7937.801554961056;3415.52182694423;3470.35798377301;3473.114355839245;3477.6174620791694;3792.544894612609;3579.463080537888;4131.628153466202;4247.532771395144;4269.978650106792;4274.230638485103;900.5587262241454;938.9342746007851;939.3377668743519;941.0730430525927;944.475815922156;528.2472476720658;645.2353960556756;646.9616265893268;657.7533424965637;678.782789507353;9121.36150469474;10300.381977081399;10835.295705779099;11830.645080647595;12075.154739168174;635.1203583037526;647.155462121926;665.7303791964263;675.562061700679;679.9252082270946;5256.763445644955;5399.649022813504;5487.259220005328;5525.005499010136;5588.2455868458055;101228;102650;103109;104157.47921260346;104791;93917;95470;96089.51955240179;98213;98440.50779746055;64043.35956640467;64720.352796472376;65084.674576626814;65370.69258614801;65722;10524.421156836448;10553.788924221626;12412.007039437003;12768.808467872803;12878.227306361649;32009.35981280408;40055;40474.19051618892;40670.186596268504;41062.58937410707;58380;65949.68100638056;72981;74415.255847443;74470.62764686142;16299.614532764848;16647.583810404838;17180.484585462455;17350.91324543369;17447.38934137299;14320.713585728436;18109;18493.81506184921;18699.439016829518;19010.524736881136;17035.48893533196;17097.914510427367;17461.738073928645;17508.737368939732;17777.111144442984;16926.32294708198;17375.47873563795;17617.735733963746;17878.5530361742;18030.36893708714;16366.345346186019;17119.57201069983;17249.137543123044;17369;17580.912095439442;10950.616728414787;11412.600558980395;11575.826362604399;12032.458539365925;12037.157398981988;11506.94246528762;11820.940895295469;12012.939935300268;12167.634970950885;12196.268223906594 09:54:17 INFO - PID 1634 | __end_tp_report 09:54:17 INFO - PID 1634 | __start_cc_report 09:54:17 INFO - PID 1634 | _x_x_mozilla_cycle_collect,415 09:54:17 INFO - PID 1634 | __end_cc_report 09:54:17 INFO - PID 1634 | __startTimestamp1494608057891__endTimestamp 09:54:17 INFO - PID 1634 | 09:54:17 INFO - PID 1634 | ------- Summary: start ------- 09:54:17 INFO - PID 1634 | Number of tests: 6 09:54:17 INFO - PID 1634 | 09:54:17 INFO - PID 1634 | [#0] dojo.html Cycles:165 Average:6032.15 Median:6276.56 stddev:752.54 (12.0%) stddev-sans-first:715.18 09:54:17 INFO - PID 1634 | Values: 2958.7 3480.7 3521.2 3582.4 3590.2 4925.0 5377.5 6054.7 6154.1 6237.3 4886.4 6102.2 6143.9 6170.6 6254.2 4413.0 5396.0 5418.2 5420.5 5491.3 5345.9 5496.9 5505.3 5516.0 5528.8 5426.3 5446.4 5477.9 5481.3 5512.6 5413.2 5423.7 5426.4 5444.1 5459.5 5366.7 5385.4 5404.6 5449.1 5487.1 5294.3 5489.6 6133.5 6138.4 6250.7 5506.8 5543.9 6584.8 6601.7 6668.6 6678.3 6723.5 6736.5 6758.7 6760.4 6127.4 6191.5 6224.7 6310.3 6338.9 6824.4 6866.8 6873.7 6879.0 6899.7 5080.3 5615.0 6317.5 6389.9 6391.2 6168.3 6500.5 6563.7 6682.5 6705.3 5847.0 6335.9 6340.9 6561.2 6634.5 6440.2 6441.6 6500.2 6534.7 6592.8 5147.2 5260.3 5265.1 5298.1 5309.0 4655.9 4678.7 4684.6 4708.8 4739.0 6145.5 6235.3 6244.8 6265.6 6298.7 5388.5 6690.9 6712.8 6714.8 6727.2 5912.7 6230.7 6263.3 6267.5 6299.9 5140.9 5733.5 5771.2 5796.2 5799.8 6198.7 6256.2 6741.9 6793.5 6851.6 6726.4 6760.7 6764.2 6773.8 6777.2 6599.7 6814.8 6827.8 6848.5 6887.1 6457.9 6529.3 6584.0 6613.2 6628.2 6718.9 6731.5 6732.5 6737.6 6739.6 6276.6 6344.3 6371.7 6386.2 6430.6 6499.0 6508.9 6520.9 6522.7 6534.8 6106.1 6277.5 6289.6 6295.6 6375.0 6428.1 6509.5 6525.7 6526.8 6530.5 5393.3 6373.1 6379.3 6381.4 6412.2 09:54:17 INFO - PID 1634 | 09:54:17 INFO - PID 1634 | [#1] ext.html Cycles:180 Average:46343.99 Median:347706.00 stddev:81666.07 (23.5%) stddev-sans-first:81823.45 09:54:17 INFO - PID 1634 | Values: 761.9 793.1 815.2 1216.5 1288.3 39687.6 40184.0 40589.0 40843.0 41064.8 33212.8 33296.8 41379.6 41644.8 41699.8 7769.0 7937.0 8269.2 8436.9 8513.4 8453.0 8552.8 8564.0 8568.8 8570.3 8516.8 8589.7 8591.6 8617.3 8631.3 8447.7 8538.4 8561.6 8564.4 8571.1 7007.5 8264.3 8320.9 8324.5 8371.5 37788.8 37880.2 38234.8 38240.2 38266.8 3653.0 3659.8 3697.5 3702.5 3703.1 30888.5 30889.0 31085.8 31115.0 31307.7 3123.8 3139.5 3159.8 3164.3 3167.3 298847.0 299159.0 312332.4 319396.0 322337.0 48824.5 48865.0 48940.5 48944.0 49030.3 46610.1 51360.0 51689.5 52540.0 56048.4 14435.4 14448.6 14450.3 14467.6 14480.6 11305.1 11476.6 11567.2 11569.3 11576.1 12852.7 13932.4 14203.7 14209.3 14327.7 5649.5 6246.4 6250.5 6288.0 6305.3 985.8 1009.3 1026.4 1056.3 1064.0 23784.5 24711.3 24922.5 24970.5 25032.2 1042.9 1125.6 1152.3 1157.4 1162.5 10665.6 12005.9 12466.8 12626.9 12801.7 274225.0 343894.0 347601.0 347811.0 348774.0 55455.0 55599.6 55729.0 55940.4 56025.7 1280.3 1364.8 1549.1 1560.5 1586.8 20717.5 23355.3 23623.8 24375.6 24502.3 38671.0 38691.6 38961.0 39005.8 39027.0 286449.8 286607.0 287126.0 287587.0 288161.0 35839.8 36184.6 36368.1 36398.8 36413.7 44898.8 45596.1 46449.8 46515.8 46725.1 24284.6 29779.6 30957.4 31660.5 32172.8 30595.6 31235.8 31455.2 31598.7 31963.4 37576.4 37929.4 38221.8 38267.8 38276.4 30305.7 32188.5 32452.8 32954.0 33339.7 19738.9 20688.2 21522.5 23392.0 25076.6 09:54:17 INFO - PID 1634 | 09:54:17 INFO - PID 1634 | [#2] jquery.html Cycles:180 Average:36304.12 Median:22841.14 stddev:96856.56 (424.0%) stddev-sans-first:97090.64 09:54:17 INFO - PID 1634 | Values: 345.1 422.9 423.7 432.5 453.6 16022.0 19489.9 21876.7 22899.3 23806.6 22783.0 22911.3 23199.0 23600.6 23625.5 5525.2 6202.8 6215.3 6278.6 6285.7 5152.5 5892.3 6132.7 6177.9 6191.9 5977.3 6044.5 6109.9 6117.1 6117.7 5979.9 6081.9 6087.4 6097.2 6103.0 5370.7 5392.3 5439.3 5455.3 5456.1 28037.0 28060.7 28248.9 28725.7 29166.0 2154.4 2215.6 2219.2 2219.2 2223.6 17711.0 17917.3 18125.2 18534.4 18730.3 2133.4 2136.1 2159.8 2163.5 2181.0 128320.0 146003.0 164336.0 167733.0 169260.3 11052.1 12576.9 12958.5 13165.9 13194.7 16678.0 16954.9 16978.0 17064.6 17070.2 7346.7 8754.3 8830.0 9033.7 9044.0 8430.0 8435.1 8456.7 8597.9 8617.8 6595.7 8007.6 8081.4 8096.4 8155.7 2172.9 2196.6 2200.8 2203.5 2381.4 911.7 937.7 954.0 989.1 1017.6 15526.7 16152.4 20738.3 21435.0 21835.6 874.8 995.9 1002.5 1019.8 1022.2 10759.8 11125.3 11164.1 11177.3 11177.3 563977.0 568707.0 569400.0 576541.0 577885.0 135321.0 139796.3 142395.0 143466.0 146399.3 1153.6 1174.0 1206.0 1262.0 1266.1 18301.0 21140.4 22520.3 22647.4 22728.7 48155.8 51849.0 56047.2 56336.7 56579.7 67729.0 67733.7 68787.7 69975.0 71205.0 8976.6 9076.9 9128.0 9143.9 9197.7 13972.7 14131.4 14486.9 14541.4 14678.4 11891.5 12042.4 12106.0 12224.3 12342.7 9666.6 11291.6 11307.4 11330.0 11456.0 11790.0 12079.1 12221.8 12389.0 12508.2 8449.3 8812.7 8815.2 8890.9 8921.3 11878.8 11954.7 12067.9 12086.1 12158.3 09:54:17 INFO - PID 1634 | 09:54:17 INFO - PID 1634 | [#3] mootools.html Cycles:180 Average:7198.36 Median:3941.62 stddev:4565.98 (115.8%) stddev-sans-first:4558.06 09:54:17 INFO - PID 1634 | Values: 1408.4 1460.1 1461.7 1477.2 1492.1 11764.6 12144.0 12183.0 12201.2 12475.1 10150.6 12198.8 12378.3 12401.9 12459.4 2228.8 2589.7 2632.1 2637.9 2640.5 2554.1 2579.4 2608.4 2608.6 2616.0 2477.7 2556.2 2566.0 2611.8 2615.2 2261.0 2460.3 2534.1 2559.8 2579.7 2550.9 2554.3 2584.8 2589.2 2589.7 11573.3 11814.9 11819.7 11821.3 11824.6 6683.3 6899.2 6924.4 6929.9 6945.7 4192.8 4305.2 4333.9 4454.2 4469.3 1228.7 1237.3 1288.2 1288.4 1295.4 16515.2 16598.9 16771.7 16846.6 16960.3 5619.4 5700.2 5796.9 5832.3 5881.0 11935.5 12053.6 12056.8 12064.9 12091.0 6437.5 6588.8 6703.5 6704.7 6712.2 6108.9 6148.3 6221.8 6302.7 6314.1 4448.0 4490.4 4504.1 4512.6 4563.0 1295.8 1312.8 1321.1 1330.3 1336.9 785.8 794.6 802.8 805.7 822.2 4605.7 5696.7 5729.8 6183.9 6461.1 524.9 592.5 594.4 603.6 611.9 3724.6 4158.7 4322.6 4374.8 4513.0 14925.4 15241.6 15286.6 15355.2 15400.4 11318.6 12090.8 15385.7 15386.7 15410.5 7130.8 7949.8 8459.6 8568.0 8696.9 6832.0 6967.3 7153.5 7175.6 7353.2 13331.7 13602.5 13686.6 14004.2 14030.6 13793.7 13812.4 13991.2 14014.3 14211.6 9306.5 9433.2 9442.4 9458.1 9518.2 10858.9 11390.6 11478.7 11484.7 11547.9 9207.7 9219.0 9366.2 9459.8 9476.2 8100.5 9164.1 9244.1 9518.4 9524.8 8222.1 8325.8 8345.4 8429.9 8443.6 5281.3 6193.2 6194.3 6222.1 6333.5 6144.4 6151.9 6251.8 6384.5 6451.0 09:54:17 INFO - PID 1634 | 09:54:17 INFO - PID 1634 | [#4] prototype.html Cycles:180 Average:5322.29 Median:6181.08 stddev:1762.65 (28.5%) stddev-sans-first:1728.04 09:54:17 INFO - PID 1634 | Values: 374.8 393.8 399.4 401.1 401.9 4731.0 5994.5 6112.5 6174.3 6394.3 5183.1 6139.9 6187.9 6211.9 6239.3 4523.2 4523.9 4529.5 4538.6 4559.9 3855.7 4363.8 4395.7 4469.5 4496.5 4447.8 4451.8 4464.4 4483.2 4484.4 4459.8 4495.3 4499.9 4501.5 4513.1 3127.6 3580.7 3826.9 3857.2 3864.8 4788.0 4943.8 5668.7 6083.8 6115.7 4421.6 4865.5 5124.7 5391.4 5425.3 6489.8 6533.3 6569.9 6605.2 6629.9 4324.5 4334.6 4334.8 4340.4 4348.4 6797.3 6967.8 7008.0 7071.2 7128.5 3804.7 4269.7 4341.0 4380.2 4421.5 3727.3 3803.7 4002.7 4277.8 4523.6 4461.2 4594.7 4914.7 5297.8 5308.2 6632.5 6634.4 6641.1 6668.4 6706.8 1349.4 1514.6 1522.3 1526.1 1532.6 870.0 905.5 996.9 1002.7 1003.7 4805.3 5287.8 6296.6 6349.2 6358.4 5994.6 6655.4 6697.7 6782.8 6970.9 5379.9 6358.2 6381.3 6435.2 6502.7 2891.0 3135.3 3305.1 3524.4 3536.8 6538.9 6824.8 6847.3 7123.3 7220.4 6798.8 7197.6 7252.5 7253.3 7273.7 6873.7 7065.6 7159.2 7167.7 7173.6 6964.8 6966.5 6973.8 7002.5 7007.4 6456.5 6459.3 6645.1 6662.6 6932.9 5737.8 6971.1 7174.5 7188.8 7216.1 3662.4 3785.2 4384.4 4469.2 4470.8 6429.8 6686.4 6751.9 6996.1 7047.6 6677.0 6682.3 6684.7 6697.9 6705.8 6510.2 6605.5 6638.3 6654.7 6695.1 5828.8 6369.2 6519.9 6524.7 6589.7 6525.4 6578.8 6681.4 6684.0 6710.5 5877.1 5960.3 6498.4 6502.5 6544.4 09:54:17 INFO - PID 1634 | 09:54:17 INFO - PID 1634 | [#5] yui.html Cycles:180 Average:22023.26 Median:17616.47 stddev:31621.21 (179.5%) stddev-sans-first:31669.37 09:54:17 INFO - PID 1634 | Values: 698.3 809.1 819.5 845.8 849.2 14416.3 15030.2 16230.6 16660.7 16772.6 16353.2 16456.1 16950.7 17597.6 17621.4 983.0 1000.1 1001.4 1001.5 1004.4 1020.1 1024.2 1025.0 1025.2 1027.6 933.0 1049.7 1055.2 1058.0 1064.7 1030.3 1049.1 1052.3 1052.3 1055.3 755.7 771.4 777.0 777.4 780.8 16880.2 17045.8 17220.8 17380.9 17615.2 17100.3 17262.6 17609.0 17620.6 17759.3 14156.7 14379.4 14471.3 14590.9 14600.6 3541.6 3542.6 3544.1 3564.6 3566.7 132990.7 134803.3 136581.3 136814.3 137280.3 10499.4 11520.5 11862.3 13094.1 13232.0 24736.3 24737.5 25155.6 25258.9 25400.6 7660.4 7883.1 7908.7 7935.5 7937.8 3415.5 3470.4 3473.1 3477.6 3792.5 3579.5 4131.6 4247.5 4270.0 4274.2 900.6 938.9 939.3 941.1 944.5 528.2 645.2 647.0 657.8 678.8 9121.4 10300.4 10835.3 11830.6 12075.2 635.1 647.2 665.7 675.6 679.9 5256.8 5399.6 5487.3 5525.0 5588.2 101228.0 102650.0 103109.0 104157.5 104791.0 93917.0 95470.0 96089.5 98213.0 98440.5 64043.4 64720.4 65084.7 65370.7 65722.0 10524.4 10553.8 12412.0 12768.8 12878.2 32009.4 40055.0 40474.2 40670.2 41062.6 58380.0 65949.7 72981.0 74415.3 74470.6 16299.6 16647.6 17180.5 17350.9 17447.4 14320.7 18109.0 18493.8 18699.4 19010.5 17035.5 17097.9 17461.7 17508.7 17777.1 16926.3 17375.5 17617.7 17878.6 18030.4 16366.3 17119.6 17249.1 17369.0 17580.9 10950.6 11412.6 11575.8 12032.5 12037.2 11506.9 11820.9 12012.9 12167.6 12196.3 09:54:17 INFO - PID 1634 | -------- Summary: end -------- 09:54:17 INFO - PID 1634 | 09:54:18 INFO - TEST-INFO | 1634: exit 0 09:54:18 INFO - TEST-OK | dromaeo_css | took 1114085ms 09:54:18 INFO - TEST-START | kraken 09:54:18 INFO - operating with platform_type : mac_ 09:54:18 INFO - Initialising browser for kraken test... 09:54:18 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/tmpaGB_Xk/profile http://localhost:49219/getInfo.html) 09:54:20 INFO - PID 1648 | 2017-05-12 09:54:20.916 plugin-container[1649:10011] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 09:54:20 INFO - PID 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:54:20 INFO - PID 1648 | 2017-05-12 09:54:20.919 plugin-container[1649:10011] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 09:54:20 INFO - PID 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:54:20 INFO - PID 1648 | 2017-05-12 09:54:20.919 plugin-container[1649:10011] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:54:21 INFO - PID 1648 | __metrics Screen width/height:1600/1200 09:54:21 INFO - PID 1648 | colorDepth:24 09:54:21 INFO - PID 1648 | Browser inner width/height: 1024/691 09:54:21 INFO - PID 1648 | __metrics 09:54:22 INFO - TEST-INFO | 1648: exit 0 09:54:22 INFO - Browser initialized. 09:54:22 INFO - Running cycle 1/1 for kraken test... 09:54:22 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/tmpaGB_Xk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:54:24 INFO - PID 1650 | 2017-05-12 09:54:24.463 plugin-container[1651:10135] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x923f, name = 'com.apple.tsm.portname' 09:54:24 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:54:24 INFO - PID 1650 | 2017-05-12 09:54:24.464 plugin-container[1651:10135] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a03, name = 'com.apple.CFPasteboardClient' 09:54:24 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:54:24 INFO - PID 1650 | 2017-05-12 09:54:24.464 plugin-container[1651:10135] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:54:24 INFO - PID 1650 | 2017-05-12 09:54:24.888 plugin-container[1652:10179] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 09:54:24 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:54:24 INFO - PID 1650 | 2017-05-12 09:54:24.888 plugin-container[1652:10179] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9707, name = 'com.apple.CFPasteboardClient' 09:54:24 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:54:24 INFO - PID 1650 | 2017-05-12 09:54:24.888 plugin-container[1652:10179] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:54:34 INFO - PID 1650 | RSS: Main: 245252096 09:54:34 INFO - PID 1650 | 09:54:37 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-beat-detection) 09:54:38 INFO - PID 1650 | RSS: Main: 255074304 09:54:38 INFO - PID 1650 | 09:54:40 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-dft) 09:54:41 INFO - PID 1650 | RSS: Main: 261976064 09:54:41 INFO - PID 1650 | 09:54:44 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-fft) 09:54:45 INFO - PID 1650 | RSS: Main: 258297856 09:54:45 INFO - PID 1650 | 09:54:47 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-oscillator) 09:54:47 INFO - PID 1650 | RSS: Main: 260644864 09:54:47 INFO - PID 1650 | 09:54:49 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49219/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:54:50 INFO - PID 1650 | RSS: Main: 260964352 09:54:50 INFO - PID 1650 | 09:54:53 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49219/tests/kraken/driver.html?testName=imaging-darkroom) 09:54:54 INFO - PID 1650 | RSS: Main: 266641408 09:54:54 INFO - PID 1650 | 09:54:57 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49219/tests/kraken/driver.html?testName=imaging-desaturate) 09:54:58 INFO - PID 1650 | RSS: Main: 267714560 09:54:58 INFO - PID 1650 | 09:55:00 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49219/tests/kraken/driver.html?testName=json-parse-financial) 09:55:01 INFO - PID 1650 | RSS: Main: 267591680 09:55:01 INFO - PID 1650 | 09:55:03 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49219/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:55:03 INFO - PID 1650 | RSS: Main: 267472896 09:55:03 INFO - PID 1650 | 09:55:05 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:55:06 INFO - PID 1650 | RSS: Main: 268120064 09:55:06 INFO - PID 1650 | 09:55:08 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:55:09 INFO - PID 1650 | RSS: Main: 267628544 09:55:09 INFO - PID 1650 | 09:55:11 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:55:12 INFO - PID 1650 | RSS: Main: 267788288 09:55:12 INFO - PID 1650 | 09:55:15 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:55:16 INFO - PID 1650 | RSS: Main: 267845632 09:55:16 INFO - PID 1650 | 09:55:18 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49219/tests/kraken/driver.html?testName=ai-astar) 09:55:18 INFO - PID 1650 | RSS: Main: 269840384 09:55:18 INFO - PID 1650 | 09:55:18 INFO - PID 1650 | __start_tp_report 09:55:18 INFO - PID 1650 | _x_x_mozilla_page_load 09:55:18 INFO - PID 1650 | _x_x_mozilla_page_load_details 09:55:18 INFO - PID 1650 | |i|pagename|runs| 09:55:18 INFO - PID 1650 | |0;ai-astar;102;131;168;159;115;111;114;117;114;128 09:55:18 INFO - PID 1650 | |1;audio-beat-detection;178;147;114;154;111;112;110;122;136;132 09:55:18 INFO - PID 1650 | |2;audio-dft;199;191;209;216;228;199;233;294;196;196 09:55:18 INFO - PID 1650 | |3;audio-fft;84;77;58;83;52;57;63;75;97;87 09:55:18 INFO - PID 1650 | |4;audio-oscillator;79;68;96;75;69;68;105;103;107;81 09:55:18 INFO - PID 1650 | |5;imaging-gaussian-blur;110;90;91;91;91;102;102;91;90;90 09:55:18 INFO - PID 1650 | |6;imaging-darkroom;154;153;154;97;98;98;97;97;129;109 09:55:18 INFO - PID 1650 | |7;imaging-desaturate;90;94;90;92;94;92;89;94;93;89 09:55:18 INFO - PID 1650 | |8;json-parse-financial;51;47;49;66;77;78;73;51;52;50 09:55:18 INFO - PID 1650 | |9;json-stringify-tinderbox;61;56;67;70;72;88;89;84;88;54 09:55:18 INFO - PID 1650 | |10;stanford-crypto-aes;85;83;84;87;85;83;83;83;83;83 09:55:18 INFO - PID 1650 | |11;stanford-crypto-ccm;151;162;138;131;138;136;170;162;190;141 09:55:18 INFO - PID 1650 | |12;stanford-crypto-pbkdf2;181;185;186;188;184;183;189;192;222;185 09:55:18 INFO - PID 1650 | |13;stanford-crypto-sha256-iterative;68;79;101;101;101;63;77;98;100;102 09:55:18 INFO - PID 1650 | __end_tp_report 09:55:18 INFO - PID 1650 | __start_cc_report 09:55:18 INFO - PID 1650 | _x_x_mozilla_cycle_collect,1044 09:55:18 INFO - PID 1650 | __end_cc_report 09:55:18 INFO - PID 1650 | __startTimestamp1494608118570__endTimestamp 09:55:18 INFO - PID 1650 | 09:55:18 INFO - PID 1650 | ------- Summary: start ------- 09:55:18 INFO - PID 1650 | Number of tests: 14 09:55:18 INFO - PID 1650 | 09:55:18 INFO - PID 1650 | [#0] ai-astar Cycles:10 Average:125.90 Median:122.50 stddev:21.53 (17.6%) stddev-sans-first:21.03 09:55:18 INFO - PID 1650 | Values: 102.0 131.0 168.0 159.0 115.0 111.0 114.0 117.0 114.0 128.0 09:55:18 INFO - PID 1650 | 09:55:18 INFO - PID 1650 | [#1] audio-beat-detection Cycles:10 Average:131.60 Median:134.00 stddev:22.58 (16.9%) stddev-sans-first:16.57 09:55:18 INFO - PID 1650 | Values: 178.0 147.0 114.0 154.0 111.0 112.0 110.0 122.0 136.0 132.0 09:55:18 INFO - PID 1650 | 09:55:18 INFO - PID 1650 | [#2] audio-dft Cycles:10 Average:216.10 Median:212.50 stddev:30.86 (14.5%) stddev-sans-first:32.10 09:55:18 INFO - PID 1650 | Values: 199.0 191.0 209.0 216.0 228.0 199.0 233.0 294.0 196.0 196.0 09:55:18 INFO - PID 1650 | 09:55:18 INFO - PID 1650 | [#3] audio-fft Cycles:10 Average:73.30 Median:80.00 stddev:15.03 (18.8%) stddev-sans-first:15.44 09:55:18 INFO - PID 1650 | Values: 84.0 77.0 58.0 83.0 52.0 57.0 63.0 75.0 97.0 87.0 09:55:18 INFO - PID 1650 | 09:55:18 INFO - PID 1650 | [#4] audio-oscillator Cycles:10 Average:85.10 Median:72.00 stddev:16.04 (22.3%) stddev-sans-first:16.86 09:55:18 INFO - PID 1650 | Values: 79.0 68.0 96.0 75.0 69.0 68.0 105.0 103.0 107.0 81.0 09:55:18 INFO - PID 1650 | 09:55:18 INFO - PID 1650 | [#5] imaging-gaussian-blur Cycles:10 Average:94.80 Median:90.50 stddev:7.16 (7.9%) stddev-sans-first:5.06 09:55:18 INFO - PID 1650 | Values: 110.0 90.0 91.0 91.0 91.0 102.0 102.0 91.0 90.0 90.0 09:55:18 INFO - PID 1650 | 09:55:18 INFO - PID 1650 | [#6] imaging-darkroom Cycles:10 Average:118.60 Median:97.00 stddev:26.12 (26.9%) stddev-sans-first:24.36 09:55:18 INFO - PID 1650 | Values: 154.0 153.0 154.0 97.0 98.0 98.0 97.0 97.0 129.0 109.0 09:55:18 INFO - PID 1650 | 09:55:18 INFO - PID 1650 | [#7] imaging-desaturate Cycles:10 Average:91.70 Median:92.50 stddev:2.06 (2.2%) stddev-sans-first:2.09 09:55:18 INFO - PID 1650 | Values: 90.0 94.0 90.0 92.0 94.0 92.0 89.0 94.0 93.0 89.0 09:55:18 INFO - PID 1650 | 09:55:18 INFO - PID 1650 | [#8] json-parse-financial Cycles:10 Average:59.40 Median:59.00 stddev:12.61 (21.4%) stddev-sans-first:13.00 09:55:18 INFO - PID 1650 | Values: 51.0 47.0 49.0 66.0 77.0 78.0 73.0 51.0 52.0 50.0 09:55:18 INFO - PID 1650 | 09:55:18 INFO - PID 1650 | [#9] json-stringify-tinderbox Cycles:10 Average:72.90 Median:78.00 stddev:13.61 (17.4%) stddev-sans-first:13.74 09:55:18 INFO - PID 1650 | Values: 61.0 56.0 67.0 70.0 72.0 88.0 89.0 84.0 88.0 54.0 09:55:18 INFO - PID 1650 | 09:55:18 INFO - PID 1650 | [#10] stanford-crypto-aes Cycles:10 Average:83.90 Median:83.50 stddev:1.37 (1.6%) stddev-sans-first:1.39 09:55:18 INFO - PID 1650 | Values: 85.0 83.0 84.0 87.0 85.0 83.0 83.0 83.0 83.0 83.0 09:55:18 INFO - PID 1650 | 09:55:18 INFO - PID 1650 | [#11] stanford-crypto-ccm Cycles:10 Average:151.90 Median:156.50 stddev:18.79 (12.0%) stddev-sans-first:19.93 09:55:18 INFO - PID 1650 | Values: 151.0 162.0 138.0 131.0 138.0 136.0 170.0 162.0 190.0 141.0 09:55:18 INFO - PID 1650 | 09:55:18 INFO - PID 1650 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.50 Median:187.00 stddev:11.84 (6.3%) stddev-sans-first:12.16 09:55:18 INFO - PID 1650 | Values: 181.0 185.0 186.0 188.0 184.0 183.0 189.0 192.0 222.0 185.0 09:55:18 INFO - PID 1650 | 09:55:18 INFO - PID 1650 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:89.00 Median:65.50 stddev:15.51 (23.7%) stddev-sans-first:14.47 09:55:18 INFO - PID 1650 | Values: 68.0 79.0 101.0 101.0 101.0 63.0 77.0 98.0 100.0 102.0 09:55:18 INFO - PID 1650 | -------- Summary: end -------- 09:55:18 INFO - PID 1650 | 09:55:19 INFO - TEST-INFO | 1650: exit 0 09:55:19 INFO - TEST-OK | kraken | took 60975ms 09:55:19 INFO - SUITE-END | took 1175s 09:55:19 INFO - Completed test suite (00:19:35) 09:55:19 INFO - javascript benchmark 09:55:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7875.68137889328, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2958.7041295870404, 3480.6867381935604, 3521.225330427306, 3582.373084710175, 3590.2281009582935, 4924.950750492494, 5377.542908852747, 6054.697265136748, 6154.138420621117, 6237.28271248806, 4886.364772579568, 6102.156009074801, 6143.880361275353, 6170.62976221427, 6254.249490061187, 4412.955870441303, 5396.0, 5418.241446197526, 5420.4850539198715, 5491.313585801775, 5345.866353341178, 5496.917546236789, 5505.256790333294, 5516.0, 5528.778848846061, 5426.321709786277, 5446.400895901447, 5477.863053423656, 5481.260029895953, 5512.614117011811, 5413.242146099549, 5423.728813559345, 5426.430224826376, 5444.101723215665, 5459.508644222039, 5366.651167674088, 5385.380681221671, 5404.648697834628, 5449.073657478199, 5487.149491828773, 5294.311739473882, 5489.64317319373, 6133.51829027926, 6138.449706860586, 6250.6874656267, 5506.834794956118, 5543.916841247385, 6584.76953306632, 6601.6699165041555, 6668.599884006975, 6678.265390807007, 6723.462123030145, 6736.46108311334, 6758.729650813962, 6760.357766012221, 6127.403330890814, 6191.473724733448, 6224.719887605069, 6310.274318453345, 6338.904916426257, 6824.385805277449, 6866.793996180123, 6873.690683919235, 6879.036934829128, 6899.724011039602, 5080.314157588752, 5615.0, 6317.46301564363, 6389.872202555923, 6391.201099862517, 6168.259808822881, 6500.512461565307, 6563.737450501926, 6682.465402767767, 6705.2624211336715, 5846.970765146147, 6335.904961425582, 6340.936590634127, 6561.212654481397, 6634.502412319095, 6440.194975628046, 6441.64570948593, 6500.154979852589, 6534.673266336664, 6592.80221593353, 5147.176451767774, 5260.342457192851, 5265.104932161542, 5298.072837253465, 5309.044372012998, 4655.906881862344, 4678.719276843401, 4684.601808846286, 4708.8351907683755, 4739.004808990151, 6145.539084568675, 6235.345288744707, 6244.843878903063, 6265.592736472115, 6298.653574053471, 5388.488093631099, 6690.866182676319, 6712.798616041527, 6714.832129196711, 6727.226368967631, 5912.674802885796, 6230.719617617218, 6263.342349053375, 6267.467265282411, 6299.937000630027, 5140.871478213, 5733.512651424592, 5771.163181338682, 5796.188533605298, 5799.768009279666, 6198.721057552511, 6256.217972753406, 6741.898871516931, 6793.490488213502, 6851.554648947803, 6726.3609957054005, 6760.695768690519, 6764.154480689914, 6773.796786096425, 6777.186737591521, 6599.7360105595235, 6814.795556133324, 6827.761028363983, 6848.452123830181, 6887.139107611549, 6457.935420645734, 6529.314421985623, 6583.967080164569, 6613.173353330833, 6628.237752658313, 6718.93281067183, 6731.461483081243, 6732.528722989344, 6737.629430381376, 6739.59562426256, 6276.560640755103, 6344.270408903035, 6371.74513019474, 6386.16979792624, 6430.582012169194, 6499.0, 6508.9349106508325, 6520.902186467301, 6522.739090436329, 6534.803955881332, 6106.053561697944, 6277.466415354746, 6289.622622642656, 6295.5907865988565, 6375.0, 6428.067930150196, 6509.544331896911, 6525.738970441129, 6526.836829079121, 6530.47756179514, 5393.298871146726, 6373.075903993988, 6379.298277189413, 6381.3937675920715, 6412.198475190601], "value": 5983.363453641877, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [761.8914479432423, 793.1434051224678, 815.2418251026546, 1216.4769149265812, 1288.3429450980009, 39687.60312396875, 40183.99999999996, 40589.0, 40843.0, 41064.79467602661, 33212.83393583035, 33296.83351583239, 41379.58620413795, 41644.75065748033, 41699.79150104253, 7769.0, 7936.960315198445, 8269.21442462965, 8436.915630843705, 8513.404061715682, 8453.0, 8552.828943421127, 8563.95718021409, 8568.828623427527, 8570.27152692022, 8516.78708032301, 8589.699360522383, 8591.570421478933, 8617.310615150804, 8631.266342360906, 8447.704330348439, 8538.359623028267, 8561.571921403967, 8564.400491965565, 8571.14288571141, 7007.509474336798, 8264.297534709556, 8320.916790832074, 8324.542150181798, 8371.455855369382, 37788.81105594454, 37880.242395151945, 38234.80882595597, 38240.235195295936, 38266.80866595677, 3653.0319465341704, 3659.8170091495585, 3697.5378077740284, 3702.4816525686424, 3703.055720791208, 30888.53667194994, 30889.0, 31085.844570777226, 31115.0, 31307.686923130706, 3123.8281894495794, 3139.5290706394226, 3159.842007899596, 3164.303853152303, 3167.319026409333, 298847.0, 299159.0, 312332.4383378091, 319395.9999999977, 322337.0, 48824.511754882704, 48865.02269954652, 48940.51059489431, 48944.0, 49030.26454603184, 46610.067798644515, 51360.0, 51689.483105169216, 52540.0, 56048.439515604325, 14435.35040923161, 14448.566543003726, 14450.349734261983, 14467.638309042148, 14480.565583032527, 11305.095592352593, 11476.598319058792, 11567.248128871597, 11569.305841649528, 11576.073914086854, 12852.678683032998, 13932.442702291997, 14203.715925681636, 14209.289535523183, 14327.713445731028, 5649.542449034683, 6246.3753624637175, 6250.468710159687, 6288.025356069817, 6305.274893387319, 985.7880555680563, 1009.3489699144158, 1026.4457193115784, 1056.3239526702916, 1063.967951087444, 23784.524309513712, 24711.25866224016, 24922.5015499689, 24970.5005899881, 25032.249032529053, 1042.9061384475433, 1125.6285425809463, 1152.2567943676281, 1157.386585109893, 1162.511745067074, 10665.573377064986, 12005.879941200476, 12466.75066498665, 12626.873731262569, 12801.743965120831, 274225.0, 343894.0, 347601.0, 347811.0, 348774.0, 55455.0, 55599.61000975008, 55729.0, 55940.44059559352, 56025.7198714012, 1280.3214296422912, 1364.836219653628, 1549.1170033081034, 1560.531840447861, 1586.7937168168064, 20717.482062948548, 23355.299341019796, 23623.763762362156, 24375.634365484886, 24502.26493205207, 38671.0, 38691.613083868804, 38961.0, 39005.80497097496, 39027.0, 286449.7615309996, 286607.0, 287126.0, 287587.0, 288161.0, 35839.82080089687, 36184.63815361918, 36368.09079773027, 36398.81800591086, 36413.725519607746, 44898.77550612226, 45596.08807823758, 46449.83875403007, 46515.76742116268, 46725.065498690514, 24284.635730463702, 29779.55330670085, 30957.380852383278, 31660.525092123175, 32172.83913580417, 30595.623196955745, 31235.843820780752, 31455.213619658774, 31598.684013159575, 31963.36073278568, 37576.43635345417, 37929.43105853359, 38221.80889095537, 38267.808660956514, 38276.42585361166, 30305.69694303029, 32188.517172241965, 32452.83773581117, 32954.0, 33339.66660333366, 19738.901305493382, 20688.17247310091, 21522.461938451663, 23392.0, 25076.62385064262], "value": 17779.51762319042, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [345.0889651320513, 422.9492460904692, 423.7457525484709, 432.54150600363636, 453.5668436643004, 16022.0, 19489.902550487244, 21876.671849922262, 22899.31302060941, 23806.64290035651, 22782.97476613552, 22911.312660620166, 23199.0, 23600.645990310117, 23625.527489450244, 5525.226468294442, 6202.78290259841, 6215.316315205324, 6278.6232826030355, 6285.748570057215, 5152.484751524837, 5892.322382925954, 6132.724027418777, 6177.907331390033, 6191.907121393183, 5977.282726072877, 6044.546659000571, 6109.938900611004, 6117.143599896018, 6117.694115294217, 5979.850503737398, 6081.878362432749, 6087.421694938974, 6097.176881121059, 6102.9694851525455, 5370.677759334413, 5392.272043274147, 5439.347278326626, 5455.263539422167, 5456.072467680504, 28037.0, 28060.71939280622, 28248.85875570609, 28725.712742872514, 29166.0, 2154.407537927067, 2215.5679642469727, 2219.1789038055863, 2219.2343641443676, 2223.5997520446313, 17711.025893575847, 17917.283308667636, 18125.18436670353, 18534.351297704514, 18730.250789968384, 2133.4453042208984, 2136.134865379508, 2159.8164156046755, 2163.5132095278595, 2180.9890950545305, 128320.0, 146003.0, 164336.0, 167733.0, 169260.3073969257, 11052.065888469486, 12576.937115314548, 12958.509771376224, 13165.934170329087, 13194.736105277841, 16677.999320040835, 16954.91522542404, 16978.0, 17064.57338566546, 17070.23183956725, 7346.6693998769115, 8754.299656027502, 8830.0, 9033.728988130366, 9044.0, 8429.957850210709, 8435.11431299717, 8456.704015359555, 8597.850537365659, 8617.827643447095, 6595.70319335631, 8007.559584222924, 8081.434299598972, 8096.392770542233, 8155.714549990722, 2172.891355432222, 2196.5826492966285, 2200.8129309008596, 2203.5262418580082, 2381.4284571702665, 911.6900253913568, 937.7340590203171, 953.952302384878, 989.1196834817018, 1017.6183931025865, 15526.689466210613, 16152.353905843634, 20738.274160404315, 21435.035423405985, 21835.563288734455, 874.790050387915, 995.9352642078387, 1002.5338217728792, 1019.7501612105082, 1022.1924679503257, 10759.78480430387, 11125.332480051062, 11164.051055660197, 11177.32936023825, 11177.329360238411, 563977.0, 568707.0, 569400.0, 576541.0, 577885.0, 135321.0, 139796.31120288483, 142395.0, 143466.0, 146399.26800366142, 1153.5674122204173, 1173.9941300293444, 1205.9879401206051, 1262.0471543984233, 1266.1326991011188, 18301.0, 21140.365789026353, 22520.324390268317, 22647.433814154778, 22728.65907011363, 48155.75922120507, 51848.96302074013, 56047.15929260983, 56336.71831640816, 56579.71710141423, 67728.98406524005, 67733.66133169303, 68787.65606171938, 69975.0, 71205.0, 8976.640934362553, 9076.863847042432, 9128.0, 9143.908560914306, 9197.712320275301, 13972.720545589234, 14131.434742610592, 14486.927565362106, 14541.41834326615, 14678.412863485768, 11891.524339026688, 12042.397880106135, 12106.0, 12224.327661978321, 12342.691432714253, 9666.565004574952, 11291.604793832177, 11307.434628268718, 11330.0, 11456.026237769549, 11790.0, 12079.094067945114, 12221.755564888475, 12388.955822289221, 12508.186967847061, 8449.324054075782, 8812.691555795516, 8815.162559556831, 8890.911090889267, 8921.286297096087, 11878.762424751629, 11954.701132471759, 12067.939660301643, 12086.093542984136, 12158.331291779037], "value": 10475.140309937668, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1408.3873515020964, 1460.0874453466577, 1461.7120924358885, 1477.1727832413849, 1492.0749135535968, 11764.647060588175, 12144.028477721784, 12183.025357971363, 12201.206921550103, 12475.064370172251, 10150.593976240942, 12198.756024879496, 12378.319192444413, 12401.945834604046, 12459.377031148451, 2228.799408053275, 2589.650397196378, 2632.078772429644, 2637.9076732314375, 2640.498305321992, 2554.0932968796087, 2579.3551612096976, 2608.3870290481723, 2608.595667671514, 2616.0, 2477.6531285620026, 2556.194798638423, 2565.961510577343, 2611.7518835710575, 2615.163147792707, 2260.954780904381, 2460.3480077779404, 2534.0623969131448, 2559.846409215453, 2579.6775403074616, 2550.923472295834, 2554.2592648132018, 2584.806139539542, 2589.1973488218523, 2589.7021842488166, 11573.305601663842, 11814.940925295405, 11819.704507387383, 11821.34982575958, 11824.645260642195, 6683.298253683391, 6899.206591242021, 6924.446044316443, 6929.896051559231, 6945.72217111315, 4192.842117313733, 4305.246581848164, 4333.9133217335475, 4454.153710794939, 4469.284914413711, 1228.686684895346, 1237.257645412754, 1288.239938436327, 1288.375138058041, 1295.436485128972, 16515.17424128789, 16598.917005415016, 16771.664566708598, 16846.578835529086, 16960.3215871365, 5619.381867994517, 5700.173474846124, 5796.94203057964, 5832.3292821326095, 5881.0, 11935.462904169159, 12053.63839084829, 12056.819147712791, 12064.879351206375, 12090.972267357198, 6437.452816510556, 6588.802335929834, 6703.4972377070935, 6704.698288577026, 6712.228093769181, 6108.877822443526, 6148.323684394713, 6221.751129954841, 6302.65335406557, 6314.084457753692, 4448.043670610834, 4490.416245888079, 4504.09918016398, 4512.571305725951, 4562.954370456319, 1295.844498660167, 1312.783390740517, 1321.0950498908278, 1330.2949436798513, 1336.9197848129143, 785.7721260834294, 794.5533368757202, 802.7752229375667, 805.6777289084413, 822.198356602308, 4605.65457590682, 5696.689761354829, 5729.7708091676695, 6183.938160618336, 6461.095446637474, 524.9291345668362, 592.4608606168453, 594.4352864778477, 603.6378173096107, 611.9204503414617, 3724.5530536335814, 4158.688098392572, 4322.589354011364, 4374.803133858984, 4512.977435112869, 14925.402983880524, 15241.618959525878, 15286.617834554225, 15355.155466449229, 15400.383984640714, 11318.641762988495, 12090.758184836253, 15385.692286154439, 15386.692266154838, 15410.460633877985, 7130.786076417716, 7949.841003179904, 8459.619317130744, 8568.0, 8696.869546956801, 6831.9658401707675, 6967.338102880219, 7153.535020223669, 7175.605341706257, 7353.154387245535, 13331.733365332833, 13602.52391166344, 13686.589402317946, 14004.229767362893, 14030.649233769238, 13793.655158621115, 13812.378442970292, 13991.16053036821, 14014.299285035911, 14211.64470888236, 9306.534673266175, 9433.24534037289, 9442.38624489406, 9458.054194580762, 9518.190953769008, 10858.945705271422, 11390.601328953448, 11478.713032174262, 11484.655460336204, 11547.884521154681, 9207.723768286962, 9218.9539052307, 9366.2038726709, 9459.810803784023, 9476.194523465583, 8100.513969161869, 9164.129407706258, 9244.075592440702, 9518.381305215138, 9524.809503810024, 8222.054463736507, 8325.834383186238, 8345.374096942633, 8429.87355189685, 8443.620037098468, 5281.287026251407, 6193.194884665145, 6194.318624951162, 6222.128901953821, 6333.461655759312, 6144.38556144382, 6151.878241578064, 6251.812445626639, 6384.489240860813, 6451.032345148265], "value": 5395.4894524417705, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [374.7863717680923, 393.80506649208644, 399.3510545363784, 401.13755425834455, 401.9397090436434, 4730.959188978423, 5994.490468310193, 6112.541559383042, 6174.259088909332, 6394.337472257212, 5183.108165519587, 6139.907901381483, 6187.907181392283, 6211.9068213976825, 6239.34486878878, 4523.231050721377, 4523.909521809578, 4529.501754806968, 4538.568835960578, 4559.863204103882, 3855.664849904826, 4363.781810909441, 4395.692301538894, 4469.486009108945, 4496.505384407713, 4447.798339821793, 4451.844185453494, 4464.441944756905, 4483.237849565582, 4484.372187893698, 4459.843905463309, 4495.280755079171, 4499.932501012471, 4501.482329532113, 4513.142502924434, 3127.56214130022, 3580.6751501944186, 3826.9425958610364, 3857.24783667185, 3864.768113913174, 4788.0, 4943.813484763631, 5668.716564171775, 6083.783243351348, 6115.663638499881, 4421.624161946238, 4865.537773911473, 5124.692518448867, 5391.353037635469, 5425.3489581250105, 6489.805305840832, 6533.314002029766, 6569.93430065698, 6605.240397354318, 6629.900551491732, 4324.545922678105, 4334.5882141196535, 4334.804933777956, 4340.370646256307, 4348.434703488558, 6797.320267973212, 6967.780638388262, 7007.964960175166, 7071.1868135164605, 7128.501004929605, 3804.6575808177395, 4269.7224680395675, 4340.956590434118, 4380.233459144701, 4421.491528474202, 3727.254549090193, 3803.733738638324, 4002.7198096132993, 4277.807498662568, 4523.615492683093, 4461.152381047591, 4594.747288899076, 4914.65597408178, 5297.8145764898045, 5308.203769434538, 6632.502562307846, 6634.436072933758, 6641.103451034145, 6668.366505182, 6706.765263215764, 1349.3537883781771, 1514.6364872430759, 1522.3453914816514, 1526.114853385034, 1532.632168279605, 870.0472982084607, 905.542700936023, 996.8685541909839, 1002.7292630989592, 1003.7239759066315, 4805.327254184417, 5287.788488460418, 6296.590721603081, 6349.174607301022, 6358.395952384516, 5994.610350327215, 6655.43428808547, 6697.6986035628515, 6782.796516104525, 6970.895436568456, 5379.901838283073, 6358.173437453194, 6381.298057213703, 6435.227772667216, 6502.739890404331, 2890.985545072261, 3135.263213144909, 3305.1406634275263, 3524.3656141894194, 3536.8231588420476, 6538.901916471256, 6824.771541122645, 6847.349501797321, 7123.323284287984, 7220.350168484756, 6798.762043328558, 7197.604131772803, 7252.461638426508, 7253.274672532704, 7273.745418910313, 6873.690683919235, 7065.611391373421, 7159.243691178829, 7167.713291468387, 7173.605451700207, 6964.756233531802, 6966.5471744336455, 6973.755918542826, 7002.544834585736, 7007.40437062835, 6456.515761317826, 6459.321771213955, 6645.069690243273, 6662.56693314933, 6932.861342773015, 5737.799177028951, 6971.128608923885, 7174.533655312597, 7188.784336469915, 7216.097987751531, 3662.3957047086933, 3785.2240290739783, 4384.386185933908, 4469.240229160985, 4470.776461176863, 6429.774957876639, 6686.431653309329, 6751.852185128638, 6996.0905082341005, 7047.647617618996, 6677.0, 6682.298358672269, 6684.665766711548, 6697.856685860673, 6705.765298214539, 6510.21877374718, 6605.53761236728, 6638.269790323157, 6654.73381064752, 6695.12963314766, 5828.825135245949, 6369.172007639163, 6519.869602608016, 6524.706388212637, 6589.670516474253, 6525.3800888915475, 6578.828986434561, 6681.365270299314, 6683.966580167068, 6710.530262881747, 5877.147813567094, 5960.314563825215, 6498.415142637185, 6502.479801615954, 6544.447665410398], "value": 4765.122009631338, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [698.3191388396314, 809.134226377776, 819.5492479136477, 845.7631863078336, 849.2187187787237, 14416.279186040681, 15030.17334046627, 16230.594235144112, 16660.66678666426, 16772.58068548287, 16353.182340882968, 16456.094914779682, 16950.660986780258, 17597.648047039085, 17621.383251586198, 983.0464449483989, 1000.0799264676479, 1001.3691374434096, 1001.529281237817, 1004.3672486333636, 1020.1175982774884, 1024.1857723110109, 1025.0467065628961, 1025.2208321675505, 1027.6146445083093, 932.9533523323806, 1049.716576524338, 1055.171690223175, 1057.989619912985, 1064.669952314785, 1030.3354336452983, 1049.0506091986765, 1052.2844465763278, 1052.3159946035062, 1055.3140458701828, 755.6599530211427, 771.4137255685663, 777.0481160578246, 777.3780975219858, 780.75406247032, 16880.240389182516, 17045.82954170455, 17220.82779172217, 17380.913095434444, 17615.207315670697, 17100.31598736049, 17262.56843578908, 17609.0, 17620.559486012826, 17759.28962841485, 14156.716865662629, 14379.424823007068, 14471.276436178148, 14590.854091459161, 14600.561983140522, 3541.628129046439, 3542.557180352456, 3544.131687736495, 3564.554430696163, 3566.696830769388, 132990.6700932988, 134803.32598337045, 136581.3170934139, 136814.31592842072, 137280.31359843237, 10499.422531760827, 11520.481578328996, 11862.347570883685, 13094.14888032275, 13232.0, 24736.25791226266, 24737.5052498949, 25155.62266566003, 25258.873705631355, 25400.618990715153, 7660.387169026464, 7883.093444253869, 7908.683652653829, 7935.523868567904, 7937.801554961056, 3415.52182694423, 3470.35798377301, 3473.114355839245, 3477.6174620791694, 3792.544894612609, 3579.463080537888, 4131.628153466202, 4247.532771395144, 4269.978650106792, 4274.230638485103, 900.5587262241454, 938.9342746007851, 939.3377668743519, 941.0730430525927, 944.475815922156, 528.2472476720658, 645.2353960556756, 646.9616265893268, 657.7533424965637, 678.782789507353, 9121.36150469474, 10300.381977081399, 10835.295705779099, 11830.645080647595, 12075.154739168174, 635.1203583037526, 647.155462121926, 665.7303791964263, 675.562061700679, 679.9252082270946, 5256.763445644955, 5399.649022813504, 5487.259220005328, 5525.005499010136, 5588.2455868458055, 101228.0, 102650.0, 103109.0, 104157.47921260346, 104791.0, 93917.0, 95470.0, 96089.51955240179, 98213.0, 98440.50779746055, 64043.35956640467, 64720.352796472376, 65084.674576626814, 65370.69258614801, 65722.0, 10524.421156836448, 10553.788924221626, 12412.007039437003, 12768.808467872803, 12878.227306361649, 32009.35981280408, 40055.0, 40474.19051618892, 40670.186596268504, 41062.58937410707, 58380.0, 65949.68100638056, 72981.0, 74415.255847443, 74470.62764686142, 16299.614532764848, 16647.583810404838, 17180.484585462455, 17350.91324543369, 17447.38934137299, 14320.713585728436, 18109.0, 18493.81506184921, 18699.439016829518, 19010.524736881136, 17035.48893533196, 17097.914510427367, 17461.738073928645, 17508.737368939732, 17777.111144442984, 16926.32294708198, 17375.47873563795, 17617.735733963746, 17878.5530361742, 18030.36893708714, 16366.345346186019, 17119.57201069983, 17249.137543123044, 17369.0, 17580.912095439442, 10950.616728414787, 11412.600558980395, 11575.826362604399, 12032.458539365925, 12037.157398981988, 11506.94246528762, 11820.940895295469, 12012.939935300268, 12167.634970950885, 12196.268223906594], "value": 8328.511110575913, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1583.7000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 131.0, 168.0, 159.0, 115.0, 111.0, 114.0, 117.0, 114.0, 128.0], "value": 125.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 147.0, 114.0, 154.0, 111.0, 112.0, 110.0, 122.0, 136.0, 132.0], "value": 131.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 191.0, 209.0, 216.0, 228.0, 199.0, 233.0, 294.0, 196.0, 196.0], "value": 216.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 77.0, 58.0, 83.0, 52.0, 57.0, 63.0, 75.0, 97.0, 87.0], "value": 73.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 68.0, 96.0, 75.0, 69.0, 68.0, 105.0, 103.0, 107.0, 81.0], "value": 85.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 90.0, 91.0, 91.0, 91.0, 102.0, 102.0, 91.0, 90.0, 90.0], "value": 94.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 153.0, 154.0, 97.0, 98.0, 98.0, 97.0, 97.0, 129.0, 109.0], "value": 118.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 94.0, 90.0, 92.0, 94.0, 92.0, 89.0, 94.0, 93.0, 89.0], "value": 91.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 47.0, 49.0, 66.0, 77.0, 78.0, 73.0, 51.0, 52.0, 50.0], "value": 59.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 56.0, 67.0, 70.0, 72.0, 88.0, 89.0, 84.0, 88.0, 54.0], "value": 72.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 84.0, 87.0, 85.0, 83.0, 83.0, 83.0, 83.0, 83.0], "value": 83.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 162.0, 138.0, 131.0, 138.0, 136.0, 170.0, 162.0, 190.0, 141.0], "value": 151.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 185.0, 186.0, 188.0, 184.0, 183.0, 189.0, 192.0, 222.0, 185.0], "value": 189.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 79.0, 101.0, 101.0, 101.0, 63.0, 77.0, 98.0, 100.0, 102.0], "value": 89.0, "unit": "score"}]}]} 09:55:19 INFO - Return code: 0 09:55:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:55:19 INFO - # TBPL SUCCESS # 09:55:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:55:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:55:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:55:19 INFO - 2017-05-12 09:55:19.888 screenresolution[1657:10575] starting screenresolution argv=screenresolution get 09:55:19 INFO - 2017-05-12 09:55:19.893 screenresolution[1657:10575] Display 0: 1600x1200x32@60 09:55:19 INFO - 2017-05-12 09:55:19.899 screenresolution[1658:10578] starting screenresolution argv=screenresolution list 09:55:19 INFO - Available Modes on Display 0 09:55:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:55:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:55:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:55:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:55:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:55:20 INFO - Intel Iris: 09:55:20 INFO - Chipset Model: Intel Iris 09:55:20 INFO - Type: GPU 09:55:20 INFO - Bus: Built-In 09:55:20 INFO - VRAM (Dynamic, Max): 1536 MB 09:55:20 INFO - Vendor: Intel (0x8086) 09:55:20 INFO - Device ID: 0x0a2e 09:55:20 INFO - Revision ID: 0x0009 09:55:20 INFO - Displays: 09:55:20 INFO - Display: 09:55:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:55:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:55:20 INFO - Display Serial Number: 1600x1200 60 09:55:20 INFO - Main Display: Yes 09:55:20 INFO - Mirror: Off 09:55:20 INFO - Online: Yes 09:55:20 INFO - Rotation: Supported 09:55:20 INFO - Return code: 0 09:55:20 INFO - Running post-action listener: _resource_record_post_action 09:55:20 INFO - [mozharness: 2017-05-12 16:55:20.138385Z] Finished run-tests step (success) 09:55:20 INFO - Running post-run listener: _resource_record_post_run 09:55:20 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 39357440; Write bytes: 527662080; Read time: 251; Write time: 1644 09:55:20 INFO - TinderboxPrint: CPU usage
54.0% 09:55:20 INFO - TinderboxPrint: I/O read bytes / time
39,357,440 / 251 09:55:20 INFO - TinderboxPrint: I/O write bytes / time
527,662,080 / 1,644 09:55:20 INFO - TinderboxPrint: CPU idle
2,201.1 (46.0%) 09:55:20 INFO - TinderboxPrint: CPU system
961.8 (20.1%) 09:55:20 INFO - TinderboxPrint: CPU user
1,622.7 (33.9%) 09:55:20 INFO - TinderboxPrint: Swap in / out
254,062,592 / 0 09:55:20 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 203237888; Write bytes: 197502976; Read time: 15723; Write time: 411 09:55:20 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 38394880; Write bytes: 310420480; Read time: 238; Write time: 1176 09:55:20 INFO - Running post-run listener: _upload_blobber_files 09:55:20 INFO - Blob upload gear active. 09:55:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:55:20 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 09:55:20 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'] 09:55:20 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 09:55:21 INFO - (blobuploader) - INFO - Open directory for files ... 09:55:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:55:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:55:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:55:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:55:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:55:22 INFO - (blobuploader) - INFO - Done attempting. 09:55:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:55:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:55:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:55:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:55:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:55:23 INFO - (blobuploader) - INFO - Done attempting. 09:55:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:55:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:55:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:55:24 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:55:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:55:24 INFO - (blobuploader) - INFO - Done attempting. 09:55:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:55:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:55:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:55:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:55:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:55:26 INFO - (blobuploader) - INFO - Done attempting. 09:55:26 INFO - (blobuploader) - INFO - Iteration through files over. 09:55:26 INFO - Return code: 0 09:55:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:55:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:55:26 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fbe1e1eaadf12b4408cf25f7ecdf1817aa5c7edf97790848c9a6adb995325a2b407b11e812182c688f19c841ffbed392f32b644ff811577b7feef65c50beffdc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2d35efe348490363daadacbfdcc38b7cdfb2477572e73bbb6929b31651f25d169eb9d352b050ee9d3eaf100b54c061f6678e7f4542e6906cfd2a4f658c99ca93", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc968549a044b5232f8c29707549b799224a1b0439eef00b54120960e68fc2568ccb710d0d227d965e38666a21dee4264b6f0082d2fb3db17999438a8ec3ff76", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/999e5a553b716f72feccf94c9aff682f6581884cd27828212735da63fd9cf883bd4de77d260b9fa5bbe6c3e908459468eace4a5906ef6906b413077883e133be"} 09:55:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:55:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:55:26 INFO - Contents: 09:55:26 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fbe1e1eaadf12b4408cf25f7ecdf1817aa5c7edf97790848c9a6adb995325a2b407b11e812182c688f19c841ffbed392f32b644ff811577b7feef65c50beffdc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2d35efe348490363daadacbfdcc38b7cdfb2477572e73bbb6929b31651f25d169eb9d352b050ee9d3eaf100b54c061f6678e7f4542e6906cfd2a4f658c99ca93", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc968549a044b5232f8c29707549b799224a1b0439eef00b54120960e68fc2568ccb710d0d227d965e38666a21dee4264b6f0082d2fb3db17999438a8ec3ff76", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/999e5a553b716f72feccf94c9aff682f6581884cd27828212735da63fd9cf883bd4de77d260b9fa5bbe6c3e908459468eace4a5906ef6906b413077883e133be"} 09:55:26 INFO - Running post-run listener: copy_logs_to_upload_dir 09:55:26 INFO - Copying logs to upload dir... 09:55:26 INFO - mkdir: /builds/slave/test/build/upload/logs 09:55:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.716438 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-05-12 09:55:26.569293) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-12 09:55:26.572409) ========= 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.yGbrCQgnxZ/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.x5kKNhbZMr/Listeners TMPDIR=/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fbe1e1eaadf12b4408cf25f7ecdf1817aa5c7edf97790848c9a6adb995325a2b407b11e812182c688f19c841ffbed392f32b644ff811577b7feef65c50beffdc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2d35efe348490363daadacbfdcc38b7cdfb2477572e73bbb6929b31651f25d169eb9d352b050ee9d3eaf100b54c061f6678e7f4542e6906cfd2a4f658c99ca93", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc968549a044b5232f8c29707549b799224a1b0439eef00b54120960e68fc2568ccb710d0d227d965e38666a21dee4264b6f0082d2fb3db17999438a8ec3ff76", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/999e5a553b716f72feccf94c9aff682f6581884cd27828212735da63fd9cf883bd4de77d260b9fa5bbe6c3e908459468eace4a5906ef6906b413077883e133be"} build_url:https://queue.taskcluster.net/v1/task/dlnsPqIIRw-eRDfwXc_Bhw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011126 build_url: 'https://queue.taskcluster.net/v1/task/dlnsPqIIRw-eRDfwXc_Bhw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fbe1e1eaadf12b4408cf25f7ecdf1817aa5c7edf97790848c9a6adb995325a2b407b11e812182c688f19c841ffbed392f32b644ff811577b7feef65c50beffdc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2d35efe348490363daadacbfdcc38b7cdfb2477572e73bbb6929b31651f25d169eb9d352b050ee9d3eaf100b54c061f6678e7f4542e6906cfd2a4f658c99ca93", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc968549a044b5232f8c29707549b799224a1b0439eef00b54120960e68fc2568ccb710d0d227d965e38666a21dee4264b6f0082d2fb3db17999438a8ec3ff76", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/999e5a553b716f72feccf94c9aff682f6581884cd27828212735da63fd9cf883bd4de77d260b9fa5bbe6c3e908459468eace4a5906ef6906b413077883e133be"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-12 09:55:26.606654) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 09:55:26.606997) ========= 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.yGbrCQgnxZ/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.x5kKNhbZMr/Listeners TMPDIR=/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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.005101 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 09:55:26.655880) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-12 09:55:26.656216) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-12 09:55:26.656563) ========= ========= Total master_lag: 0.18 =========