builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0255 starttime: 1496181130.42 results: success (0) revision: fbe0e3f31233cc7846a2168c613452a1d7147cc2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-30 14:52:10.423544) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-30 14:52:10.423986) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-30 14:52:10.424287) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-30 14:52:10.452288) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 14:52:10.452602) ========= 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.T4DIiBmsdx/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.PtECUnkGMt/Listeners TMPDIR=/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-30 14:52:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 168M=0s 2017-05-30 14:52:10 (168 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.140528 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 14:52:10.616226) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 14:52:10.616567) ========= 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.T4DIiBmsdx/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.PtECUnkGMt/Listeners TMPDIR=/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/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.026687 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 14:52:10.664840) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-30 14:52:10.665187) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev fbe0e3f31233cc7846a2168c613452a1d7147cc2 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev fbe0e3f31233cc7846a2168c613452a1d7147cc2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.T4DIiBmsdx/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.PtECUnkGMt/Listeners TMPDIR=/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-30 14:52:10,750 truncating revision to first 12 chars 2017-05-30 14:52:10,750 Setting DEBUG logging. 2017-05-30 14:52:10,750 attempt 1/10 2017-05-30 14:52:10,750 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/fbe0e3f31233?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-30 14:52:11,480 unpacking tar archive at: mozilla-central-fbe0e3f31233/testing/mozharness/ program finished with exit code 0 elapsedTime=1.012866 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-30 14:52:11.700526) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-30 14:52:11.700856) ========= script_repo_revision: fbe0e3f31233cc7846a2168c613452a1d7147cc2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-30 14:52:11.701250) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-30 14:52:11.701537) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-30 14:52:11.729006) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2017-05-30 14:52:11.729373) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.T4DIiBmsdx/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.PtECUnkGMt/Listeners TMPDIR=/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/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 14:52:11 INFO - MultiFileLogger online at 20170530 14:52:11 in /builds/slave/test 14:52:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 14:52:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:52:11 INFO - {'append_to_log': False, 14:52:11 INFO - 'base_work_dir': '/builds/slave/test', 14:52:11 INFO - 'blob_upload_branch': 'Firefox', 14:52:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:52:11 INFO - 'branch': 'Firefox', 14:52:11 INFO - 'buildbot_json_path': 'buildprops.json', 14:52:11 INFO - 'config_files': ('talos/mac_config.py',), 14:52:11 INFO - 'default_actions': ('clobber', 14:52:11 INFO - 'read-buildbot-config', 14:52:11 INFO - 'download-and-extract', 14:52:11 INFO - 'populate-webroot', 14:52:11 INFO - 'create-virtualenv', 14:52:11 INFO - 'install', 14:52:11 INFO - 'setup-mitmproxy', 14:52:11 INFO - 'run-tests'), 14:52:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:52:11 INFO - 'download_minidump_stackwalk': True, 14:52:11 INFO - 'download_symbols': 'ondemand', 14:52:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:52:11 INFO - 'tooltool.py': '/tools/tooltool.py', 14:52:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:52:11 INFO - '/tools/misc-python/virtualenv.py')}, 14:52:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:52:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:52:11 INFO - 'gecko_profile': False, 14:52:11 INFO - 'gecko_profile_interval': 0, 14:52:11 INFO - 'installer_path': 'installer.exe', 14:52:11 INFO - 'log_level': 'info', 14:52:11 INFO - 'log_name': 'talos', 14:52:11 INFO - 'log_to_console': True, 14:52:11 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:52:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:52:11 INFO - 'opt_config_files': (), 14:52:11 INFO - 'pip_index': False, 14:52:11 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:52:11 INFO - 'cmd': ('bash', 14:52:11 INFO - '-c', 14:52:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:52:11 INFO - 'enabled': True, 14:52:11 INFO - 'halt_on_failure': False, 14:52:11 INFO - 'name': 'check_screen_resolution'},), 14:52:11 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:52:11 INFO - 'cmd': ('bash', 14:52:11 INFO - '-c', 14:52:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:52:11 INFO - 'enabled': True, 14:52:11 INFO - 'halt_on_failure': False, 14:52:11 INFO - 'name': 'check_screen_resolution'},), 14:52:11 INFO - 'run_cmd_checks_enabled': True, 14:52:11 INFO - 'suite': 'dromaeojs-e10s', 14:52:11 INFO - 'system_bits': '32', 14:52:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:52:11 INFO - 'title': 't-yosemite-r7-0255', 14:52:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:52:11 INFO - 'use_talos_json': True, 14:52:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:52:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:52:11 INFO - 'work_dir': 'build'} 14:52:11 INFO - [mozharness: 2017-05-30 21:52:11.925131Z] Running clobber step. 14:52:11 INFO - Running pre-action listener: _resource_record_pre_action 14:52:11 INFO - Running main action method: clobber 14:52:11 INFO - rmtree: /builds/slave/test/build 14:52:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:52:14 INFO - Running post-action listener: _resource_record_post_action 14:52:14 INFO - [mozharness: 2017-05-30 21:52:14.499878Z] Finished clobber step (success) 14:52:14 INFO - [mozharness: 2017-05-30 21:52:14.500031Z] Running read-buildbot-config step. 14:52:14 INFO - Running pre-action listener: _resource_record_pre_action 14:52:14 INFO - Running main action method: read_buildbot_config 14:52:14 INFO - Using buildbot properties: 14:52:14 INFO - { 14:52:14 INFO - "project": "", 14:52:14 INFO - "product": "firefox", 14:52:14 INFO - "who": "ryanvm@gmail.com", 14:52:14 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 14:52:14 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 14:52:14 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 14:52:14 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 14:52:14 INFO - "stage_platform": "macosx64", 14:52:14 INFO - "basedir": "/builds/slave/test", 14:52:14 INFO - "buildnumber": 68, 14:52:14 INFO - "platform": "yosemite_r7", 14:52:14 INFO - "parent_task_id": "PhApj6BRQlWkJeJyoDtXkg", 14:52:14 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 14:52:14 INFO - "slavebuilddir": "test", 14:52:14 INFO - "taskId": "bI9ygJvITXqnu53EOSp4hw", 14:52:14 INFO - "branch": "mozilla-central", 14:52:14 INFO - "script_repo_revision": "production", 14:52:14 INFO - "revision": "fbe0e3f31233cc7846a2168c613452a1d7147cc2", 14:52:14 INFO - "slavename": "t-yosemite-r7-0255", 14:52:14 INFO - "repo_path": "mozilla-central" 14:52:14 INFO - } 14:52:14 INFO - Finding installer, test and symbols from parent task. 14:52:14 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bI9ygJvITXqnu53EOSp4hw'}, attempt #1 14:52:15 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bI9ygJvITXqnu53EOSp4hw'}, attempt #1 14:52:15 INFO - Task dependencies: KGh5CcypQ56HCvm8VzPFMA PhApj6BRQlWkJeJyoDtXkg 14:52:15 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PhApj6BRQlWkJeJyoDtXkg'}, attempt #1 14:52:16 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/PhApj6BRQlWkJeJyoDtXkg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:52:16 INFO - Set test_url: https://queue.taskcluster.net/v1/task/PhApj6BRQlWkJeJyoDtXkg/artifacts/public/build/test_packages.json 14:52:16 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/PhApj6BRQlWkJeJyoDtXkg/artifacts/public/build/target.crashreporter-symbols.zip 14:52:16 INFO - Running post-action listener: _resource_record_post_action 14:52:16 INFO - [mozharness: 2017-05-30 21:52:16.283282Z] Finished read-buildbot-config step (success) 14:52:16 INFO - [mozharness: 2017-05-30 21:52:16.283435Z] Running download-and-extract step. 14:52:16 INFO - Running pre-action listener: _resource_record_pre_action 14:52:16 INFO - Running main action method: download_and_extract 14:52:16 INFO - mkdir: /builds/slave/test/build/tests 14:52:16 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')]} 14:52:16 INFO - trying https://queue.taskcluster.net/v1/task/PhApj6BRQlWkJeJyoDtXkg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 14:52:16 INFO - Downloading https://queue.taskcluster.net/v1/task/PhApj6BRQlWkJeJyoDtXkg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 14:52:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PhApj6BRQlWkJeJyoDtXkg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 14:52:17 INFO - Downloaded 1325 bytes. 14:52:17 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 14:52:17 INFO - Using the following test package requirements: 14:52:17 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:52:17 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 14:52:17 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 14:52:17 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:52:17 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 14:52:17 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:52:17 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 14:52:17 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:52:17 INFO - u'jsshell-mac.zip'], 14:52:17 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:52:17 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 14:52:17 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 14:52:17 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:52:17 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 14:52:17 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:52:17 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 14:52:17 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:52:17 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 14:52:17 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:52:17 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 14:52:17 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:52:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PhApj6BRQlWkJeJyoDtXkg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 14:52:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PhApj6BRQlWkJeJyoDtXkg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:52:17 INFO - Fetch https://queue.taskcluster.net/v1/task/PhApj6BRQlWkJeJyoDtXkg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 14:52:19 INFO - Content-Length response header: 57628821 14:52:19 INFO - Bytes received: 57628821 14:52:37 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 14:52:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PhApj6BRQlWkJeJyoDtXkg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 14:52:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PhApj6BRQlWkJeJyoDtXkg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:52:37 INFO - Fetch https://queue.taskcluster.net/v1/task/PhApj6BRQlWkJeJyoDtXkg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 14:52:38 INFO - Content-Length response header: 57628821 14:52:38 INFO - Bytes received: 57628821 14:52:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PhApj6BRQlWkJeJyoDtXkg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 14:52:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PhApj6BRQlWkJeJyoDtXkg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 14:52:55 INFO - Fetch https://queue.taskcluster.net/v1/task/PhApj6BRQlWkJeJyoDtXkg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 14:52:56 INFO - Content-Length response header: 14447425 14:52:56 INFO - Bytes received: 14447425 14:52:56 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')]} 14:52:56 INFO - trying https://queue.taskcluster.net/v1/task/PhApj6BRQlWkJeJyoDtXkg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:52:56 INFO - Downloading https://queue.taskcluster.net/v1/task/PhApj6BRQlWkJeJyoDtXkg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 14:52:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PhApj6BRQlWkJeJyoDtXkg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 14:52:58 INFO - Downloaded 67895947 bytes. 14:52:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PhApj6BRQlWkJeJyoDtXkg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:52:58 INFO - mkdir: /builds/slave/test/properties 14:52:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:52:58 INFO - Writing to file /builds/slave/test/properties/build_url 14:52:58 INFO - Contents: 14:52:58 INFO - build_url:https://queue.taskcluster.net/v1/task/PhApj6BRQlWkJeJyoDtXkg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:52:58 INFO - Running post-action listener: _resource_record_post_action 14:52:58 INFO - Running post-action listener: set_extra_try_arguments 14:52:58 INFO - [mozharness: 2017-05-30 21:52:58.799746Z] Finished download-and-extract step (success) 14:52:58 INFO - [mozharness: 2017-05-30 21:52:58.799897Z] Running populate-webroot step. 14:52:58 INFO - Running pre-action listener: _resource_record_pre_action 14:52:58 INFO - Running main action method: populate_webroot 14:52:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:52:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:52:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:52:58 INFO - '--tppagecycles', 14:52:58 INFO - '7'], 14:52:58 INFO - 'tests': ['tsvgm']}}, 14:52:58 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:52:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:52:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:52:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:52:58 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:52:58 INFO - 'talos_options': ['--disable-e10s'], 14:52:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:52:58 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:52:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:52:58 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:52:58 INFO - 'talos_options': ['--disable-e10s'], 14:52:58 INFO - 'tests': ['damp', 'tps']}, 14:52:58 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:52:58 INFO - 'tests': ['damp', 'tps']}, 14:52:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:52:58 INFO - 'tests': ['dromaeo_dom']}, 14:52:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:52:58 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:52:58 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 14:52:58 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 14:52:58 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 14:52:58 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 14:52:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:52:58 INFO - 'tests': ['a11yr', 14:52:58 INFO - 'ts_paint', 14:52:58 INFO - 'tpaint', 14:52:58 INFO - 'sessionrestore', 14:52:58 INFO - 'sessionrestore_no_auto_restore', 14:52:58 INFO - 'tabpaint']}, 14:52:58 INFO - 'other-e10s': {'tests': ['a11yr', 14:52:58 INFO - 'ts_paint', 14:52:58 INFO - 'tpaint', 14:52:58 INFO - 'sessionrestore', 14:52:58 INFO - 'sessionrestore_no_auto_restore', 14:52:58 INFO - 'tabpaint']}, 14:52:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:52:58 INFO - 'ts_paint', 14:52:58 INFO - 'tpaint', 14:52:58 INFO - 'sessionrestore', 14:52:58 INFO - 'sessionrestore_no_auto_restore', 14:52:58 INFO - 'tabpaint']}, 14:52:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:52:58 INFO - 'ts_paint', 14:52:58 INFO - 'tpaint', 14:52:58 INFO - 'sessionrestore', 14:52:58 INFO - 'sessionrestore_no_auto_restore', 14:52:58 INFO - 'tabpaint']}, 14:52:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:52:58 INFO - 'tests': ['a11yr', 14:52:58 INFO - 'ts_paint', 14:52:58 INFO - 'tpaint', 14:52:58 INFO - 'sessionrestore', 14:52:58 INFO - 'sessionrestore_no_auto_restore', 14:52:58 INFO - 'tabpaint']}, 14:52:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:52:58 INFO - 'tests': ['a11yr', 14:52:58 INFO - 'ts_paint', 14:52:58 INFO - 'tpaint', 14:52:58 INFO - 'sessionrestore', 14:52:58 INFO - 'sessionrestore_no_auto_restore', 14:52:58 INFO - 'tabpaint']}, 14:52:58 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 14:52:58 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 14:52:58 INFO - 'bloom_basic_ref']}, 14:52:58 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 14:52:58 INFO - 'talos_options': ['--mitmproxy', 14:52:58 INFO - 'mitmproxy-recording-1.mp', 14:52:58 INFO - '--firstNonBlankPaint'], 14:52:58 INFO - 'tests': ['Quantum_1']}, 14:52:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:52:58 INFO - 'tests': ['tsvgx', 14:52:58 INFO - 'tsvgr_opacity', 14:52:58 INFO - 'tart', 14:52:58 INFO - 'tscrollx', 14:52:58 INFO - 'cart', 14:52:58 INFO - 'tsvg_static']}, 14:52:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:52:58 INFO - 'tsvgr_opacity', 14:52:58 INFO - 'tart', 14:52:58 INFO - 'tscrollx', 14:52:58 INFO - 'cart', 14:52:58 INFO - 'tsvg_static']}, 14:52:58 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:52:58 INFO - 'talos_options': ['--disable-e10s'], 14:52:58 INFO - 'tests': ['tp5o']}, 14:52:58 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:52:58 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:52:58 INFO - 'talos_options': ['--disable-e10s', 14:52:58 INFO - '--xperf_path', 14:52:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:52:58 INFO - 'tests': ['tp5n']}, 14:52:58 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:52:58 INFO - 'talos_options': ['--xperf_path', 14:52:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:52:58 INFO - 'tests': ['tp5n']}}, 14:52:58 INFO - 'talos.zip': {'path': '', 14:52:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:52:58 INFO - Running post-action listener: _resource_record_post_action 14:52:58 INFO - [mozharness: 2017-05-30 21:52:58.821548Z] Finished populate-webroot step (success) 14:52:58 INFO - [mozharness: 2017-05-30 21:52:58.821689Z] Running create-virtualenv step. 14:52:58 INFO - Running pre-action listener: _resource_record_pre_action 14:52:58 INFO - Running main action method: create_virtualenv 14:52:58 INFO - Creating virtualenv /builds/slave/test/build/venv 14:52:58 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 14:52:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:52:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:52:58 INFO - Using real prefix '/tools/python27' 14:52:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:52:59 INFO - Installing distribute.............................................................................................................................................................................................done. 14:53:02 INFO - Installing pip.................done. 14:53:02 INFO - Return code: 0 14:53:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:53:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:53:02 INFO - Reading from file tmpfile_stdout 14:53:02 INFO - Output received: 14:53:02 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) 14:53:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:53:02 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')]} 14:53:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:53:02 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')]} 14:53:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:53:02 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 0x10f467c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f46b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91b1d8b400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3f0c60>, '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 0x10f185cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f1aebe8>, '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.T4DIiBmsdx/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.PtECUnkGMt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/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 14:53:02 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 14:53:02 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 14:53:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T4DIiBmsdx/Render', 14:53:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:53:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:53:02 INFO - 'HOME': '/Users/cltbld', 14:53:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:53:02 INFO - 'LOGNAME': 'cltbld', 14:53:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:53:02 INFO - 'MOZ_NO_REMOTE': '1', 14:53:02 INFO - 'NO_EM_RESTART': '1', 14:53:02 INFO - 'PAGER': '/bin/cat', 14:53:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:53:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:53:02 INFO - 'PWD': '/builds/slave/test', 14:53:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:53:02 INFO - 'SHELL': '/bin/bash', 14:53:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PtECUnkGMt/Listeners', 14:53:02 INFO - 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/', 14:53:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:53:02 INFO - 'USER': 'cltbld', 14:53:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:53:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:53:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:53:02 INFO - 'XPC_FLAGS': '0x0', 14:53:02 INFO - 'XPC_SERVICE_NAME': '0', 14:53:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:53:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:53:03 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 14:53:03 INFO - Cleaning up... 14:53:03 INFO - Return code: 0 14:53:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:53:03 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')]} 14:53:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:53:03 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')]} 14:53:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:53:03 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 0x10f467c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f46b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91b1d8b400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3f0c60>, '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 0x10f185cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f1aebe8>, '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.T4DIiBmsdx/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.PtECUnkGMt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/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 14:53:03 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 14:53:03 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 14:53:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T4DIiBmsdx/Render', 14:53:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:53:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:53:03 INFO - 'HOME': '/Users/cltbld', 14:53:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:53:03 INFO - 'LOGNAME': 'cltbld', 14:53:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:53:03 INFO - 'MOZ_NO_REMOTE': '1', 14:53:03 INFO - 'NO_EM_RESTART': '1', 14:53:03 INFO - 'PAGER': '/bin/cat', 14:53:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:53:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:53:03 INFO - 'PWD': '/builds/slave/test', 14:53:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:53:03 INFO - 'SHELL': '/bin/bash', 14:53:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PtECUnkGMt/Listeners', 14:53:03 INFO - 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/', 14:53:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:53:03 INFO - 'USER': 'cltbld', 14:53:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:53:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:53:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:53:03 INFO - 'XPC_FLAGS': '0x0', 14:53:03 INFO - 'XPC_SERVICE_NAME': '0', 14:53:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:53:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:53:03 INFO - Downloading/unpacking psutil>=3.1.1 14:53:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:53:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:53:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:53:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:53:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:53:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:53:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:53:05 INFO - Installing collected packages: psutil 14:53:05 INFO - Running setup.py install for psutil 14:53:05 INFO - building 'psutil._psutil_osx' extension 14:53:05 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 14:53:06 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 14:53:06 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 14:53:06 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 14:53:06 INFO - building 'psutil._psutil_posix' extension 14:53:06 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 14:53:06 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:53:06 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:53:06 INFO - ^ 14:53:06 INFO - 1 warning generated. 14:53:06 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 14:53:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:53:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:53:06 INFO - Successfully installed psutil 14:53:06 INFO - Cleaning up... 14:53:06 INFO - Return code: 0 14:53:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:53: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')]} 14:53:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:53: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')]} 14:53:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:53: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f467c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f46b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91b1d8b400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3f0c60>, '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 0x10f185cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f1aebe8>, '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.T4DIiBmsdx/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.PtECUnkGMt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/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 14:53: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:53: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 mozsystemmonitor==0.3 14:53:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T4DIiBmsdx/Render', 14:53:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:53:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:53:06 INFO - 'HOME': '/Users/cltbld', 14:53:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:53:06 INFO - 'LOGNAME': 'cltbld', 14:53:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:53:06 INFO - 'MOZ_NO_REMOTE': '1', 14:53:06 INFO - 'NO_EM_RESTART': '1', 14:53:06 INFO - 'PAGER': '/bin/cat', 14:53:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:53:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:53:06 INFO - 'PWD': '/builds/slave/test', 14:53:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:53:06 INFO - 'SHELL': '/bin/bash', 14:53:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PtECUnkGMt/Listeners', 14:53:06 INFO - 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/', 14:53:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:53:06 INFO - 'USER': 'cltbld', 14:53:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:53:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:53:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:53:06 INFO - 'XPC_FLAGS': '0x0', 14:53:06 INFO - 'XPC_SERVICE_NAME': '0', 14:53:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:53:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:53:07 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:53: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 14:53: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 14:53: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 14:53: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 14:53:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:53:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:53:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:53:08 INFO - Installing collected packages: mozsystemmonitor 14:53:08 INFO - Running setup.py install for mozsystemmonitor 14:53:08 INFO - Successfully installed mozsystemmonitor 14:53:08 INFO - Cleaning up... 14:53:08 INFO - Return code: 0 14:53:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:53:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:53:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:53:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:53:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:53:08 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 0x10f467c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f46b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91b1d8b400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3f0c60>, '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 0x10f185cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f1aebe8>, '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.T4DIiBmsdx/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.PtECUnkGMt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/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 14:53:08 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 14:53:08 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 14:53:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T4DIiBmsdx/Render', 14:53:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:53:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:53:08 INFO - 'HOME': '/Users/cltbld', 14:53:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:53:08 INFO - 'LOGNAME': 'cltbld', 14:53:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:53:08 INFO - 'MOZ_NO_REMOTE': '1', 14:53:08 INFO - 'NO_EM_RESTART': '1', 14:53:08 INFO - 'PAGER': '/bin/cat', 14:53:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:53:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:53:08 INFO - 'PWD': '/builds/slave/test', 14:53:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:53:08 INFO - 'SHELL': '/bin/bash', 14:53:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PtECUnkGMt/Listeners', 14:53:08 INFO - 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/', 14:53:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:53:08 INFO - 'USER': 'cltbld', 14:53:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:53:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:53:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:53:08 INFO - 'XPC_FLAGS': '0x0', 14:53:08 INFO - 'XPC_SERVICE_NAME': '0', 14:53:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:53:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:53:08 INFO - Downloading/unpacking jsonschema==2.5.1 14:53:08 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 14:53:08 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 14:53:08 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 14:53:08 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 14:53:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:53:10 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:53: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 14:53: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 14:53: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 14:53: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 14:53:10 INFO - Downloading functools32-3.2.3-2.tar.gz 14:53:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:53:10 INFO - warning: no files found matching '*.txt' 14:53:10 INFO - no previously-included directories found matching 'build' 14:53:10 INFO - no previously-included directories found matching 'dist' 14:53:10 INFO - no previously-included directories found matching '.git*' 14:53:10 INFO - Installing collected packages: jsonschema, functools32 14:53:10 INFO - Running setup.py install for functools32 14:53:10 INFO - warning: no files found matching '*.txt' 14:53:10 INFO - no previously-included directories found matching 'build' 14:53:10 INFO - no previously-included directories found matching 'dist' 14:53:10 INFO - no previously-included directories found matching '.git*' 14:53:10 INFO - Successfully installed jsonschema functools32 14:53:10 INFO - Cleaning up... 14:53:10 INFO - Return code: 0 14:53:10 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:53: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')]} 14:53:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:53: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')]} 14:53:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:53: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', '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 0x10f467c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f46b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91b1d8b400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3f0c60>, '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 0x10f185cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f1aebe8>, '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.T4DIiBmsdx/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.PtECUnkGMt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/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 14:53: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:53: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 functools32==3.2.3-2 14:53:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T4DIiBmsdx/Render', 14:53:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:53:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:53:10 INFO - 'HOME': '/Users/cltbld', 14:53:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:53:10 INFO - 'LOGNAME': 'cltbld', 14:53:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:53:10 INFO - 'MOZ_NO_REMOTE': '1', 14:53:10 INFO - 'NO_EM_RESTART': '1', 14:53:10 INFO - 'PAGER': '/bin/cat', 14:53:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:53:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:53:10 INFO - 'PWD': '/builds/slave/test', 14:53:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:53:10 INFO - 'SHELL': '/bin/bash', 14:53:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PtECUnkGMt/Listeners', 14:53:10 INFO - 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/', 14:53:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:53:10 INFO - 'USER': 'cltbld', 14:53:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:53:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:53:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:53:10 INFO - 'XPC_FLAGS': '0x0', 14:53:10 INFO - 'XPC_SERVICE_NAME': '0', 14:53:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:53:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:53:11 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:53:11 INFO - Cleaning up... 14:53:11 INFO - Return code: 0 14:53:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:53:11 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')]} 14:53:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:53:11 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')]} 14:53:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:53:11 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 0x10f467c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f46b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91b1d8b400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3f0c60>, '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 0x10f185cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f1aebe8>, '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.T4DIiBmsdx/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.PtECUnkGMt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/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 14:53:11 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 14:53:11 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 14:53:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T4DIiBmsdx/Render', 14:53:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:53:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:53:11 INFO - 'HOME': '/Users/cltbld', 14:53:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:53:11 INFO - 'LOGNAME': 'cltbld', 14:53:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:53:11 INFO - 'MOZ_NO_REMOTE': '1', 14:53:11 INFO - 'NO_EM_RESTART': '1', 14:53:11 INFO - 'PAGER': '/bin/cat', 14:53:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:53:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:53:11 INFO - 'PWD': '/builds/slave/test', 14:53:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:53:11 INFO - 'SHELL': '/bin/bash', 14:53:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PtECUnkGMt/Listeners', 14:53:11 INFO - 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/', 14:53:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:53:11 INFO - 'USER': 'cltbld', 14:53:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:53:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:53:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:53:11 INFO - 'XPC_FLAGS': '0x0', 14:53:11 INFO - 'XPC_SERVICE_NAME': '0', 14:53:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:53:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:53:11 INFO - Downloading/unpacking blobuploader==1.2.4 14:53:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:53:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:53:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:53:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:53:12 INFO - Downloading blobuploader-1.2.4.tar.gz 14:53:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:53:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:53:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:53:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:53:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:53:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:53:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:53:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:53: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 14:53: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 14:53: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 14:53: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 14:53:13 INFO - Downloading docopt-0.6.1.tar.gz 14:53:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:53:13 INFO - Installing collected packages: blobuploader, requests, docopt 14:53:13 INFO - Running setup.py install for blobuploader 14:53:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:53:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:53:14 INFO - Running setup.py install for requests 14:53:14 INFO - Running setup.py install for docopt 14:53:14 INFO - Successfully installed blobuploader requests docopt 14:53:14 INFO - Cleaning up... 14:53:14 INFO - Return code: 0 14:53:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:53:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:53:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:53:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:53:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:53:14 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 0x10f467c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f46b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91b1d8b400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3f0c60>, '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 0x10f185cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f1aebe8>, '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.T4DIiBmsdx/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.PtECUnkGMt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/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 14:53:14 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 14:53:14 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 14:53:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T4DIiBmsdx/Render', 14:53:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:53:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:53:14 INFO - 'HOME': '/Users/cltbld', 14:53:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:53:14 INFO - 'LOGNAME': 'cltbld', 14:53:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:53:14 INFO - 'MOZ_NO_REMOTE': '1', 14:53:14 INFO - 'NO_EM_RESTART': '1', 14:53:14 INFO - 'PAGER': '/bin/cat', 14:53:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:53:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:53:14 INFO - 'PWD': '/builds/slave/test', 14:53:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:53:14 INFO - 'SHELL': '/bin/bash', 14:53:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PtECUnkGMt/Listeners', 14:53:14 INFO - 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/', 14:53:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:53:14 INFO - 'USER': 'cltbld', 14:53:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:53:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:53:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:53:14 INFO - 'XPC_FLAGS': '0x0', 14:53:14 INFO - 'XPC_SERVICE_NAME': '0', 14:53:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:53:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:53:14 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-qaAluh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:53:15 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-ilVDvl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:53:15 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-wuvsF6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:53:15 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-SJvQqu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:53:15 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-1RjepZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:53:15 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-RhnmVT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:53:15 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-rSU1w6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:53:15 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-F6Fbsq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:53:15 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-i9gm4e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:53:15 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-_1hhWI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:53:16 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-IujiBn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:53:16 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-VFGvTN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:53:16 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-DNrTSn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:53:16 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-A5jL6z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:53:16 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-fBG4SN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:53:16 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-PD2CZa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:53:16 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-gkmAtz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:53:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:53:16 INFO - Running setup.py install for manifestparser 14:53:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:53:16 INFO - Running setup.py install for mozcrash 14:53:17 INFO - Running setup.py install for mozdebug 14:53:17 INFO - Running setup.py install for mozdevice 14:53:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:53:17 INFO - Running setup.py install for mozfile 14:53:17 INFO - Running setup.py install for mozhttpd 14:53:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:53:17 INFO - Running setup.py install for mozinfo 14:53:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:53:17 INFO - Running setup.py install for mozInstall 14:53:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:53:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:53:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:53:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:53:17 INFO - Running setup.py install for mozleak 14:53:18 INFO - Running setup.py install for mozlog 14:53:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:53:18 INFO - Running setup.py install for moznetwork 14:53:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:53:18 INFO - Running setup.py install for mozprocess 14:53:18 INFO - Running setup.py install for mozprofile 14:53:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:53:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:53:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:53:18 INFO - Running setup.py install for mozrunner 14:53:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:53:18 INFO - Running setup.py install for mozscreenshot 14:53:18 INFO - Running setup.py install for moztest 14:53:19 INFO - Running setup.py install for mozversion 14:53:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:53:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:53:19 INFO - Cleaning up... 14:53:19 INFO - Return code: 0 14:53:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:53: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')]} 14:53:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:53: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')]} 14:53:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:53:19 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 0x10f467c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f46b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91b1d8b400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3f0c60>, '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 0x10f185cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f1aebe8>, '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.T4DIiBmsdx/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.PtECUnkGMt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/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 14:53:19 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 14:53:19 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 14:53:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T4DIiBmsdx/Render', 14:53:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:53:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:53:19 INFO - 'HOME': '/Users/cltbld', 14:53:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:53:19 INFO - 'LOGNAME': 'cltbld', 14:53:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:53:19 INFO - 'MOZ_NO_REMOTE': '1', 14:53:19 INFO - 'NO_EM_RESTART': '1', 14:53:19 INFO - 'PAGER': '/bin/cat', 14:53:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:53:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:53:19 INFO - 'PWD': '/builds/slave/test', 14:53:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:53:19 INFO - 'SHELL': '/bin/bash', 14:53:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PtECUnkGMt/Listeners', 14:53:19 INFO - 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/', 14:53:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:53:19 INFO - 'USER': 'cltbld', 14:53:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:53:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:53:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:53:19 INFO - 'XPC_FLAGS': '0x0', 14:53:19 INFO - 'XPC_SERVICE_NAME': '0', 14:53:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:53:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:53:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:53:19 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-y4p1Za-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:53:19 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)) 14:53:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:53:19 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-cFrSiu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:53:19 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)) 14:53:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:53:19 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-WDv686-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:53:19 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)) 14:53:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:53:19 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-j2Hgkr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:53:19 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)) 14:53:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:53:19 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-vEYOxX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:53:20 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)) 14:53:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:53:20 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-iFImE4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:53:20 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)) 14:53:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:53:20 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-m8A2S9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:53:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 14:53:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:53:20 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-8WpfmY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:53:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 14:53:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:53:20 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-eoyR85-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:53:20 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)) 14:53:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:53:20 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-t6jHSQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:53:20 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)) 14:53:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:53:20 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-2LZsvC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:53:20 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)) 14:53:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:53:20 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-6SolIX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:53:20 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)) 14:53:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:53:20 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-7oIy7W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:53:20 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)) 14:53:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:53:20 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-z7S7RW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:53:21 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)) 14:53:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:53:21 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-kFZ2Ib-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:53:21 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)) 14:53:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:53:21 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-dJLhfJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:53:21 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)) 14:53:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:53:21 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-yWYkdx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:53:21 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)) 14:53:21 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)) 14:53:21 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)) 14:53:21 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)) 14:53:21 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)) 14:53:21 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)) 14:53:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:53:21 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 14:53:21 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 14:53:21 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 14:53:21 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 14:53:22 INFO - Downloading blessings-1.6.tar.gz 14:53:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:53:22 INFO - Installing collected packages: blessings 14:53:22 INFO - Running setup.py install for blessings 14:53:22 INFO - Successfully installed blessings 14:53:22 INFO - Cleaning up... 14:53:23 INFO - Return code: 0 14:53:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:53:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:53:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:53:23 INFO - Reading from file tmpfile_stdout 14:53:23 INFO - Current package versions: 14:53:23 INFO - blessings == 1.6 14:53:23 INFO - blobuploader == 1.2.4 14:53:23 INFO - docopt == 0.6.1 14:53:23 INFO - functools32 == 3.2.3-2 14:53:23 INFO - jsonschema == 2.5.1 14:53:23 INFO - manifestparser == 1.1 14:53:23 INFO - mozInstall == 1.12 14:53:23 INFO - mozcrash == 1.0 14:53:23 INFO - mozdebug == 0.1 14:53:23 INFO - mozdevice == 0.51 14:53:23 INFO - mozfile == 1.2 14:53:23 INFO - mozhttpd == 0.7 14:53:23 INFO - mozinfo == 0.9 14:53:23 INFO - mozleak == 0.1 14:53:23 INFO - mozlog == 3.4 14:53:23 INFO - moznetwork == 0.27 14:53:23 INFO - mozprocess == 0.25 14:53:23 INFO - mozprofile == 0.28 14:53:23 INFO - mozrunner == 6.13 14:53:23 INFO - mozscreenshot == 0.1 14:53:23 INFO - mozsystemmonitor == 0.3 14:53:23 INFO - moztest == 0.8 14:53:23 INFO - mozversion == 1.4 14:53:23 INFO - psutil == 3.1.1 14:53:23 INFO - requests == 1.2.3 14:53:23 INFO - wsgiref == 0.1.2 14:53:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:53: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')]} 14:53:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:53: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')]} 14:53:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:53:23 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 0x10f467c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f46b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91b1d8b400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3f0c60>, '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 0x10f185cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f1aebe8>, '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.T4DIiBmsdx/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.PtECUnkGMt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/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 14:53:23 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 14:53:23 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 14:53:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T4DIiBmsdx/Render', 14:53:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:53:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:53:23 INFO - 'HOME': '/Users/cltbld', 14:53:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:53:23 INFO - 'LOGNAME': 'cltbld', 14:53:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:53:23 INFO - 'MOZ_NO_REMOTE': '1', 14:53:23 INFO - 'NO_EM_RESTART': '1', 14:53:23 INFO - 'PAGER': '/bin/cat', 14:53:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:53:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:53:23 INFO - 'PWD': '/builds/slave/test', 14:53:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:53:23 INFO - 'SHELL': '/bin/bash', 14:53:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PtECUnkGMt/Listeners', 14:53:23 INFO - 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/', 14:53:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:53:23 INFO - 'USER': 'cltbld', 14:53:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:53:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:53:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:53:23 INFO - 'XPC_FLAGS': '0x0', 14:53:23 INFO - 'XPC_SERVICE_NAME': '0', 14:53:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:53:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:53:23 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)) 14:53:23 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)) 14:53:23 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)) 14:53:23 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)) 14:53:23 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)) 14:53:23 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)) 14:53:23 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)) 14:53:23 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)) 14:53:23 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)) 14:53:23 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 14:53:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:53:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:53:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:53:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:53:25 INFO - Downloading marionette_driver-1.4.0.tar.gz 14:53:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 14:53:25 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)) 14:53:25 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)) 14:53:25 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)) 14:53:25 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)) 14:53:25 INFO - Installing collected packages: marionette-driver 14:53:25 INFO - Running setup.py install for marionette-driver 14:53:25 INFO - Successfully installed marionette-driver 14:53:25 INFO - Cleaning up... 14:53:25 INFO - Return code: 0 14:53:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:53:25 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')]} 14:53:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:53:25 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')]} 14:53:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:53:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10f467c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f46b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91b1d8b400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3f0c60>, '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 0x10f185cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f1aebe8>, '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.T4DIiBmsdx/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.PtECUnkGMt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/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 14:53:25 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 14:53:25 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 14:53:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T4DIiBmsdx/Render', 14:53:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:53:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:53:25 INFO - 'HOME': '/Users/cltbld', 14:53:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:53:25 INFO - 'LOGNAME': 'cltbld', 14:53:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:53:25 INFO - 'MOZ_NO_REMOTE': '1', 14:53:25 INFO - 'NO_EM_RESTART': '1', 14:53:25 INFO - 'PAGER': '/bin/cat', 14:53:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:53:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:53:25 INFO - 'PWD': '/builds/slave/test', 14:53:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:53:25 INFO - 'SHELL': '/bin/bash', 14:53:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PtECUnkGMt/Listeners', 14:53:25 INFO - 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/', 14:53:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:53:25 INFO - 'USER': 'cltbld', 14:53:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:53:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:53:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:53:25 INFO - 'XPC_FLAGS': '0x0', 14:53:25 INFO - 'XPC_SERVICE_NAME': '0', 14:53:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:53:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:53:25 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:53:25 INFO - Cleaning up... 14:53:25 INFO - Return code: 0 14:53:25 INFO - Running post-action listener: _resource_record_post_action 14:53:25 INFO - Running post-action listener: _start_resource_monitoring 14:53:25 INFO - Starting resource monitoring. 14:53:25 INFO - [mozharness: 2017-05-30 21:53:25.642679Z] Finished create-virtualenv step (success) 14:53:25 INFO - [mozharness: 2017-05-30 21:53:25.643622Z] Running install step. 14:53:25 INFO - Running pre-action listener: _resource_record_pre_action 14:53:25 INFO - Running main action method: install 14:53:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:53:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:53:25 INFO - Reading from file tmpfile_stdout 14:53:25 INFO - Detecting whether we're running mozinstall >=1.0... 14:53:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:53:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:53:26 INFO - Reading from file tmpfile_stdout 14:53:26 INFO - Output received: 14:53:26 INFO - Usage: mozinstall [options] installer 14:53:26 INFO - Options: 14:53:26 INFO - -h, --help show this help message and exit 14:53:26 INFO - -d DEST, --destination=DEST 14:53:26 INFO - Directory to install application into. [default: 14:53:26 INFO - "/builds/slave/test"] 14:53:26 INFO - --app=APP Application being installed. [default: firefox] 14:53:26 INFO - mkdir: /builds/slave/test/build/application 14:53:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 14:53:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 14:53:47 INFO - Reading from file tmpfile_stdout 14:53:47 INFO - Output received: 14:53:47 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:53:47 INFO - Running post-action listener: _resource_record_post_action 14:53:47 INFO - [mozharness: 2017-05-30 21:53:47.604699Z] Finished install step (success) 14:53:47 INFO - [mozharness: 2017-05-30 21:53:47.604823Z] Running setup-mitmproxy step. 14:53:47 INFO - Running pre-action listener: _resource_record_pre_action 14:53:47 INFO - Running main action method: setup_mitmproxy 14:53:47 INFO - Skipping: mitmproxy is not required 14:53:47 INFO - Running post-action listener: _resource_record_post_action 14:53:47 INFO - [mozharness: 2017-05-30 21:53:47.605555Z] Finished setup-mitmproxy step (success) 14:53:47 INFO - [mozharness: 2017-05-30 21:53:47.605695Z] Running run-tests step. 14:53:47 INFO - Running pre-action listener: _resource_record_pre_action 14:53:47 INFO - Running main action method: run_tests 14:53:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:53:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:53:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:53:47 INFO - 2017-05-30 14:53:47.620 screenresolution[1625:7666] starting screenresolution argv=screenresolution get 14:53:47 INFO - 2017-05-30 14:53:47.622 screenresolution[1625:7666] Display 0: 1600x1200x32@60 14:53:47 INFO - 2017-05-30 14:53:47.628 screenresolution[1626:7669] starting screenresolution argv=screenresolution list 14:53:47 INFO - Available Modes on Display 0 14:53:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:53:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:53:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:53:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:53:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:53:47 INFO - Intel Iris: 14:53:47 INFO - Chipset Model: Intel Iris 14:53:47 INFO - Type: GPU 14:53:47 INFO - Bus: Built-In 14:53:47 INFO - VRAM (Dynamic, Max): 1536 MB 14:53:47 INFO - Vendor: Intel (0x8086) 14:53:47 INFO - Device ID: 0x0a2e 14:53:47 INFO - Revision ID: 0x0009 14:53:47 INFO - Displays: 14:53:47 INFO - Display: 14:53:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:53:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:53:47 INFO - Display Serial Number: 1600x1200 60 14:53:47 INFO - Main Display: Yes 14:53:47 INFO - Mirror: Off 14:53:47 INFO - Online: Yes 14:53:47 INFO - Rotation: Supported 14:53:47 INFO - Return code: 0 14:53:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:53:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:53:47 INFO - Python 2.7.3 14:53:47 INFO - Return code: 0 14:53:47 INFO - grabbing minidump binary from tooltool 14:53:47 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')]} 14:53:47 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 0x10f3f0c60>, '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 0x10f185cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f1aebe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:53:47 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 14:53:47 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 14:53:47 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 14:53:47 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:53:47 INFO - Return code: 0 14:53:47 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:53:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:53:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:53:47 INFO - ENV: RUST_BACKTRACE is now 1 14:53:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:53:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:53:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PhApj6BRQlWkJeJyoDtXkg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0255', '--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 14:53:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PhApj6BRQlWkJeJyoDtXkg/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0255 --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 14:53:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T4DIiBmsdx/Render', 14:53:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:53:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:53:47 INFO - 'HOME': '/Users/cltbld', 14:53:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:53:47 INFO - 'LOGNAME': 'cltbld', 14:53:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:53:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:53:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:53:47 INFO - 'MOZ_NO_REMOTE': '1', 14:53:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:53:47 INFO - 'NO_EM_RESTART': '1', 14:53:47 INFO - 'PAGER': '/bin/cat', 14:53:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:53:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:53:47 INFO - 'PWD': '/builds/slave/test', 14:53:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:53:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:53:47 INFO - 'RUST_BACKTRACE': '1', 14:53:47 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 14:53:47 INFO - 'SHELL': '/bin/bash', 14:53:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PtECUnkGMt/Listeners', 14:53:47 INFO - 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/', 14:53:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:53:47 INFO - 'USER': 'cltbld', 14:53:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:53:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:53:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:53:47 INFO - 'XPC_FLAGS': '0x0', 14:53:47 INFO - 'XPC_SERVICE_NAME': '0', 14:53:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:53:47 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PhApj6BRQlWkJeJyoDtXkg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0255', '--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 14:53:48 INFO - mozversion application_buildid: 20170530131206 14:53:48 INFO - mozversion application_changeset: fbe0e3f31233cc7846a2168c613452a1d7147cc2 14:53:48 INFO - mozversion application_display_name: Nightly 14:53:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:53:48 INFO - mozversion application_name: Firefox 14:53:48 INFO - mozversion application_remotingname: firefox 14:53:48 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:53:48 INFO - mozversion application_vendor: Mozilla 14:53:48 INFO - mozversion application_version: 55.0a1 14:53:48 INFO - mozversion platform_buildid: 20170530131206 14:53:48 INFO - mozversion platform_changeset: fbe0e3f31233cc7846a2168c613452a1d7147cc2 14:53:48 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:53:48 INFO - mozversion platform_version: 55.0a1 14:53:48 INFO - using testdate: 1496181228 14:53:48 INFO - actual date: 1496181228 14:53:48 INFO - starting webserver on 'localhost:49228' 14:53:48 INFO - SUITE-START | Running 2 tests 14:53:48 INFO - TEST-START | dromaeo_css 14:53:48 INFO - operating with platform_type : mac_ 14:53:48 INFO - Initialising browser for dromaeo_css test... 14:53:48 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/tmp22bMsL/profile 14:53:48 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 14:54:22 INFO - TEST-INFO | 1636: killed by SIGTERM 14:54:22 INFO - Browser initialized. 14:54:22 INFO - Running cycle 1/1 for dromaeo_css test... 14:54:22 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/tmp22bMsL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:54:24 INFO - PID 1646 | 2017-05-30 14:54:24.519 plugin-container[1647:8384] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9743, name = 'com.apple.tsm.portname' 14:54:24 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:54:24 INFO - PID 1646 | 2017-05-30 14:54:24.519 plugin-container[1647:8384] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x990f, name = 'com.apple.CFPasteboardClient' 14:54:24 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:54:24 INFO - PID 1646 | 2017-05-30 14:54:24.519 plugin-container[1647:8384] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:54:25 INFO - PID 1646 | 2017-05-30 14:54:25.072 plugin-container[1648:8427] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464f, name = 'com.apple.tsm.portname' 14:54:25 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:54:25 INFO - PID 1646 | 2017-05-30 14:54:25.072 plugin-container[1648:8427] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 14:54:25 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:54:25 INFO - PID 1646 | 2017-05-30 14:54:25.072 plugin-container[1648:8427] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:54:34 INFO - PID 1646 | RSS: Main: 261775360 14:54:34 INFO - PID 1646 | 14:57:37 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49228/tests/dromaeo/cssquery-ext.html) 14:57:38 INFO - PID 1646 | RSS: Main: 268873728 14:57:38 INFO - PID 1646 | 15:00:40 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-ext.html (next: http://localhost:49228/tests/dromaeo/cssquery-jquery.html) 15:00:40 INFO - PID 1646 | RSS: Main: 283578368 15:00:40 INFO - PID 1646 | 15:03:42 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49228/tests/dromaeo/cssquery-mootools.html) 15:03:43 INFO - PID 1646 | RSS: Main: 282537984 15:03:43 INFO - PID 1646 | 15:06:44 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49228/tests/dromaeo/cssquery-prototype.html) 15:06:45 INFO - PID 1646 | RSS: Main: 286150656 15:06:45 INFO - PID 1646 | 15:09:48 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49228/tests/dromaeo/cssquery-yui.html) 15:09:49 INFO - PID 1646 | RSS: Main: 286416896 15:09:49 INFO - PID 1646 | 15:12:50 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-yui.html (next: http://localhost:49228/tests/dromaeo/cssquery-dojo.html) 15:12:51 INFO - PID 1646 | RSS: Main: 286277632 15:12:51 INFO - PID 1646 | 15:12:51 INFO - PID 1646 | __start_tp_report 15:12:51 INFO - PID 1646 | _x_x_mozilla_page_load 15:12:51 INFO - PID 1646 | _x_x_mozilla_page_load_details 15:12:51 INFO - PID 1646 | |i|pagename|runs| 15:12:51 INFO - PID 1646 | |0;dojo.html;3093.613298337708;3568.3041806847673;3593.9820300898523;3609.657082577156;3626.5104210931518;4862.2706594010915;6063.272407311118;6104.328523862371;6138.324784273726;6166.475849552788;6131.386861313867;6131.9884064911685;6148.708771158051;6174.660393678346;6204.379562043793;5261.368635763713;5300.893982120365;5348.491893270133;5389.218563308318;5495.780168793263;5511.255980442629;5517.282753242093;5529.751161197756;5531.142672885689;5570.164475328693;5327.493888080626;5415.702136382497;5418;5468.64453810505;5490.094134467828;5442.564594832404;5451;5469.917951230735;5473.397926228152;5478.890422191574;5390.460953904618;5400.189971504266;5422.942526207391;5442.510174084351;5459.153831156177;6068.969655151696;6089.238845144357;6147.10866924298;6235.532335074888;6246.063090536456;6480.805575832733;6548.148740663731;6636.800895973129;6659.4006539411685;6666.233383160901;6766.661666916683;6784.626845523494;6790.626515541643;6799.490038247101;6807.761728339534;6303.779367722108;6306.243250809886;6310.4636105930595;6312.747490100344;6319.52603554726;6631.900521492182;6726.899096513556;6768.627725475048;6864.622445765431;6878.896816547755;6056.879477296714;6193.876122477525;6302.401271879165;6395.712192951329;6415.973444248898;6609.801705948733;6723.764668236686;6732.932670673328;6744.393004629607;6750.426213771786;5406.405295417501;6600.8349791255605;6623.9006414903815;6636.900446493307;6640.734370625121;6402.647854367961;6484.870302593922;6525.706343214567;6615.437687796495;6655.966720166368;4374.753195339313;5152.510511501401;5346.518813306743;5353.598480113929;5391.622586418913;4716.575508204278;4730.290456431494;4785.353977213032;4791.544803243686;4798.352222449925;6153.1693221415435;6172;6232.127502149703;6234.064890266496;6256.061590761422;6673.165854268216;6736.124303840469;6760.560563563353;6778.559393639398;6789.490788190906;6249.500039996789;6252.468540174045;6257.937420625826;6281.403266689657;6285.120083188357;5792.594518383757;5960.105984102419;5973.820785376446;5976.73104710289;5986.042233242661;5664.631798933056;6726.990951357336;6771.559848609824;6772.390484856288;6879.931200687929;5870.236869206976;6503.642299673564;6738;6778.966105169443;6788.626625535643;6734.764283250062;6888.827779305558;6906.37842594169;6917.342852429011;6920.896186557105;6402.455791257796;6505.837354066186;6536.215654121535;6607.3062328456745;6674.332566743286;6727.529072964846;6754.493412993947;6755.93244067553;6759.493038022069;6788.287229840796;6268.373162683695;6440.29156792743;6535.052417399545;6549.70526326326;6549.934500655123;6435.420812126931;6477.740890364521;6494.772682956074;6502.122213501306;6512.023196520559;6244.2819075806865;6300.432961033529;6322.905156422566;6383;6385.648789316632;5442.891142177214;6453.999630057348;6457.741690332334;6467.450266727381;6515.924872396002;6375.649339286384;6375.968120159369;6399.200099987502;6403.0715546246465;6411.358864113551 15:12:51 INFO - PID 1646 | |1;ext.html;785.3246208260896;800.6077022259091;848.7368915636154;1196.5094311332355;1244.7074937389707;36990.890273291814;38787.80606096969;38801.80599097;38861;38913.805430972876;38962;39026.21947561047;39243.60756392435;39249;39332.60667393325;6572.474202063835;7898.60506974649;8205.671773129068;8422.199891010345;8427.620757065915;8249.505029698208;8356.871822303972;8361.331093512505;8364.121767214447;8398.286145677625;8316.002079750438;8382.329413646894;8387.95806020969;8389.496630202208;8406;8255.752327430188;8334.458260213098;8369.204925532065;8380.20388063133;8385.664573417087;8120.3503719702285;8190.959045204795;8198.754037378829;8206;8207.425480216387;36710.63289367099;36830.63169368299;36928.261434771426;37034.07414814624;37098.62901370979;3567.7680950738118;3598.5321908152036;3603.4054381027363;3605.0086226287717;3606.3328284267495;30304.84847575748;30475;30487.695123048707;30683.386332273454;30727.231819204477;2829.670055074112;2873.7700983921236;3093.381323735239;3097.1947293703797;3109;249746.50253497413;253205.46794532004;288036.9999999979;308202;308582;47215.819604510165;47546.762266188445;47563.81090472766;47617.761911190224;47680.7615961918;42852.78573607112;50129.74935125301;50235.74882125639;53096;55172.448275517534;14505;14674.339654715563;14674.63313417173;14687.26563671812;14731;11690.88309116915;11785;11816.995555377718;11835.940820295844;11857.5849845255;11255.718607034725;11356.886431135748;13840.723185536233;14013.859861401459;14026.228557429235;5148.382194136652;6120.112583675431;6129.478994285447;6162.322144564095;6177.413145751147;996.6910257820099;1007.6019972111121;1012.8581998520214;1036.4921188617523;1036.6786296248188;23206.65190022151;24206.51586968286;24249.151279705125;24319.112560136928;24628.50742985166;1021.3157184686278;1089.199438412766;1102.8235482322789;1107.5791199344199;1134.183387960667;10621.415822129857;11641.6507504775;11796.70508237301;12376.81434778479;12423.689407764878;280287;329103;333084;335222;335377;42284.15431691349;53651.46348536465;53668.463315366345;53844.65388365244;53921;1448.7681970884607;1495.820501539822;1524.367387534183;1525.5804653720315;1537.1007960343325;23949.28152155438;23990.04039838387;24007.879960600087;24011.159609413586;24021.51956960886;38292.61707383002;38296.808515957244;38406.039849003995;38421;38494.42258366071;276383.6180819083;277332;277934;278802;278972;29100.854495728232;34701.13247168841;35690.64309356977;35903.820480897426;36056.45915311325;43145;45758.54241457543;45886.77056614696;45896;45998.08003839971;28715.856420717762;30115.849420752755;30808.075757727303;31458.52812207773;31756.682433175374;30947.381052378376;31146.37707245797;31720.682793171774;31758.68241317557;31827.6817231834;36155.734549290646;36253.45619815652;37240.6275937248;37635.43546846745;37783.62216377801;31519.527207091454;31693;31736.206594835316;32212.83893580517;32637;23104.306870793902;24536.141235056686;24644.394627730646;24797;25006.749932500443 15:12:51 INFO - PID 1646 | |2;jquery.html;422.55761697389084;425.4532925191126;433.7635988386329;438.37750394439894;450.257075824889;22477.438064048387;23239.954202060886;23296.65055024174;23351;24126.276211713634;25638;26278.948842046295;26306.47387052258;26307.736922630767;26385.472290554226;6791.626460544668;6881.587104773705;6924.723011079551;6930.237673855897;6937.965310173442;6557.7376904923985;6915.239323674392;6941.583504989691;6966.02475653409;7008.579485230903;6922.7923162305215;6939.44484441246;6959.8956015659805;6965.199002114746;6999.9850021746815;6946.79159625212;6956.721731130749;6960.9999999999745;6963.1644202696;6969.337912898267;6758.391744742997;6822.761203357908;6826.112605361321;6833.658317084126;6841.144856892888;20950.685739713765;30306.181629102102;31130.75476980918;31421.525892345766;31467.842660786777;1970.3891793543903;2205.37146913129;2226.2096955580696;2247.0786977339376;2251.0770584060456;19993.600127997503;20490.897545512325;20498;20524.79475205244;20556.691649625267;2273.34073118797;2276.3967548599635;2337.0651739304244;2354.3643216331666;2361.1617875654238;168668.99999999878;173992.13003935025;181237;205583;207640.96179519157;22086.558268834535;25786.35534111662;26387;27023.189304320902;27341.863290683817;44196.779016105356;44661.10677786426;44733;44737.32894006593;44747;11103.611373601885;11160.16298777595;11298.7175320616;11337.093032557375;11383.317000979967;8991.640334386682;9017.954910225408;9049.728508144766;9102.954485227532;9114.361994660443;10254.58981640741;12334;12511.93744031274;12576.434060467302;12650.683732906567;4185.330347144442;4865.829695960624;4995.575376093;5000.374953130859;5040.773165207501;798.892149559802;906;920.8039173856291;924.778053267211;966.8501382285757;20897.05963231658;21123.78876211218;21395.572088558452;21590.352289431343;21594.892025540084;919.6735159018585;941.7174847545709;971.4171497101682;995.581855620641;1008.1380419741163;8107.189281071964;9030.148819491988;9587.424754514752;10915.56337746497;11214.66356009321;448404;448733.5126648757;516207;526431.3678431583;534803;112515;139045.30477347548;141680;142021.28989354987;144658;1124.5445594534062;1133.7562424078862;1205.3792296967245;1208.178438661701;1212.4180393411211;18539.72190417118;20192.192312307932;20753.962301884545;22968.081276748744;23657.645135323328;67901.66049169921;68240;68647;69701.30298697151;71172.28827711656;90557.64163537684;90566.54716726374;90743.0925690761;90948;91147.08852911385;10337;10395.792084158425;10529.947350263506;10533.42066186367;10546.947265263625;18374.448766537025;18581.2567497304;18765.437036888914;18786.6242675143;18839.717404238672;15212;15313.310901009258;15412.845871541589;15512.22438878029;15522.611934701723;12809.61571152867;12823;12877.871221287667;13530.391132399263;13968.30158492051;15147.848521514643;15358.923205383902;15405.537833865003;15523;15673.294701738221;8831.955840220759;10220.948895255477;10267.332623379456;10368.844467333147;10398.012188842047;12404.75190496167;14900.552983410515;15150.848491514944;15152.393904243707;15266.465673701367 15:12:51 INFO - PID 1646 | |3;mootools.html;1252.1985929005434;1462.4077248714275;1468.4640106361178;1468.764997600384;1474.2702362330642;11179.776404471915;11464;11519.884801151997;11560.306381617098;12232;11723.355215463167;11803.232789868653;11910.40447977602;11925.64223073307;11958.282503049822;2534.41708407066;2578.7421257874157;2580.2001379572393;2580.329114430252;2587.637730717701;2499.7500249974946;2534.4677617700213;2534.8605826679527;2538.0989748639245;2541.313845261778;2489.7759201671847;2510.7991360691194;2514.308565144591;2518.6347979542957;2521.8739063046864;2099.8425118116124;2374.41826752445;2466.4203912080648;2502.336880726609;2502.4619706763133;2408.12103582193;2464.5810212263777;2474.2206205045354;2519.067944860395;2520.483300923306;11567.305961642329;11653.766924661459;11655.358955257458;11658.766824663544;11659.88340116605;6402.551821372505;6544.869102617921;6613.305602911673;6619.669016549202;6645.734170633169;3468.5490886184634;4110.157417729388;4160.646345060673;4180.979095104505;4266.445362102938;1200.574058494583;1220.5422966387605;1238.8699186585368;1245.6138597034949;1247.1768632702374;13964.812990895785;15286.847131528653;15648.843511564852;15751.212439377985;16279;4893.412790465167;5406.945930540644;5435.429279925551;5615.466530679579;5642.351129620064;11752.529898804123;11775.646730598095;11795.41022948849;11807.17349785524;11816.88183118175;6387.425131738166;6400.807975760735;6494.285628580947;6505.284418713938;6526.412622863965;6003.219581454383;6105.48103411215;6149.13912052313;6160.353162917919;6166.475849552839;4382.211201983673;4419.270820314677;4476.216662084123;4479.417675702203;4516.683832131785;1239.4918083585687;1271.3261971155302;1278.8721127887322;1289.935503224835;1291.6706239908951;758.852023855343;762.089303282572;784.1883650421786;791.5369508837395;794.9682012719542;6160.722767475474;6838.863222735517;6871.312868713088;6927.792166235021;7083.89374159388;933.6312156698202;935.0229010683852;937.105064663245;944.8063147054837;966.367029595616;3860.189360234326;3895.824687889052;3920.725549211585;3962.6829853611644;3986.242613903349;14436.855631443552;15028.098314100971;15138.470153544573;15155.393784248507;15165.772513412308;14897.553073407815;15075.54773356801;15078.698426031418;15105.3202605883;15106;8461.661533538712;8475.364347673949;8548.974123105143;8577.656893724306;8705.782355441039;6016.338202797601;6800.897986530107;7066.505344625827;7120.896261079541;7151.42788576923;12477.188982716094;12489.313087779896;12666.430010649727;12710.936445317715;12802.871971280541;12548.749025019266;12719.55481558141;12799.42402591867;12849.614511564669;12879.549215777402;8451.323894088335;8564.785880353042;8601.526916019679;8601.569921504026;8603.182697643713;10591.89408105909;10719.839202412126;10924.50839712199;11023.228374013743;11039.116870650168;8944.239739622206;9229.630814767334;9303.953480232556;9314.649375840601;9400.200982916529;8949.865752013855;9010.234130099016;9018.954905225432;9076.228520575562;9176.587053582738;8257.710980115668;8354.206350396702;8356.456830306011;8359.122292159236;8372.623231954698;6056.152138700498;6058.57589968698;6168.414000669929;6193.783217587363;6251.281102673069;5823.417658234142;5882.470577647861;5997.250343707036;6098.268207814913;6175.135481032748 15:12:51 INFO - PID 1646 | |4;prototype.html;381.73660174479613;394.6448196623041;398.73085667174666;399.2055808940208;411.8970257435641;5182;5627.099664053752;5746.827595172152;6314.7789827356055;6629.86740265195;6313.62118272904;6442.581232219903;6444.774432894849;6469.611823290606;6488.675566221694;4562.6121779648765;4682.016776476952;4695.225287827504;4714.882127946812;4723.048094940662;4653.27874179501;4692.812287508513;4712.293156026589;4714.98627795024;4725.598324142452;4624.445066592014;4634.791434385444;4639;4639.396878405802;4671.696339737906;4044.2720310344275;4637.884052898671;4644.164050470914;4661.347411362412;4672.602828759525;3137;3981.9601803981877;3984.027906186456;3995.860144894944;4156.937645935313;5729.4843464088435;6324.367563243685;6404.903926441107;6407.391297826699;6441.742330306782;5180.611454140954;5570.470805273492;5575.442455754433;5580;5605.411431799643;5463.972680136574;6975.267596902303;6975.616341101238;6979.685914133876;7002.334778196063;3778.6032466591164;4529.6376289896725;4542.182407166709;4544.250198717207;4545.886352841172;6979.336962988508;7016;7237.240089790602;7366.963165184194;7458.627068646546;3421.1789170598877;4012.53855806586;4132.822145688464;4295.355696645528;4412;4670.416197975253;4675.883102922386;4677.695949763254;4690.413698287714;4706.576408123286;5311.725185955343;5314.5216930476445;5546.001719690414;5555.416681248492;5601.915971260434;5675.631083979611;6279.613746776287;6825.249222585511;6900.03399524067;6920.4809639277255;1571.316477332364;1579.4156162220052;1579.921003949798;1584.7226735321274;1591.1805420208602;994.80103979203;1026.4046852825343;1026.8511065895552;1039.5685790397051;1039.8977084290675;5184.045094296215;5660.971695141498;6254.499640028786;6451.903221451682;6543.44380727634;6372.968135159294;6815.693293801791;7201.819954501075;7202.675879585431;7273.709051637981;6453.806385808433;6466.6766661666725;6471.935280647227;6472.482201423874;6477.5465717400275;3505.614382417932;3759.586445490994;3769.453429252743;3773.9997801165428;3775.3765880671026;6053.576249662569;6361.141245931833;7325.084364454443;7338.302861228175;7355.166747413987;6362.9045564316575;6559.836004099936;7031.866737281813;7242.529235599669;7307.926920730724;6159.907601386072;6206.751729930843;7368.963155184189;7380.571657005754;7386.630668466555;6196.907046394217;6979.569188316282;7034.683439245144;7155.677994490156;7185.353318201387;5701.8859622806485;6144.78493252734;6803.80933336674;7055.153381594241;7083.539569927938;6639.402453779183;6818.522703410714;7162.385729208905;7284.125904891235;7384.778456646309;4410.544520308355;4544.522825103317;4572.35986961819;4594.264917613166;4599.735072854992;6137.662428566293;6593.274739778524;6986.825329366807;7271.2365201653065;7286.380657644159;5663.915041274302;5761.798337058183;6362.427381535684;6903.824240849265;6966.9651651741415;5910.438508341701;6193.628382297076;6844.041834143323;6999.720011199699;7016.19313778922;5639.464250896158;6841.213260475109;6891.345322194384;6894.172699276119;6962.651867406711;7008.719651213894;7013.193482749549;7014;7016.36852683261;7044.506884518035;5355;5446.373667028184;6822;6829.180498340231;6883.552569083194 15:12:51 INFO - PID 1646 | |5;yui.html;719.5610677486732;804.4730701390591;850.2942557677127;863.8531449653558;869.8869147010892;15021.92489037556;15538.84461155388;16999.745003824937;17444.91277543612;17506.12469376531;16215.432459863907;17142.914285428586;18021.63956720868;18060;18220.18009189586;973.4110862927951;1020.1328870460087;1036.8289232276666;1037.9221558383115;1039.002557544758;1007.7631756537207;1015.3248090020164;1015.6800607808553;1017.7760892603652;1021.8824835143942;951.0679534056628;1062.617457715222;1066.653337665258;1070.684148176286;1078.8543546621183;999.280518027023;1056.6565866093551;1057.228223396917;1061.2995423020807;1067.5409573883228;768.5158350239369;768.5350363030398;770.1759117744016;772.7905827380132;775.7595145504912;17453.04008279544;17913.7312940306;17972.921624702558;18042.00768957707;18063.09684515782;17597.648047038987;17784.3775467858;17909.641807163785;18147.27410903562;18151.36470223549;14934.626634334229;15038.473653422077;15151.545453636298;15169.24153792317;15196.240187990556;3576.213233088716;3579.874704385334;3616.9276614467826;3695;3729.8321575528894;139360.30319848337;143027;143697.2815135928;143716.56283437138;145222;11286.435678216056;13389;13589.796153057712;13643.863561364458;13644.590662280147;25304.493910121695;26537.86731066371;27224.31939201496;27240.04659836896;27253.86373068122;6804.6257455840405;7048.894266586005;7569.962150189324;7673.53958762476;7718.53688778675;3343.3981883261245;3937.8818635440985;3964.1675248197625;4023.8994025149605;4027.1744292419985;4214.157168566299;4260.275753121977;4285.228658841438;4303.698741088157;4314.115606300701;842.4103127810557;913.4519288426891;914.2868562521244;945.60284680433;953.1993125774383;583.9984426708215;638.6455517188006;652.2824892618082;667.1094089390717;684.5413572906066;11501.194916355942;12350.07374446902;12478.064145189148;12516.31160286193;12519.248845069325;573.7131434282859;644.3781750610683;644.9451796597248;664.833791552112;669.5681285570877;5788.710564471759;5910.468057874812;5917.585768996215;5926.229590153339;6044.788432404845;146045;146991;147284.26357868357;147414;148481;84602.5769871159;116890.41554792342;118863;119175;121442.39278803549;91512.96678682737;91955;104589;105873;106738.3989240162;9752.920058277014;10994.450277486254;12178.939105304771;13136.408861601441;13170.209787412787;44954.77522612366;45396;45855;46116.53883461122;46290;60742.78514429598;61170.694146528986;61172.69413652903;62008;62099.6895015522;16580.58548536296;16829;17098.487045388658;17214.225359858585;17237.482875513753;15848;18608.883466992025;18801.811981880008;19159.137838797567;19234.615307694046;14534.927325363305;17519.562010949827;18093.81906180974;18151.45545633633;18173;16908.2700798729;16952;17250.65498690044;17553.12234388301;17675.558111046812;17427.39004134849;17492.125393730515;17593.208305626533;17625;17778;9890.752731181778;10544.57821687124;11616.593419230287;11940.044796416092;12295.297224713577;12196.57311994076;13179.538716144889;13385.063045586716;13550.864491355354;13592.45630174782 15:12:51 INFO - PID 1646 | __end_tp_report 15:12:51 INFO - PID 1646 | __start_cc_report 15:12:51 INFO - PID 1646 | _x_x_mozilla_cycle_collect,460 15:12:51 INFO - PID 1646 | __end_cc_report 15:12:51 INFO - PID 1646 | __startTimestamp1496182371206__endTimestamp 15:12:51 INFO - PID 1646 | 15:12:51 INFO - PID 1646 | ------- Summary: start ------- 15:12:51 INFO - PID 1646 | Number of tests: 6 15:12:51 INFO - PID 1646 | 15:12:51 INFO - PID 1646 | [#0] dojo.html Cycles:165 Average:6106.77 Median:6310.46 stddev:730.39 (11.6%) stddev-sans-first:693.33 15:12:51 INFO - PID 1646 | Values: 3093.6 3568.3 3594.0 3609.7 3626.5 4862.3 6063.3 6104.3 6138.3 6166.5 6131.4 6132.0 6148.7 6174.7 6204.4 5261.4 5300.9 5348.5 5389.2 5495.8 5511.3 5517.3 5529.8 5531.1 5570.2 5327.5 5415.7 5418.0 5468.6 5490.1 5442.6 5451.0 5469.9 5473.4 5478.9 5390.5 5400.2 5422.9 5442.5 5459.2 6069.0 6089.2 6147.1 6235.5 6246.1 6480.8 6548.1 6636.8 6659.4 6666.2 6766.7 6784.6 6790.6 6799.5 6807.8 6303.8 6306.2 6310.5 6312.7 6319.5 6631.9 6726.9 6768.6 6864.6 6878.9 6056.9 6193.9 6302.4 6395.7 6416.0 6609.8 6723.8 6732.9 6744.4 6750.4 5406.4 6600.8 6623.9 6636.9 6640.7 6402.6 6484.9 6525.7 6615.4 6656.0 4374.8 5152.5 5346.5 5353.6 5391.6 4716.6 4730.3 4785.4 4791.5 4798.4 6153.2 6172.0 6232.1 6234.1 6256.1 6673.2 6736.1 6760.6 6778.6 6789.5 6249.5 6252.5 6257.9 6281.4 6285.1 5792.6 5960.1 5973.8 5976.7 5986.0 5664.6 6727.0 6771.6 6772.4 6879.9 5870.2 6503.6 6738.0 6779.0 6788.6 6734.8 6888.8 6906.4 6917.3 6920.9 6402.5 6505.8 6536.2 6607.3 6674.3 6727.5 6754.5 6755.9 6759.5 6788.3 6268.4 6440.3 6535.1 6549.7 6549.9 6435.4 6477.7 6494.8 6502.1 6512.0 6244.3 6300.4 6322.9 6383.0 6385.6 5442.9 6454.0 6457.7 6467.5 6515.9 6375.6 6376.0 6399.2 6403.1 6411.4 15:12:51 INFO - PID 1646 | 15:12:51 INFO - PID 1646 | [#1] ext.html Cycles:180 Average:44650.69 Median:335299.50 stddev:77414.60 (23.1%) stddev-sans-first:77561.71 15:12:51 INFO - PID 1646 | Values: 785.3 800.6 848.7 1196.5 1244.7 36990.9 38787.8 38801.8 38861.0 38913.8 38962.0 39026.2 39243.6 39249.0 39332.6 6572.5 7898.6 8205.7 8422.2 8427.6 8249.5 8356.9 8361.3 8364.1 8398.3 8316.0 8382.3 8388.0 8389.5 8406.0 8255.8 8334.5 8369.2 8380.2 8385.7 8120.4 8191.0 8198.8 8206.0 8207.4 36710.6 36830.6 36928.3 37034.1 37098.6 3567.8 3598.5 3603.4 3605.0 3606.3 30304.8 30475.0 30487.7 30683.4 30727.2 2829.7 2873.8 3093.4 3097.2 3109.0 249746.5 253205.5 288037.0 308202.0 308582.0 47215.8 47546.8 47563.8 47617.8 47680.8 42852.8 50129.7 50235.7 53096.0 55172.4 14505.0 14674.3 14674.6 14687.3 14731.0 11690.9 11785.0 11817.0 11835.9 11857.6 11255.7 11356.9 13840.7 14013.9 14026.2 5148.4 6120.1 6129.5 6162.3 6177.4 996.7 1007.6 1012.9 1036.5 1036.7 23206.7 24206.5 24249.2 24319.1 24628.5 1021.3 1089.2 1102.8 1107.6 1134.2 10621.4 11641.7 11796.7 12376.8 12423.7 280287.0 329103.0 333084.0 335222.0 335377.0 42284.2 53651.5 53668.5 53844.7 53921.0 1448.8 1495.8 1524.4 1525.6 1537.1 23949.3 23990.0 24007.9 24011.2 24021.5 38292.6 38296.8 38406.0 38421.0 38494.4 276383.6 277332.0 277934.0 278802.0 278972.0 29100.9 34701.1 35690.6 35903.8 36056.5 43145.0 45758.5 45886.8 45896.0 45998.1 28715.9 30115.8 30808.1 31458.5 31756.7 30947.4 31146.4 31720.7 31758.7 31827.7 36155.7 36253.5 37240.6 37635.4 37783.6 31519.5 31693.0 31736.2 32212.8 32637.0 23104.3 24536.1 24644.4 24797.0 25006.7 15:12:51 INFO - PID 1646 | 15:12:51 INFO - PID 1646 | [#2] jquery.html Cycles:180 Average:38257.85 Median:2357.76 stddev:86680.26 (3676.4%) stddev-sans-first:86876.88 15:12:51 INFO - PID 1646 | Values: 422.6 425.5 433.8 438.4 450.3 22477.4 23240.0 23296.7 23351.0 24126.3 25638.0 26278.9 26306.5 26307.7 26385.5 6791.6 6881.6 6924.7 6930.2 6938.0 6557.7 6915.2 6941.6 6966.0 7008.6 6922.8 6939.4 6959.9 6965.2 7000.0 6946.8 6956.7 6961.0 6963.2 6969.3 6758.4 6822.8 6826.1 6833.7 6841.1 20950.7 30306.2 31130.8 31421.5 31467.8 1970.4 2205.4 2226.2 2247.1 2251.1 19993.6 20490.9 20498.0 20524.8 20556.7 2273.3 2276.4 2337.1 2354.4 2361.2 168669.0 173992.1 181237.0 205583.0 207641.0 22086.6 25786.4 26387.0 27023.2 27341.9 44196.8 44661.1 44733.0 44737.3 44747.0 11103.6 11160.2 11298.7 11337.1 11383.3 8991.6 9018.0 9049.7 9103.0 9114.4 10254.6 12334.0 12511.9 12576.4 12650.7 4185.3 4865.8 4995.6 5000.4 5040.8 798.9 906.0 920.8 924.8 966.9 20897.1 21123.8 21395.6 21590.4 21594.9 919.7 941.7 971.4 995.6 1008.1 8107.2 9030.1 9587.4 10915.6 11214.7 448404.0 448733.5 516207.0 526431.4 534803.0 112515.0 139045.3 141680.0 142021.3 144658.0 1124.5 1133.8 1205.4 1208.2 1212.4 18539.7 20192.2 20754.0 22968.1 23657.6 67901.7 68240.0 68647.0 69701.3 71172.3 90557.6 90566.5 90743.1 90948.0 91147.1 10337.0 10395.8 10529.9 10533.4 10546.9 18374.4 18581.3 18765.4 18786.6 18839.7 15212.0 15313.3 15412.8 15512.2 15522.6 12809.6 12823.0 12877.9 13530.4 13968.3 15147.8 15358.9 15405.5 15523.0 15673.3 8832.0 10220.9 10267.3 10368.8 10398.0 12404.8 14900.6 15150.8 15152.4 15266.5 15:12:51 INFO - PID 1646 | 15:12:51 INFO - PID 1646 | [#3] mootools.html Cycles:180 Average:7031.67 Median:3664.37 stddev:4388.57 (119.8%) stddev-sans-first:4379.39 15:12:51 INFO - PID 1646 | Values: 1252.2 1462.4 1468.5 1468.8 1474.3 11179.8 11464.0 11519.9 11560.3 12232.0 11723.4 11803.2 11910.4 11925.6 11958.3 2534.4 2578.7 2580.2 2580.3 2587.6 2499.8 2534.5 2534.9 2538.1 2541.3 2489.8 2510.8 2514.3 2518.6 2521.9 2099.8 2374.4 2466.4 2502.3 2502.5 2408.1 2464.6 2474.2 2519.1 2520.5 11567.3 11653.8 11655.4 11658.8 11659.9 6402.6 6544.9 6613.3 6619.7 6645.7 3468.5 4110.2 4160.6 4181.0 4266.4 1200.6 1220.5 1238.9 1245.6 1247.2 13964.8 15286.8 15648.8 15751.2 16279.0 4893.4 5406.9 5435.4 5615.5 5642.4 11752.5 11775.6 11795.4 11807.2 11816.9 6387.4 6400.8 6494.3 6505.3 6526.4 6003.2 6105.5 6149.1 6160.4 6166.5 4382.2 4419.3 4476.2 4479.4 4516.7 1239.5 1271.3 1278.9 1289.9 1291.7 758.9 762.1 784.2 791.5 795.0 6160.7 6838.9 6871.3 6927.8 7083.9 933.6 935.0 937.1 944.8 966.4 3860.2 3895.8 3920.7 3962.7 3986.2 14436.9 15028.1 15138.5 15155.4 15165.8 14897.6 15075.5 15078.7 15105.3 15106.0 8461.7 8475.4 8549.0 8577.7 8705.8 6016.3 6800.9 7066.5 7120.9 7151.4 12477.2 12489.3 12666.4 12710.9 12802.9 12548.7 12719.6 12799.4 12849.6 12879.5 8451.3 8564.8 8601.5 8601.6 8603.2 10591.9 10719.8 10924.5 11023.2 11039.1 8944.2 9229.6 9304.0 9314.6 9400.2 8949.9 9010.2 9019.0 9076.2 9176.6 8257.7 8354.2 8356.5 8359.1 8372.6 6056.2 6058.6 6168.4 6193.8 6251.3 5823.4 5882.5 5997.3 6098.3 6175.1 15:12:51 INFO - PID 1646 | 15:12:51 INFO - PID 1646 | [#4] prototype.html Cycles:180 Average:5476.47 Median:6141.22 stddev:1748.52 (28.5%) stddev-sans-first:1711.10 15:12:51 INFO - PID 1646 | Values: 381.7 394.6 398.7 399.2 411.9 5182.0 5627.1 5746.8 6314.8 6629.9 6313.6 6442.6 6444.8 6469.6 6488.7 4562.6 4682.0 4695.2 4714.9 4723.0 4653.3 4692.8 4712.3 4715.0 4725.6 4624.4 4634.8 4639.0 4639.4 4671.7 4044.3 4637.9 4644.2 4661.3 4672.6 3137.0 3982.0 3984.0 3995.9 4156.9 5729.5 6324.4 6404.9 6407.4 6441.7 5180.6 5570.5 5575.4 5580.0 5605.4 5464.0 6975.3 6975.6 6979.7 7002.3 3778.6 4529.6 4542.2 4544.3 4545.9 6979.3 7016.0 7237.2 7367.0 7458.6 3421.2 4012.5 4132.8 4295.4 4412.0 4670.4 4675.9 4677.7 4690.4 4706.6 5311.7 5314.5 5546.0 5555.4 5601.9 5675.6 6279.6 6825.2 6900.0 6920.5 1571.3 1579.4 1579.9 1584.7 1591.2 994.8 1026.4 1026.9 1039.6 1039.9 5184.0 5661.0 6254.5 6451.9 6543.4 6373.0 6815.7 7201.8 7202.7 7273.7 6453.8 6466.7 6471.9 6472.5 6477.5 3505.6 3759.6 3769.5 3774.0 3775.4 6053.6 6361.1 7325.1 7338.3 7355.2 6362.9 6559.8 7031.9 7242.5 7307.9 6159.9 6206.8 7369.0 7380.6 7386.6 6196.9 6979.6 7034.7 7155.7 7185.4 5701.9 6144.8 6803.8 7055.2 7083.5 6639.4 6818.5 7162.4 7284.1 7384.8 4410.5 4544.5 4572.4 4594.3 4599.7 6137.7 6593.3 6986.8 7271.2 7286.4 5663.9 5761.8 6362.4 6903.8 6967.0 5910.4 6193.6 6844.0 6999.7 7016.2 5639.5 6841.2 6891.3 6894.2 6962.7 7008.7 7013.2 7014.0 7016.4 7044.5 5355.0 5446.4 6822.0 6829.2 6883.6 15:12:51 INFO - PID 1646 | 15:12:51 INFO - PID 1646 | [#5] yui.html Cycles:180 Average:25097.32 Median:17911.69 stddev:38400.12 (214.4%) stddev-sans-first:38464.21 15:12:51 INFO - PID 1646 | Values: 719.6 804.5 850.3 863.9 869.9 15021.9 15538.8 16999.7 17444.9 17506.1 16215.4 17142.9 18021.6 18060.0 18220.2 973.4 1020.1 1036.8 1037.9 1039.0 1007.8 1015.3 1015.7 1017.8 1021.9 951.1 1062.6 1066.7 1070.7 1078.9 999.3 1056.7 1057.2 1061.3 1067.5 768.5 768.5 770.2 772.8 775.8 17453.0 17913.7 17972.9 18042.0 18063.1 17597.6 17784.4 17909.6 18147.3 18151.4 14934.6 15038.5 15151.5 15169.2 15196.2 3576.2 3579.9 3616.9 3695.0 3729.8 139360.3 143027.0 143697.3 143716.6 145222.0 11286.4 13389.0 13589.8 13643.9 13644.6 25304.5 26537.9 27224.3 27240.0 27253.9 6804.6 7048.9 7570.0 7673.5 7718.5 3343.4 3937.9 3964.2 4023.9 4027.2 4214.2 4260.3 4285.2 4303.7 4314.1 842.4 913.5 914.3 945.6 953.2 584.0 638.6 652.3 667.1 684.5 11501.2 12350.1 12478.1 12516.3 12519.2 573.7 644.4 644.9 664.8 669.6 5788.7 5910.5 5917.6 5926.2 6044.8 146045.0 146991.0 147284.3 147414.0 148481.0 84602.6 116890.4 118863.0 119175.0 121442.4 91513.0 91955.0 104589.0 105873.0 106738.4 9752.9 10994.5 12178.9 13136.4 13170.2 44954.8 45396.0 45855.0 46116.5 46290.0 60742.8 61170.7 61172.7 62008.0 62099.7 16580.6 16829.0 17098.5 17214.2 17237.5 15848.0 18608.9 18801.8 19159.1 19234.6 14534.9 17519.6 18093.8 18151.5 18173.0 16908.3 16952.0 17250.7 17553.1 17675.6 17427.4 17492.1 17593.2 17625.0 17778.0 9890.8 10544.6 11616.6 11940.0 12295.3 12196.6 13179.5 13385.1 13550.9 13592.5 15:12:51 INFO - PID 1646 | -------- Summary: end -------- 15:12:51 INFO - PID 1646 | 15:12:51 INFO - TEST-INFO | 1646: exit 0 15:12:51 INFO - TEST-OK | dromaeo_css | took 1143609ms 15:12:51 INFO - TEST-START | kraken 15:12:51 INFO - operating with platform_type : mac_ 15:12:51 INFO - Initialising browser for kraken test... 15:12:51 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/tmpu5OEX1/profile 15:12:51 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 15:13:25 INFO - TEST-INFO | 1656: killed by SIGTERM 15:13:25 INFO - Browser initialized. 15:13:25 INFO - Running cycle 1/1 for kraken test... 15:13:25 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/tmpu5OEX1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:13:27 INFO - PID 1660 | 2017-05-30 15:13:27.772 plugin-container[1661:10990] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x953f, name = 'com.apple.tsm.portname' 15:13:27 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:13:27 INFO - PID 1660 | 2017-05-30 15:13:27.772 plugin-container[1661:10990] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0b, name = 'com.apple.CFPasteboardClient' 15:13:27 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:13:27 INFO - PID 1660 | 2017-05-30 15:13:27.772 plugin-container[1661:10990] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:13:28 INFO - PID 1660 | 2017-05-30 15:13:28.517 plugin-container[1662:11038] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x943f, name = 'com.apple.tsm.portname' 15:13:28 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:13:28 INFO - PID 1660 | 2017-05-30 15:13:28.517 plugin-container[1662:11038] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980b, name = 'com.apple.CFPasteboardClient' 15:13:28 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:13:28 INFO - PID 1660 | 2017-05-30 15:13:28.517 plugin-container[1662:11038] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:13:37 INFO - PID 1660 | RSS: Main: 262324224 15:13:37 INFO - PID 1660 | 15:13:40 INFO - PID 1660 | 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) 15:13:41 INFO - PID 1660 | RSS: Main: 271912960 15:13:41 INFO - PID 1660 | 15:13:44 INFO - PID 1660 | 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) 15:13:44 INFO - PID 1660 | RSS: Main: 280018944 15:13:44 INFO - PID 1660 | 15:13:48 INFO - PID 1660 | 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) 15:13:48 INFO - PID 1660 | RSS: Main: 282136576 15:13:48 INFO - PID 1660 | 15:13:50 INFO - PID 1660 | 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) 15:13:51 INFO - PID 1660 | RSS: Main: 283975680 15:13:51 INFO - PID 1660 | 15:13:53 INFO - PID 1660 | 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) 15:13:54 INFO - PID 1660 | RSS: Main: 284774400 15:13:54 INFO - PID 1660 | 15:13:56 INFO - PID 1660 | 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) 15:13:57 INFO - PID 1660 | RSS: Main: 288993280 15:13:57 INFO - PID 1660 | 15:14:00 INFO - PID 1660 | 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) 15:14:01 INFO - PID 1660 | RSS: Main: 292016128 15:14:01 INFO - PID 1660 | 15:14:04 INFO - PID 1660 | 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) 15:14:04 INFO - PID 1660 | RSS: Main: 291069952 15:14:04 INFO - PID 1660 | 15:14:06 INFO - PID 1660 | 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) 15:14:07 INFO - PID 1660 | RSS: Main: 290484224 15:14:07 INFO - PID 1660 | 15:14:08 INFO - PID 1660 | 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) 15:14:09 INFO - PID 1660 | RSS: Main: 290283520 15:14:09 INFO - PID 1660 | 15:14:11 INFO - PID 1660 | 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) 15:14:12 INFO - PID 1660 | RSS: Main: 290316288 15:14:12 INFO - PID 1660 | 15:14:15 INFO - PID 1660 | 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) 15:14:15 INFO - PID 1660 | RSS: Main: 290258944 15:14:15 INFO - PID 1660 | 15:14:19 INFO - PID 1660 | 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) 15:14:19 INFO - PID 1660 | RSS: Main: 290418688 15:14:19 INFO - PID 1660 | 15:14:21 INFO - PID 1660 | 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) 15:14:21 INFO - PID 1660 | RSS: Main: 292151296 15:14:21 INFO - PID 1660 | 15:14:21 INFO - PID 1660 | __start_tp_report 15:14:21 INFO - PID 1660 | _x_x_mozilla_page_load 15:14:21 INFO - PID 1660 | _x_x_mozilla_page_load_details 15:14:21 INFO - PID 1660 | |i|pagename|runs| 15:14:21 INFO - PID 1660 | |0;ai-astar;96;113;96;167;170;170;115;114;114;115 15:14:21 INFO - PID 1660 | |1;audio-beat-detection;121;113;133;160;112;110;116;99;146;146 15:14:21 INFO - PID 1660 | |2;audio-dft;194;195;203;226;217;269;277;228;199;194 15:14:21 INFO - PID 1660 | |3;audio-fft;67;63;60;86;60;70;59;59;81;68 15:14:21 INFO - PID 1660 | |4;audio-oscillator;85;69;82;70;69;68;85;71;72;104 15:14:21 INFO - PID 1660 | |5;imaging-gaussian-blur;91;90;88;89;90;89;89;89;89;90 15:14:21 INFO - PID 1660 | |6;imaging-darkroom;97;97;96;97;97;97;97;98;97;98 15:14:21 INFO - PID 1660 | |7;imaging-desaturate;79;77;148;115;95;79;76;74;146;145 15:14:21 INFO - PID 1660 | |8;json-parse-financial;52;51;53;51;53;53;54;52;51;53 15:14:21 INFO - PID 1660 | |9;json-stringify-tinderbox;56;59;58;56;64;65;57;56;68;80 15:14:21 INFO - PID 1660 | |10;stanford-crypto-aes;87;89;91;109;84;87;87;86;132;92 15:14:21 INFO - PID 1660 | |11;stanford-crypto-ccm;136;137;148;138;132;174;148;137;134;150 15:14:21 INFO - PID 1660 | |12;stanford-crypto-pbkdf2;184;189;231;184;226;229;184;190;193;217 15:14:21 INFO - PID 1660 | |13;stanford-crypto-sha256-iterative;66;67;75;69;66;69;68;66;69;66 15:14:21 INFO - PID 1660 | __end_tp_report 15:14:21 INFO - PID 1660 | __start_cc_report 15:14:21 INFO - PID 1660 | _x_x_mozilla_cycle_collect,1187 15:14:21 INFO - PID 1660 | __end_cc_report 15:14:21 INFO - PID 1660 | __startTimestamp1496182461703__endTimestamp 15:14:21 INFO - PID 1660 | 15:14:21 INFO - PID 1660 | ------- Summary: start ------- 15:14:21 INFO - PID 1660 | Number of tests: 14 15:14:21 INFO - PID 1660 | 15:14:21 INFO - PID 1660 | [#0] ai-astar Cycles:10 Average:127.00 Median:168.50 stddev:29.89 (17.7%) stddev-sans-first:29.53 15:14:21 INFO - PID 1660 | Values: 96.0 113.0 96.0 167.0 170.0 170.0 115.0 114.0 114.0 115.0 15:14:21 INFO - PID 1660 | 15:14:21 INFO - PID 1660 | [#1] audio-beat-detection Cycles:10 Average:125.60 Median:139.50 stddev:19.66 (14.1%) stddev-sans-first:20.78 15:14:21 INFO - PID 1660 | Values: 121.0 113.0 133.0 160.0 112.0 110.0 116.0 99.0 146.0 146.0 15:14:21 INFO - PID 1660 | 15:14:21 INFO - PID 1660 | [#2] audio-dft Cycles:10 Average:220.20 Median:221.50 stddev:30.67 (13.8%) stddev-sans-first:31.03 15:14:21 INFO - PID 1660 | Values: 194.0 195.0 203.0 226.0 217.0 269.0 277.0 228.0 199.0 194.0 15:14:21 INFO - PID 1660 | 15:14:21 INFO - PID 1660 | [#3] audio-fft Cycles:10 Average:67.30 Median:67.50 stddev:9.48 (14.0%) stddev-sans-first:10.05 15:14:21 INFO - PID 1660 | Values: 67.0 63.0 60.0 86.0 60.0 70.0 59.0 59.0 81.0 68.0 15:14:21 INFO - PID 1660 | 15:14:21 INFO - PID 1660 | [#4] audio-oscillator Cycles:10 Average:77.50 Median:71.50 stddev:11.54 (16.1%) stddev-sans-first:11.92 15:14:21 INFO - PID 1660 | Values: 85.0 69.0 82.0 70.0 69.0 68.0 85.0 71.0 72.0 104.0 15:14:21 INFO - PID 1660 | 15:14:21 INFO - PID 1660 | [#5] imaging-gaussian-blur Cycles:10 Average:89.40 Median:89.50 stddev:0.84 (0.9%) stddev-sans-first:0.67 15:14:21 INFO - PID 1660 | Values: 91.0 90.0 88.0 89.0 90.0 89.0 89.0 89.0 89.0 90.0 15:14:21 INFO - PID 1660 | 15:14:21 INFO - PID 1660 | [#6] imaging-darkroom Cycles:10 Average:97.10 Median:97.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 15:14:21 INFO - PID 1660 | Values: 97.0 97.0 96.0 97.0 97.0 97.0 97.0 98.0 97.0 98.0 15:14:21 INFO - PID 1660 | 15:14:21 INFO - PID 1660 | [#7] imaging-desaturate Cycles:10 Average:103.40 Median:76.50 stddev:32.05 (41.9%) stddev-sans-first:32.75 15:14:21 INFO - PID 1660 | Values: 79.0 77.0 148.0 115.0 95.0 79.0 76.0 74.0 146.0 145.0 15:14:21 INFO - PID 1660 | 15:14:21 INFO - PID 1660 | [#8] json-parse-financial Cycles:10 Average:52.30 Median:53.00 stddev:1.06 (2.0%) stddev-sans-first:1.12 15:14:21 INFO - PID 1660 | Values: 52.0 51.0 53.0 51.0 53.0 53.0 54.0 52.0 51.0 53.0 15:14:21 INFO - PID 1660 | 15:14:21 INFO - PID 1660 | [#9] json-stringify-tinderbox Cycles:10 Average:61.90 Median:61.50 stddev:7.68 (12.5%) stddev-sans-first:7.84 15:14:21 INFO - PID 1660 | Values: 56.0 59.0 58.0 56.0 64.0 65.0 57.0 56.0 68.0 80.0 15:14:21 INFO - PID 1660 | 15:14:21 INFO - PID 1660 | [#10] stanford-crypto-aes Cycles:10 Average:94.40 Median:87.00 stddev:14.97 (17.2%) stddev-sans-first:15.63 15:14:21 INFO - PID 1660 | Values: 87.0 89.0 91.0 109.0 84.0 87.0 87.0 86.0 132.0 92.0 15:14:21 INFO - PID 1660 | 15:14:21 INFO - PID 1660 | [#11] stanford-crypto-ccm Cycles:10 Average:143.40 Median:143.00 stddev:12.50 (8.7%) stddev-sans-first:12.97 15:14:21 INFO - PID 1660 | Values: 136.0 137.0 148.0 138.0 132.0 174.0 148.0 137.0 134.0 150.0 15:14:21 INFO - PID 1660 | 15:14:21 INFO - PID 1660 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:202.70 Median:205.00 stddev:20.36 (9.9%) stddev-sans-first:20.44 15:14:21 INFO - PID 1660 | Values: 184.0 189.0 231.0 184.0 226.0 229.0 184.0 190.0 193.0 217.0 15:14:21 INFO - PID 1660 | 15:14:21 INFO - PID 1660 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:68.50 stddev:2.77 (4.0%) stddev-sans-first:2.83 15:14:21 INFO - PID 1660 | Values: 66.0 67.0 75.0 69.0 66.0 69.0 68.0 66.0 69.0 66.0 15:14:21 INFO - PID 1660 | -------- Summary: end -------- 15:14:21 INFO - PID 1660 | 15:14:22 INFO - TEST-INFO | 1660: exit 0 15:14:22 INFO - TEST-OK | kraken | took 90810ms 15:14:22 INFO - SUITE-END | took 1234s 15:14:22 INFO - Completed test suite (00:20:34) 15:14:22 INFO - javascript benchmark 15:14:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8199.350285593639, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3093.613298337708, 3568.3041806847673, 3593.9820300898523, 3609.657082577156, 3626.5104210931518, 4862.2706594010915, 6063.272407311118, 6104.328523862371, 6138.324784273726, 6166.475849552788, 6131.386861313867, 6131.9884064911685, 6148.708771158051, 6174.660393678346, 6204.379562043793, 5261.368635763713, 5300.893982120365, 5348.491893270133, 5389.218563308318, 5495.780168793263, 5511.255980442629, 5517.282753242093, 5529.751161197756, 5531.142672885689, 5570.164475328693, 5327.493888080626, 5415.702136382497, 5418.0, 5468.64453810505, 5490.094134467828, 5442.564594832404, 5451.0, 5469.917951230735, 5473.397926228152, 5478.890422191574, 5390.460953904618, 5400.189971504266, 5422.942526207391, 5442.510174084351, 5459.153831156177, 6068.969655151696, 6089.238845144357, 6147.10866924298, 6235.532335074888, 6246.063090536456, 6480.805575832733, 6548.148740663731, 6636.800895973129, 6659.4006539411685, 6666.233383160901, 6766.661666916683, 6784.626845523494, 6790.626515541643, 6799.490038247101, 6807.761728339534, 6303.779367722108, 6306.243250809886, 6310.4636105930595, 6312.747490100344, 6319.52603554726, 6631.900521492182, 6726.899096513556, 6768.627725475048, 6864.622445765431, 6878.896816547755, 6056.879477296714, 6193.876122477525, 6302.401271879165, 6395.712192951329, 6415.973444248898, 6609.801705948733, 6723.764668236686, 6732.932670673328, 6744.393004629607, 6750.426213771786, 5406.405295417501, 6600.8349791255605, 6623.9006414903815, 6636.900446493307, 6640.734370625121, 6402.647854367961, 6484.870302593922, 6525.706343214567, 6615.437687796495, 6655.966720166368, 4374.753195339313, 5152.510511501401, 5346.518813306743, 5353.598480113929, 5391.622586418913, 4716.575508204278, 4730.290456431494, 4785.353977213032, 4791.544803243686, 4798.352222449925, 6153.1693221415435, 6172.0, 6232.127502149703, 6234.064890266496, 6256.061590761422, 6673.165854268216, 6736.124303840469, 6760.560563563353, 6778.559393639398, 6789.490788190906, 6249.500039996789, 6252.468540174045, 6257.937420625826, 6281.403266689657, 6285.120083188357, 5792.594518383757, 5960.105984102419, 5973.820785376446, 5976.73104710289, 5986.042233242661, 5664.631798933056, 6726.990951357336, 6771.559848609824, 6772.390484856288, 6879.931200687929, 5870.236869206976, 6503.642299673564, 6738.0, 6778.966105169443, 6788.626625535643, 6734.764283250062, 6888.827779305558, 6906.37842594169, 6917.342852429011, 6920.896186557105, 6402.455791257796, 6505.837354066186, 6536.215654121535, 6607.3062328456745, 6674.332566743286, 6727.529072964846, 6754.493412993947, 6755.93244067553, 6759.493038022069, 6788.287229840796, 6268.373162683695, 6440.29156792743, 6535.052417399545, 6549.70526326326, 6549.934500655123, 6435.420812126931, 6477.740890364521, 6494.772682956074, 6502.122213501306, 6512.023196520559, 6244.2819075806865, 6300.432961033529, 6322.905156422566, 6383.0, 6385.648789316632, 5442.891142177214, 6453.999630057348, 6457.741690332334, 6467.450266727381, 6515.924872396002, 6375.649339286384, 6375.968120159369, 6399.200099987502, 6403.0715546246465, 6411.358864113551], "value": 6058.640834959493, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [785.3246208260896, 800.6077022259091, 848.7368915636154, 1196.5094311332355, 1244.7074937389707, 36990.890273291814, 38787.80606096969, 38801.80599097, 38861.0, 38913.805430972876, 38962.0, 39026.21947561047, 39243.60756392435, 39249.0, 39332.60667393325, 6572.474202063835, 7898.60506974649, 8205.671773129068, 8422.199891010345, 8427.620757065915, 8249.505029698208, 8356.871822303972, 8361.331093512505, 8364.121767214447, 8398.286145677625, 8316.002079750438, 8382.329413646894, 8387.95806020969, 8389.496630202208, 8406.0, 8255.752327430188, 8334.458260213098, 8369.204925532065, 8380.20388063133, 8385.664573417087, 8120.3503719702285, 8190.959045204795, 8198.754037378829, 8206.0, 8207.425480216387, 36710.63289367099, 36830.63169368299, 36928.261434771426, 37034.07414814624, 37098.62901370979, 3567.7680950738118, 3598.5321908152036, 3603.4054381027363, 3605.0086226287717, 3606.3328284267495, 30304.84847575748, 30475.0, 30487.695123048707, 30683.386332273454, 30727.231819204477, 2829.670055074112, 2873.7700983921236, 3093.381323735239, 3097.1947293703797, 3109.0, 249746.50253497413, 253205.46794532004, 288036.9999999979, 308202.0, 308582.0, 47215.819604510165, 47546.762266188445, 47563.81090472766, 47617.761911190224, 47680.7615961918, 42852.78573607112, 50129.74935125301, 50235.74882125639, 53096.0, 55172.448275517534, 14505.0, 14674.339654715563, 14674.63313417173, 14687.26563671812, 14731.0, 11690.88309116915, 11785.0, 11816.995555377718, 11835.940820295844, 11857.5849845255, 11255.718607034725, 11356.886431135748, 13840.723185536233, 14013.859861401459, 14026.228557429235, 5148.382194136652, 6120.112583675431, 6129.478994285447, 6162.322144564095, 6177.413145751147, 996.6910257820099, 1007.6019972111121, 1012.8581998520214, 1036.4921188617523, 1036.6786296248188, 23206.65190022151, 24206.51586968286, 24249.151279705125, 24319.112560136928, 24628.50742985166, 1021.3157184686278, 1089.199438412766, 1102.8235482322789, 1107.5791199344199, 1134.183387960667, 10621.415822129857, 11641.6507504775, 11796.70508237301, 12376.81434778479, 12423.689407764878, 280287.0, 329103.0, 333084.0, 335222.0, 335377.0, 42284.15431691349, 53651.46348536465, 53668.463315366345, 53844.65388365244, 53921.0, 1448.7681970884607, 1495.820501539822, 1524.367387534183, 1525.5804653720315, 1537.1007960343325, 23949.28152155438, 23990.04039838387, 24007.879960600087, 24011.159609413586, 24021.51956960886, 38292.61707383002, 38296.808515957244, 38406.039849003995, 38421.0, 38494.42258366071, 276383.6180819083, 277332.0, 277934.0, 278802.0, 278972.0, 29100.854495728232, 34701.13247168841, 35690.64309356977, 35903.820480897426, 36056.45915311325, 43145.0, 45758.54241457543, 45886.77056614696, 45896.0, 45998.08003839971, 28715.856420717762, 30115.849420752755, 30808.075757727303, 31458.52812207773, 31756.682433175374, 30947.381052378376, 31146.37707245797, 31720.682793171774, 31758.68241317557, 31827.6817231834, 36155.734549290646, 36253.45619815652, 37240.6275937248, 37635.43546846745, 37783.62216377801, 31519.527207091454, 31693.0, 31736.206594835316, 32212.83893580517, 32637.0, 23104.306870793902, 24536.141235056686, 24644.394627730646, 24797.0, 25006.749932500443], "value": 17469.64923872022, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [422.55761697389084, 425.4532925191126, 433.7635988386329, 438.37750394439894, 450.257075824889, 22477.438064048387, 23239.954202060886, 23296.65055024174, 23351.0, 24126.276211713634, 25638.0, 26278.948842046295, 26306.47387052258, 26307.736922630767, 26385.472290554226, 6791.626460544668, 6881.587104773705, 6924.723011079551, 6930.237673855897, 6937.965310173442, 6557.7376904923985, 6915.239323674392, 6941.583504989691, 6966.02475653409, 7008.579485230903, 6922.7923162305215, 6939.44484441246, 6959.8956015659805, 6965.199002114746, 6999.9850021746815, 6946.79159625212, 6956.721731130749, 6960.9999999999745, 6963.1644202696, 6969.337912898267, 6758.391744742997, 6822.761203357908, 6826.112605361321, 6833.658317084126, 6841.144856892888, 20950.685739713765, 30306.181629102102, 31130.75476980918, 31421.525892345766, 31467.842660786777, 1970.3891793543903, 2205.37146913129, 2226.2096955580696, 2247.0786977339376, 2251.0770584060456, 19993.600127997503, 20490.897545512325, 20498.0, 20524.79475205244, 20556.691649625267, 2273.34073118797, 2276.3967548599635, 2337.0651739304244, 2354.3643216331666, 2361.1617875654238, 168668.99999999878, 173992.13003935025, 181237.0, 205583.0, 207640.96179519157, 22086.558268834535, 25786.35534111662, 26387.0, 27023.189304320902, 27341.863290683817, 44196.779016105356, 44661.10677786426, 44733.0, 44737.32894006593, 44747.0, 11103.611373601885, 11160.16298777595, 11298.7175320616, 11337.093032557375, 11383.317000979967, 8991.640334386682, 9017.954910225408, 9049.728508144766, 9102.954485227532, 9114.361994660443, 10254.58981640741, 12334.0, 12511.93744031274, 12576.434060467302, 12650.683732906567, 4185.330347144442, 4865.829695960624, 4995.575376093, 5000.374953130859, 5040.773165207501, 798.892149559802, 906.0, 920.8039173856291, 924.778053267211, 966.8501382285757, 20897.05963231658, 21123.78876211218, 21395.572088558452, 21590.352289431343, 21594.892025540084, 919.6735159018585, 941.7174847545709, 971.4171497101682, 995.581855620641, 1008.1380419741163, 8107.189281071964, 9030.148819491988, 9587.424754514752, 10915.56337746497, 11214.66356009321, 448404.0, 448733.5126648757, 516207.0, 526431.3678431583, 534803.0, 112515.0, 139045.30477347548, 141680.0, 142021.28989354987, 144658.0, 1124.5445594534062, 1133.7562424078862, 1205.3792296967245, 1208.178438661701, 1212.4180393411211, 18539.72190417118, 20192.192312307932, 20753.962301884545, 22968.081276748744, 23657.645135323328, 67901.66049169921, 68240.0, 68647.0, 69701.30298697151, 71172.28827711656, 90557.64163537684, 90566.54716726374, 90743.0925690761, 90948.0, 91147.08852911385, 10337.0, 10395.792084158425, 10529.947350263506, 10533.42066186367, 10546.947265263625, 18374.448766537025, 18581.2567497304, 18765.437036888914, 18786.6242675143, 18839.717404238672, 15212.0, 15313.310901009258, 15412.845871541589, 15512.22438878029, 15522.611934701723, 12809.61571152867, 12823.0, 12877.871221287667, 13530.391132399263, 13968.30158492051, 15147.848521514643, 15358.923205383902, 15405.537833865003, 15523.0, 15673.294701738221, 8831.955840220759, 10220.948895255477, 10267.332623379456, 10368.844467333147, 10398.012188842047, 12404.75190496167, 14900.552983410515, 15150.848491514944, 15152.393904243707, 15266.465673701367], "value": 12458.899245177405, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1252.1985929005434, 1462.4077248714275, 1468.4640106361178, 1468.764997600384, 1474.2702362330642, 11179.776404471915, 11464.0, 11519.884801151997, 11560.306381617098, 12232.0, 11723.355215463167, 11803.232789868653, 11910.40447977602, 11925.64223073307, 11958.282503049822, 2534.41708407066, 2578.7421257874157, 2580.2001379572393, 2580.329114430252, 2587.637730717701, 2499.7500249974946, 2534.4677617700213, 2534.8605826679527, 2538.0989748639245, 2541.313845261778, 2489.7759201671847, 2510.7991360691194, 2514.308565144591, 2518.6347979542957, 2521.8739063046864, 2099.8425118116124, 2374.41826752445, 2466.4203912080648, 2502.336880726609, 2502.4619706763133, 2408.12103582193, 2464.5810212263777, 2474.2206205045354, 2519.067944860395, 2520.483300923306, 11567.305961642329, 11653.766924661459, 11655.358955257458, 11658.766824663544, 11659.88340116605, 6402.551821372505, 6544.869102617921, 6613.305602911673, 6619.669016549202, 6645.734170633169, 3468.5490886184634, 4110.157417729388, 4160.646345060673, 4180.979095104505, 4266.445362102938, 1200.574058494583, 1220.5422966387605, 1238.8699186585368, 1245.6138597034949, 1247.1768632702374, 13964.812990895785, 15286.847131528653, 15648.843511564852, 15751.212439377985, 16279.0, 4893.412790465167, 5406.945930540644, 5435.429279925551, 5615.466530679579, 5642.351129620064, 11752.529898804123, 11775.646730598095, 11795.41022948849, 11807.17349785524, 11816.88183118175, 6387.425131738166, 6400.807975760735, 6494.285628580947, 6505.284418713938, 6526.412622863965, 6003.219581454383, 6105.48103411215, 6149.13912052313, 6160.353162917919, 6166.475849552839, 4382.211201983673, 4419.270820314677, 4476.216662084123, 4479.417675702203, 4516.683832131785, 1239.4918083585687, 1271.3261971155302, 1278.8721127887322, 1289.935503224835, 1291.6706239908951, 758.852023855343, 762.089303282572, 784.1883650421786, 791.5369508837395, 794.9682012719542, 6160.722767475474, 6838.863222735517, 6871.312868713088, 6927.792166235021, 7083.89374159388, 933.6312156698202, 935.0229010683852, 937.105064663245, 944.8063147054837, 966.367029595616, 3860.189360234326, 3895.824687889052, 3920.725549211585, 3962.6829853611644, 3986.242613903349, 14436.855631443552, 15028.098314100971, 15138.470153544573, 15155.393784248507, 15165.772513412308, 14897.553073407815, 15075.54773356801, 15078.698426031418, 15105.3202605883, 15106.0, 8461.661533538712, 8475.364347673949, 8548.974123105143, 8577.656893724306, 8705.782355441039, 6016.338202797601, 6800.897986530107, 7066.505344625827, 7120.896261079541, 7151.42788576923, 12477.188982716094, 12489.313087779896, 12666.430010649727, 12710.936445317715, 12802.871971280541, 12548.749025019266, 12719.55481558141, 12799.42402591867, 12849.614511564669, 12879.549215777402, 8451.323894088335, 8564.785880353042, 8601.526916019679, 8601.569921504026, 8603.182697643713, 10591.89408105909, 10719.839202412126, 10924.50839712199, 11023.228374013743, 11039.116870650168, 8944.239739622206, 9229.630814767334, 9303.953480232556, 9314.649375840601, 9400.200982916529, 8949.865752013855, 9010.234130099016, 9018.954905225432, 9076.228520575562, 9176.587053582738, 8257.710980115668, 8354.206350396702, 8356.456830306011, 8359.122292159236, 8372.623231954698, 6056.152138700498, 6058.57589968698, 6168.414000669929, 6193.783217587363, 6251.281102673069, 5823.417658234142, 5882.470577647861, 5997.250343707036, 6098.268207814913, 6175.135481032748], "value": 5341.69296784672, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.73660174479613, 394.6448196623041, 398.73085667174666, 399.2055808940208, 411.8970257435641, 5182.0, 5627.099664053752, 5746.827595172152, 6314.7789827356055, 6629.86740265195, 6313.62118272904, 6442.581232219903, 6444.774432894849, 6469.611823290606, 6488.675566221694, 4562.6121779648765, 4682.016776476952, 4695.225287827504, 4714.882127946812, 4723.048094940662, 4653.27874179501, 4692.812287508513, 4712.293156026589, 4714.98627795024, 4725.598324142452, 4624.445066592014, 4634.791434385444, 4639.0, 4639.396878405802, 4671.696339737906, 4044.2720310344275, 4637.884052898671, 4644.164050470914, 4661.347411362412, 4672.602828759525, 3137.0, 3981.9601803981877, 3984.027906186456, 3995.860144894944, 4156.937645935313, 5729.4843464088435, 6324.367563243685, 6404.903926441107, 6407.391297826699, 6441.742330306782, 5180.611454140954, 5570.470805273492, 5575.442455754433, 5580.0, 5605.411431799643, 5463.972680136574, 6975.267596902303, 6975.616341101238, 6979.685914133876, 7002.334778196063, 3778.6032466591164, 4529.6376289896725, 4542.182407166709, 4544.250198717207, 4545.886352841172, 6979.336962988508, 7016.0, 7237.240089790602, 7366.963165184194, 7458.627068646546, 3421.1789170598877, 4012.53855806586, 4132.822145688464, 4295.355696645528, 4412.0, 4670.416197975253, 4675.883102922386, 4677.695949763254, 4690.413698287714, 4706.576408123286, 5311.725185955343, 5314.5216930476445, 5546.001719690414, 5555.416681248492, 5601.915971260434, 5675.631083979611, 6279.613746776287, 6825.249222585511, 6900.03399524067, 6920.4809639277255, 1571.316477332364, 1579.4156162220052, 1579.921003949798, 1584.7226735321274, 1591.1805420208602, 994.80103979203, 1026.4046852825343, 1026.8511065895552, 1039.5685790397051, 1039.8977084290675, 5184.045094296215, 5660.971695141498, 6254.499640028786, 6451.903221451682, 6543.44380727634, 6372.968135159294, 6815.693293801791, 7201.819954501075, 7202.675879585431, 7273.709051637981, 6453.806385808433, 6466.6766661666725, 6471.935280647227, 6472.482201423874, 6477.5465717400275, 3505.614382417932, 3759.586445490994, 3769.453429252743, 3773.9997801165428, 3775.3765880671026, 6053.576249662569, 6361.141245931833, 7325.084364454443, 7338.302861228175, 7355.166747413987, 6362.9045564316575, 6559.836004099936, 7031.866737281813, 7242.529235599669, 7307.926920730724, 6159.907601386072, 6206.751729930843, 7368.963155184189, 7380.571657005754, 7386.630668466555, 6196.907046394217, 6979.569188316282, 7034.683439245144, 7155.677994490156, 7185.353318201387, 5701.8859622806485, 6144.78493252734, 6803.80933336674, 7055.153381594241, 7083.539569927938, 6639.402453779183, 6818.522703410714, 7162.385729208905, 7284.125904891235, 7384.778456646309, 4410.544520308355, 4544.522825103317, 4572.35986961819, 4594.264917613166, 4599.735072854992, 6137.662428566293, 6593.274739778524, 6986.825329366807, 7271.2365201653065, 7286.380657644159, 5663.915041274302, 5761.798337058183, 6362.427381535684, 6903.824240849265, 6966.9651651741415, 5910.438508341701, 6193.628382297076, 6844.041834143323, 6999.720011199699, 7016.19313778922, 5639.464250896158, 6841.213260475109, 6891.345322194384, 6894.172699276119, 6962.651867406711, 7008.719651213894, 7013.193482749549, 7014.0, 7016.36852683261, 7044.506884518035, 5355.0, 5446.373667028184, 6822.0, 6829.180498340231, 6883.552569083194], "value": 4928.374564359416, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [719.5610677486732, 804.4730701390591, 850.2942557677127, 863.8531449653558, 869.8869147010892, 15021.92489037556, 15538.84461155388, 16999.745003824937, 17444.91277543612, 17506.12469376531, 16215.432459863907, 17142.914285428586, 18021.63956720868, 18060.0, 18220.18009189586, 973.4110862927951, 1020.1328870460087, 1036.8289232276666, 1037.9221558383115, 1039.002557544758, 1007.7631756537207, 1015.3248090020164, 1015.6800607808553, 1017.7760892603652, 1021.8824835143942, 951.0679534056628, 1062.617457715222, 1066.653337665258, 1070.684148176286, 1078.8543546621183, 999.280518027023, 1056.6565866093551, 1057.228223396917, 1061.2995423020807, 1067.5409573883228, 768.5158350239369, 768.5350363030398, 770.1759117744016, 772.7905827380132, 775.7595145504912, 17453.04008279544, 17913.7312940306, 17972.921624702558, 18042.00768957707, 18063.09684515782, 17597.648047038987, 17784.3775467858, 17909.641807163785, 18147.27410903562, 18151.36470223549, 14934.626634334229, 15038.473653422077, 15151.545453636298, 15169.24153792317, 15196.240187990556, 3576.213233088716, 3579.874704385334, 3616.9276614467826, 3695.0, 3729.8321575528894, 139360.30319848337, 143027.0, 143697.2815135928, 143716.56283437138, 145222.0, 11286.435678216056, 13389.0, 13589.796153057712, 13643.863561364458, 13644.590662280147, 25304.493910121695, 26537.86731066371, 27224.31939201496, 27240.04659836896, 27253.86373068122, 6804.6257455840405, 7048.894266586005, 7569.962150189324, 7673.53958762476, 7718.53688778675, 3343.3981883261245, 3937.8818635440985, 3964.1675248197625, 4023.8994025149605, 4027.1744292419985, 4214.157168566299, 4260.275753121977, 4285.228658841438, 4303.698741088157, 4314.115606300701, 842.4103127810557, 913.4519288426891, 914.2868562521244, 945.60284680433, 953.1993125774383, 583.9984426708215, 638.6455517188006, 652.2824892618082, 667.1094089390717, 684.5413572906066, 11501.194916355942, 12350.07374446902, 12478.064145189148, 12516.31160286193, 12519.248845069325, 573.7131434282859, 644.3781750610683, 644.9451796597248, 664.833791552112, 669.5681285570877, 5788.710564471759, 5910.468057874812, 5917.585768996215, 5926.229590153339, 6044.788432404845, 146045.0, 146991.0, 147284.26357868357, 147414.0, 148481.0, 84602.5769871159, 116890.41554792342, 118863.0, 119175.0, 121442.39278803549, 91512.96678682737, 91955.0, 104589.0, 105873.0, 106738.3989240162, 9752.920058277014, 10994.450277486254, 12178.939105304771, 13136.408861601441, 13170.209787412787, 44954.77522612366, 45396.0, 45855.0, 46116.53883461122, 46290.0, 60742.78514429598, 61170.694146528986, 61172.69413652903, 62008.0, 62099.6895015522, 16580.58548536296, 16829.0, 17098.487045388658, 17214.225359858585, 17237.482875513753, 15848.0, 18608.883466992025, 18801.811981880008, 19159.137838797567, 19234.615307694046, 14534.927325363305, 17519.562010949827, 18093.81906180974, 18151.45545633633, 18173.0, 16908.2700798729, 16952.0, 17250.65498690044, 17553.12234388301, 17675.558111046812, 17427.39004134849, 17492.125393730515, 17593.208305626533, 17625.0, 17778.0, 9890.752731181778, 10544.57821687124, 11616.593419230287, 11940.044796416092, 12295.297224713577, 12196.57311994076, 13179.538716144889, 13385.063045586716, 13550.864491355354, 13592.45630174782], "value": 8752.302337648498, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1530.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 113.0, 96.0, 167.0, 170.0, 170.0, 115.0, 114.0, 114.0, 115.0], "value": 127.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 113.0, 133.0, 160.0, 112.0, 110.0, 116.0, 99.0, 146.0, 146.0], "value": 125.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 195.0, 203.0, 226.0, 217.0, 269.0, 277.0, 228.0, 199.0, 194.0], "value": 220.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 63.0, 60.0, 86.0, 60.0, 70.0, 59.0, 59.0, 81.0, 68.0], "value": 67.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 69.0, 82.0, 70.0, 69.0, 68.0, 85.0, 71.0, 72.0, 104.0], "value": 77.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 90.0, 88.0, 89.0, 90.0, 89.0, 89.0, 89.0, 89.0, 90.0], "value": 89.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 96.0, 97.0, 97.0, 97.0, 97.0, 98.0, 97.0, 98.0], "value": 97.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 77.0, 148.0, 115.0, 95.0, 79.0, 76.0, 74.0, 146.0, 145.0], "value": 103.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 53.0, 51.0, 53.0, 53.0, 54.0, 52.0, 51.0, 53.0], "value": 52.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 59.0, 58.0, 56.0, 64.0, 65.0, 57.0, 56.0, 68.0, 80.0], "value": 61.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 89.0, 91.0, 109.0, 84.0, 87.0, 87.0, 86.0, 132.0, 92.0], "value": 94.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 137.0, 148.0, 138.0, 132.0, 174.0, 148.0, 137.0, 134.0, 150.0], "value": 143.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 189.0, 231.0, 184.0, 226.0, 229.0, 184.0, 190.0, 193.0, 217.0], "value": 202.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 75.0, 69.0, 66.0, 69.0, 68.0, 66.0, 69.0, 66.0], "value": 68.1, "unit": "score"}]}]} 15:14:22 INFO - Return code: 0 15:14:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:14:23 INFO - # TBPL SUCCESS # 15:14:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:14:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:14:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:14:23 INFO - 2017-05-30 15:14:23.055 screenresolution[1668:11409] starting screenresolution argv=screenresolution get 15:14:23 INFO - 2017-05-30 15:14:23.058 screenresolution[1668:11409] Display 0: 1600x1200x32@60 15:14:23 INFO - 2017-05-30 15:14:23.064 screenresolution[1669:11412] starting screenresolution argv=screenresolution list 15:14:23 INFO - Available Modes on Display 0 15:14:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:14:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:14:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:14:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:14:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:14:23 INFO - Intel Iris: 15:14:23 INFO - Chipset Model: Intel Iris 15:14:23 INFO - Type: GPU 15:14:23 INFO - Bus: Built-In 15:14:23 INFO - VRAM (Dynamic, Max): 1536 MB 15:14:23 INFO - Vendor: Intel (0x8086) 15:14:23 INFO - Device ID: 0x0a2e 15:14:23 INFO - Revision ID: 0x0009 15:14:23 INFO - Displays: 15:14:23 INFO - Display: 15:14:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:14:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:14:23 INFO - Display Serial Number: 1600x1200 60 15:14:23 INFO - Main Display: Yes 15:14:23 INFO - Mirror: Off 15:14:23 INFO - Online: Yes 15:14:23 INFO - Rotation: Supported 15:14:23 INFO - Return code: 0 15:14:23 INFO - Running post-action listener: _resource_record_post_action 15:14:23 INFO - [mozharness: 2017-05-30 22:14:23.302859Z] Finished run-tests step (success) 15:14:23 INFO - Running post-run listener: _resource_record_post_run 15:14:23 INFO - Total resource usage - Wall time: 1257s; CPU: 53.0%; Read bytes: 40236544; Write bytes: 569666560; Read time: 273; Write time: 1785 15:14:23 INFO - TinderboxPrint: CPU usage
53.3% 15:14:23 INFO - TinderboxPrint: I/O read bytes / time
40,236,544 / 273 15:14:23 INFO - TinderboxPrint: I/O write bytes / time
569,666,560 / 1,785 15:14:23 INFO - TinderboxPrint: CPU idle
2,351.3 (46.7%) 15:14:23 INFO - TinderboxPrint: CPU system
1,016.8 (20.2%) 15:14:23 INFO - TinderboxPrint: CPU user
1,661.6 (33.0%) 15:14:23 INFO - TinderboxPrint: Swap in / out
286,183,424 / 0 15:14:23 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 226300416; Write bytes: 218746880; Read time: 17104; Write time: 444 15:14:23 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 15:14:23 INFO - run-tests - Wall time: 1236s; CPU: 53.0%; Read bytes: 39302656; Write bytes: 322591744; Read time: 259; Write time: 1267 15:14:23 INFO - Running post-run listener: _upload_blobber_files 15:14:23 INFO - Blob upload gear active. 15:14:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:14:23 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 15:14:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:14:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:14:24 INFO - (blobuploader) - INFO - Open directory for files ... 15:14:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:14:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:14:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:14:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:14:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:14:25 INFO - (blobuploader) - INFO - Done attempting. 15:14:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:14:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:14:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:14:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:14:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:14:26 INFO - (blobuploader) - INFO - Done attempting. 15:14:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:14:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:14:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:14:27 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:14:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:14:27 INFO - (blobuploader) - INFO - Done attempting. 15:14:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:14:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:14:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:14:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:14:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:14:29 INFO - (blobuploader) - INFO - Done attempting. 15:14:29 INFO - (blobuploader) - INFO - Iteration through files over. 15:14:29 INFO - Return code: 0 15:14:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:14:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:14:29 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/40b45222339dc9c0ccb79a42adef11a210b2eb3ed366f7a476175d1a68656de02397b5af2f061a09f184e89e76d253bd40f9c4970a0866f96e0ccf761ecb99f8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/53100039f9cf3cbc6386c3da3ac412156699e278918b259599251edf2dae86d2802266524070d0a6b5c068dce3447e674b0749a23c2cb23ecdcd1ef12dc8ee9c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/256f95c3aff59b96109f185d3a4257a2b6d20b22e75806dba9386a61c3c05d721701a771817695852da70f2c6c31c8f1907e32f98a6d935e64b613be8f1a8870", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5ab82fe0872f1b2e8dbe5b9ccd1641f1e53b74a52ea99e15f3f0b9e67edcbfb0cc902b007d8757c72f76681c8e127648507fe3532ee0c566d55582a16a0c77e8"} 15:14:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:14:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:14:29 INFO - Contents: 15:14:29 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/40b45222339dc9c0ccb79a42adef11a210b2eb3ed366f7a476175d1a68656de02397b5af2f061a09f184e89e76d253bd40f9c4970a0866f96e0ccf761ecb99f8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/53100039f9cf3cbc6386c3da3ac412156699e278918b259599251edf2dae86d2802266524070d0a6b5c068dce3447e674b0749a23c2cb23ecdcd1ef12dc8ee9c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/256f95c3aff59b96109f185d3a4257a2b6d20b22e75806dba9386a61c3c05d721701a771817695852da70f2c6c31c8f1907e32f98a6d935e64b613be8f1a8870", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5ab82fe0872f1b2e8dbe5b9ccd1641f1e53b74a52ea99e15f3f0b9e67edcbfb0cc902b007d8757c72f76681c8e127648507fe3532ee0c566d55582a16a0c77e8"} 15:14:29 INFO - Running post-run listener: copy_logs_to_upload_dir 15:14:29 INFO - Copying logs to upload dir... 15:14:29 INFO - mkdir: /builds/slave/test/build/upload/logs 15:14:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1337.792915 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2017-05-30 15:14:29.574732) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-30 15:14:29.578738) ========= 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.T4DIiBmsdx/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.PtECUnkGMt/Listeners TMPDIR=/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/40b45222339dc9c0ccb79a42adef11a210b2eb3ed366f7a476175d1a68656de02397b5af2f061a09f184e89e76d253bd40f9c4970a0866f96e0ccf761ecb99f8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/53100039f9cf3cbc6386c3da3ac412156699e278918b259599251edf2dae86d2802266524070d0a6b5c068dce3447e674b0749a23c2cb23ecdcd1ef12dc8ee9c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/256f95c3aff59b96109f185d3a4257a2b6d20b22e75806dba9386a61c3c05d721701a771817695852da70f2c6c31c8f1907e32f98a6d935e64b613be8f1a8870", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5ab82fe0872f1b2e8dbe5b9ccd1641f1e53b74a52ea99e15f3f0b9e67edcbfb0cc902b007d8757c72f76681c8e127648507fe3532ee0c566d55582a16a0c77e8"} build_url:https://queue.taskcluster.net/v1/task/PhApj6BRQlWkJeJyoDtXkg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011206 build_url: 'https://queue.taskcluster.net/v1/task/PhApj6BRQlWkJeJyoDtXkg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/40b45222339dc9c0ccb79a42adef11a210b2eb3ed366f7a476175d1a68656de02397b5af2f061a09f184e89e76d253bd40f9c4970a0866f96e0ccf761ecb99f8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/53100039f9cf3cbc6386c3da3ac412156699e278918b259599251edf2dae86d2802266524070d0a6b5c068dce3447e674b0749a23c2cb23ecdcd1ef12dc8ee9c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/256f95c3aff59b96109f185d3a4257a2b6d20b22e75806dba9386a61c3c05d721701a771817695852da70f2c6c31c8f1907e32f98a6d935e64b613be8f1a8870", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5ab82fe0872f1b2e8dbe5b9ccd1641f1e53b74a52ea99e15f3f0b9e67edcbfb0cc902b007d8757c72f76681c8e127648507fe3532ee0c566d55582a16a0c77e8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-30 15:14:29.614464) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 15:14:29.614890) ========= 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.T4DIiBmsdx/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.PtECUnkGMt/Listeners TMPDIR=/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/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.005058 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 15:14:29.664247) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-30 15:14:29.664563) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-30 15:14:29.664889) ========= ========= Total master_lag: 0.19 =========