builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0141 starttime: 1497011163.89 results: success (0) revision: 7c9d96bbc400aa77f5310486162abf6b61344b74 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-09 05:26:03.888113) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-09 05:26:03.888560) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-09 05:26:03.888859) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-09 05:26:03.920233) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-09 05:26:03.920530) ========= 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.Y1HcDw7Fa7/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.2E6IVV5AYQ/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-09 05:26:03-- 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% 121M=0s 2017-06-09 05:26:04 (121 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.453330 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-09 05:26:04.396319) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-09 05:26:04.396618) ========= 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.Y1HcDw7Fa7/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.2E6IVV5AYQ/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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.021817 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-09 05:26:04.446331) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-09 05:26:04.446676) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 7c9d96bbc400aa77f5310486162abf6b61344b74 --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 7c9d96bbc400aa77f5310486162abf6b61344b74 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Y1HcDw7Fa7/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.2E6IVV5AYQ/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-09 05:26:04,519 truncating revision to first 12 chars 2017-06-09 05:26:04,519 Setting DEBUG logging. 2017-06-09 05:26:04,519 attempt 1/10 2017-06-09 05:26:04,519 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/7c9d96bbc400?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-09 05:26:05,684 unpacking tar archive at: mozilla-central-7c9d96bbc400/testing/mozharness/ program finished with exit code 0 elapsedTime=1.427434 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-09 05:26:05.896527) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-09 05:26:05.896937) ========= script_repo_revision: 7c9d96bbc400aa77f5310486162abf6b61344b74 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-09 05:26:05.897436) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-09 05:26:05.897769) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-09 05:26:05.917793) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-06-09 05:26:05.918505) ========= /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.Y1HcDw7Fa7/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.2E6IVV5AYQ/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 05:26:06 INFO - MultiFileLogger online at 20170609 05:26:06 in /builds/slave/test 05:26:06 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 05:26:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:26:06 INFO - {'append_to_log': False, 05:26:06 INFO - 'base_work_dir': '/builds/slave/test', 05:26:06 INFO - 'blob_upload_branch': 'Firefox', 05:26:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:26:06 INFO - 'branch': 'Firefox', 05:26:06 INFO - 'buildbot_json_path': 'buildprops.json', 05:26:06 INFO - 'config_files': ('talos/mac_config.py',), 05:26:06 INFO - 'default_actions': ('clobber', 05:26:06 INFO - 'read-buildbot-config', 05:26:06 INFO - 'download-and-extract', 05:26:06 INFO - 'populate-webroot', 05:26:06 INFO - 'create-virtualenv', 05:26:06 INFO - 'install', 05:26:06 INFO - 'setup-mitmproxy', 05:26:06 INFO - 'run-tests'), 05:26:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:26:06 INFO - 'download_minidump_stackwalk': True, 05:26:06 INFO - 'download_symbols': 'ondemand', 05:26:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:26:06 INFO - 'tooltool.py': '/tools/tooltool.py', 05:26:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:26:06 INFO - '/tools/misc-python/virtualenv.py')}, 05:26:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:26:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:26:06 INFO - 'gecko_profile': False, 05:26:06 INFO - 'gecko_profile_interval': 0, 05:26:06 INFO - 'installer_path': 'installer.exe', 05:26:06 INFO - 'log_level': 'info', 05:26:06 INFO - 'log_name': 'talos', 05:26:06 INFO - 'log_to_console': True, 05:26:06 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:26:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:26:06 INFO - 'opt_config_files': (), 05:26:06 INFO - 'pip_index': False, 05:26:06 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:26:06 INFO - 'cmd': ('bash', 05:26:06 INFO - '-c', 05:26:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:26:06 INFO - 'enabled': True, 05:26:06 INFO - 'halt_on_failure': False, 05:26:06 INFO - 'name': 'check_screen_resolution'},), 05:26:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:26:06 INFO - 'cmd': ('bash', 05:26:06 INFO - '-c', 05:26:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:26:06 INFO - 'enabled': True, 05:26:06 INFO - 'halt_on_failure': False, 05:26:06 INFO - 'name': 'check_screen_resolution'},), 05:26:06 INFO - 'run_cmd_checks_enabled': True, 05:26:06 INFO - 'suite': 'dromaeojs-e10s', 05:26:06 INFO - 'system_bits': '32', 05:26:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:26:06 INFO - 'title': 't-yosemite-r7-0141', 05:26:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:26:06 INFO - 'use_talos_json': True, 05:26:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:26:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:26:06 INFO - 'work_dir': 'build'} 05:26:06 INFO - [mozharness: 2017-06-09 12:26:06.133092Z] Running clobber step. 05:26:06 INFO - Running pre-action listener: _resource_record_pre_action 05:26:06 INFO - Running main action method: clobber 05:26:06 INFO - rmtree: /builds/slave/test/build 05:26:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:26:09 INFO - Running post-action listener: _resource_record_post_action 05:26:09 INFO - [mozharness: 2017-06-09 12:26:09.591841Z] Finished clobber step (success) 05:26:09 INFO - [mozharness: 2017-06-09 12:26:09.591977Z] Running read-buildbot-config step. 05:26:09 INFO - Running pre-action listener: _resource_record_pre_action 05:26:09 INFO - Running main action method: read_buildbot_config 05:26:09 INFO - Using buildbot properties: 05:26:09 INFO - { 05:26:09 INFO - "project": "", 05:26:09 INFO - "product": "firefox", 05:26:09 INFO - "who": "cbook@mozilla.com", 05:26:09 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 05:26:09 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:26:09 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 05:26:09 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:26:09 INFO - "stage_platform": "macosx64", 05:26:09 INFO - "basedir": "/builds/slave/test", 05:26:09 INFO - "buildnumber": 88, 05:26:09 INFO - "platform": "yosemite_r7", 05:26:09 INFO - "parent_task_id": "Wx3sesJGQgqAxd-UY1QTGg", 05:26:09 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 05:26:09 INFO - "slavebuilddir": "test", 05:26:09 INFO - "taskId": "GRGUnU1QR_iTm2oGakV7eA", 05:26:09 INFO - "branch": "mozilla-central", 05:26:09 INFO - "script_repo_revision": "production", 05:26:09 INFO - "revision": "7c9d96bbc400aa77f5310486162abf6b61344b74", 05:26:09 INFO - "slavename": "t-yosemite-r7-0141", 05:26:09 INFO - "repo_path": "mozilla-central" 05:26:09 INFO - } 05:26:09 INFO - Finding installer, test and symbols from parent task. 05:26:09 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GRGUnU1QR_iTm2oGakV7eA'}, attempt #1 05:26:10 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GRGUnU1QR_iTm2oGakV7eA'}, attempt #1 05:26:10 INFO - Task dependencies: C8G9KInKT1uApdGJj4vTfQ Wx3sesJGQgqAxd-UY1QTGg 05:26:10 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Wx3sesJGQgqAxd-UY1QTGg'}, attempt #1 05:26:11 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Wx3sesJGQgqAxd-UY1QTGg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:26:11 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Wx3sesJGQgqAxd-UY1QTGg/artifacts/public/build/test_packages.json 05:26:11 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Wx3sesJGQgqAxd-UY1QTGg/artifacts/public/build/target.crashreporter-symbols.zip 05:26:11 INFO - Running post-action listener: _resource_record_post_action 05:26:11 INFO - [mozharness: 2017-06-09 12:26:11.304698Z] Finished read-buildbot-config step (success) 05:26:11 INFO - [mozharness: 2017-06-09 12:26:11.304814Z] Running download-and-extract step. 05:26:11 INFO - Running pre-action listener: _resource_record_pre_action 05:26:11 INFO - Running main action method: download_and_extract 05:26:11 INFO - mkdir: /builds/slave/test/build/tests 05:26: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')]} 05:26:11 INFO - trying https://queue.taskcluster.net/v1/task/Wx3sesJGQgqAxd-UY1QTGg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 05:26:11 INFO - Downloading https://queue.taskcluster.net/v1/task/Wx3sesJGQgqAxd-UY1QTGg/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 05:26:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Wx3sesJGQgqAxd-UY1QTGg/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 05:26:11 INFO - Downloaded 1328 bytes. 05:26:11 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 05:26:11 INFO - Using the following test package requirements: 05:26:11 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:26:11 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 05:26:11 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 05:26:11 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:26:11 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 05:26:11 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:26:11 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 05:26:11 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:26:11 INFO - u'jsshell-mac.zip'], 05:26:11 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:26:11 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 05:26:11 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 05:26:11 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:26:11 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 05:26:11 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:26:11 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 05:26:11 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:26:11 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.tar.gz'], 05:26:11 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:26:11 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 05:26:11 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:26:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Wx3sesJGQgqAxd-UY1QTGg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 05:26:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Wx3sesJGQgqAxd-UY1QTGg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:26:11 INFO - Fetch https://queue.taskcluster.net/v1/task/Wx3sesJGQgqAxd-UY1QTGg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 05:26:13 INFO - Content-Length response header: 59547676 05:26:13 INFO - Bytes received: 59547676 05:26:31 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 05:26:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Wx3sesJGQgqAxd-UY1QTGg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 05:26:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Wx3sesJGQgqAxd-UY1QTGg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:26:31 INFO - Fetch https://queue.taskcluster.net/v1/task/Wx3sesJGQgqAxd-UY1QTGg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 05:26:34 INFO - Content-Length response header: 59547676 05:26:34 INFO - Bytes received: 59547676 05:26:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Wx3sesJGQgqAxd-UY1QTGg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 05:26:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Wx3sesJGQgqAxd-UY1QTGg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:26:51 INFO - Fetch https://queue.taskcluster.net/v1/task/Wx3sesJGQgqAxd-UY1QTGg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 05:26:52 INFO - Content-Length response header: 14449999 05:26:52 INFO - Bytes received: 14449999 05:26:52 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')]} 05:26:52 INFO - trying https://queue.taskcluster.net/v1/task/Wx3sesJGQgqAxd-UY1QTGg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:26:52 INFO - Downloading https://queue.taskcluster.net/v1/task/Wx3sesJGQgqAxd-UY1QTGg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 05:26:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Wx3sesJGQgqAxd-UY1QTGg/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 05:26:54 INFO - Downloaded 69333986 bytes. 05:26:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Wx3sesJGQgqAxd-UY1QTGg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:26:54 INFO - mkdir: /builds/slave/test/properties 05:26:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:26:54 INFO - Writing to file /builds/slave/test/properties/build_url 05:26:54 INFO - Contents: 05:26:54 INFO - build_url:https://queue.taskcluster.net/v1/task/Wx3sesJGQgqAxd-UY1QTGg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:26:54 INFO - Running post-action listener: _resource_record_post_action 05:26:54 INFO - Running post-action listener: set_extra_try_arguments 05:26:54 INFO - [mozharness: 2017-06-09 12:26:54.571567Z] Finished download-and-extract step (success) 05:26:54 INFO - [mozharness: 2017-06-09 12:26:54.571697Z] Running populate-webroot step. 05:26:54 INFO - Running pre-action listener: _resource_record_pre_action 05:26:54 INFO - Running main action method: populate_webroot 05:26:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:26:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:26:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:26:54 INFO - '--tppagecycles', 05:26:54 INFO - '7'], 05:26:54 INFO - 'tests': ['tsvgm']}}, 05:26:54 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:26:54 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:26:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:26:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:26:54 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:26:54 INFO - 'talos_options': ['--disable-e10s'], 05:26:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:26:54 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:26:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:26:54 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:26:54 INFO - 'talos_options': ['--disable-e10s'], 05:26:54 INFO - 'tests': ['damp', 'tps']}, 05:26:54 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:26:54 INFO - 'tests': ['damp', 'tps']}, 05:26:54 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:26:54 INFO - 'tests': ['dromaeo_dom']}, 05:26:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:26:54 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:26:54 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 05:26:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 05:26:54 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 05:26:54 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 05:26:54 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:26:54 INFO - 'tests': ['a11yr', 05:26:54 INFO - 'ts_paint', 05:26:54 INFO - 'tpaint', 05:26:54 INFO - 'sessionrestore', 05:26:54 INFO - 'sessionrestore_no_auto_restore', 05:26:54 INFO - 'tabpaint']}, 05:26:54 INFO - 'other-e10s': {'tests': ['a11yr', 05:26:54 INFO - 'ts_paint', 05:26:54 INFO - 'tpaint', 05:26:54 INFO - 'sessionrestore', 05:26:54 INFO - 'sessionrestore_no_auto_restore', 05:26:54 INFO - 'tabpaint']}, 05:26:54 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:26:54 INFO - 'ts_paint', 05:26:54 INFO - 'tpaint', 05:26:54 INFO - 'sessionrestore', 05:26:54 INFO - 'sessionrestore_no_auto_restore', 05:26:54 INFO - 'tabpaint']}, 05:26:54 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:26:54 INFO - 'ts_paint', 05:26:54 INFO - 'tpaint', 05:26:54 INFO - 'sessionrestore', 05:26:54 INFO - 'sessionrestore_no_auto_restore', 05:26:54 INFO - 'tabpaint']}, 05:26:54 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:26:54 INFO - 'tests': ['a11yr', 05:26:54 INFO - 'ts_paint', 05:26:54 INFO - 'tpaint', 05:26:54 INFO - 'sessionrestore', 05:26:54 INFO - 'sessionrestore_no_auto_restore', 05:26:54 INFO - 'tabpaint']}, 05:26:54 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:26:54 INFO - 'tests': ['a11yr', 05:26:54 INFO - 'ts_paint', 05:26:54 INFO - 'tpaint', 05:26:54 INFO - 'sessionrestore', 05:26:54 INFO - 'sessionrestore_no_auto_restore', 05:26:54 INFO - 'tabpaint']}, 05:26:54 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 05:26:54 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 05:26:54 INFO - 'bloom_basic_ref']}, 05:26:54 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:26:54 INFO - 'talos_options': ['--mitmproxy', 05:26:54 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:26:54 INFO - '--firstNonBlankPaint'], 05:26:54 INFO - 'tests': ['quantum_pageload_google', 05:26:54 INFO - 'quantum_pageload_youtube', 05:26:54 INFO - 'quantum_pageload_amazon', 05:26:54 INFO - 'quantum_pageload_facebook']}, 05:26:54 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:26:54 INFO - 'tests': ['tsvgx', 05:26:54 INFO - 'tsvgr_opacity', 05:26:54 INFO - 'tart', 05:26:54 INFO - 'tscrollx', 05:26:54 INFO - 'cart', 05:26:54 INFO - 'tsvg_static']}, 05:26:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:26:54 INFO - 'tsvgr_opacity', 05:26:54 INFO - 'tart', 05:26:54 INFO - 'tscrollx', 05:26:54 INFO - 'cart', 05:26:54 INFO - 'tsvg_static']}, 05:26:54 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:26:54 INFO - 'talos_options': ['--disable-e10s'], 05:26:54 INFO - 'tests': ['tp5o']}, 05:26:54 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:26:54 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:26:54 INFO - 'talos_options': ['--disable-e10s', 05:26:54 INFO - '--xperf_path', 05:26:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:26:54 INFO - 'tests': ['tp5n']}, 05:26:54 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:26:54 INFO - 'talos_options': ['--xperf_path', 05:26:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:26:54 INFO - 'tests': ['tp5n']}}, 05:26:54 INFO - 'talos.zip': {'path': '', 05:26:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:26:54 INFO - Running post-action listener: _resource_record_post_action 05:26:54 INFO - [mozharness: 2017-06-09 12:26:54.594430Z] Finished populate-webroot step (success) 05:26:54 INFO - [mozharness: 2017-06-09 12:26:54.594543Z] Running create-virtualenv step. 05:26:54 INFO - Running pre-action listener: _resource_record_pre_action 05:26:54 INFO - Running main action method: create_virtualenv 05:26:54 INFO - Creating virtualenv /builds/slave/test/build/venv 05:26:54 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 05:26:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:26:54 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 05:26:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:26:54 INFO - Using real prefix '/tools/python27' 05:26:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:26:55 INFO - Installing distribute.............................................................................................................................................................................................done. 05:26:58 INFO - Installing pip.................done. 05:26:58 INFO - Return code: 0 05:26:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:26:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:26:58 INFO - Reading from file tmpfile_stdout 05:26:58 INFO - Output received: 05:26:58 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) 05:26:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:26:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:26:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:26:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:26:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:26:58 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 0x10eaf4c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaf8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc833c9be80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e84ad30>, '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 0x10e814cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e83bbe8>, '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.Y1HcDw7Fa7/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.2E6IVV5AYQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 05:26:58 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 05:26:58 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 05:26:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y1HcDw7Fa7/Render', 05:26:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:26:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:26:58 INFO - 'HOME': '/Users/cltbld', 05:26:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:26:58 INFO - 'LOGNAME': 'cltbld', 05:26:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:26:58 INFO - 'MOZ_NO_REMOTE': '1', 05:26:58 INFO - 'NO_EM_RESTART': '1', 05:26:58 INFO - 'PAGER': '/bin/cat', 05:26:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:26:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:26:58 INFO - 'PWD': '/builds/slave/test', 05:26:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:26:58 INFO - 'SHELL': '/bin/bash', 05:26:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2E6IVV5AYQ/Listeners', 05:26:58 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 05:26:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:26:58 INFO - 'USER': 'cltbld', 05:26:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:26:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:26:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:26:58 INFO - 'XPC_FLAGS': '0x0', 05:26:58 INFO - 'XPC_SERVICE_NAME': '0', 05:26:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:26:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:26:58 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 05:26:58 INFO - Cleaning up... 05:26:58 INFO - Return code: 0 05:26:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:26:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:26:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:26:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:26:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:26:58 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 0x10eaf4c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaf8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc833c9be80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e84ad30>, '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 0x10e814cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e83bbe8>, '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.Y1HcDw7Fa7/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.2E6IVV5AYQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 05:26:58 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 05:26:58 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 05:26:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y1HcDw7Fa7/Render', 05:26:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:26:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:26:58 INFO - 'HOME': '/Users/cltbld', 05:26:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:26:58 INFO - 'LOGNAME': 'cltbld', 05:26:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:26:58 INFO - 'MOZ_NO_REMOTE': '1', 05:26:58 INFO - 'NO_EM_RESTART': '1', 05:26:58 INFO - 'PAGER': '/bin/cat', 05:26:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:26:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:26:58 INFO - 'PWD': '/builds/slave/test', 05:26:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:26:58 INFO - 'SHELL': '/bin/bash', 05:26:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2E6IVV5AYQ/Listeners', 05:26:58 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 05:26:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:26:58 INFO - 'USER': 'cltbld', 05:26:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:26:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:26:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:26:58 INFO - 'XPC_FLAGS': '0x0', 05:26:58 INFO - 'XPC_SERVICE_NAME': '0', 05:26:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:26:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:26:59 INFO - Downloading/unpacking psutil>=3.1.1 05:26:59 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 05:26:59 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 05:26:59 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 05:26:59 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 05:27:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:27:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:27:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:27:01 INFO - Installing collected packages: psutil 05:27:01 INFO - Running setup.py install for psutil 05:27:01 INFO - building 'psutil._psutil_osx' extension 05:27:01 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 05:27:01 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 05:27:01 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 05:27:01 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 05:27:01 INFO - building 'psutil._psutil_posix' extension 05:27:01 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 05:27:01 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:27:01 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:27:01 INFO - ^ 05:27:02 INFO - 1 warning generated. 05:27:02 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 05:27:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:27:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:27:02 INFO - Successfully installed psutil 05:27:02 INFO - Cleaning up... 05:27:02 INFO - Return code: 0 05:27:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:27: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')]} 05:27:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:27: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')]} 05:27:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:27: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10eaf4c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaf8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc833c9be80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e84ad30>, '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 0x10e814cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e83bbe8>, '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.Y1HcDw7Fa7/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.2E6IVV5AYQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 05:27: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:27: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 mozsystemmonitor==0.3 05:27:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y1HcDw7Fa7/Render', 05:27:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:27:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:27:02 INFO - 'HOME': '/Users/cltbld', 05:27:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:27:02 INFO - 'LOGNAME': 'cltbld', 05:27:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:27:02 INFO - 'MOZ_NO_REMOTE': '1', 05:27:02 INFO - 'NO_EM_RESTART': '1', 05:27:02 INFO - 'PAGER': '/bin/cat', 05:27:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:27:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:27:02 INFO - 'PWD': '/builds/slave/test', 05:27:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:27:02 INFO - 'SHELL': '/bin/bash', 05:27:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2E6IVV5AYQ/Listeners', 05:27:02 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 05:27:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:27:02 INFO - 'USER': 'cltbld', 05:27:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:27:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:27:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:27:02 INFO - 'XPC_FLAGS': '0x0', 05:27:02 INFO - 'XPC_SERVICE_NAME': '0', 05:27:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:27:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:27:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:27:02 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 05:27:02 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 05:27:02 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 05:27:02 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 05:27:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:27:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:27:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:27:04 INFO - Installing collected packages: mozsystemmonitor 05:27:04 INFO - Running setup.py install for mozsystemmonitor 05:27:04 INFO - Successfully installed mozsystemmonitor 05:27:04 INFO - Cleaning up... 05:27:04 INFO - Return code: 0 05:27:04 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:27:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:27:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:27:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:27:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:27:04 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 0x10eaf4c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaf8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc833c9be80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e84ad30>, '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 0x10e814cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e83bbe8>, '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.Y1HcDw7Fa7/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.2E6IVV5AYQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 05:27:04 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 05:27:04 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 05:27:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y1HcDw7Fa7/Render', 05:27:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:27:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:27:04 INFO - 'HOME': '/Users/cltbld', 05:27:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:27:04 INFO - 'LOGNAME': 'cltbld', 05:27:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:27:04 INFO - 'MOZ_NO_REMOTE': '1', 05:27:04 INFO - 'NO_EM_RESTART': '1', 05:27:04 INFO - 'PAGER': '/bin/cat', 05:27:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:27:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:27:04 INFO - 'PWD': '/builds/slave/test', 05:27:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:27:04 INFO - 'SHELL': '/bin/bash', 05:27:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2E6IVV5AYQ/Listeners', 05:27:04 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 05:27:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:27:04 INFO - 'USER': 'cltbld', 05:27:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:27:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:27:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:27:04 INFO - 'XPC_FLAGS': '0x0', 05:27:04 INFO - 'XPC_SERVICE_NAME': '0', 05:27:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:27:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:27:04 INFO - Downloading/unpacking jsonschema==2.5.1 05:27:04 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 05:27:04 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 05:27:04 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 05:27:04 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 05:27:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:27:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:27:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:06 INFO - Downloading functools32-3.2.3-2.tar.gz 05:27:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:27:06 INFO - warning: no files found matching '*.txt' 05:27:06 INFO - no previously-included directories found matching 'build' 05:27:06 INFO - no previously-included directories found matching 'dist' 05:27:06 INFO - no previously-included directories found matching '.git*' 05:27:06 INFO - Installing collected packages: jsonschema, functools32 05:27:06 INFO - Running setup.py install for functools32 05:27:06 INFO - warning: no files found matching '*.txt' 05:27:06 INFO - no previously-included directories found matching 'build' 05:27:06 INFO - no previously-included directories found matching 'dist' 05:27:06 INFO - no previously-included directories found matching '.git*' 05:27:06 INFO - Successfully installed jsonschema functools32 05:27:06 INFO - Cleaning up... 05:27:06 INFO - Return code: 0 05:27:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:27: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')]} 05:27:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:27: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')]} 05:27:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:27: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', '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 0x10eaf4c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaf8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc833c9be80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e84ad30>, '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 0x10e814cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e83bbe8>, '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.Y1HcDw7Fa7/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.2E6IVV5AYQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 05:27: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:27: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 functools32==3.2.3-2 05:27:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y1HcDw7Fa7/Render', 05:27:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:27:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:27:06 INFO - 'HOME': '/Users/cltbld', 05:27:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:27:06 INFO - 'LOGNAME': 'cltbld', 05:27:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:27:06 INFO - 'MOZ_NO_REMOTE': '1', 05:27:06 INFO - 'NO_EM_RESTART': '1', 05:27:06 INFO - 'PAGER': '/bin/cat', 05:27:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:27:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:27:06 INFO - 'PWD': '/builds/slave/test', 05:27:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:27:06 INFO - 'SHELL': '/bin/bash', 05:27:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2E6IVV5AYQ/Listeners', 05:27:06 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 05:27:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:27:06 INFO - 'USER': 'cltbld', 05:27:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:27:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:27:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:27:06 INFO - 'XPC_FLAGS': '0x0', 05:27:06 INFO - 'XPC_SERVICE_NAME': '0', 05:27:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:27:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:27:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:27:06 INFO - Cleaning up... 05:27:06 INFO - Return code: 0 05:27:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:27: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')]} 05:27:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:27: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')]} 05:27:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:27: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', '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 0x10eaf4c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaf8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc833c9be80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e84ad30>, '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 0x10e814cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e83bbe8>, '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.Y1HcDw7Fa7/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.2E6IVV5AYQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 05:27: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:27: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 blobuploader==1.2.4 05:27:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y1HcDw7Fa7/Render', 05:27:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:27:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:27:06 INFO - 'HOME': '/Users/cltbld', 05:27:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:27:06 INFO - 'LOGNAME': 'cltbld', 05:27:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:27:06 INFO - 'MOZ_NO_REMOTE': '1', 05:27:06 INFO - 'NO_EM_RESTART': '1', 05:27:06 INFO - 'PAGER': '/bin/cat', 05:27:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:27:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:27:06 INFO - 'PWD': '/builds/slave/test', 05:27:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:27:06 INFO - 'SHELL': '/bin/bash', 05:27:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2E6IVV5AYQ/Listeners', 05:27:06 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 05:27:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:27:06 INFO - 'USER': 'cltbld', 05:27:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:27:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:27:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:27:06 INFO - 'XPC_FLAGS': '0x0', 05:27:06 INFO - 'XPC_SERVICE_NAME': '0', 05:27:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:27:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:27:06 INFO - Downloading/unpacking blobuploader==1.2.4 05:27:06 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 05:27:06 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 05:27:06 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 05:27:06 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 05:27:08 INFO - Downloading blobuploader-1.2.4.tar.gz 05:27:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:27:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:27: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 05:27: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 05:27: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 05:27: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 05:27:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:27:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:27: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 05:27: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 05:27: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 05:27: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 05:27:09 INFO - Downloading docopt-0.6.1.tar.gz 05:27:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:27:09 INFO - Installing collected packages: blobuploader, requests, docopt 05:27:09 INFO - Running setup.py install for blobuploader 05:27:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:27:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:27:09 INFO - Running setup.py install for requests 05:27:09 INFO - Running setup.py install for docopt 05:27:10 INFO - Successfully installed blobuploader requests docopt 05:27:10 INFO - Cleaning up... 05:27:10 INFO - Return code: 0 05:27:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:27: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')]} 05:27:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:27: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')]} 05:27:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:27:10 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 0x10eaf4c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaf8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc833c9be80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e84ad30>, '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 0x10e814cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e83bbe8>, '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.Y1HcDw7Fa7/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.2E6IVV5AYQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 05:27:10 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 05:27:10 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 05:27:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y1HcDw7Fa7/Render', 05:27:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:27:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:27:10 INFO - 'HOME': '/Users/cltbld', 05:27:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:27:10 INFO - 'LOGNAME': 'cltbld', 05:27:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:27:10 INFO - 'MOZ_NO_REMOTE': '1', 05:27:10 INFO - 'NO_EM_RESTART': '1', 05:27:10 INFO - 'PAGER': '/bin/cat', 05:27:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:27:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:27:10 INFO - 'PWD': '/builds/slave/test', 05:27:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:27:10 INFO - 'SHELL': '/bin/bash', 05:27:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2E6IVV5AYQ/Listeners', 05:27:10 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 05:27:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:27:10 INFO - 'USER': 'cltbld', 05:27:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:27:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:27:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:27:10 INFO - 'XPC_FLAGS': '0x0', 05:27:10 INFO - 'XPC_SERVICE_NAME': '0', 05:27:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:27:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:27:10 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-XalbbT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:27:10 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-EpKw3K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:27:10 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-Mjem8F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:27:10 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-sJiXcq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:27:10 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-UVpLJW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:27:10 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-yVAfW7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:27:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:27:11 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-IyD5Yc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:27:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:27:11 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-zXp2Al-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:27:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:27:11 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-EPcp0W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:27:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:27:11 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-sxKJNC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:27:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:27:11 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-Myvc6R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:27:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:27:11 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-lKJ2Sv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:27:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:27:11 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-ukFzBO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:27:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:27:11 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-4tePeX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:27:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:27:11 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-FaLriJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:27:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:27:11 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-Mt7Xry-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:27:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:27:12 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-H2siJd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:27:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:27:12 INFO - Running setup.py install for manifestparser 05:27:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:27:12 INFO - Running setup.py install for mozcrash 05:27:12 INFO - Running setup.py install for mozdebug 05:27:12 INFO - Running setup.py install for mozdevice 05:27:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:27:12 INFO - Running setup.py install for mozfile 05:27:12 INFO - Running setup.py install for mozhttpd 05:27:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:27:13 INFO - Running setup.py install for mozinfo 05:27:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:27:13 INFO - Running setup.py install for mozInstall 05:27:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:27:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:27:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:27:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:27:13 INFO - Running setup.py install for mozleak 05:27:13 INFO - Running setup.py install for mozlog 05:27:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:27:13 INFO - Running setup.py install for moznetwork 05:27:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:27:13 INFO - Running setup.py install for mozprocess 05:27:13 INFO - Running setup.py install for mozprofile 05:27:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:27:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:27:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:27:14 INFO - Running setup.py install for mozrunner 05:27:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:27:14 INFO - Running setup.py install for mozscreenshot 05:27:14 INFO - Running setup.py install for moztest 05:27:14 INFO - Running setup.py install for mozversion 05:27:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:27:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:27:14 INFO - Cleaning up... 05:27:14 INFO - Return code: 0 05:27:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:27: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')]} 05:27:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:27: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')]} 05:27:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:27:14 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 0x10eaf4c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaf8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc833c9be80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e84ad30>, '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 0x10e814cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e83bbe8>, '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.Y1HcDw7Fa7/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.2E6IVV5AYQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 05:27:14 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 05:27:14 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 05:27:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y1HcDw7Fa7/Render', 05:27:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:27:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:27:14 INFO - 'HOME': '/Users/cltbld', 05:27:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:27:14 INFO - 'LOGNAME': 'cltbld', 05:27:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:27:14 INFO - 'MOZ_NO_REMOTE': '1', 05:27:14 INFO - 'NO_EM_RESTART': '1', 05:27:14 INFO - 'PAGER': '/bin/cat', 05:27:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:27:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:27:14 INFO - 'PWD': '/builds/slave/test', 05:27:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:27:14 INFO - 'SHELL': '/bin/bash', 05:27:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2E6IVV5AYQ/Listeners', 05:27:14 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 05:27:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:27:14 INFO - 'USER': 'cltbld', 05:27:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:27:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:27:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:27:14 INFO - 'XPC_FLAGS': '0x0', 05:27:14 INFO - 'XPC_SERVICE_NAME': '0', 05:27:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:27:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:27:15 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-FR1a37-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:27:15 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)) 05:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:27:15 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-IQRdS0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:27:15 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)) 05:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:27:15 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-K40Xbi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:27:15 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)) 05:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:27:15 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-9xblsP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:27:15 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)) 05:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:27:15 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-IXB99j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:27:15 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)) 05:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:27:15 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-iADTKM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:27:15 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)) 05:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:27:15 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-Xe8qFv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:27:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:27:15 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-cq1OgI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:27:15 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)) 05:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:27:15 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-B3wexa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:27:15 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)) 05:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:27:15 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-JUvCNi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:27:16 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)) 05:27:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:27:16 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-mcowTF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:27:16 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)) 05:27:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:27:16 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-1rpksC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:27:16 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)) 05:27:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:27:16 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-_iZQKE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:27:16 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)) 05:27:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:27:16 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-SAQewU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:27:16 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)) 05:27:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:27:16 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-ga4xeT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:27:16 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)) 05:27:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:27:16 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-eSrAOw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:27:16 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)) 05:27:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:27:16 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-OjcD9g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:27:16 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)) 05:27:16 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)) 05:27:16 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)) 05:27:16 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)) 05:27:16 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)) 05:27:16 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)) 05:27:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:27:16 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 05:27:16 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 05:27:16 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 05:27:16 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 05:27:18 INFO - Downloading blessings-1.6.tar.gz 05:27:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:27:18 INFO - Installing collected packages: blessings 05:27:18 INFO - Running setup.py install for blessings 05:27:18 INFO - Successfully installed blessings 05:27:18 INFO - Cleaning up... 05:27:18 INFO - Return code: 0 05:27:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:27:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:27:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:27:18 INFO - Reading from file tmpfile_stdout 05:27:18 INFO - Current package versions: 05:27:18 INFO - blessings == 1.6 05:27:18 INFO - blobuploader == 1.2.4 05:27:18 INFO - docopt == 0.6.1 05:27:18 INFO - functools32 == 3.2.3-2 05:27:18 INFO - jsonschema == 2.5.1 05:27:18 INFO - manifestparser == 1.1 05:27:18 INFO - mozInstall == 1.12 05:27:18 INFO - mozcrash == 1.0 05:27:18 INFO - mozdebug == 0.1 05:27:18 INFO - mozdevice == 0.51 05:27:18 INFO - mozfile == 1.2 05:27:18 INFO - mozhttpd == 0.7 05:27:18 INFO - mozinfo == 0.10 05:27:18 INFO - mozleak == 0.1 05:27:18 INFO - mozlog == 3.4 05:27:18 INFO - moznetwork == 0.27 05:27:18 INFO - mozprocess == 0.25 05:27:18 INFO - mozprofile == 0.28 05:27:18 INFO - mozrunner == 6.13 05:27:18 INFO - mozscreenshot == 0.1 05:27:18 INFO - mozsystemmonitor == 0.3 05:27:18 INFO - moztest == 0.8 05:27:18 INFO - mozversion == 1.4 05:27:18 INFO - psutil == 3.1.1 05:27:18 INFO - requests == 1.2.3 05:27:18 INFO - wsgiref == 0.1.2 05:27:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:27:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:27:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:27:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:27:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:27:18 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 0x10eaf4c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaf8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc833c9be80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e84ad30>, '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 0x10e814cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e83bbe8>, '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.Y1HcDw7Fa7/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.2E6IVV5AYQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 05:27:18 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 05:27:18 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 05:27:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y1HcDw7Fa7/Render', 05:27:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:27:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:27:18 INFO - 'HOME': '/Users/cltbld', 05:27:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:27:18 INFO - 'LOGNAME': 'cltbld', 05:27:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:27:18 INFO - 'MOZ_NO_REMOTE': '1', 05:27:18 INFO - 'NO_EM_RESTART': '1', 05:27:18 INFO - 'PAGER': '/bin/cat', 05:27:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:27:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:27:18 INFO - 'PWD': '/builds/slave/test', 05:27:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:27:18 INFO - 'SHELL': '/bin/bash', 05:27:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2E6IVV5AYQ/Listeners', 05:27:18 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 05:27:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:27:18 INFO - 'USER': 'cltbld', 05:27:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:27:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:27:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:27:18 INFO - 'XPC_FLAGS': '0x0', 05:27:18 INFO - 'XPC_SERVICE_NAME': '0', 05:27:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:27:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:27:19 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)) 05:27:19 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)) 05:27:19 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)) 05:27:19 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)) 05:27:19 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)) 05:27:19 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)) 05:27:19 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)) 05:27:19 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)) 05:27:19 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)) 05:27:19 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 05:27:19 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 05:27:19 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 05:27:19 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 05:27:19 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 05:27:20 INFO - Downloading marionette_driver-1.4.0.tar.gz 05:27:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 05:27:20 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)) 05:27:20 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)) 05:27:20 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)) 05:27:20 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)) 05:27:20 INFO - Installing collected packages: marionette-driver 05:27:20 INFO - Running setup.py install for marionette-driver 05:27:20 INFO - Successfully installed marionette-driver 05:27:20 INFO - Cleaning up... 05:27:20 INFO - Return code: 0 05:27:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:27:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:27:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:27:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:27:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:27:20 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 0x10eaf4c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaf8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc833c9be80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e84ad30>, '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 0x10e814cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e83bbe8>, '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.Y1HcDw7Fa7/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.2E6IVV5AYQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 05:27:20 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 05:27:20 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 05:27:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y1HcDw7Fa7/Render', 05:27:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:27:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:27:20 INFO - 'HOME': '/Users/cltbld', 05:27:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:27:20 INFO - 'LOGNAME': 'cltbld', 05:27:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:27:20 INFO - 'MOZ_NO_REMOTE': '1', 05:27:20 INFO - 'NO_EM_RESTART': '1', 05:27:20 INFO - 'PAGER': '/bin/cat', 05:27:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:27:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:27:20 INFO - 'PWD': '/builds/slave/test', 05:27:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:27:20 INFO - 'SHELL': '/bin/bash', 05:27:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2E6IVV5AYQ/Listeners', 05:27:20 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 05:27:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:27:20 INFO - 'USER': 'cltbld', 05:27:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:27:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:27:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:27:20 INFO - 'XPC_FLAGS': '0x0', 05:27:20 INFO - 'XPC_SERVICE_NAME': '0', 05:27:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:27:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:27:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:27:21 INFO - Cleaning up... 05:27:21 INFO - Return code: 0 05:27:21 INFO - Running post-action listener: _resource_record_post_action 05:27:21 INFO - Running post-action listener: _start_resource_monitoring 05:27:21 INFO - Starting resource monitoring. 05:27:21 INFO - [mozharness: 2017-06-09 12:27:21.149887Z] Finished create-virtualenv step (success) 05:27:21 INFO - [mozharness: 2017-06-09 12:27:21.150819Z] Running install step. 05:27:21 INFO - Running pre-action listener: _resource_record_pre_action 05:27:21 INFO - Running main action method: install 05:27:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:27:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:27:21 INFO - Reading from file tmpfile_stdout 05:27:21 INFO - Detecting whether we're running mozinstall >=1.0... 05:27:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:27:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:27:21 INFO - Reading from file tmpfile_stdout 05:27:21 INFO - Output received: 05:27:21 INFO - Usage: mozinstall [options] installer 05:27:21 INFO - Options: 05:27:21 INFO - -h, --help show this help message and exit 05:27:21 INFO - -d DEST, --destination=DEST 05:27:21 INFO - Directory to install application into. [default: 05:27:21 INFO - "/builds/slave/test"] 05:27:21 INFO - --app=APP Application being installed. [default: firefox] 05:27:21 INFO - mkdir: /builds/slave/test/build/application 05:27:21 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'] 05:27:21 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 05:27:43 INFO - Reading from file tmpfile_stdout 05:27:43 INFO - Output received: 05:27:43 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:27:43 INFO - Running post-action listener: _resource_record_post_action 05:27:43 INFO - [mozharness: 2017-06-09 12:27:43.555239Z] Finished install step (success) 05:27:43 INFO - [mozharness: 2017-06-09 12:27:43.555413Z] Running setup-mitmproxy step. 05:27:43 INFO - Running pre-action listener: _resource_record_pre_action 05:27:43 INFO - Running main action method: setup_mitmproxy 05:27:43 INFO - Skipping: mitmproxy is not required 05:27:43 INFO - Running post-action listener: _resource_record_post_action 05:27:43 INFO - [mozharness: 2017-06-09 12:27:43.556079Z] Finished setup-mitmproxy step (success) 05:27:43 INFO - [mozharness: 2017-06-09 12:27:43.556211Z] Running run-tests step. 05:27:43 INFO - Running pre-action listener: _resource_record_pre_action 05:27:43 INFO - Running main action method: run_tests 05:27:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:27:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:27:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:27:43 INFO - 2017-06-09 05:27:43.571 screenresolution[1965:10946] starting screenresolution argv=screenresolution get 05:27:43 INFO - 2017-06-09 05:27:43.573 screenresolution[1965:10946] Display 0: 1600x1200x32@60 05:27:43 INFO - 2017-06-09 05:27:43.579 screenresolution[1966:10949] starting screenresolution argv=screenresolution list 05:27:43 INFO - Available Modes on Display 0 05:27:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:27:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:27:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:27:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:27:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:27:43 INFO - Intel Iris: 05:27:43 INFO - Chipset Model: Intel Iris 05:27:43 INFO - Type: GPU 05:27:43 INFO - Bus: Built-In 05:27:43 INFO - VRAM (Dynamic, Max): 1536 MB 05:27:43 INFO - Vendor: Intel (0x8086) 05:27:43 INFO - Device ID: 0x0a2e 05:27:43 INFO - Revision ID: 0x0009 05:27:43 INFO - Displays: 05:27:43 INFO - Display: 05:27:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:27:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:27:43 INFO - Display Serial Number: 1600x1200 60 05:27:43 INFO - Main Display: Yes 05:27:43 INFO - Mirror: Off 05:27:43 INFO - Online: Yes 05:27:43 INFO - Rotation: Supported 05:27:43 INFO - Return code: 0 05:27:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:27:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:27:43 INFO - Python 2.7.3 05:27:43 INFO - Return code: 0 05:27:43 INFO - grabbing minidump binary from tooltool 05:27:43 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')]} 05:27:43 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 0x10e84ad30>, '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 0x10e814cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e83bbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:27:43 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 05:27:43 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 05:27:43 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 05:27:43 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:27:43 INFO - Return code: 0 05:27:43 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:27:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:27:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:27:43 INFO - ENV: RUST_BACKTRACE is now 1 05:27:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:27:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:27:43 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/Wx3sesJGQgqAxd-UY1QTGg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0141', '--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 05:27:43 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/Wx3sesJGQgqAxd-UY1QTGg/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0141 --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 05:27:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y1HcDw7Fa7/Render', 05:27:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:27:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:27:43 INFO - 'HOME': '/Users/cltbld', 05:27:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:27:43 INFO - 'LOGNAME': 'cltbld', 05:27:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:27:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:27:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:27:43 INFO - 'MOZ_NO_REMOTE': '1', 05:27:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:27:43 INFO - 'NO_EM_RESTART': '1', 05:27:43 INFO - 'PAGER': '/bin/cat', 05:27:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:27:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:27:43 INFO - 'PWD': '/builds/slave/test', 05:27:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:27:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:27:43 INFO - 'RUST_BACKTRACE': '1', 05:27:43 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 05:27:43 INFO - 'SHELL': '/bin/bash', 05:27:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2E6IVV5AYQ/Listeners', 05:27:43 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 05:27:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:27:43 INFO - 'USER': 'cltbld', 05:27:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:27:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:27:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:27:43 INFO - 'XPC_FLAGS': '0x0', 05:27:43 INFO - 'XPC_SERVICE_NAME': '0', 05:27:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:27:43 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/Wx3sesJGQgqAxd-UY1QTGg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0141', '--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 05:27:44 INFO - mozversion application_buildid: 20170609035954 05:27:44 INFO - mozversion application_changeset: 7c9d96bbc400aa77f5310486162abf6b61344b74 05:27:44 INFO - mozversion application_display_name: Nightly 05:27:44 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:27:44 INFO - mozversion application_name: Firefox 05:27:44 INFO - mozversion application_remotingname: firefox 05:27:44 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:27:44 INFO - mozversion application_vendor: Mozilla 05:27:44 INFO - mozversion application_version: 55.0a1 05:27:44 INFO - mozversion platform_buildid: 20170609035954 05:27:44 INFO - mozversion platform_changeset: 7c9d96bbc400aa77f5310486162abf6b61344b74 05:27:44 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:27:44 INFO - mozversion platform_version: 55.0a1 05:27:44 INFO - using testdate: 1497011264 05:27:44 INFO - actual date: 1497011264 05:27:44 INFO - starting webserver on 'localhost:49346' 05:27:44 INFO - SUITE-START | Running 2 tests 05:27:44 INFO - TEST-START | dromaeo_css 05:27:44 INFO - operating with platform_type : mac_ 05:27:44 INFO - Initialising browser for dromaeo_css test... 05:27:44 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49346/getInfo.html -foreground -profile /var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/tmpR7wgUN/profile 05:27:44 INFO - TEST-INFO | started process 1976 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49346/getInfo.html -foreground) 05:28:17 INFO - TEST-INFO | 1976: killed by SIGTERM 05:28:17 INFO - Browser initialized. 05:28:17 INFO - Running cycle 1/1 for dromaeo_css test... 05:28:17 INFO - TEST-INFO | started process 1983 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/tmpR7wgUN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:28:20 INFO - PID 1983 | 2017-06-09 05:28:20.154 plugin-container[1984:11537] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9843, name = 'com.apple.tsm.portname' 05:28:20 INFO - PID 1983 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:28:20 INFO - PID 1983 | 2017-06-09 05:28:20.155 plugin-container[1984:11537] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b0b, name = 'com.apple.CFPasteboardClient' 05:28:20 INFO - PID 1983 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:28:20 INFO - PID 1983 | 2017-06-09 05:28:20.155 plugin-container[1984:11537] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:28:20 INFO - PID 1983 | 2017-06-09 05:28:20.578 plugin-container[1985:11584] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x953f, name = 'com.apple.tsm.portname' 05:28:20 INFO - PID 1983 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:28:20 INFO - PID 1983 | 2017-06-09 05:28:20.579 plugin-container[1985:11584] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x990b, name = 'com.apple.CFPasteboardClient' 05:28:20 INFO - PID 1983 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:28:20 INFO - PID 1983 | 2017-06-09 05:28:20.579 plugin-container[1985:11584] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:28:29 INFO - PID 1983 | RSS: Main: 253681664 05:28:29 INFO - PID 1983 | 05:31:31 INFO - PID 1983 | Cycle 1(1): loaded http://localhost:49346/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49346/tests/dromaeo/cssquery-ext.html) 05:31:33 INFO - PID 1983 | RSS: Main: 264740864 05:31:33 INFO - PID 1983 | 05:34:35 INFO - PID 1983 | Cycle 1(1): loaded http://localhost:49346/tests/dromaeo/cssquery-ext.html (next: http://localhost:49346/tests/dromaeo/cssquery-jquery.html) 05:34:36 INFO - PID 1983 | RSS: Main: 279011328 05:34:36 INFO - PID 1983 | 05:37:37 INFO - PID 1983 | Cycle 1(1): loaded http://localhost:49346/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49346/tests/dromaeo/cssquery-mootools.html) 05:37:38 INFO - PID 1983 | RSS: Main: 278896640 05:37:38 INFO - PID 1983 | 05:40:40 INFO - PID 1983 | Cycle 1(1): loaded http://localhost:49346/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49346/tests/dromaeo/cssquery-prototype.html) 05:40:41 INFO - PID 1983 | RSS: Main: 281653248 05:40:41 INFO - PID 1983 | 05:43:44 INFO - PID 1983 | Cycle 1(1): loaded http://localhost:49346/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49346/tests/dromaeo/cssquery-yui.html) 05:43:44 INFO - PID 1983 | RSS: Main: 280612864 05:43:44 INFO - PID 1983 | 05:46:46 INFO - PID 1983 | Cycle 1(1): loaded http://localhost:49346/tests/dromaeo/cssquery-yui.html (next: http://localhost:49346/tests/dromaeo/cssquery-dojo.html) 05:46:46 INFO - PID 1983 | RSS: Main: 281886720 05:46:46 INFO - PID 1983 | 05:46:46 INFO - PID 1983 | __start_tp_report 05:46:46 INFO - PID 1983 | _x_x_mozilla_page_load 05:46:46 INFO - PID 1983 | _x_x_mozilla_page_load_details 05:46:46 INFO - PID 1983 | |i|pagename|runs| 05:46:46 INFO - PID 1983 | |0;dojo.html;3032.8635211415485;3573.8749143779955;3605.56733192017;3606.99004278802;3614.8734794282195;6145.17040199573;6170.012797952329;6201.8759624807535;6224;6258.155149054876;4839.951600483994;6166.3525329840395;6204.317525072239;6255.687215639223;6277.340879207686;5245.816396426126;5397.568194544437;5461.754221060042;5464.4262352453015;5494.862628434281;5536.916946245789;5568.1091025435935;5582.9950608890385;5603.3836278009385;5626.071698169797;5395.973020134914;5431.565474762009;5465.759038794075;5493.7802487900435;5506.587005974568;5489.066858634042;5493.862653433656;5508.944910550923;5516.200150978126;5523.28197334348;5470.8085217017215;5472.91790623141;5473.698946557938;5487.560995120421;5489.807856724996;6137.631742095444;6158.04550294705;6189.938100619026;6216.813495595139;6325.462335701471;5897;6183.6598987055695;6217.658028808413;6713.832154196184;6724.462043036546;5686.2607860977805;6798.796036118924;6807.4894382921475;6809.421199198074;6810.489213309022;6236.220472440945;6292.559520833498;6348.936510634927;6366.84082897922;6371.553991220663;6861.142357205349;6920.861582768316;6949.061876646689;6965.79102626922;6976.162860456778;6299.275583307886;6431.228252609623;6507.739690412331;6584.473242140617;6599.076129341896;6781.42357899584;6813.3527314905;6824.7952561423235;6835.111435513351;6836.316368363124;6430.807075787734;6495.772647957298;6662.002099055348;6699.062131301622;6724.159480064992;6593.044008618819;6607.570507917067;6696.8995465068065;6723.0923825283935;6747.05541224229;4815.662903596784;5304.124819404755;5341.198820177005;5385.138377859524;5394;4300;4657.371254880548;4758.476567577563;4777.928331075037;4808.687435316693;5837.474627283565;5861.824145275648;6318.778842740482;6373.203349581302;6386.489080873519;6775.525713200029;6787.728490860409;6791.830204244835;6807.9319206807295;6810.62541560219;6340.048992651047;6357.427831495188;6380.425761681471;6399.392057754506;6403.231612206565;5358.713908661893;5777.913331300034;5783.942160578425;5987.9101813472835;6031.698415079229;6956.2000369958105;6978.546394484342;6983.162020557464;6984.266652001568;7002.7899163025195;6778.546885115362;6790.32096790317;6798.388145066968;6808.557443766139;6823.590584564942;6718.361755633207;6795.660216989229;6854.451643868579;6948.374646281657;6972.198197207386;6624.105745724266;6636.7013484392355;6670.366315200048;6696.933030669826;6697.531172817856;6580.078788969644;6795.7961261162245;6799.728010879509;6820.62486563224;6842.24735279109;6061;6118.4668989547035;6389.041643753474;6593.241777195492;6616.66916654175;6575.605463672195;6583.572067815577;6613.966930165511;6619.801405957829;6620.337966203341;6381.3937675920715;6470.288268290578;6479.384458476437;6490.837729056622;6502.869942601216;5375.865603359947;5682.261306030189;6506.4469520091325;6554.63949482783;6590.176227971504;6369.108324834619;6376.681165941777;6390.297067322684;6392.232932048184;6404.647744373918 05:46:46 INFO - PID 1983 | |1;ext.html;656.763565116558;777.2538363171356;850.91065438129;1238.3374894431481;1302.4464602543917;32400.837995809987;38646.420303695464;39719.40420893685;39730.60942867004;39779.01104944756;40202;40652.79673601628;40662;40686.59313406865;40718;7842.058952925643;8310.501369917823;8514.318854491657;8519.446235994672;8548.102449242833;8436.325093992466;8531.360147988895;8537.018242902084;8540.829183416328;8541.14588541144;7550.4337174711845;8429.452085614446;8447.95776021122;8530.146985301482;8586.914130858706;8424.705135320295;8494.40539162259;8509.872351914726;8533.104024077507;8561.914380856175;8207.95896020522;8406.243438090602;8440.83118337636;8467.49195048299;8474.025487068942;31313;32446;37460.438093428624;37780.43329350062;37870;3514.9297014059575;3551.94672079919;3552.7335449841107;3556.3242983833247;3561;30348.84825575858;30704.38591228163;30757.53863692024;30787.538186927213;30911.07266782;3058.0520038787827;3079.63044434667;3086.783925125242;3097.0863595239234;3130.6086739157604;254784;256403;284794.57602712064;298136;299783;48777.51222487801;49061.50938490641;49455.75272123616;49460;49467.75266123646;51418.74290628523;51499;51534.74232628813;51540.48459515357;52061.95876082533;14690.11859288446;14737.557873263819;14814.62963425923;14844;14863.70272594542;11858.584949526725;11937.820932686016;11945;11958.88041119595;11979.981701555465;14395.856041439452;14445.711085778436;14487.85512144865;14530.200838953959;14531.491397801026;5301.3373328333955;6291.961826298701;6299.590526615755;6331.271903731038;6371.96814015927;929.874466946967;960.8030353777599;965.2519297544459;990.6780296403698;1058.1799105693149;23987.880060599586;32499;32863.83568082192;32998.50502242468;33398.998030058654;1095.6767753512574;1095.7589330347312;1129.3506233915532;1140.0879296562716;1158.44394690549;10936.179786516042;12339.69150771238;12531.749365012649;12575.056870734548;12634.483861936626;330462;330766;332969;333709;335142;56599;56604.58488537819;56927.715361422925;57117.42882571204;57175.42824571784;1509.7810817431412;1510.184500369788;1540.091346105803;1547.3733138079097;1552.7826104345402;22927;23491.060357585506;23541.411464713517;24320.391990199678;24554.75445245596;35095.47356789599;40492.595074048884;40502.79748601238;40539;40547;286570;287588;289334;289461;290372;36707.71522996682;36941.63058369382;37230.6276937238;37276.813615931744;37287.44068839024;44893;46861.53138468708;47010;47010.05979880452;47095.293570595786;29140.445547267333;29578.852105739334;30090.84954575213;30789.846050769604;31254;29852.656630452115;30737.385252295277;31305.373892521566;31618.683813161573;31634.99467550579;36700.816495918414;37880.621193788815;38519.614803851604;38657.2268554633;38675.6132438672;29893.402131957675;29954.700452995192;30181.547276790425;31155.532667010466;31156.844215778776;23421.648675269545;25036;25214.495710086063;25448.745512544636;25486.617700734874 05:46:46 INFO - PID 1983 | |2;jquery.html;441.85418811792107;500.57017608814783;502.9094762942671;515.1371452816533;528.5454509122156;21725.565488690216;22382.10471581137;23029.769702302972;23311.950962206698;24818.627720584205;23628.05487780487;24144;24346.02615895362;24441.388965275833;24483.877580612116;6448.7742928997495;6578.736850525973;6595.670216489157;6602.8679426411445;6618.172728408949;6174.50603951685;6483.773067942623;6525.347465253484;6536.117624120754;6539.542232043759;6410.679466026703;6473.5144864135145;6481.2222533295835;6487.221533415996;6487.513436492259;6469.12666789982;6470.9029364559565;6479.611223326592;6496.870062598745;6511.9348806512035;5864.472197502245;5883.79406720767;5921.319048309457;5922.58541902067;5926.140709597127;30231.395372092436;30251.243718906982;30265.697343026728;30633;31017.379652406824;2244.4052326133587;2278.111536500766;2278.726552813673;2279.2022792022826;2282.931512054641;13766.31168441574;19197.232110715555;19495.02524873765;19787.604247915104;19799.703004454946;2189.2228258968;2235.4970131720397;2272.4091736148557;2279.87460689662;2291.7937385635373;163754;171147.99999999875;202498;204490;212809.99999999846;12354.75290494185;15233.771493427608;15236.466723664618;15578.376864925503;15606.29771660278;16975.405860794814;20201.080897314732;20465.693014604793;20567.280145194847;20575.485612859797;9912.851307230398;9928.652497162564;9939.155171810333;9942.701718948443;10002.449865257335;7508.812279693051;7575.469717119749;8972.013078561353;9069.274458043341;9092.772680683036;7954.886315915776;9194.218491428304;9203.539823008823;9219.631214751469;9295.442273463614;2613.1376645706873;2640.8943642254094;2643;2646.550086485264;2788.1914244868763;943.740471370365;952.319091849331;959.2794658513138;991.7850632974693;1003.9799204015878;17186;20148.899255503628;20782.58434831295;21228;21850.562988740137;980.7155924781733;987.980240395188;1015.6851376073441;1016.7712264740522;1028.6039874648166;9903.356281841658;11084.944575277234;11264.380459074666;13685;14783.70432591342;573875;577479;578469;588203;590871;120688;140246.2987685055;145859.5414045846;146293;147910;1019.887812340642;1072.1904961753826;1139.3619573039125;1193.91045671575;1216.173002358387;21744.89127554352;22697.09211631516;24529.632055519538;24695.62956555689;25261;68021.89522426749;69742;70266;71079.57840843257;71916;80870.59564702139;91186;91308.54345728229;91964;92056.53971730366;11381.43092845371;11473.655790326304;11965.641030769091;11980.460879260629;12099.939500302442;18619.906900465412;18729.063546822334;18765.718514222026;18776.436706898814;18911.621767564848;15185;15315.310811013755;15367.231638418258;15386.99984501004;15408.922955385151;12309.44607492647;13430.93284533571;13745.793813092612;13868.791968120688;14322.355494002586;15326.846731532542;15413.614659633151;15679.137647429501;15724.685506289581;15724.977876437995;11794.115441342105;11847.229930054518;11947.581834635746;11971.281723096643;11984.221025633306;12938.835504804612;15007.549773506813;15255.313510891814;15265.23673816327;15279.77080343818 05:46:46 INFO - PID 1983 | |3;mootools.html;1161.3032180691584;1304.708338744643;1428.0860249440366;1435.6267370483677;1451.542764029331;13250.933745331273;13465.730685386286;13850.376733047013;13850.961177911659;13872.167669939785;13478.595642130751;13508.729825403487;13561.389737461814;13668.726625467485;13677.179369237852;2370.7036620422446;2605.413781899067;2694.622752814603;2737.288305040694;2739.9726002740017;2181.640029395148;2236.027328112268;2333.801626861719;2604.0365064926004;2693.2862791360303;2493.7256901740793;2504.7100743117308;2728.208819442369;2755.7519823215907;2764.378014946641;2684.7311527583115;2690.9596356054676;2697.608846717225;2701.4597080583962;2710.214037928998;2110.7889211078923;2677.8393296402082;2681.423493948791;2682.1685277563824;2689.072270066824;13161.341932903317;13478.932605337008;13509.121907076107;13518.526851560246;13525.458981640722;7336;7589;7593.202713715035;7623.161452240249;7639.656215470317;4009.777018009506;4547.613452856511;4726.291056341542;4844.709317440929;4846.7576621169155;1362.50949658123;1387.2300873015472;1400.7128538649552;1415.745165870143;1429.3853642933534;15907.20463976808;19779.90110049455;19801.702974455395;19922.103505342293;19952;6280.55547224141;6328.95572230586;6510.08858759774;6546.803595892131;6559.376859198369;14017.579472615838;14108;14121.011529193063;14150.29248537569;14194.716105677828;7188.35304822558;7336.266373362728;7343.522671026366;7348.816279593053;7357.153927298322;6569.474442044625;6708.563943343667;6732.697028633724;6799.796006119825;6809.761658341934;4871.707697538159;5032;5098.439171691111;5118.436971933084;5125.615578831603;1313.080843409617;1357.6470117769254;1418.3121186224673;1453.6147920800997;1475.3508456279417;803.9638216280165;824.0729179672868;835.444429454406;838.983220335602;862.5773371048141;7231.566106033655;7637.274458926393;7737.264959828807;7803.20526278959;7826.8434631307055;955.7753927826955;981.215027977615;987.6740675577042;990.5641517732175;991.137710192123;4674.789634466457;4907.484714105039;5091.694498330112;5142.665726727751;5229.895402091937;17737.113144342733;17823.37618183383;18235.361762338256;18240.087995600166;18497.81502184961;17999.73000404995;18056.548586285186;18090.90954545219;18154.90922545379;18156.36452724175;9401.858972115424;9451.574679139318;9512.571934262975;9534.618615255451;9559.091886270793;7537.886931696139;7616.466847320856;7702.0748995414815;7719.884201736866;7948.761537153895;15960.441384551485;15964.962277451928;16172.757408638645;16285.18574071268;16293.348266069224;16552.42066527714;16692.24884880159;16707.331706731595;16753.83246167523;16847.578810529834;9758.9753075926;9890.653827116017;9902.950485247527;9903.207743380657;9926.404415735078;12266.815997759863;12400.56598019065;12426;12437.315947622606;12535.686607834878;9321.161095501166;9815.55829987638;10244.590216391261;10362.585496580052;10397.272190946562;9992.400455972665;10052.69841904744;10065.345752526062;10122.139618132247;10161.695149145537;9124.5437728112;9289.303302252223;9352.111549403067;9354.251659867059;9357.344985850925;5847.363448103657;5941.287045554561;6170.10533472653;6657.1345725055435;7066.187388450394;5845.255037075976;6361.491080713439;6828.248892621907;7003.089598352181;7058.6470676467 05:46:46 INFO - PID 1983 | |4;prototype.html;452.18155139198046;454.62720569133313;463.9605633521153;464.1645038929925;482.27899290560595;5111.718855462948;6292.244930608322;6432.5497215194955;6503.861824180767;6505.577137486066;5419.810306639269;5586.692731899744;6170.704152128047;6311.431971122598;6328.272248691402;4625.537446255381;4769.4515130760055;4773.331733557305;4805.903881922368;4818.87952801181;3981.940270895924;4781.055629105984;4797.424309082898;4802.303665968432;4824.831130910401;4725.787339569728;4733.219018861883;4736.71579705217;4747.786349614258;4759.857204283877;4750.432357322075;4751.857444276678;4758.666893317469;4762.64280178988;4783.880402989936;4186.330187170038;4191.993921458828;4193.853215137486;4201.327787554007;4208.558101399371;5272.815451459181;6212.627242365473;6238.095476155977;6245.657878295639;6267.655278959655;4750.857474275777;5677.6309539880185;5677.914831277534;5682.119271512922;5719.942800571983;5844.883102337972;5968.522518198577;6194.079835251042;6836.794896153124;6856.760013399557;4747.45404278509;4772.665913386064;4772.832950846704;4800.807967681289;4807.8364735869;6368.936310636881;6868.278830722802;7125.289930416715;7190.173130090055;7263.927360726378;4262.744235345889;4500.932486012713;4820.542048505386;4823.879403014953;4833.564979151894;4806.375171227788;4850.975745121252;4929.531694489017;4946.876328091755;4956.330895329084;5474;5548.8082207527805;5594.86012849682;5607.52336051432;5643.633163844419;6829.692663830139;6847.82282807487;6847.897281540781;6858.554193977376;6878.484113691393;1494.7234761569146;1677.379369633219;1689.4847071643267;1690.6196105876325;1699.9460334592632;929.9349045566745;1025.2618114957368;1050.6427814543092;1115.5314767797545;1117.8323251512338;6126.846828829226;6243.845873173747;6379.425851673372;6457.030605665223;6461.19235095613;6127.387261273837;6951.89572156418;7079.787606371817;7081.327273908971;7130.750423735144;5415.512603865593;6047.909281360783;6410.45511131559;6417.807465776034;6425.485961123192;3464.7401444891734;3675.8162091895833;3687.8978981980013;3698.7794027970704;3715.479832823407;6117.663528505975;6661.56929181472;7190.928090719026;7265.346118849329;7326.377257933029;6399.776007839703;7193.496455248187;7257.564546127249;7268.781936541912;7270.200277969525;7193.172785129673;7265.745698900513;7277.781666550012;7290.3438690518105;7292.848609660163;5523.8066667666435;6153.261608606996;6819.079424277659;7019.824504387431;7043;6379.712912918837;6943.409810166192;6946.5484743493535;6959.304069593;6988.720451182099;6052.334243233153;6683.832904177434;7220.9529618204015;7232.74685386009;7263.0921134858145;4205.222033923734;4737.489130760702;4811.831585894617;4848.490908454562;4853.029394121119;6834.760783372558;7047.577145371294;7060.894086588808;7067.752628658178;7092.120588044069;5133.974330128325;6506.967465162644;6605.57063790852;6789.694463749242;6807.9319206807295;5477.780888764405;5670.773169073191;6874.587524748531;6876;6880.139982502187;5669.363745858196;6592.923229182792;6717.798466046026;6718.932810672026;6729;6790.966045169743;6798.626075565693;6847.349501797321;6861.416779573792;6872.312768723088;5929.407059294036;6591;6666.83332916681;6700.932990670226;6754.155730533684 05:46:46 INFO - PID 1983 | |5;yui.html;831.538496134645;833.6623667414698;840.3823189955382;849.9503113654636;873.7771868173617;13653.590392288235;14574.125552466832;15195.240237988084;17125.058121803297;17265.48203553894;16948.406805761802;17597.912010439963;17661.82338176618;17722.291108355683;17729.91135044326;926.805370872116;1025.9538320775546;1035.7100011996615;1038.5482315192878;1041.2919214933843;1037.8598889149982;1046.4924511611862;1046.8691413573304;1048.979020419591;1050.6532844161447;975.7999610079917;1076.5048077884182;1080.864891888514;1081.826907694769;1082.9874067746644;1066.786642671461;1068.8984546468112;1069.3370110531444;1073.4954571351452;1080.778440419712;644.6905485367004;761.4936067515151;775.2054144501931;785.8428314337099;786.319833344159;17136.57158571046;17424.91287543554;17552.561185970324;17703.02633355165;18102.45692629223;14217.715645687029;17431.302747890197;17450.825491745047;17964.371247006424;18011;14103.71792564143;14213.360398781977;14361.497347592784;14391.2084835334;14397.280135993264;3581.4090675038588;3583.3549961007006;3618.149734812317;3619.6561326673923;3627.4014787560286;146707;147286;149246.25376873155;149370.50629493676;150778.24610876874;13551.25468099264;13718.314084295745;13750.312484375941;13885.166889986633;13911.930440347936;26633.866830665724;26997.78509967056;27049.323766905592;27109.728902711115;27142.864285678446;7098.574085554883;7218.45861560385;7668.769936901902;8145.51126932386;8146.918530814616;3865.091703449687;3945.822437990297;3945.9605403946166;3946.5856085111222;3949.2101579684177;4560;4622.075584883037;4681.110588988081;4681.297805329228;4967.850964471072;1035.9119474844572;1049.4333060147587;1062.8830828608848;1062.914966802654;1065.8880817514205;646.8221239159269;648.3775575447529;659.3768888400416;667.4660271782554;680.792358330704;11409.942950285196;12121.151519393734;12758.872411275954;12894.29081400532;12912.741745165044;647.1489990662265;649.8018104478085;659.7822718502979;663.9834004149935;664.8304682305981;5602.579806514527;5902.2622172228475;6172.197614310111;6577.901331480031;6595.764856191695;123771.3811430937;138662;138760;141246;143307;113751.43124284326;119672.40163799125;124221;124375;126958.36520817337;94603.51101691538;96677.0332296682;98420;100017;100592.99407005982;10213;12294;12511;12940.547080852126;13264.46942122343;43774.62253774597;45181.54818451905;45257;45428;45992.54007460016;71811.28188718254;77544;80419.59790201012;83714.58142709247;84244.15755842609;17731;17778.733318999966;17913.910430448286;18054.09729513493;18107.09464526742;15517.45688900883;18396.724049139008;18912.14895329741;19109.23563057462;19337.323193688717;14880.255987200813;15342.463013794464;17439.30242790274;18393.724094138866;18537.90731046336;16865.494035178963;16895.732820038793;17346.65306693834;17477.563060923578;17483;16693.415730449375;17757;17772.377966771102;18014.36949706754;18080;11753.412329383325;11867;12097.81853272184;12365.07261955375;12407;13065.542706005624;13872.930635346758;13879.514217002357;14169.716605668034;14447.422103116178 05:46:46 INFO - PID 1983 | __end_tp_report 05:46:46 INFO - PID 1983 | __start_cc_report 05:46:46 INFO - PID 1983 | _x_x_mozilla_cycle_collect,456 05:46:46 INFO - PID 1983 | __end_cc_report 05:46:46 INFO - PID 1983 | __startTimestamp1497012406718__endTimestamp 05:46:46 INFO - PID 1983 | 05:46:46 INFO - PID 1983 | ------- Summary: start ------- 05:46:46 INFO - PID 1983 | Number of tests: 6 05:46:46 INFO - PID 1983 | 05:46:46 INFO - PID 1983 | [#0] dojo.html Cycles:165 Average:6154.35 Median:6380.43 stddev:757.14 (11.9%) stddev-sans-first:718.78 05:46:46 INFO - PID 1983 | Values: 3032.9 3573.9 3605.6 3607.0 3614.9 6145.2 6170.0 6201.9 6224.0 6258.2 4840.0 6166.4 6204.3 6255.7 6277.3 5245.8 5397.6 5461.8 5464.4 5494.9 5536.9 5568.1 5583.0 5603.4 5626.1 5396.0 5431.6 5465.8 5493.8 5506.6 5489.1 5493.9 5508.9 5516.2 5523.3 5470.8 5472.9 5473.7 5487.6 5489.8 6137.6 6158.0 6189.9 6216.8 6325.5 5897.0 6183.7 6217.7 6713.8 6724.5 5686.3 6798.8 6807.5 6809.4 6810.5 6236.2 6292.6 6348.9 6366.8 6371.6 6861.1 6920.9 6949.1 6965.8 6976.2 6299.3 6431.2 6507.7 6584.5 6599.1 6781.4 6813.4 6824.8 6835.1 6836.3 6430.8 6495.8 6662.0 6699.1 6724.2 6593.0 6607.6 6696.9 6723.1 6747.1 4815.7 5304.1 5341.2 5385.1 5394.0 4300.0 4657.4 4758.5 4777.9 4808.7 5837.5 5861.8 6318.8 6373.2 6386.5 6775.5 6787.7 6791.8 6807.9 6810.6 6340.0 6357.4 6380.4 6399.4 6403.2 5358.7 5777.9 5783.9 5987.9 6031.7 6956.2 6978.5 6983.2 6984.3 7002.8 6778.5 6790.3 6798.4 6808.6 6823.6 6718.4 6795.7 6854.5 6948.4 6972.2 6624.1 6636.7 6670.4 6696.9 6697.5 6580.1 6795.8 6799.7 6820.6 6842.2 6061.0 6118.5 6389.0 6593.2 6616.7 6575.6 6583.6 6614.0 6619.8 6620.3 6381.4 6470.3 6479.4 6490.8 6502.9 5375.9 5682.3 6506.4 6554.6 6590.2 6369.1 6376.7 6390.3 6392.2 6404.6 05:46:46 INFO - PID 1983 | 05:46:46 INFO - PID 1983 | [#1] ext.html Cycles:180 Average:45779.07 Median:185118.74 stddev:78910.09 (42.6%) stddev-sans-first:79058.73 05:46:46 INFO - PID 1983 | Values: 656.8 777.3 850.9 1238.3 1302.4 32400.8 38646.4 39719.4 39730.6 39779.0 40202.0 40652.8 40662.0 40686.6 40718.0 7842.1 8310.5 8514.3 8519.4 8548.1 8436.3 8531.4 8537.0 8540.8 8541.1 7550.4 8429.5 8448.0 8530.1 8586.9 8424.7 8494.4 8509.9 8533.1 8561.9 8208.0 8406.2 8440.8 8467.5 8474.0 31313.0 32446.0 37460.4 37780.4 37870.0 3514.9 3551.9 3552.7 3556.3 3561.0 30348.8 30704.4 30757.5 30787.5 30911.1 3058.1 3079.6 3086.8 3097.1 3130.6 254784.0 256403.0 284794.6 298136.0 299783.0 48777.5 49061.5 49455.8 49460.0 49467.8 51418.7 51499.0 51534.7 51540.5 52062.0 14690.1 14737.6 14814.6 14844.0 14863.7 11858.6 11937.8 11945.0 11958.9 11980.0 14395.9 14445.7 14487.9 14530.2 14531.5 5301.3 6292.0 6299.6 6331.3 6372.0 929.9 960.8 965.3 990.7 1058.2 23987.9 32499.0 32863.8 32998.5 33399.0 1095.7 1095.8 1129.4 1140.1 1158.4 10936.2 12339.7 12531.7 12575.1 12634.5 330462.0 330766.0 332969.0 333709.0 335142.0 56599.0 56604.6 56927.7 57117.4 57175.4 1509.8 1510.2 1540.1 1547.4 1552.8 22927.0 23491.1 23541.4 24320.4 24554.8 35095.5 40492.6 40502.8 40539.0 40547.0 286570.0 287588.0 289334.0 289461.0 290372.0 36707.7 36941.6 37230.6 37276.8 37287.4 44893.0 46861.5 47010.0 47010.1 47095.3 29140.4 29578.9 30090.8 30789.8 31254.0 29852.7 30737.4 31305.4 31618.7 31635.0 36700.8 37880.6 38519.6 38657.2 38675.6 29893.4 29954.7 30181.5 31155.5 31156.8 23421.6 25036.0 25214.5 25448.7 25486.6 05:46:46 INFO - PID 1983 | 05:46:46 INFO - PID 1983 | [#2] jquery.html Cycles:180 Average:39754.36 Median:2281.40 stddev:99901.62 (4379.0%) stddev-sans-first:100138.27 05:46:46 INFO - PID 1983 | Values: 441.9 500.6 502.9 515.1 528.5 21725.6 22382.1 23029.8 23312.0 24818.6 23628.1 24144.0 24346.0 24441.4 24483.9 6448.8 6578.7 6595.7 6602.9 6618.2 6174.5 6483.8 6525.3 6536.1 6539.5 6410.7 6473.5 6481.2 6487.2 6487.5 6469.1 6470.9 6479.6 6496.9 6511.9 5864.5 5883.8 5921.3 5922.6 5926.1 30231.4 30251.2 30265.7 30633.0 31017.4 2244.4 2278.1 2278.7 2279.2 2282.9 13766.3 19197.2 19495.0 19787.6 19799.7 2189.2 2235.5 2272.4 2279.9 2291.8 163754.0 171148.0 202498.0 204490.0 212810.0 12354.8 15233.8 15236.5 15578.4 15606.3 16975.4 20201.1 20465.7 20567.3 20575.5 9912.9 9928.7 9939.2 9942.7 10002.4 7508.8 7575.5 8972.0 9069.3 9092.8 7954.9 9194.2 9203.5 9219.6 9295.4 2613.1 2640.9 2643.0 2646.6 2788.2 943.7 952.3 959.3 991.8 1004.0 17186.0 20148.9 20782.6 21228.0 21850.6 980.7 988.0 1015.7 1016.8 1028.6 9903.4 11084.9 11264.4 13685.0 14783.7 573875.0 577479.0 578469.0 588203.0 590871.0 120688.0 140246.3 145859.5 146293.0 147910.0 1019.9 1072.2 1139.4 1193.9 1216.2 21744.9 22697.1 24529.6 24695.6 25261.0 68021.9 69742.0 70266.0 71079.6 71916.0 80870.6 91186.0 91308.5 91964.0 92056.5 11381.4 11473.7 11965.6 11980.5 12099.9 18619.9 18729.1 18765.7 18776.4 18911.6 15185.0 15315.3 15367.2 15387.0 15408.9 12309.4 13430.9 13745.8 13868.8 14322.4 15326.8 15413.6 15679.1 15724.7 15725.0 11794.1 11847.2 11947.6 11971.3 11984.2 12938.8 15007.5 15255.3 15265.2 15279.8 05:46:46 INFO - PID 1983 | 05:46:46 INFO - PID 1983 | [#3] mootools.html Cycles:180 Average:8167.34 Median:2705.84 stddev:5425.73 (200.5%) stddev-sans-first:5415.41 05:46:46 INFO - PID 1983 | Values: 1161.3 1304.7 1428.1 1435.6 1451.5 13250.9 13465.7 13850.4 13851.0 13872.2 13478.6 13508.7 13561.4 13668.7 13677.2 2370.7 2605.4 2694.6 2737.3 2740.0 2181.6 2236.0 2333.8 2604.0 2693.3 2493.7 2504.7 2728.2 2755.8 2764.4 2684.7 2691.0 2697.6 2701.5 2710.2 2110.8 2677.8 2681.4 2682.2 2689.1 13161.3 13478.9 13509.1 13518.5 13525.5 7336.0 7589.0 7593.2 7623.2 7639.7 4009.8 4547.6 4726.3 4844.7 4846.8 1362.5 1387.2 1400.7 1415.7 1429.4 15907.2 19779.9 19801.7 19922.1 19952.0 6280.6 6329.0 6510.1 6546.8 6559.4 14017.6 14108.0 14121.0 14150.3 14194.7 7188.4 7336.3 7343.5 7348.8 7357.2 6569.5 6708.6 6732.7 6799.8 6809.8 4871.7 5032.0 5098.4 5118.4 5125.6 1313.1 1357.6 1418.3 1453.6 1475.4 804.0 824.1 835.4 839.0 862.6 7231.6 7637.3 7737.3 7803.2 7826.8 955.8 981.2 987.7 990.6 991.1 4674.8 4907.5 5091.7 5142.7 5229.9 17737.1 17823.4 18235.4 18240.1 18497.8 17999.7 18056.5 18090.9 18154.9 18156.4 9401.9 9451.6 9512.6 9534.6 9559.1 7537.9 7616.5 7702.1 7719.9 7948.8 15960.4 15965.0 16172.8 16285.2 16293.3 16552.4 16692.2 16707.3 16753.8 16847.6 9759.0 9890.7 9903.0 9903.2 9926.4 12266.8 12400.6 12426.0 12437.3 12535.7 9321.2 9815.6 10244.6 10362.6 10397.3 9992.4 10052.7 10065.3 10122.1 10161.7 9124.5 9289.3 9352.1 9354.3 9357.3 5847.4 5941.3 6170.1 6657.1 7066.2 5845.3 6361.5 6828.2 7003.1 7058.6 05:46:46 INFO - PID 1983 | 05:46:46 INFO - PID 1983 | [#4] prototype.html Cycles:180 Average:5521.65 Median:6127.12 stddev:1688.06 (27.6%) stddev-sans-first:1649.36 05:46:46 INFO - PID 1983 | Values: 452.2 454.6 464.0 464.2 482.3 5111.7 6292.2 6432.5 6503.9 6505.6 5419.8 5586.7 6170.7 6311.4 6328.3 4625.5 4769.5 4773.3 4805.9 4818.9 3981.9 4781.1 4797.4 4802.3 4824.8 4725.8 4733.2 4736.7 4747.8 4759.9 4750.4 4751.9 4758.7 4762.6 4783.9 4186.3 4192.0 4193.9 4201.3 4208.6 5272.8 6212.6 6238.1 6245.7 6267.7 4750.9 5677.6 5677.9 5682.1 5719.9 5844.9 5968.5 6194.1 6836.8 6856.8 4747.5 4772.7 4772.8 4800.8 4807.8 6368.9 6868.3 7125.3 7190.2 7263.9 4262.7 4500.9 4820.5 4823.9 4833.6 4806.4 4851.0 4929.5 4946.9 4956.3 5474.0 5548.8 5594.9 5607.5 5643.6 6829.7 6847.8 6847.9 6858.6 6878.5 1494.7 1677.4 1689.5 1690.6 1699.9 929.9 1025.3 1050.6 1115.5 1117.8 6126.8 6243.8 6379.4 6457.0 6461.2 6127.4 6951.9 7079.8 7081.3 7130.8 5415.5 6047.9 6410.5 6417.8 6425.5 3464.7 3675.8 3687.9 3698.8 3715.5 6117.7 6661.6 7190.9 7265.3 7326.4 6399.8 7193.5 7257.6 7268.8 7270.2 7193.2 7265.7 7277.8 7290.3 7292.8 5523.8 6153.3 6819.1 7019.8 7043.0 6379.7 6943.4 6946.5 6959.3 6988.7 6052.3 6683.8 7221.0 7232.7 7263.1 4205.2 4737.5 4811.8 4848.5 4853.0 6834.8 7047.6 7060.9 7067.8 7092.1 5134.0 6507.0 6605.6 6789.7 6807.9 5477.8 5670.8 6874.6 6876.0 6880.1 5669.4 6592.9 6717.8 6718.9 6729.0 6791.0 6798.6 6847.3 6861.4 6872.3 5929.4 6591.0 6666.8 6700.9 6754.2 05:46:46 INFO - PID 1983 | 05:46:46 INFO - PID 1983 | [#5] yui.html Cycles:180 Average:25720.49 Median:17517.78 stddev:39067.38 (223.0%) stddev-sans-first:39132.27 05:46:46 INFO - PID 1983 | Values: 831.5 833.7 840.4 850.0 873.8 13653.6 14574.1 15195.2 17125.1 17265.5 16948.4 17597.9 17661.8 17722.3 17729.9 926.8 1026.0 1035.7 1038.5 1041.3 1037.9 1046.5 1046.9 1049.0 1050.7 975.8 1076.5 1080.9 1081.8 1083.0 1066.8 1068.9 1069.3 1073.5 1080.8 644.7 761.5 775.2 785.8 786.3 17136.6 17424.9 17552.6 17703.0 18102.5 14217.7 17431.3 17450.8 17964.4 18011.0 14103.7 14213.4 14361.5 14391.2 14397.3 3581.4 3583.4 3618.1 3619.7 3627.4 146707.0 147286.0 149246.3 149370.5 150778.2 13551.3 13718.3 13750.3 13885.2 13911.9 26633.9 26997.8 27049.3 27109.7 27142.9 7098.6 7218.5 7668.8 8145.5 8146.9 3865.1 3945.8 3946.0 3946.6 3949.2 4560.0 4622.1 4681.1 4681.3 4967.9 1035.9 1049.4 1062.9 1062.9 1065.9 646.8 648.4 659.4 667.5 680.8 11409.9 12121.2 12758.9 12894.3 12912.7 647.1 649.8 659.8 664.0 664.8 5602.6 5902.3 6172.2 6577.9 6595.8 123771.4 138662.0 138760.0 141246.0 143307.0 113751.4 119672.4 124221.0 124375.0 126958.4 94603.5 96677.0 98420.0 100017.0 100593.0 10213.0 12294.0 12511.0 12940.5 13264.5 43774.6 45181.5 45257.0 45428.0 45992.5 71811.3 77544.0 80419.6 83714.6 84244.2 17731.0 17778.7 17913.9 18054.1 18107.1 15517.5 18396.7 18912.1 19109.2 19337.3 14880.3 15342.5 17439.3 18393.7 18537.9 16865.5 16895.7 17346.7 17477.6 17483.0 16693.4 17757.0 17772.4 18014.4 18080.0 11753.4 11867.0 12097.8 12365.1 12407.0 13065.5 13872.9 13879.5 14169.7 14447.4 05:46:46 INFO - PID 1983 | -------- Summary: end -------- 05:46:46 INFO - PID 1983 | 05:46:47 INFO - TEST-INFO | 1983: exit 0 05:46:47 INFO - TEST-OK | dromaeo_css | took 1143122ms 05:46:47 INFO - TEST-START | kraken 05:46:47 INFO - operating with platform_type : mac_ 05:46:47 INFO - Initialising browser for kraken test... 05:46:47 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49346/getInfo.html -foreground -profile /var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/tmpvFoUJs/profile 05:46:47 INFO - TEST-INFO | started process 1990 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49346/getInfo.html -foreground) 05:47:21 INFO - TEST-INFO | 1990: killed by SIGTERM 05:47:21 INFO - Browser initialized. 05:47:21 INFO - Running cycle 1/1 for kraken test... 05:47:21 INFO - TEST-INFO | started process 1994 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/tmpvFoUJs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:47:23 INFO - PID 1994 | 2017-06-09 05:47:23.483 plugin-container[1995:13895] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x463f, name = 'com.apple.tsm.portname' 05:47:23 INFO - PID 1994 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:47:23 INFO - PID 1994 | 2017-06-09 05:47:23.484 plugin-container[1995:13895] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4643, name = 'com.apple.CFPasteboardClient' 05:47:23 INFO - PID 1994 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:47:23 INFO - PID 1994 | 2017-06-09 05:47:23.484 plugin-container[1995:13895] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:47:24 INFO - PID 1994 | 2017-06-09 05:47:24.082 plugin-container[1996:13942] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464b, name = 'com.apple.tsm.portname' 05:47:24 INFO - PID 1994 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:47:24 INFO - PID 1994 | 2017-06-09 05:47:24.082 plugin-container[1996:13942] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4907, name = 'com.apple.CFPasteboardClient' 05:47:24 INFO - PID 1994 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:47:24 INFO - PID 1994 | 2017-06-09 05:47:24.082 plugin-container[1996:13942] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:47:33 INFO - PID 1994 | RSS: Main: 253194240 05:47:33 INFO - PID 1994 | 05:47:36 INFO - PID 1994 | Cycle 1(1): loaded http://localhost:49346/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49346/tests/kraken/driver.html?testName=audio-beat-detection) 05:47:37 INFO - PID 1994 | RSS: Main: 266735616 05:47:37 INFO - PID 1994 | 05:47:39 INFO - PID 1994 | Cycle 1(1): loaded http://localhost:49346/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49346/tests/kraken/driver.html?testName=audio-dft) 05:47:40 INFO - PID 1994 | RSS: Main: 277377024 05:47:40 INFO - PID 1994 | 05:47:43 INFO - PID 1994 | Cycle 1(1): loaded http://localhost:49346/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49346/tests/kraken/driver.html?testName=audio-fft) 05:47:44 INFO - PID 1994 | RSS: Main: 279359488 05:47:44 INFO - PID 1994 | 05:47:46 INFO - PID 1994 | Cycle 1(1): loaded http://localhost:49346/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49346/tests/kraken/driver.html?testName=audio-oscillator) 05:47:46 INFO - PID 1994 | RSS: Main: 280350720 05:47:46 INFO - PID 1994 | 05:47:48 INFO - PID 1994 | Cycle 1(1): loaded http://localhost:49346/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49346/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:47:49 INFO - PID 1994 | RSS: Main: 282173440 05:47:49 INFO - PID 1994 | 05:47:52 INFO - PID 1994 | Cycle 1(1): loaded http://localhost:49346/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49346/tests/kraken/driver.html?testName=imaging-darkroom) 05:47:53 INFO - PID 1994 | RSS: Main: 286576640 05:47:53 INFO - PID 1994 | 05:47:56 INFO - PID 1994 | Cycle 1(1): loaded http://localhost:49346/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49346/tests/kraken/driver.html?testName=imaging-desaturate) 05:47:56 INFO - PID 1994 | RSS: Main: 288395264 05:47:56 INFO - PID 1994 | 05:47:59 INFO - PID 1994 | Cycle 1(1): loaded http://localhost:49346/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49346/tests/kraken/driver.html?testName=json-parse-financial) 05:48:00 INFO - PID 1994 | RSS: Main: 287907840 05:48:00 INFO - PID 1994 | 05:48:02 INFO - PID 1994 | Cycle 1(1): loaded http://localhost:49346/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49346/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:48:02 INFO - PID 1994 | RSS: Main: 288137216 05:48:02 INFO - PID 1994 | 05:48:04 INFO - PID 1994 | Cycle 1(1): loaded http://localhost:49346/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49346/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:48:05 INFO - PID 1994 | RSS: Main: 287981568 05:48:05 INFO - PID 1994 | 05:48:07 INFO - PID 1994 | Cycle 1(1): loaded http://localhost:49346/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49346/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:48:08 INFO - PID 1994 | RSS: Main: 287899648 05:48:08 INFO - PID 1994 | 05:48:11 INFO - PID 1994 | Cycle 1(1): loaded http://localhost:49346/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49346/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:48:11 INFO - PID 1994 | RSS: Main: 287715328 05:48:11 INFO - PID 1994 | 05:48:14 INFO - PID 1994 | Cycle 1(1): loaded http://localhost:49346/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49346/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:48:15 INFO - PID 1994 | RSS: Main: 287744000 05:48:15 INFO - PID 1994 | 05:48:17 INFO - PID 1994 | Cycle 1(1): loaded http://localhost:49346/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49346/tests/kraken/driver.html?testName=ai-astar) 05:48:17 INFO - PID 1994 | RSS: Main: 289439744 05:48:17 INFO - PID 1994 | 05:48:17 INFO - PID 1994 | __start_tp_report 05:48:17 INFO - PID 1994 | _x_x_mozilla_page_load 05:48:17 INFO - PID 1994 | _x_x_mozilla_page_load_details 05:48:17 INFO - PID 1994 | |i|pagename|runs| 05:48:17 INFO - PID 1994 | |0;ai-astar;112;118;101;115;117;117;116;114;114;114 05:48:17 INFO - PID 1994 | |1;audio-beat-detection;141;109;131;156;136;162;114;182;171;118 05:48:17 INFO - PID 1994 | |2;audio-dft;208;196;211;224;208;233;201;199;199;195 05:48:17 INFO - PID 1994 | |3;audio-fft;68;80;79;150;57;58;57;61;128;58 05:48:17 INFO - PID 1994 | |4;audio-oscillator;74;70;115;70;72;77;109;72;74;115 05:48:17 INFO - PID 1994 | |5;imaging-gaussian-blur;87;89;87;87;89;87;88;97;89;89 05:48:17 INFO - PID 1994 | |6;imaging-darkroom;97;95;95;95;96;95;97;95;122;114 05:48:17 INFO - PID 1994 | |7;imaging-desaturate;88;90;123;80;140;142;90;140;96;118 05:48:17 INFO - PID 1994 | |8;json-parse-financial;49;51;55;66;67;54;55;54;52;54 05:48:17 INFO - PID 1994 | |9;json-stringify-tinderbox;56;65;49;59;57;82;59;46;47;47 05:48:17 INFO - PID 1994 | |10;stanford-crypto-aes;99;107;111;84;83;85;88;84;114;91 05:48:17 INFO - PID 1994 | |11;stanford-crypto-ccm;153;144;149;134;146;139;140;152;138;139 05:48:17 INFO - PID 1994 | |12;stanford-crypto-pbkdf2;185;231;179;182;185;186;229;180;183;183 05:48:17 INFO - PID 1994 | |13;stanford-crypto-sha256-iterative;66;64;68;76;68;96;69;68;65;68 05:48:17 INFO - PID 1994 | __end_tp_report 05:48:17 INFO - PID 1994 | __start_cc_report 05:48:17 INFO - PID 1994 | _x_x_mozilla_cycle_collect,1231 05:48:17 INFO - PID 1994 | __end_cc_report 05:48:17 INFO - PID 1994 | __startTimestamp1497012497480__endTimestamp 05:48:17 INFO - PID 1994 | 05:48:17 INFO - PID 1994 | ------- Summary: start ------- 05:48:17 INFO - PID 1994 | Number of tests: 14 05:48:17 INFO - PID 1994 | 05:48:17 INFO - PID 1994 | [#0] ai-astar Cycles:10 Average:113.80 Median:115.50 stddev:4.85 (4.2%) stddev-sans-first:5.10 05:48:17 INFO - PID 1994 | Values: 112.0 118.0 101.0 115.0 117.0 117.0 116.0 114.0 114.0 114.0 05:48:17 INFO - PID 1994 | 05:48:17 INFO - PID 1994 | [#1] audio-beat-detection Cycles:10 Average:142.00 Median:148.50 stddev:25.04 (16.9%) stddev-sans-first:26.56 05:48:17 INFO - PID 1994 | Values: 141.0 109.0 131.0 156.0 136.0 162.0 114.0 182.0 171.0 118.0 05:48:17 INFO - PID 1994 | 05:48:17 INFO - PID 1994 | [#2] audio-dft Cycles:10 Average:207.40 Median:208.00 stddev:12.52 (6.0%) stddev-sans-first:13.28 05:48:17 INFO - PID 1994 | Values: 208.0 196.0 211.0 224.0 208.0 233.0 201.0 199.0 199.0 195.0 05:48:17 INFO - PID 1994 | 05:48:17 INFO - PID 1994 | [#3] audio-fft Cycles:10 Average:79.60 Median:59.50 stddev:32.89 (55.3%) stddev-sans-first:34.61 05:48:17 INFO - PID 1994 | Values: 68.0 80.0 79.0 150.0 57.0 58.0 57.0 61.0 128.0 58.0 05:48:17 INFO - PID 1994 | 05:48:17 INFO - PID 1994 | [#4] audio-oscillator Cycles:10 Average:84.80 Median:72.00 stddev:19.63 (27.3%) stddev-sans-first:20.43 05:48:17 INFO - PID 1994 | Values: 74.0 70.0 115.0 70.0 72.0 77.0 109.0 72.0 74.0 115.0 05:48:17 INFO - PID 1994 | 05:48:17 INFO - PID 1994 | [#5] imaging-gaussian-blur Cycles:10 Average:88.90 Median:89.00 stddev:3.00 (3.4%) stddev-sans-first:3.10 05:48:17 INFO - PID 1994 | Values: 87.0 89.0 87.0 87.0 89.0 87.0 88.0 97.0 89.0 89.0 05:48:17 INFO - PID 1994 | 05:48:17 INFO - PID 1994 | [#6] imaging-darkroom Cycles:10 Average:100.10 Median:95.00 stddev:9.65 (10.2%) stddev-sans-first:10.17 05:48:17 INFO - PID 1994 | Values: 97.0 95.0 95.0 95.0 96.0 95.0 97.0 95.0 122.0 114.0 05:48:17 INFO - PID 1994 | 05:48:17 INFO - PID 1994 | [#7] imaging-desaturate Cycles:10 Average:110.70 Median:84.00 stddev:24.57 (29.2%) stddev-sans-first:24.65 05:48:17 INFO - PID 1994 | Values: 88.0 90.0 123.0 80.0 140.0 142.0 90.0 140.0 96.0 118.0 05:48:17 INFO - PID 1994 | 05:48:17 INFO - PID 1994 | [#8] json-parse-financial Cycles:10 Average:55.70 Median:54.50 stddev:6.00 (11.0%) stddev-sans-first:5.85 05:48:17 INFO - PID 1994 | Values: 49.0 51.0 55.0 66.0 67.0 54.0 55.0 54.0 52.0 54.0 05:48:17 INFO - PID 1994 | 05:48:17 INFO - PID 1994 | [#9] json-stringify-tinderbox Cycles:10 Average:56.70 Median:58.00 stddev:10.97 (18.9%) stddev-sans-first:11.63 05:48:17 INFO - PID 1994 | Values: 56.0 65.0 49.0 59.0 57.0 82.0 59.0 46.0 47.0 47.0 05:48:17 INFO - PID 1994 | 05:48:17 INFO - PID 1994 | [#10] stanford-crypto-aes Cycles:10 Average:94.60 Median:84.50 stddev:12.14 (14.4%) stddev-sans-first:12.77 05:48:17 INFO - PID 1994 | Values: 99.0 107.0 111.0 84.0 83.0 85.0 88.0 84.0 114.0 91.0 05:48:17 INFO - PID 1994 | 05:48:17 INFO - PID 1994 | [#11] stanford-crypto-ccm Cycles:10 Average:143.40 Median:145.00 stddev:6.43 (4.4%) stddev-sans-first:5.81 05:48:17 INFO - PID 1994 | Values: 153.0 144.0 149.0 134.0 146.0 139.0 140.0 152.0 138.0 139.0 05:48:17 INFO - PID 1994 | 05:48:17 INFO - PID 1994 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:192.30 Median:185.00 stddev:19.99 (10.8%) stddev-sans-first:21.03 05:48:17 INFO - PID 1994 | Values: 185.0 231.0 179.0 182.0 185.0 186.0 229.0 180.0 183.0 183.0 05:48:17 INFO - PID 1994 | 05:48:17 INFO - PID 1994 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.80 Median:68.00 stddev:9.43 (13.9%) stddev-sans-first:9.84 05:48:17 INFO - PID 1994 | Values: 66.0 64.0 68.0 76.0 68.0 96.0 69.0 68.0 65.0 68.0 05:48:17 INFO - PID 1994 | -------- Summary: end -------- 05:48:17 INFO - PID 1994 | 05:48:18 INFO - TEST-INFO | 1994: exit 0 05:48:18 INFO - TEST-OK | kraken | took 91006ms 05:48:18 INFO - SUITE-END | took 1234s 05:48:18 INFO - Completed test suite (00:20:34) 05:48:18 INFO - javascript benchmark 05:48:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8385.492037317004, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3032.8635211415485, 3573.8749143779955, 3605.56733192017, 3606.99004278802, 3614.8734794282195, 6145.17040199573, 6170.012797952329, 6201.8759624807535, 6224.0, 6258.155149054876, 4839.951600483994, 6166.3525329840395, 6204.317525072239, 6255.687215639223, 6277.340879207686, 5245.816396426126, 5397.568194544437, 5461.754221060042, 5464.4262352453015, 5494.862628434281, 5536.916946245789, 5568.1091025435935, 5582.9950608890385, 5603.3836278009385, 5626.071698169797, 5395.973020134914, 5431.565474762009, 5465.759038794075, 5493.7802487900435, 5506.587005974568, 5489.066858634042, 5493.862653433656, 5508.944910550923, 5516.200150978126, 5523.28197334348, 5470.8085217017215, 5472.91790623141, 5473.698946557938, 5487.560995120421, 5489.807856724996, 6137.631742095444, 6158.04550294705, 6189.938100619026, 6216.813495595139, 6325.462335701471, 5897.0, 6183.6598987055695, 6217.658028808413, 6713.832154196184, 6724.462043036546, 5686.2607860977805, 6798.796036118924, 6807.4894382921475, 6809.421199198074, 6810.489213309022, 6236.220472440945, 6292.559520833498, 6348.936510634927, 6366.84082897922, 6371.553991220663, 6861.142357205349, 6920.861582768316, 6949.061876646689, 6965.79102626922, 6976.162860456778, 6299.275583307886, 6431.228252609623, 6507.739690412331, 6584.473242140617, 6599.076129341896, 6781.42357899584, 6813.3527314905, 6824.7952561423235, 6835.111435513351, 6836.316368363124, 6430.807075787734, 6495.772647957298, 6662.002099055348, 6699.062131301622, 6724.159480064992, 6593.044008618819, 6607.570507917067, 6696.8995465068065, 6723.0923825283935, 6747.05541224229, 4815.662903596784, 5304.124819404755, 5341.198820177005, 5385.138377859524, 5394.0, 4300.0, 4657.371254880548, 4758.476567577563, 4777.928331075037, 4808.687435316693, 5837.474627283565, 5861.824145275648, 6318.778842740482, 6373.203349581302, 6386.489080873519, 6775.525713200029, 6787.728490860409, 6791.830204244835, 6807.9319206807295, 6810.62541560219, 6340.048992651047, 6357.427831495188, 6380.425761681471, 6399.392057754506, 6403.231612206565, 5358.713908661893, 5777.913331300034, 5783.942160578425, 5987.9101813472835, 6031.698415079229, 6956.2000369958105, 6978.546394484342, 6983.162020557464, 6984.266652001568, 7002.7899163025195, 6778.546885115362, 6790.32096790317, 6798.388145066968, 6808.557443766139, 6823.590584564942, 6718.361755633207, 6795.660216989229, 6854.451643868579, 6948.374646281657, 6972.198197207386, 6624.105745724266, 6636.7013484392355, 6670.366315200048, 6696.933030669826, 6697.531172817856, 6580.078788969644, 6795.7961261162245, 6799.728010879509, 6820.62486563224, 6842.24735279109, 6061.0, 6118.4668989547035, 6389.041643753474, 6593.241777195492, 6616.66916654175, 6575.605463672195, 6583.572067815577, 6613.966930165511, 6619.801405957829, 6620.337966203341, 6381.3937675920715, 6470.288268290578, 6479.384458476437, 6490.837729056622, 6502.869942601216, 5375.865603359947, 5682.261306030189, 6506.4469520091325, 6554.63949482783, 6590.176227971504, 6369.108324834619, 6376.681165941777, 6390.297067322684, 6392.232932048184, 6404.647744373918], "value": 6102.76606499671, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [656.763565116558, 777.2538363171356, 850.91065438129, 1238.3374894431481, 1302.4464602543917, 32400.837995809987, 38646.420303695464, 39719.40420893685, 39730.60942867004, 39779.01104944756, 40202.0, 40652.79673601628, 40662.0, 40686.59313406865, 40718.0, 7842.058952925643, 8310.501369917823, 8514.318854491657, 8519.446235994672, 8548.102449242833, 8436.325093992466, 8531.360147988895, 8537.018242902084, 8540.829183416328, 8541.14588541144, 7550.4337174711845, 8429.452085614446, 8447.95776021122, 8530.146985301482, 8586.914130858706, 8424.705135320295, 8494.40539162259, 8509.872351914726, 8533.104024077507, 8561.914380856175, 8207.95896020522, 8406.243438090602, 8440.83118337636, 8467.49195048299, 8474.025487068942, 31313.0, 32446.0, 37460.438093428624, 37780.43329350062, 37870.0, 3514.9297014059575, 3551.94672079919, 3552.7335449841107, 3556.3242983833247, 3561.0, 30348.84825575858, 30704.38591228163, 30757.53863692024, 30787.538186927213, 30911.07266782, 3058.0520038787827, 3079.63044434667, 3086.783925125242, 3097.0863595239234, 3130.6086739157604, 254784.0, 256403.0, 284794.57602712064, 298136.0, 299783.0, 48777.51222487801, 49061.50938490641, 49455.75272123616, 49460.0, 49467.75266123646, 51418.74290628523, 51499.0, 51534.74232628813, 51540.48459515357, 52061.95876082533, 14690.11859288446, 14737.557873263819, 14814.62963425923, 14844.0, 14863.70272594542, 11858.584949526725, 11937.820932686016, 11945.0, 11958.88041119595, 11979.981701555465, 14395.856041439452, 14445.711085778436, 14487.85512144865, 14530.200838953959, 14531.491397801026, 5301.3373328333955, 6291.961826298701, 6299.590526615755, 6331.271903731038, 6371.96814015927, 929.874466946967, 960.8030353777599, 965.2519297544459, 990.6780296403698, 1058.1799105693149, 23987.880060599586, 32499.0, 32863.83568082192, 32998.50502242468, 33398.998030058654, 1095.6767753512574, 1095.7589330347312, 1129.3506233915532, 1140.0879296562716, 1158.44394690549, 10936.179786516042, 12339.69150771238, 12531.749365012649, 12575.056870734548, 12634.483861936626, 330462.0, 330766.0, 332969.0, 333709.0, 335142.0, 56599.0, 56604.58488537819, 56927.715361422925, 57117.42882571204, 57175.42824571784, 1509.7810817431412, 1510.184500369788, 1540.091346105803, 1547.3733138079097, 1552.7826104345402, 22927.0, 23491.060357585506, 23541.411464713517, 24320.391990199678, 24554.75445245596, 35095.47356789599, 40492.595074048884, 40502.79748601238, 40539.0, 40547.0, 286570.0, 287588.0, 289334.0, 289461.0, 290372.0, 36707.71522996682, 36941.63058369382, 37230.6276937238, 37276.813615931744, 37287.44068839024, 44893.0, 46861.53138468708, 47010.0, 47010.05979880452, 47095.293570595786, 29140.445547267333, 29578.852105739334, 30090.84954575213, 30789.846050769604, 31254.0, 29852.656630452115, 30737.385252295277, 31305.373892521566, 31618.683813161573, 31634.99467550579, 36700.816495918414, 37880.621193788815, 38519.614803851604, 38657.2268554633, 38675.6132438672, 29893.402131957675, 29954.700452995192, 30181.547276790425, 31155.532667010466, 31156.844215778776, 23421.648675269545, 25036.0, 25214.495710086063, 25448.745512544636, 25486.617700734874], "value": 17905.322133724778, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [441.85418811792107, 500.57017608814783, 502.9094762942671, 515.1371452816533, 528.5454509122156, 21725.565488690216, 22382.10471581137, 23029.769702302972, 23311.950962206698, 24818.627720584205, 23628.05487780487, 24144.0, 24346.02615895362, 24441.388965275833, 24483.877580612116, 6448.7742928997495, 6578.736850525973, 6595.670216489157, 6602.8679426411445, 6618.172728408949, 6174.50603951685, 6483.773067942623, 6525.347465253484, 6536.117624120754, 6539.542232043759, 6410.679466026703, 6473.5144864135145, 6481.2222533295835, 6487.221533415996, 6487.513436492259, 6469.12666789982, 6470.9029364559565, 6479.611223326592, 6496.870062598745, 6511.9348806512035, 5864.472197502245, 5883.79406720767, 5921.319048309457, 5922.58541902067, 5926.140709597127, 30231.395372092436, 30251.243718906982, 30265.697343026728, 30633.0, 31017.379652406824, 2244.4052326133587, 2278.111536500766, 2278.726552813673, 2279.2022792022826, 2282.931512054641, 13766.31168441574, 19197.232110715555, 19495.02524873765, 19787.604247915104, 19799.703004454946, 2189.2228258968, 2235.4970131720397, 2272.4091736148557, 2279.87460689662, 2291.7937385635373, 163754.0, 171147.99999999875, 202498.0, 204490.0, 212809.99999999846, 12354.75290494185, 15233.771493427608, 15236.466723664618, 15578.376864925503, 15606.29771660278, 16975.405860794814, 20201.080897314732, 20465.693014604793, 20567.280145194847, 20575.485612859797, 9912.851307230398, 9928.652497162564, 9939.155171810333, 9942.701718948443, 10002.449865257335, 7508.812279693051, 7575.469717119749, 8972.013078561353, 9069.274458043341, 9092.772680683036, 7954.886315915776, 9194.218491428304, 9203.539823008823, 9219.631214751469, 9295.442273463614, 2613.1376645706873, 2640.8943642254094, 2643.0, 2646.550086485264, 2788.1914244868763, 943.740471370365, 952.319091849331, 959.2794658513138, 991.7850632974693, 1003.9799204015878, 17186.0, 20148.899255503628, 20782.58434831295, 21228.0, 21850.562988740137, 980.7155924781733, 987.980240395188, 1015.6851376073441, 1016.7712264740522, 1028.6039874648166, 9903.356281841658, 11084.944575277234, 11264.380459074666, 13685.0, 14783.70432591342, 573875.0, 577479.0, 578469.0, 588203.0, 590871.0, 120688.0, 140246.2987685055, 145859.5414045846, 146293.0, 147910.0, 1019.887812340642, 1072.1904961753826, 1139.3619573039125, 1193.91045671575, 1216.173002358387, 21744.89127554352, 22697.09211631516, 24529.632055519538, 24695.62956555689, 25261.0, 68021.89522426749, 69742.0, 70266.0, 71079.57840843257, 71916.0, 80870.59564702139, 91186.0, 91308.54345728229, 91964.0, 92056.53971730366, 11381.43092845371, 11473.655790326304, 11965.641030769091, 11980.460879260629, 12099.939500302442, 18619.906900465412, 18729.063546822334, 18765.718514222026, 18776.436706898814, 18911.621767564848, 15185.0, 15315.310811013755, 15367.231638418258, 15386.99984501004, 15408.922955385151, 12309.44607492647, 13430.93284533571, 13745.793813092612, 13868.791968120688, 14322.355494002586, 15326.846731532542, 15413.614659633151, 15679.137647429501, 15724.685506289581, 15724.977876437995, 11794.115441342105, 11847.229930054518, 11947.581834635746, 11971.281723096643, 11984.221025633306, 12938.835504804612, 15007.549773506813, 15255.313510891814, 15265.23673816327, 15279.77080343818], "value": 11803.498505823103, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1161.3032180691584, 1304.708338744643, 1428.0860249440366, 1435.6267370483677, 1451.542764029331, 13250.933745331273, 13465.730685386286, 13850.376733047013, 13850.961177911659, 13872.167669939785, 13478.595642130751, 13508.729825403487, 13561.389737461814, 13668.726625467485, 13677.179369237852, 2370.7036620422446, 2605.413781899067, 2694.622752814603, 2737.288305040694, 2739.9726002740017, 2181.640029395148, 2236.027328112268, 2333.801626861719, 2604.0365064926004, 2693.2862791360303, 2493.7256901740793, 2504.7100743117308, 2728.208819442369, 2755.7519823215907, 2764.378014946641, 2684.7311527583115, 2690.9596356054676, 2697.608846717225, 2701.4597080583962, 2710.214037928998, 2110.7889211078923, 2677.8393296402082, 2681.423493948791, 2682.1685277563824, 2689.072270066824, 13161.341932903317, 13478.932605337008, 13509.121907076107, 13518.526851560246, 13525.458981640722, 7336.0, 7589.0, 7593.202713715035, 7623.161452240249, 7639.656215470317, 4009.777018009506, 4547.613452856511, 4726.291056341542, 4844.709317440929, 4846.7576621169155, 1362.50949658123, 1387.2300873015472, 1400.7128538649552, 1415.745165870143, 1429.3853642933534, 15907.20463976808, 19779.90110049455, 19801.702974455395, 19922.103505342293, 19952.0, 6280.55547224141, 6328.95572230586, 6510.08858759774, 6546.803595892131, 6559.376859198369, 14017.579472615838, 14108.0, 14121.011529193063, 14150.29248537569, 14194.716105677828, 7188.35304822558, 7336.266373362728, 7343.522671026366, 7348.816279593053, 7357.153927298322, 6569.474442044625, 6708.563943343667, 6732.697028633724, 6799.796006119825, 6809.761658341934, 4871.707697538159, 5032.0, 5098.439171691111, 5118.436971933084, 5125.615578831603, 1313.080843409617, 1357.6470117769254, 1418.3121186224673, 1453.6147920800997, 1475.3508456279417, 803.9638216280165, 824.0729179672868, 835.444429454406, 838.983220335602, 862.5773371048141, 7231.566106033655, 7637.274458926393, 7737.264959828807, 7803.20526278959, 7826.8434631307055, 955.7753927826955, 981.215027977615, 987.6740675577042, 990.5641517732175, 991.137710192123, 4674.789634466457, 4907.484714105039, 5091.694498330112, 5142.665726727751, 5229.895402091937, 17737.113144342733, 17823.37618183383, 18235.361762338256, 18240.087995600166, 18497.81502184961, 17999.73000404995, 18056.548586285186, 18090.90954545219, 18154.90922545379, 18156.36452724175, 9401.858972115424, 9451.574679139318, 9512.571934262975, 9534.618615255451, 9559.091886270793, 7537.886931696139, 7616.466847320856, 7702.0748995414815, 7719.884201736866, 7948.761537153895, 15960.441384551485, 15964.962277451928, 16172.757408638645, 16285.18574071268, 16293.348266069224, 16552.42066527714, 16692.24884880159, 16707.331706731595, 16753.83246167523, 16847.578810529834, 9758.9753075926, 9890.653827116017, 9902.950485247527, 9903.207743380657, 9926.404415735078, 12266.815997759863, 12400.56598019065, 12426.0, 12437.315947622606, 12535.686607834878, 9321.161095501166, 9815.55829987638, 10244.590216391261, 10362.585496580052, 10397.272190946562, 9992.400455972665, 10052.69841904744, 10065.345752526062, 10122.139618132247, 10161.695149145537, 9124.5437728112, 9289.303302252223, 9352.111549403067, 9354.251659867059, 9357.344985850925, 5847.363448103657, 5941.287045554561, 6170.10533472653, 6657.1345725055435, 7066.187388450394, 5845.255037075976, 6361.491080713439, 6828.248892621907, 7003.089598352181, 7058.6470676467], "value": 6015.607355366465, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [452.18155139198046, 454.62720569133313, 463.9605633521153, 464.1645038929925, 482.27899290560595, 5111.718855462948, 6292.244930608322, 6432.5497215194955, 6503.861824180767, 6505.577137486066, 5419.810306639269, 5586.692731899744, 6170.704152128047, 6311.431971122598, 6328.272248691402, 4625.537446255381, 4769.4515130760055, 4773.331733557305, 4805.903881922368, 4818.87952801181, 3981.940270895924, 4781.055629105984, 4797.424309082898, 4802.303665968432, 4824.831130910401, 4725.787339569728, 4733.219018861883, 4736.71579705217, 4747.786349614258, 4759.857204283877, 4750.432357322075, 4751.857444276678, 4758.666893317469, 4762.64280178988, 4783.880402989936, 4186.330187170038, 4191.993921458828, 4193.853215137486, 4201.327787554007, 4208.558101399371, 5272.815451459181, 6212.627242365473, 6238.095476155977, 6245.657878295639, 6267.655278959655, 4750.857474275777, 5677.6309539880185, 5677.914831277534, 5682.119271512922, 5719.942800571983, 5844.883102337972, 5968.522518198577, 6194.079835251042, 6836.794896153124, 6856.760013399557, 4747.45404278509, 4772.665913386064, 4772.832950846704, 4800.807967681289, 4807.8364735869, 6368.936310636881, 6868.278830722802, 7125.289930416715, 7190.173130090055, 7263.927360726378, 4262.744235345889, 4500.932486012713, 4820.542048505386, 4823.879403014953, 4833.564979151894, 4806.375171227788, 4850.975745121252, 4929.531694489017, 4946.876328091755, 4956.330895329084, 5474.0, 5548.8082207527805, 5594.86012849682, 5607.52336051432, 5643.633163844419, 6829.692663830139, 6847.82282807487, 6847.897281540781, 6858.554193977376, 6878.484113691393, 1494.7234761569146, 1677.379369633219, 1689.4847071643267, 1690.6196105876325, 1699.9460334592632, 929.9349045566745, 1025.2618114957368, 1050.6427814543092, 1115.5314767797545, 1117.8323251512338, 6126.846828829226, 6243.845873173747, 6379.425851673372, 6457.030605665223, 6461.19235095613, 6127.387261273837, 6951.89572156418, 7079.787606371817, 7081.327273908971, 7130.750423735144, 5415.512603865593, 6047.909281360783, 6410.45511131559, 6417.807465776034, 6425.485961123192, 3464.7401444891734, 3675.8162091895833, 3687.8978981980013, 3698.7794027970704, 3715.479832823407, 6117.663528505975, 6661.56929181472, 7190.928090719026, 7265.346118849329, 7326.377257933029, 6399.776007839703, 7193.496455248187, 7257.564546127249, 7268.781936541912, 7270.200277969525, 7193.172785129673, 7265.745698900513, 7277.781666550012, 7290.3438690518105, 7292.848609660163, 5523.8066667666435, 6153.261608606996, 6819.079424277659, 7019.824504387431, 7043.0, 6379.712912918837, 6943.409810166192, 6946.5484743493535, 6959.304069593, 6988.720451182099, 6052.334243233153, 6683.832904177434, 7220.9529618204015, 7232.74685386009, 7263.0921134858145, 4205.222033923734, 4737.489130760702, 4811.831585894617, 4848.490908454562, 4853.029394121119, 6834.760783372558, 7047.577145371294, 7060.894086588808, 7067.752628658178, 7092.120588044069, 5133.974330128325, 6506.967465162644, 6605.57063790852, 6789.694463749242, 6807.9319206807295, 5477.780888764405, 5670.773169073191, 6874.587524748531, 6876.0, 6880.139982502187, 5669.363745858196, 6592.923229182792, 6717.798466046026, 6718.932810672026, 6729.0, 6790.966045169743, 6798.626075565693, 6847.349501797321, 6861.416779573792, 6872.312768723088, 5929.407059294036, 6591.0, 6666.83332916681, 6700.932990670226, 6754.155730533684], "value": 5008.756752640677, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [831.538496134645, 833.6623667414698, 840.3823189955382, 849.9503113654636, 873.7771868173617, 13653.590392288235, 14574.125552466832, 15195.240237988084, 17125.058121803297, 17265.48203553894, 16948.406805761802, 17597.912010439963, 17661.82338176618, 17722.291108355683, 17729.91135044326, 926.805370872116, 1025.9538320775546, 1035.7100011996615, 1038.5482315192878, 1041.2919214933843, 1037.8598889149982, 1046.4924511611862, 1046.8691413573304, 1048.979020419591, 1050.6532844161447, 975.7999610079917, 1076.5048077884182, 1080.864891888514, 1081.826907694769, 1082.9874067746644, 1066.786642671461, 1068.8984546468112, 1069.3370110531444, 1073.4954571351452, 1080.778440419712, 644.6905485367004, 761.4936067515151, 775.2054144501931, 785.8428314337099, 786.319833344159, 17136.57158571046, 17424.91287543554, 17552.561185970324, 17703.02633355165, 18102.45692629223, 14217.715645687029, 17431.302747890197, 17450.825491745047, 17964.371247006424, 18011.0, 14103.71792564143, 14213.360398781977, 14361.497347592784, 14391.2084835334, 14397.280135993264, 3581.4090675038588, 3583.3549961007006, 3618.149734812317, 3619.6561326673923, 3627.4014787560286, 146707.0, 147286.0, 149246.25376873155, 149370.50629493676, 150778.24610876874, 13551.25468099264, 13718.314084295745, 13750.312484375941, 13885.166889986633, 13911.930440347936, 26633.866830665724, 26997.78509967056, 27049.323766905592, 27109.728902711115, 27142.864285678446, 7098.574085554883, 7218.45861560385, 7668.769936901902, 8145.51126932386, 8146.918530814616, 3865.091703449687, 3945.822437990297, 3945.9605403946166, 3946.5856085111222, 3949.2101579684177, 4560.0, 4622.075584883037, 4681.110588988081, 4681.297805329228, 4967.850964471072, 1035.9119474844572, 1049.4333060147587, 1062.8830828608848, 1062.914966802654, 1065.8880817514205, 646.8221239159269, 648.3775575447529, 659.3768888400416, 667.4660271782554, 680.792358330704, 11409.942950285196, 12121.151519393734, 12758.872411275954, 12894.29081400532, 12912.741745165044, 647.1489990662265, 649.8018104478085, 659.7822718502979, 663.9834004149935, 664.8304682305981, 5602.579806514527, 5902.2622172228475, 6172.197614310111, 6577.901331480031, 6595.764856191695, 123771.3811430937, 138662.0, 138760.0, 141246.0, 143307.0, 113751.43124284326, 119672.40163799125, 124221.0, 124375.0, 126958.36520817337, 94603.51101691538, 96677.0332296682, 98420.0, 100017.0, 100592.99407005982, 10213.0, 12294.0, 12511.0, 12940.547080852126, 13264.46942122343, 43774.62253774597, 45181.54818451905, 45257.0, 45428.0, 45992.54007460016, 71811.28188718254, 77544.0, 80419.59790201012, 83714.58142709247, 84244.15755842609, 17731.0, 17778.733318999966, 17913.910430448286, 18054.09729513493, 18107.09464526742, 15517.45688900883, 18396.724049139008, 18912.14895329741, 19109.23563057462, 19337.323193688717, 14880.255987200813, 15342.463013794464, 17439.30242790274, 18393.724094138866, 18537.90731046336, 16865.494035178963, 16895.732820038793, 17346.65306693834, 17477.563060923578, 17483.0, 16693.415730449375, 17757.0, 17772.377966771102, 18014.36949706754, 18080.0, 11753.412329383325, 11867.0, 12097.81853272184, 12365.07261955375, 12407.0, 13065.542706005624, 13872.930635346758, 13879.514217002357, 14169.716605668034, 14447.422103116178], "value": 8945.680105353395, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1540.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 118.0, 101.0, 115.0, 117.0, 117.0, 116.0, 114.0, 114.0, 114.0], "value": 113.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 109.0, 131.0, 156.0, 136.0, 162.0, 114.0, 182.0, 171.0, 118.0], "value": 142.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [208.0, 196.0, 211.0, 224.0, 208.0, 233.0, 201.0, 199.0, 199.0, 195.0], "value": 207.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 80.0, 79.0, 150.0, 57.0, 58.0, 57.0, 61.0, 128.0, 58.0], "value": 79.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 70.0, 115.0, 70.0, 72.0, 77.0, 109.0, 72.0, 74.0, 115.0], "value": 84.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 89.0, 87.0, 87.0, 89.0, 87.0, 88.0, 97.0, 89.0, 89.0], "value": 88.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 95.0, 95.0, 95.0, 96.0, 95.0, 97.0, 95.0, 122.0, 114.0], "value": 100.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 90.0, 123.0, 80.0, 140.0, 142.0, 90.0, 140.0, 96.0, 118.0], "value": 110.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 51.0, 55.0, 66.0, 67.0, 54.0, 55.0, 54.0, 52.0, 54.0], "value": 55.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 65.0, 49.0, 59.0, 57.0, 82.0, 59.0, 46.0, 47.0, 47.0], "value": 56.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 107.0, 111.0, 84.0, 83.0, 85.0, 88.0, 84.0, 114.0, 91.0], "value": 94.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 144.0, 149.0, 134.0, 146.0, 139.0, 140.0, 152.0, 138.0, 139.0], "value": 143.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 231.0, 179.0, 182.0, 185.0, 186.0, 229.0, 180.0, 183.0, 183.0], "value": 192.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 68.0, 76.0, 68.0, 96.0, 69.0, 68.0, 65.0, 68.0], "value": 70.8, "unit": "score"}]}]} 05:48:18 INFO - Return code: 0 05:48:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:48:18 INFO - # TBPL SUCCESS # 05:48:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:48:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:48:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:48:18 INFO - 2017-06-09 05:48:18.886 screenresolution[2002:14334] starting screenresolution argv=screenresolution get 05:48:18 INFO - 2017-06-09 05:48:18.889 screenresolution[2002:14334] Display 0: 1600x1200x32@60 05:48:18 INFO - 2017-06-09 05:48:18.895 screenresolution[2003:14337] starting screenresolution argv=screenresolution list 05:48:18 INFO - Available Modes on Display 0 05:48:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:48:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:48:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:48:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:48:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:48:19 INFO - Intel Iris: 05:48:19 INFO - Chipset Model: Intel Iris 05:48:19 INFO - Type: GPU 05:48:19 INFO - Bus: Built-In 05:48:19 INFO - VRAM (Dynamic, Max): 1536 MB 05:48:19 INFO - Vendor: Intel (0x8086) 05:48:19 INFO - Device ID: 0x0a2e 05:48:19 INFO - Revision ID: 0x0009 05:48:19 INFO - Displays: 05:48:19 INFO - Display: 05:48:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:48:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:48:19 INFO - Display Serial Number: 1600x1200 60 05:48:19 INFO - Main Display: Yes 05:48:19 INFO - Mirror: Off 05:48:19 INFO - Online: Yes 05:48:19 INFO - Rotation: Supported 05:48:19 INFO - Return code: 0 05:48:19 INFO - Running post-action listener: _resource_record_post_action 05:48:19 INFO - [mozharness: 2017-06-09 12:48:19.130605Z] Finished run-tests step (success) 05:48:19 INFO - Running post-run listener: _resource_record_post_run 05:48:19 INFO - Total resource usage - Wall time: 1257s; CPU: 53.0%; Read bytes: 1665024; Write bytes: 578777088; Read time: 22; Write time: 1880 05:48:19 INFO - TinderboxPrint: CPU usage
53.1% 05:48:19 INFO - TinderboxPrint: I/O read bytes / time
1,665,024 / 22 05:48:19 INFO - TinderboxPrint: I/O write bytes / time
578,777,088 / 1,880 05:48:19 INFO - TinderboxPrint: CPU idle
2,358.2 (46.9%) 05:48:19 INFO - TinderboxPrint: CPU system
1,017.7 (20.2%) 05:48:19 INFO - TinderboxPrint: CPU user
1,653.8 (32.9%) 05:48:19 INFO - TinderboxPrint: Swap in / out
253,161,472 / 0 05:48:19 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 244918784; Write bytes: 239503360; Read time: 18042; Write time: 475 05:48:19 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 05:48:19 INFO - run-tests - Wall time: 1236s; CPU: 53.0%; Read bytes: 1656832; Write bytes: 323020800; Read time: 21; Write time: 1361 05:48:19 INFO - Running post-run listener: _upload_blobber_files 05:48:19 INFO - Blob upload gear active. 05:48:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:48:19 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:48:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:48:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:48:20 INFO - (blobuploader) - INFO - Open directory for files ... 05:48:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:48:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:48:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:48:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:48:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:48:21 INFO - (blobuploader) - INFO - Done attempting. 05:48:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:48:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:48:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:48:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:48:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:48:22 INFO - (blobuploader) - INFO - Done attempting. 05:48:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:48:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:48:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:48:23 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:48:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:48:23 INFO - (blobuploader) - INFO - Done attempting. 05:48:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:48:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:48:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:48:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:48:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:48:25 INFO - (blobuploader) - INFO - Done attempting. 05:48:25 INFO - (blobuploader) - INFO - Iteration through files over. 05:48:25 INFO - Return code: 0 05:48:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:48:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:48:25 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/904c00818bc240f6d7dbaf41f6000b10a57172ffae40ff51799681f0482b5faed1aafce0201932e8538f8c1d1208f296e1a280d75f207a145f7ad5dab78f790c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0cce85e5a52e305a8777457520313aa16da7bc204d11b2f3244246350dd797d9e490fb0a3a76f2f7a76ca36ec4d8d6359641ed4641593f3d6cae9d691f671a87", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2d7cb97e111747070b810b9b006eb663e8fd4f005537a1a6d5bae13b2d42b75ac79550199309be834b04933695fcb21e90e3438f6dee6c9b3f02ebf774ab98e2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e29732002f1e724e632d1d63561c619da9d0e6a8fe4429013a44585344c9614052adbc3a4a86c3e2b4389601ccb9f15712117d12bfba5ee156a238e5cd756a0b"} 05:48:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:48:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:48:25 INFO - Contents: 05:48:25 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/904c00818bc240f6d7dbaf41f6000b10a57172ffae40ff51799681f0482b5faed1aafce0201932e8538f8c1d1208f296e1a280d75f207a145f7ad5dab78f790c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0cce85e5a52e305a8777457520313aa16da7bc204d11b2f3244246350dd797d9e490fb0a3a76f2f7a76ca36ec4d8d6359641ed4641593f3d6cae9d691f671a87", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2d7cb97e111747070b810b9b006eb663e8fd4f005537a1a6d5bae13b2d42b75ac79550199309be834b04933695fcb21e90e3438f6dee6c9b3f02ebf774ab98e2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e29732002f1e724e632d1d63561c619da9d0e6a8fe4429013a44585344c9614052adbc3a4a86c3e2b4389601ccb9f15712117d12bfba5ee156a238e5cd756a0b"} 05:48:25 INFO - Running post-run listener: copy_logs_to_upload_dir 05:48:25 INFO - Copying logs to upload dir... 05:48:25 INFO - mkdir: /builds/slave/test/build/upload/logs 05:48:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1339.165975 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-06-09 05:48:25.134126) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-09 05:48:25.137580) ========= 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.Y1HcDw7Fa7/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.2E6IVV5AYQ/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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/904c00818bc240f6d7dbaf41f6000b10a57172ffae40ff51799681f0482b5faed1aafce0201932e8538f8c1d1208f296e1a280d75f207a145f7ad5dab78f790c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0cce85e5a52e305a8777457520313aa16da7bc204d11b2f3244246350dd797d9e490fb0a3a76f2f7a76ca36ec4d8d6359641ed4641593f3d6cae9d691f671a87", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2d7cb97e111747070b810b9b006eb663e8fd4f005537a1a6d5bae13b2d42b75ac79550199309be834b04933695fcb21e90e3438f6dee6c9b3f02ebf774ab98e2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e29732002f1e724e632d1d63561c619da9d0e6a8fe4429013a44585344c9614052adbc3a4a86c3e2b4389601ccb9f15712117d12bfba5ee156a238e5cd756a0b"} build_url:https://queue.taskcluster.net/v1/task/Wx3sesJGQgqAxd-UY1QTGg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011196 build_url: 'https://queue.taskcluster.net/v1/task/Wx3sesJGQgqAxd-UY1QTGg/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/904c00818bc240f6d7dbaf41f6000b10a57172ffae40ff51799681f0482b5faed1aafce0201932e8538f8c1d1208f296e1a280d75f207a145f7ad5dab78f790c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0cce85e5a52e305a8777457520313aa16da7bc204d11b2f3244246350dd797d9e490fb0a3a76f2f7a76ca36ec4d8d6359641ed4641593f3d6cae9d691f671a87", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2d7cb97e111747070b810b9b006eb663e8fd4f005537a1a6d5bae13b2d42b75ac79550199309be834b04933695fcb21e90e3438f6dee6c9b3f02ebf774ab98e2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e29732002f1e724e632d1d63561c619da9d0e6a8fe4429013a44585344c9614052adbc3a4a86c3e2b4389601ccb9f15712117d12bfba5ee156a238e5cd756a0b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-09 05:48:25.173018) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-09 05:48:25.173487) ========= 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.Y1HcDw7Fa7/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.2E6IVV5AYQ/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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.005367 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-09 05:48:25.226343) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-09 05:48:25.227069) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-09 05:48:25.227760) ========= ========= Total master_lag: 0.19 =========