builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0379 starttime: 1495479662.59 results: success (0) revision: f9ca97a334296facd2e0ea5582e7f12d0fe70fe4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-22 12:01:02.590578) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-22 12:01:02.591048) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-22 12:01:02.591325) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-22 12:01:02.616248) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 12:01:02.616724) ========= 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.T740Hc0YGc/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.zsyEpH4z6N/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-22 12:01:02-- 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% 63.5M=0s 2017-05-22 12:01:02 (63.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.207424 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 12:01:02.847059) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 12:01:02.847415) ========= 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.T740Hc0YGc/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.zsyEpH4z6N/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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.031559 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 12:01:02.900478) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-22 12:01:02.900853) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f9ca97a334296facd2e0ea5582e7f12d0fe70fe4 --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 f9ca97a334296facd2e0ea5582e7f12d0fe70fe4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.T740Hc0YGc/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.zsyEpH4z6N/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-22 12:01:02,997 truncating revision to first 12 chars 2017-05-22 12:01:02,997 Setting DEBUG logging. 2017-05-22 12:01:02,997 attempt 1/10 2017-05-22 12:01:02,997 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f9ca97a33429?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-22 12:01:04,421 unpacking tar archive at: mozilla-central-f9ca97a33429/testing/mozharness/ program finished with exit code 0 elapsedTime=1.708601 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-22 12:01:04.631868) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-22 12:01:04.632466) ========= script_repo_revision: f9ca97a334296facd2e0ea5582e7f12d0fe70fe4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-22 12:01:04.632972) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-22 12:01:04.633378) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-22 12:01:04.652557) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2017-05-22 12:01:04.652958) ========= /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.T740Hc0YGc/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.zsyEpH4z6N/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 12:01:04 INFO - MultiFileLogger online at 20170522 12:01:04 in /builds/slave/test 12:01:04 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 12:01:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:01:04 INFO - {'append_to_log': False, 12:01:04 INFO - 'base_work_dir': '/builds/slave/test', 12:01:04 INFO - 'blob_upload_branch': 'Firefox', 12:01:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:01:04 INFO - 'branch': 'Firefox', 12:01:04 INFO - 'buildbot_json_path': 'buildprops.json', 12:01:04 INFO - 'config_files': ('talos/mac_config.py',), 12:01:04 INFO - 'default_actions': ('clobber', 12:01:04 INFO - 'read-buildbot-config', 12:01:04 INFO - 'download-and-extract', 12:01:04 INFO - 'populate-webroot', 12:01:04 INFO - 'create-virtualenv', 12:01:04 INFO - 'install', 12:01:04 INFO - 'run-tests'), 12:01:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:01:04 INFO - 'download_minidump_stackwalk': True, 12:01:04 INFO - 'download_symbols': 'ondemand', 12:01:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:01:04 INFO - 'tooltool.py': '/tools/tooltool.py', 12:01:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:01:04 INFO - '/tools/misc-python/virtualenv.py')}, 12:01:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:01:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:01:04 INFO - 'gecko_profile': False, 12:01:04 INFO - 'gecko_profile_interval': 0, 12:01:04 INFO - 'installer_path': 'installer.exe', 12:01:04 INFO - 'log_level': 'info', 12:01:04 INFO - 'log_name': 'talos', 12:01:04 INFO - 'log_to_console': True, 12:01:04 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:01:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:01:04 INFO - 'opt_config_files': (), 12:01:04 INFO - 'pip_index': False, 12:01:04 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:01:04 INFO - 'cmd': ('bash', 12:01:04 INFO - '-c', 12:01:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:01:04 INFO - 'enabled': True, 12:01:04 INFO - 'halt_on_failure': False, 12:01:04 INFO - 'name': 'check_screen_resolution'},), 12:01:04 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:01:04 INFO - 'cmd': ('bash', 12:01:04 INFO - '-c', 12:01:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:01:04 INFO - 'enabled': True, 12:01:04 INFO - 'halt_on_failure': False, 12:01:04 INFO - 'name': 'check_screen_resolution'},), 12:01:04 INFO - 'run_cmd_checks_enabled': True, 12:01:04 INFO - 'suite': 'dromaeojs-e10s', 12:01:04 INFO - 'system_bits': '32', 12:01:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:01:04 INFO - 'title': 't-yosemite-r7-0379', 12:01:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:01:04 INFO - 'use_talos_json': True, 12:01:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:01:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:01:04 INFO - 'work_dir': 'build'} 12:01:04 INFO - [mozharness: 2017-05-22 19:01:04.858688Z] Running clobber step. 12:01:04 INFO - Running pre-action listener: _resource_record_pre_action 12:01:04 INFO - Running main action method: clobber 12:01:04 INFO - rmtree: /builds/slave/test/build 12:01:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:01:07 INFO - Running post-action listener: _resource_record_post_action 12:01:07 INFO - [mozharness: 2017-05-22 19:01:07.437640Z] Finished clobber step (success) 12:01:07 INFO - [mozharness: 2017-05-22 19:01:07.437768Z] Running read-buildbot-config step. 12:01:07 INFO - Running pre-action listener: _resource_record_pre_action 12:01:07 INFO - Running main action method: read_buildbot_config 12:01:07 INFO - Using buildbot properties: 12:01:07 INFO - { 12:01:07 INFO - "project": "", 12:01:07 INFO - "product": "firefox", 12:01:07 INFO - "who": "archaeopteryx@coole-files.de", 12:01:07 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 12:01:07 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 12:01:07 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 12:01:07 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 12:01:07 INFO - "stage_platform": "macosx64", 12:01:07 INFO - "basedir": "/builds/slave/test", 12:01:07 INFO - "buildnumber": 63, 12:01:07 INFO - "platform": "yosemite_r7", 12:01:07 INFO - "parent_task_id": "E5_26LKdQLaB4QXRpKyufw", 12:01:07 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 12:01:07 INFO - "slavebuilddir": "test", 12:01:07 INFO - "taskId": "Fvv5FZSnQSi_oXDajoOygA", 12:01:07 INFO - "branch": "mozilla-central", 12:01:07 INFO - "script_repo_revision": "production", 12:01:07 INFO - "revision": "f9ca97a334296facd2e0ea5582e7f12d0fe70fe4", 12:01:07 INFO - "slavename": "t-yosemite-r7-0379", 12:01:07 INFO - "repo_path": "mozilla-central" 12:01:07 INFO - } 12:01:07 INFO - Finding installer, test and symbols from parent task. 12:01:07 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Fvv5FZSnQSi_oXDajoOygA'}, attempt #1 12:01:08 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Fvv5FZSnQSi_oXDajoOygA'}, attempt #1 12:01:08 INFO - Task dependencies: W51mBcb6RQSDeoHA4z-6oQ E5_26LKdQLaB4QXRpKyufw 12:01:08 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E5_26LKdQLaB4QXRpKyufw'}, attempt #1 12:01:09 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/E5_26LKdQLaB4QXRpKyufw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 12:01:09 INFO - Set test_url: https://queue.taskcluster.net/v1/task/E5_26LKdQLaB4QXRpKyufw/artifacts/public/build/test_packages.json 12:01:09 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/E5_26LKdQLaB4QXRpKyufw/artifacts/public/build/target.crashreporter-symbols.zip 12:01:09 INFO - Running post-action listener: _resource_record_post_action 12:01:09 INFO - [mozharness: 2017-05-22 19:01:09.187860Z] Finished read-buildbot-config step (success) 12:01:09 INFO - [mozharness: 2017-05-22 19:01:09.187976Z] Running download-and-extract step. 12:01:09 INFO - Running pre-action listener: _resource_record_pre_action 12:01:09 INFO - Running main action method: download_and_extract 12:01:09 INFO - mkdir: /builds/slave/test/build/tests 12:01:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:01:09 INFO - trying https://queue.taskcluster.net/v1/task/E5_26LKdQLaB4QXRpKyufw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 12:01:09 INFO - Downloading https://queue.taskcluster.net/v1/task/E5_26LKdQLaB4QXRpKyufw/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 12:01:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E5_26LKdQLaB4QXRpKyufw/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 12:01:10 INFO - Downloaded 1325 bytes. 12:01:10 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 12:01:10 INFO - Using the following test package requirements: 12:01:10 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:01:10 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 12:01:10 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 12:01:10 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:01:10 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 12:01:10 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:01:10 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 12:01:10 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:01:10 INFO - u'jsshell-mac.zip'], 12:01:10 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:01:10 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 12:01:10 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 12:01:10 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:01:10 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 12:01:10 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:01:10 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 12:01:10 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:01:10 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 12:01:10 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:01:10 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 12:01:10 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:01:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E5_26LKdQLaB4QXRpKyufw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 12:01:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E5_26LKdQLaB4QXRpKyufw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:01:10 INFO - Fetch https://queue.taskcluster.net/v1/task/E5_26LKdQLaB4QXRpKyufw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 12:01:11 INFO - Content-Length response header: 57622768 12:01:11 INFO - Bytes received: 57622768 12:01:29 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 12:01:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E5_26LKdQLaB4QXRpKyufw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 12:01:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E5_26LKdQLaB4QXRpKyufw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:01:29 INFO - Fetch https://queue.taskcluster.net/v1/task/E5_26LKdQLaB4QXRpKyufw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 12:01:31 INFO - Content-Length response header: 57622768 12:01:31 INFO - Bytes received: 57622768 12:01:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E5_26LKdQLaB4QXRpKyufw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 12:01:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E5_26LKdQLaB4QXRpKyufw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 12:01:48 INFO - Fetch https://queue.taskcluster.net/v1/task/E5_26LKdQLaB4QXRpKyufw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 12:01:49 INFO - Content-Length response header: 14410000 12:01:49 INFO - Bytes received: 14410000 12:01:50 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')]} 12:01:50 INFO - trying https://queue.taskcluster.net/v1/task/E5_26LKdQLaB4QXRpKyufw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 12:01:50 INFO - Downloading https://queue.taskcluster.net/v1/task/E5_26LKdQLaB4QXRpKyufw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 12:01:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E5_26LKdQLaB4QXRpKyufw/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 12:01:52 INFO - Downloaded 67990377 bytes. 12:01:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/E5_26LKdQLaB4QXRpKyufw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 12:01:52 INFO - mkdir: /builds/slave/test/properties 12:01:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:01:52 INFO - Writing to file /builds/slave/test/properties/build_url 12:01:52 INFO - Contents: 12:01:52 INFO - build_url:https://queue.taskcluster.net/v1/task/E5_26LKdQLaB4QXRpKyufw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 12:01:52 INFO - Running post-action listener: _resource_record_post_action 12:01:52 INFO - Running post-action listener: set_extra_try_arguments 12:01:52 INFO - [mozharness: 2017-05-22 19:01:52.264047Z] Finished download-and-extract step (success) 12:01:52 INFO - [mozharness: 2017-05-22 19:01:52.264171Z] Running populate-webroot step. 12:01:52 INFO - Running pre-action listener: _resource_record_pre_action 12:01:52 INFO - Running main action method: populate_webroot 12:01:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:01:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:01:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:01:52 INFO - '--tppagecycles', 12:01:52 INFO - '7'], 12:01:52 INFO - 'tests': ['tsvgm']}}, 12:01:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:01:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:01:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:01:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:01:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:01:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:01:52 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:01:52 INFO - 'talos_options': ['--disable-e10s'], 12:01:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:01:52 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:01:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:01:52 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:01:52 INFO - 'talos_options': ['--disable-e10s'], 12:01:52 INFO - 'tests': ['damp', 'tps']}, 12:01:52 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:01:52 INFO - 'tests': ['damp', 'tps']}, 12:01:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:01:52 INFO - 'tests': ['dromaeo_dom']}, 12:01:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:01:52 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:01:52 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 12:01:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 12:01:52 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 12:01:52 INFO - 'tests': ['ts_paint', 'tp5o']}, 12:01:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:01:52 INFO - 'tests': ['a11yr', 12:01:52 INFO - 'ts_paint', 12:01:52 INFO - 'tpaint', 12:01:52 INFO - 'sessionrestore', 12:01:52 INFO - 'sessionrestore_no_auto_restore', 12:01:52 INFO - 'tabpaint']}, 12:01:52 INFO - 'other-e10s': {'tests': ['a11yr', 12:01:52 INFO - 'ts_paint', 12:01:52 INFO - 'tpaint', 12:01:52 INFO - 'sessionrestore', 12:01:52 INFO - 'sessionrestore_no_auto_restore', 12:01:52 INFO - 'tabpaint']}, 12:01:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:01:52 INFO - 'ts_paint', 12:01:52 INFO - 'tpaint', 12:01:52 INFO - 'sessionrestore', 12:01:52 INFO - 'sessionrestore_no_auto_restore', 12:01:52 INFO - 'tabpaint']}, 12:01:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:01:52 INFO - 'ts_paint', 12:01:52 INFO - 'tpaint', 12:01:52 INFO - 'sessionrestore', 12:01:52 INFO - 'sessionrestore_no_auto_restore', 12:01:52 INFO - 'tabpaint']}, 12:01:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:01:52 INFO - 'tests': ['a11yr', 12:01:52 INFO - 'ts_paint', 12:01:52 INFO - 'tpaint', 12:01:52 INFO - 'sessionrestore', 12:01:52 INFO - 'sessionrestore_no_auto_restore', 12:01:52 INFO - 'tabpaint']}, 12:01:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:01:52 INFO - 'tests': ['a11yr', 12:01:52 INFO - 'ts_paint', 12:01:52 INFO - 'tpaint', 12:01:52 INFO - 'sessionrestore', 12:01:52 INFO - 'sessionrestore_no_auto_restore', 12:01:52 INFO - 'tabpaint']}, 12:01:52 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 12:01:52 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 12:01:52 INFO - 'bloom_basic_ref']}, 12:01:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:01:52 INFO - 'tests': ['tsvgx', 12:01:52 INFO - 'tsvgr_opacity', 12:01:52 INFO - 'tart', 12:01:52 INFO - 'tscrollx', 12:01:52 INFO - 'cart', 12:01:52 INFO - 'tsvg_static']}, 12:01:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:01:52 INFO - 'tsvgr_opacity', 12:01:52 INFO - 'tart', 12:01:52 INFO - 'tscrollx', 12:01:52 INFO - 'cart', 12:01:52 INFO - 'tsvg_static']}, 12:01:52 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:01:52 INFO - 'talos_options': ['--disable-e10s'], 12:01:52 INFO - 'tests': ['tp5o']}, 12:01:52 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:01:52 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:01:52 INFO - 'talos_options': ['--disable-e10s', 12:01:52 INFO - '--xperf_path', 12:01:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:01:52 INFO - 'tests': ['tp5n']}, 12:01:52 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:01:52 INFO - 'talos_options': ['--xperf_path', 12:01:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:01:52 INFO - 'tests': ['tp5n']}}, 12:01:52 INFO - 'talos.zip': {'path': '', 12:01:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:01:52 INFO - Running post-action listener: _resource_record_post_action 12:01:52 INFO - [mozharness: 2017-05-22 19:01:52.286268Z] Finished populate-webroot step (success) 12:01:52 INFO - [mozharness: 2017-05-22 19:01:52.286396Z] Running create-virtualenv step. 12:01:52 INFO - Running pre-action listener: _resource_record_pre_action 12:01:52 INFO - Running main action method: create_virtualenv 12:01:52 INFO - Creating virtualenv /builds/slave/test/build/venv 12:01:52 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 12:01:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:01:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:01:52 INFO - Using real prefix '/tools/python27' 12:01:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:01:53 INFO - Installing distribute.............................................................................................................................................................................................done. 12:01:56 INFO - Installing pip.................done. 12:01:56 INFO - Return code: 0 12:01:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:01:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:01:56 INFO - Reading from file tmpfile_stdout 12:01:56 INFO - Output received: 12:01:56 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) 12:01:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:01:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:01:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:01:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:56 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 0x1104feb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110502768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb61d6c0c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11020ed30>, '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 0x1101c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110203be8>, '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.T740Hc0YGc/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.zsyEpH4z6N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 12:01:56 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 12:01:56 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 12:01:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T740Hc0YGc/Render', 12:01:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:01:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:01:56 INFO - 'HOME': '/Users/cltbld', 12:01:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:01:56 INFO - 'LOGNAME': 'cltbld', 12:01:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:56 INFO - 'MOZ_NO_REMOTE': '1', 12:01:56 INFO - 'NO_EM_RESTART': '1', 12:01:56 INFO - 'PAGER': '/bin/cat', 12:01:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:01:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:56 INFO - 'PWD': '/builds/slave/test', 12:01:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:01:56 INFO - 'SHELL': '/bin/bash', 12:01:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zsyEpH4z6N/Listeners', 12:01:56 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 12:01:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:01:56 INFO - 'USER': 'cltbld', 12:01:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:01:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:01:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:56 INFO - 'XPC_FLAGS': '0x0', 12:01:56 INFO - 'XPC_SERVICE_NAME': '0', 12:01:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:01:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:56 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 12:01:56 INFO - Cleaning up... 12:01:56 INFO - Return code: 0 12:01:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:01:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:01:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:01:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:56 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 0x1104feb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110502768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb61d6c0c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11020ed30>, '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 0x1101c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110203be8>, '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.T740Hc0YGc/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.zsyEpH4z6N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 12:01:56 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 12:01:56 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 12:01:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T740Hc0YGc/Render', 12:01:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:01:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:01:56 INFO - 'HOME': '/Users/cltbld', 12:01:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:01:56 INFO - 'LOGNAME': 'cltbld', 12:01:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:56 INFO - 'MOZ_NO_REMOTE': '1', 12:01:56 INFO - 'NO_EM_RESTART': '1', 12:01:56 INFO - 'PAGER': '/bin/cat', 12:01:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:01:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:56 INFO - 'PWD': '/builds/slave/test', 12:01:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:01:56 INFO - 'SHELL': '/bin/bash', 12:01:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zsyEpH4z6N/Listeners', 12:01:56 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 12:01:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:01:56 INFO - 'USER': 'cltbld', 12:01:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:01:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:01:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:56 INFO - 'XPC_FLAGS': '0x0', 12:01:56 INFO - 'XPC_SERVICE_NAME': '0', 12:01:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:01:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:56 INFO - Downloading/unpacking psutil>=3.1.1 12:01:56 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 12:01:56 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 12:01:56 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 12:01:56 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 12:01:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:01:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:01:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:01:58 INFO - Installing collected packages: psutil 12:01:58 INFO - Running setup.py install for psutil 12:01:58 INFO - building 'psutil._psutil_osx' extension 12:01:58 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 12:01:59 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 12:01:59 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 12:01:59 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 12:01:59 INFO - building 'psutil._psutil_posix' extension 12:01:59 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 12:01:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:01:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:01:59 INFO - ^ 12:01:59 INFO - 1 warning generated. 12:01:59 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 12:01:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:01:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:01:59 INFO - Successfully installed psutil 12:01:59 INFO - Cleaning up... 12:01:59 INFO - Return code: 0 12:01:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:01:59 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')]} 12:01:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1104feb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110502768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb61d6c0c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11020ed30>, '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 0x1101c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110203be8>, '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.T740Hc0YGc/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.zsyEpH4z6N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 12:02:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:02:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:02:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T740Hc0YGc/Render', 12:02:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:02:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:02:00 INFO - 'HOME': '/Users/cltbld', 12:02:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:02:00 INFO - 'LOGNAME': 'cltbld', 12:02:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:00 INFO - 'MOZ_NO_REMOTE': '1', 12:02:00 INFO - 'NO_EM_RESTART': '1', 12:02:00 INFO - 'PAGER': '/bin/cat', 12:02:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:02:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:00 INFO - 'PWD': '/builds/slave/test', 12:02:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:02:00 INFO - 'SHELL': '/bin/bash', 12:02:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zsyEpH4z6N/Listeners', 12:02:00 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 12:02:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:02:00 INFO - 'USER': 'cltbld', 12:02:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:02:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:02:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:00 INFO - 'XPC_FLAGS': '0x0', 12:02:00 INFO - 'XPC_SERVICE_NAME': '0', 12:02:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:02:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:02:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:02:00 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 12:02:00 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 12:02:00 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 12:02:00 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 12:02:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:02:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:02:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:02:02 INFO - Installing collected packages: mozsystemmonitor 12:02:02 INFO - Running setup.py install for mozsystemmonitor 12:02:02 INFO - Successfully installed mozsystemmonitor 12:02:02 INFO - Cleaning up... 12:02:02 INFO - Return code: 0 12:02:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:02: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')]} 12:02:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02: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')]} 12:02:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02: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', '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 0x1104feb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110502768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb61d6c0c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11020ed30>, '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 0x1101c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110203be8>, '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.T740Hc0YGc/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.zsyEpH4z6N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 12:02: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:02: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 jsonschema==2.5.1 12:02:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T740Hc0YGc/Render', 12:02:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:02:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:02:02 INFO - 'HOME': '/Users/cltbld', 12:02:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:02:02 INFO - 'LOGNAME': 'cltbld', 12:02:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:02 INFO - 'MOZ_NO_REMOTE': '1', 12:02:02 INFO - 'NO_EM_RESTART': '1', 12:02:02 INFO - 'PAGER': '/bin/cat', 12:02:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:02:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:02 INFO - 'PWD': '/builds/slave/test', 12:02:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:02:02 INFO - 'SHELL': '/bin/bash', 12:02:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zsyEpH4z6N/Listeners', 12:02:02 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 12:02:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:02:02 INFO - 'USER': 'cltbld', 12:02:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:02:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:02:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:02 INFO - 'XPC_FLAGS': '0x0', 12:02:02 INFO - 'XPC_SERVICE_NAME': '0', 12:02:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:02:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:02:03 INFO - Downloading/unpacking jsonschema==2.5.1 12:02:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:02:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:02:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:02:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:02:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:02:04 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:02: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 12:02: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 12:02: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 12:02: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 12:02:04 INFO - Downloading functools32-3.2.3-2.tar.gz 12:02:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:02:04 INFO - warning: no files found matching '*.txt' 12:02:04 INFO - no previously-included directories found matching 'build' 12:02:04 INFO - no previously-included directories found matching 'dist' 12:02:04 INFO - no previously-included directories found matching '.git*' 12:02:04 INFO - Installing collected packages: jsonschema, functools32 12:02:05 INFO - Running setup.py install for functools32 12:02:05 INFO - warning: no files found matching '*.txt' 12:02:05 INFO - no previously-included directories found matching 'build' 12:02:05 INFO - no previously-included directories found matching 'dist' 12:02:05 INFO - no previously-included directories found matching '.git*' 12:02:05 INFO - Successfully installed jsonschema functools32 12:02:05 INFO - Cleaning up... 12:02:05 INFO - Return code: 0 12:02:05 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:02:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1104feb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110502768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb61d6c0c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11020ed30>, '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 0x1101c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110203be8>, '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.T740Hc0YGc/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.zsyEpH4z6N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 12:02:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:02:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:02:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T740Hc0YGc/Render', 12:02:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:02:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:02:05 INFO - 'HOME': '/Users/cltbld', 12:02:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:02:05 INFO - 'LOGNAME': 'cltbld', 12:02:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:05 INFO - 'MOZ_NO_REMOTE': '1', 12:02:05 INFO - 'NO_EM_RESTART': '1', 12:02:05 INFO - 'PAGER': '/bin/cat', 12:02:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:02:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:05 INFO - 'PWD': '/builds/slave/test', 12:02:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:02:05 INFO - 'SHELL': '/bin/bash', 12:02:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zsyEpH4z6N/Listeners', 12:02:05 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 12:02:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:02:05 INFO - 'USER': 'cltbld', 12:02:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:02:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:02:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:05 INFO - 'XPC_FLAGS': '0x0', 12:02:05 INFO - 'XPC_SERVICE_NAME': '0', 12:02:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:02:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:02:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:02:05 INFO - Cleaning up... 12:02:05 INFO - Return code: 0 12:02:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:02:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1104feb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110502768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb61d6c0c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11020ed30>, '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 0x1101c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110203be8>, '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.T740Hc0YGc/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.zsyEpH4z6N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 12:02:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:02:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:02:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T740Hc0YGc/Render', 12:02:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:02:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:02:05 INFO - 'HOME': '/Users/cltbld', 12:02:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:02:05 INFO - 'LOGNAME': 'cltbld', 12:02:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:05 INFO - 'MOZ_NO_REMOTE': '1', 12:02:05 INFO - 'NO_EM_RESTART': '1', 12:02:05 INFO - 'PAGER': '/bin/cat', 12:02:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:02:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:05 INFO - 'PWD': '/builds/slave/test', 12:02:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:02:05 INFO - 'SHELL': '/bin/bash', 12:02:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zsyEpH4z6N/Listeners', 12:02:05 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 12:02:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:02:05 INFO - 'USER': 'cltbld', 12:02:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:02:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:02:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:05 INFO - 'XPC_FLAGS': '0x0', 12:02:05 INFO - 'XPC_SERVICE_NAME': '0', 12:02:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:02:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:02:05 INFO - Downloading/unpacking blobuploader==1.2.4 12:02: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 12:02: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 12:02: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 12:02: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 12:02:07 INFO - Downloading blobuploader-1.2.4.tar.gz 12:02:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:02:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:02:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:02:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:02:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:02:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:02:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:02:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:02:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:02:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:02:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:02:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:02:07 INFO - Downloading docopt-0.6.1.tar.gz 12:02:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:02:08 INFO - Installing collected packages: blobuploader, requests, docopt 12:02:08 INFO - Running setup.py install for blobuploader 12:02:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:02:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:02:08 INFO - Running setup.py install for requests 12:02:08 INFO - Running setup.py install for docopt 12:02:08 INFO - Successfully installed blobuploader requests docopt 12:02:08 INFO - Cleaning up... 12:02:08 INFO - Return code: 0 12:02:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:02:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02:08 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 0x1104feb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110502768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb61d6c0c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11020ed30>, '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 0x1101c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110203be8>, '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.T740Hc0YGc/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.zsyEpH4z6N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 12:02:08 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 12:02:08 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 12:02:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T740Hc0YGc/Render', 12:02:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:02:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:02:08 INFO - 'HOME': '/Users/cltbld', 12:02:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:02:08 INFO - 'LOGNAME': 'cltbld', 12:02:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:08 INFO - 'MOZ_NO_REMOTE': '1', 12:02:08 INFO - 'NO_EM_RESTART': '1', 12:02:08 INFO - 'PAGER': '/bin/cat', 12:02:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:02:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:08 INFO - 'PWD': '/builds/slave/test', 12:02:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:02:08 INFO - 'SHELL': '/bin/bash', 12:02:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zsyEpH4z6N/Listeners', 12:02:08 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 12:02:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:02:08 INFO - 'USER': 'cltbld', 12:02:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:02:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:02:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:08 INFO - 'XPC_FLAGS': '0x0', 12:02:08 INFO - 'XPC_SERVICE_NAME': '0', 12:02:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:02:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:02:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:02:09 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-etXlH0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:02:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:02:09 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-UotUgU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:02:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:02:09 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-zL8Ap4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:02:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:02:09 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-vHhwSS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:02:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:02:09 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-tkJB99-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:02:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:02:09 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-p1mpGY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:02:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:02:09 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-Q12q4p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:02:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:02:09 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-5j5Jip-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:02:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:02:09 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-imcej4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:02:10 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-ct9U6c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:02:10 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-2h_Aw7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:02:10 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-gy6f0D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:02:10 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-Y8u1yh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:02:10 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-f9odLb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:02:10 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-M5isx_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:02:10 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-iipq2h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:02:10 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-Qlh3T9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:02:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:02:10 INFO - Running setup.py install for manifestparser 12:02:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:02:11 INFO - Running setup.py install for mozcrash 12:02:11 INFO - Running setup.py install for mozdebug 12:02:11 INFO - Running setup.py install for mozdevice 12:02:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:02:11 INFO - Running setup.py install for mozfile 12:02:11 INFO - Running setup.py install for mozhttpd 12:02:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:02:11 INFO - Running setup.py install for mozinfo 12:02:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:02:11 INFO - Running setup.py install for mozInstall 12:02:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:02:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:02:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:02:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:02:12 INFO - Running setup.py install for mozleak 12:02:12 INFO - Running setup.py install for mozlog 12:02:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:02:12 INFO - Running setup.py install for moznetwork 12:02:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:02:12 INFO - Running setup.py install for mozprocess 12:02:12 INFO - Running setup.py install for mozprofile 12:02:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:02:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:02:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:02:12 INFO - Running setup.py install for mozrunner 12:02:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:02:13 INFO - Running setup.py install for mozscreenshot 12:02:13 INFO - Running setup.py install for moztest 12:02:13 INFO - Running setup.py install for mozversion 12:02:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:02:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:02:13 INFO - Cleaning up... 12:02:13 INFO - Return code: 0 12:02:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:02:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02:13 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 0x1104feb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110502768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb61d6c0c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11020ed30>, '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 0x1101c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110203be8>, '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.T740Hc0YGc/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.zsyEpH4z6N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 12:02:13 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 12:02:13 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 12:02:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T740Hc0YGc/Render', 12:02:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:02:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:02:13 INFO - 'HOME': '/Users/cltbld', 12:02:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:02:13 INFO - 'LOGNAME': 'cltbld', 12:02:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:13 INFO - 'MOZ_NO_REMOTE': '1', 12:02:13 INFO - 'NO_EM_RESTART': '1', 12:02:13 INFO - 'PAGER': '/bin/cat', 12:02:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:02:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:13 INFO - 'PWD': '/builds/slave/test', 12:02:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:02:13 INFO - 'SHELL': '/bin/bash', 12:02:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zsyEpH4z6N/Listeners', 12:02:13 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 12:02:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:02:13 INFO - 'USER': 'cltbld', 12:02:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:02:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:02:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:13 INFO - 'XPC_FLAGS': '0x0', 12:02:13 INFO - 'XPC_SERVICE_NAME': '0', 12:02:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:02:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:02:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:02:13 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-vigAxh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:02:13 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)) 12:02:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:02:13 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-BPLCNl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:02:13 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)) 12:02:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:02:13 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-Z_co6a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:02:14 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)) 12:02:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:02:14 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-8Lh6od-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:02:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:02:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:02:14 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-iCIpkr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:02:14 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)) 12:02:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:02:14 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-KE7AvV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:02:14 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)) 12:02:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:02:14 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-0IhYbA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:02:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 12:02:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:02:14 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-9gQD6B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:02:14 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)) 12:02:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:02:14 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-R7GK3B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:02:14 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)) 12:02:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:02:14 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-vHrho7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:02:14 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)) 12:02:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:02:14 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-loC5dw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:02:14 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)) 12:02:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:02:14 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-YPSBNK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:02:15 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)) 12:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:02:15 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-BznYS0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:02:15 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)) 12:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:02:15 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-h3AhnF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:02:15 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)) 12:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:02:15 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-1fEbwK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:02:15 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)) 12:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:02:15 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-mg0_am-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:02:15 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)) 12:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:02:15 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-tsAfX0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:02:15 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)) 12:02:15 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)) 12:02:15 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)) 12:02:15 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)) 12:02:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:02:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:02:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:02:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:02:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:02:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:02:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:02:16 INFO - Downloading blessings-1.6.tar.gz 12:02:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:02:17 INFO - Installing collected packages: blessings 12:02:17 INFO - Running setup.py install for blessings 12:02:17 INFO - Successfully installed blessings 12:02:17 INFO - Cleaning up... 12:02:17 INFO - Return code: 0 12:02:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:02:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:02:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:02:17 INFO - Reading from file tmpfile_stdout 12:02:17 INFO - Current package versions: 12:02:17 INFO - blessings == 1.6 12:02:17 INFO - blobuploader == 1.2.4 12:02:17 INFO - docopt == 0.6.1 12:02:17 INFO - functools32 == 3.2.3-2 12:02:17 INFO - jsonschema == 2.5.1 12:02:17 INFO - manifestparser == 1.1 12:02:17 INFO - mozInstall == 1.12 12:02:17 INFO - mozcrash == 1.0 12:02:17 INFO - mozdebug == 0.1 12:02:17 INFO - mozdevice == 0.50 12:02:17 INFO - mozfile == 1.2 12:02:17 INFO - mozhttpd == 0.7 12:02:17 INFO - mozinfo == 0.9 12:02:17 INFO - mozleak == 0.1 12:02:17 INFO - mozlog == 3.4 12:02:17 INFO - moznetwork == 0.27 12:02:17 INFO - mozprocess == 0.25 12:02:17 INFO - mozprofile == 0.28 12:02:17 INFO - mozrunner == 6.13 12:02:17 INFO - mozscreenshot == 0.1 12:02:17 INFO - mozsystemmonitor == 0.3 12:02:17 INFO - moztest == 0.8 12:02:17 INFO - mozversion == 1.4 12:02:17 INFO - psutil == 3.1.1 12:02:17 INFO - requests == 1.2.3 12:02:17 INFO - wsgiref == 0.1.2 12:02:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:02:17 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')]} 12:02:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02:17 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')]} 12:02:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02:17 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 0x1104feb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110502768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb61d6c0c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11020ed30>, '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 0x1101c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110203be8>, '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.T740Hc0YGc/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.zsyEpH4z6N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 12:02:17 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 12:02:17 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 12:02:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T740Hc0YGc/Render', 12:02:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:02:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:02:17 INFO - 'HOME': '/Users/cltbld', 12:02:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:02:17 INFO - 'LOGNAME': 'cltbld', 12:02:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:17 INFO - 'MOZ_NO_REMOTE': '1', 12:02:17 INFO - 'NO_EM_RESTART': '1', 12:02:17 INFO - 'PAGER': '/bin/cat', 12:02:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:02:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:17 INFO - 'PWD': '/builds/slave/test', 12:02:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:02:17 INFO - 'SHELL': '/bin/bash', 12:02:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zsyEpH4z6N/Listeners', 12:02:17 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 12:02:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:02:17 INFO - 'USER': 'cltbld', 12:02:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:02:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:02:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:17 INFO - 'XPC_FLAGS': '0x0', 12:02:17 INFO - 'XPC_SERVICE_NAME': '0', 12:02:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:02:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:02:17 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)) 12:02:17 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)) 12:02:17 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)) 12:02:17 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)) 12:02:17 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)) 12:02:17 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)) 12:02:17 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)) 12:02:17 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)) 12:02:17 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)) 12:02:17 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)) 12:02:17 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)) 12:02:17 INFO - Cleaning up... 12:02:17 INFO - Return code: 0 12:02:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:02:17 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')]} 12:02:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02:17 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')]} 12:02:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02:17 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 0x1104feb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110502768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb61d6c0c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11020ed30>, '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 0x1101c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110203be8>, '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.T740Hc0YGc/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.zsyEpH4z6N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 12:02:17 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 12:02:17 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 12:02:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T740Hc0YGc/Render', 12:02:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:02:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:02:17 INFO - 'HOME': '/Users/cltbld', 12:02:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:02:17 INFO - 'LOGNAME': 'cltbld', 12:02:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:17 INFO - 'MOZ_NO_REMOTE': '1', 12:02:17 INFO - 'NO_EM_RESTART': '1', 12:02:17 INFO - 'PAGER': '/bin/cat', 12:02:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:02:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:17 INFO - 'PWD': '/builds/slave/test', 12:02:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:02:17 INFO - 'SHELL': '/bin/bash', 12:02:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zsyEpH4z6N/Listeners', 12:02:17 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 12:02:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:02:17 INFO - 'USER': 'cltbld', 12:02:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:02:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:02:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:17 INFO - 'XPC_FLAGS': '0x0', 12:02:17 INFO - 'XPC_SERVICE_NAME': '0', 12:02:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:02:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:02:18 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:02:18 INFO - Cleaning up... 12:02:18 INFO - Return code: 0 12:02:18 INFO - Running post-action listener: _resource_record_post_action 12:02:18 INFO - Running post-action listener: _start_resource_monitoring 12:02:18 INFO - Starting resource monitoring. 12:02:18 INFO - [mozharness: 2017-05-22 19:02:18.136233Z] Finished create-virtualenv step (success) 12:02:18 INFO - [mozharness: 2017-05-22 19:02:18.137013Z] Running install step. 12:02:18 INFO - Running pre-action listener: _resource_record_pre_action 12:02:18 INFO - Running main action method: install 12:02:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:02:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:02:18 INFO - Reading from file tmpfile_stdout 12:02:18 INFO - Detecting whether we're running mozinstall >=1.0... 12:02:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:02:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:02:18 INFO - Reading from file tmpfile_stdout 12:02:18 INFO - Output received: 12:02:18 INFO - Usage: mozinstall [options] installer 12:02:18 INFO - Options: 12:02:18 INFO - -h, --help show this help message and exit 12:02:18 INFO - -d DEST, --destination=DEST 12:02:18 INFO - Directory to install application into. [default: 12:02:18 INFO - "/builds/slave/test"] 12:02:18 INFO - --app=APP Application being installed. [default: firefox] 12:02:18 INFO - mkdir: /builds/slave/test/build/application 12:02:18 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'] 12:02:18 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 12:02:40 INFO - Reading from file tmpfile_stdout 12:02:40 INFO - Output received: 12:02:40 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:02:40 INFO - Running post-action listener: _resource_record_post_action 12:02:40 INFO - [mozharness: 2017-05-22 19:02:40.009578Z] Finished install step (success) 12:02:40 INFO - [mozharness: 2017-05-22 19:02:40.009713Z] Running run-tests step. 12:02:40 INFO - Running pre-action listener: _resource_record_pre_action 12:02:40 INFO - Running main action method: run_tests 12:02:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:02:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:02:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:02:40 INFO - 2017-05-22 12:02:40.024 screenresolution[1626:7430] starting screenresolution argv=screenresolution get 12:02:40 INFO - 2017-05-22 12:02:40.026 screenresolution[1626:7430] Display 0: 1600x1200x32@60 12:02:40 INFO - 2017-05-22 12:02:40.032 screenresolution[1627:7432] starting screenresolution argv=screenresolution list 12:02:40 INFO - Available Modes on Display 0 12:02:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:02:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:02:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:02:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:02:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:02:40 INFO - Intel Iris: 12:02:40 INFO - Chipset Model: Intel Iris 12:02:40 INFO - Type: GPU 12:02:40 INFO - Bus: Built-In 12:02:40 INFO - VRAM (Dynamic, Max): 1536 MB 12:02:40 INFO - Vendor: Intel (0x8086) 12:02:40 INFO - Device ID: 0x0a2e 12:02:40 INFO - Revision ID: 0x0009 12:02:40 INFO - Displays: 12:02:40 INFO - Display: 12:02:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:02:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:02:40 INFO - Display Serial Number: 1600x1200 60 12:02:40 INFO - Main Display: Yes 12:02:40 INFO - Mirror: Off 12:02:40 INFO - Online: Yes 12:02:40 INFO - Rotation: Supported 12:02:40 INFO - Return code: 0 12:02:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:02:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:02:40 INFO - Python 2.7.3 12:02:40 INFO - Return code: 0 12:02:40 INFO - grabbing minidump binary from tooltool 12:02:40 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')]} 12:02:40 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 0x11020ed30>, '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 0x1101c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110203be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:02:40 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 12:02:40 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 12:02:40 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 12:02:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:02:40 INFO - Return code: 0 12:02:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:02:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:02:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:02:40 INFO - ENV: RUST_BACKTRACE is now 1 12:02:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:02:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:02:40 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/E5_26LKdQLaB4QXRpKyufw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0379', '--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 12:02:40 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/E5_26LKdQLaB4QXRpKyufw/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0379 --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 12:02:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T740Hc0YGc/Render', 12:02:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:02:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:02:40 INFO - 'HOME': '/Users/cltbld', 12:02:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:02:40 INFO - 'LOGNAME': 'cltbld', 12:02:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:02:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:02:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:40 INFO - 'MOZ_NO_REMOTE': '1', 12:02:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:02:40 INFO - 'NO_EM_RESTART': '1', 12:02:40 INFO - 'PAGER': '/bin/cat', 12:02:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:02:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:40 INFO - 'PWD': '/builds/slave/test', 12:02:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:02:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:02:40 INFO - 'RUST_BACKTRACE': '1', 12:02:40 INFO - 'SHELL': '/bin/bash', 12:02:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zsyEpH4z6N/Listeners', 12:02:40 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 12:02:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:02:40 INFO - 'USER': 'cltbld', 12:02:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:02:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:02:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:40 INFO - 'XPC_FLAGS': '0x0', 12:02:40 INFO - 'XPC_SERVICE_NAME': '0', 12:02:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:02:40 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/E5_26LKdQLaB4QXRpKyufw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0379', '--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 12:02:40 INFO - mozversion application_buildid: 20170522103045 12:02:40 INFO - mozversion application_changeset: f9ca97a334296facd2e0ea5582e7f12d0fe70fe4 12:02:40 INFO - mozversion application_display_name: Nightly 12:02:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:02:40 INFO - mozversion application_name: Firefox 12:02:40 INFO - mozversion application_remotingname: firefox 12:02:40 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:02:40 INFO - mozversion application_vendor: Mozilla 12:02:40 INFO - mozversion application_version: 55.0a1 12:02:40 INFO - mozversion platform_buildid: 20170522103045 12:02:40 INFO - mozversion platform_changeset: f9ca97a334296facd2e0ea5582e7f12d0fe70fe4 12:02:40 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:02:40 INFO - mozversion platform_version: 55.0a1 12:02:40 INFO - using testdate: 1495479760 12:02:40 INFO - actual date: 1495479760 12:02:40 INFO - starting webserver on 'localhost:49230' 12:02:40 INFO - SUITE-START | Running 2 tests 12:02:40 INFO - TEST-START | dromaeo_css 12:02:40 INFO - operating with platform_type : mac_ 12:02:40 INFO - Initialising browser for dromaeo_css test... 12:02:40 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/tmpFQ2ePO/profile http://localhost:49230/getInfo.html) 12:02:41 INFO - PID 1637 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2978: String.localeCompare is deprecated; use String.prototype.localeCompare instead 12:02:43 INFO - PID 1637 | 2017-05-22 12:02:43.306 plugin-container[1639:7604] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b3f, name = 'com.apple.tsm.portname' 12:02:43 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:02:43 INFO - PID 1637 | 2017-05-22 12:02:43.310 plugin-container[1639:7604] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4e23, name = 'com.apple.CFPasteboardClient' 12:02:43 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:02:43 INFO - PID 1637 | 2017-05-22 12:02:43.310 plugin-container[1639:7604] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:02:43 INFO - PID 1637 | __metrics Screen width/height:1600/1200 12:02:43 INFO - PID 1637 | colorDepth:24 12:02:43 INFO - PID 1637 | Browser inner width/height: 1024/691 12:02:43 INFO - PID 1637 | __metrics 12:02:43 INFO - PID 1637 | 12:02:43 INFO - PID 1637 | ###!!! [Child][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost 12:02:43 INFO - PID 1637 | 12:02:44 INFO - TEST-INFO | 1637: exit 0 12:02:44 INFO - Browser initialized. 12:02:44 INFO - Running cycle 1/1 for dromaeo_css test... 12:02:44 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/tmpFQ2ePO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:02:46 INFO - PID 1641 | 2017-05-22 12:02:46.419 plugin-container[1642:7748] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x953f, name = 'com.apple.tsm.portname' 12:02:46 INFO - PID 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:02:46 INFO - PID 1641 | 2017-05-22 12:02:46.420 plugin-container[1642:7748] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x481f, name = 'com.apple.CFPasteboardClient' 12:02:46 INFO - PID 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:02:46 INFO - PID 1641 | 2017-05-22 12:02:46.420 plugin-container[1642:7748] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:02:47 INFO - PID 1641 | 2017-05-22 12:02:47.132 plugin-container[1643:7795] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x974b, name = 'com.apple.tsm.portname' 12:02:47 INFO - PID 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:02:47 INFO - PID 1641 | 2017-05-22 12:02:47.133 plugin-container[1643:7795] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0f, name = 'com.apple.CFPasteboardClient' 12:02:47 INFO - PID 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:02:47 INFO - PID 1641 | 2017-05-22 12:02:47.133 plugin-container[1643:7795] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:02:56 INFO - PID 1641 | RSS: Main: 241086464 12:02:56 INFO - PID 1641 | 12:05:59 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49230/tests/dromaeo/cssquery-ext.html) 12:06:01 INFO - PID 1641 | RSS: Main: 264024064 12:06:01 INFO - PID 1641 | 12:09:02 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-ext.html (next: http://localhost:49230/tests/dromaeo/cssquery-jquery.html) 12:09:03 INFO - PID 1641 | RSS: Main: 271704064 12:09:03 INFO - PID 1641 | 12:12:05 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49230/tests/dromaeo/cssquery-mootools.html) 12:12:05 INFO - PID 1641 | RSS: Main: 275116032 12:12:05 INFO - PID 1641 | 12:15:07 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49230/tests/dromaeo/cssquery-prototype.html) 12:15:08 INFO - PID 1641 | RSS: Main: 272855040 12:15:08 INFO - PID 1641 | 12:18:10 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49230/tests/dromaeo/cssquery-yui.html) 12:18:11 INFO - PID 1641 | RSS: Main: 274391040 12:18:11 INFO - PID 1641 | 12:21:13 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-yui.html (next: http://localhost:49230/tests/dromaeo/cssquery-dojo.html) 12:21:13 INFO - PID 1641 | RSS: Main: 275546112 12:21:13 INFO - PID 1641 | 12:21:13 INFO - PID 1641 | __start_tp_report 12:21:13 INFO - PID 1641 | _x_x_mozilla_page_load 12:21:13 INFO - PID 1641 | _x_x_mozilla_page_load_details 12:21:13 INFO - PID 1641 | |i|pagename|runs| 12:21:13 INFO - PID 1641 | |0;dojo.html;2883.8269703817773;3566.2867426514704;3598.4422414525757;3608.6030536640983;3619.441057163477;6089.360617135203;6103.664298463588;6151.538634602406;6168.784092556762;6169.352218017111;6117.127159805721;6141.455323060925;6143.174139895642;6143.784967526126;6143.938560614392;5355.303810504629;5402.6758394496255;5408.4591540845995;5433.1035379162395;5560.304961879765;5492.038893193695;5501.724913754317;5516.365617953947;5521.0062188806;5526.944730552704;4836.37127173467;5259.474052594729;5419.512243898049;5491.423400542945;5559.054960656698;5307.2569840222395;5349.491798279158;5415.296011518478;5445.564354851642;5502.862428439301;5367.973160134175;5382.1926710993275;5409.323834520685;5434.592405569598;5436.646617969819;5294.735263236823;5556.749731310463;6151.231096112986;6166.907496387557;6170.96914515429;5259.658122222043;6628.171478565179;6637.93362066378;6646.734130634769;6664.733410663568;6463.450606698436;6570.901436478457;6659.300773418819;6741.0899528563505;6756.223034351015;6230.59501132425;6258.092576576462;6259.6870156492;6259.71831267594;6268.905966410553;6869.484788640872;6874.931250687529;6884.827879302958;6895.620740859301;6904.758333458305;6295.685215739195;6356.682165891687;6470.09418681385;6554.14796076507;6571.507136964747;6651.600903945779;6677.732890684319;6722.630255336004;6736.2590115087305;6744.22441419243;5177.404598471224;6443.613383197023;6567.901481477782;6583.144191255106;6600.86798264032;6330.430261276507;6441.32366101562;6452.935470645328;6505.447037001908;6621.4040736333;5324.121519949243;5340.973295133499;5368.5436737876935;5368.785248590013;5369.27514785499;4680.321353403738;4763.928541071887;4765.046990601893;4787.712737235777;4813.879653008633;6151.507879369639;6244.063390491463;6262.1546091278005;6263.71813268404;6271.686415679198;5704.315482142087;6059.151718759377;6371.267304259976;6642.070110184578;6717.832054198587;6151.200343955257;6186;6200.56596038282;6241.750329986749;6252.280987686474;5849.415058494116;5904.527637788967;5910.645361278337;5918.260217472816;5945;5637.661740295432;5929.733162007634;6644.136262285871;6762.628055456997;6804.727810887707;6722;6732.091167692494;6741.730330786713;6745.662716864235;6776.93223067763;6745.730170793309;6842.178938527409;6856.5200435969;6870.416014638811;6879.243283238739;6387.393197646216;6445.516586255956;6527.934720652733;6583.30875258091;6642.368974947372;6716.731330746716;6759.831004224934;6789.253182149861;6792.286809885089;6793.694283757144;6130.938690613037;6508.446782023391;6508.642024688687;6517.608943463218;6520.380563846427;5729.427057294237;6281.309056003744;6367.172267605181;6515.511336649675;6549.377809108127;5451;6066.908996365147;6076.179715738319;6321.399467050623;6400.615963042233;6515.185601799775;6523.543351965317;6525.412712855866;6544.869102618016;6560.081588577507;6156.415140561639;6177.073438984188;6299.874002519832;6364.93635063662;6386.9361306386345 12:21:13 INFO - PID 1641 | |1;ext.html;808.551254054;830.958452077396;1105.2373862035195;1130.2709752209826;1276.8723127687226;38666.806665966666;39477;39490.60509394909;39511.802440987754;39576.40635390471;39352.803235983854;39751.204975900466;39910;39941.60058399415;39944.60055399445;7770.728024519129;7961.164077771838;8354.331653467709;8518.361122915776;8530.70142545011;8322.66709331626;8461.280791132762;8479.830403391928;8494.74515764525;8497.61760720769;8447.704330348439;8521.701740439086;8536.530490823003;8540.402171847973;8551.230389264965;8492.278156356717;8496.78758031048;8522.573871306471;8527.914720852805;8548.829023419497;8247.422680412374;8306.875396869053;8309.37679674027;8319.084900660922;8325.542095184763;37661;37850.43224351637;37869;37915;37920;3671.7429779915415;3706.036430528055;3713.4429835524616;3723.6834869036165;3724.7951362675044;31019.22451938697;31040.379192416025;31082;31086.378272434424;31098.911538096283;3120.641126270485;3148.1972097115317;3155.1323386069016;3164.2089477630593;3194.4569423198113;303810.48094759387;306602;307639;308028;308064;32504.512432313535;36855;38976.6102338973;48773.780655483184;49272.50727492679;41851.372229416585;46542.836429088864;51395.972080558924;51473.48526514762;51508;13281.867181328256;14185.077969931921;14309.856901430852;14324.713505729827;14334.211618360881;9486.525673716287;11400.543978240778;11415.828762568568;11428.542858285742;11633.47649355764;11059.778804423866;13732.107413018122;13754.587362379145;13756.86243137576;13807.792883106762;5963.0459126539545;6034.758609655564;6043.365446628129;6045.21412216409;6067.514598832083;1034.8240799064176;1047.0942634621;1054.3568423261804;1076.2197406880107;1088.6353071720903;19904.90047549782;20710;22797.544049119257;23020;24666.766661667203;1111.8443417921496;1123.3035517978742;1128.249713940237;1161.7676464707092;1167.287954347847;10309.175265978703;11650.6504804856;12606.873931260754;12649.873501265054;12790.040746944016;316508;318399;318728;319337;319475;53726.731366342916;54042.7297863516;54270;54354.184687229725;54619.18071228855;1502.136271643809;1544.9304781284645;1575.9290831912592;1587.3412533798344;1599.0885195438489;22887.77112228923;24319.51360972806;24437.38906527351;24607;24614.507709845344;38504.80747596244;38638.034049149;38699;38805.41791873181;38892.6110738889;277430;278711;279162;279423;279596;33804.66195338015;34659.65340346564;34728.13179670528;34789.47815782816;34803.78186763451;43612.12775744404;44199.33700994423;44689.77655111704;44775.10449790921;44883.77558112189;30157.849210753804;30308.393832123675;31102;31102.911398100958;31435.05694829159;30059.849700751358;30113.849430752707;30979.845100774353;31159.37681246317;31316.06051818449;37425;37568.68509602151;37622;37639.62360376361;37667;24010.87994560016;27960.72039279581;29545.85227073851;31664.683353166172;31945.367921650766;21443.249486267905;21578.568428631654;24403.755962440147;24507.01971921103;24691 12:21:13 INFO - PID 1641 | |2;jquery.html;378.7424551305113;436.83618643008873;437.89271628451036;452.33055078483835;463.768115942029;20886.373408797743;21501.784982150173;21731;21977.890110549466;22274.443138921535;17994.730079048826;21101.050452729625;22192.779397133152;22396.21613243537;22521.887390563024;5757.877213943283;5933;5984.581079324438;6239.937600624004;6254.937450625481;3917.9804100979454;6176.073588961647;6211.6273023618505;6212.875742485147;6225.097360882669;4767.380240568721;6171.598846075013;6181.9690901545655;6190.3500132486115;6202.9379706203035;5877.6473411595225;5906.763729450839;5954.618152738917;5997.220361352996;6009.75960961561;5242.9213561796605;5324.121519949243;5353.063213937546;5365.222042803811;5434.755436005389;27922.720772792214;28112.578311325346;28486.43919510061;28642.713572864213;29054.709452905412;1886.5472286651104;2079.896005199749;2140.6681964295403;2141.914323427061;2146.785321467856;16822.90651107674;17045.403410880685;17807.643847123116;17845.643087138313;17993;2087.95824083519;2195.121951219509;2204.768499307582;2222.4554984029014;2228.5988522066023;128668;159908.20045899812;160629;161608;164459.1777041119;10561;12204.694882628006;12792.360381980863;12805.423755931006;12855.871441285468;13957.930210348883;16763.245653945356;16869;16875.746863797052;16949.830501694825;8692.261157801531;8893.866592001124;8912.910870891337;8973.775655608662;9045.547722613843;8325.500469971821;8398.790030249293;8399.748007559783;8549.316054715608;8576.142385761374;6785.592864428051;7930.246626570467;8044.477108987897;8072.8385432291025;8131.186881311822;2229.4203507088273;2237.762237762238;2241.60771864923;2246.4383904023994;2409.481961378312;934.2806039349798;961.6393852305386;972.6547075788135;983.5770618633914;1006.4766321512918;20435.38693839187;20921.476963076046;20938.37184884456;20973.161073557192;21354;994.5872462927948;999.2206079258191;1011.6054738651932;1015.3197357770312;1024.416082832778;10403.42781147029;10694.358338499715;10826;10885.510152043176;10897.455127243764;535863;543146;548353;554756;554985;126961;131153.34423328014;134067;134375.32812336073;135895;1184.1237484261756;1218.4334284557724;1230.6431134970758;1234.8888600026041;1241.4599649152647;20737.377878663665;20751;23235.419114522272;23278.95244713958;23350.18274360389;54250;55860.72069639788;56340.43659563352;56513;56690.716546417;79824;80267.59866200632;80871;80931;81154;8923.955380223057;8933.910660893307;9000.819983600422;9107.547628377117;9252.768680783034;14066.437342506186;14083;14094.788578171541;14316.212608306643;14335.713285734018;11718.531258749554;12092.879071209527;12155;12162.513499459923;12182.269063856196;11171.553137874394;11222.989930906258;11324.547018119183;11631.76736465283;11711.707207319885;10244.487775611338;11198.83201751958;12284.324362160167;12291.877081229431;12408.131430800118;8661.13388661129;8839.646414143619;9057.954710226406;9088.863667044867;9149.679761208326;10124.645637402655;11601.709957251136;11798.233114847508;11936.10479214045;11973.161878668758 12:21:13 INFO - PID 1641 | |3;mootools.html;1408.9506867259645;1463.817022872141;1480.4744315767903;1496.0425327790213;1496.944654018916;11497.137714671391;11871.287722736643;12060.939695301522;12063.155579109465;12264.264144151357;10070.546825392856;11895.167338286323;12019.338936358497;12021.098417618692;12022.398880056006;2543.0590681447843;2595.5328040952622;2599.181257903754;2599.922002339933;2611.8302310349864;2420.685310909579;2464.6426268191103;2466.235467005225;2469.1358024691394;2471.7404672509397;2508.7867531259867;2529.127451029392;2554.425254317782;2554.8083893707953;2556.322574517754;2529.557327467695;2563.602641590547;2575;2577.7808886244693;2586.430985183257;2546.7071286801884;2547.0066673997158;2558.6417901493805;2562.5515534781525;2565.486902619465;11506;11745.530178792838;11763.000144987685;11764.882351176464;11772.882271177265;6471.773487927947;6742.8314292142595;6745.797626071177;6753.257141714407;6755.1556055493065;3866.3040652682507;4222.345536441826;4285.078708077778;4298.871033868958;4302.118065796504;1176.7411169542686;1196.5572738086964;1250.4310538704958;1253.3921048291572;1254.9811752823714;13297.468101275937;13464;15377.692446151126;16136.112513811735;16305.021698698116;5305.814296499604;5366.329208848894;5545.168224766237;5550.888982220333;5555.138953462219;11566.65300041;11739.23694959825;11754.824517548348;11765.823512647317;11786;5589.720513974285;6367.204099487564;6479.319671434526;6486.956608668254;6526.869462610721;5986.4013598639785;6122.418370254819;6205.875882482325;6207.937920620826;6253.6560489173535;4243.726881935407;4380.846670366522;4382.452193475816;4405.515393306734;4423.336499525097;1279.7760391931374;1288.6198571421453;1289.1233960906673;1296.742160104697;1308.718625495504;697.870893884633;713.1762813949896;782.7769085810517;817.7056259746605;820.2658620534588;6711.0940023096255;6718.59931744708;6795.456363491007;6869.622170780555;6944.409725173316;760.3005235183645;857.3184318466834;936.4708939449189;963.4055637919255;965.6620182935916;3688.1517251032415;3750.3999360102252;3821.7133714970932;3840.6927445804267;3868.574456809749;13806.102603330752;14036.298185090705;14464.20446875432;14817.185054822088;14945.103293802407;14477.420903163755;14637.634059148606;14766.48317308889;14774.261286935825;14813.185274809988;8230.958845205736;8369.204925532065;8458.87311690325;8516.957415212884;8537.060923298432;6412.6152430852435;6478.611283323015;6550.082988381726;6568.1789776277965;6594.604323740591;10698.786024279627;12554.493460784764;12790.744185116431;12926.448826140548;12988.415521301753;10880.727981800519;11263.831042534204;13038.47846086146;13128.474861005452;13285;8194.918050819415;8403.033651130198;8417.873731893904;8434.493930364199;8453.154684531744;10553.41956192386;10574.206934479791;10672.679819605424;10688.465576721288;10698.572057117628;9113.453192808453;9178.219851312446;9180.036096209802;9191.080891910755;9236.30727695439;8903.732888013112;8941.329400295133;9003.099690030944;9012.2790176787;9099;7062.39969602569;8143.714969976022;8312.376571757264;8314;8334.916650833657;5256.921146182887;5641.294838145232;5828.708564571839;5917.792877249276;5997.100434934795;6059.787907423219;6117.632942023492;6199.56603037783;6201.015674983962;6234.407731265523 12:21:13 INFO - PID 1641 | |4;prototype.html;386.5921452867225;405.339296945978;407.12870504414684;411.5514089642289;421.13456846181083;5812.070068788995;5991.801639672067;6388.744450221991;6426.357364263571;6462.353764623535;6001.5798894077325;6065.818025459233;6130;6247.907285893478;6297.779577714781;4735.763211839403;4747.548982846624;4753.453352864413;4757.904841903177;4776.785044672981;4320.740755554661;4756.595689366408;4758.191107511714;4763.737994410306;4784.1149387363275;4449.733016019033;4458.88527868033;4467.352233926079;4473.709208901428;4516.661250406216;3730.9440358394645;4468.50846406895;4481.126180394825;4488.955110448919;4497.190505708971;3848.5766565677754;3849.538055433366;3851.6726078283377;3857.537095548524;3869.226154769029;4956.281339205795;4966.801327946878;5978.671173085479;6102.72537735803;6118.816435506942;5317.042932272189;5386.730663466811;5391.973040134774;5395.406505284415;5411.9717253722065;5334.413214546396;6360;6592.241892182413;6632.469402447792;6677.699503522354;4399.36209249657;4416.205083085043;4424.513303536641;4428.933565996513;4444.73331600105;4905.901881962376;6710.228323742735;6888.965555172192;7089.680964356565;7179.676914538858;4297.978510107429;4359.193549193409;4465.37484752135;4481.3726078349055;4491.371208030879;3976.8069579126145;4442.888927776831;4566.995261042565;4570.988764943414;4591.609713174417;4928.063667903087;5045.318881950963;5377.435369286169;5413.512783849473;5439.5104440600535;5568.52667523257;5852.736626851802;6652.667366631746;6733.70048676155;6839.93160068393;1489;1545.9072455652697;1558.096304143594;1564.843515648426;1565.1391734546182;783.475071701961;962.9903700963041;990.960361585543;1007.3552926127285;1023.2325755683238;5043.8903441242865;5603.3836278009385;6119.388061193934;6273.498120150377;6295.401936815995;5302.337207849019;6407.647579383085;6917.27368626287;6967.860642787217;6997.405220556309;5833.970830145907;5927.496162826122;6143.477804386588;6302.833975714508;6309.495240380758;3212.6305474870223;3614.9096272593397;3642.21692336149;3646.3983442732183;3667.578228503703;5316.9202461963105;6888.345607167311;7072.434205263567;7098.751543695946;7138.179109402381;7176.748813791597;7182.856342873113;7185.712571497186;7192.676329565182;7198.244184360671;6372.522060845455;6788.436071062732;7011.432654519475;7053.753118640823;7101.786946391617;5781.768729250783;6449.774257901138;6604.768833090819;6932.826679333057;6991;5380.0046991306735;5859.326177489644;6361.991945074758;6653.469701968671;6841.144856892888;5851.64890106595;6116.969415152896;7030.15638123408;7137.678804453708;7218.639068046682;3764.9058773529787;4512.593866552026;4527.909441811211;4534.546545345439;4548.340490628906;4999.425066117443;6518.087467754423;6891.173059232924;6957.512974091967;7120.582120582046;6280.9685951569945;6762.850315446484;6787.490938179755;6791.388775010273;6809.319068093349;6008.098785182257;6139.324674285915;6538.313477084837;6711.832204194934;6804.183497980274;5510.614257001971;5513.786966867283;5624.971875140598;6076.817695469143;6551.44312733404;6392.65754191625;6612.173478315211;6674.799756007327;6795;6818.795436136924;5933.732982015733;6308.495320374451;6619.97390404488;6630.801075967729;6646.634435106115 12:21:13 INFO - PID 1641 | |5;yui.html;820.192110770891;825.6367198432689;841.3353450773889;847.7033038436545;860.6041221038322;16279.75580366294;16700.081495517756;16717.331306747732;18326.908365458155;18941.52646183846;17987.280508779666;18192.27230910765;18231.453056408296;18469.261229550837;18491.907540462278;994.2791476179766;1000.7498125468633;1002.6641075239803;1005.3314545827053;1005.4168582222294;989.8119357322122;999.1257649556638;1003.3678782367135;1003.9196864250843;1010.1211945607295;1050.6585359758071;1053.8577292065588;1055.2876808154504;1057.8307470804673;1062.7502536903821;1036.0571879589613;1050.9054185123375;1050.9106725928305;1051.4795176387738;1058.5659879449427;755.8148253677829;760;770.6223950264387;771.9459637825355;774.7830607429872;16889.91555042229;17007.894486858313;17268.39560615385;17270.96374217551;17290.96254224738;16788;16988.915055424768;17168.91415542927;17417.39039133637;17465.563360915952;14136.646583835487;14178.858211417857;14396.13623182602;14456.060356076927;14485.927570362186;3455.3089382123676;3490.9650903491147;3506.5441492605796;3535.169235229719;3536.6109727929907;120398.3980080094;135376;136019;138591.30704346515;139457;13373.933130334286;13414.463421463031;13688.52090176839;13752.93123534376;13905.513307034205;25545.361365966;25555.36111597225;25780;25933;26058.869705651352;6517.934820651828;7853.293203611703;7865.292123708894;7998.600069996477;8035.397345199133;3399.847006884696;3476.5654293213347;3953.940690889639;3994.1811928554575;4034.3545032794614;4256.3189889617515;4319.784010799447;4372.453443319585;4393.780310984438;4410.933835992462;939.5865819039601;941.3645789092473;952.5665822050951;953.2993249961273;961.2598299309493;636.4939872801134;637.4995628431659;640.794945617398;661.7485355564854;678.8404724889746;11267.774644507228;11816.70458238551;11866.525338986516;12144.514219431301;12196.634100976808;634.3561285295428;641.2337256977867;650.4211251985737;655.3348351423309;675.9324067593201;4489.753063581469;5413.9729301353245;5622.971885140548;5631.18347839561;5641.984442800335;83250.58374708208;109544;109715.45142274396;111061.88938110677;112395;84527.04155354656;86684;91580;96695.51652241693;97229;68275.65862170757;78469;79254.71551909349;79832;80116.59941700254;12152.817707734213;12389.256644601353;12460.937695311466;12694.682632934251;12717.173383730027;38363;38403.807980959915;38681.6131838678;38851.80574097111;39159.6084039156;45801;48973;49262.52212433633;49462.75268623634;49649.503504965935;15567.455139070078;15915.88588798773;16211.837881621033;16309.673806523566;16464.506064818073;17518.91240543789;18109.275628974694;18212.089395530435;18332.725009124606;18629.347972820888;16468.752968705718;16667.416640417527;17401.390951316644;17499.56251093733;17560.38538651141;14066.507672231422;16240.837591623933;16334;17363.56591085233;17394.82605173932;15850.7622385662;15885.444009459614;16040.839591603935;16311.59221019484;16420.75368869444;11769.470373833026;11887.702807429883;11898.940505297764;12105.152639315163;12271.386430678609;13001;13217;13263.734725305247;13339.533116340881;13374.264415457243 12:21:13 INFO - PID 1641 | __end_tp_report 12:21:13 INFO - PID 1641 | __start_cc_report 12:21:13 INFO - PID 1641 | _x_x_mozilla_cycle_collect,337 12:21:13 INFO - PID 1641 | __end_cc_report 12:21:13 INFO - PID 1641 | __startTimestamp1495480873323__endTimestamp 12:21:13 INFO - PID 1641 | 12:21:13 INFO - PID 1641 | ------- Summary: start ------- 12:21:13 INFO - PID 1641 | Number of tests: 6 12:21:13 INFO - PID 1641 | 12:21:13 INFO - PID 1641 | [#0] dojo.html Cycles:165 Average:6069.84 Median:6259.69 stddev:721.96 (11.5%) stddev-sans-first:679.54 12:21:13 INFO - PID 1641 | Values: 2883.8 3566.3 3598.4 3608.6 3619.4 6089.4 6103.7 6151.5 6168.8 6169.4 6117.1 6141.5 6143.2 6143.8 6143.9 5355.3 5402.7 5408.5 5433.1 5560.3 5492.0 5501.7 5516.4 5521.0 5526.9 4836.4 5259.5 5419.5 5491.4 5559.1 5307.3 5349.5 5415.3 5445.6 5502.9 5368.0 5382.2 5409.3 5434.6 5436.6 5294.7 5556.7 6151.2 6166.9 6171.0 5259.7 6628.2 6637.9 6646.7 6664.7 6463.5 6570.9 6659.3 6741.1 6756.2 6230.6 6258.1 6259.7 6259.7 6268.9 6869.5 6874.9 6884.8 6895.6 6904.8 6295.7 6356.7 6470.1 6554.1 6571.5 6651.6 6677.7 6722.6 6736.3 6744.2 5177.4 6443.6 6567.9 6583.1 6600.9 6330.4 6441.3 6452.9 6505.4 6621.4 5324.1 5341.0 5368.5 5368.8 5369.3 4680.3 4763.9 4765.0 4787.7 4813.9 6151.5 6244.1 6262.2 6263.7 6271.7 5704.3 6059.2 6371.3 6642.1 6717.8 6151.2 6186.0 6200.6 6241.8 6252.3 5849.4 5904.5 5910.6 5918.3 5945.0 5637.7 5929.7 6644.1 6762.6 6804.7 6722.0 6732.1 6741.7 6745.7 6776.9 6745.7 6842.2 6856.5 6870.4 6879.2 6387.4 6445.5 6527.9 6583.3 6642.4 6716.7 6759.8 6789.3 6792.3 6793.7 6130.9 6508.4 6508.6 6517.6 6520.4 5729.4 6281.3 6367.2 6515.5 6549.4 5451.0 6066.9 6076.2 6321.4 6400.6 6515.2 6523.5 6525.4 6544.9 6560.1 6156.4 6177.1 6299.9 6364.9 6386.9 12:21:13 INFO - PID 1641 | 12:21:13 INFO - PID 1641 | [#1] ext.html Cycles:180 Average:45053.00 Median:175710.18 stddev:79167.87 (45.1%) stddev-sans-first:79320.26 12:21:13 INFO - PID 1641 | Values: 808.6 831.0 1105.2 1130.3 1276.9 38666.8 39477.0 39490.6 39511.8 39576.4 39352.8 39751.2 39910.0 39941.6 39944.6 7770.7 7961.2 8354.3 8518.4 8530.7 8322.7 8461.3 8479.8 8494.7 8497.6 8447.7 8521.7 8536.5 8540.4 8551.2 8492.3 8496.8 8522.6 8527.9 8548.8 8247.4 8306.9 8309.4 8319.1 8325.5 37661.0 37850.4 37869.0 37915.0 37920.0 3671.7 3706.0 3713.4 3723.7 3724.8 31019.2 31040.4 31082.0 31086.4 31098.9 3120.6 3148.2 3155.1 3164.2 3194.5 303810.5 306602.0 307639.0 308028.0 308064.0 32504.5 36855.0 38976.6 48773.8 49272.5 41851.4 46542.8 51396.0 51473.5 51508.0 13281.9 14185.1 14309.9 14324.7 14334.2 9486.5 11400.5 11415.8 11428.5 11633.5 11059.8 13732.1 13754.6 13756.9 13807.8 5963.0 6034.8 6043.4 6045.2 6067.5 1034.8 1047.1 1054.4 1076.2 1088.6 19904.9 20710.0 22797.5 23020.0 24666.8 1111.8 1123.3 1128.2 1161.8 1167.3 10309.2 11650.7 12606.9 12649.9 12790.0 316508.0 318399.0 318728.0 319337.0 319475.0 53726.7 54042.7 54270.0 54354.2 54619.2 1502.1 1544.9 1575.9 1587.3 1599.1 22887.8 24319.5 24437.4 24607.0 24614.5 38504.8 38638.0 38699.0 38805.4 38892.6 277430.0 278711.0 279162.0 279423.0 279596.0 33804.7 34659.7 34728.1 34789.5 34803.8 43612.1 44199.3 44689.8 44775.1 44883.8 30157.8 30308.4 31102.0 31102.9 31435.1 30059.8 30113.8 30979.8 31159.4 31316.1 37425.0 37568.7 37622.0 37639.6 37667.0 24010.9 27960.7 29545.9 31664.7 31945.4 21443.2 21578.6 24403.8 24507.0 24691.0 12:21:13 INFO - PID 1641 | 12:21:13 INFO - PID 1641 | [#2] jquery.html Cycles:180 Average:35721.20 Median:12316.99 stddev:93056.54 (755.5%) stddev-sans-first:93279.75 12:21:13 INFO - PID 1641 | Values: 378.7 436.8 437.9 452.3 463.8 20886.4 21501.8 21731.0 21977.9 22274.4 17994.7 21101.1 22192.8 22396.2 22521.9 5757.9 5933.0 5984.6 6239.9 6254.9 3918.0 6176.1 6211.6 6212.9 6225.1 4767.4 6171.6 6182.0 6190.4 6202.9 5877.6 5906.8 5954.6 5997.2 6009.8 5242.9 5324.1 5353.1 5365.2 5434.8 27922.7 28112.6 28486.4 28642.7 29054.7 1886.5 2079.9 2140.7 2141.9 2146.8 16822.9 17045.4 17807.6 17845.6 17993.0 2088.0 2195.1 2204.8 2222.5 2228.6 128668.0 159908.2 160629.0 161608.0 164459.2 10561.0 12204.7 12792.4 12805.4 12855.9 13957.9 16763.2 16869.0 16875.7 16949.8 8692.3 8893.9 8912.9 8973.8 9045.5 8325.5 8398.8 8399.7 8549.3 8576.1 6785.6 7930.2 8044.5 8072.8 8131.2 2229.4 2237.8 2241.6 2246.4 2409.5 934.3 961.6 972.7 983.6 1006.5 20435.4 20921.5 20938.4 20973.2 21354.0 994.6 999.2 1011.6 1015.3 1024.4 10403.4 10694.4 10826.0 10885.5 10897.5 535863.0 543146.0 548353.0 554756.0 554985.0 126961.0 131153.3 134067.0 134375.3 135895.0 1184.1 1218.4 1230.6 1234.9 1241.5 20737.4 20751.0 23235.4 23279.0 23350.2 54250.0 55860.7 56340.4 56513.0 56690.7 79824.0 80267.6 80871.0 80931.0 81154.0 8924.0 8933.9 9000.8 9107.5 9252.8 14066.4 14083.0 14094.8 14316.2 14335.7 11718.5 12092.9 12155.0 12162.5 12182.3 11171.6 11223.0 11324.5 11631.8 11711.7 10244.5 11198.8 12284.3 12291.9 12408.1 8661.1 8839.6 9058.0 9088.9 9149.7 10124.6 11601.7 11798.2 11936.1 11973.2 12:21:13 INFO - PID 1641 | 12:21:13 INFO - PID 1641 | [#3] mootools.html Cycles:180 Average:6942.02 Median:3719.28 stddev:4291.92 (115.4%) stddev-sans-first:4283.82 12:21:13 INFO - PID 1641 | Values: 1409.0 1463.8 1480.5 1496.0 1496.9 11497.1 11871.3 12060.9 12063.2 12264.3 10070.5 11895.2 12019.3 12021.1 12022.4 2543.1 2595.5 2599.2 2599.9 2611.8 2420.7 2464.6 2466.2 2469.1 2471.7 2508.8 2529.1 2554.4 2554.8 2556.3 2529.6 2563.6 2575.0 2577.8 2586.4 2546.7 2547.0 2558.6 2562.6 2565.5 11506.0 11745.5 11763.0 11764.9 11772.9 6471.8 6742.8 6745.8 6753.3 6755.2 3866.3 4222.3 4285.1 4298.9 4302.1 1176.7 1196.6 1250.4 1253.4 1255.0 13297.5 13464.0 15377.7 16136.1 16305.0 5305.8 5366.3 5545.2 5550.9 5555.1 11566.7 11739.2 11754.8 11765.8 11786.0 5589.7 6367.2 6479.3 6487.0 6526.9 5986.4 6122.4 6205.9 6207.9 6253.7 4243.7 4380.8 4382.5 4405.5 4423.3 1279.8 1288.6 1289.1 1296.7 1308.7 697.9 713.2 782.8 817.7 820.3 6711.1 6718.6 6795.5 6869.6 6944.4 760.3 857.3 936.5 963.4 965.7 3688.2 3750.4 3821.7 3840.7 3868.6 13806.1 14036.3 14464.2 14817.2 14945.1 14477.4 14637.6 14766.5 14774.3 14813.2 8231.0 8369.2 8458.9 8517.0 8537.1 6412.6 6478.6 6550.1 6568.2 6594.6 10698.8 12554.5 12790.7 12926.4 12988.4 10880.7 11263.8 13038.5 13128.5 13285.0 8194.9 8403.0 8417.9 8434.5 8453.2 10553.4 10574.2 10672.7 10688.5 10698.6 9113.5 9178.2 9180.0 9191.1 9236.3 8903.7 8941.3 9003.1 9012.3 9099.0 7062.4 8143.7 8312.4 8314.0 8334.9 5256.9 5641.3 5828.7 5917.8 5997.1 6059.8 6117.6 6199.6 6201.0 6234.4 12:21:13 INFO - PID 1641 | 12:21:13 INFO - PID 1641 | [#4] prototype.html Cycles:180 Average:5313.10 Median:5856.03 stddev:1677.10 (28.6%) stddev-sans-first:1640.54 12:21:13 INFO - PID 1641 | Values: 386.6 405.3 407.1 411.6 421.1 5812.1 5991.8 6388.7 6426.4 6462.4 6001.6 6065.8 6130.0 6247.9 6297.8 4735.8 4747.5 4753.5 4757.9 4776.8 4320.7 4756.6 4758.2 4763.7 4784.1 4449.7 4458.9 4467.4 4473.7 4516.7 3730.9 4468.5 4481.1 4489.0 4497.2 3848.6 3849.5 3851.7 3857.5 3869.2 4956.3 4966.8 5978.7 6102.7 6118.8 5317.0 5386.7 5392.0 5395.4 5412.0 5334.4 6360.0 6592.2 6632.5 6677.7 4399.4 4416.2 4424.5 4428.9 4444.7 4905.9 6710.2 6889.0 7089.7 7179.7 4298.0 4359.2 4465.4 4481.4 4491.4 3976.8 4442.9 4567.0 4571.0 4591.6 4928.1 5045.3 5377.4 5413.5 5439.5 5568.5 5852.7 6652.7 6733.7 6839.9 1489.0 1545.9 1558.1 1564.8 1565.1 783.5 963.0 991.0 1007.4 1023.2 5043.9 5603.4 6119.4 6273.5 6295.4 5302.3 6407.6 6917.3 6967.9 6997.4 5834.0 5927.5 6143.5 6302.8 6309.5 3212.6 3614.9 3642.2 3646.4 3667.6 5316.9 6888.3 7072.4 7098.8 7138.2 7176.7 7182.9 7185.7 7192.7 7198.2 6372.5 6788.4 7011.4 7053.8 7101.8 5781.8 6449.8 6604.8 6932.8 6991.0 5380.0 5859.3 6362.0 6653.5 6841.1 5851.6 6117.0 7030.2 7137.7 7218.6 3764.9 4512.6 4527.9 4534.5 4548.3 4999.4 6518.1 6891.2 6957.5 7120.6 6281.0 6762.9 6787.5 6791.4 6809.3 6008.1 6139.3 6538.3 6711.8 6804.2 5510.6 5513.8 5625.0 6076.8 6551.4 6392.7 6612.2 6674.8 6795.0 6818.8 5933.7 6308.5 6620.0 6630.8 6646.6 12:21:13 INFO - PID 1641 | 12:21:13 INFO - PID 1641 | [#5] yui.html Cycles:180 Average:21796.06 Median:18048.28 stddev:31150.68 (172.6%) stddev-sans-first:31198.25 12:21:13 INFO - PID 1641 | Values: 820.2 825.6 841.3 847.7 860.6 16279.8 16700.1 16717.3 18326.9 18941.5 17987.3 18192.3 18231.5 18469.3 18491.9 994.3 1000.7 1002.7 1005.3 1005.4 989.8 999.1 1003.4 1003.9 1010.1 1050.7 1053.9 1055.3 1057.8 1062.8 1036.1 1050.9 1050.9 1051.5 1058.6 755.8 760.0 770.6 771.9 774.8 16889.9 17007.9 17268.4 17271.0 17291.0 16788.0 16988.9 17168.9 17417.4 17465.6 14136.6 14178.9 14396.1 14456.1 14485.9 3455.3 3491.0 3506.5 3535.2 3536.6 120398.4 135376.0 136019.0 138591.3 139457.0 13373.9 13414.5 13688.5 13752.9 13905.5 25545.4 25555.4 25780.0 25933.0 26058.9 6517.9 7853.3 7865.3 7998.6 8035.4 3399.8 3476.6 3953.9 3994.2 4034.4 4256.3 4319.8 4372.5 4393.8 4410.9 939.6 941.4 952.6 953.3 961.3 636.5 637.5 640.8 661.7 678.8 11267.8 11816.7 11866.5 12144.5 12196.6 634.4 641.2 650.4 655.3 675.9 4489.8 5414.0 5623.0 5631.2 5642.0 83250.6 109544.0 109715.5 111061.9 112395.0 84527.0 86684.0 91580.0 96695.5 97229.0 68275.7 78469.0 79254.7 79832.0 80116.6 12152.8 12389.3 12460.9 12694.7 12717.2 38363.0 38403.8 38681.6 38851.8 39159.6 45801.0 48973.0 49262.5 49462.8 49649.5 15567.5 15915.9 16211.8 16309.7 16464.5 17518.9 18109.3 18212.1 18332.7 18629.3 16468.8 16667.4 17401.4 17499.6 17560.4 14066.5 16240.8 16334.0 17363.6 17394.8 15850.8 15885.4 16040.8 16311.6 16420.8 11769.5 11887.7 11898.9 12105.2 12271.4 13001.0 13217.0 13263.7 13339.5 13374.3 12:21:13 INFO - PID 1641 | -------- Summary: end -------- 12:21:13 INFO - PID 1641 | 12:21:14 INFO - TEST-INFO | 1641: exit 0 12:21:14 INFO - TEST-OK | dromaeo_css | took 1113480ms 12:21:14 INFO - TEST-START | kraken 12:21:14 INFO - operating with platform_type : mac_ 12:21:14 INFO - Initialising browser for kraken test... 12:21:14 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/tmp_voblz/profile http://localhost:49230/getInfo.html) 12:21:14 INFO - PID 1655 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2978: String.localeCompare is deprecated; use String.prototype.localeCompare instead 12:21:16 INFO - PID 1655 | 2017-05-22 12:21:16.660 plugin-container[1656:10050] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x973f, name = 'com.apple.tsm.portname' 12:21:16 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:21:16 INFO - PID 1655 | 2017-05-22 12:21:16.663 plugin-container[1656:10050] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x450f, name = 'com.apple.CFPasteboardClient' 12:21:16 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:21:16 INFO - PID 1655 | 2017-05-22 12:21:16.663 plugin-container[1656:10050] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:21:16 INFO - PID 1655 | __metrics Screen width/height:1600/1200 12:21:16 INFO - PID 1655 | colorDepth:24 12:21:16 INFO - PID 1655 | Browser inner width/height: 1024/691 12:21:16 INFO - PID 1655 | __metrics 12:21:17 INFO - PID 1655 | [Child 1657] WARNING: pipe error: Broken pipe: file /builds/slave/m-cen-m64-00000000000000000000/build/src/ipc/chromium/src/chrome/common/ipc_channel_posix.cc, line 709 12:21:17 INFO - PID 1655 | 2017-05-22 12:21:17.295 plugin-container[1657:10105] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 12:21:17 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:21:17 INFO - PID 1655 | 2017-05-22 12:21:17.296 plugin-container[1657:10105] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464b, name = 'com.apple.CFPasteboardClient' 12:21:17 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:21:17 INFO - PID 1655 | 2017-05-22 12:21:17.296 plugin-container[1657:10105] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:21:18 INFO - TEST-INFO | 1655: exit 0 12:21:18 INFO - Browser initialized. 12:21:18 INFO - Running cycle 1/1 for kraken test... 12:21:18 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/tmp_voblz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:21:19 INFO - PID 1658 | 2017-05-22 12:21:19.811 plugin-container[1659:10189] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9737, name = 'com.apple.tsm.portname' 12:21:19 INFO - PID 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:21:19 INFO - PID 1658 | 2017-05-22 12:21:19.812 plugin-container[1659:10189] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x981b, name = 'com.apple.CFPasteboardClient' 12:21:19 INFO - PID 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:21:19 INFO - PID 1658 | 2017-05-22 12:21:19.812 plugin-container[1659:10189] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:21:20 INFO - PID 1658 | 2017-05-22 12:21:20.591 plugin-container[1660:10233] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 12:21:20 INFO - PID 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:21:20 INFO - PID 1658 | 2017-05-22 12:21:20.591 plugin-container[1660:10233] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9613, name = 'com.apple.CFPasteboardClient' 12:21:20 INFO - PID 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:21:20 INFO - PID 1658 | 2017-05-22 12:21:20.591 plugin-container[1660:10233] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:21:29 INFO - PID 1658 | RSS: Main: 242507776 12:21:29 INFO - PID 1658 | 12:21:32 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-beat-detection) 12:21:33 INFO - PID 1658 | RSS: Main: 254271488 12:21:33 INFO - PID 1658 | 12:21:36 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-dft) 12:21:36 INFO - PID 1658 | RSS: Main: 263278592 12:21:36 INFO - PID 1658 | 12:21:40 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-fft) 12:21:40 INFO - PID 1658 | RSS: Main: 265129984 12:21:40 INFO - PID 1658 | 12:21:42 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-oscillator) 12:21:43 INFO - PID 1658 | RSS: Main: 266534912 12:21:43 INFO - PID 1658 | 12:21:45 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49230/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:21:45 INFO - PID 1658 | RSS: Main: 267866112 12:21:45 INFO - PID 1658 | 12:21:48 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49230/tests/kraken/driver.html?testName=imaging-darkroom) 12:21:49 INFO - PID 1658 | RSS: Main: 277356544 12:21:49 INFO - PID 1658 | 12:21:52 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49230/tests/kraken/driver.html?testName=imaging-desaturate) 12:21:53 INFO - PID 1658 | RSS: Main: 277086208 12:21:53 INFO - PID 1658 | 12:21:56 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49230/tests/kraken/driver.html?testName=json-parse-financial) 12:21:56 INFO - PID 1658 | RSS: Main: 276475904 12:21:56 INFO - PID 1658 | 12:21:58 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49230/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:21:58 INFO - PID 1658 | RSS: Main: 275877888 12:21:58 INFO - PID 1658 | 12:22:00 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:22:01 INFO - PID 1658 | RSS: Main: 275615744 12:22:01 INFO - PID 1658 | 12:22:03 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:22:03 INFO - PID 1658 | RSS: Main: 275644416 12:22:03 INFO - PID 1658 | 12:22:06 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:22:07 INFO - PID 1658 | RSS: Main: 275267584 12:22:07 INFO - PID 1658 | 12:22:10 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:22:11 INFO - PID 1658 | RSS: Main: 275943424 12:22:11 INFO - PID 1658 | 12:22:13 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49230/tests/kraken/driver.html?testName=ai-astar) 12:22:13 INFO - PID 1658 | RSS: Main: 277807104 12:22:13 INFO - PID 1658 | 12:22:13 INFO - PID 1658 | __start_tp_report 12:22:13 INFO - PID 1658 | _x_x_mozilla_page_load 12:22:13 INFO - PID 1658 | _x_x_mozilla_page_load_details 12:22:13 INFO - PID 1658 | |i|pagename|runs| 12:22:13 INFO - PID 1658 | |0;ai-astar;105;117;114;138;148;115;115;115;116;114 12:22:13 INFO - PID 1658 | |1;audio-beat-detection;142;112;152;187;113;119;144;95;135;131 12:22:13 INFO - PID 1658 | |2;audio-dft;197;200;201;198;204;211;207;198;226;192 12:22:13 INFO - PID 1658 | |3;audio-fft;80;59;57;89;73;84;59;59;89;86 12:22:13 INFO - PID 1658 | |4;audio-oscillator;89;70;79;73;71;70;84;69;69;93 12:22:13 INFO - PID 1658 | |5;imaging-gaussian-blur;90;88;87;88;90;87;78;136;89;88 12:22:13 INFO - PID 1658 | |6;imaging-darkroom;96;97;143;128;96;95;97;95;106;96 12:22:13 INFO - PID 1658 | |7;imaging-desaturate;92;119;142;103;145;92;118;93;96;90 12:22:13 INFO - PID 1658 | |8;json-parse-financial;49;50;49;51;53;62;44;44;49;50 12:22:13 INFO - PID 1658 | |9;json-stringify-tinderbox;56;61;54;80;64;60;56;54;56;59 12:22:13 INFO - PID 1658 | |10;stanford-crypto-aes;83;86;81;83;81;82;82;85;82;83 12:22:13 INFO - PID 1658 | |11;stanford-crypto-ccm;170;154;137;140;142;138;151;172;201;136 12:22:13 INFO - PID 1658 | |12;stanford-crypto-pbkdf2;206;231;197;186;182;179;186;185;189;183 12:22:13 INFO - PID 1658 | |13;stanford-crypto-sha256-iterative;67;70;69;69;69;71;67;71;69;65 12:22:13 INFO - PID 1658 | __end_tp_report 12:22:13 INFO - PID 1658 | __start_cc_report 12:22:13 INFO - PID 1658 | _x_x_mozilla_cycle_collect,910 12:22:13 INFO - PID 1658 | __end_cc_report 12:22:13 INFO - PID 1658 | __startTimestamp1495480933314__endTimestamp 12:22:13 INFO - PID 1658 | 12:22:13 INFO - PID 1658 | ------- Summary: start ------- 12:22:13 INFO - PID 1658 | Number of tests: 14 12:22:13 INFO - PID 1658 | 12:22:13 INFO - PID 1658 | [#0] ai-astar Cycles:10 Average:119.70 Median:115.50 stddev:12.93 (11.2%) stddev-sans-first:12.57 12:22:13 INFO - PID 1658 | Values: 105.0 117.0 114.0 138.0 148.0 115.0 115.0 115.0 116.0 114.0 12:22:13 INFO - PID 1658 | 12:22:13 INFO - PID 1658 | [#1] audio-beat-detection Cycles:10 Average:133.00 Median:143.00 stddev:25.75 (18.0%) stddev-sans-first:27.11 12:22:13 INFO - PID 1658 | Values: 142.0 112.0 152.0 187.0 113.0 119.0 144.0 95.0 135.0 131.0 12:22:13 INFO - PID 1658 | 12:22:13 INFO - PID 1658 | [#2] audio-dft Cycles:10 Average:203.40 Median:202.50 stddev:9.59 (4.7%) stddev-sans-first:9.89 12:22:13 INFO - PID 1658 | Values: 197.0 200.0 201.0 198.0 204.0 211.0 207.0 198.0 226.0 192.0 12:22:13 INFO - PID 1658 | 12:22:13 INFO - PID 1658 | [#3] audio-fft Cycles:10 Average:73.50 Median:82.00 stddev:13.71 (16.7%) stddev-sans-first:14.34 12:22:13 INFO - PID 1658 | Values: 80.0 59.0 57.0 89.0 73.0 84.0 59.0 59.0 89.0 86.0 12:22:13 INFO - PID 1658 | 12:22:13 INFO - PID 1658 | [#4] audio-oscillator Cycles:10 Average:76.70 Median:76.00 stddev:9.01 (11.9%) stddev-sans-first:8.38 12:22:13 INFO - PID 1658 | Values: 89.0 70.0 79.0 73.0 71.0 70.0 84.0 69.0 69.0 93.0 12:22:13 INFO - PID 1658 | 12:22:13 INFO - PID 1658 | [#5] imaging-gaussian-blur Cycles:10 Average:92.10 Median:88.00 stddev:15.80 (18.0%) stddev-sans-first:16.74 12:22:13 INFO - PID 1658 | Values: 90.0 88.0 87.0 88.0 90.0 87.0 78.0 136.0 89.0 88.0 12:22:13 INFO - PID 1658 | 12:22:13 INFO - PID 1658 | [#6] imaging-darkroom Cycles:10 Average:104.90 Median:96.00 stddev:16.82 (17.5%) stddev-sans-first:17.52 12:22:13 INFO - PID 1658 | Values: 96.0 97.0 143.0 128.0 96.0 95.0 97.0 95.0 106.0 96.0 12:22:13 INFO - PID 1658 | 12:22:13 INFO - PID 1658 | [#7] imaging-desaturate Cycles:10 Average:109.00 Median:91.00 stddev:20.99 (23.1%) stddev-sans-first:21.35 12:22:13 INFO - PID 1658 | Values: 92.0 119.0 142.0 103.0 145.0 92.0 118.0 93.0 96.0 90.0 12:22:13 INFO - PID 1658 | 12:22:13 INFO - PID 1658 | [#8] json-parse-financial Cycles:10 Average:50.10 Median:50.00 stddev:5.04 (10.1%) stddev-sans-first:5.33 12:22:13 INFO - PID 1658 | Values: 49.0 50.0 49.0 51.0 53.0 62.0 44.0 44.0 49.0 50.0 12:22:13 INFO - PID 1658 | 12:22:13 INFO - PID 1658 | [#9] json-stringify-tinderbox Cycles:10 Average:60.00 Median:59.50 stddev:7.73 (13.0%) stddev-sans-first:8.06 12:22:13 INFO - PID 1658 | Values: 56.0 61.0 54.0 80.0 64.0 60.0 56.0 54.0 56.0 59.0 12:22:13 INFO - PID 1658 | 12:22:13 INFO - PID 1658 | [#10] stanford-crypto-aes Cycles:10 Average:82.80 Median:83.00 stddev:1.62 (2.0%) stddev-sans-first:1.72 12:22:13 INFO - PID 1658 | Values: 83.0 86.0 81.0 83.0 81.0 82.0 82.0 85.0 82.0 83.0 12:22:13 INFO - PID 1658 | 12:22:13 INFO - PID 1658 | [#11] stanford-crypto-ccm Cycles:10 Average:154.10 Median:152.50 stddev:21.10 (13.8%) stddev-sans-first:21.58 12:22:13 INFO - PID 1658 | Values: 170.0 154.0 137.0 140.0 142.0 138.0 151.0 172.0 201.0 136.0 12:22:13 INFO - PID 1658 | 12:22:13 INFO - PID 1658 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:192.40 Median:187.50 stddev:15.71 (8.4%) stddev-sans-first:15.87 12:22:13 INFO - PID 1658 | Values: 206.0 231.0 197.0 186.0 182.0 179.0 186.0 185.0 189.0 183.0 12:22:13 INFO - PID 1658 | 12:22:13 INFO - PID 1658 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.70 Median:69.00 stddev:1.89 (2.7%) stddev-sans-first:1.90 12:22:13 INFO - PID 1658 | Values: 67.0 70.0 69.0 69.0 69.0 71.0 67.0 71.0 69.0 65.0 12:22:13 INFO - PID 1658 | -------- Summary: end -------- 12:22:13 INFO - PID 1658 | 12:22:14 INFO - TEST-INFO | 1658: exit 0 12:22:14 INFO - TEST-OK | kraken | took 60072ms 12:22:14 INFO - SUITE-END | took 1173s 12:22:14 INFO - Completed test suite (00:19:33) 12:22:14 INFO - javascript benchmark 12:22:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7859.396786455245, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2883.8269703817773, 3566.2867426514704, 3598.4422414525757, 3608.6030536640983, 3619.441057163477, 6089.360617135203, 6103.664298463588, 6151.538634602406, 6168.784092556762, 6169.352218017111, 6117.127159805721, 6141.455323060925, 6143.174139895642, 6143.784967526126, 6143.938560614392, 5355.303810504629, 5402.6758394496255, 5408.4591540845995, 5433.1035379162395, 5560.304961879765, 5492.038893193695, 5501.724913754317, 5516.365617953947, 5521.0062188806, 5526.944730552704, 4836.37127173467, 5259.474052594729, 5419.512243898049, 5491.423400542945, 5559.054960656698, 5307.2569840222395, 5349.491798279158, 5415.296011518478, 5445.564354851642, 5502.862428439301, 5367.973160134175, 5382.1926710993275, 5409.323834520685, 5434.592405569598, 5436.646617969819, 5294.735263236823, 5556.749731310463, 6151.231096112986, 6166.907496387557, 6170.96914515429, 5259.658122222043, 6628.171478565179, 6637.93362066378, 6646.734130634769, 6664.733410663568, 6463.450606698436, 6570.901436478457, 6659.300773418819, 6741.0899528563505, 6756.223034351015, 6230.59501132425, 6258.092576576462, 6259.6870156492, 6259.71831267594, 6268.905966410553, 6869.484788640872, 6874.931250687529, 6884.827879302958, 6895.620740859301, 6904.758333458305, 6295.685215739195, 6356.682165891687, 6470.09418681385, 6554.14796076507, 6571.507136964747, 6651.600903945779, 6677.732890684319, 6722.630255336004, 6736.2590115087305, 6744.22441419243, 5177.404598471224, 6443.613383197023, 6567.901481477782, 6583.144191255106, 6600.86798264032, 6330.430261276507, 6441.32366101562, 6452.935470645328, 6505.447037001908, 6621.4040736333, 5324.121519949243, 5340.973295133499, 5368.5436737876935, 5368.785248590013, 5369.27514785499, 4680.321353403738, 4763.928541071887, 4765.046990601893, 4787.712737235777, 4813.879653008633, 6151.507879369639, 6244.063390491463, 6262.1546091278005, 6263.71813268404, 6271.686415679198, 5704.315482142087, 6059.151718759377, 6371.267304259976, 6642.070110184578, 6717.832054198587, 6151.200343955257, 6186.0, 6200.56596038282, 6241.750329986749, 6252.280987686474, 5849.415058494116, 5904.527637788967, 5910.645361278337, 5918.260217472816, 5945.0, 5637.661740295432, 5929.733162007634, 6644.136262285871, 6762.628055456997, 6804.727810887707, 6722.0, 6732.091167692494, 6741.730330786713, 6745.662716864235, 6776.93223067763, 6745.730170793309, 6842.178938527409, 6856.5200435969, 6870.416014638811, 6879.243283238739, 6387.393197646216, 6445.516586255956, 6527.934720652733, 6583.30875258091, 6642.368974947372, 6716.731330746716, 6759.831004224934, 6789.253182149861, 6792.286809885089, 6793.694283757144, 6130.938690613037, 6508.446782023391, 6508.642024688687, 6517.608943463218, 6520.380563846427, 5729.427057294237, 6281.309056003744, 6367.172267605181, 6515.511336649675, 6549.377809108127, 5451.0, 6066.908996365147, 6076.179715738319, 6321.399467050623, 6400.615963042233, 6515.185601799775, 6523.543351965317, 6525.412712855866, 6544.869102618016, 6560.081588577507, 6156.415140561639, 6177.073438984188, 6299.874002519832, 6364.93635063662, 6386.9361306386345], "value": 6023.280651843951, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [808.551254054, 830.958452077396, 1105.2373862035195, 1130.2709752209826, 1276.8723127687226, 38666.806665966666, 39477.0, 39490.60509394909, 39511.802440987754, 39576.40635390471, 39352.803235983854, 39751.204975900466, 39910.0, 39941.60058399415, 39944.60055399445, 7770.728024519129, 7961.164077771838, 8354.331653467709, 8518.361122915776, 8530.70142545011, 8322.66709331626, 8461.280791132762, 8479.830403391928, 8494.74515764525, 8497.61760720769, 8447.704330348439, 8521.701740439086, 8536.530490823003, 8540.402171847973, 8551.230389264965, 8492.278156356717, 8496.78758031048, 8522.573871306471, 8527.914720852805, 8548.829023419497, 8247.422680412374, 8306.875396869053, 8309.37679674027, 8319.084900660922, 8325.542095184763, 37661.0, 37850.43224351637, 37869.0, 37915.0, 37920.0, 3671.7429779915415, 3706.036430528055, 3713.4429835524616, 3723.6834869036165, 3724.7951362675044, 31019.22451938697, 31040.379192416025, 31082.0, 31086.378272434424, 31098.911538096283, 3120.641126270485, 3148.1972097115317, 3155.1323386069016, 3164.2089477630593, 3194.4569423198113, 303810.48094759387, 306602.0, 307639.0, 308028.0, 308064.0, 32504.512432313535, 36855.0, 38976.6102338973, 48773.780655483184, 49272.50727492679, 41851.372229416585, 46542.836429088864, 51395.972080558924, 51473.48526514762, 51508.0, 13281.867181328256, 14185.077969931921, 14309.856901430852, 14324.713505729827, 14334.211618360881, 9486.525673716287, 11400.543978240778, 11415.828762568568, 11428.542858285742, 11633.47649355764, 11059.778804423866, 13732.107413018122, 13754.587362379145, 13756.86243137576, 13807.792883106762, 5963.0459126539545, 6034.758609655564, 6043.365446628129, 6045.21412216409, 6067.514598832083, 1034.8240799064176, 1047.0942634621, 1054.3568423261804, 1076.2197406880107, 1088.6353071720903, 19904.90047549782, 20710.0, 22797.544049119257, 23020.0, 24666.766661667203, 1111.8443417921496, 1123.3035517978742, 1128.249713940237, 1161.7676464707092, 1167.287954347847, 10309.175265978703, 11650.6504804856, 12606.873931260754, 12649.873501265054, 12790.040746944016, 316508.0, 318399.0, 318728.0, 319337.0, 319475.0, 53726.731366342916, 54042.7297863516, 54270.0, 54354.184687229725, 54619.18071228855, 1502.136271643809, 1544.9304781284645, 1575.9290831912592, 1587.3412533798344, 1599.0885195438489, 22887.77112228923, 24319.51360972806, 24437.38906527351, 24607.0, 24614.507709845344, 38504.80747596244, 38638.034049149, 38699.0, 38805.41791873181, 38892.6110738889, 277430.0, 278711.0, 279162.0, 279423.0, 279596.0, 33804.66195338015, 34659.65340346564, 34728.13179670528, 34789.47815782816, 34803.78186763451, 43612.12775744404, 44199.33700994423, 44689.77655111704, 44775.10449790921, 44883.77558112189, 30157.849210753804, 30308.393832123675, 31102.0, 31102.911398100958, 31435.05694829159, 30059.849700751358, 30113.849430752707, 30979.845100774353, 31159.37681246317, 31316.06051818449, 37425.0, 37568.68509602151, 37622.0, 37639.62360376361, 37667.0, 24010.87994560016, 27960.72039279581, 29545.85227073851, 31664.683353166172, 31945.367921650766, 21443.249486267905, 21578.568428631654, 24403.755962440147, 24507.01971921103, 24691.0], "value": 17530.435238920465, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.7424551305113, 436.83618643008873, 437.89271628451036, 452.33055078483835, 463.768115942029, 20886.373408797743, 21501.784982150173, 21731.0, 21977.890110549466, 22274.443138921535, 17994.730079048826, 21101.050452729625, 22192.779397133152, 22396.21613243537, 22521.887390563024, 5757.877213943283, 5933.0, 5984.581079324438, 6239.937600624004, 6254.937450625481, 3917.9804100979454, 6176.073588961647, 6211.6273023618505, 6212.875742485147, 6225.097360882669, 4767.380240568721, 6171.598846075013, 6181.9690901545655, 6190.3500132486115, 6202.9379706203035, 5877.6473411595225, 5906.763729450839, 5954.618152738917, 5997.220361352996, 6009.75960961561, 5242.9213561796605, 5324.121519949243, 5353.063213937546, 5365.222042803811, 5434.755436005389, 27922.720772792214, 28112.578311325346, 28486.43919510061, 28642.713572864213, 29054.709452905412, 1886.5472286651104, 2079.896005199749, 2140.6681964295403, 2141.914323427061, 2146.785321467856, 16822.90651107674, 17045.403410880685, 17807.643847123116, 17845.643087138313, 17993.0, 2087.95824083519, 2195.121951219509, 2204.768499307582, 2222.4554984029014, 2228.5988522066023, 128668.0, 159908.20045899812, 160629.0, 161608.0, 164459.1777041119, 10561.0, 12204.694882628006, 12792.360381980863, 12805.423755931006, 12855.871441285468, 13957.930210348883, 16763.245653945356, 16869.0, 16875.746863797052, 16949.830501694825, 8692.261157801531, 8893.866592001124, 8912.910870891337, 8973.775655608662, 9045.547722613843, 8325.500469971821, 8398.790030249293, 8399.748007559783, 8549.316054715608, 8576.142385761374, 6785.592864428051, 7930.246626570467, 8044.477108987897, 8072.8385432291025, 8131.186881311822, 2229.4203507088273, 2237.762237762238, 2241.60771864923, 2246.4383904023994, 2409.481961378312, 934.2806039349798, 961.6393852305386, 972.6547075788135, 983.5770618633914, 1006.4766321512918, 20435.38693839187, 20921.476963076046, 20938.37184884456, 20973.161073557192, 21354.0, 994.5872462927948, 999.2206079258191, 1011.6054738651932, 1015.3197357770312, 1024.416082832778, 10403.42781147029, 10694.358338499715, 10826.0, 10885.510152043176, 10897.455127243764, 535863.0, 543146.0, 548353.0, 554756.0, 554985.0, 126961.0, 131153.34423328014, 134067.0, 134375.32812336073, 135895.0, 1184.1237484261756, 1218.4334284557724, 1230.6431134970758, 1234.8888600026041, 1241.4599649152647, 20737.377878663665, 20751.0, 23235.419114522272, 23278.95244713958, 23350.18274360389, 54250.0, 55860.72069639788, 56340.43659563352, 56513.0, 56690.716546417, 79824.0, 80267.59866200632, 80871.0, 80931.0, 81154.0, 8923.955380223057, 8933.910660893307, 9000.819983600422, 9107.547628377117, 9252.768680783034, 14066.437342506186, 14083.0, 14094.788578171541, 14316.212608306643, 14335.713285734018, 11718.531258749554, 12092.879071209527, 12155.0, 12162.513499459923, 12182.269063856196, 11171.553137874394, 11222.989930906258, 11324.547018119183, 11631.76736465283, 11711.707207319885, 10244.487775611338, 11198.83201751958, 12284.324362160167, 12291.877081229431, 12408.131430800118, 8661.13388661129, 8839.646414143619, 9057.954710226406, 9088.863667044867, 9149.679761208326, 10124.645637402655, 11601.709957251136, 11798.233114847508, 11936.10479214045, 11973.161878668758], "value": 10461.741067115005, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1408.9506867259645, 1463.817022872141, 1480.4744315767903, 1496.0425327790213, 1496.944654018916, 11497.137714671391, 11871.287722736643, 12060.939695301522, 12063.155579109465, 12264.264144151357, 10070.546825392856, 11895.167338286323, 12019.338936358497, 12021.098417618692, 12022.398880056006, 2543.0590681447843, 2595.5328040952622, 2599.181257903754, 2599.922002339933, 2611.8302310349864, 2420.685310909579, 2464.6426268191103, 2466.235467005225, 2469.1358024691394, 2471.7404672509397, 2508.7867531259867, 2529.127451029392, 2554.425254317782, 2554.8083893707953, 2556.322574517754, 2529.557327467695, 2563.602641590547, 2575.0, 2577.7808886244693, 2586.430985183257, 2546.7071286801884, 2547.0066673997158, 2558.6417901493805, 2562.5515534781525, 2565.486902619465, 11506.0, 11745.530178792838, 11763.000144987685, 11764.882351176464, 11772.882271177265, 6471.773487927947, 6742.8314292142595, 6745.797626071177, 6753.257141714407, 6755.1556055493065, 3866.3040652682507, 4222.345536441826, 4285.078708077778, 4298.871033868958, 4302.118065796504, 1176.7411169542686, 1196.5572738086964, 1250.4310538704958, 1253.3921048291572, 1254.9811752823714, 13297.468101275937, 13464.0, 15377.692446151126, 16136.112513811735, 16305.021698698116, 5305.814296499604, 5366.329208848894, 5545.168224766237, 5550.888982220333, 5555.138953462219, 11566.65300041, 11739.23694959825, 11754.824517548348, 11765.823512647317, 11786.0, 5589.720513974285, 6367.204099487564, 6479.319671434526, 6486.956608668254, 6526.869462610721, 5986.4013598639785, 6122.418370254819, 6205.875882482325, 6207.937920620826, 6253.6560489173535, 4243.726881935407, 4380.846670366522, 4382.452193475816, 4405.515393306734, 4423.336499525097, 1279.7760391931374, 1288.6198571421453, 1289.1233960906673, 1296.742160104697, 1308.718625495504, 697.870893884633, 713.1762813949896, 782.7769085810517, 817.7056259746605, 820.2658620534588, 6711.0940023096255, 6718.59931744708, 6795.456363491007, 6869.622170780555, 6944.409725173316, 760.3005235183645, 857.3184318466834, 936.4708939449189, 963.4055637919255, 965.6620182935916, 3688.1517251032415, 3750.3999360102252, 3821.7133714970932, 3840.6927445804267, 3868.574456809749, 13806.102603330752, 14036.298185090705, 14464.20446875432, 14817.185054822088, 14945.103293802407, 14477.420903163755, 14637.634059148606, 14766.48317308889, 14774.261286935825, 14813.185274809988, 8230.958845205736, 8369.204925532065, 8458.87311690325, 8516.957415212884, 8537.060923298432, 6412.6152430852435, 6478.611283323015, 6550.082988381726, 6568.1789776277965, 6594.604323740591, 10698.786024279627, 12554.493460784764, 12790.744185116431, 12926.448826140548, 12988.415521301753, 10880.727981800519, 11263.831042534204, 13038.47846086146, 13128.474861005452, 13285.0, 8194.918050819415, 8403.033651130198, 8417.873731893904, 8434.493930364199, 8453.154684531744, 10553.41956192386, 10574.206934479791, 10672.679819605424, 10688.465576721288, 10698.572057117628, 9113.453192808453, 9178.219851312446, 9180.036096209802, 9191.080891910755, 9236.30727695439, 8903.732888013112, 8941.329400295133, 9003.099690030944, 9012.2790176787, 9099.0, 7062.39969602569, 8143.714969976022, 8312.376571757264, 8314.0, 8334.916650833657, 5256.921146182887, 5641.294838145232, 5828.708564571839, 5917.792877249276, 5997.100434934795, 6059.787907423219, 6117.632942023492, 6199.56603037783, 6201.015674983962, 6234.407731265523], "value": 5302.004935779852, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.5921452867225, 405.339296945978, 407.12870504414684, 411.5514089642289, 421.13456846181083, 5812.070068788995, 5991.801639672067, 6388.744450221991, 6426.357364263571, 6462.353764623535, 6001.5798894077325, 6065.818025459233, 6130.0, 6247.907285893478, 6297.779577714781, 4735.763211839403, 4747.548982846624, 4753.453352864413, 4757.904841903177, 4776.785044672981, 4320.740755554661, 4756.595689366408, 4758.191107511714, 4763.737994410306, 4784.1149387363275, 4449.733016019033, 4458.88527868033, 4467.352233926079, 4473.709208901428, 4516.661250406216, 3730.9440358394645, 4468.50846406895, 4481.126180394825, 4488.955110448919, 4497.190505708971, 3848.5766565677754, 3849.538055433366, 3851.6726078283377, 3857.537095548524, 3869.226154769029, 4956.281339205795, 4966.801327946878, 5978.671173085479, 6102.72537735803, 6118.816435506942, 5317.042932272189, 5386.730663466811, 5391.973040134774, 5395.406505284415, 5411.9717253722065, 5334.413214546396, 6360.0, 6592.241892182413, 6632.469402447792, 6677.699503522354, 4399.36209249657, 4416.205083085043, 4424.513303536641, 4428.933565996513, 4444.73331600105, 4905.901881962376, 6710.228323742735, 6888.965555172192, 7089.680964356565, 7179.676914538858, 4297.978510107429, 4359.193549193409, 4465.37484752135, 4481.3726078349055, 4491.371208030879, 3976.8069579126145, 4442.888927776831, 4566.995261042565, 4570.988764943414, 4591.609713174417, 4928.063667903087, 5045.318881950963, 5377.435369286169, 5413.512783849473, 5439.5104440600535, 5568.52667523257, 5852.736626851802, 6652.667366631746, 6733.70048676155, 6839.93160068393, 1489.0, 1545.9072455652697, 1558.096304143594, 1564.843515648426, 1565.1391734546182, 783.475071701961, 962.9903700963041, 990.960361585543, 1007.3552926127285, 1023.2325755683238, 5043.8903441242865, 5603.3836278009385, 6119.388061193934, 6273.498120150377, 6295.401936815995, 5302.337207849019, 6407.647579383085, 6917.27368626287, 6967.860642787217, 6997.405220556309, 5833.970830145907, 5927.496162826122, 6143.477804386588, 6302.833975714508, 6309.495240380758, 3212.6305474870223, 3614.9096272593397, 3642.21692336149, 3646.3983442732183, 3667.578228503703, 5316.9202461963105, 6888.345607167311, 7072.434205263567, 7098.751543695946, 7138.179109402381, 7176.748813791597, 7182.856342873113, 7185.712571497186, 7192.676329565182, 7198.244184360671, 6372.522060845455, 6788.436071062732, 7011.432654519475, 7053.753118640823, 7101.786946391617, 5781.768729250783, 6449.774257901138, 6604.768833090819, 6932.826679333057, 6991.0, 5380.0046991306735, 5859.326177489644, 6361.991945074758, 6653.469701968671, 6841.144856892888, 5851.64890106595, 6116.969415152896, 7030.15638123408, 7137.678804453708, 7218.639068046682, 3764.9058773529787, 4512.593866552026, 4527.909441811211, 4534.546545345439, 4548.340490628906, 4999.425066117443, 6518.087467754423, 6891.173059232924, 6957.512974091967, 7120.582120582046, 6280.9685951569945, 6762.850315446484, 6787.490938179755, 6791.388775010273, 6809.319068093349, 6008.098785182257, 6139.324674285915, 6538.313477084837, 6711.832204194934, 6804.183497980274, 5510.614257001971, 5513.786966867283, 5624.971875140598, 6076.817695469143, 6551.44312733404, 6392.65754191625, 6612.173478315211, 6674.799756007327, 6795.0, 6818.795436136924, 5933.732982015733, 6308.495320374451, 6619.97390404488, 6630.801075967729, 6646.634435106115], "value": 4791.667886582162, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [820.192110770891, 825.6367198432689, 841.3353450773889, 847.7033038436545, 860.6041221038322, 16279.75580366294, 16700.081495517756, 16717.331306747732, 18326.908365458155, 18941.52646183846, 17987.280508779666, 18192.27230910765, 18231.453056408296, 18469.261229550837, 18491.907540462278, 994.2791476179766, 1000.7498125468633, 1002.6641075239803, 1005.3314545827053, 1005.4168582222294, 989.8119357322122, 999.1257649556638, 1003.3678782367135, 1003.9196864250843, 1010.1211945607295, 1050.6585359758071, 1053.8577292065588, 1055.2876808154504, 1057.8307470804673, 1062.7502536903821, 1036.0571879589613, 1050.9054185123375, 1050.9106725928305, 1051.4795176387738, 1058.5659879449427, 755.8148253677829, 760.0, 770.6223950264387, 771.9459637825355, 774.7830607429872, 16889.91555042229, 17007.894486858313, 17268.39560615385, 17270.96374217551, 17290.96254224738, 16788.0, 16988.915055424768, 17168.91415542927, 17417.39039133637, 17465.563360915952, 14136.646583835487, 14178.858211417857, 14396.13623182602, 14456.060356076927, 14485.927570362186, 3455.3089382123676, 3490.9650903491147, 3506.5441492605796, 3535.169235229719, 3536.6109727929907, 120398.3980080094, 135376.0, 136019.0, 138591.30704346515, 139457.0, 13373.933130334286, 13414.463421463031, 13688.52090176839, 13752.93123534376, 13905.513307034205, 25545.361365966, 25555.36111597225, 25780.0, 25933.0, 26058.869705651352, 6517.934820651828, 7853.293203611703, 7865.292123708894, 7998.600069996477, 8035.397345199133, 3399.847006884696, 3476.5654293213347, 3953.940690889639, 3994.1811928554575, 4034.3545032794614, 4256.3189889617515, 4319.784010799447, 4372.453443319585, 4393.780310984438, 4410.933835992462, 939.5865819039601, 941.3645789092473, 952.5665822050951, 953.2993249961273, 961.2598299309493, 636.4939872801134, 637.4995628431659, 640.794945617398, 661.7485355564854, 678.8404724889746, 11267.774644507228, 11816.70458238551, 11866.525338986516, 12144.514219431301, 12196.634100976808, 634.3561285295428, 641.2337256977867, 650.4211251985737, 655.3348351423309, 675.9324067593201, 4489.753063581469, 5413.9729301353245, 5622.971885140548, 5631.18347839561, 5641.984442800335, 83250.58374708208, 109544.0, 109715.45142274396, 111061.88938110677, 112395.0, 84527.04155354656, 86684.0, 91580.0, 96695.51652241693, 97229.0, 68275.65862170757, 78469.0, 79254.71551909349, 79832.0, 80116.59941700254, 12152.817707734213, 12389.256644601353, 12460.937695311466, 12694.682632934251, 12717.173383730027, 38363.0, 38403.807980959915, 38681.6131838678, 38851.80574097111, 39159.6084039156, 45801.0, 48973.0, 49262.52212433633, 49462.75268623634, 49649.503504965935, 15567.455139070078, 15915.88588798773, 16211.837881621033, 16309.673806523566, 16464.506064818073, 17518.91240543789, 18109.275628974694, 18212.089395530435, 18332.725009124606, 18629.347972820888, 16468.752968705718, 16667.416640417527, 17401.390951316644, 17499.56251093733, 17560.38538651141, 14066.507672231422, 16240.837591623933, 16334.0, 17363.56591085233, 17394.82605173932, 15850.7622385662, 15885.444009459614, 16040.839591603935, 16311.59221019484, 16420.75368869444, 11769.470373833026, 11887.702807429883, 11898.940505297764, 12105.152639315163, 12271.386430678609, 13001.0, 13217.0, 13263.734725305247, 13339.533116340881, 13374.264415457243], "value": 8397.453168043425, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1520.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 117.0, 114.0, 138.0, 148.0, 115.0, 115.0, 115.0, 116.0, 114.0], "value": 119.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 112.0, 152.0, 187.0, 113.0, 119.0, 144.0, 95.0, 135.0, 131.0], "value": 133.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 200.0, 201.0, 198.0, 204.0, 211.0, 207.0, 198.0, 226.0, 192.0], "value": 203.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 57.0, 89.0, 73.0, 84.0, 59.0, 59.0, 89.0, 86.0], "value": 73.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 70.0, 79.0, 73.0, 71.0, 70.0, 84.0, 69.0, 69.0, 93.0], "value": 76.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 87.0, 88.0, 90.0, 87.0, 78.0, 136.0, 89.0, 88.0], "value": 92.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 143.0, 128.0, 96.0, 95.0, 97.0, 95.0, 106.0, 96.0], "value": 104.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 119.0, 142.0, 103.0, 145.0, 92.0, 118.0, 93.0, 96.0, 90.0], "value": 109.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 50.0, 49.0, 51.0, 53.0, 62.0, 44.0, 44.0, 49.0, 50.0], "value": 50.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 61.0, 54.0, 80.0, 64.0, 60.0, 56.0, 54.0, 56.0, 59.0], "value": 60.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 81.0, 83.0, 81.0, 82.0, 82.0, 85.0, 82.0, 83.0], "value": 82.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 154.0, 137.0, 140.0, 142.0, 138.0, 151.0, 172.0, 201.0, 136.0], "value": 154.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [206.0, 231.0, 197.0, 186.0, 182.0, 179.0, 186.0, 185.0, 189.0, 183.0], "value": 192.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 70.0, 69.0, 69.0, 69.0, 71.0, 67.0, 71.0, 69.0, 65.0], "value": 68.7, "unit": "score"}]}]} 12:22:14 INFO - Return code: 0 12:22:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:22:14 INFO - # TBPL SUCCESS # 12:22:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:22:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:22:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:22:14 INFO - 2017-05-22 12:22:14.659 screenresolution[1665:10605] starting screenresolution argv=screenresolution get 12:22:14 INFO - 2017-05-22 12:22:14.662 screenresolution[1665:10605] Display 0: 1600x1200x32@60 12:22:14 INFO - 2017-05-22 12:22:14.668 screenresolution[1666:10608] starting screenresolution argv=screenresolution list 12:22:14 INFO - Available Modes on Display 0 12:22:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:22:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:22:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:22:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:22:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:22:14 INFO - Intel Iris: 12:22:14 INFO - Chipset Model: Intel Iris 12:22:14 INFO - Type: GPU 12:22:14 INFO - Bus: Built-In 12:22:14 INFO - VRAM (Dynamic, Max): 1536 MB 12:22:14 INFO - Vendor: Intel (0x8086) 12:22:14 INFO - Device ID: 0x0a2e 12:22:14 INFO - Revision ID: 0x0009 12:22:14 INFO - Displays: 12:22:14 INFO - Display: 12:22:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:22:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:22:14 INFO - Display Serial Number: 1600x1200 60 12:22:14 INFO - Main Display: Yes 12:22:14 INFO - Mirror: Off 12:22:14 INFO - Online: Yes 12:22:14 INFO - Rotation: Supported 12:22:14 INFO - Return code: 0 12:22:14 INFO - Running post-action listener: _resource_record_post_action 12:22:14 INFO - [mozharness: 2017-05-22 19:22:14.901854Z] Finished run-tests step (success) 12:22:14 INFO - Running post-run listener: _resource_record_post_run 12:22:14 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 44891136; Write bytes: 566074368; Read time: 366; Write time: 1721 12:22:14 INFO - TinderboxPrint: CPU usage
54.3% 12:22:14 INFO - TinderboxPrint: I/O read bytes / time
44,891,136 / 366 12:22:14 INFO - TinderboxPrint: I/O write bytes / time
566,074,368 / 1,721 12:22:14 INFO - TinderboxPrint: CPU idle
2,185.4 (45.7%) 12:22:14 INFO - TinderboxPrint: CPU system
970.7 (20.3%) 12:22:14 INFO - TinderboxPrint: CPU user
1,629.4 (34.0%) 12:22:14 INFO - TinderboxPrint: Swap in / out
289,734,656 / 0 12:22:14 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 227420672; Write bytes: 222017536; Read time: 17146; Write time: 480 12:22:14 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 43920384; Write bytes: 317854720; Read time: 352; Write time: 1175 12:22:15 INFO - Running post-run listener: _upload_blobber_files 12:22:15 INFO - Blob upload gear active. 12:22:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:22:15 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 12:22:15 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'] 12:22:15 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 12:22:15 INFO - (blobuploader) - INFO - Open directory for files ... 12:22:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:22:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:22:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:22:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:22:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:22:17 INFO - (blobuploader) - INFO - Done attempting. 12:22:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:22:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:22:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:22:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:22:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:22:19 INFO - (blobuploader) - INFO - Done attempting. 12:22:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:22:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:22:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:22:20 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:22:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:22:20 INFO - (blobuploader) - INFO - Done attempting. 12:22:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:22:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:22:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:22:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:22:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:22:22 INFO - (blobuploader) - INFO - Done attempting. 12:22:22 INFO - (blobuploader) - INFO - Iteration through files over. 12:22:22 INFO - Return code: 0 12:22:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:22:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:22:22 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/38cc126570dc63ba01f30960b0c153b017fe085e295e37a3b2ea59c0c8f14d9f7934ba9412d160019d8eb3c16577261a91f165de3c9d8d4058d2b52f62070ed4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aa1659456ef4e171297bcf37e2dd8c3815567f827ff2cdd8b2143bb154fc78a9b02daa73fb0d3d5e0143760a8ae431cdd4cad7b074d1fa5db82b6287ae85cb5a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3afbbec0d8a4d15a4f6b468e45a729a2177fd5e842f7c4d00e93b0b0ef1262aa9ebb2366545d82e12bc2e82ea5966bd6abdef48631c1118e59fcfa0544f42a9a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/abca601d058a97a40d4a6d95fbfc8f461c8125456eaf0a2b27a8f13a301e2e5083f16fa85b02b52b21637f475c6962be163781eecba90dd5ca81d822ac9024fc"} 12:22:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:22:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:22:22 INFO - Contents: 12:22:22 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/38cc126570dc63ba01f30960b0c153b017fe085e295e37a3b2ea59c0c8f14d9f7934ba9412d160019d8eb3c16577261a91f165de3c9d8d4058d2b52f62070ed4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aa1659456ef4e171297bcf37e2dd8c3815567f827ff2cdd8b2143bb154fc78a9b02daa73fb0d3d5e0143760a8ae431cdd4cad7b074d1fa5db82b6287ae85cb5a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3afbbec0d8a4d15a4f6b468e45a729a2177fd5e842f7c4d00e93b0b0ef1262aa9ebb2366545d82e12bc2e82ea5966bd6abdef48631c1118e59fcfa0544f42a9a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/abca601d058a97a40d4a6d95fbfc8f461c8125456eaf0a2b27a8f13a301e2e5083f16fa85b02b52b21637f475c6962be163781eecba90dd5ca81d822ac9024fc"} 12:22:22 INFO - Running post-run listener: copy_logs_to_upload_dir 12:22:22 INFO - Copying logs to upload dir... 12:22:22 INFO - mkdir: /builds/slave/test/build/upload/logs 12:22:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.486719 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2017-05-22 12:22:22.177924) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-22 12:22:22.181513) ========= 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.T740Hc0YGc/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.zsyEpH4z6N/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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/38cc126570dc63ba01f30960b0c153b017fe085e295e37a3b2ea59c0c8f14d9f7934ba9412d160019d8eb3c16577261a91f165de3c9d8d4058d2b52f62070ed4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aa1659456ef4e171297bcf37e2dd8c3815567f827ff2cdd8b2143bb154fc78a9b02daa73fb0d3d5e0143760a8ae431cdd4cad7b074d1fa5db82b6287ae85cb5a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3afbbec0d8a4d15a4f6b468e45a729a2177fd5e842f7c4d00e93b0b0ef1262aa9ebb2366545d82e12bc2e82ea5966bd6abdef48631c1118e59fcfa0544f42a9a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/abca601d058a97a40d4a6d95fbfc8f461c8125456eaf0a2b27a8f13a301e2e5083f16fa85b02b52b21637f475c6962be163781eecba90dd5ca81d822ac9024fc"} build_url:https://queue.taskcluster.net/v1/task/E5_26LKdQLaB4QXRpKyufw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010948 build_url: 'https://queue.taskcluster.net/v1/task/E5_26LKdQLaB4QXRpKyufw/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/38cc126570dc63ba01f30960b0c153b017fe085e295e37a3b2ea59c0c8f14d9f7934ba9412d160019d8eb3c16577261a91f165de3c9d8d4058d2b52f62070ed4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aa1659456ef4e171297bcf37e2dd8c3815567f827ff2cdd8b2143bb154fc78a9b02daa73fb0d3d5e0143760a8ae431cdd4cad7b074d1fa5db82b6287ae85cb5a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3afbbec0d8a4d15a4f6b468e45a729a2177fd5e842f7c4d00e93b0b0ef1262aa9ebb2366545d82e12bc2e82ea5966bd6abdef48631c1118e59fcfa0544f42a9a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/abca601d058a97a40d4a6d95fbfc8f461c8125456eaf0a2b27a8f13a301e2e5083f16fa85b02b52b21637f475c6962be163781eecba90dd5ca81d822ac9024fc"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-22 12:22:22.213772) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 12:22:22.214133) ========= 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.T740Hc0YGc/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.zsyEpH4z6N/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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.005107 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 12:22:22.264667) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-22 12:22:22.265190) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-22 12:22:22.265760) ========= ========= Total master_lag: 0.17 =========