builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0325 starttime: 1497462794.55 results: success (0) revision: 21965d67c5fe68e82b9c3d2a04ba128baf2bd589 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-14 10:53:14.550656) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-14 10:53:14.551094) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-14 10:53:14.551380) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-14 10:53:14.578781) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 10:53:14.579124) ========= 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.g9frllOJX9/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.4WQTWnHIdV/Listeners TMPDIR=/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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-06-14 10:53:14-- 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% 48.8M=0s 2017-06-14 10:53:14 (48.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.160464 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 10:53:14.762300) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 10:53:14.762613) ========= 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.g9frllOJX9/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.4WQTWnHIdV/Listeners TMPDIR=/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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.026957 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 10:53:14.811180) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-14 10:53:14.811525) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 21965d67c5fe68e82b9c3d2a04ba128baf2bd589 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 21965d67c5fe68e82b9c3d2a04ba128baf2bd589 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.g9frllOJX9/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.4WQTWnHIdV/Listeners TMPDIR=/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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-06-14 10:53:14,912 truncating revision to first 12 chars 2017-06-14 10:53:14,912 Setting DEBUG logging. 2017-06-14 10:53:14,912 attempt 1/10 2017-06-14 10:53:14,912 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/21965d67c5fe?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-14 10:53:15,849 unpacking tar archive at: mozilla-beta-21965d67c5fe/testing/mozharness/ program finished with exit code 0 elapsedTime=1.218595 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-14 10:53:16.051700) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-14 10:53:16.052013) ========= script_repo_revision: 21965d67c5fe68e82b9c3d2a04ba128baf2bd589 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-14 10:53:16.052393) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-14 10:53:16.052654) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-14 10:53:16.070560) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 10 secs) (at 2017-06-14 10:53:16.070830) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.g9frllOJX9/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.4WQTWnHIdV/Listeners TMPDIR=/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 10:53:16 INFO - MultiFileLogger online at 20170614 10:53:16 in /builds/slave/test 10:53:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 10:53:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:53:16 INFO - {'append_to_log': False, 10:53:16 INFO - 'base_work_dir': '/builds/slave/test', 10:53:16 INFO - 'blob_upload_branch': 'Mozilla-Beta', 10:53:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:53:16 INFO - 'branch': 'Mozilla-Beta', 10:53:16 INFO - 'buildbot_json_path': 'buildprops.json', 10:53:16 INFO - 'config_files': ('talos/mac_config.py',), 10:53:16 INFO - 'default_actions': ('clobber', 10:53:16 INFO - 'read-buildbot-config', 10:53:16 INFO - 'download-and-extract', 10:53:16 INFO - 'populate-webroot', 10:53:16 INFO - 'create-virtualenv', 10:53:16 INFO - 'install', 10:53:16 INFO - 'setup-mitmproxy', 10:53:16 INFO - 'run-tests'), 10:53:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:53:16 INFO - 'download_minidump_stackwalk': True, 10:53:16 INFO - 'download_symbols': 'ondemand', 10:53:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:53:16 INFO - 'tooltool.py': '/tools/tooltool.py', 10:53:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:53:16 INFO - '/tools/misc-python/virtualenv.py')}, 10:53:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:53:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:53:16 INFO - 'gecko_profile': False, 10:53:16 INFO - 'gecko_profile_interval': 0, 10:53:16 INFO - 'installer_path': 'installer.exe', 10:53:16 INFO - 'log_level': 'info', 10:53:16 INFO - 'log_name': 'talos', 10:53:16 INFO - 'log_to_console': True, 10:53:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:53:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:53:16 INFO - 'opt_config_files': (), 10:53:16 INFO - 'pip_index': False, 10:53:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:53:16 INFO - 'cmd': ('bash', 10:53:16 INFO - '-c', 10:53:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:53:16 INFO - 'enabled': True, 10:53:16 INFO - 'halt_on_failure': False, 10:53:16 INFO - 'name': 'check_screen_resolution'},), 10:53:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:53:16 INFO - 'cmd': ('bash', 10:53:16 INFO - '-c', 10:53:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:53:16 INFO - 'enabled': True, 10:53:16 INFO - 'halt_on_failure': False, 10:53:16 INFO - 'name': 'check_screen_resolution'},), 10:53:16 INFO - 'run_cmd_checks_enabled': True, 10:53:16 INFO - 'suite': 'dromaeojs-e10s', 10:53:16 INFO - 'system_bits': '32', 10:53:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:53:16 INFO - 'title': 't-yosemite-r7-0325', 10:53:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:53:16 INFO - 'use_talos_json': True, 10:53:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:53:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:53:16 INFO - 'work_dir': 'build'} 10:53:16 INFO - [mozharness: 2017-06-14 17:53:16.284650Z] Running clobber step. 10:53:16 INFO - Running pre-action listener: _resource_record_pre_action 10:53:16 INFO - Running main action method: clobber 10:53:16 INFO - rmtree: /builds/slave/test/build 10:53:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:53:18 INFO - Running post-action listener: _resource_record_post_action 10:53:18 INFO - [mozharness: 2017-06-14 17:53:18.854376Z] Finished clobber step (success) 10:53:18 INFO - [mozharness: 2017-06-14 17:53:18.854553Z] Running read-buildbot-config step. 10:53:18 INFO - Running pre-action listener: _resource_record_pre_action 10:53:18 INFO - Running main action method: read_buildbot_config 10:53:18 INFO - Using buildbot properties: 10:53:18 INFO - { 10:53:18 INFO - "project": "", 10:53:18 INFO - "product": "firefox", 10:53:18 INFO - "who": "ffxbld@noreply.mozilla.org", 10:53:18 INFO - "installer_path": "public/build/firefox-55.0.en-US.mac.dmg", 10:53:18 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 10:53:18 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta/", 10:53:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 10:53:18 INFO - "stage_platform": "macosx64", 10:53:18 INFO - "basedir": "/builds/slave/test", 10:53:18 INFO - "buildnumber": 2, 10:53:18 INFO - "platform": "yosemite_r7", 10:53:18 INFO - "parent_task_id": "ciFHRDZfS0OKDPV45N_FDQ", 10:53:18 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 10:53:18 INFO - "slavebuilddir": "test", 10:53:18 INFO - "taskId": "O_KDkDCpRMGl9qtAKcuXdg", 10:53:18 INFO - "branch": "mozilla-beta", 10:53:18 INFO - "script_repo_revision": "production", 10:53:18 INFO - "revision": "21965d67c5fe68e82b9c3d2a04ba128baf2bd589", 10:53:18 INFO - "slavename": "t-yosemite-r7-0325", 10:53:18 INFO - "repo_path": "releases/mozilla-beta" 10:53:18 INFO - } 10:53:18 INFO - Finding installer, test and symbols from parent task. 10:53:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O_KDkDCpRMGl9qtAKcuXdg'}, attempt #1 10:53:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O_KDkDCpRMGl9qtAKcuXdg'}, attempt #1 10:53:19 INFO - Task dependencies: Z47yQ4AgRPS775tryhCgvQ ciFHRDZfS0OKDPV45N_FDQ 10:53:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ciFHRDZfS0OKDPV45N_FDQ'}, attempt #1 10:53:20 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/ciFHRDZfS0OKDPV45N_FDQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg 10:53:20 INFO - Set test_url: https://queue.taskcluster.net/v1/task/ciFHRDZfS0OKDPV45N_FDQ/artifacts/public/build/test_packages.json 10:53:20 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/ciFHRDZfS0OKDPV45N_FDQ/artifacts/public/build/target.crashreporter-symbols.zip 10:53:20 INFO - Running post-action listener: _resource_record_post_action 10:53:20 INFO - [mozharness: 2017-06-14 17:53:20.284934Z] Finished read-buildbot-config step (success) 10:53:20 INFO - [mozharness: 2017-06-14 17:53:20.285053Z] Running download-and-extract step. 10:53:20 INFO - Running pre-action listener: _resource_record_pre_action 10:53:20 INFO - Running main action method: download_and_extract 10:53:20 INFO - mkdir: /builds/slave/test/build/tests 10:53:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:53:20 INFO - trying https://queue.taskcluster.net/v1/task/ciFHRDZfS0OKDPV45N_FDQ/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json 10:53:20 INFO - Downloading https://queue.taskcluster.net/v1/task/ciFHRDZfS0OKDPV45N_FDQ/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 10:53:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ciFHRDZfS0OKDPV45N_FDQ/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json'}, attempt #1 10:53:21 INFO - Downloaded 1290 bytes. 10:53:21 INFO - Reading from file /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 10:53:21 INFO - Using the following test package requirements: 10:53:21 INFO - {u'awsy': [u'firefox-55.0.en-US.mac.common.tests.zip', 10:53:21 INFO - u'firefox-55.0.en-US.mac.awsy.tests.zip'], 10:53:21 INFO - u'common': [u'firefox-55.0.en-US.mac.common.tests.zip'], 10:53:21 INFO - u'cppunittest': [u'firefox-55.0.en-US.mac.common.tests.zip', 10:53:21 INFO - u'firefox-55.0.en-US.mac.cppunittest.tests.zip'], 10:53:21 INFO - u'gtest': [u'firefox-55.0.en-US.mac.common.tests.zip', 10:53:21 INFO - u'firefox-55.0.en-US.mac.gtest.tests.zip'], 10:53:21 INFO - u'jittest': [u'firefox-55.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 10:53:21 INFO - u'mochitest': [u'firefox-55.0.en-US.mac.common.tests.zip', 10:53:21 INFO - u'firefox-55.0.en-US.mac.mochitest.tests.zip'], 10:53:21 INFO - u'mozbase': [u'firefox-55.0.en-US.mac.common.tests.zip'], 10:53:21 INFO - u'reftest': [u'firefox-55.0.en-US.mac.common.tests.zip', 10:53:21 INFO - u'firefox-55.0.en-US.mac.reftest.tests.zip'], 10:53:21 INFO - u'talos': [u'firefox-55.0.en-US.mac.common.tests.zip', 10:53:21 INFO - u'firefox-55.0.en-US.mac.talos.tests.zip'], 10:53:21 INFO - u'web-platform': [u'firefox-55.0.en-US.mac.common.tests.zip', 10:53:21 INFO - u'firefox-55.0.en-US.mac.web-platform.tests.tar.gz'], 10:53:21 INFO - u'xpcshell': [u'firefox-55.0.en-US.mac.common.tests.zip', 10:53:21 INFO - u'firefox-55.0.en-US.mac.xpcshell.tests.zip']} 10:53:21 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip'] for test suite category: common 10:53:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ciFHRDZfS0OKDPV45N_FDQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 10:53:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ciFHRDZfS0OKDPV45N_FDQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 10:53:21 INFO - Fetch https://queue.taskcluster.net/v1/task/ciFHRDZfS0OKDPV45N_FDQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 10:53:23 INFO - Content-Length response header: 58981043 10:53:23 INFO - Bytes received: 58981043 10:53:40 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip', u'firefox-55.0.en-US.mac.talos.tests.zip'] for test suite category: talos 10:53:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ciFHRDZfS0OKDPV45N_FDQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 10:53:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ciFHRDZfS0OKDPV45N_FDQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 10:53:41 INFO - Fetch https://queue.taskcluster.net/v1/task/ciFHRDZfS0OKDPV45N_FDQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 10:53:42 INFO - Content-Length response header: 58981043 10:53:42 INFO - Bytes received: 58981043 10:53:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ciFHRDZfS0OKDPV45N_FDQ/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip 10:53:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ciFHRDZfS0OKDPV45N_FDQ/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip'}, attempt #1 10:53:59 INFO - Fetch https://queue.taskcluster.net/v1/task/ciFHRDZfS0OKDPV45N_FDQ/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip into memory 10:54:00 INFO - Content-Length response header: 14450018 10:54:00 INFO - Bytes received: 14450018 10:54: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')]} 10:54:00 INFO - trying https://queue.taskcluster.net/v1/task/ciFHRDZfS0OKDPV45N_FDQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg 10:54:00 INFO - Downloading https://queue.taskcluster.net/v1/task/ciFHRDZfS0OKDPV45N_FDQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0.en-US.mac.dmg 10:54:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ciFHRDZfS0OKDPV45N_FDQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0.en-US.mac.dmg'}, attempt #1 10:54:02 INFO - Downloaded 51079895 bytes. 10:54:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ciFHRDZfS0OKDPV45N_FDQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg 10:54:02 INFO - mkdir: /builds/slave/test/properties 10:54:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:54:02 INFO - Writing to file /builds/slave/test/properties/build_url 10:54:02 INFO - Contents: 10:54:02 INFO - build_url:https://queue.taskcluster.net/v1/task/ciFHRDZfS0OKDPV45N_FDQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg 10:54:02 INFO - Running post-action listener: _resource_record_post_action 10:54:02 INFO - Running post-action listener: set_extra_try_arguments 10:54:02 INFO - [mozharness: 2017-06-14 17:54:02.546813Z] Finished download-and-extract step (success) 10:54:02 INFO - [mozharness: 2017-06-14 17:54:02.546950Z] Running populate-webroot step. 10:54:02 INFO - Running pre-action listener: _resource_record_pre_action 10:54:02 INFO - Running main action method: populate_webroot 10:54:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:54:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:54:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:54:02 INFO - '--tppagecycles', 10:54:02 INFO - '7'], 10:54:02 INFO - 'tests': ['tsvgm']}}, 10:54:02 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:54:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:54:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:54:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:54:02 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:54:02 INFO - 'talos_options': ['--disable-e10s'], 10:54:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:54:02 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:54:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:54:02 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:54:02 INFO - 'talos_options': ['--disable-e10s'], 10:54:02 INFO - 'tests': ['damp', 'tps']}, 10:54:02 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:54:02 INFO - 'tests': ['damp', 'tps']}, 10:54:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:54:02 INFO - 'tests': ['dromaeo_dom']}, 10:54:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:54:02 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:54:02 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 10:54:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 10:54:02 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 10:54:02 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 10:54:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:54:02 INFO - 'tests': ['a11yr', 10:54:02 INFO - 'ts_paint', 10:54:02 INFO - 'tpaint', 10:54:02 INFO - 'sessionrestore', 10:54:02 INFO - 'sessionrestore_no_auto_restore', 10:54:02 INFO - 'tabpaint']}, 10:54:02 INFO - 'other-e10s': {'tests': ['a11yr', 10:54:02 INFO - 'ts_paint', 10:54:02 INFO - 'tpaint', 10:54:02 INFO - 'sessionrestore', 10:54:02 INFO - 'sessionrestore_no_auto_restore', 10:54:02 INFO - 'tabpaint']}, 10:54:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:54:02 INFO - 'ts_paint', 10:54:02 INFO - 'tpaint', 10:54:02 INFO - 'sessionrestore', 10:54:02 INFO - 'sessionrestore_no_auto_restore', 10:54:02 INFO - 'tabpaint']}, 10:54:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:54:02 INFO - 'ts_paint', 10:54:02 INFO - 'tpaint', 10:54:02 INFO - 'sessionrestore', 10:54:02 INFO - 'sessionrestore_no_auto_restore', 10:54:02 INFO - 'tabpaint']}, 10:54:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:54:02 INFO - 'tests': ['a11yr', 10:54:02 INFO - 'ts_paint', 10:54:02 INFO - 'tpaint', 10:54:02 INFO - 'sessionrestore', 10:54:02 INFO - 'sessionrestore_no_auto_restore', 10:54:02 INFO - 'tabpaint']}, 10:54:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:54:02 INFO - 'tests': ['a11yr', 10:54:02 INFO - 'ts_paint', 10:54:02 INFO - 'tpaint', 10:54:02 INFO - 'sessionrestore', 10:54:02 INFO - 'sessionrestore_no_auto_restore', 10:54:02 INFO - 'tabpaint']}, 10:54:02 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 10:54:02 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 10:54:02 INFO - 'bloom_basic_ref']}, 10:54:02 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 10:54:02 INFO - 'talos_options': ['--mitmproxy', 10:54:02 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 10:54:02 INFO - '--firstNonBlankPaint'], 10:54:02 INFO - 'tests': ['quantum_pageload_google', 10:54:02 INFO - 'quantum_pageload_youtube', 10:54:02 INFO - 'quantum_pageload_amazon', 10:54:02 INFO - 'quantum_pageload_facebook']}, 10:54:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:54:02 INFO - 'tests': ['tsvgx', 10:54:02 INFO - 'tsvgr_opacity', 10:54:02 INFO - 'tart', 10:54:02 INFO - 'tscrollx', 10:54:02 INFO - 'cart', 10:54:02 INFO - 'tsvg_static']}, 10:54:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:54:02 INFO - 'tsvgr_opacity', 10:54:02 INFO - 'tart', 10:54:02 INFO - 'tscrollx', 10:54:02 INFO - 'cart', 10:54:02 INFO - 'tsvg_static']}, 10:54:02 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:54:02 INFO - 'talos_options': ['--disable-e10s'], 10:54:02 INFO - 'tests': ['tp5o']}, 10:54:02 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:54:02 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:54:02 INFO - 'talos_options': ['--disable-e10s', 10:54:02 INFO - '--xperf_path', 10:54:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:54:02 INFO - 'tests': ['tp5n']}, 10:54:02 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:54:02 INFO - 'talos_options': ['--xperf_path', 10:54:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:54:02 INFO - 'tests': ['tp5n']}}, 10:54:02 INFO - 'talos.zip': {'path': '', 10:54:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:54:02 INFO - Running post-action listener: _resource_record_post_action 10:54:02 INFO - [mozharness: 2017-06-14 17:54:02.570217Z] Finished populate-webroot step (success) 10:54:02 INFO - [mozharness: 2017-06-14 17:54:02.570380Z] Running create-virtualenv step. 10:54:02 INFO - Running pre-action listener: _resource_record_pre_action 10:54:02 INFO - Running main action method: create_virtualenv 10:54:02 INFO - Creating virtualenv /builds/slave/test/build/venv 10:54:02 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 10:54:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:54:02 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 10:54:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:54:02 INFO - Using real prefix '/tools/python27' 10:54:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:54:03 INFO - Installing distribute.............................................................................................................................................................................................done. 10:54:06 INFO - Installing pip.................done. 10:54:06 INFO - Return code: 0 10:54:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:54:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:54:06 INFO - Reading from file tmpfile_stdout 10:54:06 INFO - Output received: 10:54:06 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 10:54:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:54:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:54:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:54:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:54:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:54:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10e87cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e880768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffde0556830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5d2d30>, '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 0x10e59ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5c3be8>, '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.g9frllOJX9/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.4WQTWnHIdV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:54:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:54:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:54:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g9frllOJX9/Render', 10:54:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:54:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:54:06 INFO - 'HOME': '/Users/cltbld', 10:54:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:54:06 INFO - 'LOGNAME': 'cltbld', 10:54:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:54:06 INFO - 'MOZ_NO_REMOTE': '1', 10:54:06 INFO - 'NO_EM_RESTART': '1', 10:54:06 INFO - 'PAGER': '/bin/cat', 10:54:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:54:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:54:06 INFO - 'PWD': '/builds/slave/test', 10:54:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:54:06 INFO - 'SHELL': '/bin/bash', 10:54:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4WQTWnHIdV/Listeners', 10:54:06 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 10:54:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:54:06 INFO - 'USER': 'cltbld', 10:54:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:54:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:54:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:54:06 INFO - 'XPC_FLAGS': '0x0', 10:54:06 INFO - 'XPC_SERVICE_NAME': '0', 10:54:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:54:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:54:06 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 10:54:06 INFO - Cleaning up... 10:54:06 INFO - Return code: 0 10:54:06 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:54:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:54:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:54:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:54:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:54:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10e87cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e880768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffde0556830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5d2d30>, '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 0x10e59ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5c3be8>, '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.g9frllOJX9/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.4WQTWnHIdV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:54:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:54:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:54:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g9frllOJX9/Render', 10:54:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:54:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:54:06 INFO - 'HOME': '/Users/cltbld', 10:54:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:54:06 INFO - 'LOGNAME': 'cltbld', 10:54:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:54:06 INFO - 'MOZ_NO_REMOTE': '1', 10:54:06 INFO - 'NO_EM_RESTART': '1', 10:54:06 INFO - 'PAGER': '/bin/cat', 10:54:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:54:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:54:06 INFO - 'PWD': '/builds/slave/test', 10:54:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:54:06 INFO - 'SHELL': '/bin/bash', 10:54:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4WQTWnHIdV/Listeners', 10:54:06 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 10:54:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:54:06 INFO - 'USER': 'cltbld', 10:54:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:54:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:54:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:54:06 INFO - 'XPC_FLAGS': '0x0', 10:54:06 INFO - 'XPC_SERVICE_NAME': '0', 10:54:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:54:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:54:07 INFO - Downloading/unpacking psutil>=3.1.1 10:54: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 10:54: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 10:54: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 10:54: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 10:54:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:54:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:54:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:54:09 INFO - Installing collected packages: psutil 10:54:09 INFO - Running setup.py install for psutil 10:54:09 INFO - building 'psutil._psutil_osx' extension 10:54:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 10:54:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 10:54:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 10:54:09 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 10:54:09 INFO - building 'psutil._psutil_posix' extension 10:54:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 10:54:09 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:54:09 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:54:09 INFO - ^ 10:54:09 INFO - 1 warning generated. 10:54:09 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 10:54:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:54:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:54:10 INFO - Successfully installed psutil 10:54:10 INFO - Cleaning up... 10:54:10 INFO - Return code: 0 10:54:10 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:54: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')]} 10:54:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:54: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')]} 10:54:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:54: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e87cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e880768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffde0556830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5d2d30>, '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 0x10e59ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5c3be8>, '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.g9frllOJX9/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.4WQTWnHIdV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:54: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:54: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 mozsystemmonitor==0.3 10:54:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g9frllOJX9/Render', 10:54:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:54:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:54:10 INFO - 'HOME': '/Users/cltbld', 10:54:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:54:10 INFO - 'LOGNAME': 'cltbld', 10:54:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:54:10 INFO - 'MOZ_NO_REMOTE': '1', 10:54:10 INFO - 'NO_EM_RESTART': '1', 10:54:10 INFO - 'PAGER': '/bin/cat', 10:54:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:54:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:54:10 INFO - 'PWD': '/builds/slave/test', 10:54:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:54:10 INFO - 'SHELL': '/bin/bash', 10:54:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4WQTWnHIdV/Listeners', 10:54:10 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 10:54:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:54:10 INFO - 'USER': 'cltbld', 10:54:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:54:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:54:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:54:10 INFO - 'XPC_FLAGS': '0x0', 10:54:10 INFO - 'XPC_SERVICE_NAME': '0', 10:54:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:54:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:54:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:54: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 10:54: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 10:54: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 10:54: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 10:54:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:54:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:54:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:54:11 INFO - Installing collected packages: mozsystemmonitor 10:54:11 INFO - Running setup.py install for mozsystemmonitor 10:54:12 INFO - Successfully installed mozsystemmonitor 10:54:12 INFO - Cleaning up... 10:54:12 INFO - Return code: 0 10:54:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:54:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:54:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:54:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:54:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:54:12 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 0x10e87cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e880768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffde0556830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5d2d30>, '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 0x10e59ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5c3be8>, '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.g9frllOJX9/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.4WQTWnHIdV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:54:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:54:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:54:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g9frllOJX9/Render', 10:54:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:54:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:54:12 INFO - 'HOME': '/Users/cltbld', 10:54:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:54:12 INFO - 'LOGNAME': 'cltbld', 10:54:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:54:12 INFO - 'MOZ_NO_REMOTE': '1', 10:54:12 INFO - 'NO_EM_RESTART': '1', 10:54:12 INFO - 'PAGER': '/bin/cat', 10:54:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:54:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:54:12 INFO - 'PWD': '/builds/slave/test', 10:54:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:54:12 INFO - 'SHELL': '/bin/bash', 10:54:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4WQTWnHIdV/Listeners', 10:54:12 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 10:54:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:54:12 INFO - 'USER': 'cltbld', 10:54:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:54:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:54:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:54:12 INFO - 'XPC_FLAGS': '0x0', 10:54:12 INFO - 'XPC_SERVICE_NAME': '0', 10:54:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:54:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:54:13 INFO - Downloading/unpacking jsonschema==2.5.1 10:54:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:54:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:54:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:54:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:54:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:54:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:54:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:54:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:54:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:54:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:54:15 INFO - Downloading functools32-3.2.3-2.tar.gz 10:54:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:54:15 INFO - warning: no files found matching '*.txt' 10:54:15 INFO - no previously-included directories found matching 'build' 10:54:15 INFO - no previously-included directories found matching 'dist' 10:54:15 INFO - no previously-included directories found matching '.git*' 10:54:15 INFO - Installing collected packages: jsonschema, functools32 10:54:15 INFO - Running setup.py install for functools32 10:54:15 INFO - warning: no files found matching '*.txt' 10:54:15 INFO - no previously-included directories found matching 'build' 10:54:15 INFO - no previously-included directories found matching 'dist' 10:54:15 INFO - no previously-included directories found matching '.git*' 10:54:15 INFO - Successfully installed jsonschema functools32 10:54:15 INFO - Cleaning up... 10:54:15 INFO - Return code: 0 10:54:15 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:54:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:54:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:54:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:54:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:54:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e87cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e880768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffde0556830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5d2d30>, '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 0x10e59ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5c3be8>, '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.g9frllOJX9/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.4WQTWnHIdV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:54:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:54:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:54:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g9frllOJX9/Render', 10:54:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:54:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:54:15 INFO - 'HOME': '/Users/cltbld', 10:54:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:54:15 INFO - 'LOGNAME': 'cltbld', 10:54:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:54:15 INFO - 'MOZ_NO_REMOTE': '1', 10:54:15 INFO - 'NO_EM_RESTART': '1', 10:54:15 INFO - 'PAGER': '/bin/cat', 10:54:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:54:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:54:15 INFO - 'PWD': '/builds/slave/test', 10:54:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:54:15 INFO - 'SHELL': '/bin/bash', 10:54:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4WQTWnHIdV/Listeners', 10:54:15 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 10:54:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:54:15 INFO - 'USER': 'cltbld', 10:54:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:54:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:54:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:54:15 INFO - 'XPC_FLAGS': '0x0', 10:54:15 INFO - 'XPC_SERVICE_NAME': '0', 10:54:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:54:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:54:15 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:54:15 INFO - Cleaning up... 10:54:15 INFO - Return code: 0 10:54:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:54:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:54:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:54:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:54:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:54:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e87cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e880768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffde0556830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5d2d30>, '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 0x10e59ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5c3be8>, '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.g9frllOJX9/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.4WQTWnHIdV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:54:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:54:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:54:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g9frllOJX9/Render', 10:54:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:54:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:54:15 INFO - 'HOME': '/Users/cltbld', 10:54:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:54:15 INFO - 'LOGNAME': 'cltbld', 10:54:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:54:15 INFO - 'MOZ_NO_REMOTE': '1', 10:54:15 INFO - 'NO_EM_RESTART': '1', 10:54:15 INFO - 'PAGER': '/bin/cat', 10:54:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:54:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:54:15 INFO - 'PWD': '/builds/slave/test', 10:54:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:54:15 INFO - 'SHELL': '/bin/bash', 10:54:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4WQTWnHIdV/Listeners', 10:54:15 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 10:54:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:54:15 INFO - 'USER': 'cltbld', 10:54:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:54:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:54:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:54:15 INFO - 'XPC_FLAGS': '0x0', 10:54:15 INFO - 'XPC_SERVICE_NAME': '0', 10:54:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:54:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:54:15 INFO - Downloading/unpacking blobuploader==1.2.4 10:54:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:54:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:54:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:54:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:54:17 INFO - Downloading blobuploader-1.2.4.tar.gz 10:54:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:54:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:54:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:54:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:54:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:54:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:54:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:54:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:54:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:54:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:54:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:54:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:54:18 INFO - Downloading docopt-0.6.1.tar.gz 10:54:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:54:18 INFO - Installing collected packages: blobuploader, requests, docopt 10:54:18 INFO - Running setup.py install for blobuploader 10:54:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:54:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:54:18 INFO - Running setup.py install for requests 10:54:19 INFO - Running setup.py install for docopt 10:54:19 INFO - Successfully installed blobuploader requests docopt 10:54:19 INFO - Cleaning up... 10:54:19 INFO - Return code: 0 10:54:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:54:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:54:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:54:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:54:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:54:19 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 0x10e87cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e880768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffde0556830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5d2d30>, '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 0x10e59ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5c3be8>, '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.g9frllOJX9/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.4WQTWnHIdV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:54:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:54:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:54:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g9frllOJX9/Render', 10:54:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:54:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:54:19 INFO - 'HOME': '/Users/cltbld', 10:54:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:54:19 INFO - 'LOGNAME': 'cltbld', 10:54:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:54:19 INFO - 'MOZ_NO_REMOTE': '1', 10:54:19 INFO - 'NO_EM_RESTART': '1', 10:54:19 INFO - 'PAGER': '/bin/cat', 10:54:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:54:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:54:19 INFO - 'PWD': '/builds/slave/test', 10:54:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:54:19 INFO - 'SHELL': '/bin/bash', 10:54:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4WQTWnHIdV/Listeners', 10:54:19 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 10:54:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:54:19 INFO - 'USER': 'cltbld', 10:54:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:54:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:54:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:54:19 INFO - 'XPC_FLAGS': '0x0', 10:54:19 INFO - 'XPC_SERVICE_NAME': '0', 10:54:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:54:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:54:19 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-Y3GiRz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:54:19 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-kJCGBJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:54:19 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-Fjm4Vv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:54:19 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-jYezJs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:54:19 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-VlzFLK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:54:19 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-hCGYFS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:54:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:54:20 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-pf9G0s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:54:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:54:20 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-RwsYwp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:54:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:54:20 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-J4hX2f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:54:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:54:20 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-UyJsl5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:54:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:54:20 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-hp50GH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:54:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:54:20 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-lMutfI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:54:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:54:20 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-ImHesz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:54:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:54:20 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-a2TeVP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:54:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:54:20 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-JuoCEw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:54:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:54:21 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-sG8xvX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:54:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:54:21 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-aNRGxz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:54:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:54:21 INFO - Running setup.py install for manifestparser 10:54:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:54:21 INFO - Running setup.py install for mozcrash 10:54:21 INFO - Running setup.py install for mozdebug 10:54:21 INFO - Running setup.py install for mozdevice 10:54:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:54:21 INFO - Running setup.py install for mozfile 10:54:21 INFO - Running setup.py install for mozhttpd 10:54:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:54:22 INFO - Running setup.py install for mozinfo 10:54:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:54:22 INFO - Running setup.py install for mozInstall 10:54:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:54:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:54:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:54:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:54:22 INFO - Running setup.py install for mozleak 10:54:22 INFO - Running setup.py install for mozlog 10:54:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:54:22 INFO - Running setup.py install for moznetwork 10:54:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:54:22 INFO - Running setup.py install for mozprocess 10:54:23 INFO - Running setup.py install for mozprofile 10:54:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:54:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:54:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:54:23 INFO - Running setup.py install for mozrunner 10:54:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:54:23 INFO - Running setup.py install for mozscreenshot 10:54:23 INFO - Running setup.py install for moztest 10:54:23 INFO - Running setup.py install for mozversion 10:54:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:54:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:54:23 INFO - Cleaning up... 10:54:23 INFO - Return code: 0 10:54:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:54:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:54:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:54:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:54:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:54:23 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 0x10e87cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e880768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffde0556830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5d2d30>, '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 0x10e59ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5c3be8>, '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.g9frllOJX9/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.4WQTWnHIdV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:54:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:54:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:54:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g9frllOJX9/Render', 10:54:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:54:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:54:23 INFO - 'HOME': '/Users/cltbld', 10:54:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:54:23 INFO - 'LOGNAME': 'cltbld', 10:54:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:54:23 INFO - 'MOZ_NO_REMOTE': '1', 10:54:23 INFO - 'NO_EM_RESTART': '1', 10:54:23 INFO - 'PAGER': '/bin/cat', 10:54:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:54:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:54:23 INFO - 'PWD': '/builds/slave/test', 10:54:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:54:23 INFO - 'SHELL': '/bin/bash', 10:54:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4WQTWnHIdV/Listeners', 10:54:23 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 10:54:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:54:23 INFO - 'USER': 'cltbld', 10:54:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:54:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:54:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:54:23 INFO - 'XPC_FLAGS': '0x0', 10:54:23 INFO - 'XPC_SERVICE_NAME': '0', 10:54:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:54:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:54:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:54:24 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-nSWr_t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:54:24 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 10:54:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:54:24 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-zBZY9k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:54:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:54:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:54:24 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-FywltK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:54:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:54:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:54:24 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-snzG42-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:54:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:54:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:54:24 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-D8uLyS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:54:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 10:54:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:54:24 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-6gC0kA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:54:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 10:54:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:54:24 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-N0eCYM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:54:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 10:54:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:54:24 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-HjhGSd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:54:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 10:54:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:54:24 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-6oWDQw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:54:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 10:54:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:54:25 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-KVZ99s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:54:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:54:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:54:25 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-UhIOV5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:54:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 10:54:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:54:25 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-_Pnul1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:54:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 10:54:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:54:25 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-KFUexF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:54:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 10:54:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:54:25 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-B2oPtr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:54:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 10:54:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:54:25 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-bWt1I5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:54:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 10:54:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:54:25 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-575gWm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:54:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 10:54:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:54:25 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-MxVS8t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:54:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 10:54:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:54:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:54:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:54:25 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:54:25 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:54:25 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 10:54:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:54:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:54:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:54:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:54:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:54:27 INFO - Downloading blessings-1.6.tar.gz 10:54:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:54:27 INFO - Installing collected packages: blessings 10:54:27 INFO - Running setup.py install for blessings 10:54:27 INFO - Successfully installed blessings 10:54:27 INFO - Cleaning up... 10:54:27 INFO - Return code: 0 10:54:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:54:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:54:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:54:27 INFO - Reading from file tmpfile_stdout 10:54:27 INFO - Current package versions: 10:54:27 INFO - blessings == 1.6 10:54:27 INFO - blobuploader == 1.2.4 10:54:27 INFO - docopt == 0.6.1 10:54:27 INFO - functools32 == 3.2.3-2 10:54:27 INFO - jsonschema == 2.5.1 10:54:27 INFO - manifestparser == 1.1 10:54:27 INFO - mozInstall == 1.13 10:54:27 INFO - mozcrash == 1.0 10:54:27 INFO - mozdebug == 0.1 10:54:27 INFO - mozdevice == 0.51 10:54:27 INFO - mozfile == 1.2 10:54:27 INFO - mozhttpd == 0.7 10:54:27 INFO - mozinfo == 0.10 10:54:27 INFO - mozleak == 0.1 10:54:27 INFO - mozlog == 3.4 10:54:27 INFO - moznetwork == 0.27 10:54:27 INFO - mozprocess == 0.25 10:54:27 INFO - mozprofile == 0.28 10:54:27 INFO - mozrunner == 6.13 10:54:27 INFO - mozscreenshot == 0.1 10:54:27 INFO - mozsystemmonitor == 0.3 10:54:27 INFO - moztest == 0.8 10:54:27 INFO - mozversion == 1.4 10:54:27 INFO - psutil == 3.1.1 10:54:27 INFO - requests == 1.2.3 10:54:27 INFO - wsgiref == 0.1.2 10:54:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:54:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:54:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:54:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:54:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:54:27 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 0x10e87cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e880768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffde0556830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5d2d30>, '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 0x10e59ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5c3be8>, '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.g9frllOJX9/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.4WQTWnHIdV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:54:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 10:54:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:54:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g9frllOJX9/Render', 10:54:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:54:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:54:27 INFO - 'HOME': '/Users/cltbld', 10:54:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:54:27 INFO - 'LOGNAME': 'cltbld', 10:54:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:54:27 INFO - 'MOZ_NO_REMOTE': '1', 10:54:27 INFO - 'NO_EM_RESTART': '1', 10:54:27 INFO - 'PAGER': '/bin/cat', 10:54:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:54:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:54:27 INFO - 'PWD': '/builds/slave/test', 10:54:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:54:27 INFO - 'SHELL': '/bin/bash', 10:54:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4WQTWnHIdV/Listeners', 10:54:27 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 10:54:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:54:27 INFO - 'USER': 'cltbld', 10:54:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:54:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:54:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:54:27 INFO - 'XPC_FLAGS': '0x0', 10:54:27 INFO - 'XPC_SERVICE_NAME': '0', 10:54:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:54:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:54:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:54:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 10:54:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 10:54:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:54:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 10:54:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 10:54:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 10:54:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 10:54:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 10:54:28 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 10:54:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:54:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:54:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:54:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:54:29 INFO - Downloading marionette_driver-1.4.0.tar.gz 10:54:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 10:54:29 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:54:29 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:54:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 10:54:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 10:54:29 INFO - Installing collected packages: marionette-driver 10:54:29 INFO - Running setup.py install for marionette-driver 10:54:29 INFO - Successfully installed marionette-driver 10:54:29 INFO - Cleaning up... 10:54:29 INFO - Return code: 0 10:54:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:54:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:54:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:54:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:54:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:54:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10e87cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e880768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffde0556830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5d2d30>, '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 0x10e59ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5c3be8>, '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.g9frllOJX9/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.4WQTWnHIdV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:54:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:54:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:54:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g9frllOJX9/Render', 10:54:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:54:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:54:29 INFO - 'HOME': '/Users/cltbld', 10:54:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:54:29 INFO - 'LOGNAME': 'cltbld', 10:54:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:54:29 INFO - 'MOZ_NO_REMOTE': '1', 10:54:29 INFO - 'NO_EM_RESTART': '1', 10:54:29 INFO - 'PAGER': '/bin/cat', 10:54:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:54:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:54:29 INFO - 'PWD': '/builds/slave/test', 10:54:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:54:29 INFO - 'SHELL': '/bin/bash', 10:54:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4WQTWnHIdV/Listeners', 10:54:29 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 10:54:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:54:29 INFO - 'USER': 'cltbld', 10:54:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:54:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:54:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:54:29 INFO - 'XPC_FLAGS': '0x0', 10:54:29 INFO - 'XPC_SERVICE_NAME': '0', 10:54:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:54:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:54:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:54:30 INFO - Cleaning up... 10:54:30 INFO - Return code: 0 10:54:30 INFO - Running post-action listener: _resource_record_post_action 10:54:30 INFO - Running post-action listener: _start_resource_monitoring 10:54:30 INFO - Starting resource monitoring. 10:54:30 INFO - [mozharness: 2017-06-14 17:54:30.152061Z] Finished create-virtualenv step (success) 10:54:30 INFO - [mozharness: 2017-06-14 17:54:30.153103Z] Running install step. 10:54:30 INFO - Running pre-action listener: _resource_record_pre_action 10:54:30 INFO - Running main action method: install 10:54:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:54:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:54:30 INFO - Reading from file tmpfile_stdout 10:54:30 INFO - Detecting whether we're running mozinstall >=1.0... 10:54:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:54:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:54:30 INFO - Reading from file tmpfile_stdout 10:54:30 INFO - Output received: 10:54:30 INFO - Usage: mozinstall [options] installer 10:54:30 INFO - Options: 10:54:30 INFO - -h, --help show this help message and exit 10:54:30 INFO - -d DEST, --destination=DEST 10:54:30 INFO - Directory to install application into. [default: 10:54:30 INFO - "/builds/slave/test"] 10:54:30 INFO - --app=APP Application being installed. [default: firefox] 10:54:30 INFO - mkdir: /builds/slave/test/build/application 10:54:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:54:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0.en-US.mac.dmg --destination /builds/slave/test/build/application 10:54:46 INFO - Reading from file tmpfile_stdout 10:54:46 INFO - Output received: 10:54:46 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 10:54:46 INFO - Running post-action listener: _resource_record_post_action 10:54:46 INFO - [mozharness: 2017-06-14 17:54:46.077369Z] Finished install step (success) 10:54:46 INFO - [mozharness: 2017-06-14 17:54:46.077511Z] Running setup-mitmproxy step. 10:54:46 INFO - Running pre-action listener: _resource_record_pre_action 10:54:46 INFO - Running main action method: setup_mitmproxy 10:54:46 INFO - Skipping: mitmproxy is not required 10:54:46 INFO - Running post-action listener: _resource_record_post_action 10:54:46 INFO - [mozharness: 2017-06-14 17:54:46.078151Z] Finished setup-mitmproxy step (success) 10:54:46 INFO - [mozharness: 2017-06-14 17:54:46.078295Z] Running run-tests step. 10:54:46 INFO - Running pre-action listener: _resource_record_pre_action 10:54:46 INFO - Running main action method: run_tests 10:54:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:54:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:54:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:54:46 INFO - 2017-06-14 10:54:46.092 screenresolution[1629:7713] starting screenresolution argv=screenresolution get 10:54:46 INFO - 2017-06-14 10:54:46.095 screenresolution[1629:7713] Display 0: 1600x1200x32@60 10:54:46 INFO - 2017-06-14 10:54:46.101 screenresolution[1630:7715] starting screenresolution argv=screenresolution list 10:54:46 INFO - Available Modes on Display 0 10:54:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:54:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:54:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:54:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:54:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:54:46 INFO - Intel Iris: 10:54:46 INFO - Chipset Model: Intel Iris 10:54:46 INFO - Type: GPU 10:54:46 INFO - Bus: Built-In 10:54:46 INFO - VRAM (Dynamic, Max): 1536 MB 10:54:46 INFO - Vendor: Intel (0x8086) 10:54:46 INFO - Device ID: 0x0a2e 10:54:46 INFO - Revision ID: 0x0009 10:54:46 INFO - Displays: 10:54:46 INFO - Display: 10:54:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:54:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:54:46 INFO - Display Serial Number: 1600x1200 60 10:54:46 INFO - Main Display: Yes 10:54:46 INFO - Mirror: Off 10:54:46 INFO - Online: Yes 10:54:46 INFO - Rotation: Supported 10:54:46 INFO - Return code: 0 10:54:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:54:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:54:46 INFO - Python 2.7.3 10:54:46 INFO - Return code: 0 10:54:46 INFO - grabbing minidump binary from tooltool 10:54:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:54:46 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 0x10e5d2d30>, '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 0x10e59ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5c3be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:54:46 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 10:54:46 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 10:54:46 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 10:54:46 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:54:46 INFO - Return code: 0 10:54:46 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:54:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:54:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:54:46 INFO - ENV: RUST_BACKTRACE is now 1 10:54:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:54:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:54:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ciFHRDZfS0OKDPV45N_FDQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0325', '--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 10:54:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ciFHRDZfS0OKDPV45N_FDQ/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0325 --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 10:54:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g9frllOJX9/Render', 10:54:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:54:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:54:46 INFO - 'HOME': '/Users/cltbld', 10:54:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:54:46 INFO - 'LOGNAME': 'cltbld', 10:54:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:54:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:54:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:54:46 INFO - 'MOZ_NO_REMOTE': '1', 10:54:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:54:46 INFO - 'NO_EM_RESTART': '1', 10:54:46 INFO - 'PAGER': '/bin/cat', 10:54:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:54:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:54:46 INFO - 'PWD': '/builds/slave/test', 10:54:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:54:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:54:46 INFO - 'RUST_BACKTRACE': '1', 10:54:46 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 10:54:46 INFO - 'SHELL': '/bin/bash', 10:54:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4WQTWnHIdV/Listeners', 10:54:46 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 10:54:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:54:46 INFO - 'USER': 'cltbld', 10:54:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:54:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:54:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:54:46 INFO - 'XPC_FLAGS': '0x0', 10:54:46 INFO - 'XPC_SERVICE_NAME': '0', 10:54:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:54:46 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ciFHRDZfS0OKDPV45N_FDQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0325', '--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 10:54:46 INFO - mozversion application_buildid: 20170614093257 10:54:46 INFO - mozversion application_changeset: 21965d67c5fe68e82b9c3d2a04ba128baf2bd589 10:54:46 INFO - mozversion application_display_name: Firefox 10:54:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:54:46 INFO - mozversion application_name: Firefox 10:54:46 INFO - mozversion application_remotingname: firefox 10:54:46 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 10:54:46 INFO - mozversion application_vendor: Mozilla 10:54:46 INFO - mozversion application_version: 55.0 10:54:46 INFO - mozversion platform_buildid: 20170614093257 10:54:46 INFO - mozversion platform_changeset: 21965d67c5fe68e82b9c3d2a04ba128baf2bd589 10:54:46 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 10:54:46 INFO - mozversion platform_version: 55.0 10:54:46 INFO - using testdate: 1497462886 10:54:46 INFO - actual date: 1497462886 10:54:46 INFO - starting webserver on 'localhost:49228' 10:54:46 INFO - SUITE-START | Running 2 tests 10:54:46 INFO - TEST-START | dromaeo_css 10:54:46 INFO - operating with platform_type : mac_ 10:54:46 INFO - Initialising browser for dromaeo_css test... 10:54:46 INFO - Application command: /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/tmpXDm_mw/profile 10:54:46 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 10:55:19 INFO - TEST-INFO | 1640: killed by SIGTERM 10:55:19 INFO - Browser initialized. 10:55:19 INFO - Running cycle 1/1 for dromaeo_css test... 10:55:19 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/tmpXDm_mw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:55:21 INFO - PID 1650 | 2017-06-14 10:55:21.605 plugin-container[1651:8240] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x494b, name = 'com.apple.tsm.portname' 10:55:21 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:55:21 INFO - PID 1650 | 2017-06-14 10:55:21.606 plugin-container[1651:8240] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 10:55:21 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:55:21 INFO - PID 1650 | 2017-06-14 10:55:21.606 plugin-container[1651:8240] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:55:31 INFO - PID 1650 | RSS: Main: 184881152 10:55:31 INFO - PID 1650 | 10:58:34 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49228/tests/dromaeo/cssquery-ext.html) 10:58:37 INFO - PID 1650 | RSS: Main: 203939840 10:58:37 INFO - PID 1650 | 11:01:38 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-ext.html (next: http://localhost:49228/tests/dromaeo/cssquery-jquery.html) 11:01:39 INFO - PID 1650 | RSS: Main: 209760256 11:01:39 INFO - PID 1650 | 11:04:41 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49228/tests/dromaeo/cssquery-mootools.html) 11:04:41 INFO - PID 1650 | RSS: Main: 214749184 11:04:41 INFO - PID 1650 | 11:07:43 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49228/tests/dromaeo/cssquery-prototype.html) 11:07:43 INFO - PID 1650 | RSS: Main: 216199168 11:07:43 INFO - PID 1650 | 11:10:46 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49228/tests/dromaeo/cssquery-yui.html) 11:10:47 INFO - PID 1650 | RSS: Main: 216760320 11:10:47 INFO - PID 1650 | 11:13:48 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-yui.html (next: http://localhost:49228/tests/dromaeo/cssquery-dojo.html) 11:13:49 INFO - PID 1650 | RSS: Main: 215474176 11:13:49 INFO - PID 1650 | 11:13:49 INFO - PID 1650 | __start_tp_report 11:13:49 INFO - PID 1650 | _x_x_mozilla_page_load 11:13:49 INFO - PID 1650 | _x_x_mozilla_page_load_details 11:13:49 INFO - PID 1650 | |i|pagename|runs| 11:13:49 INFO - PID 1650 | |0;dojo.html;3091.9072427827173;3662.7436079474437;3727.8881633551005;3864.4010178422336;3871.767693938364;5404.297441332631;6338.841528961772;6375.298717141117;6419.390157934989;6453.322401147871;6436.645984470852;6466.321036291192;6472.967635161829;6487;6494.5453818232745;5739.51214146796;5740.8851822963725;5748.338941021773;5915.763369465216;5972.402759724015;5614.185943038257;5730.341010783751;5742.971285143569;5767.307923049219;5781.768729250825;5682.26130603021;5685.857853553694;5722.828315150552;5722.856928576777;5730.570207234474;4763.476017638057;5728.3126024876865;5756.971215143897;5781.84363127372;5835.562332825013;5700.572457065737;5795.913061304083;5799.623024503394;5826.329972053226;5826.796062137847;6454.419102280817;6483.152301594023;6516.771912983023;6519.217693876718;6533.18335208099;6773.559718618274;6891.448684105259;7094.432445404355;7097.89353159703;7117.64411779409;6486.124373209651;7076.150861896554;7113.430925525946;7139.535930164575;7174.282571742836;6598.86802263952;6628.502862285348;6704.597724136567;6706.83232919181;6724.831879203059;6044.879102417927;6972.372486476242;7036.226015138331;7397.482176247719;7407.925920740831;5651.745671444713;6774.525783195129;6807.795766127024;6818.386345228854;6840.965795170992;6987.475939304572;7047.43620510358;7123.145222573325;7167.319104685047;7172.784816455514;7042.0493233412835;7057.8235544110785;7074.610896400747;7097;7101;7024.964875175591;7056.823579410453;7072.328128827753;7089.291070892869;7115.644217789089;5482.91775623366;5511.311086114236;5549.417311182348;5559.471850174227;5582.7766889324785;3680.429533422324;4917.164082106051;4987.600991920638;5019.648624596243;5032.974835125801;5496.258005169251;6652.368025037614;6705.966470167618;6706;6724.293949135368;5981.222441082631;7197.1363436387965;7211.098612673416;7220.205777364486;7238.92761072393;6431.0031945048595;6512.869742605216;6523.836904077341;6548.770793022317;6711.6979735912;6060.424259695322;6246.593971391936;6286.151369565142;6309.716062777186;6355.332690067568;6045.909311360333;7397.334239918433;7478.775636730907;7486;7516.661750221144;5864.970675146597;6403.903941440975;7207.603581802841;7227.819304517429;7262;7088.539244949062;7428.554286742813;7435.9628201860805;7444.664990075568;7451.552906825391;6908.378245957687;6917.515773895779;6922.203946546211;6927.757528486681;6995.265497122932;7165.140183178052;7227.1327440707455;7240.022596949555;7241.131064272321;7259.092613423322;6996.230414654486;6998.6850591724515;7015.543989640657;7016.859662806613;7032.1912980007955;6667.633280169443;6922.550034247758;6961.060256865258;6969.756058537927;7012.474064445085;6598.769043083469;6653.767118150841;6686.0973768540625;6729.69716362755;6819.4544436443975;6965.094537710065;6988.685509152202;6990.16118065815;7026.086608740831;7035.542689725152;6842.8973565397555;6853.451723862178;6855.177378714387;6860.451163906975;6879.312068793281 11:13:49 INFO - PID 1650 | |1;ext.html;794.6463823598501;864.2955990867447;864.6325311742507;884.6417201033581;902.2331018634162;33315.83342083289;43206;43305.917352066215;43327.35008974864;43366.69899903;42311.57688423115;42538.57461425385;42634.72095837122;42685.14629707412;43449.34825977613;7991.760247192594;8126.837463250731;8132.715354962577;8148;8160.755177344689;8073.11195768465;8114.472559283658;8189;8193.631286592117;8197.05733840607;8113.634886430124;8119.675212991503;8137.349012079049;8150.959245203766;8748.25639820616;8912.598933048028;8965.910340896573;9007.05425930281;9015.233705135071;9024.819503609924;7294.854102917965;7700.383969282444;8428.747137585882;8769;8793.604287807064;39351.01622459433;39365.01587460336;39516;39585.40621890674;40371.59628403708;3656.2687462507606;3663.9633603663888;3690.7785532867847;3695.8891233262775;3711.7030637548933;32074.358512829614;32434;32468.83765581157;32496.70013199469;32500.912413320697;2717.3070866928806;3276.4921437177277;3297.9340413191603;3332.583427071616;3336.2660214752714;263842.3615763837;267674;267815;287151.1284887145;290992.54503727343;42404.363934541005;51420;52148;52152.739236303576;52310.73844630752;47978;54344;54900.45099549033;54970;55040.17439738407;15926.124063176405;15929.203539822962;16108.919455402647;16113.919430402773;16127.193640317937;13042.869571304354;13081.672958175932;13087.738245235232;13114.803277950838;13145;14236.715265694835;14307.356168972421;14318.713625727427;14346.282685865664;14418.42326306936;6164.075388691732;6179.567430279837;6242.469390101892;6252.124702541648;6286.371362863677;1023.5803320638503;1027.5427434791595;1030.4023666273542;1085.918556108295;1086.6468397770757;24281.39296517566;25981.2514670701;26005.349866253116;26021.219363419128;26121.477570448485;1089.4600954148086;1135.233717240859;1174.723939874129;1180.527788884453;1213.7997230457052;9971.00289970997;10659.520321585547;11273.887261127447;11841.407929603485;12787.936060319638;354879;356990;358120;358687;358863;59326;59519;59741.70129149414;59827.10259346113;59851;1489.6871656951944;1566.6788308396751;1578.7316156253464;1579.7077540655016;1583.841615838407;23681.64477532804;24049.797510124772;24913.750862491142;25338.36654083589;25436.745632543436;40042.1991560173;40154.59845401509;40170.19659606733;40235;40287.3956890641;294432;294510;294683;295486;295603;24825.25524234276;31241;31258.374832502766;31429.528557071204;36757.63242367649;38777;38895.2220955585;38981.6101838978;44256.778716106215;44320.335194971456;29069;29436;30539;31309.217269568442;31469.685303146674;30255.789768409017;30861.38277234398;31317.8434107828;31483.527747083353;31724.04827855168;35045.64954350424;37851.05372365603;37859.62140378561;38113.237735244584;38227.42658860064;29752.70247297586;30821.845890770404;31174;33399.33201336008;33495.1626209337;24210.48954887295;24315.75684243135;24481;25805.354866127745;26080.86959565266 11:13:49 INFO - PID 1650 | |2;jquery.html;471.6981132075472;547.3213215612642;549.3407910507392;551.5532418740822;551.8123837895115;23954.640680389788;24734;24763.62854557183;24824.50350992979;24832.627510587357;24772.009119635237;27265.182044538647;27321.590176147372;27351.316217094583;27412.725872741266;6668.96665516673;6772.627505487197;6774.93225067748;6810.59136451812;6826.351496607839;6769.492288078389;6809.0467334573195;6809.557378770364;6830.795076147723;6839.452843772487;6124.356942521038;6767.864642707143;6774.491913106512;6801.6599170041545;6805.353491418332;6533.673316334188;6919.273476284993;6923.826904327382;6930.549514281555;6965.721371145148;6322.589031712924;6322.683865806691;6341.492680585542;6343.46080583151;6345.650989195638;29808.403831923457;32146.03561893147;32264.6773532264;32430;32662;2333.9066437342485;2365.751596082421;2384.3204686664217;2385.1175065225975;2393.664886915833;19622.31321903741;19942;20176.092075856475;20190.697139542917;20222.292219772386;2294.8967296471533;2305.7002589663402;2346.3782097743942;2354.705661792276;2391.641253811925;209516;210702;212193.9390303054;214129;214752;13629.795553066711;16522.00867947903;16595.751063734053;16633.334666613442;16727.498175054767;18854.05729713509;21739.78260217409;22206.222782202545;22230.444238894157;22366;10841.512131954081;10875.129989600813;11025.228234023542;11061.944690276498;11158.832617510743;10217.33587316822;10288.639897603547;10313.896861031444;10316.123129534073;10320.535575898952;9714.22286217101;9911.207103431709;10128.848067278997;10176.440295783656;10204.336718113298;2428.8178386621075;2528.8229823912516;2626.500964816679;2693.165118813174;2843.5734639804195;997.7306127345576;1003.3377970539555;1017.4454922067491;1053.951318438159;1062.4315990944808;19168;21442.035108420157;21556.839129900454;21658.133674652836;21867.234646787285;1029.696239609317;1049.8740151181755;1071.5338827610015;1083.8970297821695;1084.9186311026547;10286.948565257275;10544;10809.78380432387;12946.287954162612;15137.394504219708;604464.1184647705;617875;628271;630463;638197;125198.37400812938;163221;164798;167444;167968;1227.699213692651;1271.2436100520176;1286.2475451860766;1287.4721364240804;1291.9095663303478;22732.772672273728;23783.643245350988;24254.757452425954;24349.51300974006;24618.138365157134;67292.32707673057;69311.65344173448;69943.30056699368;70362;71116;75116;91226;95842.5207873984;95910;96125.51937240269;12231.143819932518;12449.626511204679;12509.937450312691;12542.874571254171;12573.1198816082;14218.644533886736;15331.923340383226;19255.903720481307;19394.903025484782;19442.319518816774;14440.638984025483;15735.763963540785;16066.357345706509;16274.43039493612;16301.103439310951;13599.728005440034;14093.365798539295;14204.857951420354;14574.562763117123;14678.486252981094;16313.918430407772;16443.58891027734;16462.75305870389;16556.337746490004;16612.169391530133;11307.547698092314;12169.33068681231;12354.567590134662;12379.62861114168;12382.938085309515;15469.303881325142;15643.530694079196;15690.137042462775;15694.058356498646;15794.526164215093 11:13:49 INFO - PID 1650 | |3;mootools.html;1146.495541961537;1516.969660606787;1525.984740152598;1528.0831501099349;1537.507997440819;13998.090124141936;14598.124112553254;14622.415103395879;14622.780658290107;14667;14058.50795222165;14438.566842994727;14445.844332453378;14447.349869255882;14466.493672721457;2893.493638613245;2933.3399985003416;2944.8085874418202;2947.675755666875;2948.557716342544;2515.383730985902;2811.8734656940487;2935.060780550225;2936.8825246990095;2941.3234456359883;2670.664667666167;2751.1883994221653;2793.4413117376503;2861.1845623997165;2943.5143201371748;2926.839023853687;2934.9119526414243;2954.6602140753876;2957.3641667041475;2962.096560549032;2894.18962690447;2934.2517657997073;2942.661593916684;2946.4401763664837;2949.351142748592;14036.929815350857;14039;14052.437902483889;14134.36395362211;14247.928760356132;7765.223477652246;8004.159563245834;8066.717664881759;8095.919040809576;8099.595020248964;4919.458859525449;5125.487451254882;5191;5201.8959620807755;5206.687598744087;1224.663217615154;1443.6174413780357;1479.4599971010612;1509.6603264265561;1510.3958416633325;21119.521633485507;21360.572788544298;21473.926303684908;21483.462913427134;21488.677669834964;5528.944710552923;6878.8993760998;6896.344847239504;6956.443484521327;7092.078029856086;14814.555563332902;14866.479673211386;14878.92560537212;14903;14907.552773416815;7717.189695082048;7753.53478791263;7807.375409967189;7859.253370929752;7862.2137786222065;7136.3220494052985;7266.745663901738;7280.635968201569;7310.122785265696;7323.450741194431;5246.947530524722;5338.599605029561;5363.088274993261;5389.568834493231;5428.5114339708825;1583.6199312165227;1584.6196912740859;1594.3542865139636;1599.6240883392393;1615.1843319124002;694.7290556682898;708.5713143548182;835.1481488881428;835.7450977451936;838.195332480813;6193.752249910043;8077.515349079074;8112.878306825402;8460.323174146053;8463.407561470762;973.109604711695;1002.3184234720314;1022.0494939706144;1041.776018156085;1056.2183983851894;4936.925946110811;4972.154733695281;5036.496350364934;5240.9475905241225;5359.598030147755;18180.909095454437;18829.529261768566;19223.61552768937;19290.324838630862;19352.806471935382;18854.528636783914;19101.516802815546;19107.23571057142;19215.13531891068;19379.80620193808;8510.74467765968;10064.597416103421;10120.443375614264;10177.643782467578;10190.949045254727;7616.3906887447765;7719.228077192461;7786.454948153575;7926.128125906261;7942.960285198537;14760.188189649672;17670;17863.821361786737;18116.456506304832;18165.909170454062;17146.142692865058;17976.460706178834;18056.45830625083;18252.726209106608;18266.908665456587;11196.216264861381;11235.213535052468;11445.656630301104;11563.074954003492;11576.958073773401;12851.935740321238;12878.227306361649;12983;13032.47870085186;13044.478220871439;10238.948805255926;10335.483225838527;10566.524506397076;10717.9464102679;11017.504212310305;10073;10256.743581410525;10372.844407334047;10523.105536029523;10528.526216320131;9689.806203875742;9713.417194968324;9749.9025009749;9750.219982401533;9768.218542516724;6891.241963384124;7042.753503627348;7120.501564890408;7351.485396022227;7371.852562948819;7042.366187043191;7152.356287934111;7336.926630733625;7361.895715642482;7413.369863561442 11:13:49 INFO - PID 1650 | |4;prototype.html;457.16339099448015;467.1800989263841;487.3737247636787;494.52031529416456;497.47019424313106;6500.829850626886;6553.705083271252;6601.346051034022;6617.305182955781;6618.5108350621085;5118.9744051279695;6163.753449862;6336.42972132508;6386.774678787217;6433.485321174307;4324.978375108128;4850.514948505156;4864.537868902448;4870.366852309195;4886.802733330339;4012.739171953828;4876.780544875489;4883.340748998875;4888.877778055559;4902.558769710726;3996.5803590623;4253.234417804794;4589.5410458954175;4767.833125840596;4813.927791083137;4107.9178416431505;4549.499555048926;4818.132736107499;4844.75776211188;4870.902581948341;3720.3675375186012;4257.489101307863;4294.355846623001;4300.247456695066;4303.634191093761;6241.812745617639;6246.219222597176;6269.686515674198;6355.682215889187;6364.172657554534;5737.770489180428;5756.33802112754;5761.337446193699;5788.437121977085;5795.478406943354;6858.828529286758;6933.757318493879;6954.617496037716;6961.477889158334;6966.512344135913;4731.101090792773;4887.364642596475;4894.975525122387;4899.28960300758;4949.925751113737;6377.8201032809075;6389.488840892717;6657.934730443106;6779.62712050837;7376.483646144826;4382.912341753211;4395.780210989437;4973.90052198954;5000.024995125988;5001.224810154432;5084.56781173597;5098.898022039612;5100.642954993189;5101.591872650179;5149.588032957355;5762.394948530427;5777.566682498829;5780.508656764138;5780.6415492359265;5801.825945221565;5779.711014449261;5790.913136302959;6045.909311360333;6952.270011648805;7014.508984371045;1467.4863797670944;1546.6520032992712;1663.236968813062;1737.6611560745532;1753.377550969413;977.4868194197961;1161.9302841829517;1170.1633332167542;1188.738477534941;1200.1359021504502;6036.547258955508;6295;6332.4934005279465;6428.807135785841;6689.46484281266;7056.435485161175;7143.42852571793;7264.055672762613;7280.890786638205;7314.817129571803;6649.73401063952;6666.333366663392;6672.731271367758;6675.432588229958;6695.933040669628;3051.8321492318137;3176.38060578185;3722.6091260417807;3749.6812770914776;3772.320982223226;7491.213422590658;7492.438067144986;7494.838300063389;7511.549307041595;7520.548767073884;5512.3660779010925;6548.607083574905;7407.777766667009;7410.888836667455;7424;6920.5847649142215;7156.606386648784;7469.81690253449;7504.922981577527;7534.510256833289;7154.284571542804;7172.892406613801;7174.605396703023;7201.927980720126;7224.024756657784;5853.536615846038;6159.599626024294;7046.330598593126;7063.540869843444;7076.646167691491;7204.56772593646;7442.720435749357;7450.143233527997;7503.737369192052;7524.962375188089;4169.2495350835925;4490.393796837385;4541.909161816679;4954.455009948831;4957.206846904623;6256.436920677161;6939.479539034492;7074.575525468488;7153.284671532805;7154.928450715634;5627.465390787868;6913.308669133046;6945.583265004116;6946.756863509753;6956.460926728859;5221.738913054408;6485.189351331083;6839.179298484015;6934.375906168469;6952.061471701256;6766.560173588701;6767.191615479521;6769.9661501694145;6773.627450490073;6865.828354290983;5592.888142237214;6162.890679677701;6324.335944725922;6924.503125921377;6946.097007389007;5122.513361230677;5638.407967163553;6655.800325990228;6706.463482921257;6751.729930802713 11:13:49 INFO - PID 1650 | |5;yui.html;819.6229734322211;826.9958650206748;876.5398165962873;896.5203616065402;900.086412291524;18218.5445363866;18324.083795810206;18537.90731046343;18549.35077272296;18549.814501854995;15228.619284517892;15256.923715381408;16373;18328.266869325213;19420.028998550086;1016.374929418406;1028.5525796278605;1036.9325993810417;1040.266612038511;1041.6614600254948;1030.1141018723893;1038.7662775875442;1040.8386700061503;1047.6542740895525;1050.6585359758071;1101.102601379877;1103.3214573037565;1104.4312179227704;1105.8838821923703;1110.3337997201695;1037.082182268694;1080.1466841195447;1083.285031878956;1087.0977089016176;1113.7549739057436;763.6029264782337;776.491398134223;781.6716978868889;781.8436312737475;782.22950393862;18000.009999450023;18666.906665466722;18697.439076827715;18719.344822931267;18819.62360752791;18196.545086372815;18590.349337773114;18734.906325468284;18930.810691893043;19000.5249868753;15570.29933652977;15687.68624627501;15743.2128393581;15767.763483547757;15785.526434206991;3755.7183211258994;3765.0399148217302;3781.130340021783;3804.2391521695495;3996.820143093568;147230.79153812808;150923;153042.2119374405;158061.2096939508;159131.2043439787;14487.275636218146;14699.338529766186;14786.778198327034;14795.038322509217;14818.184999824898;26981.325466863487;27741.722582773913;28221.576676349872;28697;29086.418271634448;8501.362397820189;8597.140285971353;8636.136386361313;8641.395102342776;8654.610542525601;3554.413521768931;3617.186133120027;4102.589741025873;4151.501819781585;4207.116505533811;4895.04546613407;4984.451710311862;5009.223570346602;5009.574186194214;5047.798088076509;1048.916086713061;1070.5280239670453;1082.9079528240031;1087.1791797193227;1090.8254679251281;626.6240255846456;679.78926532775;682.3756263019282;703.3985942019602;706.3783870194299;12791;14353.78469322961;14434.639134021734;14541.636459088395;14848.628784280263;650.5576208178484;654.6693919570681;682.5972676121013;700.2122612061431;710.3784188834769;5523.392426833045;5606.411326810708;5841.94158058414;5975.551833612496;5980.85047873798;128127.35936320259;131349;144110;150094.24952875383;151078.48921510865;111701.44149279365;112436;117809;118456.40771796259;118469.81530184588;96380.51809740906;97626;101984.49007754914;105218;107497;10081.949590252001;11774.587889423829;12657.367131643197;12871.485140594647;13055.608331750063;42801.785991069846;44635.553644463136;44969;46112.07775844532;46397;85748.71376929476;86410.1358986402;87556.12443875479;88149.55925220333;88309;18452.538686532946;18574.2570297192;18727.250909963448;18729.96985165829;18767.530811729815;18561.44315670532;19028.04859757034;19502.512437189183;19734.1119649619;20062.398128056182;14732.410703571737;17688.292468301122;18492.35276765307;18639.254429822657;18845.811541884956;16528.917355413145;16902.83097169062;17420.651586968444;17451.04019278901;17613.295468181128;17426.651466970845;18153.364632238336;18375.632487350445;18562.164702588147;18670.066496675383;11942.462589183333;11987.460564274454;12167;12208.69478263015;12374.257544547356;13986.370613322628;14848.257587120817;14897.925510372379;14900.105993640416;14941.62645933817 11:13:49 INFO - PID 1650 | __end_tp_report 11:13:49 INFO - PID 1650 | __start_cc_report 11:13:49 INFO - PID 1650 | _x_x_mozilla_cycle_collect,375 11:13:49 INFO - PID 1650 | __end_cc_report 11:13:49 INFO - PID 1650 | __startTimestamp1497464029237__endTimestamp 11:13:49 INFO - PID 1650 | 11:13:49 INFO - PID 1650 | ------- Summary: start ------- 11:13:49 INFO - PID 1650 | Number of tests: 6 11:13:49 INFO - PID 1650 | 11:13:49 INFO - PID 1650 | [#0] dojo.html Cycles:165 Average:6465.83 Median:6706.83 stddev:831.79 (12.4%) stddev-sans-first:791.11 11:13:49 INFO - PID 1650 | Values: 3091.9 3662.7 3727.9 3864.4 3871.8 5404.3 6338.8 6375.3 6419.4 6453.3 6436.6 6466.3 6473.0 6487.0 6494.5 5739.5 5740.9 5748.3 5915.8 5972.4 5614.2 5730.3 5743.0 5767.3 5781.8 5682.3 5685.9 5722.8 5722.9 5730.6 4763.5 5728.3 5757.0 5781.8 5835.6 5700.6 5795.9 5799.6 5826.3 5826.8 6454.4 6483.2 6516.8 6519.2 6533.2 6773.6 6891.4 7094.4 7097.9 7117.6 6486.1 7076.2 7113.4 7139.5 7174.3 6598.9 6628.5 6704.6 6706.8 6724.8 6044.9 6972.4 7036.2 7397.5 7407.9 5651.7 6774.5 6807.8 6818.4 6841.0 6987.5 7047.4 7123.1 7167.3 7172.8 7042.0 7057.8 7074.6 7097.0 7101.0 7025.0 7056.8 7072.3 7089.3 7115.6 5482.9 5511.3 5549.4 5559.5 5582.8 3680.4 4917.2 4987.6 5019.6 5033.0 5496.3 6652.4 6706.0 6706.0 6724.3 5981.2 7197.1 7211.1 7220.2 7238.9 6431.0 6512.9 6523.8 6548.8 6711.7 6060.4 6246.6 6286.2 6309.7 6355.3 6045.9 7397.3 7478.8 7486.0 7516.7 5865.0 6403.9 7207.6 7227.8 7262.0 7088.5 7428.6 7436.0 7444.7 7451.6 6908.4 6917.5 6922.2 6927.8 6995.3 7165.1 7227.1 7240.0 7241.1 7259.1 6996.2 6998.7 7015.5 7016.9 7032.2 6667.6 6922.6 6961.1 6969.8 7012.5 6598.8 6653.8 6686.1 6729.7 6819.5 6965.1 6988.7 6990.2 7026.1 7035.5 6842.9 6853.5 6855.2 6860.5 6879.3 11:13:49 INFO - PID 1650 | 11:13:49 INFO - PID 1650 | [#1] ext.html Cycles:180 Average:46658.54 Median:355934.50 stddev:81746.94 (23.0%) stddev-sans-first:81903.73 11:13:49 INFO - PID 1650 | Values: 794.6 864.3 864.6 884.6 902.2 33315.8 43206.0 43305.9 43327.4 43366.7 42311.6 42538.6 42634.7 42685.1 43449.3 7991.8 8126.8 8132.7 8148.0 8160.8 8073.1 8114.5 8189.0 8193.6 8197.1 8113.6 8119.7 8137.3 8151.0 8748.3 8912.6 8965.9 9007.1 9015.2 9024.8 7294.9 7700.4 8428.7 8769.0 8793.6 39351.0 39365.0 39516.0 39585.4 40371.6 3656.3 3664.0 3690.8 3695.9 3711.7 32074.4 32434.0 32468.8 32496.7 32500.9 2717.3 3276.5 3297.9 3332.6 3336.3 263842.4 267674.0 267815.0 287151.1 290992.5 42404.4 51420.0 52148.0 52152.7 52310.7 47978.0 54344.0 54900.5 54970.0 55040.2 15926.1 15929.2 16108.9 16113.9 16127.2 13042.9 13081.7 13087.7 13114.8 13145.0 14236.7 14307.4 14318.7 14346.3 14418.4 6164.1 6179.6 6242.5 6252.1 6286.4 1023.6 1027.5 1030.4 1085.9 1086.6 24281.4 25981.3 26005.3 26021.2 26121.5 1089.5 1135.2 1174.7 1180.5 1213.8 9971.0 10659.5 11273.9 11841.4 12787.9 354879.0 356990.0 358120.0 358687.0 358863.0 59326.0 59519.0 59741.7 59827.1 59851.0 1489.7 1566.7 1578.7 1579.7 1583.8 23681.6 24049.8 24913.8 25338.4 25436.7 40042.2 40154.6 40170.2 40235.0 40287.4 294432.0 294510.0 294683.0 295486.0 295603.0 24825.3 31241.0 31258.4 31429.5 36757.6 38777.0 38895.2 38981.6 44256.8 44320.3 29069.0 29436.0 30539.0 31309.2 31469.7 30255.8 30861.4 31317.8 31483.5 31724.0 35045.6 37851.1 37859.6 38113.2 38227.4 29752.7 30821.8 31174.0 33399.3 33495.2 24210.5 24315.8 24481.0 25805.4 26080.9 11:13:49 INFO - PID 1650 | 11:13:49 INFO - PID 1650 | [#2] jquery.html Cycles:180 Average:42565.81 Median:21607.49 stddev:107571.00 (497.8%) stddev-sans-first:107826.33 11:13:49 INFO - PID 1650 | Values: 471.7 547.3 549.3 551.6 551.8 23954.6 24734.0 24763.6 24824.5 24832.6 24772.0 27265.2 27321.6 27351.3 27412.7 6669.0 6772.6 6774.9 6810.6 6826.4 6769.5 6809.0 6809.6 6830.8 6839.5 6124.4 6767.9 6774.5 6801.7 6805.4 6533.7 6919.3 6923.8 6930.5 6965.7 6322.6 6322.7 6341.5 6343.5 6345.7 29808.4 32146.0 32264.7 32430.0 32662.0 2333.9 2365.8 2384.3 2385.1 2393.7 19622.3 19942.0 20176.1 20190.7 20222.3 2294.9 2305.7 2346.4 2354.7 2391.6 209516.0 210702.0 212193.9 214129.0 214752.0 13629.8 16522.0 16595.8 16633.3 16727.5 18854.1 21739.8 22206.2 22230.4 22366.0 10841.5 10875.1 11025.2 11061.9 11158.8 10217.3 10288.6 10313.9 10316.1 10320.5 9714.2 9911.2 10128.8 10176.4 10204.3 2428.8 2528.8 2626.5 2693.2 2843.6 997.7 1003.3 1017.4 1054.0 1062.4 19168.0 21442.0 21556.8 21658.1 21867.2 1029.7 1049.9 1071.5 1083.9 1084.9 10286.9 10544.0 10809.8 12946.3 15137.4 604464.1 617875.0 628271.0 630463.0 638197.0 125198.4 163221.0 164798.0 167444.0 167968.0 1227.7 1271.2 1286.2 1287.5 1291.9 22732.8 23783.6 24254.8 24349.5 24618.1 67292.3 69311.7 69943.3 70362.0 71116.0 75116.0 91226.0 95842.5 95910.0 96125.5 12231.1 12449.6 12509.9 12542.9 12573.1 14218.6 15331.9 19255.9 19394.9 19442.3 14440.6 15735.8 16066.4 16274.4 16301.1 13599.7 14093.4 14204.9 14574.6 14678.5 16313.9 16443.6 16462.8 16556.3 16612.2 11307.5 12169.3 12354.6 12379.6 12382.9 15469.3 15643.5 15690.1 15694.1 15794.5 11:13:49 INFO - PID 1650 | 11:13:49 INFO - PID 1650 | [#3] mootools.html Cycles:180 Average:8710.21 Median:2772.31 stddev:5796.04 (209.1%) stddev-sans-first:5784.43 11:13:49 INFO - PID 1650 | Values: 1146.5 1517.0 1526.0 1528.1 1537.5 13998.1 14598.1 14622.4 14622.8 14667.0 14058.5 14438.6 14445.8 14447.3 14466.5 2893.5 2933.3 2944.8 2947.7 2948.6 2515.4 2811.9 2935.1 2936.9 2941.3 2670.7 2751.2 2793.4 2861.2 2943.5 2926.8 2934.9 2954.7 2957.4 2962.1 2894.2 2934.3 2942.7 2946.4 2949.4 14036.9 14039.0 14052.4 14134.4 14247.9 7765.2 8004.2 8066.7 8095.9 8099.6 4919.5 5125.5 5191.0 5201.9 5206.7 1224.7 1443.6 1479.5 1509.7 1510.4 21119.5 21360.6 21473.9 21483.5 21488.7 5528.9 6878.9 6896.3 6956.4 7092.1 14814.6 14866.5 14878.9 14903.0 14907.6 7717.2 7753.5 7807.4 7859.3 7862.2 7136.3 7266.7 7280.6 7310.1 7323.5 5246.9 5338.6 5363.1 5389.6 5428.5 1583.6 1584.6 1594.4 1599.6 1615.2 694.7 708.6 835.1 835.7 838.2 6193.8 8077.5 8112.9 8460.3 8463.4 973.1 1002.3 1022.0 1041.8 1056.2 4936.9 4972.2 5036.5 5240.9 5359.6 18180.9 18829.5 19223.6 19290.3 19352.8 18854.5 19101.5 19107.2 19215.1 19379.8 8510.7 10064.6 10120.4 10177.6 10190.9 7616.4 7719.2 7786.5 7926.1 7943.0 14760.2 17670.0 17863.8 18116.5 18165.9 17146.1 17976.5 18056.5 18252.7 18266.9 11196.2 11235.2 11445.7 11563.1 11577.0 12851.9 12878.2 12983.0 13032.5 13044.5 10238.9 10335.5 10566.5 10717.9 11017.5 10073.0 10256.7 10372.8 10523.1 10528.5 9689.8 9713.4 9749.9 9750.2 9768.2 6891.2 7042.8 7120.5 7351.5 7371.9 7042.4 7152.4 7336.9 7361.9 7413.4 11:13:49 INFO - PID 1650 | 11:13:49 INFO - PID 1650 | [#4] prototype.html Cycles:180 Average:5609.86 Median:6251.33 stddev:1745.11 (27.9%) stddev-sans-first:1706.61 11:13:49 INFO - PID 1650 | Values: 457.2 467.2 487.4 494.5 497.5 6500.8 6553.7 6601.3 6617.3 6618.5 5119.0 6163.8 6336.4 6386.8 6433.5 4325.0 4850.5 4864.5 4870.4 4886.8 4012.7 4876.8 4883.3 4888.9 4902.6 3996.6 4253.2 4589.5 4767.8 4813.9 4107.9 4549.5 4818.1 4844.8 4870.9 3720.4 4257.5 4294.4 4300.2 4303.6 6241.8 6246.2 6269.7 6355.7 6364.2 5737.8 5756.3 5761.3 5788.4 5795.5 6858.8 6933.8 6954.6 6961.5 6966.5 4731.1 4887.4 4895.0 4899.3 4949.9 6377.8 6389.5 6657.9 6779.6 7376.5 4382.9 4395.8 4973.9 5000.0 5001.2 5084.6 5098.9 5100.6 5101.6 5149.6 5762.4 5777.6 5780.5 5780.6 5801.8 5779.7 5790.9 6045.9 6952.3 7014.5 1467.5 1546.7 1663.2 1737.7 1753.4 977.5 1161.9 1170.2 1188.7 1200.1 6036.5 6295.0 6332.5 6428.8 6689.5 7056.4 7143.4 7264.1 7280.9 7314.8 6649.7 6666.3 6672.7 6675.4 6695.9 3051.8 3176.4 3722.6 3749.7 3772.3 7491.2 7492.4 7494.8 7511.5 7520.5 5512.4 6548.6 7407.8 7410.9 7424.0 6920.6 7156.6 7469.8 7504.9 7534.5 7154.3 7172.9 7174.6 7201.9 7224.0 5853.5 6159.6 7046.3 7063.5 7076.6 7204.6 7442.7 7450.1 7503.7 7525.0 4169.2 4490.4 4541.9 4954.5 4957.2 6256.4 6939.5 7074.6 7153.3 7154.9 5627.5 6913.3 6945.6 6946.8 6956.5 5221.7 6485.2 6839.2 6934.4 6952.1 6766.6 6767.2 6770.0 6773.6 6865.8 5592.9 6162.9 6324.3 6924.5 6946.1 5122.5 5638.4 6655.8 6706.5 6751.7 11:13:49 INFO - PID 1650 | 11:13:49 INFO - PID 1650 | [#5] yui.html Cycles:180 Average:26535.87 Median:18414.09 stddev:39829.97 (216.3%) stddev-sans-first:39894.89 11:13:49 INFO - PID 1650 | Values: 819.6 827.0 876.5 896.5 900.1 18218.5 18324.1 18537.9 18549.4 18549.8 15228.6 15256.9 16373.0 18328.3 19420.0 1016.4 1028.6 1036.9 1040.3 1041.7 1030.1 1038.8 1040.8 1047.7 1050.7 1101.1 1103.3 1104.4 1105.9 1110.3 1037.1 1080.1 1083.3 1087.1 1113.8 763.6 776.5 781.7 781.8 782.2 18000.0 18666.9 18697.4 18719.3 18819.6 18196.5 18590.3 18734.9 18930.8 19000.5 15570.3 15687.7 15743.2 15767.8 15785.5 3755.7 3765.0 3781.1 3804.2 3996.8 147230.8 150923.0 153042.2 158061.2 159131.2 14487.3 14699.3 14786.8 14795.0 14818.2 26981.3 27741.7 28221.6 28697.0 29086.4 8501.4 8597.1 8636.1 8641.4 8654.6 3554.4 3617.2 4102.6 4151.5 4207.1 4895.0 4984.5 5009.2 5009.6 5047.8 1048.9 1070.5 1082.9 1087.2 1090.8 626.6 679.8 682.4 703.4 706.4 12791.0 14353.8 14434.6 14541.6 14848.6 650.6 654.7 682.6 700.2 710.4 5523.4 5606.4 5841.9 5975.6 5980.9 128127.4 131349.0 144110.0 150094.2 151078.5 111701.4 112436.0 117809.0 118456.4 118469.8 96380.5 97626.0 101984.5 105218.0 107497.0 10081.9 11774.6 12657.4 12871.5 13055.6 42801.8 44635.6 44969.0 46112.1 46397.0 85748.7 86410.1 87556.1 88149.6 88309.0 18452.5 18574.3 18727.3 18730.0 18767.5 18561.4 19028.0 19502.5 19734.1 20062.4 14732.4 17688.3 18492.4 18639.3 18845.8 16528.9 16902.8 17420.7 17451.0 17613.3 17426.7 18153.4 18375.6 18562.2 18670.1 11942.5 11987.5 12167.0 12208.7 12374.3 13986.4 14848.3 14897.9 14900.1 14941.6 11:13:49 INFO - PID 1650 | -------- Summary: end -------- 11:13:49 INFO - PID 1650 | 11:13:49 INFO - TEST-INFO | 1650: exit 0 11:13:49 INFO - TEST-OK | dromaeo_css | took 1143029ms 11:13:49 INFO - TEST-START | kraken 11:13:49 INFO - operating with platform_type : mac_ 11:13:49 INFO - Initialising browser for kraken test... 11:13:49 INFO - Application command: /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/tmp7nMXDw/profile 11:13:49 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 11:14:22 INFO - TEST-INFO | 1660: killed by SIGTERM 11:14:22 INFO - Browser initialized. 11:14:22 INFO - Running cycle 1/1 for kraken test... 11:14:22 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/tmp7nMXDw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:14:24 INFO - PID 1663 | 2017-06-14 11:14:24.271 plugin-container[1664:10786] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 11:14:24 INFO - PID 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:14:24 INFO - PID 1663 | 2017-06-14 11:14:24.273 plugin-container[1664:10786] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x990b, name = 'com.apple.CFPasteboardClient' 11:14:24 INFO - PID 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:14:24 INFO - PID 1663 | 2017-06-14 11:14:24.273 plugin-container[1664:10786] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:14:34 INFO - PID 1663 | RSS: Main: 184623104 11:14:34 INFO - PID 1663 | 11:14:37 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection) 11:14:38 INFO - PID 1663 | RSS: Main: 197554176 11:14:38 INFO - PID 1663 | 11:14:40 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-dft) 11:14:41 INFO - PID 1663 | RSS: Main: 207220736 11:14:41 INFO - PID 1663 | 11:14:44 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-fft) 11:14:45 INFO - PID 1663 | RSS: Main: 211374080 11:14:45 INFO - PID 1663 | 11:14:47 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator) 11:14:47 INFO - PID 1663 | RSS: Main: 212295680 11:14:47 INFO - PID 1663 | 11:14:49 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:14:50 INFO - PID 1663 | RSS: Main: 214372352 11:14:50 INFO - PID 1663 | 11:14:53 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom) 11:14:53 INFO - PID 1663 | RSS: Main: 218292224 11:14:53 INFO - PID 1663 | 11:14:56 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate) 11:14:57 INFO - PID 1663 | RSS: Main: 219676672 11:14:57 INFO - PID 1663 | 11:15:00 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial) 11:15:01 INFO - PID 1663 | RSS: Main: 220213248 11:15:01 INFO - PID 1663 | 11:15:02 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:15:03 INFO - PID 1663 | RSS: Main: 219721728 11:15:03 INFO - PID 1663 | 11:15:04 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:15:05 INFO - PID 1663 | RSS: Main: 219721728 11:15:05 INFO - PID 1663 | 11:15:07 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:15:08 INFO - PID 1663 | RSS: Main: 219676672 11:15:08 INFO - PID 1663 | 11:15:10 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:15:11 INFO - PID 1663 | RSS: Main: 219725824 11:15:11 INFO - PID 1663 | 11:15:14 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:15:15 INFO - PID 1663 | RSS: Main: 219926528 11:15:15 INFO - PID 1663 | 11:15:16 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49228/tests/kraken/driver.html?testName=ai-astar) 11:15:17 INFO - PID 1663 | RSS: Main: 221429760 11:15:17 INFO - PID 1663 | 11:15:17 INFO - PID 1663 | __start_tp_report 11:15:17 INFO - PID 1663 | _x_x_mozilla_page_load 11:15:17 INFO - PID 1663 | _x_x_mozilla_page_load_details 11:15:17 INFO - PID 1663 | |i|pagename|runs| 11:15:17 INFO - PID 1663 | |0;ai-astar;100;127;136;163;114;116;115;103;118;119 11:15:17 INFO - PID 1663 | |1;audio-beat-detection;126;109;149;179;111;122;123;120;183;115 11:15:17 INFO - PID 1663 | |2;audio-dft;201;202;200;203;197;223;197;286;216;201 11:15:17 INFO - PID 1663 | |3;audio-fft;69;84;73;157;78;88;91;90;121;56 11:15:17 INFO - PID 1663 | |4;audio-oscillator;68;70;117;72;94;110;142;82;74;108 11:15:17 INFO - PID 1663 | |5;imaging-gaussian-blur;89;104;89;88;89;88;89;91;87;90 11:15:17 INFO - PID 1663 | |6;imaging-darkroom;96;96;96;95;96;97;97;97;96;104 11:15:17 INFO - PID 1663 | |7;imaging-desaturate;92;92;99;124;88;90;91;90;92;131 11:15:17 INFO - PID 1663 | |8;json-parse-financial;49;49;51;49;52;51;51;50;51;51 11:15:17 INFO - PID 1663 | |9;json-stringify-tinderbox;54;54;58;55;55;64;56;54;56;59 11:15:17 INFO - PID 1663 | |10;stanford-crypto-aes;81;79;77;106;67;102;101;102;101;77 11:15:17 INFO - PID 1663 | |11;stanford-crypto-ccm;132;130;132;131;132;138;156;153;148;160 11:15:17 INFO - PID 1663 | |12;stanford-crypto-pbkdf2;177;181;175;168;168;167;216;177;176;174 11:15:17 INFO - PID 1663 | |13;stanford-crypto-sha256-iterative;65;64;67;61;64;65;64;65;62;63 11:15:17 INFO - PID 1663 | __end_tp_report 11:15:17 INFO - PID 1663 | __start_cc_report 11:15:17 INFO - PID 1663 | _x_x_mozilla_cycle_collect,987 11:15:17 INFO - PID 1663 | __end_cc_report 11:15:17 INFO - PID 1663 | __startTimestamp1497464117113__endTimestamp 11:15:17 INFO - PID 1663 | 11:15:17 INFO - PID 1663 | ------- Summary: start ------- 11:15:17 INFO - PID 1663 | Number of tests: 14 11:15:17 INFO - PID 1663 | 11:15:17 INFO - PID 1663 | [#0] ai-astar Cycles:10 Average:121.10 Median:118.50 stddev:17.99 (15.2%) stddev-sans-first:17.39 11:15:17 INFO - PID 1663 | Values: 100.0 127.0 136.0 163.0 114.0 116.0 115.0 103.0 118.0 119.0 11:15:17 INFO - PID 1663 | 11:15:17 INFO - PID 1663 | [#1] audio-beat-detection Cycles:10 Average:133.70 Median:124.50 stddev:27.26 (21.9%) stddev-sans-first:28.78 11:15:17 INFO - PID 1663 | Values: 126.0 109.0 149.0 179.0 111.0 122.0 123.0 120.0 183.0 115.0 11:15:17 INFO - PID 1663 | 11:15:17 INFO - PID 1663 | [#2] audio-dft Cycles:10 Average:212.60 Median:202.50 stddev:27.13 (13.4%) stddev-sans-first:28.45 11:15:17 INFO - PID 1663 | Values: 201.0 202.0 200.0 203.0 197.0 223.0 197.0 286.0 216.0 201.0 11:15:17 INFO - PID 1663 | 11:15:17 INFO - PID 1663 | [#3] audio-fft Cycles:10 Average:90.70 Median:81.00 stddev:28.90 (35.7%) stddev-sans-first:29.57 11:15:17 INFO - PID 1663 | Values: 69.0 84.0 73.0 157.0 78.0 88.0 91.0 90.0 121.0 56.0 11:15:17 INFO - PID 1663 | 11:15:17 INFO - PID 1663 | [#4] audio-oscillator Cycles:10 Average:93.70 Median:71.00 stddev:24.86 (35.0%) stddev-sans-first:24.57 11:15:17 INFO - PID 1663 | Values: 68.0 70.0 117.0 72.0 94.0 110.0 142.0 82.0 74.0 108.0 11:15:17 INFO - PID 1663 | 11:15:17 INFO - PID 1663 | [#5] imaging-gaussian-blur Cycles:10 Average:90.40 Median:89.00 stddev:4.90 (5.5%) stddev-sans-first:5.17 11:15:17 INFO - PID 1663 | Values: 89.0 104.0 89.0 88.0 89.0 88.0 89.0 91.0 87.0 90.0 11:15:17 INFO - PID 1663 | 11:15:17 INFO - PID 1663 | [#6] imaging-darkroom Cycles:10 Average:97.00 Median:96.00 stddev:2.54 (2.6%) stddev-sans-first:2.67 11:15:17 INFO - PID 1663 | Values: 96.0 96.0 96.0 95.0 96.0 97.0 97.0 97.0 96.0 104.0 11:15:17 INFO - PID 1663 | 11:15:17 INFO - PID 1663 | [#7] imaging-desaturate Cycles:10 Average:98.90 Median:91.50 stddev:15.43 (16.9%) stddev-sans-first:16.16 11:15:17 INFO - PID 1663 | Values: 92.0 92.0 99.0 124.0 88.0 90.0 91.0 90.0 92.0 131.0 11:15:17 INFO - PID 1663 | 11:15:17 INFO - PID 1663 | [#8] json-parse-financial Cycles:10 Average:50.40 Median:51.00 stddev:1.07 (2.1%) stddev-sans-first:1.01 11:15:17 INFO - PID 1663 | Values: 49.0 49.0 51.0 49.0 52.0 51.0 51.0 50.0 51.0 51.0 11:15:17 INFO - PID 1663 | 11:15:17 INFO - PID 1663 | [#9] json-stringify-tinderbox Cycles:10 Average:56.50 Median:56.00 stddev:3.14 (5.6%) stddev-sans-first:3.19 11:15:17 INFO - PID 1663 | Values: 54.0 54.0 58.0 55.0 55.0 64.0 56.0 54.0 56.0 59.0 11:15:17 INFO - PID 1663 | 11:15:17 INFO - PID 1663 | [#10] stanford-crypto-aes Cycles:10 Average:89.30 Median:72.00 stddev:14.34 (19.9%) stddev-sans-first:14.89 11:15:17 INFO - PID 1663 | Values: 81.0 79.0 77.0 106.0 67.0 102.0 101.0 102.0 101.0 77.0 11:15:17 INFO - PID 1663 | 11:15:17 INFO - PID 1663 | [#11] stanford-crypto-ccm Cycles:10 Average:141.20 Median:143.00 stddev:11.79 (8.2%) stddev-sans-first:12.03 11:15:17 INFO - PID 1663 | Values: 132.0 130.0 132.0 131.0 132.0 138.0 156.0 153.0 148.0 160.0 11:15:17 INFO - PID 1663 | 11:15:17 INFO - PID 1663 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.90 Median:176.50 stddev:14.16 (8.0%) stddev-sans-first:15.02 11:15:17 INFO - PID 1663 | Values: 177.0 181.0 175.0 168.0 168.0 167.0 216.0 177.0 176.0 174.0 11:15:17 INFO - PID 1663 | 11:15:17 INFO - PID 1663 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.00 Median:64.50 stddev:1.70 (2.6%) stddev-sans-first:1.76 11:15:17 INFO - PID 1663 | Values: 65.0 64.0 67.0 61.0 64.0 65.0 64.0 65.0 62.0 63.0 11:15:17 INFO - PID 1663 | -------- Summary: end -------- 11:15:17 INFO - PID 1663 | 11:15:17 INFO - TEST-INFO | 1663: exit 0 11:15:17 INFO - TEST-OK | kraken | took 88109ms 11:15:17 INFO - SUITE-END | took 1231s 11:15:18 INFO - Completed test suite (00:20:31) 11:15:18 INFO - javascript benchmark 11:15:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8710.89721873867, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3091.9072427827173, 3662.7436079474437, 3727.8881633551005, 3864.4010178422336, 3871.767693938364, 5404.297441332631, 6338.841528961772, 6375.298717141117, 6419.390157934989, 6453.322401147871, 6436.645984470852, 6466.321036291192, 6472.967635161829, 6487.0, 6494.5453818232745, 5739.51214146796, 5740.8851822963725, 5748.338941021773, 5915.763369465216, 5972.402759724015, 5614.185943038257, 5730.341010783751, 5742.971285143569, 5767.307923049219, 5781.768729250825, 5682.26130603021, 5685.857853553694, 5722.828315150552, 5722.856928576777, 5730.570207234474, 4763.476017638057, 5728.3126024876865, 5756.971215143897, 5781.84363127372, 5835.562332825013, 5700.572457065737, 5795.913061304083, 5799.623024503394, 5826.329972053226, 5826.796062137847, 6454.419102280817, 6483.152301594023, 6516.771912983023, 6519.217693876718, 6533.18335208099, 6773.559718618274, 6891.448684105259, 7094.432445404355, 7097.89353159703, 7117.64411779409, 6486.124373209651, 7076.150861896554, 7113.430925525946, 7139.535930164575, 7174.282571742836, 6598.86802263952, 6628.502862285348, 6704.597724136567, 6706.83232919181, 6724.831879203059, 6044.879102417927, 6972.372486476242, 7036.226015138331, 7397.482176247719, 7407.925920740831, 5651.745671444713, 6774.525783195129, 6807.795766127024, 6818.386345228854, 6840.965795170992, 6987.475939304572, 7047.43620510358, 7123.145222573325, 7167.319104685047, 7172.784816455514, 7042.0493233412835, 7057.8235544110785, 7074.610896400747, 7097.0, 7101.0, 7024.964875175591, 7056.823579410453, 7072.328128827753, 7089.291070892869, 7115.644217789089, 5482.91775623366, 5511.311086114236, 5549.417311182348, 5559.471850174227, 5582.7766889324785, 3680.429533422324, 4917.164082106051, 4987.600991920638, 5019.648624596243, 5032.974835125801, 5496.258005169251, 6652.368025037614, 6705.966470167618, 6706.0, 6724.293949135368, 5981.222441082631, 7197.1363436387965, 7211.098612673416, 7220.205777364486, 7238.92761072393, 6431.0031945048595, 6512.869742605216, 6523.836904077341, 6548.770793022317, 6711.6979735912, 6060.424259695322, 6246.593971391936, 6286.151369565142, 6309.716062777186, 6355.332690067568, 6045.909311360333, 7397.334239918433, 7478.775636730907, 7486.0, 7516.661750221144, 5864.970675146597, 6403.903941440975, 7207.603581802841, 7227.819304517429, 7262.0, 7088.539244949062, 7428.554286742813, 7435.9628201860805, 7444.664990075568, 7451.552906825391, 6908.378245957687, 6917.515773895779, 6922.203946546211, 6927.757528486681, 6995.265497122932, 7165.140183178052, 7227.1327440707455, 7240.022596949555, 7241.131064272321, 7259.092613423322, 6996.230414654486, 6998.6850591724515, 7015.543989640657, 7016.859662806613, 7032.1912980007955, 6667.633280169443, 6922.550034247758, 6961.060256865258, 6969.756058537927, 7012.474064445085, 6598.769043083469, 6653.767118150841, 6686.0973768540625, 6729.69716362755, 6819.4544436443975, 6965.094537710065, 6988.685509152202, 6990.16118065815, 7026.086608740831, 7035.542689725152, 6842.8973565397555, 6853.451723862178, 6855.177378714387, 6860.451163906975, 6879.312068793281], "value": 6408.834344865236, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [794.6463823598501, 864.2955990867447, 864.6325311742507, 884.6417201033581, 902.2331018634162, 33315.83342083289, 43206.0, 43305.917352066215, 43327.35008974864, 43366.69899903, 42311.57688423115, 42538.57461425385, 42634.72095837122, 42685.14629707412, 43449.34825977613, 7991.760247192594, 8126.837463250731, 8132.715354962577, 8148.0, 8160.755177344689, 8073.11195768465, 8114.472559283658, 8189.0, 8193.631286592117, 8197.05733840607, 8113.634886430124, 8119.675212991503, 8137.349012079049, 8150.959245203766, 8748.25639820616, 8912.598933048028, 8965.910340896573, 9007.05425930281, 9015.233705135071, 9024.819503609924, 7294.854102917965, 7700.383969282444, 8428.747137585882, 8769.0, 8793.604287807064, 39351.01622459433, 39365.01587460336, 39516.0, 39585.40621890674, 40371.59628403708, 3656.2687462507606, 3663.9633603663888, 3690.7785532867847, 3695.8891233262775, 3711.7030637548933, 32074.358512829614, 32434.0, 32468.83765581157, 32496.70013199469, 32500.912413320697, 2717.3070866928806, 3276.4921437177277, 3297.9340413191603, 3332.583427071616, 3336.2660214752714, 263842.3615763837, 267674.0, 267815.0, 287151.1284887145, 290992.54503727343, 42404.363934541005, 51420.0, 52148.0, 52152.739236303576, 52310.73844630752, 47978.0, 54344.0, 54900.45099549033, 54970.0, 55040.17439738407, 15926.124063176405, 15929.203539822962, 16108.919455402647, 16113.919430402773, 16127.193640317937, 13042.869571304354, 13081.672958175932, 13087.738245235232, 13114.803277950838, 13145.0, 14236.715265694835, 14307.356168972421, 14318.713625727427, 14346.282685865664, 14418.42326306936, 6164.075388691732, 6179.567430279837, 6242.469390101892, 6252.124702541648, 6286.371362863677, 1023.5803320638503, 1027.5427434791595, 1030.4023666273542, 1085.918556108295, 1086.6468397770757, 24281.39296517566, 25981.2514670701, 26005.349866253116, 26021.219363419128, 26121.477570448485, 1089.4600954148086, 1135.233717240859, 1174.723939874129, 1180.527788884453, 1213.7997230457052, 9971.00289970997, 10659.520321585547, 11273.887261127447, 11841.407929603485, 12787.936060319638, 354879.0, 356990.0, 358120.0, 358687.0, 358863.0, 59326.0, 59519.0, 59741.70129149414, 59827.10259346113, 59851.0, 1489.6871656951944, 1566.6788308396751, 1578.7316156253464, 1579.7077540655016, 1583.841615838407, 23681.64477532804, 24049.797510124772, 24913.750862491142, 25338.36654083589, 25436.745632543436, 40042.1991560173, 40154.59845401509, 40170.19659606733, 40235.0, 40287.3956890641, 294432.0, 294510.0, 294683.0, 295486.0, 295603.0, 24825.25524234276, 31241.0, 31258.374832502766, 31429.528557071204, 36757.63242367649, 38777.0, 38895.2220955585, 38981.6101838978, 44256.778716106215, 44320.335194971456, 29069.0, 29436.0, 30539.0, 31309.217269568442, 31469.685303146674, 30255.789768409017, 30861.38277234398, 31317.8434107828, 31483.527747083353, 31724.04827855168, 35045.64954350424, 37851.05372365603, 37859.62140378561, 38113.237735244584, 38227.42658860064, 29752.70247297586, 30821.845890770404, 31174.0, 33399.33201336008, 33495.1626209337, 24210.48954887295, 24315.75684243135, 24481.0, 25805.354866127745, 26080.86959565266], "value": 18009.15062060067, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [471.6981132075472, 547.3213215612642, 549.3407910507392, 551.5532418740822, 551.8123837895115, 23954.640680389788, 24734.0, 24763.62854557183, 24824.50350992979, 24832.627510587357, 24772.009119635237, 27265.182044538647, 27321.590176147372, 27351.316217094583, 27412.725872741266, 6668.96665516673, 6772.627505487197, 6774.93225067748, 6810.59136451812, 6826.351496607839, 6769.492288078389, 6809.0467334573195, 6809.557378770364, 6830.795076147723, 6839.452843772487, 6124.356942521038, 6767.864642707143, 6774.491913106512, 6801.6599170041545, 6805.353491418332, 6533.673316334188, 6919.273476284993, 6923.826904327382, 6930.549514281555, 6965.721371145148, 6322.589031712924, 6322.683865806691, 6341.492680585542, 6343.46080583151, 6345.650989195638, 29808.403831923457, 32146.03561893147, 32264.6773532264, 32430.0, 32662.0, 2333.9066437342485, 2365.751596082421, 2384.3204686664217, 2385.1175065225975, 2393.664886915833, 19622.31321903741, 19942.0, 20176.092075856475, 20190.697139542917, 20222.292219772386, 2294.8967296471533, 2305.7002589663402, 2346.3782097743942, 2354.705661792276, 2391.641253811925, 209516.0, 210702.0, 212193.9390303054, 214129.0, 214752.0, 13629.795553066711, 16522.00867947903, 16595.751063734053, 16633.334666613442, 16727.498175054767, 18854.05729713509, 21739.78260217409, 22206.222782202545, 22230.444238894157, 22366.0, 10841.512131954081, 10875.129989600813, 11025.228234023542, 11061.944690276498, 11158.832617510743, 10217.33587316822, 10288.639897603547, 10313.896861031444, 10316.123129534073, 10320.535575898952, 9714.22286217101, 9911.207103431709, 10128.848067278997, 10176.440295783656, 10204.336718113298, 2428.8178386621075, 2528.8229823912516, 2626.500964816679, 2693.165118813174, 2843.5734639804195, 997.7306127345576, 1003.3377970539555, 1017.4454922067491, 1053.951318438159, 1062.4315990944808, 19168.0, 21442.035108420157, 21556.839129900454, 21658.133674652836, 21867.234646787285, 1029.696239609317, 1049.8740151181755, 1071.5338827610015, 1083.8970297821695, 1084.9186311026547, 10286.948565257275, 10544.0, 10809.78380432387, 12946.287954162612, 15137.394504219708, 604464.1184647705, 617875.0, 628271.0, 630463.0, 638197.0, 125198.37400812938, 163221.0, 164798.0, 167444.0, 167968.0, 1227.699213692651, 1271.2436100520176, 1286.2475451860766, 1287.4721364240804, 1291.9095663303478, 22732.772672273728, 23783.643245350988, 24254.757452425954, 24349.51300974006, 24618.138365157134, 67292.32707673057, 69311.65344173448, 69943.30056699368, 70362.0, 71116.0, 75116.0, 91226.0, 95842.5207873984, 95910.0, 96125.51937240269, 12231.143819932518, 12449.626511204679, 12509.937450312691, 12542.874571254171, 12573.1198816082, 14218.644533886736, 15331.923340383226, 19255.903720481307, 19394.903025484782, 19442.319518816774, 14440.638984025483, 15735.763963540785, 16066.357345706509, 16274.43039493612, 16301.103439310951, 13599.728005440034, 14093.365798539295, 14204.857951420354, 14574.562763117123, 14678.486252981094, 16313.918430407772, 16443.58891027734, 16462.75305870389, 16556.337746490004, 16612.169391530133, 11307.547698092314, 12169.33068681231, 12354.567590134662, 12379.62861114168, 12382.938085309515, 15469.303881325142, 15643.530694079196, 15690.137042462775, 15694.058356498646, 15794.526164215093], "value": 12481.07579980164, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1146.495541961537, 1516.969660606787, 1525.984740152598, 1528.0831501099349, 1537.507997440819, 13998.090124141936, 14598.124112553254, 14622.415103395879, 14622.780658290107, 14667.0, 14058.50795222165, 14438.566842994727, 14445.844332453378, 14447.349869255882, 14466.493672721457, 2893.493638613245, 2933.3399985003416, 2944.8085874418202, 2947.675755666875, 2948.557716342544, 2515.383730985902, 2811.8734656940487, 2935.060780550225, 2936.8825246990095, 2941.3234456359883, 2670.664667666167, 2751.1883994221653, 2793.4413117376503, 2861.1845623997165, 2943.5143201371748, 2926.839023853687, 2934.9119526414243, 2954.6602140753876, 2957.3641667041475, 2962.096560549032, 2894.18962690447, 2934.2517657997073, 2942.661593916684, 2946.4401763664837, 2949.351142748592, 14036.929815350857, 14039.0, 14052.437902483889, 14134.36395362211, 14247.928760356132, 7765.223477652246, 8004.159563245834, 8066.717664881759, 8095.919040809576, 8099.595020248964, 4919.458859525449, 5125.487451254882, 5191.0, 5201.8959620807755, 5206.687598744087, 1224.663217615154, 1443.6174413780357, 1479.4599971010612, 1509.6603264265561, 1510.3958416633325, 21119.521633485507, 21360.572788544298, 21473.926303684908, 21483.462913427134, 21488.677669834964, 5528.944710552923, 6878.8993760998, 6896.344847239504, 6956.443484521327, 7092.078029856086, 14814.555563332902, 14866.479673211386, 14878.92560537212, 14903.0, 14907.552773416815, 7717.189695082048, 7753.53478791263, 7807.375409967189, 7859.253370929752, 7862.2137786222065, 7136.3220494052985, 7266.745663901738, 7280.635968201569, 7310.122785265696, 7323.450741194431, 5246.947530524722, 5338.599605029561, 5363.088274993261, 5389.568834493231, 5428.5114339708825, 1583.6199312165227, 1584.6196912740859, 1594.3542865139636, 1599.6240883392393, 1615.1843319124002, 694.7290556682898, 708.5713143548182, 835.1481488881428, 835.7450977451936, 838.195332480813, 6193.752249910043, 8077.515349079074, 8112.878306825402, 8460.323174146053, 8463.407561470762, 973.109604711695, 1002.3184234720314, 1022.0494939706144, 1041.776018156085, 1056.2183983851894, 4936.925946110811, 4972.154733695281, 5036.496350364934, 5240.9475905241225, 5359.598030147755, 18180.909095454437, 18829.529261768566, 19223.61552768937, 19290.324838630862, 19352.806471935382, 18854.528636783914, 19101.516802815546, 19107.23571057142, 19215.13531891068, 19379.80620193808, 8510.74467765968, 10064.597416103421, 10120.443375614264, 10177.643782467578, 10190.949045254727, 7616.3906887447765, 7719.228077192461, 7786.454948153575, 7926.128125906261, 7942.960285198537, 14760.188189649672, 17670.0, 17863.821361786737, 18116.456506304832, 18165.909170454062, 17146.142692865058, 17976.460706178834, 18056.45830625083, 18252.726209106608, 18266.908665456587, 11196.216264861381, 11235.213535052468, 11445.656630301104, 11563.074954003492, 11576.958073773401, 12851.935740321238, 12878.227306361649, 12983.0, 13032.47870085186, 13044.478220871439, 10238.948805255926, 10335.483225838527, 10566.524506397076, 10717.9464102679, 11017.504212310305, 10073.0, 10256.743581410525, 10372.844407334047, 10523.105536029523, 10528.526216320131, 9689.806203875742, 9713.417194968324, 9749.9025009749, 9750.219982401533, 9768.218542516724, 6891.241963384124, 7042.753503627348, 7120.501564890408, 7351.485396022227, 7371.852562948819, 7042.366187043191, 7152.356287934111, 7336.926630733625, 7361.895715642482, 7413.369863561442], "value": 6419.627922174619, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [457.16339099448015, 467.1800989263841, 487.3737247636787, 494.52031529416456, 497.47019424313106, 6500.829850626886, 6553.705083271252, 6601.346051034022, 6617.305182955781, 6618.5108350621085, 5118.9744051279695, 6163.753449862, 6336.42972132508, 6386.774678787217, 6433.485321174307, 4324.978375108128, 4850.514948505156, 4864.537868902448, 4870.366852309195, 4886.802733330339, 4012.739171953828, 4876.780544875489, 4883.340748998875, 4888.877778055559, 4902.558769710726, 3996.5803590623, 4253.234417804794, 4589.5410458954175, 4767.833125840596, 4813.927791083137, 4107.9178416431505, 4549.499555048926, 4818.132736107499, 4844.75776211188, 4870.902581948341, 3720.3675375186012, 4257.489101307863, 4294.355846623001, 4300.247456695066, 4303.634191093761, 6241.812745617639, 6246.219222597176, 6269.686515674198, 6355.682215889187, 6364.172657554534, 5737.770489180428, 5756.33802112754, 5761.337446193699, 5788.437121977085, 5795.478406943354, 6858.828529286758, 6933.757318493879, 6954.617496037716, 6961.477889158334, 6966.512344135913, 4731.101090792773, 4887.364642596475, 4894.975525122387, 4899.28960300758, 4949.925751113737, 6377.8201032809075, 6389.488840892717, 6657.934730443106, 6779.62712050837, 7376.483646144826, 4382.912341753211, 4395.780210989437, 4973.90052198954, 5000.024995125988, 5001.224810154432, 5084.56781173597, 5098.898022039612, 5100.642954993189, 5101.591872650179, 5149.588032957355, 5762.394948530427, 5777.566682498829, 5780.508656764138, 5780.6415492359265, 5801.825945221565, 5779.711014449261, 5790.913136302959, 6045.909311360333, 6952.270011648805, 7014.508984371045, 1467.4863797670944, 1546.6520032992712, 1663.236968813062, 1737.6611560745532, 1753.377550969413, 977.4868194197961, 1161.9302841829517, 1170.1633332167542, 1188.738477534941, 1200.1359021504502, 6036.547258955508, 6295.0, 6332.4934005279465, 6428.807135785841, 6689.46484281266, 7056.435485161175, 7143.42852571793, 7264.055672762613, 7280.890786638205, 7314.817129571803, 6649.73401063952, 6666.333366663392, 6672.731271367758, 6675.432588229958, 6695.933040669628, 3051.8321492318137, 3176.38060578185, 3722.6091260417807, 3749.6812770914776, 3772.320982223226, 7491.213422590658, 7492.438067144986, 7494.838300063389, 7511.549307041595, 7520.548767073884, 5512.3660779010925, 6548.607083574905, 7407.777766667009, 7410.888836667455, 7424.0, 6920.5847649142215, 7156.606386648784, 7469.81690253449, 7504.922981577527, 7534.510256833289, 7154.284571542804, 7172.892406613801, 7174.605396703023, 7201.927980720126, 7224.024756657784, 5853.536615846038, 6159.599626024294, 7046.330598593126, 7063.540869843444, 7076.646167691491, 7204.56772593646, 7442.720435749357, 7450.143233527997, 7503.737369192052, 7524.962375188089, 4169.2495350835925, 4490.393796837385, 4541.909161816679, 4954.455009948831, 4957.206846904623, 6256.436920677161, 6939.479539034492, 7074.575525468488, 7153.284671532805, 7154.928450715634, 5627.465390787868, 6913.308669133046, 6945.583265004116, 6946.756863509753, 6956.460926728859, 5221.738913054408, 6485.189351331083, 6839.179298484015, 6934.375906168469, 6952.061471701256, 6766.560173588701, 6767.191615479521, 6769.9661501694145, 6773.627450490073, 6865.828354290983, 5592.888142237214, 6162.890679677701, 6324.335944725922, 6924.503125921377, 6946.097007389007, 5122.513361230677, 5638.407967163553, 6655.800325990228, 6706.463482921257, 6751.729930802713], "value": 5086.7300943608, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [819.6229734322211, 826.9958650206748, 876.5398165962873, 896.5203616065402, 900.086412291524, 18218.5445363866, 18324.083795810206, 18537.90731046343, 18549.35077272296, 18549.814501854995, 15228.619284517892, 15256.923715381408, 16373.0, 18328.266869325213, 19420.028998550086, 1016.374929418406, 1028.5525796278605, 1036.9325993810417, 1040.266612038511, 1041.6614600254948, 1030.1141018723893, 1038.7662775875442, 1040.8386700061503, 1047.6542740895525, 1050.6585359758071, 1101.102601379877, 1103.3214573037565, 1104.4312179227704, 1105.8838821923703, 1110.3337997201695, 1037.082182268694, 1080.1466841195447, 1083.285031878956, 1087.0977089016176, 1113.7549739057436, 763.6029264782337, 776.491398134223, 781.6716978868889, 781.8436312737475, 782.22950393862, 18000.009999450023, 18666.906665466722, 18697.439076827715, 18719.344822931267, 18819.62360752791, 18196.545086372815, 18590.349337773114, 18734.906325468284, 18930.810691893043, 19000.5249868753, 15570.29933652977, 15687.68624627501, 15743.2128393581, 15767.763483547757, 15785.526434206991, 3755.7183211258994, 3765.0399148217302, 3781.130340021783, 3804.2391521695495, 3996.820143093568, 147230.79153812808, 150923.0, 153042.2119374405, 158061.2096939508, 159131.2043439787, 14487.275636218146, 14699.338529766186, 14786.778198327034, 14795.038322509217, 14818.184999824898, 26981.325466863487, 27741.722582773913, 28221.576676349872, 28697.0, 29086.418271634448, 8501.362397820189, 8597.140285971353, 8636.136386361313, 8641.395102342776, 8654.610542525601, 3554.413521768931, 3617.186133120027, 4102.589741025873, 4151.501819781585, 4207.116505533811, 4895.04546613407, 4984.451710311862, 5009.223570346602, 5009.574186194214, 5047.798088076509, 1048.916086713061, 1070.5280239670453, 1082.9079528240031, 1087.1791797193227, 1090.8254679251281, 626.6240255846456, 679.78926532775, 682.3756263019282, 703.3985942019602, 706.3783870194299, 12791.0, 14353.78469322961, 14434.639134021734, 14541.636459088395, 14848.628784280263, 650.5576208178484, 654.6693919570681, 682.5972676121013, 700.2122612061431, 710.3784188834769, 5523.392426833045, 5606.411326810708, 5841.94158058414, 5975.551833612496, 5980.85047873798, 128127.35936320259, 131349.0, 144110.0, 150094.24952875383, 151078.48921510865, 111701.44149279365, 112436.0, 117809.0, 118456.40771796259, 118469.81530184588, 96380.51809740906, 97626.0, 101984.49007754914, 105218.0, 107497.0, 10081.949590252001, 11774.587889423829, 12657.367131643197, 12871.485140594647, 13055.608331750063, 42801.785991069846, 44635.553644463136, 44969.0, 46112.07775844532, 46397.0, 85748.71376929476, 86410.1358986402, 87556.12443875479, 88149.55925220333, 88309.0, 18452.538686532946, 18574.2570297192, 18727.250909963448, 18729.96985165829, 18767.530811729815, 18561.44315670532, 19028.04859757034, 19502.512437189183, 19734.1119649619, 20062.398128056182, 14732.410703571737, 17688.292468301122, 18492.35276765307, 18639.254429822657, 18845.811541884956, 16528.917355413145, 16902.83097169062, 17420.651586968444, 17451.04019278901, 17613.295468181128, 17426.651466970845, 18153.364632238336, 18375.632487350445, 18562.164702588147, 18670.066496675383, 11942.462589183333, 11987.460564274454, 12167.0, 12208.69478263015, 12374.257544547356, 13986.370613322628, 14848.257587120817, 14897.925510372379, 14900.105993640416, 14941.62645933817], "value": 9287.036751054176, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1517.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 127.0, 136.0, 163.0, 114.0, 116.0, 115.0, 103.0, 118.0, 119.0], "value": 121.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 109.0, 149.0, 179.0, 111.0, 122.0, 123.0, 120.0, 183.0, 115.0], "value": 133.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 202.0, 200.0, 203.0, 197.0, 223.0, 197.0, 286.0, 216.0, 201.0], "value": 212.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 84.0, 73.0, 157.0, 78.0, 88.0, 91.0, 90.0, 121.0, 56.0], "value": 90.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 117.0, 72.0, 94.0, 110.0, 142.0, 82.0, 74.0, 108.0], "value": 93.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 104.0, 89.0, 88.0, 89.0, 88.0, 89.0, 91.0, 87.0, 90.0], "value": 90.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 95.0, 96.0, 97.0, 97.0, 97.0, 96.0, 104.0], "value": 97.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 99.0, 124.0, 88.0, 90.0, 91.0, 90.0, 92.0, 131.0], "value": 98.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 49.0, 51.0, 49.0, 52.0, 51.0, 51.0, 50.0, 51.0, 51.0], "value": 50.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 58.0, 55.0, 55.0, 64.0, 56.0, 54.0, 56.0, 59.0], "value": 56.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 79.0, 77.0, 106.0, 67.0, 102.0, 101.0, 102.0, 101.0, 77.0], "value": 89.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 130.0, 132.0, 131.0, 132.0, 138.0, 156.0, 153.0, 148.0, 160.0], "value": 141.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 181.0, 175.0, 168.0, 168.0, 167.0, 216.0, 177.0, 176.0, 174.0], "value": 177.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 67.0, 61.0, 64.0, 65.0, 64.0, 65.0, 62.0, 63.0], "value": 64.0, "unit": "score"}]}]} 11:15:18 INFO - Return code: 0 11:15:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:15:18 INFO - # TBPL SUCCESS # 11:15:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:15:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:15:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:15:18 INFO - 2017-06-14 11:15:18.483 screenresolution[1670:11169] starting screenresolution argv=screenresolution get 11:15:18 INFO - 2017-06-14 11:15:18.485 screenresolution[1670:11169] Display 0: 1600x1200x32@60 11:15:18 INFO - 2017-06-14 11:15:18.491 screenresolution[1671:11172] starting screenresolution argv=screenresolution list 11:15:18 INFO - Available Modes on Display 0 11:15:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:15:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:15:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:15:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:15:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:15:18 INFO - Intel Iris: 11:15:18 INFO - Chipset Model: Intel Iris 11:15:18 INFO - Type: GPU 11:15:18 INFO - Bus: Built-In 11:15:18 INFO - VRAM (Dynamic, Max): 1536 MB 11:15:18 INFO - Vendor: Intel (0x8086) 11:15:18 INFO - Device ID: 0x0a2e 11:15:18 INFO - Revision ID: 0x0009 11:15:18 INFO - Displays: 11:15:18 INFO - Display: 11:15:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:15:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:15:18 INFO - Display Serial Number: 1600x1200 60 11:15:18 INFO - Main Display: Yes 11:15:18 INFO - Mirror: Off 11:15:18 INFO - Online: Yes 11:15:18 INFO - Rotation: Supported 11:15:18 INFO - Return code: 0 11:15:18 INFO - Running post-action listener: _resource_record_post_action 11:15:18 INFO - [mozharness: 2017-06-14 18:15:18.715691Z] Finished run-tests step (success) 11:15:18 INFO - Running post-run listener: _resource_record_post_run 11:15:18 INFO - Total resource usage - Wall time: 1248s; CPU: 53.0%; Read bytes: 41167360; Write bytes: 471314432; Read time: 269; Write time: 1566 11:15:18 INFO - TinderboxPrint: CPU usage
52.9% 11:15:18 INFO - TinderboxPrint: I/O read bytes / time
41,167,360 / 269 11:15:18 INFO - TinderboxPrint: I/O write bytes / time
471,314,432 / 1,566 11:15:18 INFO - TinderboxPrint: CPU idle
2,350.2 (47.1%) 11:15:18 INFO - TinderboxPrint: CPU system
1,010.0 (20.2%) 11:15:18 INFO - TinderboxPrint: CPU user
1,633.5 (32.7%) 11:15:18 INFO - TinderboxPrint: Swap in / out
194,355,200 / 0 11:15:18 INFO - install - Wall time: 16s; CPU: 54.0%; Read bytes: 133816832; Write bytes: 128046592; Read time: 10764; Write time: 286 11:15:18 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 11:15:18 INFO - run-tests - Wall time: 1233s; CPU: 53.0%; Read bytes: 40159744; Write bytes: 314960384; Read time: 255; Write time: 1206 11:15:19 INFO - Running post-run listener: _upload_blobber_files 11:15:19 INFO - Blob upload gear active. 11:15:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:15:19 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 11:15:19 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:15:19 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:15:19 INFO - (blobuploader) - INFO - Open directory for files ... 11:15:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:15:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:15:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:15:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:15:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:15:21 INFO - (blobuploader) - INFO - Done attempting. 11:15:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:15:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:15:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:15:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:15:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:15:22 INFO - (blobuploader) - INFO - Done attempting. 11:15:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:15:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:15:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:15:23 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:15:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:15:23 INFO - (blobuploader) - INFO - Done attempting. 11:15:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:15:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:15:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:15:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:15:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:15:24 INFO - (blobuploader) - INFO - Done attempting. 11:15:24 INFO - (blobuploader) - INFO - Iteration through files over. 11:15:24 INFO - Return code: 0 11:15:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:15:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:15:24 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8efbc6d8ec8296169028849315d1354ea023ff62ddfd21512a023bb151b105b29cb89008f28dd7eb12e889b8e1b3373e8c4b30205fcf7f1a31fe918a63e3d8ff", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a1a342124470b7b5facf80c7a35b9d7499b7bb401eb1b658b268df8d2bfe2aea638077322a81ea1b5ad1e341c00dffbe08a51f62ebfe3011bd00b1427cafc2d2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0490b857eb54f7d1c225222e1d3bf2ee319eb3b1ed2bee9cea82b39b4cc72dc1a4e9ecad7a432d0b46573a88b62384e971be67d2e1c807264bd41e86181df536", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/be5b3e6c5cc55f1116e0915dd5b2f98785cc3e60f096c0707d5dae07eac642efb292e65eb510d2f701c9babb37b66e9ef2bcbd4c4dd4ef3eb551d10f953cd8c7"} 11:15:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:15:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:15:24 INFO - Contents: 11:15:24 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8efbc6d8ec8296169028849315d1354ea023ff62ddfd21512a023bb151b105b29cb89008f28dd7eb12e889b8e1b3373e8c4b30205fcf7f1a31fe918a63e3d8ff", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a1a342124470b7b5facf80c7a35b9d7499b7bb401eb1b658b268df8d2bfe2aea638077322a81ea1b5ad1e341c00dffbe08a51f62ebfe3011bd00b1427cafc2d2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0490b857eb54f7d1c225222e1d3bf2ee319eb3b1ed2bee9cea82b39b4cc72dc1a4e9ecad7a432d0b46573a88b62384e971be67d2e1c807264bd41e86181df536", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/be5b3e6c5cc55f1116e0915dd5b2f98785cc3e60f096c0707d5dae07eac642efb292e65eb510d2f701c9babb37b66e9ef2bcbd4c4dd4ef3eb551d10f953cd8c7"} 11:15:24 INFO - Running post-run listener: copy_logs_to_upload_dir 11:15:24 INFO - Copying logs to upload dir... 11:15:24 INFO - mkdir: /builds/slave/test/build/upload/logs 11:15:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1328.900451 ========= master_lag: 1.50 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 10 secs) (at 2017-06-14 11:15:26.475192) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-14 11:15:26.478666) ========= 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.g9frllOJX9/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.4WQTWnHIdV/Listeners TMPDIR=/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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/Mozilla-Beta/sha512/8efbc6d8ec8296169028849315d1354ea023ff62ddfd21512a023bb151b105b29cb89008f28dd7eb12e889b8e1b3373e8c4b30205fcf7f1a31fe918a63e3d8ff", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a1a342124470b7b5facf80c7a35b9d7499b7bb401eb1b658b268df8d2bfe2aea638077322a81ea1b5ad1e341c00dffbe08a51f62ebfe3011bd00b1427cafc2d2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0490b857eb54f7d1c225222e1d3bf2ee319eb3b1ed2bee9cea82b39b4cc72dc1a4e9ecad7a432d0b46573a88b62384e971be67d2e1c807264bd41e86181df536", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/be5b3e6c5cc55f1116e0915dd5b2f98785cc3e60f096c0707d5dae07eac642efb292e65eb510d2f701c9babb37b66e9ef2bcbd4c4dd4ef3eb551d10f953cd8c7"} build_url:https://queue.taskcluster.net/v1/task/ciFHRDZfS0OKDPV45N_FDQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011084 build_url: 'https://queue.taskcluster.net/v1/task/ciFHRDZfS0OKDPV45N_FDQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8efbc6d8ec8296169028849315d1354ea023ff62ddfd21512a023bb151b105b29cb89008f28dd7eb12e889b8e1b3373e8c4b30205fcf7f1a31fe918a63e3d8ff", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a1a342124470b7b5facf80c7a35b9d7499b7bb401eb1b658b268df8d2bfe2aea638077322a81ea1b5ad1e341c00dffbe08a51f62ebfe3011bd00b1427cafc2d2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0490b857eb54f7d1c225222e1d3bf2ee319eb3b1ed2bee9cea82b39b4cc72dc1a4e9ecad7a432d0b46573a88b62384e971be67d2e1c807264bd41e86181df536", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/be5b3e6c5cc55f1116e0915dd5b2f98785cc3e60f096c0707d5dae07eac642efb292e65eb510d2f701c9babb37b66e9ef2bcbd4c4dd4ef3eb551d10f953cd8c7"}' ========= master_lag: 0.10 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-14 11:15:26.592594) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 11:15:26.592944) ========= 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.g9frllOJX9/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.4WQTWnHIdV/Listeners TMPDIR=/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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.005117 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 11:15:26.618155) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-14 11:15:26.618461) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-14 11:15:26.618749) ========= ========= Total master_lag: 1.69 =========