builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0309 starttime: 1497405960.14 results: success (0) revision: 6bea8e940b9c7cb5fdb769692cef7801616b983e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-13 19:06:00.140610) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-13 19:06:00.141089) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-13 19:06:00.141382) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-13 19:06:00.167097) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 19:06:00.167578) ========= 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.uP27uZtczl/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.ajvLj454sv/Listeners TMPDIR=/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-13 19:06:00-- 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% 18.8M=0.001s 2017-06-13 19:06:00 (18.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.599048 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 19:06:00.790774) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 19:06:00.791057) ========= 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.uP27uZtczl/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.ajvLj454sv/Listeners TMPDIR=/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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.026629 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 19:06:00.840215) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-13 19:06:00.840540) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 6bea8e940b9c7cb5fdb769692cef7801616b983e --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 6bea8e940b9c7cb5fdb769692cef7801616b983e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.uP27uZtczl/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.ajvLj454sv/Listeners TMPDIR=/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-13 19:06:00,938 truncating revision to first 12 chars 2017-06-13 19:06:00,938 Setting DEBUG logging. 2017-06-13 19:06:00,939 attempt 1/10 2017-06-13 19:06:00,939 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/6bea8e940b9c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-13 19:06:02,301 unpacking tar archive at: mozilla-central-6bea8e940b9c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.647785 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-13 19:06:02.511399) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-13 19:06:02.511678) ========= script_repo_revision: 6bea8e940b9c7cb5fdb769692cef7801616b983e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-13 19:06:02.512031) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-13 19:06:02.512288) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-13 19:06:02.531329) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 20 secs) (at 2017-06-13 19:06:02.531623) ========= /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.uP27uZtczl/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.ajvLj454sv/Listeners TMPDIR=/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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 19:06:02 INFO - MultiFileLogger online at 20170613 19:06:02 in /builds/slave/test 19:06:02 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 19:06:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:06:02 INFO - {'append_to_log': False, 19:06:02 INFO - 'base_work_dir': '/builds/slave/test', 19:06:02 INFO - 'blob_upload_branch': 'Firefox', 19:06:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:06:02 INFO - 'branch': 'Firefox', 19:06:02 INFO - 'buildbot_json_path': 'buildprops.json', 19:06:02 INFO - 'config_files': ('talos/mac_config.py',), 19:06:02 INFO - 'default_actions': ('clobber', 19:06:02 INFO - 'read-buildbot-config', 19:06:02 INFO - 'download-and-extract', 19:06:02 INFO - 'populate-webroot', 19:06:02 INFO - 'create-virtualenv', 19:06:02 INFO - 'install', 19:06:02 INFO - 'setup-mitmproxy', 19:06:02 INFO - 'run-tests'), 19:06:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:06:02 INFO - 'download_minidump_stackwalk': True, 19:06:02 INFO - 'download_symbols': 'ondemand', 19:06:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:06:02 INFO - 'tooltool.py': '/tools/tooltool.py', 19:06:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:06:02 INFO - '/tools/misc-python/virtualenv.py')}, 19:06:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:06:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:06:02 INFO - 'gecko_profile': False, 19:06:02 INFO - 'gecko_profile_interval': 0, 19:06:02 INFO - 'installer_path': 'installer.exe', 19:06:02 INFO - 'log_level': 'info', 19:06:02 INFO - 'log_name': 'talos', 19:06:02 INFO - 'log_to_console': True, 19:06:02 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:06:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:06:02 INFO - 'opt_config_files': (), 19:06:02 INFO - 'pip_index': False, 19:06:02 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:06:02 INFO - 'cmd': ('bash', 19:06:02 INFO - '-c', 19:06:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:06:02 INFO - 'enabled': True, 19:06:02 INFO - 'halt_on_failure': False, 19:06:02 INFO - 'name': 'check_screen_resolution'},), 19:06:02 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:06:02 INFO - 'cmd': ('bash', 19:06:02 INFO - '-c', 19:06:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:06:02 INFO - 'enabled': True, 19:06:02 INFO - 'halt_on_failure': False, 19:06:02 INFO - 'name': 'check_screen_resolution'},), 19:06:02 INFO - 'run_cmd_checks_enabled': True, 19:06:02 INFO - 'suite': 'dromaeojs-e10s', 19:06:02 INFO - 'system_bits': '32', 19:06:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:06:02 INFO - 'title': 't-yosemite-r7-0309', 19:06:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:06:02 INFO - 'use_talos_json': True, 19:06:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:06:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:06:02 INFO - 'work_dir': 'build'} 19:06:02 INFO - [mozharness: 2017-06-14 02:06:02.739766Z] Running clobber step. 19:06:02 INFO - Running pre-action listener: _resource_record_pre_action 19:06:02 INFO - Running main action method: clobber 19:06:02 INFO - rmtree: /builds/slave/test/build 19:06:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:06:05 INFO - Running post-action listener: _resource_record_post_action 19:06:05 INFO - [mozharness: 2017-06-14 02:06:05.088366Z] Finished clobber step (success) 19:06:05 INFO - [mozharness: 2017-06-14 02:06:05.088531Z] Running read-buildbot-config step. 19:06:05 INFO - Running pre-action listener: _resource_record_pre_action 19:06:05 INFO - Running main action method: read_buildbot_config 19:06:05 INFO - Using buildbot properties: 19:06:05 INFO - { 19:06:05 INFO - "project": "", 19:06:05 INFO - "product": "firefox", 19:06:05 INFO - "who": "kwierso@gmail.com", 19:06:05 INFO - "installer_path": "public/build/firefox-56.0a1.en-US.mac.dmg", 19:06:05 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 19:06:05 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 19:06:05 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 19:06:05 INFO - "stage_platform": "macosx64", 19:06:05 INFO - "basedir": "/builds/slave/test", 19:06:05 INFO - "buildnumber": 237, 19:06:05 INFO - "platform": "yosemite_r7", 19:06:05 INFO - "parent_task_id": "W8qfCTz3TEa8B6WiBGA0Ow", 19:06:05 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 19:06:05 INFO - "slavebuilddir": "test", 19:06:05 INFO - "taskId": "S_FU4r9RQs6YzjcDiB3v5Q", 19:06:05 INFO - "branch": "mozilla-central", 19:06:05 INFO - "script_repo_revision": "production", 19:06:05 INFO - "revision": "6bea8e940b9c7cb5fdb769692cef7801616b983e", 19:06:05 INFO - "slavename": "t-yosemite-r7-0309", 19:06:05 INFO - "repo_path": "mozilla-central" 19:06:05 INFO - } 19:06:05 INFO - Finding installer, test and symbols from parent task. 19:06:05 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/S_FU4r9RQs6YzjcDiB3v5Q'}, attempt #1 19:06:05 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/S_FU4r9RQs6YzjcDiB3v5Q'}, attempt #1 19:06:06 INFO - Task dependencies: B5NPJFeFTIeQGKaEVFSOwQ W8qfCTz3TEa8B6WiBGA0Ow 19:06:06 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/W8qfCTz3TEa8B6WiBGA0Ow'}, attempt #1 19:06:06 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/W8qfCTz3TEa8B6WiBGA0Ow/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 19:06:06 INFO - Set test_url: https://queue.taskcluster.net/v1/task/W8qfCTz3TEa8B6WiBGA0Ow/artifacts/public/build/test_packages.json 19:06:06 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/W8qfCTz3TEa8B6WiBGA0Ow/artifacts/public/build/target.crashreporter-symbols.zip 19:06:06 INFO - Running post-action listener: _resource_record_post_action 19:06:06 INFO - [mozharness: 2017-06-14 02:06:06.463963Z] Finished read-buildbot-config step (success) 19:06:06 INFO - [mozharness: 2017-06-14 02:06:06.464102Z] Running download-and-extract step. 19:06:06 INFO - Running pre-action listener: _resource_record_pre_action 19:06:06 INFO - Running main action method: download_and_extract 19:06:06 INFO - mkdir: /builds/slave/test/build/tests 19:06:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:06:06 INFO - trying https://queue.taskcluster.net/v1/task/W8qfCTz3TEa8B6WiBGA0Ow/artifacts/public/build/firefox-56.0a1.en-US.mac.test_packages.json 19:06:06 INFO - Downloading https://queue.taskcluster.net/v1/task/W8qfCTz3TEa8B6WiBGA0Ow/artifacts/public/build/firefox-56.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-56.0a1.en-US.mac.test_packages.json 19:06:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/W8qfCTz3TEa8B6WiBGA0Ow/artifacts/public/build/firefox-56.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-56.0a1.en-US.mac.test_packages.json'}, attempt #1 19:06:07 INFO - Downloaded 1328 bytes. 19:06:07 INFO - Reading from file /builds/slave/test/build/firefox-56.0a1.en-US.mac.test_packages.json 19:06:07 INFO - Using the following test package requirements: 19:06:07 INFO - {u'awsy': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 19:06:07 INFO - u'firefox-56.0a1.en-US.mac.awsy.tests.zip'], 19:06:07 INFO - u'common': [u'firefox-56.0a1.en-US.mac.common.tests.zip'], 19:06:07 INFO - u'cppunittest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 19:06:07 INFO - u'firefox-56.0a1.en-US.mac.cppunittest.tests.zip'], 19:06:07 INFO - u'gtest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 19:06:07 INFO - u'firefox-56.0a1.en-US.mac.gtest.tests.zip'], 19:06:07 INFO - u'jittest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 19:06:07 INFO - u'jsshell-mac.zip'], 19:06:07 INFO - u'mochitest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 19:06:07 INFO - u'firefox-56.0a1.en-US.mac.mochitest.tests.zip'], 19:06:07 INFO - u'mozbase': [u'firefox-56.0a1.en-US.mac.common.tests.zip'], 19:06:07 INFO - u'reftest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 19:06:07 INFO - u'firefox-56.0a1.en-US.mac.reftest.tests.zip'], 19:06:07 INFO - u'talos': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 19:06:07 INFO - u'firefox-56.0a1.en-US.mac.talos.tests.zip'], 19:06:07 INFO - u'web-platform': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 19:06:07 INFO - u'firefox-56.0a1.en-US.mac.web-platform.tests.tar.gz'], 19:06:07 INFO - u'xpcshell': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 19:06:07 INFO - u'firefox-56.0a1.en-US.mac.xpcshell.tests.zip']} 19:06:07 INFO - Downloading packages: [u'firefox-56.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:06:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/W8qfCTz3TEa8B6WiBGA0Ow/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip 19:06:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/W8qfCTz3TEa8B6WiBGA0Ow/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:06:07 INFO - Fetch https://queue.taskcluster.net/v1/task/W8qfCTz3TEa8B6WiBGA0Ow/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip into memory 19:06:09 INFO - Content-Length response header: 59538209 19:06:09 INFO - Bytes received: 59538209 19:06:27 INFO - Downloading packages: [u'firefox-56.0a1.en-US.mac.common.tests.zip', u'firefox-56.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 19:06:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/W8qfCTz3TEa8B6WiBGA0Ow/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip 19:06:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/W8qfCTz3TEa8B6WiBGA0Ow/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:06:27 INFO - Fetch https://queue.taskcluster.net/v1/task/W8qfCTz3TEa8B6WiBGA0Ow/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip into memory 19:06:29 INFO - Content-Length response header: 59538209 19:06:29 INFO - Bytes received: 59538209 19:06:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/W8qfCTz3TEa8B6WiBGA0Ow/artifacts/public/build/firefox-56.0a1.en-US.mac.talos.tests.zip 19:06:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/W8qfCTz3TEa8B6WiBGA0Ow/artifacts/public/build/firefox-56.0a1.en-US.mac.talos.tests.zip'}, attempt #1 19:06:46 INFO - Fetch https://queue.taskcluster.net/v1/task/W8qfCTz3TEa8B6WiBGA0Ow/artifacts/public/build/firefox-56.0a1.en-US.mac.talos.tests.zip into memory 19:06:47 INFO - Content-Length response header: 14450022 19:06:47 INFO - Bytes received: 14450022 19:06:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:06:47 INFO - trying https://queue.taskcluster.net/v1/task/W8qfCTz3TEa8B6WiBGA0Ow/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 19:06:47 INFO - Downloading https://queue.taskcluster.net/v1/task/W8qfCTz3TEa8B6WiBGA0Ow/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg 19:06:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/W8qfCTz3TEa8B6WiBGA0Ow/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg'}, attempt #1 19:06:49 INFO - Downloaded 69357076 bytes. 19:06:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/W8qfCTz3TEa8B6WiBGA0Ow/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 19:06:49 INFO - mkdir: /builds/slave/test/properties 19:06:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:06:49 INFO - Writing to file /builds/slave/test/properties/build_url 19:06:49 INFO - Contents: 19:06:49 INFO - build_url:https://queue.taskcluster.net/v1/task/W8qfCTz3TEa8B6WiBGA0Ow/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 19:06:49 INFO - Running post-action listener: _resource_record_post_action 19:06:49 INFO - Running post-action listener: set_extra_try_arguments 19:06:49 INFO - [mozharness: 2017-06-14 02:06:49.747547Z] Finished download-and-extract step (success) 19:06:49 INFO - [mozharness: 2017-06-14 02:06:49.747705Z] Running populate-webroot step. 19:06:49 INFO - Running pre-action listener: _resource_record_pre_action 19:06:49 INFO - Running main action method: populate_webroot 19:06:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:06:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:06:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:06:49 INFO - '--tppagecycles', 19:06:49 INFO - '7'], 19:06:49 INFO - 'tests': ['tsvgm']}}, 19:06:49 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:06:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:06:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:06:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:06:49 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 19:06:49 INFO - 'talos_options': ['--disable-e10s'], 19:06:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:06:49 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 19:06:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:06:49 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 19:06:49 INFO - 'talos_options': ['--disable-e10s'], 19:06:49 INFO - 'tests': ['damp', 'tps']}, 19:06:49 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 19:06:49 INFO - 'tests': ['damp', 'tps']}, 19:06:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:06:49 INFO - 'tests': ['dromaeo_dom']}, 19:06:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:06:49 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:06:49 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 19:06:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 19:06:49 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 19:06:49 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 19:06:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:06:49 INFO - 'tests': ['a11yr', 19:06:49 INFO - 'ts_paint', 19:06:49 INFO - 'tpaint', 19:06:49 INFO - 'sessionrestore', 19:06:49 INFO - 'sessionrestore_no_auto_restore', 19:06:49 INFO - 'tabpaint']}, 19:06:49 INFO - 'other-e10s': {'tests': ['a11yr', 19:06:49 INFO - 'ts_paint', 19:06:49 INFO - 'tpaint', 19:06:49 INFO - 'sessionrestore', 19:06:49 INFO - 'sessionrestore_no_auto_restore', 19:06:49 INFO - 'tabpaint']}, 19:06:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:06:49 INFO - 'ts_paint', 19:06:49 INFO - 'tpaint', 19:06:49 INFO - 'sessionrestore', 19:06:49 INFO - 'sessionrestore_no_auto_restore', 19:06:49 INFO - 'tabpaint']}, 19:06:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:06:49 INFO - 'ts_paint', 19:06:49 INFO - 'tpaint', 19:06:49 INFO - 'sessionrestore', 19:06:49 INFO - 'sessionrestore_no_auto_restore', 19:06:49 INFO - 'tabpaint']}, 19:06:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:06:49 INFO - 'tests': ['a11yr', 19:06:49 INFO - 'ts_paint', 19:06:49 INFO - 'tpaint', 19:06:49 INFO - 'sessionrestore', 19:06:49 INFO - 'sessionrestore_no_auto_restore', 19:06:49 INFO - 'tabpaint']}, 19:06:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:06:49 INFO - 'tests': ['a11yr', 19:06:49 INFO - 'ts_paint', 19:06:49 INFO - 'tpaint', 19:06:49 INFO - 'sessionrestore', 19:06:49 INFO - 'sessionrestore_no_auto_restore', 19:06:49 INFO - 'tabpaint']}, 19:06:49 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 19:06:49 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 19:06:49 INFO - 'bloom_basic_ref']}, 19:06:49 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 19:06:49 INFO - 'talos_options': ['--mitmproxy', 19:06:49 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 19:06:49 INFO - '--firstNonBlankPaint'], 19:06:49 INFO - 'tests': ['quantum_pageload_google', 19:06:49 INFO - 'quantum_pageload_youtube', 19:06:49 INFO - 'quantum_pageload_amazon', 19:06:49 INFO - 'quantum_pageload_facebook']}, 19:06:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:06:49 INFO - 'tests': ['tsvgx', 19:06:49 INFO - 'tsvgr_opacity', 19:06:49 INFO - 'tart', 19:06:49 INFO - 'tscrollx', 19:06:49 INFO - 'cart', 19:06:49 INFO - 'tsvg_static']}, 19:06:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:06:49 INFO - 'tsvgr_opacity', 19:06:49 INFO - 'tart', 19:06:49 INFO - 'tscrollx', 19:06:49 INFO - 'cart', 19:06:49 INFO - 'tsvg_static']}, 19:06:49 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 19:06:49 INFO - 'talos_options': ['--disable-e10s'], 19:06:49 INFO - 'tests': ['tp5o']}, 19:06:49 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 19:06:49 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 19:06:49 INFO - 'talos_options': ['--disable-e10s', 19:06:49 INFO - '--xperf_path', 19:06:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:06:49 INFO - 'tests': ['tp5n']}, 19:06:49 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 19:06:49 INFO - 'talos_options': ['--xperf_path', 19:06:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:06:49 INFO - 'tests': ['tp5n']}}, 19:06:49 INFO - 'talos.zip': {'path': '', 19:06:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:06:49 INFO - Running post-action listener: _resource_record_post_action 19:06:49 INFO - [mozharness: 2017-06-14 02:06:49.770296Z] Finished populate-webroot step (success) 19:06:49 INFO - [mozharness: 2017-06-14 02:06:49.770441Z] Running create-virtualenv step. 19:06:49 INFO - Running pre-action listener: _resource_record_pre_action 19:06:49 INFO - Running main action method: create_virtualenv 19:06:49 INFO - Creating virtualenv /builds/slave/test/build/venv 19:06:49 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 19:06:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:06:49 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 19:06:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:06:49 INFO - Using real prefix '/tools/python27' 19:06:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:06:50 INFO - Installing distribute.............................................................................................................................................................................................done. 19:06:53 INFO - Installing pip.................done. 19:06:53 INFO - Return code: 0 19:06:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:06:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:06:53 INFO - Reading from file tmpfile_stdout 19:06:53 INFO - Output received: 19:06:53 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) 19:06:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:06:53 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')]} 19:06:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:06:53 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')]} 19:06:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:06:53 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 0x10e88cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e890768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feaaad63710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5e2d30>, '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 0x10e5accd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5d3be8>, '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.uP27uZtczl/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.ajvLj454sv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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 19:06:53 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 19:06:53 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 19:06:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uP27uZtczl/Render', 19:06:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:06:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:06:53 INFO - 'HOME': '/Users/cltbld', 19:06:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:06:53 INFO - 'LOGNAME': 'cltbld', 19:06:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:06:53 INFO - 'MOZ_NO_REMOTE': '1', 19:06:53 INFO - 'NO_EM_RESTART': '1', 19:06:53 INFO - 'PAGER': '/bin/cat', 19:06:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:06:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:06:53 INFO - 'PWD': '/builds/slave/test', 19:06:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:06:53 INFO - 'SHELL': '/bin/bash', 19:06:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ajvLj454sv/Listeners', 19:06:53 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 19:06:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:06:53 INFO - 'USER': 'cltbld', 19:06:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:06:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:06:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:06:53 INFO - 'XPC_FLAGS': '0x0', 19:06:53 INFO - 'XPC_SERVICE_NAME': '0', 19:06:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:06:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:06:54 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 19:06:54 INFO - Cleaning up... 19:06:54 INFO - Return code: 0 19:06:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:06:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:06:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:06:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:06:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:06:54 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 0x10e88cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e890768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feaaad63710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5e2d30>, '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 0x10e5accd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5d3be8>, '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.uP27uZtczl/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.ajvLj454sv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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 19:06:54 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 19:06:54 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 19:06:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uP27uZtczl/Render', 19:06:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:06:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:06:54 INFO - 'HOME': '/Users/cltbld', 19:06:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:06:54 INFO - 'LOGNAME': 'cltbld', 19:06:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:06:54 INFO - 'MOZ_NO_REMOTE': '1', 19:06:54 INFO - 'NO_EM_RESTART': '1', 19:06:54 INFO - 'PAGER': '/bin/cat', 19:06:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:06:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:06:54 INFO - 'PWD': '/builds/slave/test', 19:06:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:06:54 INFO - 'SHELL': '/bin/bash', 19:06:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ajvLj454sv/Listeners', 19:06:54 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 19:06:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:06:54 INFO - 'USER': 'cltbld', 19:06:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:06:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:06:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:06:54 INFO - 'XPC_FLAGS': '0x0', 19:06:54 INFO - 'XPC_SERVICE_NAME': '0', 19:06:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:06:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:06:54 INFO - Downloading/unpacking psutil>=3.1.1 19:06:54 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 19:06:54 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 19:06:54 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 19:06:54 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 19:06:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:06:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:06:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:06:56 INFO - Installing collected packages: psutil 19:06:56 INFO - Running setup.py install for psutil 19:06:56 INFO - building 'psutil._psutil_osx' extension 19:06:56 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 19:06:57 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 19:06:57 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 19:06:57 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 19:06:57 INFO - building 'psutil._psutil_posix' extension 19:06:57 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 19:06:57 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:06:57 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:06:57 INFO - ^ 19:06:57 INFO - 1 warning generated. 19:06:57 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 19:06:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:06:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:06:57 INFO - Successfully installed psutil 19:06:57 INFO - Cleaning up... 19:06:57 INFO - Return code: 0 19:06:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:06:57 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')]} 19:06:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:06:57 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')]} 19:06:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:06:57 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 0x10e88cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e890768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feaaad63710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5e2d30>, '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 0x10e5accd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5d3be8>, '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.uP27uZtczl/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.ajvLj454sv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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 19:06:57 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 19:06:57 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 19:06:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uP27uZtczl/Render', 19:06:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:06:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:06:57 INFO - 'HOME': '/Users/cltbld', 19:06:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:06:57 INFO - 'LOGNAME': 'cltbld', 19:06:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:06:57 INFO - 'MOZ_NO_REMOTE': '1', 19:06:57 INFO - 'NO_EM_RESTART': '1', 19:06:57 INFO - 'PAGER': '/bin/cat', 19:06:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:06:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:06:57 INFO - 'PWD': '/builds/slave/test', 19:06:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:06:57 INFO - 'SHELL': '/bin/bash', 19:06:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ajvLj454sv/Listeners', 19:06:57 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 19:06:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:06:57 INFO - 'USER': 'cltbld', 19:06:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:06:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:06:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:06:57 INFO - 'XPC_FLAGS': '0x0', 19:06:57 INFO - 'XPC_SERVICE_NAME': '0', 19:06:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:06:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:06:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:06:58 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 19:06:58 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 19:06:58 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 19:06:58 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 19:06:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:06:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:06:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:06:59 INFO - Installing collected packages: mozsystemmonitor 19:06:59 INFO - Running setup.py install for mozsystemmonitor 19:06:59 INFO - Successfully installed mozsystemmonitor 19:06:59 INFO - Cleaning up... 19:06:59 INFO - Return code: 0 19:06:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:06: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')]} 19:06:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:06: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')]} 19:06:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:06:59 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 0x10e88cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e890768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feaaad63710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5e2d30>, '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 0x10e5accd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5d3be8>, '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.uP27uZtczl/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.ajvLj454sv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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 19:06:59 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 19:06:59 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 19:06:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uP27uZtczl/Render', 19:06:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:06:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:06:59 INFO - 'HOME': '/Users/cltbld', 19:06:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:06:59 INFO - 'LOGNAME': 'cltbld', 19:06:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:06:59 INFO - 'MOZ_NO_REMOTE': '1', 19:06:59 INFO - 'NO_EM_RESTART': '1', 19:06:59 INFO - 'PAGER': '/bin/cat', 19:06:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:06:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:06:59 INFO - 'PWD': '/builds/slave/test', 19:06:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:06:59 INFO - 'SHELL': '/bin/bash', 19:06:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ajvLj454sv/Listeners', 19:06:59 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 19:06:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:06:59 INFO - 'USER': 'cltbld', 19:06:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:06:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:06:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:06:59 INFO - 'XPC_FLAGS': '0x0', 19:06:59 INFO - 'XPC_SERVICE_NAME': '0', 19:06:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:07:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:00 INFO - Downloading/unpacking jsonschema==2.5.1 19:07: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 19:07: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 19:07: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 19:07: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 19:07:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:07:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:07:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:01 INFO - Downloading functools32-3.2.3-2.tar.gz 19:07:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:07:01 INFO - warning: no files found matching '*.txt' 19:07:01 INFO - no previously-included directories found matching 'build' 19:07:01 INFO - no previously-included directories found matching 'dist' 19:07:01 INFO - no previously-included directories found matching '.git*' 19:07:01 INFO - Installing collected packages: jsonschema, functools32 19:07:01 INFO - Running setup.py install for functools32 19:07:01 INFO - warning: no files found matching '*.txt' 19:07:01 INFO - no previously-included directories found matching 'build' 19:07:01 INFO - no previously-included directories found matching 'dist' 19:07:01 INFO - no previously-included directories found matching '.git*' 19:07:01 INFO - Successfully installed jsonschema functools32 19:07:01 INFO - Cleaning up... 19:07:02 INFO - Return code: 0 19:07:02 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:07: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')]} 19:07:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:07: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')]} 19:07:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:07: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', '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 0x10e88cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e890768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feaaad63710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5e2d30>, '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 0x10e5accd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5d3be8>, '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.uP27uZtczl/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.ajvLj454sv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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 19:07: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:07: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 functools32==3.2.3-2 19:07:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uP27uZtczl/Render', 19:07:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:07:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:07:02 INFO - 'HOME': '/Users/cltbld', 19:07:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:07:02 INFO - 'LOGNAME': 'cltbld', 19:07:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:02 INFO - 'MOZ_NO_REMOTE': '1', 19:07:02 INFO - 'NO_EM_RESTART': '1', 19:07:02 INFO - 'PAGER': '/bin/cat', 19:07:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:07:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:02 INFO - 'PWD': '/builds/slave/test', 19:07:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:07:02 INFO - 'SHELL': '/bin/bash', 19:07:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ajvLj454sv/Listeners', 19:07:02 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 19:07:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:07:02 INFO - 'USER': 'cltbld', 19:07:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:07:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:07:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:02 INFO - 'XPC_FLAGS': '0x0', 19:07:02 INFO - 'XPC_SERVICE_NAME': '0', 19:07:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:07:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:02 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:07:02 INFO - Cleaning up... 19:07:02 INFO - Return code: 0 19:07:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:07: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')]} 19:07:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:07: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')]} 19:07:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:07: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', '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 0x10e88cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e890768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feaaad63710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5e2d30>, '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 0x10e5accd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5d3be8>, '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.uP27uZtczl/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.ajvLj454sv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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 19:07: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:07: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 blobuploader==1.2.4 19:07:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uP27uZtczl/Render', 19:07:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:07:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:07:02 INFO - 'HOME': '/Users/cltbld', 19:07:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:07:02 INFO - 'LOGNAME': 'cltbld', 19:07:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:02 INFO - 'MOZ_NO_REMOTE': '1', 19:07:02 INFO - 'NO_EM_RESTART': '1', 19:07:02 INFO - 'PAGER': '/bin/cat', 19:07:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:07:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:02 INFO - 'PWD': '/builds/slave/test', 19:07:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:07:02 INFO - 'SHELL': '/bin/bash', 19:07:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ajvLj454sv/Listeners', 19:07:02 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 19:07:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:07:02 INFO - 'USER': 'cltbld', 19:07:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:07:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:07:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:02 INFO - 'XPC_FLAGS': '0x0', 19:07:02 INFO - 'XPC_SERVICE_NAME': '0', 19:07:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:07:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:02 INFO - Downloading/unpacking blobuploader==1.2.4 19:07:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:03 INFO - Downloading blobuploader-1.2.4.tar.gz 19:07:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:07:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:07: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 19:07: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 19:07: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 19:07: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 19:07:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:07:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:07: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 19:07: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 19:07: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 19:07: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 19:07:04 INFO - Downloading docopt-0.6.1.tar.gz 19:07:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:07:04 INFO - Installing collected packages: blobuploader, requests, docopt 19:07:04 INFO - Running setup.py install for blobuploader 19:07:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:07:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:07:05 INFO - Running setup.py install for requests 19:07:05 INFO - Running setup.py install for docopt 19:07:05 INFO - Successfully installed blobuploader requests docopt 19:07:05 INFO - Cleaning up... 19:07:05 INFO - Return code: 0 19:07:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:07: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')]} 19:07:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:07: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')]} 19:07:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:07:05 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 0x10e88cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e890768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feaaad63710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5e2d30>, '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 0x10e5accd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5d3be8>, '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.uP27uZtczl/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.ajvLj454sv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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 19:07:05 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 19:07:05 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 19:07:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uP27uZtczl/Render', 19:07:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:07:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:07:05 INFO - 'HOME': '/Users/cltbld', 19:07:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:07:05 INFO - 'LOGNAME': 'cltbld', 19:07:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:05 INFO - 'MOZ_NO_REMOTE': '1', 19:07:05 INFO - 'NO_EM_RESTART': '1', 19:07:05 INFO - 'PAGER': '/bin/cat', 19:07:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:07:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:05 INFO - 'PWD': '/builds/slave/test', 19:07:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:07:05 INFO - 'SHELL': '/bin/bash', 19:07:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ajvLj454sv/Listeners', 19:07:05 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 19:07:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:07:05 INFO - 'USER': 'cltbld', 19:07:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:07:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:07:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:05 INFO - 'XPC_FLAGS': '0x0', 19:07:05 INFO - 'XPC_SERVICE_NAME': '0', 19:07:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:07:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:07:06 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-Dg9iZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:07:06 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-II6kTL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:07:06 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-hlY4b5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:07:06 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-Iwaw2h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:07:06 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-nEnKNO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:07:06 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-HojiZY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:07:06 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-kWwhW4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:07:06 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-DXOYyR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:07:06 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-A2dqnF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:07:06 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-OJiEBb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:07:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:07:07 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-OgtFrG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:07:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:07:07 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-XvOXzk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:07:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:07:07 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-f3XlC8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:07:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:07:07 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-Lyjh9a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:07:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:07:07 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-nn6I_f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:07:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:07:07 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-ZH2CPT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:07:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:07:07 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-KEcE9E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:07:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:07:07 INFO - Running setup.py install for manifestparser 19:07:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:07:07 INFO - Running setup.py install for mozcrash 19:07:08 INFO - Running setup.py install for mozdebug 19:07:08 INFO - Running setup.py install for mozdevice 19:07:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:07:08 INFO - Running setup.py install for mozfile 19:07:08 INFO - Running setup.py install for mozhttpd 19:07:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:07:08 INFO - Running setup.py install for mozinfo 19:07:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:07:08 INFO - Running setup.py install for mozInstall 19:07:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:07:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:07:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:07:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:07:08 INFO - Running setup.py install for mozleak 19:07:09 INFO - Running setup.py install for mozlog 19:07:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:07:09 INFO - Running setup.py install for moznetwork 19:07:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:07:09 INFO - Running setup.py install for mozprocess 19:07:09 INFO - Running setup.py install for mozprofile 19:07:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:07:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:07:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:07:09 INFO - Running setup.py install for mozrunner 19:07:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:07:09 INFO - Running setup.py install for mozscreenshot 19:07:10 INFO - Running setup.py install for moztest 19:07:10 INFO - Running setup.py install for mozversion 19:07:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:07:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:07:10 INFO - Cleaning up... 19:07:10 INFO - Return code: 0 19:07:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:07:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:07:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:07:10 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 0x10e88cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e890768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feaaad63710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5e2d30>, '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 0x10e5accd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5d3be8>, '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.uP27uZtczl/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.ajvLj454sv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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 19:07:10 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 19:07:10 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 19:07:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uP27uZtczl/Render', 19:07:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:07:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:07:10 INFO - 'HOME': '/Users/cltbld', 19:07:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:07:10 INFO - 'LOGNAME': 'cltbld', 19:07:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:10 INFO - 'MOZ_NO_REMOTE': '1', 19:07:10 INFO - 'NO_EM_RESTART': '1', 19:07:10 INFO - 'PAGER': '/bin/cat', 19:07:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:07:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:10 INFO - 'PWD': '/builds/slave/test', 19:07:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:07:10 INFO - 'SHELL': '/bin/bash', 19:07:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ajvLj454sv/Listeners', 19:07:10 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 19:07:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:07:10 INFO - 'USER': 'cltbld', 19:07:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:07:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:07:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:10 INFO - 'XPC_FLAGS': '0x0', 19:07:10 INFO - 'XPC_SERVICE_NAME': '0', 19:07:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:07:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:07:10 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-9PhQs7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:07:10 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)) 19:07:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:07:10 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-9p3ibT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:07:10 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)) 19:07:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:07:10 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-NhCa0I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:07:10 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)) 19:07:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:07:10 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-mkmE03-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:07:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:07:11 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-8VntX3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:07:11 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)) 19:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:07:11 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-uVH6_j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:07:11 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)) 19:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:07:11 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-6tSBsg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:07:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 19:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:07:11 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-yUfVSD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:07:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 19:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:07:11 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-nmzpDv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:07:11 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)) 19:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:07:11 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-M_YYO1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:07:11 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)) 19:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:07:11 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-UGyP3G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:07:11 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)) 19:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:07:11 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-emlzCg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:07:11 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)) 19:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:07:11 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-CUf9xc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:07:12 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)) 19:07:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:07:12 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-t6dRQq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:07:12 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)) 19:07:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:07:12 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-FoixVR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:07:12 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)) 19:07:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:07:12 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-psjdbo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:07:12 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)) 19:07:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:07:12 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-KTZuE2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:07:12 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)) 19:07:12 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)) 19:07:12 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)) 19:07:12 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)) 19:07:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:07:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:07:12 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 19:07:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:07:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:13 INFO - Downloading blessings-1.6.tar.gz 19:07:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:07:13 INFO - Installing collected packages: blessings 19:07:13 INFO - Running setup.py install for blessings 19:07:14 INFO - Successfully installed blessings 19:07:14 INFO - Cleaning up... 19:07:14 INFO - Return code: 0 19:07:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:07:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:07:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:07:14 INFO - Reading from file tmpfile_stdout 19:07:14 INFO - Current package versions: 19:07:14 INFO - blessings == 1.6 19:07:14 INFO - blobuploader == 1.2.4 19:07:14 INFO - docopt == 0.6.1 19:07:14 INFO - functools32 == 3.2.3-2 19:07:14 INFO - jsonschema == 2.5.1 19:07:14 INFO - manifestparser == 1.1 19:07:14 INFO - mozInstall == 1.13 19:07:14 INFO - mozcrash == 1.0 19:07:14 INFO - mozdebug == 0.1 19:07:14 INFO - mozdevice == 0.51 19:07:14 INFO - mozfile == 1.2 19:07:14 INFO - mozhttpd == 0.7 19:07:14 INFO - mozinfo == 0.10 19:07:14 INFO - mozleak == 0.1 19:07:14 INFO - mozlog == 3.4 19:07:14 INFO - moznetwork == 0.27 19:07:14 INFO - mozprocess == 0.25 19:07:14 INFO - mozprofile == 0.28 19:07:14 INFO - mozrunner == 6.13 19:07:14 INFO - mozscreenshot == 0.1 19:07:14 INFO - mozsystemmonitor == 0.3 19:07:14 INFO - moztest == 0.8 19:07:14 INFO - mozversion == 1.4 19:07:14 INFO - psutil == 3.1.1 19:07:14 INFO - requests == 1.2.3 19:07:14 INFO - wsgiref == 0.1.2 19:07:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:07:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:07:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:07:14 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 0x10e88cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e890768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feaaad63710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5e2d30>, '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 0x10e5accd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5d3be8>, '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.uP27uZtczl/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.ajvLj454sv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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 19:07:14 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 19:07:14 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 19:07:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uP27uZtczl/Render', 19:07:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:07:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:07:14 INFO - 'HOME': '/Users/cltbld', 19:07:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:07:14 INFO - 'LOGNAME': 'cltbld', 19:07:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:14 INFO - 'MOZ_NO_REMOTE': '1', 19:07:14 INFO - 'NO_EM_RESTART': '1', 19:07:14 INFO - 'PAGER': '/bin/cat', 19:07:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:07:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:14 INFO - 'PWD': '/builds/slave/test', 19:07:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:07:14 INFO - 'SHELL': '/bin/bash', 19:07:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ajvLj454sv/Listeners', 19:07:14 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 19:07:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:07:14 INFO - 'USER': 'cltbld', 19:07:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:07:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:07:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:14 INFO - 'XPC_FLAGS': '0x0', 19:07:14 INFO - 'XPC_SERVICE_NAME': '0', 19:07:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:07:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:14 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)) 19:07:14 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)) 19:07:14 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)) 19:07:14 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)) 19:07:14 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)) 19:07:14 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)) 19:07:14 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)) 19:07:14 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)) 19:07:14 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)) 19:07:14 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 19:07:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:16 INFO - Downloading marionette_driver-1.4.0.tar.gz 19:07:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 19:07:16 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)) 19:07:16 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)) 19:07:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 19:07:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 19:07:16 INFO - Installing collected packages: marionette-driver 19:07:16 INFO - Running setup.py install for marionette-driver 19:07:16 INFO - Successfully installed marionette-driver 19:07:16 INFO - Cleaning up... 19:07:16 INFO - Return code: 0 19:07:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:07:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:07:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:07:16 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 0x10e88cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e890768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feaaad63710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5e2d30>, '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 0x10e5accd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5d3be8>, '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.uP27uZtczl/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.ajvLj454sv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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 19:07:16 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 19:07:16 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 19:07:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uP27uZtczl/Render', 19:07:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:07:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:07:16 INFO - 'HOME': '/Users/cltbld', 19:07:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:07:16 INFO - 'LOGNAME': 'cltbld', 19:07:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:16 INFO - 'MOZ_NO_REMOTE': '1', 19:07:16 INFO - 'NO_EM_RESTART': '1', 19:07:16 INFO - 'PAGER': '/bin/cat', 19:07:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:07:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:16 INFO - 'PWD': '/builds/slave/test', 19:07:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:07:16 INFO - 'SHELL': '/bin/bash', 19:07:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ajvLj454sv/Listeners', 19:07:16 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 19:07:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:07:16 INFO - 'USER': 'cltbld', 19:07:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:07:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:07:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:16 INFO - 'XPC_FLAGS': '0x0', 19:07:16 INFO - 'XPC_SERVICE_NAME': '0', 19:07:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:07:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:07:16 INFO - Cleaning up... 19:07:16 INFO - Return code: 0 19:07:16 INFO - Running post-action listener: _resource_record_post_action 19:07:16 INFO - Running post-action listener: _start_resource_monitoring 19:07:16 INFO - Starting resource monitoring. 19:07:16 INFO - [mozharness: 2017-06-14 02:07:16.744439Z] Finished create-virtualenv step (success) 19:07:16 INFO - [mozharness: 2017-06-14 02:07:16.745453Z] Running install step. 19:07:16 INFO - Running pre-action listener: _resource_record_pre_action 19:07:16 INFO - Running main action method: install 19:07:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:07:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:07:17 INFO - Reading from file tmpfile_stdout 19:07:17 INFO - Detecting whether we're running mozinstall >=1.0... 19:07:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:07:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:07:17 INFO - Reading from file tmpfile_stdout 19:07:17 INFO - Output received: 19:07:17 INFO - Usage: mozinstall [options] installer 19:07:17 INFO - Options: 19:07:17 INFO - -h, --help show this help message and exit 19:07:17 INFO - -d DEST, --destination=DEST 19:07:17 INFO - Directory to install application into. [default: 19:07:17 INFO - "/builds/slave/test"] 19:07:17 INFO - --app=APP Application being installed. [default: firefox] 19:07:17 INFO - mkdir: /builds/slave/test/build/application 19:07:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:07:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 19:07:39 INFO - Reading from file tmpfile_stdout 19:07:39 INFO - Output received: 19:07:39 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:07:39 INFO - Running post-action listener: _resource_record_post_action 19:07:39 INFO - [mozharness: 2017-06-14 02:07:39.802305Z] Finished install step (success) 19:07:39 INFO - [mozharness: 2017-06-14 02:07:39.802449Z] Running setup-mitmproxy step. 19:07:39 INFO - Running pre-action listener: _resource_record_pre_action 19:07:39 INFO - Running main action method: setup_mitmproxy 19:07:39 INFO - Skipping: mitmproxy is not required 19:07:39 INFO - Running post-action listener: _resource_record_post_action 19:07:39 INFO - [mozharness: 2017-06-14 02:07:39.803152Z] Finished setup-mitmproxy step (success) 19:07:39 INFO - [mozharness: 2017-06-14 02:07:39.803282Z] Running run-tests step. 19:07:39 INFO - Running pre-action listener: _resource_record_pre_action 19:07:39 INFO - Running main action method: run_tests 19:07:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:07:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:07:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:07:39 INFO - 2017-06-13 19:07:39.817 screenresolution[1642:10895] starting screenresolution argv=screenresolution get 19:07:39 INFO - 2017-06-13 19:07:39.820 screenresolution[1642:10895] Display 0: 1600x1200x32@60 19:07:39 INFO - 2017-06-13 19:07:39.826 screenresolution[1643:10898] starting screenresolution argv=screenresolution list 19:07:39 INFO - Available Modes on Display 0 19:07:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:07:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:07:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:07:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:07:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:07:40 INFO - Intel Iris: 19:07:40 INFO - Chipset Model: Intel Iris 19:07:40 INFO - Type: GPU 19:07:40 INFO - Bus: Built-In 19:07:40 INFO - VRAM (Dynamic, Max): 1536 MB 19:07:40 INFO - Vendor: Intel (0x8086) 19:07:40 INFO - Device ID: 0x0a2e 19:07:40 INFO - Revision ID: 0x0009 19:07:40 INFO - Displays: 19:07:40 INFO - Display: 19:07:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:07:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:07:40 INFO - Display Serial Number: 1600x1200 60 19:07:40 INFO - Main Display: Yes 19:07:40 INFO - Mirror: Off 19:07:40 INFO - Online: Yes 19:07:40 INFO - Rotation: Supported 19:07:40 INFO - Return code: 0 19:07:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:07:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:07:40 INFO - Python 2.7.3 19:07:40 INFO - Return code: 0 19:07:40 INFO - grabbing minidump binary from tooltool 19:07: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')]} 19:07: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 0x10e5e2d30>, '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 0x10e5accd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5d3be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:07: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 19:07: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 19:07: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 19:07:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:07:40 INFO - Return code: 0 19:07:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:07:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:07:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:07:40 INFO - ENV: RUST_BACKTRACE is now 1 19:07:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:07:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:07: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/W8qfCTz3TEa8B6WiBGA0Ow/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0309', '--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 19:07: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/W8qfCTz3TEa8B6WiBGA0Ow/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0309 --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 19:07:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uP27uZtczl/Render', 19:07:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:07:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:07:40 INFO - 'HOME': '/Users/cltbld', 19:07:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:07:40 INFO - 'LOGNAME': 'cltbld', 19:07:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:07:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:07:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:40 INFO - 'MOZ_NO_REMOTE': '1', 19:07:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:07:40 INFO - 'NO_EM_RESTART': '1', 19:07:40 INFO - 'PAGER': '/bin/cat', 19:07:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:07:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:40 INFO - 'PWD': '/builds/slave/test', 19:07:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:07:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:07:40 INFO - 'RUST_BACKTRACE': '1', 19:07:40 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 19:07:40 INFO - 'SHELL': '/bin/bash', 19:07:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ajvLj454sv/Listeners', 19:07:40 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 19:07:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:07:40 INFO - 'USER': 'cltbld', 19:07:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:07:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:07:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:40 INFO - 'XPC_FLAGS': '0x0', 19:07:40 INFO - 'XPC_SERVICE_NAME': '0', 19:07:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:07: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/W8qfCTz3TEa8B6WiBGA0Ow/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0309', '--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 19:07:40 INFO - mozversion application_buildid: 20170613181315 19:07:40 INFO - mozversion application_changeset: 6bea8e940b9c7cb5fdb769692cef7801616b983e 19:07:40 INFO - mozversion application_display_name: Nightly 19:07:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:07:40 INFO - mozversion application_name: Firefox 19:07:40 INFO - mozversion application_remotingname: firefox 19:07:40 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:07:40 INFO - mozversion application_vendor: Mozilla 19:07:40 INFO - mozversion application_version: 56.0a1 19:07:40 INFO - mozversion platform_buildid: 20170613181315 19:07:40 INFO - mozversion platform_changeset: 6bea8e940b9c7cb5fdb769692cef7801616b983e 19:07:40 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:07:40 INFO - mozversion platform_version: 56.0a1 19:07:40 INFO - using testdate: 1497406060 19:07:40 INFO - actual date: 1497406060 19:07:40 INFO - starting webserver on 'localhost:49231' 19:07:40 INFO - SUITE-START | Running 2 tests 19:07:40 INFO - TEST-START | dromaeo_css 19:07:40 INFO - operating with platform_type : mac_ 19:07:40 INFO - Initialising browser for dromaeo_css test... 19:07:40 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49231/getInfo.html -foreground -profile /var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/tmpUMPEDe/profile 19:07:40 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49231/getInfo.html -foreground) 19:08:14 INFO - TEST-INFO | 1653: killed by SIGTERM 19:08:14 INFO - Browser initialized. 19:08:14 INFO - Running cycle 1/1 for dromaeo_css test... 19:08:14 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/tmpUMPEDe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:08:16 INFO - PID 1662 | 2017-06-13 19:08:16.400 plugin-container[1663:11583] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 19:08:16 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:08:16 INFO - PID 1662 | 2017-06-13 19:08:16.401 plugin-container[1663:11583] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f0b, name = 'com.apple.CFPasteboardClient' 19:08:16 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:08:16 INFO - PID 1662 | 2017-06-13 19:08:16.401 plugin-container[1663:11583] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:08:17 INFO - PID 1662 | 2017-06-13 19:08:17.160 plugin-container[1664:11634] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 19:08:17 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:08:17 INFO - PID 1662 | 2017-06-13 19:08:17.161 plugin-container[1664:11634] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 19:08:17 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:08:17 INFO - PID 1662 | 2017-06-13 19:08:17.161 plugin-container[1664:11634] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:08:26 INFO - PID 1662 | RSS: Main: 253923328 19:08:26 INFO - PID 1662 | 19:11:28 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49231/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49231/tests/dromaeo/cssquery-ext.html) 19:11:31 INFO - PID 1662 | RSS: Main: 264806400 19:11:31 INFO - PID 1662 | 19:14:32 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49231/tests/dromaeo/cssquery-ext.html (next: http://localhost:49231/tests/dromaeo/cssquery-jquery.html) 19:14:33 INFO - PID 1662 | RSS: Main: 275038208 19:14:33 INFO - PID 1662 | 19:17:35 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49231/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49231/tests/dromaeo/cssquery-mootools.html) 19:17:35 INFO - PID 1662 | RSS: Main: 280379392 19:17:35 INFO - PID 1662 | 19:20:37 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49231/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49231/tests/dromaeo/cssquery-prototype.html) 19:20:38 INFO - PID 1662 | RSS: Main: 280580096 19:20:38 INFO - PID 1662 | 19:23:41 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49231/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49231/tests/dromaeo/cssquery-yui.html) 19:23:42 INFO - PID 1662 | RSS: Main: 282120192 19:23:42 INFO - PID 1662 | 19:26:43 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49231/tests/dromaeo/cssquery-yui.html (next: http://localhost:49231/tests/dromaeo/cssquery-dojo.html) 19:26:44 INFO - PID 1662 | RSS: Main: 280223744 19:26:44 INFO - PID 1662 | 19:26:44 INFO - PID 1662 | __start_tp_report 19:26:44 INFO - PID 1662 | _x_x_mozilla_page_load 19:26:44 INFO - PID 1662 | _x_x_mozilla_page_load_details 19:26:44 INFO - PID 1662 | |i|pagename|runs| 19:26:44 INFO - PID 1662 | |0;dojo.html;3040.0575821495345;3047.436224298505;3274.6234183068946;3452.413089774738;3576.3383774001813;5386.8383948481605;6038.818835434939;6125.509959203264;6160.168377269068;6169.136320915076;6100.982116608275;6140.5655994444905;6168.074788781674;6175.567710260284;6230.127782110508;5380.085385484457;5394.514493695567;5417.918731219015;5421.593380496459;5503.091989821694;5481.12302031675;5494.725263736817;5504.697241651707;5514.531264842493;5524.668519888799;5411.053065713484;5412.891742165154;5415.891682166334;5444.210589464506;5508.531774799127;4386.956130438687;5197.974010129925;5227.18978558324;5276.234945932857;5312.548433383167;5218.556422704075;5286.629935904488;5310.389305229909;5403.081476149025;5415.079436495766;4886.804527818883;4948.529889660476;5556.777728890839;6168;6234.407731265523;5453.454654534555;5485.61600687952;5749.482546570787;6553.115329430513;6647.368499992542;6471.449926756231;6732.091167692348;6769.018492318586;6791.5925044497;6802.01370801231;5239.266502689627;6190.288116866528;6225.284092329395;6242.063690446469;6260.937390626035;6788.864222715518;6795.660216989131;6906.689198986058;6908.9309106909295;6928.653567321715;5998.1602575639445;6071.483923866433;6306.369363063657;6524.217093948757;6546.607203567801;6556.63938483388;6575.079488871562;6591;6600.273969863311;6615.63614001225;6595.175603049619;6635.336466353326;6646.900296495556;6652.567583107179;6654.933450665529;6609.173853268341;6639.336066393419;6676.365745254193;6684.532082754161;6690.096836926952;5215.322008138917;5221.164613661872;5257.185136303879;5284.5772338212855;5301.310829592128;4693.319468677023;4755.429348478205;4789.353437285991;4818.903621927542;4840.298156767249;6081.17904082952;6229.283632382334;6241.531885108635;6242.438180563771;6259.123722678829;6673.4661227101715;6803.455723542104;6817.420519255918;6830.624315662587;6838.555493892881;6255.249370075529;6263.655498947602;6272.027835685476;6280.403361680633;6305.054241863665;5756;5779.046457334577;5792;5956.225690660273;5988.041913293852;6797.898031529531;6804.489663275274;6805.251422343637;6807.965960170267;6878.140232470942;6740.2248741393405;6742.595444273359;6763.830904227434;6769.1876974763345;6808.693608787716;5684.261046064151;6898.620575868375;6908.620025898624;6913.861722765617;6938.132733408324;6510.73957041713;6627.237867645283;6634.303398143318;6636.7013484392355;6664.533482656361;6553.4101930826455;6739.932600674127;6755.256921738703;6763.797086087426;6802.863942721019;5034.723090230073;6377.075324078076;6391.8721625566295;6431.935680643321;6478.935210647834;6484.935150648433;6507.186601674791;6510.3489651034515;6534.150560427114;6542.247641521096;6297.433231009231;6369.617822930639;6393.808185754435;6408.551401401857;6438.967805160944;6313.116163737172;6400.103985442135;6401.903971440339;6408.166938297992;6561.179852518435;5283.815066472655;6222.657753823583;6222.906656400067;6226.1905952225925;6256.217972753406 19:26:44 INFO - PID 1662 | |1;ext.html;717.5802155738893;810.3355248696068;823.6170180865904;827.3381294964028;857.8412993596189;38090.80954595227;38861.22277554447;38946.41580376297;38948.61051389489;39332.80333598331;33214.33571328572;38509.422358664575;38509.61490385103;38734.22531549367;38742;7325.194228634846;7395.038644976145;7449.664765085584;7455.329020388164;7476.514026588282;7375.815604609874;7477.850442991137;7499.287567681062;7506.512076715024;7508.737194198204;8123.106458289583;8322.542260175687;8379.95810020952;8398.160183981583;8402.327813774913;8333.83332333356;8335.291500222458;8337.541435221061;8405.915940840605;8413.495190288602;8143.348532117417;8177.5911204440135;8230.506169629842;8240.711575094843;8247.134050924686;36753.448698269545;36841.815790920875;36924.630753692385;36942;36962.630373696185;3084.2443601317823;3602.711783057375;3645.0887278180453;3648.1244501319757;3652.4521321801944;29918.85040574783;30423.69576304231;30457;30471.542926856117;30548;3061.173483159557;3069.2480342316053;3086.33643766589;3102.953455698166;3144.9528257076163;239625;240212;243595.78202109053;286701;289408.1059189402;38277.23445531129;46941.591752247485;47234.76382618065;47282.05435891263;47402.05195896063;50310.24534631983;50343.74828125836;50373;50481.49518504768;50536.74731626319;14175;14239.43042278318;14284.50004249867;14310;14328.713425731426;11583.07335413165;11587;11604.76790464186;11606.361650109156;11628.94185529067;13622.318884055758;13803.792943105862;13815.723685526233;13819.930900345635;13895.58313250604;5253.474652534793;6207.441330280297;6259.029850373199;6269.905951410732;6292.087647291209;976.4922240434934;991.5795225912161;997.4663554998041;1047.4239168457318;1049.9107575856137;21519.246826361315;22546.759665481757;22653.660195097087;23054.65418018731;24281.514369712506;1028.4857571214393;1066.981033113375;1078.8975047370486;1137.7155711072232;1147.6671765188169;8757;9776.266779991529;10808.729781755363;11900.345480998454;12217.389130543615;273968;284807;285673;287271;290220;56297.71851140718;56458;56648;56876.71561642248;56968.430315696314;1478.5268714011413;1482.7553453680239;1495.9252037398305;1497.3187199824272;1499.7300485912422;22778.31665050051;27802.16593502198;29805.10584682463;29870.402591948474;30261.394772104875;32789.344213116085;38283.23433531258;38622.80688596539;38627;38657.613423866525;279828;280331;280665;281334;283380;29423.264418388855;29954.550681740227;29973.700262997092;30079.248018799706;35453.113672158404;38007;39417.802910985265;40379.798101009306;43539;43597.03813328471;26350.155489116343;28043.71956280411;29120.854395727885;30485.54271685882;31203.37593248168;30352.544711828898;30873.845630771702;30995.69004309928;31360.68639313669;31829;37037.07407314839;37542;37584.624153758115;37616;37697.62302377051;27428.725712743417;29267.853660731562;29727.10818675443;31485.527717083805;31829.840850795597;21840.67238991448;22356.664650030587;23177.3046808596;24379.14672986437;24875 19:26:44 INFO - PID 1662 | |2;jquery.html;483.4150677680007;483.6300230323803;486.43330519944266;494.93813273340845;500.3220636038166;22326.883655817186;22456.214032508866;23176.536469270646;23232.070717171293;23342;18526.073696315165;18583.628327433442;19905.502362440948;25706.74293257067;25745.48509029823;6357.523185761075;6483.546151769378;6515.087887695727;6515.120458738081;6516.609003459784;6456.160699109133;6483.416492515673;6517.598716275999;6529.804105876831;6562.3765742254645;5908.1433192187105;6111.144439778434;6486.870262594745;6493.772717954873;6496.870062598745;5824.08855012163;6462.838429039311;6475.093486911836;6513.283538810727;6520.4131628153455;5928.466438020556;5970.910436343458;5982.222311099529;5992.131140984533;5999.910001349983;30589;30603.846980765175;31009.534856977163;31201.84399078013;31257.905973290824;1887.9716804247948;2212.0267082483815;2233.2072114399316;2255.6278214094655;2261.197274967379;19155.90422047881;19366.612667746565;19536.413907582653;19572.825630462077;19608.215671373127;2195.209724499179;2199.186301068599;2222.2444368914485;2227.6881236626887;2245.191730976847;164172.3582764169;167427.99999999878;171614.1419292908;172092;193211;11542.769144617228;14728.116313021254;14994.775078373616;15095.39618415251;15749.527514174593;20236.494087647927;20258.493537661383;20260.59478810445;20283.39149825478;20291.695624565644;8128.268455839003;8189;9490.90509094914;9892.901070989197;9958.203343732484;8985.595648195847;9077.500737459372;9089.318650997971;9102.302288307907;9113.726588202364;7553.471257011956;9176.587053582605;9261.44431334122;9266.860997084914;9310.674126405544;2615.9738402616113;2629.9211023669322;2635.9868200659257;2639.6142025436798;2758.158761577698;899.6266549382065;925.6066171876926;928.972130836076;947.8294306531428;984.6553706202772;24958.625620615705;27626.585601215997;27870;27880.72119278822;28050;966.5119114846957;974.3666616699202;1004.4977511244377;1010.3533738407425;1016.4358780876537;9211.032841551541;10356.119729822898;10978.890211097947;10981.3960232188;11259.380734059705;554460;561889;573848;574382;580460;133814.66185338216;157714;161065.58401623985;162990;163370;1014.2950649298814;1170.8653504846882;1206.897413719826;1229.8647148813802;1256.2776403567984;22586.887065564566;22592.66111008369;24567.877160614084;25117.120900769118;25386.619200711633;68743;68761.31238687549;72160.549486826;74663.8800418005;76399;79623.2037679639;90372;93764;94100.58849117132;94939.57590636284;11486.19596628228;11613.593524226611;11915.987141093105;11953.402329883646;12061.095417843871;13279.402426891007;16056.759148613013;18820.623587528447;18874.150663220462;19065.237390504226;14464.566063018126;14602.977791554915;14739.55781326562;14937.029093108913;15349.539513814603;13530.526431575241;13612.251326177155;13831.654208644864;13976.301184940508;14246.572602821932;13764.449422023408;14730.526947305612;15437.614059648598;15817.604559885634;15829;9976.002399759966;11699.064074873819;11773.88226117728;11791.882081179421;11995.220310679779;12438.19151755133;14332.14007159532;14796.630084247548;14844.925775371054;15225.6193595161 19:26:44 INFO - PID 1662 | |3;mootools.html;1411.675314677624;1458.8614081662245;1474.8230212374517;1491.2022068193512;1505.1796770759931;12913.096083274175;13422.26177560235;13462.259575723343;13473.595792126227;13490.325483725823;11040.503177357019;11345;11415.42922853856;11421.657350279484;13015.219086854795;2694.6362241097445;2736.192823117175;2762.392273699783;2763.281546797837;2765.4330862173306;2679.397135644484;2704.864756762164;2721.7550420462153;2725.9045933392335;2726.877290521931;2621.475704859036;2644.6958599761083;2649.8410095394242;2657.428652839639;2658.587918872578;2246.1352379334076;2329.9650505242435;2678.1162216468615;2687.395336049383;2690.6233127362184;2276.886155692209;2676.4111895382985;2760.903368382097;2776.1677292986733;2779.596958441035;13070.15044022145;13173.407196676173;13181.275029873354;13194.802077968838;13210.86789132106;7017.7543785967755;7253.891191632129;7298.416126709903;7323.780286591411;7335.046443962304;4471.105778844244;4604.976975115103;4664.020555683311;4789.209780386267;4813.374261346037;1371.636516323175;1372.7803551431723;1380.5789234283538;1389.805427240187;1413.9638919955232;14742.705145897022;19976.500587485283;20020.599588008303;20072.297469588495;20091.598168036704;6753.797386078426;6842.452603791685;6918.619475928872;6991.265917078736;7004.509684322048;16114.919425403032;16166.676666466605;16171.514854554382;16173.4339298124;16188.271527781279;7305.086864141982;7318.890216646754;7360.263973602704;7363.631818409058;7366.079240094989;6252.406021427957;6540.70566824494;6545.770898018546;6613.503987200979;6636.8672626547195;5201.375834899836;5320.015797077553;5327.280817089721;5357.946420535823;5388.191771234347;1404.8103506026555;1640.8441198086164;1645.5145732009087;1646.2591833674894;1650.5460998225344;780.6409051836218;799.2007992007992;814.8940637717059;815.6451943404519;832.4297855968559;7694.269044440769;7784.883226751604;7810.218978102144;8046.195380461907;8102.594870256464;621.6860485454816;636.9585976911486;675.1189697444784;682.7982750359404;689.8309914070984;3581.83881725323;4182.81177347014;4253.128108737701;4354.954810845375;4363.083752411965;11041.503132359063;17858.5535361617;18016.459506214833;18289.268429262946;18301.450956471326;17969.1015449227;18075.909620451814;18265.817341826678;18362.81637183638;18493.81506184948;7847.882281765778;9247.167754902091;9287;9364.859527107099;9415.670451534163;7436.144843343085;7526.285002924714;7584;7615.6953721850505;7937.523748575104;14116.435342586592;15934.681306373575;16032.358705651643;16095.356185752908;16168.514944551682;15508.767368489256;16149.111798851176;16965.23656435488;17095.914520427817;17399.739003915205;9033.728988130366;10458.790824183627;10631.308964917256;10697.197710171613;10697.839532407175;11770;12160.695982600506;12310.13829031995;12354.629361119181;12357.38213089324;9570.234381249622;9750.75623109428;9799.167070798776;9959.153471954965;10111.443870587189;8744.213020828156;9525.476098814632;9560.952195238979;9693.982131876051;9777;8925.955370223108;9099.408538444979;9172.862407063765;9199.402038867453;9203.217726493056;6135.785247516494;6459.6770161490795;6479.676016199265;7005.54463959841;7052.858942821217;6898.517103802686;6930.861382772216;7207.819804504929;7233.819154521179;7259.818504537428 19:26:44 INFO - PID 1662 | |4;prototype.html;350.75447186969126;419.35838167603566;452.36668663870586;455.87399124163323;466.24933856491066;6151.292601350846;6492.772752953648;6552.737890484381;6561.950087985923;6584.045313429556;5508.614396992212;5885.705714714269;6046.274447066358;6239.438450539451;6510.85141903172;4437.933430998538;4480.745391290436;4958.652894297401;4965.255211718235;4994.550490455858;4094.4267802507675;4942.431620363668;4975.303457515951;4989.476105008976;5004.049230646166;3841.2701586698577;4021;4719.882002949919;4737.289406589022;4969.229769385751;4041.6968727345566;4123.773192474413;4737.170995075865;4737.715737055771;4745.786439610226;4140.37894315855;4168.353905144708;4192.664586833046;4193.35003074524;4196.7272127311935;5258.816766227606;6162.165403914205;6168.383161683841;6189.814305570795;6202.4417802398;5650.6892120933335;5651.1523271509195;5661.547076233891;5680.4319568043275;5736.651886806597;6883.97105273678;6892.827679308058;6893.517453778238;6910.827229319257;6911.3779759821855;4187.895302617429;4759.4764575896625;4764;4777.116233496814;4789.880252993668;6136.478399336017;7200;7253.165885923134;7285.599292038989;7322.231165727629;4305.935410968838;4869.70781753096;4885.902281954341;4901.534354236342;4901.852944411673;4667;4709.505501922317;4910.705357678551;4965.230389289666;4967.627427942919;5003.274525193827;5380.681732975446;5675.829725108253;5703.572232082611;5721.456461636138;6292.779752708633;6892.276310987374;6912.308769123048;6929.688164032631;6977.89533157003;1545.3046129241886;1600.90180977687;1692.5430133863788;1699.9830001700072;1700.3963592924333;961.7066794627566;1091.0671375973586;1122.5509796081633;1141.3209140561519;1152.3201311226417;5267.367915850046;6220.720067596969;6382.617042977344;6390.84022899422;6442.613443193423;5500.1749737539685;6423.197100362455;6693.866122677519;6927.480438967098;7122.857542849218;6114.419130182714;6371.585846920028;6382.266039405435;6395.2732762153955;6398.488120950313;3158.1998260991013;3590.4328405190413;3601.7393912130547;3700.093477098128;3768.9057773555332;5999.760009599654;6027.7287522061615;6058.727357268934;7106.111736032996;7319.41444684424;7305.89041164383;7312.488125831141;7341.192468828424;7344.30229128232;7359.448041396917;6719.764808231786;6868.965655171693;7279.963600181965;7321;7335.706571737178;5651.519620832275;6276.717547710273;6793.150856142982;7124.679389427384;7144.035555199982;5887.882242355215;6486.6702326024;6997.96501017512;7035.507514473938;7070.257622949722;5840.883182336414;6732.764353247613;7250.746223882139;7313.20826397536;7336.889946650911;5350.785968561214;5464.890702186013;5476.6166368355425;5480.205370221375;5512.09050506678;6764.492663050192;6903;7195.964020179866;7206.963965180141;7210.315020073294;5669.518090962314;5829.970850145892;5846.239988801599;6482.698641982029;6633.635150066792;5704.286964129484;6813.931860681329;6827.317268273133;6832.316768323128;6860;6648.966755166193;6662.966685166543;6663.36698013688;6690.264070952288;6721.798346049626;6501.772437964649;6528.510361722985;6687.1975362956755;6789.185297764102;6867.313268673292;6064.089237166273;6104.938950610437;6591.571547849375;6592.110065141144;6597.901031484435 19:26:44 INFO - PID 1662 | |5;yui.html;688.3838964127109;697.7522979342331;760.6424980259279;782.1005843280227;864.0452300208268;16450.753238701414;17074.23165957532;17426.912865435686;17472.563185920342;18245.817541824577;18514.629707405842;18605;18764.71852922204;18804.717929231072;18824.717629235573;1001.35412658835;1029.3926583315842;1031.9484025798718;1032.4166845732138;1037.6627596031283;930.1163894300408;956.579105193716;1026.5277972132828;1026.9948650256738;1037.7301901505587;1075.763332066944;1075.9031687148156;1077.3751224289892;1077.6228320087985;1087.190043417651;708.0936401406208;719.7588807749411;1070.6680928912062;1071.383954226319;1092.814221582333;538.2383926743653;757.5909009135061;765.9195784442609;766.1227894061325;777.3004296133469;17430.912845435818;17467.65064698699;17593.56016099595;17604.559886002826;17625.471235862944;16707.49877503677;17871.463856084338;17881.105944702842;17988.460346189637;18050.638987220183;14220.928895355457;14518.854811451856;14748.557543273719;14826.777598336033;14912.254387280702;3156.889508867179;3636.636336366342;3637.418013117888;3641.3991691370893;3643.653852883972;111440;138897;146076;148681;149856;11380.03269722083;12421.503139874303;13053.216806991612;13443.529476468473;13820.654483637989;19661.410157695293;25915.870420647778;27068.32329191786;27148.1855544334;27167.456650866872;7143.071400717978;7532.13380461243;7794.259545343183;7847.764567062997;7851.05787305527;3611.4402267648557;3628.2743451309843;3739.1026153723456;3740.251949610089;3892.8637497687446;4097.733647312915;4254.808533615995;4256.1487702459635;4595.287730401793;4978.452370239271;1031.4327120083924;1044.6500422358442;1047.711879233217;1050.553514756241;1050.9369437833755;597.9337847339993;627.8273474794377;650.7982525417136;651.5146216068964;661.5534514202894;9872.60509579623;11728.413579321;11761.294322340687;12159.392030398445;12526.373681315898;629.4806784402886;648.8377905523619;649.2630863969334;662.9436497897636;682.1098466501263;5252.343457067866;5793.073108302651;5957.076653118774;6063.757449701962;6572.967135164389;122821;142696.28651856675;148414;149547.252263738;151285;115802;120462;123301.48593640747;124536;130197;78744;102764;103142;111381;111789.88210117795;10672.359658420519;11146.885311468788;12420.503179872703;12574.30841303737;13131.737365252451;45651.315230270906;46771;48262.5173748258;48386.75806620944;49273.50726492689;61455.6927215379;62082.37917620766;63882;65556.34443655502;66094;16968.83031169722;17495.737563936807;17561.736573951144;17695.115244237993;17814.554636134202;18466.35367762122;19012.334568290044;19078;19199;19209;17131;17604.119794010505;17930.282788688306;18356.173972171553;18386.988715620235;17347.392841251;17479.213435395184;17866.374676886768;17906.373276935246;17923.28306867763;17070.061146637054;17138.485845424657;17217.82782172162;17245.56886077858;17302.826971730123;11261.268017578832;12004.619468761204;12069;12234.632961011184;12375.69060773488;10859.674209773719;12896.161749486253;13230.272335021282;13466;13741.76324130833 19:26:44 INFO - PID 1662 | __end_tp_report 19:26:44 INFO - PID 1662 | __start_cc_report 19:26:44 INFO - PID 1662 | _x_x_mozilla_cycle_collect,461 19:26:44 INFO - PID 1662 | __end_cc_report 19:26:44 INFO - PID 1662 | __startTimestamp1497407204005__endTimestamp 19:26:44 INFO - PID 1662 | 19:26:44 INFO - PID 1662 | ------- Summary: start ------- 19:26:44 INFO - PID 1662 | Number of tests: 6 19:26:44 INFO - PID 1662 | 19:26:44 INFO - PID 1662 | [#0] dojo.html Cycles:165 Average:6053.62 Median:6260.94 stddev:783.13 (12.5%) stddev-sans-first:749.00 19:26:44 INFO - PID 1662 | Values: 3040.1 3047.4 3274.6 3452.4 3576.3 5386.8 6038.8 6125.5 6160.2 6169.1 6101.0 6140.6 6168.1 6175.6 6230.1 5380.1 5394.5 5417.9 5421.6 5503.1 5481.1 5494.7 5504.7 5514.5 5524.7 5411.1 5412.9 5415.9 5444.2 5508.5 4387.0 5198.0 5227.2 5276.2 5312.5 5218.6 5286.6 5310.4 5403.1 5415.1 4886.8 4948.5 5556.8 6168.0 6234.4 5453.5 5485.6 5749.5 6553.1 6647.4 6471.4 6732.1 6769.0 6791.6 6802.0 5239.3 6190.3 6225.3 6242.1 6260.9 6788.9 6795.7 6906.7 6908.9 6928.7 5998.2 6071.5 6306.4 6524.2 6546.6 6556.6 6575.1 6591.0 6600.3 6615.6 6595.2 6635.3 6646.9 6652.6 6654.9 6609.2 6639.3 6676.4 6684.5 6690.1 5215.3 5221.2 5257.2 5284.6 5301.3 4693.3 4755.4 4789.4 4818.9 4840.3 6081.2 6229.3 6241.5 6242.4 6259.1 6673.5 6803.5 6817.4 6830.6 6838.6 6255.2 6263.7 6272.0 6280.4 6305.1 5756.0 5779.0 5792.0 5956.2 5988.0 6797.9 6804.5 6805.3 6808.0 6878.1 6740.2 6742.6 6763.8 6769.2 6808.7 5684.3 6898.6 6908.6 6913.9 6938.1 6510.7 6627.2 6634.3 6636.7 6664.5 6553.4 6739.9 6755.3 6763.8 6802.9 5034.7 6377.1 6391.9 6431.9 6478.9 6484.9 6507.2 6510.3 6534.2 6542.2 6297.4 6369.6 6393.8 6408.6 6439.0 6313.1 6400.1 6401.9 6408.2 6561.2 5283.8 6222.7 6222.9 6226.2 6256.2 19:26:44 INFO - PID 1662 | 19:26:44 INFO - PID 1662 | [#1] ext.html Cycles:180 Average:42775.23 Median:33001.84 stddev:72125.87 (218.6%) stddev-sans-first:72259.07 19:26:44 INFO - PID 1662 | Values: 717.6 810.3 823.6 827.3 857.8 38090.8 38861.2 38946.4 38948.6 39332.8 33214.3 38509.4 38509.6 38734.2 38742.0 7325.2 7395.0 7449.7 7455.3 7476.5 7375.8 7477.9 7499.3 7506.5 7508.7 8123.1 8322.5 8380.0 8398.2 8402.3 8333.8 8335.3 8337.5 8405.9 8413.5 8143.3 8177.6 8230.5 8240.7 8247.1 36753.4 36841.8 36924.6 36942.0 36962.6 3084.2 3602.7 3645.1 3648.1 3652.5 29918.9 30423.7 30457.0 30471.5 30548.0 3061.2 3069.2 3086.3 3103.0 3145.0 239625.0 240212.0 243595.8 286701.0 289408.1 38277.2 46941.6 47234.8 47282.1 47402.1 50310.2 50343.7 50373.0 50481.5 50536.7 14175.0 14239.4 14284.5 14310.0 14328.7 11583.1 11587.0 11604.8 11606.4 11628.9 13622.3 13803.8 13815.7 13819.9 13895.6 5253.5 6207.4 6259.0 6269.9 6292.1 976.5 991.6 997.5 1047.4 1049.9 21519.2 22546.8 22653.7 23054.7 24281.5 1028.5 1067.0 1078.9 1137.7 1147.7 8757.0 9776.3 10808.7 11900.3 12217.4 273968.0 284807.0 285673.0 287271.0 290220.0 56297.7 56458.0 56648.0 56876.7 56968.4 1478.5 1482.8 1495.9 1497.3 1499.7 22778.3 27802.2 29805.1 29870.4 30261.4 32789.3 38283.2 38622.8 38627.0 38657.6 279828.0 280331.0 280665.0 281334.0 283380.0 29423.3 29954.6 29973.7 30079.2 35453.1 38007.0 39417.8 40379.8 43539.0 43597.0 26350.2 28043.7 29120.9 30485.5 31203.4 30352.5 30873.8 30995.7 31360.7 31829.0 37037.1 37542.0 37584.6 37616.0 37697.6 27428.7 29267.9 29727.1 31485.5 31829.8 21840.7 22356.7 23177.3 24379.1 24875.0 19:26:44 INFO - PID 1662 | 19:26:44 INFO - PID 1662 | [#2] jquery.html Cycles:180 Average:39464.34 Median:23954.94 stddev:97768.01 (408.1%) stddev-sans-first:97998.47 19:26:44 INFO - PID 1662 | Values: 483.4 483.6 486.4 494.9 500.3 22326.9 22456.2 23176.5 23232.1 23342.0 18526.1 18583.6 19905.5 25706.7 25745.5 6357.5 6483.5 6515.1 6515.1 6516.6 6456.2 6483.4 6517.6 6529.8 6562.4 5908.1 6111.1 6486.9 6493.8 6496.9 5824.1 6462.8 6475.1 6513.3 6520.4 5928.5 5970.9 5982.2 5992.1 5999.9 30589.0 30603.8 31009.5 31201.8 31257.9 1888.0 2212.0 2233.2 2255.6 2261.2 19155.9 19366.6 19536.4 19572.8 19608.2 2195.2 2199.2 2222.2 2227.7 2245.2 164172.4 167428.0 171614.1 172092.0 193211.0 11542.8 14728.1 14994.8 15095.4 15749.5 20236.5 20258.5 20260.6 20283.4 20291.7 8128.3 8189.0 9490.9 9892.9 9958.2 8985.6 9077.5 9089.3 9102.3 9113.7 7553.5 9176.6 9261.4 9266.9 9310.7 2616.0 2629.9 2636.0 2639.6 2758.2 899.6 925.6 929.0 947.8 984.7 24958.6 27626.6 27870.0 27880.7 28050.0 966.5 974.4 1004.5 1010.4 1016.4 9211.0 10356.1 10978.9 10981.4 11259.4 554460.0 561889.0 573848.0 574382.0 580460.0 133814.7 157714.0 161065.6 162990.0 163370.0 1014.3 1170.9 1206.9 1229.9 1256.3 22586.9 22592.7 24567.9 25117.1 25386.6 68743.0 68761.3 72160.5 74663.9 76399.0 79623.2 90372.0 93764.0 94100.6 94939.6 11486.2 11613.6 11916.0 11953.4 12061.1 13279.4 16056.8 18820.6 18874.2 19065.2 14464.6 14603.0 14739.6 14937.0 15349.5 13530.5 13612.3 13831.7 13976.3 14246.6 13764.4 14730.5 15437.6 15817.6 15829.0 9976.0 11699.1 11773.9 11791.9 11995.2 12438.2 14332.1 14796.6 14844.9 15225.6 19:26:44 INFO - PID 1662 | 19:26:44 INFO - PID 1662 | [#3] mootools.html Cycles:180 Average:8085.32 Median:2762.84 stddev:5368.24 (194.3%) stddev-sans-first:5359.88 19:26:44 INFO - PID 1662 | Values: 1411.7 1458.9 1474.8 1491.2 1505.2 12913.1 13422.3 13462.3 13473.6 13490.3 11040.5 11345.0 11415.4 11421.7 13015.2 2694.6 2736.2 2762.4 2763.3 2765.4 2679.4 2704.9 2721.8 2725.9 2726.9 2621.5 2644.7 2649.8 2657.4 2658.6 2246.1 2330.0 2678.1 2687.4 2690.6 2276.9 2676.4 2760.9 2776.2 2779.6 13070.2 13173.4 13181.3 13194.8 13210.9 7017.8 7253.9 7298.4 7323.8 7335.0 4471.1 4605.0 4664.0 4789.2 4813.4 1371.6 1372.8 1380.6 1389.8 1414.0 14742.7 19976.5 20020.6 20072.3 20091.6 6753.8 6842.5 6918.6 6991.3 7004.5 16114.9 16166.7 16171.5 16173.4 16188.3 7305.1 7318.9 7360.3 7363.6 7366.1 6252.4 6540.7 6545.8 6613.5 6636.9 5201.4 5320.0 5327.3 5357.9 5388.2 1404.8 1640.8 1645.5 1646.3 1650.5 780.6 799.2 814.9 815.6 832.4 7694.3 7784.9 7810.2 8046.2 8102.6 621.7 637.0 675.1 682.8 689.8 3581.8 4182.8 4253.1 4355.0 4363.1 11041.5 17858.6 18016.5 18289.3 18301.5 17969.1 18075.9 18265.8 18362.8 18493.8 7847.9 9247.2 9287.0 9364.9 9415.7 7436.1 7526.3 7584.0 7615.7 7937.5 14116.4 15934.7 16032.4 16095.4 16168.5 15508.8 16149.1 16965.2 17095.9 17399.7 9033.7 10458.8 10631.3 10697.2 10697.8 11770.0 12160.7 12310.1 12354.6 12357.4 9570.2 9750.8 9799.2 9959.2 10111.4 8744.2 9525.5 9561.0 9694.0 9777.0 8926.0 9099.4 9172.9 9199.4 9203.2 6135.8 6459.7 6479.7 7005.5 7052.9 6898.5 6930.9 7207.8 7233.8 7259.8 19:26:44 INFO - PID 1662 | 19:26:44 INFO - PID 1662 | [#4] prototype.html Cycles:180 Average:5533.19 Median:6084.51 stddev:1692.90 (27.8%) stddev-sans-first:1652.36 19:26:44 INFO - PID 1662 | Values: 350.8 419.4 452.4 455.9 466.2 6151.3 6492.8 6552.7 6562.0 6584.0 5508.6 5885.7 6046.3 6239.4 6510.9 4437.9 4480.7 4958.7 4965.3 4994.6 4094.4 4942.4 4975.3 4989.5 5004.0 3841.3 4021.0 4719.9 4737.3 4969.2 4041.7 4123.8 4737.2 4737.7 4745.8 4140.4 4168.4 4192.7 4193.4 4196.7 5258.8 6162.2 6168.4 6189.8 6202.4 5650.7 5651.2 5661.5 5680.4 5736.7 6884.0 6892.8 6893.5 6910.8 6911.4 4187.9 4759.5 4764.0 4777.1 4789.9 6136.5 7200.0 7253.2 7285.6 7322.2 4305.9 4869.7 4885.9 4901.5 4901.9 4667.0 4709.5 4910.7 4965.2 4967.6 5003.3 5380.7 5675.8 5703.6 5721.5 6292.8 6892.3 6912.3 6929.7 6977.9 1545.3 1600.9 1692.5 1700.0 1700.4 961.7 1091.1 1122.6 1141.3 1152.3 5267.4 6220.7 6382.6 6390.8 6442.6 5500.2 6423.2 6693.9 6927.5 7122.9 6114.4 6371.6 6382.3 6395.3 6398.5 3158.2 3590.4 3601.7 3700.1 3768.9 5999.8 6027.7 6058.7 7106.1 7319.4 7305.9 7312.5 7341.2 7344.3 7359.4 6719.8 6869.0 7280.0 7321.0 7335.7 5651.5 6276.7 6793.2 7124.7 7144.0 5887.9 6486.7 6998.0 7035.5 7070.3 5840.9 6732.8 7250.7 7313.2 7336.9 5350.8 5464.9 5476.6 5480.2 5512.1 6764.5 6903.0 7196.0 7207.0 7210.3 5669.5 5830.0 5846.2 6482.7 6633.6 5704.3 6813.9 6827.3 6832.3 6860.0 6649.0 6663.0 6663.4 6690.3 6721.8 6501.8 6528.5 6687.2 6789.2 6867.3 6064.1 6104.9 6591.6 6592.1 6597.9 19:26:44 INFO - PID 1662 | 19:26:44 INFO - PID 1662 | [#5] yui.html Cycles:180 Average:25355.95 Median:17660.29 stddev:38653.23 (218.9%) stddev-sans-first:38717.28 19:26:44 INFO - PID 1662 | Values: 688.4 697.8 760.6 782.1 864.0 16450.8 17074.2 17426.9 17472.6 18245.8 18514.6 18605.0 18764.7 18804.7 18824.7 1001.4 1029.4 1031.9 1032.4 1037.7 930.1 956.6 1026.5 1027.0 1037.7 1075.8 1075.9 1077.4 1077.6 1087.2 708.1 719.8 1070.7 1071.4 1092.8 538.2 757.6 765.9 766.1 777.3 17430.9 17467.7 17593.6 17604.6 17625.5 16707.5 17871.5 17881.1 17988.5 18050.6 14220.9 14518.9 14748.6 14826.8 14912.3 3156.9 3636.6 3637.4 3641.4 3643.7 111440.0 138897.0 146076.0 148681.0 149856.0 11380.0 12421.5 13053.2 13443.5 13820.7 19661.4 25915.9 27068.3 27148.2 27167.5 7143.1 7532.1 7794.3 7847.8 7851.1 3611.4 3628.3 3739.1 3740.3 3892.9 4097.7 4254.8 4256.1 4595.3 4978.5 1031.4 1044.7 1047.7 1050.6 1050.9 597.9 627.8 650.8 651.5 661.6 9872.6 11728.4 11761.3 12159.4 12526.4 629.5 648.8 649.3 662.9 682.1 5252.3 5793.1 5957.1 6063.8 6573.0 122821.0 142696.3 148414.0 149547.3 151285.0 115802.0 120462.0 123301.5 124536.0 130197.0 78744.0 102764.0 103142.0 111381.0 111789.9 10672.4 11146.9 12420.5 12574.3 13131.7 45651.3 46771.0 48262.5 48386.8 49273.5 61455.7 62082.4 63882.0 65556.3 66094.0 16968.8 17495.7 17561.7 17695.1 17814.6 18466.4 19012.3 19078.0 19199.0 19209.0 17131.0 17604.1 17930.3 18356.2 18387.0 17347.4 17479.2 17866.4 17906.4 17923.3 17070.1 17138.5 17217.8 17245.6 17302.8 11261.3 12004.6 12069.0 12234.6 12375.7 10859.7 12896.2 13230.3 13466.0 13741.8 19:26:44 INFO - PID 1662 | -------- Summary: end -------- 19:26:44 INFO - PID 1662 | 19:26:44 INFO - TEST-INFO | 1662: exit 0 19:26:44 INFO - TEST-OK | dromaeo_css | took 1144169ms 19:26:44 INFO - TEST-START | kraken 19:26:44 INFO - operating with platform_type : mac_ 19:26:44 INFO - Initialising browser for kraken test... 19:26:44 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49231/getInfo.html -foreground -profile /var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/tmpEZJKdJ/profile 19:26:44 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49231/getInfo.html -foreground) 19:27:18 INFO - TEST-INFO | 1670: killed by SIGTERM 19:27:18 INFO - Browser initialized. 19:27:18 INFO - Running cycle 1/1 for kraken test... 19:27:18 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/tmpEZJKdJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:27:20 INFO - PID 1674 | 2017-06-13 19:27:20.386 plugin-container[1675:14252] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963b, name = 'com.apple.tsm.portname' 19:27:20 INFO - PID 1674 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:27:20 INFO - PID 1674 | 2017-06-13 19:27:20.386 plugin-container[1675:14252] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a17, name = 'com.apple.CFPasteboardClient' 19:27:20 INFO - PID 1674 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:27:20 INFO - PID 1674 | 2017-06-13 19:27:20.386 plugin-container[1675:14252] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:27:21 INFO - PID 1674 | 2017-06-13 19:27:21.152 plugin-container[1676:14301] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4843, name = 'com.apple.tsm.portname' 19:27:21 INFO - PID 1674 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:27:21 INFO - PID 1674 | 2017-06-13 19:27:21.152 plugin-container[1676:14301] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x490b, name = 'com.apple.CFPasteboardClient' 19:27:21 INFO - PID 1674 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:27:21 INFO - PID 1674 | 2017-06-13 19:27:21.152 plugin-container[1676:14301] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:27:30 INFO - PID 1674 | RSS: Main: 252686336 19:27:30 INFO - PID 1674 | 19:27:33 INFO - PID 1674 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49231/tests/kraken/driver.html?testName=audio-beat-detection) 19:27:34 INFO - PID 1674 | RSS: Main: 265424896 19:27:34 INFO - PID 1674 | 19:27:36 INFO - PID 1674 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49231/tests/kraken/driver.html?testName=audio-dft) 19:27:37 INFO - PID 1674 | RSS: Main: 275087360 19:27:37 INFO - PID 1674 | 19:27:41 INFO - PID 1674 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49231/tests/kraken/driver.html?testName=audio-fft) 19:27:41 INFO - PID 1674 | RSS: Main: 278274048 19:27:41 INFO - PID 1674 | 19:27:43 INFO - PID 1674 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49231/tests/kraken/driver.html?testName=audio-oscillator) 19:27:44 INFO - PID 1674 | RSS: Main: 279097344 19:27:44 INFO - PID 1674 | 19:27:46 INFO - PID 1674 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49231/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:27:46 INFO - PID 1674 | RSS: Main: 279904256 19:27:46 INFO - PID 1674 | 19:27:49 INFO - PID 1674 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49231/tests/kraken/driver.html?testName=imaging-darkroom) 19:27:50 INFO - PID 1674 | RSS: Main: 285134848 19:27:50 INFO - PID 1674 | 19:27:53 INFO - PID 1674 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49231/tests/kraken/driver.html?testName=imaging-desaturate) 19:27:54 INFO - PID 1674 | RSS: Main: 285290496 19:27:54 INFO - PID 1674 | 19:27:57 INFO - PID 1674 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49231/tests/kraken/driver.html?testName=json-parse-financial) 19:27:58 INFO - PID 1674 | RSS: Main: 285237248 19:27:58 INFO - PID 1674 | 19:27:59 INFO - PID 1674 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49231/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:28:00 INFO - PID 1674 | RSS: Main: 285327360 19:28:00 INFO - PID 1674 | 19:28:02 INFO - PID 1674 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:28:02 INFO - PID 1674 | RSS: Main: 285405184 19:28:02 INFO - PID 1674 | 19:28:04 INFO - PID 1674 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:28:05 INFO - PID 1674 | RSS: Main: 285282304 19:28:05 INFO - PID 1674 | 19:28:08 INFO - PID 1674 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:28:09 INFO - PID 1674 | RSS: Main: 285229056 19:28:09 INFO - PID 1674 | 19:28:12 INFO - PID 1674 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:28:12 INFO - PID 1674 | RSS: Main: 285159424 19:28:12 INFO - PID 1674 | 19:28:14 INFO - PID 1674 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49231/tests/kraken/driver.html?testName=ai-astar) 19:28:14 INFO - PID 1674 | RSS: Main: 287490048 19:28:14 INFO - PID 1674 | 19:28:14 INFO - PID 1674 | __start_tp_report 19:28:14 INFO - PID 1674 | _x_x_mozilla_page_load 19:28:14 INFO - PID 1674 | _x_x_mozilla_page_load_details 19:28:14 INFO - PID 1674 | |i|pagename|runs| 19:28:14 INFO - PID 1674 | |0;ai-astar;107;115;125;120;111;112;113;115;115;114 19:28:14 INFO - PID 1674 | |1;audio-beat-detection;176;151;159;147;150;149;137;148;189;148 19:28:14 INFO - PID 1674 | |2;audio-dft;199;274;258;281;211;203;201;205;236;221 19:28:14 INFO - PID 1674 | |3;audio-fft;68;90;89;129;57;61;59;66;111;50 19:28:14 INFO - PID 1674 | |4;audio-oscillator;71;70;114;72;70;76;109;72;72;118 19:28:14 INFO - PID 1674 | |5;imaging-gaussian-blur;89;91;90;88;90;88;91;90;89;90 19:28:14 INFO - PID 1674 | |6;imaging-darkroom;116;153;98;95;145;99;144;153;96;125 19:28:14 INFO - PID 1674 | |7;imaging-desaturate;145;130;89;92;92;98;90;93;90;90 19:28:14 INFO - PID 1674 | |8;json-parse-financial;50;51;50;51;51;54;53;53;52;50 19:28:14 INFO - PID 1674 | |9;json-stringify-tinderbox;59;58;55;60;65;55;56;58;58;57 19:28:14 INFO - PID 1674 | |10;stanford-crypto-aes;80;80;80;82;80;83;80;81;88;81 19:28:14 INFO - PID 1674 | |11;stanford-crypto-ccm;139;143;143;132;146;181;156;159;137;161 19:28:14 INFO - PID 1674 | |12;stanford-crypto-pbkdf2;177;183;198;243;182;177;180;183;181;182 19:28:14 INFO - PID 1674 | |13;stanford-crypto-sha256-iterative;68;76;84;78;67;57;62;93;98;102 19:28:14 INFO - PID 1674 | __end_tp_report 19:28:14 INFO - PID 1674 | __start_cc_report 19:28:14 INFO - PID 1674 | _x_x_mozilla_cycle_collect,1196 19:28:14 INFO - PID 1674 | __end_cc_report 19:28:14 INFO - PID 1674 | __startTimestamp1497407294909__endTimestamp 19:28:14 INFO - PID 1674 | 19:28:14 INFO - PID 1674 | ------- Summary: start ------- 19:28:14 INFO - PID 1674 | Number of tests: 14 19:28:14 INFO - PID 1674 | 19:28:14 INFO - PID 1674 | [#0] ai-astar Cycles:10 Average:114.70 Median:115.00 stddev:4.92 (4.3%) stddev-sans-first:4.36 19:28:14 INFO - PID 1674 | Values: 107.0 115.0 125.0 120.0 111.0 112.0 113.0 115.0 115.0 114.0 19:28:14 INFO - PID 1674 | 19:28:14 INFO - PID 1674 | [#1] audio-beat-detection Cycles:10 Average:155.40 Median:150.50 stddev:15.54 (10.3%) stddev-sans-first:14.59 19:28:14 INFO - PID 1674 | Values: 176.0 151.0 159.0 147.0 150.0 149.0 137.0 148.0 189.0 148.0 19:28:14 INFO - PID 1674 | 19:28:14 INFO - PID 1674 | [#2] audio-dft Cycles:10 Average:228.90 Median:228.50 stddev:31.52 (13.8%) stddev-sans-first:31.52 19:28:14 INFO - PID 1674 | Values: 199.0 274.0 258.0 281.0 211.0 203.0 201.0 205.0 236.0 221.0 19:28:14 INFO - PID 1674 | 19:28:14 INFO - PID 1674 | [#3] audio-fft Cycles:10 Average:78.00 Median:63.50 stddev:25.98 (40.9%) stddev-sans-first:27.30 19:28:14 INFO - PID 1674 | Values: 68.0 90.0 89.0 129.0 57.0 61.0 59.0 66.0 111.0 50.0 19:28:14 INFO - PID 1674 | 19:28:14 INFO - PID 1674 | [#4] audio-oscillator Cycles:10 Average:84.40 Median:71.50 stddev:20.38 (28.5%) stddev-sans-first:21.03 19:28:14 INFO - PID 1674 | Values: 71.0 70.0 114.0 72.0 70.0 76.0 109.0 72.0 72.0 118.0 19:28:14 INFO - PID 1674 | 19:28:14 INFO - PID 1674 | [#5] imaging-gaussian-blur Cycles:10 Average:89.60 Median:90.00 stddev:1.07 (1.2%) stddev-sans-first:1.12 19:28:14 INFO - PID 1674 | Values: 89.0 91.0 90.0 88.0 90.0 88.0 91.0 90.0 89.0 90.0 19:28:14 INFO - PID 1674 | 19:28:14 INFO - PID 1674 | [#6] imaging-darkroom Cycles:10 Average:122.40 Median:124.00 stddev:24.69 (19.9%) stddev-sans-first:26.08 19:28:14 INFO - PID 1674 | Values: 116.0 153.0 98.0 95.0 145.0 99.0 144.0 153.0 96.0 125.0 19:28:14 INFO - PID 1674 | 19:28:14 INFO - PID 1674 | [#7] imaging-desaturate Cycles:10 Average:100.90 Median:91.00 stddev:19.77 (21.7%) stddev-sans-first:13.03 19:28:14 INFO - PID 1674 | Values: 145.0 130.0 89.0 92.0 92.0 98.0 90.0 93.0 90.0 90.0 19:28:14 INFO - PID 1674 | 19:28:14 INFO - PID 1674 | [#8] json-parse-financial Cycles:10 Average:51.50 Median:51.50 stddev:1.43 (2.8%) stddev-sans-first:1.41 19:28:14 INFO - PID 1674 | Values: 50.0 51.0 50.0 51.0 51.0 54.0 53.0 53.0 52.0 50.0 19:28:14 INFO - PID 1674 | 19:28:14 INFO - PID 1674 | [#9] json-stringify-tinderbox Cycles:10 Average:58.10 Median:58.00 stddev:2.92 (5.0%) stddev-sans-first:3.08 19:28:14 INFO - PID 1674 | Values: 59.0 58.0 55.0 60.0 65.0 55.0 56.0 58.0 58.0 57.0 19:28:14 INFO - PID 1674 | 19:28:14 INFO - PID 1674 | [#10] stanford-crypto-aes Cycles:10 Average:81.50 Median:81.00 stddev:2.51 (3.1%) stddev-sans-first:2.60 19:28:14 INFO - PID 1674 | Values: 80.0 80.0 80.0 82.0 80.0 83.0 80.0 81.0 88.0 81.0 19:28:14 INFO - PID 1674 | 19:28:14 INFO - PID 1674 | [#11] stanford-crypto-ccm Cycles:10 Average:149.70 Median:151.00 stddev:14.63 (9.7%) stddev-sans-first:15.00 19:28:14 INFO - PID 1674 | Values: 139.0 143.0 143.0 132.0 146.0 181.0 156.0 159.0 137.0 161.0 19:28:14 INFO - PID 1674 | 19:28:14 INFO - PID 1674 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:188.60 Median:182.50 stddev:20.00 (11.0%) stddev-sans-first:20.76 19:28:14 INFO - PID 1674 | Values: 177.0 183.0 198.0 243.0 182.0 177.0 180.0 183.0 181.0 182.0 19:28:14 INFO - PID 1674 | 19:28:14 INFO - PID 1674 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.50 Median:77.00 stddev:15.48 (20.1%) stddev-sans-first:15.95 19:28:14 INFO - PID 1674 | Values: 68.0 76.0 84.0 78.0 67.0 57.0 62.0 93.0 98.0 102.0 19:28:14 INFO - PID 1674 | -------- Summary: end -------- 19:28:14 INFO - PID 1674 | 19:28:15 INFO - TEST-INFO | 1674: exit 0 19:28:15 INFO - TEST-OK | kraken | took 91252ms 19:28:15 INFO - SUITE-END | took 1235s 19:28:16 INFO - Completed test suite (00:20:35) 19:28:16 INFO - javascript benchmark 19:28:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8245.817908547366, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3040.0575821495345, 3047.436224298505, 3274.6234183068946, 3452.413089774738, 3576.3383774001813, 5386.8383948481605, 6038.818835434939, 6125.509959203264, 6160.168377269068, 6169.136320915076, 6100.982116608275, 6140.5655994444905, 6168.074788781674, 6175.567710260284, 6230.127782110508, 5380.085385484457, 5394.514493695567, 5417.918731219015, 5421.593380496459, 5503.091989821694, 5481.12302031675, 5494.725263736817, 5504.697241651707, 5514.531264842493, 5524.668519888799, 5411.053065713484, 5412.891742165154, 5415.891682166334, 5444.210589464506, 5508.531774799127, 4386.956130438687, 5197.974010129925, 5227.18978558324, 5276.234945932857, 5312.548433383167, 5218.556422704075, 5286.629935904488, 5310.389305229909, 5403.081476149025, 5415.079436495766, 4886.804527818883, 4948.529889660476, 5556.777728890839, 6168.0, 6234.407731265523, 5453.454654534555, 5485.61600687952, 5749.482546570787, 6553.115329430513, 6647.368499992542, 6471.449926756231, 6732.091167692348, 6769.018492318586, 6791.5925044497, 6802.01370801231, 5239.266502689627, 6190.288116866528, 6225.284092329395, 6242.063690446469, 6260.937390626035, 6788.864222715518, 6795.660216989131, 6906.689198986058, 6908.9309106909295, 6928.653567321715, 5998.1602575639445, 6071.483923866433, 6306.369363063657, 6524.217093948757, 6546.607203567801, 6556.63938483388, 6575.079488871562, 6591.0, 6600.273969863311, 6615.63614001225, 6595.175603049619, 6635.336466353326, 6646.900296495556, 6652.567583107179, 6654.933450665529, 6609.173853268341, 6639.336066393419, 6676.365745254193, 6684.532082754161, 6690.096836926952, 5215.322008138917, 5221.164613661872, 5257.185136303879, 5284.5772338212855, 5301.310829592128, 4693.319468677023, 4755.429348478205, 4789.353437285991, 4818.903621927542, 4840.298156767249, 6081.17904082952, 6229.283632382334, 6241.531885108635, 6242.438180563771, 6259.123722678829, 6673.4661227101715, 6803.455723542104, 6817.420519255918, 6830.624315662587, 6838.555493892881, 6255.249370075529, 6263.655498947602, 6272.027835685476, 6280.403361680633, 6305.054241863665, 5756.0, 5779.046457334577, 5792.0, 5956.225690660273, 5988.041913293852, 6797.898031529531, 6804.489663275274, 6805.251422343637, 6807.965960170267, 6878.140232470942, 6740.2248741393405, 6742.595444273359, 6763.830904227434, 6769.1876974763345, 6808.693608787716, 5684.261046064151, 6898.620575868375, 6908.620025898624, 6913.861722765617, 6938.132733408324, 6510.73957041713, 6627.237867645283, 6634.303398143318, 6636.7013484392355, 6664.533482656361, 6553.4101930826455, 6739.932600674127, 6755.256921738703, 6763.797086087426, 6802.863942721019, 5034.723090230073, 6377.075324078076, 6391.8721625566295, 6431.935680643321, 6478.935210647834, 6484.935150648433, 6507.186601674791, 6510.3489651034515, 6534.150560427114, 6542.247641521096, 6297.433231009231, 6369.617822930639, 6393.808185754435, 6408.551401401857, 6438.967805160944, 6313.116163737172, 6400.103985442135, 6401.903971440339, 6408.166938297992, 6561.179852518435, 5283.815066472655, 6222.657753823583, 6222.906656400067, 6226.1905952225925, 6256.217972753406], "value": 5998.41881746706, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [717.5802155738893, 810.3355248696068, 823.6170180865904, 827.3381294964028, 857.8412993596189, 38090.80954595227, 38861.22277554447, 38946.41580376297, 38948.61051389489, 39332.80333598331, 33214.33571328572, 38509.422358664575, 38509.61490385103, 38734.22531549367, 38742.0, 7325.194228634846, 7395.038644976145, 7449.664765085584, 7455.329020388164, 7476.514026588282, 7375.815604609874, 7477.850442991137, 7499.287567681062, 7506.512076715024, 7508.737194198204, 8123.106458289583, 8322.542260175687, 8379.95810020952, 8398.160183981583, 8402.327813774913, 8333.83332333356, 8335.291500222458, 8337.541435221061, 8405.915940840605, 8413.495190288602, 8143.348532117417, 8177.5911204440135, 8230.506169629842, 8240.711575094843, 8247.134050924686, 36753.448698269545, 36841.815790920875, 36924.630753692385, 36942.0, 36962.630373696185, 3084.2443601317823, 3602.711783057375, 3645.0887278180453, 3648.1244501319757, 3652.4521321801944, 29918.85040574783, 30423.69576304231, 30457.0, 30471.542926856117, 30548.0, 3061.173483159557, 3069.2480342316053, 3086.33643766589, 3102.953455698166, 3144.9528257076163, 239625.0, 240212.0, 243595.78202109053, 286701.0, 289408.1059189402, 38277.23445531129, 46941.591752247485, 47234.76382618065, 47282.05435891263, 47402.05195896063, 50310.24534631983, 50343.74828125836, 50373.0, 50481.49518504768, 50536.74731626319, 14175.0, 14239.43042278318, 14284.50004249867, 14310.0, 14328.713425731426, 11583.07335413165, 11587.0, 11604.76790464186, 11606.361650109156, 11628.94185529067, 13622.318884055758, 13803.792943105862, 13815.723685526233, 13819.930900345635, 13895.58313250604, 5253.474652534793, 6207.441330280297, 6259.029850373199, 6269.905951410732, 6292.087647291209, 976.4922240434934, 991.5795225912161, 997.4663554998041, 1047.4239168457318, 1049.9107575856137, 21519.246826361315, 22546.759665481757, 22653.660195097087, 23054.65418018731, 24281.514369712506, 1028.4857571214393, 1066.981033113375, 1078.8975047370486, 1137.7155711072232, 1147.6671765188169, 8757.0, 9776.266779991529, 10808.729781755363, 11900.345480998454, 12217.389130543615, 273968.0, 284807.0, 285673.0, 287271.0, 290220.0, 56297.71851140718, 56458.0, 56648.0, 56876.71561642248, 56968.430315696314, 1478.5268714011413, 1482.7553453680239, 1495.9252037398305, 1497.3187199824272, 1499.7300485912422, 22778.31665050051, 27802.16593502198, 29805.10584682463, 29870.402591948474, 30261.394772104875, 32789.344213116085, 38283.23433531258, 38622.80688596539, 38627.0, 38657.613423866525, 279828.0, 280331.0, 280665.0, 281334.0, 283380.0, 29423.264418388855, 29954.550681740227, 29973.700262997092, 30079.248018799706, 35453.113672158404, 38007.0, 39417.802910985265, 40379.798101009306, 43539.0, 43597.03813328471, 26350.155489116343, 28043.71956280411, 29120.854395727885, 30485.54271685882, 31203.37593248168, 30352.544711828898, 30873.845630771702, 30995.69004309928, 31360.68639313669, 31829.0, 37037.07407314839, 37542.0, 37584.624153758115, 37616.0, 37697.62302377051, 27428.725712743417, 29267.853660731562, 29727.10818675443, 31485.527717083805, 31829.840850795597, 21840.67238991448, 22356.664650030587, 23177.3046808596, 24379.14672986437, 24875.0], "value": 17042.37638098862, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [483.4150677680007, 483.6300230323803, 486.43330519944266, 494.93813273340845, 500.3220636038166, 22326.883655817186, 22456.214032508866, 23176.536469270646, 23232.070717171293, 23342.0, 18526.073696315165, 18583.628327433442, 19905.502362440948, 25706.74293257067, 25745.48509029823, 6357.523185761075, 6483.546151769378, 6515.087887695727, 6515.120458738081, 6516.609003459784, 6456.160699109133, 6483.416492515673, 6517.598716275999, 6529.804105876831, 6562.3765742254645, 5908.1433192187105, 6111.144439778434, 6486.870262594745, 6493.772717954873, 6496.870062598745, 5824.08855012163, 6462.838429039311, 6475.093486911836, 6513.283538810727, 6520.4131628153455, 5928.466438020556, 5970.910436343458, 5982.222311099529, 5992.131140984533, 5999.910001349983, 30589.0, 30603.846980765175, 31009.534856977163, 31201.84399078013, 31257.905973290824, 1887.9716804247948, 2212.0267082483815, 2233.2072114399316, 2255.6278214094655, 2261.197274967379, 19155.90422047881, 19366.612667746565, 19536.413907582653, 19572.825630462077, 19608.215671373127, 2195.209724499179, 2199.186301068599, 2222.2444368914485, 2227.6881236626887, 2245.191730976847, 164172.3582764169, 167427.99999999878, 171614.1419292908, 172092.0, 193211.0, 11542.769144617228, 14728.116313021254, 14994.775078373616, 15095.39618415251, 15749.527514174593, 20236.494087647927, 20258.493537661383, 20260.59478810445, 20283.39149825478, 20291.695624565644, 8128.268455839003, 8189.0, 9490.90509094914, 9892.901070989197, 9958.203343732484, 8985.595648195847, 9077.500737459372, 9089.318650997971, 9102.302288307907, 9113.726588202364, 7553.471257011956, 9176.587053582605, 9261.44431334122, 9266.860997084914, 9310.674126405544, 2615.9738402616113, 2629.9211023669322, 2635.9868200659257, 2639.6142025436798, 2758.158761577698, 899.6266549382065, 925.6066171876926, 928.972130836076, 947.8294306531428, 984.6553706202772, 24958.625620615705, 27626.585601215997, 27870.0, 27880.72119278822, 28050.0, 966.5119114846957, 974.3666616699202, 1004.4977511244377, 1010.3533738407425, 1016.4358780876537, 9211.032841551541, 10356.119729822898, 10978.890211097947, 10981.3960232188, 11259.380734059705, 554460.0, 561889.0, 573848.0, 574382.0, 580460.0, 133814.66185338216, 157714.0, 161065.58401623985, 162990.0, 163370.0, 1014.2950649298814, 1170.8653504846882, 1206.897413719826, 1229.8647148813802, 1256.2776403567984, 22586.887065564566, 22592.66111008369, 24567.877160614084, 25117.120900769118, 25386.619200711633, 68743.0, 68761.31238687549, 72160.549486826, 74663.8800418005, 76399.0, 79623.2037679639, 90372.0, 93764.0, 94100.58849117132, 94939.57590636284, 11486.19596628228, 11613.593524226611, 11915.987141093105, 11953.402329883646, 12061.095417843871, 13279.402426891007, 16056.759148613013, 18820.623587528447, 18874.150663220462, 19065.237390504226, 14464.566063018126, 14602.977791554915, 14739.55781326562, 14937.029093108913, 15349.539513814603, 13530.526431575241, 13612.251326177155, 13831.654208644864, 13976.301184940508, 14246.572602821932, 13764.449422023408, 14730.526947305612, 15437.614059648598, 15817.604559885634, 15829.0, 9976.002399759966, 11699.064074873819, 11773.88226117728, 11791.882081179421, 11995.220310679779, 12438.19151755133, 14332.14007159532, 14796.630084247548, 14844.925775371054, 15225.6193595161], "value": 11741.368204897346, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1411.675314677624, 1458.8614081662245, 1474.8230212374517, 1491.2022068193512, 1505.1796770759931, 12913.096083274175, 13422.26177560235, 13462.259575723343, 13473.595792126227, 13490.325483725823, 11040.503177357019, 11345.0, 11415.42922853856, 11421.657350279484, 13015.219086854795, 2694.6362241097445, 2736.192823117175, 2762.392273699783, 2763.281546797837, 2765.4330862173306, 2679.397135644484, 2704.864756762164, 2721.7550420462153, 2725.9045933392335, 2726.877290521931, 2621.475704859036, 2644.6958599761083, 2649.8410095394242, 2657.428652839639, 2658.587918872578, 2246.1352379334076, 2329.9650505242435, 2678.1162216468615, 2687.395336049383, 2690.6233127362184, 2276.886155692209, 2676.4111895382985, 2760.903368382097, 2776.1677292986733, 2779.596958441035, 13070.15044022145, 13173.407196676173, 13181.275029873354, 13194.802077968838, 13210.86789132106, 7017.7543785967755, 7253.891191632129, 7298.416126709903, 7323.780286591411, 7335.046443962304, 4471.105778844244, 4604.976975115103, 4664.020555683311, 4789.209780386267, 4813.374261346037, 1371.636516323175, 1372.7803551431723, 1380.5789234283538, 1389.805427240187, 1413.9638919955232, 14742.705145897022, 19976.500587485283, 20020.599588008303, 20072.297469588495, 20091.598168036704, 6753.797386078426, 6842.452603791685, 6918.619475928872, 6991.265917078736, 7004.509684322048, 16114.919425403032, 16166.676666466605, 16171.514854554382, 16173.4339298124, 16188.271527781279, 7305.086864141982, 7318.890216646754, 7360.263973602704, 7363.631818409058, 7366.079240094989, 6252.406021427957, 6540.70566824494, 6545.770898018546, 6613.503987200979, 6636.8672626547195, 5201.375834899836, 5320.015797077553, 5327.280817089721, 5357.946420535823, 5388.191771234347, 1404.8103506026555, 1640.8441198086164, 1645.5145732009087, 1646.2591833674894, 1650.5460998225344, 780.6409051836218, 799.2007992007992, 814.8940637717059, 815.6451943404519, 832.4297855968559, 7694.269044440769, 7784.883226751604, 7810.218978102144, 8046.195380461907, 8102.594870256464, 621.6860485454816, 636.9585976911486, 675.1189697444784, 682.7982750359404, 689.8309914070984, 3581.83881725323, 4182.81177347014, 4253.128108737701, 4354.954810845375, 4363.083752411965, 11041.503132359063, 17858.5535361617, 18016.459506214833, 18289.268429262946, 18301.450956471326, 17969.1015449227, 18075.909620451814, 18265.817341826678, 18362.81637183638, 18493.81506184948, 7847.882281765778, 9247.167754902091, 9287.0, 9364.859527107099, 9415.670451534163, 7436.144843343085, 7526.285002924714, 7584.0, 7615.6953721850505, 7937.523748575104, 14116.435342586592, 15934.681306373575, 16032.358705651643, 16095.356185752908, 16168.514944551682, 15508.767368489256, 16149.111798851176, 16965.23656435488, 17095.914520427817, 17399.739003915205, 9033.728988130366, 10458.790824183627, 10631.308964917256, 10697.197710171613, 10697.839532407175, 11770.0, 12160.695982600506, 12310.13829031995, 12354.629361119181, 12357.38213089324, 9570.234381249622, 9750.75623109428, 9799.167070798776, 9959.153471954965, 10111.443870587189, 8744.213020828156, 9525.476098814632, 9560.952195238979, 9693.982131876051, 9777.0, 8925.955370223108, 9099.408538444979, 9172.862407063765, 9199.402038867453, 9203.217726493056, 6135.785247516494, 6459.6770161490795, 6479.676016199265, 7005.54463959841, 7052.858942821217, 6898.517103802686, 6930.861382772216, 7207.819804504929, 7233.819154521179, 7259.818504537428], "value": 5951.731035474024, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.75447186969126, 419.35838167603566, 452.36668663870586, 455.87399124163323, 466.24933856491066, 6151.292601350846, 6492.772752953648, 6552.737890484381, 6561.950087985923, 6584.045313429556, 5508.614396992212, 5885.705714714269, 6046.274447066358, 6239.438450539451, 6510.85141903172, 4437.933430998538, 4480.745391290436, 4958.652894297401, 4965.255211718235, 4994.550490455858, 4094.4267802507675, 4942.431620363668, 4975.303457515951, 4989.476105008976, 5004.049230646166, 3841.2701586698577, 4021.0, 4719.882002949919, 4737.289406589022, 4969.229769385751, 4041.6968727345566, 4123.773192474413, 4737.170995075865, 4737.715737055771, 4745.786439610226, 4140.37894315855, 4168.353905144708, 4192.664586833046, 4193.35003074524, 4196.7272127311935, 5258.816766227606, 6162.165403914205, 6168.383161683841, 6189.814305570795, 6202.4417802398, 5650.6892120933335, 5651.1523271509195, 5661.547076233891, 5680.4319568043275, 5736.651886806597, 6883.97105273678, 6892.827679308058, 6893.517453778238, 6910.827229319257, 6911.3779759821855, 4187.895302617429, 4759.4764575896625, 4764.0, 4777.116233496814, 4789.880252993668, 6136.478399336017, 7200.0, 7253.165885923134, 7285.599292038989, 7322.231165727629, 4305.935410968838, 4869.70781753096, 4885.902281954341, 4901.534354236342, 4901.852944411673, 4667.0, 4709.505501922317, 4910.705357678551, 4965.230389289666, 4967.627427942919, 5003.274525193827, 5380.681732975446, 5675.829725108253, 5703.572232082611, 5721.456461636138, 6292.779752708633, 6892.276310987374, 6912.308769123048, 6929.688164032631, 6977.89533157003, 1545.3046129241886, 1600.90180977687, 1692.5430133863788, 1699.9830001700072, 1700.3963592924333, 961.7066794627566, 1091.0671375973586, 1122.5509796081633, 1141.3209140561519, 1152.3201311226417, 5267.367915850046, 6220.720067596969, 6382.617042977344, 6390.84022899422, 6442.613443193423, 5500.1749737539685, 6423.197100362455, 6693.866122677519, 6927.480438967098, 7122.857542849218, 6114.419130182714, 6371.585846920028, 6382.266039405435, 6395.2732762153955, 6398.488120950313, 3158.1998260991013, 3590.4328405190413, 3601.7393912130547, 3700.093477098128, 3768.9057773555332, 5999.760009599654, 6027.7287522061615, 6058.727357268934, 7106.111736032996, 7319.41444684424, 7305.89041164383, 7312.488125831141, 7341.192468828424, 7344.30229128232, 7359.448041396917, 6719.764808231786, 6868.965655171693, 7279.963600181965, 7321.0, 7335.706571737178, 5651.519620832275, 6276.717547710273, 6793.150856142982, 7124.679389427384, 7144.035555199982, 5887.882242355215, 6486.6702326024, 6997.96501017512, 7035.507514473938, 7070.257622949722, 5840.883182336414, 6732.764353247613, 7250.746223882139, 7313.20826397536, 7336.889946650911, 5350.785968561214, 5464.890702186013, 5476.6166368355425, 5480.205370221375, 5512.09050506678, 6764.492663050192, 6903.0, 7195.964020179866, 7206.963965180141, 7210.315020073294, 5669.518090962314, 5829.970850145892, 5846.239988801599, 6482.698641982029, 6633.635150066792, 5704.286964129484, 6813.931860681329, 6827.317268273133, 6832.316768323128, 6860.0, 6648.966755166193, 6662.966685166543, 6663.36698013688, 6690.264070952288, 6721.798346049626, 6501.772437964649, 6528.510361722985, 6687.1975362956755, 6789.185297764102, 6867.313268673292, 6064.089237166273, 6104.938950610437, 6591.571547849375, 6592.110065141144, 6597.901031484435], "value": 5011.88659579654, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [688.3838964127109, 697.7522979342331, 760.6424980259279, 782.1005843280227, 864.0452300208268, 16450.753238701414, 17074.23165957532, 17426.912865435686, 17472.563185920342, 18245.817541824577, 18514.629707405842, 18605.0, 18764.71852922204, 18804.717929231072, 18824.717629235573, 1001.35412658835, 1029.3926583315842, 1031.9484025798718, 1032.4166845732138, 1037.6627596031283, 930.1163894300408, 956.579105193716, 1026.5277972132828, 1026.9948650256738, 1037.7301901505587, 1075.763332066944, 1075.9031687148156, 1077.3751224289892, 1077.6228320087985, 1087.190043417651, 708.0936401406208, 719.7588807749411, 1070.6680928912062, 1071.383954226319, 1092.814221582333, 538.2383926743653, 757.5909009135061, 765.9195784442609, 766.1227894061325, 777.3004296133469, 17430.912845435818, 17467.65064698699, 17593.56016099595, 17604.559886002826, 17625.471235862944, 16707.49877503677, 17871.463856084338, 17881.105944702842, 17988.460346189637, 18050.638987220183, 14220.928895355457, 14518.854811451856, 14748.557543273719, 14826.777598336033, 14912.254387280702, 3156.889508867179, 3636.636336366342, 3637.418013117888, 3641.3991691370893, 3643.653852883972, 111440.0, 138897.0, 146076.0, 148681.0, 149856.0, 11380.03269722083, 12421.503139874303, 13053.216806991612, 13443.529476468473, 13820.654483637989, 19661.410157695293, 25915.870420647778, 27068.32329191786, 27148.1855544334, 27167.456650866872, 7143.071400717978, 7532.13380461243, 7794.259545343183, 7847.764567062997, 7851.05787305527, 3611.4402267648557, 3628.2743451309843, 3739.1026153723456, 3740.251949610089, 3892.8637497687446, 4097.733647312915, 4254.808533615995, 4256.1487702459635, 4595.287730401793, 4978.452370239271, 1031.4327120083924, 1044.6500422358442, 1047.711879233217, 1050.553514756241, 1050.9369437833755, 597.9337847339993, 627.8273474794377, 650.7982525417136, 651.5146216068964, 661.5534514202894, 9872.60509579623, 11728.413579321, 11761.294322340687, 12159.392030398445, 12526.373681315898, 629.4806784402886, 648.8377905523619, 649.2630863969334, 662.9436497897636, 682.1098466501263, 5252.343457067866, 5793.073108302651, 5957.076653118774, 6063.757449701962, 6572.967135164389, 122821.0, 142696.28651856675, 148414.0, 149547.252263738, 151285.0, 115802.0, 120462.0, 123301.48593640747, 124536.0, 130197.0, 78744.0, 102764.0, 103142.0, 111381.0, 111789.88210117795, 10672.359658420519, 11146.885311468788, 12420.503179872703, 12574.30841303737, 13131.737365252451, 45651.315230270906, 46771.0, 48262.5173748258, 48386.75806620944, 49273.50726492689, 61455.6927215379, 62082.37917620766, 63882.0, 65556.34443655502, 66094.0, 16968.83031169722, 17495.737563936807, 17561.736573951144, 17695.115244237993, 17814.554636134202, 18466.35367762122, 19012.334568290044, 19078.0, 19199.0, 19209.0, 17131.0, 17604.119794010505, 17930.282788688306, 18356.173972171553, 18386.988715620235, 17347.392841251, 17479.213435395184, 17866.374676886768, 17906.373276935246, 17923.28306867763, 17070.061146637054, 17138.485845424657, 17217.82782172162, 17245.56886077858, 17302.826971730123, 11261.268017578832, 12004.619468761204, 12069.0, 12234.632961011184, 12375.69060773488, 10859.674209773719, 12896.161749486253, 13230.272335021282, 13466.0, 13741.76324130833], "value": 8778.985160052456, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1582.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 115.0, 125.0, 120.0, 111.0, 112.0, 113.0, 115.0, 115.0, 114.0], "value": 114.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 151.0, 159.0, 147.0, 150.0, 149.0, 137.0, 148.0, 189.0, 148.0], "value": 155.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 274.0, 258.0, 281.0, 211.0, 203.0, 201.0, 205.0, 236.0, 221.0], "value": 228.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 90.0, 89.0, 129.0, 57.0, 61.0, 59.0, 66.0, 111.0, 50.0], "value": 78.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 70.0, 114.0, 72.0, 70.0, 76.0, 109.0, 72.0, 72.0, 118.0], "value": 84.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 91.0, 90.0, 88.0, 90.0, 88.0, 91.0, 90.0, 89.0, 90.0], "value": 89.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 153.0, 98.0, 95.0, 145.0, 99.0, 144.0, 153.0, 96.0, 125.0], "value": 122.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 130.0, 89.0, 92.0, 92.0, 98.0, 90.0, 93.0, 90.0, 90.0], "value": 100.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 51.0, 50.0, 51.0, 51.0, 54.0, 53.0, 53.0, 52.0, 50.0], "value": 51.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 58.0, 55.0, 60.0, 65.0, 55.0, 56.0, 58.0, 58.0, 57.0], "value": 58.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 80.0, 82.0, 80.0, 83.0, 80.0, 81.0, 88.0, 81.0], "value": 81.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 143.0, 143.0, 132.0, 146.0, 181.0, 156.0, 159.0, 137.0, 161.0], "value": 149.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 183.0, 198.0, 243.0, 182.0, 177.0, 180.0, 183.0, 181.0, 182.0], "value": 188.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 76.0, 84.0, 78.0, 67.0, 57.0, 62.0, 93.0, 98.0, 102.0], "value": 78.5, "unit": "score"}]}]} 19:28:16 INFO - Return code: 0 19:28:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:28:16 INFO - # TBPL SUCCESS # 19:28:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:28:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:28:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:28:16 INFO - 2017-06-13 19:28:16.333 screenresolution[1682:14677] starting screenresolution argv=screenresolution get 19:28:16 INFO - 2017-06-13 19:28:16.335 screenresolution[1682:14677] Display 0: 1600x1200x32@60 19:28:16 INFO - 2017-06-13 19:28:16.341 screenresolution[1683:14680] starting screenresolution argv=screenresolution list 19:28:16 INFO - Available Modes on Display 0 19:28:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:28:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:28:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:28:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:28:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:28:16 INFO - Intel Iris: 19:28:16 INFO - Chipset Model: Intel Iris 19:28:16 INFO - Type: GPU 19:28:16 INFO - Bus: Built-In 19:28:16 INFO - VRAM (Dynamic, Max): 1536 MB 19:28:16 INFO - Vendor: Intel (0x8086) 19:28:16 INFO - Device ID: 0x0a2e 19:28:16 INFO - Revision ID: 0x0009 19:28:16 INFO - Displays: 19:28:16 INFO - Display: 19:28:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:28:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:28:16 INFO - Display Serial Number: 1600x1200 60 19:28:16 INFO - Main Display: Yes 19:28:16 INFO - Mirror: Off 19:28:16 INFO - Online: Yes 19:28:16 INFO - Rotation: Supported 19:28:16 INFO - Return code: 0 19:28:16 INFO - Running post-action listener: _resource_record_post_action 19:28:16 INFO - [mozharness: 2017-06-14 02:28:16.576457Z] Finished run-tests step (success) 19:28:16 INFO - Running post-run listener: _resource_record_post_run 19:28:16 INFO - Total resource usage - Wall time: 1259s; CPU: 53.0%; Read bytes: 34725376; Write bytes: 576753152; Read time: 230; Write time: 1762 19:28:16 INFO - TinderboxPrint: CPU usage
53.4% 19:28:16 INFO - TinderboxPrint: I/O read bytes / time
34,725,376 / 230 19:28:16 INFO - TinderboxPrint: I/O write bytes / time
576,753,152 / 1,762 19:28:16 INFO - TinderboxPrint: CPU idle
2,348.1 (46.6%) 19:28:16 INFO - TinderboxPrint: CPU system
1,022.8 (20.3%) 19:28:16 INFO - TinderboxPrint: CPU user
1,666.8 (33.1%) 19:28:16 INFO - TinderboxPrint: Swap in / out
283,373,568 / 0 19:28:16 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 253841920; Write bytes: 254912000; Read time: 19151; Write time: 550 19:28:16 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 19:28:16 INFO - run-tests - Wall time: 1237s; CPU: 53.0%; Read bytes: 30490112; Write bytes: 320579584; Read time: 203; Write time: 1206 19:28:17 INFO - Running post-run listener: _upload_blobber_files 19:28:17 INFO - Blob upload gear active. 19:28:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:28:17 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 19:28:17 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'] 19:28:17 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 19:28:17 INFO - (blobuploader) - INFO - Open directory for files ... 19:28:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:28:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:28:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:28:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:28:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:28:18 INFO - (blobuploader) - INFO - Done attempting. 19:28:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:28:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:28:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:28:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:28:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:28:19 INFO - (blobuploader) - INFO - Done attempting. 19:28:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:28:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:28:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:28:20 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:28:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:28:20 INFO - (blobuploader) - INFO - Done attempting. 19:28:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:28:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:28:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:28:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:28:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:28:22 INFO - (blobuploader) - INFO - Done attempting. 19:28:22 INFO - (blobuploader) - INFO - Iteration through files over. 19:28:22 INFO - Return code: 0 19:28:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:28:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:28:22 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d68c41652118620420bbee61756310375f334cd90bee725a3d926462d5a12adfe02db73c905202475f9ee6b74c26d39ad518888ecee110701201dda0ad1e6e6d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5c60a93eba234e64ad70d2fe2ef7e6da50bfc272dd1525d03c5422bd623f09f9268240739e83cb9b3a720c267f2f4b69ef45b96de907a6442fd04f0ef34e4c50", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0be848b7a6815206166f09226937c8fd3c2610de5e374cb5d1dea53a818376b9219ef96577ad9a6ec89ff30fc9c10c734837b5fe363d901ce721f19813137a4f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/26dd9b4adfca23308728250782fd10ca1c4d3d5f76200b518119aebed69f6a707a79a278b7a2551fc23939719019a14050400193ebbd540579b400e762d38f4c"} 19:28:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:28:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:28:22 INFO - Contents: 19:28:22 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d68c41652118620420bbee61756310375f334cd90bee725a3d926462d5a12adfe02db73c905202475f9ee6b74c26d39ad518888ecee110701201dda0ad1e6e6d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5c60a93eba234e64ad70d2fe2ef7e6da50bfc272dd1525d03c5422bd623f09f9268240739e83cb9b3a720c267f2f4b69ef45b96de907a6442fd04f0ef34e4c50", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0be848b7a6815206166f09226937c8fd3c2610de5e374cb5d1dea53a818376b9219ef96577ad9a6ec89ff30fc9c10c734837b5fe363d901ce721f19813137a4f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/26dd9b4adfca23308728250782fd10ca1c4d3d5f76200b518119aebed69f6a707a79a278b7a2551fc23939719019a14050400193ebbd540579b400e762d38f4c"} 19:28:22 INFO - Running post-run listener: copy_logs_to_upload_dir 19:28:22 INFO - Copying logs to upload dir... 19:28:22 INFO - mkdir: /builds/slave/test/build/upload/logs 19:28:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1340.047152 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 20 secs) (at 2017-06-13 19:28:22.618334) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-13 19:28:22.621928) ========= 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.uP27uZtczl/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.ajvLj454sv/Listeners TMPDIR=/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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/d68c41652118620420bbee61756310375f334cd90bee725a3d926462d5a12adfe02db73c905202475f9ee6b74c26d39ad518888ecee110701201dda0ad1e6e6d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5c60a93eba234e64ad70d2fe2ef7e6da50bfc272dd1525d03c5422bd623f09f9268240739e83cb9b3a720c267f2f4b69ef45b96de907a6442fd04f0ef34e4c50", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0be848b7a6815206166f09226937c8fd3c2610de5e374cb5d1dea53a818376b9219ef96577ad9a6ec89ff30fc9c10c734837b5fe363d901ce721f19813137a4f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/26dd9b4adfca23308728250782fd10ca1c4d3d5f76200b518119aebed69f6a707a79a278b7a2551fc23939719019a14050400193ebbd540579b400e762d38f4c"} build_url:https://queue.taskcluster.net/v1/task/W8qfCTz3TEa8B6WiBGA0Ow/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011186 build_url: 'https://queue.taskcluster.net/v1/task/W8qfCTz3TEa8B6WiBGA0Ow/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d68c41652118620420bbee61756310375f334cd90bee725a3d926462d5a12adfe02db73c905202475f9ee6b74c26d39ad518888ecee110701201dda0ad1e6e6d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5c60a93eba234e64ad70d2fe2ef7e6da50bfc272dd1525d03c5422bd623f09f9268240739e83cb9b3a720c267f2f4b69ef45b96de907a6442fd04f0ef34e4c50", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0be848b7a6815206166f09226937c8fd3c2610de5e374cb5d1dea53a818376b9219ef96577ad9a6ec89ff30fc9c10c734837b5fe363d901ce721f19813137a4f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/26dd9b4adfca23308728250782fd10ca1c4d3d5f76200b518119aebed69f6a707a79a278b7a2551fc23939719019a14050400193ebbd540579b400e762d38f4c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-13 19:28:22.656421) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 19:28:22.656728) ========= 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.uP27uZtczl/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.ajvLj454sv/Listeners TMPDIR=/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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.005020 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 19:28:22.705275) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-13 19:28:22.705581) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-13 19:28:22.705872) ========= ========= Total master_lag: 0.18 =========