builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0034 starttime: 1494853638.02 results: success (0) revision: df404e72597d2754290a95a18782ec31bc0027b3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-15 06:07:18.016819) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-15 06:07:18.017342) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-15 06:07:18.017683) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-15 06:07:18.045106) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 06:07:18.045547) ========= 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.A2NmusmDVI/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.kJR4iXT1Fx/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-15 06:07:18-- 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% 17.9M=0.001s 2017-05-15 06:07:18 (17.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.174128 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 06:07:18.244777) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 06:07:18.245223) ========= 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.A2NmusmDVI/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.kJR4iXT1Fx/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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.027494 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 06:07:18.300494) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-15 06:07:18.300875) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev df404e72597d2754290a95a18782ec31bc0027b3 --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 df404e72597d2754290a95a18782ec31bc0027b3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.A2NmusmDVI/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.kJR4iXT1Fx/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-15 06:07:18,412 truncating revision to first 12 chars 2017-05-15 06:07:18,412 Setting DEBUG logging. 2017-05-15 06:07:18,412 attempt 1/10 2017-05-15 06:07:18,412 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/df404e72597d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-15 06:07:19,332 unpacking tar archive at: mozilla-central-df404e72597d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.207530 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-15 06:07:19.531530) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-15 06:07:19.531900) ========= script_repo_revision: df404e72597d2754290a95a18782ec31bc0027b3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-15 06:07:19.532349) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-15 06:07:19.532657) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-15 06:07:19.552376) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-05-15 06:07:19.552689) ========= /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.A2NmusmDVI/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.kJR4iXT1Fx/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 06:07:19 INFO - MultiFileLogger online at 20170515 06:07:19 in /builds/slave/test 06:07:19 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 06:07:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:07:19 INFO - {'append_to_log': False, 06:07:19 INFO - 'base_work_dir': '/builds/slave/test', 06:07:19 INFO - 'blob_upload_branch': 'Firefox', 06:07:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:07:19 INFO - 'branch': 'Firefox', 06:07:19 INFO - 'buildbot_json_path': 'buildprops.json', 06:07:19 INFO - 'config_files': ('talos/mac_config.py',), 06:07:19 INFO - 'default_actions': ('clobber', 06:07:19 INFO - 'read-buildbot-config', 06:07:19 INFO - 'download-and-extract', 06:07:19 INFO - 'populate-webroot', 06:07:19 INFO - 'create-virtualenv', 06:07:19 INFO - 'install', 06:07:19 INFO - 'run-tests'), 06:07:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:07:19 INFO - 'download_minidump_stackwalk': True, 06:07:19 INFO - 'download_symbols': 'ondemand', 06:07:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:07:19 INFO - 'tooltool.py': '/tools/tooltool.py', 06:07:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:07:19 INFO - '/tools/misc-python/virtualenv.py')}, 06:07:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:07:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:07:19 INFO - 'gecko_profile': False, 06:07:19 INFO - 'gecko_profile_interval': 0, 06:07:19 INFO - 'installer_path': 'installer.exe', 06:07:19 INFO - 'log_level': 'info', 06:07:19 INFO - 'log_name': 'talos', 06:07:19 INFO - 'log_to_console': True, 06:07:19 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:07:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:07:19 INFO - 'opt_config_files': (), 06:07:19 INFO - 'pip_index': False, 06:07:19 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:07:19 INFO - 'cmd': ('bash', 06:07:19 INFO - '-c', 06:07:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:07:19 INFO - 'enabled': True, 06:07:19 INFO - 'halt_on_failure': False, 06:07:19 INFO - 'name': 'check_screen_resolution'},), 06:07:19 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:07:19 INFO - 'cmd': ('bash', 06:07:19 INFO - '-c', 06:07:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:07:19 INFO - 'enabled': True, 06:07:19 INFO - 'halt_on_failure': False, 06:07:19 INFO - 'name': 'check_screen_resolution'},), 06:07:19 INFO - 'run_cmd_checks_enabled': True, 06:07:19 INFO - 'suite': 'dromaeojs-e10s', 06:07:19 INFO - 'system_bits': '32', 06:07:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:07:19 INFO - 'title': 't-yosemite-r7-0034', 06:07:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:07:19 INFO - 'use_talos_json': True, 06:07:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:07:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:07:19 INFO - 'work_dir': 'build'} 06:07:19 INFO - [mozharness: 2017-05-15 13:07:19.782036Z] Running clobber step. 06:07:19 INFO - Running pre-action listener: _resource_record_pre_action 06:07:19 INFO - Running main action method: clobber 06:07:19 INFO - rmtree: /builds/slave/test/build 06:07:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:07:22 INFO - Running post-action listener: _resource_record_post_action 06:07:22 INFO - [mozharness: 2017-05-15 13:07:22.385762Z] Finished clobber step (success) 06:07:22 INFO - [mozharness: 2017-05-15 13:07:22.385892Z] Running read-buildbot-config step. 06:07:22 INFO - Running pre-action listener: _resource_record_pre_action 06:07:22 INFO - Running main action method: read_buildbot_config 06:07:22 INFO - Using buildbot properties: 06:07:22 INFO - { 06:07:22 INFO - "project": "", 06:07:22 INFO - "product": "firefox", 06:07:22 INFO - "who": "cbook@mozilla.com", 06:07:22 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 06:07:22 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 06:07:22 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 06:07:22 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 06:07:22 INFO - "stage_platform": "macosx64", 06:07:22 INFO - "basedir": "/builds/slave/test", 06:07:22 INFO - "buildnumber": 26, 06:07:22 INFO - "platform": "yosemite_r7", 06:07:22 INFO - "parent_task_id": "RNpQdgWsSoqEFN1nzzdpoA", 06:07:22 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 06:07:22 INFO - "slavebuilddir": "test", 06:07:22 INFO - "taskId": "HGTYAjymR4SH2fz1z8OZuw", 06:07:22 INFO - "branch": "mozilla-central", 06:07:22 INFO - "script_repo_revision": "production", 06:07:22 INFO - "revision": "df404e72597d2754290a95a18782ec31bc0027b3", 06:07:22 INFO - "slavename": "t-yosemite-r7-0034", 06:07:22 INFO - "repo_path": "mozilla-central" 06:07:22 INFO - } 06:07:22 INFO - Finding installer, test and symbols from parent task. 06:07:22 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HGTYAjymR4SH2fz1z8OZuw'}, attempt #1 06:07:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HGTYAjymR4SH2fz1z8OZuw'}, attempt #1 06:07:23 INFO - Task dependencies: fSFwh3jnSKW9FOho9EpHeQ 06:07:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RNpQdgWsSoqEFN1nzzdpoA'}, attempt #1 06:07:24 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/RNpQdgWsSoqEFN1nzzdpoA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 06:07:24 INFO - Set test_url: https://queue.taskcluster.net/v1/task/RNpQdgWsSoqEFN1nzzdpoA/artifacts/public/build/test_packages.json 06:07:24 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/RNpQdgWsSoqEFN1nzzdpoA/artifacts/public/build/target.crashreporter-symbols.zip 06:07:24 INFO - Running post-action listener: _resource_record_post_action 06:07:24 INFO - [mozharness: 2017-05-15 13:07:24.069938Z] Finished read-buildbot-config step (success) 06:07:24 INFO - [mozharness: 2017-05-15 13:07:24.070109Z] Running download-and-extract step. 06:07:24 INFO - Running pre-action listener: _resource_record_pre_action 06:07:24 INFO - Running main action method: download_and_extract 06:07:24 INFO - mkdir: /builds/slave/test/build/tests 06:07:24 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')]} 06:07:24 INFO - trying https://queue.taskcluster.net/v1/task/RNpQdgWsSoqEFN1nzzdpoA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 06:07:24 INFO - Downloading https://queue.taskcluster.net/v1/task/RNpQdgWsSoqEFN1nzzdpoA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 06:07:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RNpQdgWsSoqEFN1nzzdpoA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 06:07:25 INFO - Downloaded 1325 bytes. 06:07:25 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 06:07:25 INFO - Using the following test package requirements: 06:07:25 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:07:25 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 06:07:25 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 06:07:25 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:07:25 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 06:07:25 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:07:25 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 06:07:25 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:07:25 INFO - u'jsshell-mac.zip'], 06:07:25 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:07:25 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 06:07:25 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 06:07:25 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:07:25 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 06:07:25 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:07:25 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 06:07:25 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:07:25 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 06:07:25 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:07:25 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 06:07:25 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:07:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RNpQdgWsSoqEFN1nzzdpoA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 06:07:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RNpQdgWsSoqEFN1nzzdpoA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:07:25 INFO - Fetch https://queue.taskcluster.net/v1/task/RNpQdgWsSoqEFN1nzzdpoA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 06:07:26 INFO - Content-Length response header: 56564782 06:07:26 INFO - Bytes received: 56564782 06:07:45 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 06:07:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RNpQdgWsSoqEFN1nzzdpoA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 06:07:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RNpQdgWsSoqEFN1nzzdpoA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:07:45 INFO - Fetch https://queue.taskcluster.net/v1/task/RNpQdgWsSoqEFN1nzzdpoA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 06:07:47 INFO - Content-Length response header: 56564782 06:07:47 INFO - Bytes received: 56564782 06:08:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RNpQdgWsSoqEFN1nzzdpoA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 06:08:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RNpQdgWsSoqEFN1nzzdpoA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 06:08:05 INFO - Fetch https://queue.taskcluster.net/v1/task/RNpQdgWsSoqEFN1nzzdpoA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 06:08:06 INFO - Content-Length response header: 14410013 06:08:06 INFO - Bytes received: 14410013 06:08: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')]} 06:08:06 INFO - trying https://queue.taskcluster.net/v1/task/RNpQdgWsSoqEFN1nzzdpoA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 06:08:06 INFO - Downloading https://queue.taskcluster.net/v1/task/RNpQdgWsSoqEFN1nzzdpoA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 06:08:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RNpQdgWsSoqEFN1nzzdpoA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 06:08:09 INFO - Downloaded 75953927 bytes. 06:08:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RNpQdgWsSoqEFN1nzzdpoA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 06:08:09 INFO - mkdir: /builds/slave/test/properties 06:08:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:08:09 INFO - Writing to file /builds/slave/test/properties/build_url 06:08:09 INFO - Contents: 06:08:09 INFO - build_url:https://queue.taskcluster.net/v1/task/RNpQdgWsSoqEFN1nzzdpoA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 06:08:09 INFO - Running post-action listener: _resource_record_post_action 06:08:09 INFO - Running post-action listener: set_extra_try_arguments 06:08:09 INFO - [mozharness: 2017-05-15 13:08:09.134454Z] Finished download-and-extract step (success) 06:08:09 INFO - [mozharness: 2017-05-15 13:08:09.134610Z] Running populate-webroot step. 06:08:09 INFO - Running pre-action listener: _resource_record_pre_action 06:08:09 INFO - Running main action method: populate_webroot 06:08:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:08:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:08:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:08:09 INFO - '--tppagecycles', 06:08:09 INFO - '7'], 06:08:09 INFO - 'tests': ['tsvgm']}}, 06:08:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:08:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:08:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:08:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:08:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:08:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:08:09 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 06:08:09 INFO - 'talos_options': ['--disable-e10s'], 06:08:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:08:09 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 06:08:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:08:09 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 06:08:09 INFO - 'talos_options': ['--disable-e10s'], 06:08:09 INFO - 'tests': ['damp', 'tps']}, 06:08:09 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 06:08:09 INFO - 'tests': ['damp', 'tps']}, 06:08:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:08:09 INFO - 'tests': ['dromaeo_dom']}, 06:08:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:08:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:08:09 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 06:08:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 06:08:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:08:09 INFO - 'tests': ['a11yr', 06:08:09 INFO - 'ts_paint', 06:08:09 INFO - 'tpaint', 06:08:09 INFO - 'sessionrestore', 06:08:09 INFO - 'sessionrestore_no_auto_restore', 06:08:09 INFO - 'tabpaint']}, 06:08:09 INFO - 'other-e10s': {'tests': ['a11yr', 06:08:09 INFO - 'ts_paint', 06:08:09 INFO - 'tpaint', 06:08:09 INFO - 'sessionrestore', 06:08:09 INFO - 'sessionrestore_no_auto_restore', 06:08:09 INFO - 'tabpaint']}, 06:08:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:08:09 INFO - 'ts_paint', 06:08:09 INFO - 'tpaint', 06:08:09 INFO - 'sessionrestore', 06:08:09 INFO - 'sessionrestore_no_auto_restore', 06:08:09 INFO - 'tabpaint']}, 06:08:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:08:09 INFO - 'ts_paint', 06:08:09 INFO - 'tpaint', 06:08:09 INFO - 'sessionrestore', 06:08:09 INFO - 'sessionrestore_no_auto_restore', 06:08:09 INFO - 'tabpaint']}, 06:08:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:08:09 INFO - 'tests': ['a11yr', 06:08:09 INFO - 'ts_paint', 06:08:09 INFO - 'tpaint', 06:08:09 INFO - 'sessionrestore', 06:08:09 INFO - 'sessionrestore_no_auto_restore', 06:08:09 INFO - 'tabpaint']}, 06:08:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:08:09 INFO - 'tests': ['a11yr', 06:08:09 INFO - 'ts_paint', 06:08:09 INFO - 'tpaint', 06:08:09 INFO - 'sessionrestore', 06:08:09 INFO - 'sessionrestore_no_auto_restore', 06:08:09 INFO - 'tabpaint']}, 06:08:09 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 06:08:09 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 06:08:09 INFO - 'bloom_basic_ref']}, 06:08:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:08:09 INFO - 'tests': ['tsvgx', 06:08:09 INFO - 'tsvgr_opacity', 06:08:09 INFO - 'tart', 06:08:09 INFO - 'tscrollx', 06:08:09 INFO - 'cart', 06:08:09 INFO - 'tsvg_static']}, 06:08:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:08:09 INFO - 'tsvgr_opacity', 06:08:09 INFO - 'tart', 06:08:09 INFO - 'tscrollx', 06:08:09 INFO - 'cart', 06:08:09 INFO - 'tsvg_static']}, 06:08:09 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 06:08:09 INFO - 'talos_options': ['--disable-e10s'], 06:08:09 INFO - 'tests': ['tp5o']}, 06:08:09 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 06:08:09 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 06:08:09 INFO - 'talos_options': ['--disable-e10s', 06:08:09 INFO - '--xperf_path', 06:08:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:08:09 INFO - 'tests': ['tp5n']}, 06:08:09 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 06:08:09 INFO - 'talos_options': ['--xperf_path', 06:08:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:08:09 INFO - 'tests': ['tp5n']}}, 06:08:09 INFO - 'talos.zip': {'path': '', 06:08:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:08:09 INFO - Running post-action listener: _resource_record_post_action 06:08:09 INFO - [mozharness: 2017-05-15 13:08:09.156397Z] Finished populate-webroot step (success) 06:08:09 INFO - [mozharness: 2017-05-15 13:08:09.156541Z] Running create-virtualenv step. 06:08:09 INFO - Running pre-action listener: _resource_record_pre_action 06:08:09 INFO - Running main action method: create_virtualenv 06:08:09 INFO - Creating virtualenv /builds/slave/test/build/venv 06:08:09 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 06:08:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:08:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:08:09 INFO - Using real prefix '/tools/python27' 06:08:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:08:10 INFO - Installing distribute.............................................................................................................................................................................................done. 06:08:13 INFO - Installing pip.................done. 06:08:13 INFO - Return code: 0 06:08:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:08:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:08:13 INFO - Reading from file tmpfile_stdout 06:08:13 INFO - Output received: 06:08:13 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) 06:08:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:08:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:08:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:08:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:08:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:08:14 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 0x1044bfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044c3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8d04b4ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041cfd30>, '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 0x104183db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1041c4be8>, '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.A2NmusmDVI/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.kJR4iXT1Fx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 06:08:14 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 06:08:14 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 06:08:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.A2NmusmDVI/Render', 06:08:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:08:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:08:14 INFO - 'HOME': '/Users/cltbld', 06:08:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:08:14 INFO - 'LOGNAME': 'cltbld', 06:08:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:08:14 INFO - 'MOZ_NO_REMOTE': '1', 06:08:14 INFO - 'NO_EM_RESTART': '1', 06:08:14 INFO - 'PAGER': '/bin/cat', 06:08:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:08:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:08:14 INFO - 'PWD': '/builds/slave/test', 06:08:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:08:14 INFO - 'SHELL': '/bin/bash', 06:08:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kJR4iXT1Fx/Listeners', 06:08:14 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 06:08:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:08:14 INFO - 'USER': 'cltbld', 06:08:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:08:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:08:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:08:14 INFO - 'XPC_FLAGS': '0x0', 06:08:14 INFO - 'XPC_SERVICE_NAME': '0', 06:08:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:08:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:08:14 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 06:08:14 INFO - Cleaning up... 06:08:14 INFO - Return code: 0 06:08:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:08: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')]} 06:08:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:08: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')]} 06:08:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:08:14 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 0x1044bfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044c3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8d04b4ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041cfd30>, '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 0x104183db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1041c4be8>, '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.A2NmusmDVI/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.kJR4iXT1Fx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 06:08:14 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 06:08:14 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 06:08:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.A2NmusmDVI/Render', 06:08:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:08:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:08:14 INFO - 'HOME': '/Users/cltbld', 06:08:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:08:14 INFO - 'LOGNAME': 'cltbld', 06:08:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:08:14 INFO - 'MOZ_NO_REMOTE': '1', 06:08:14 INFO - 'NO_EM_RESTART': '1', 06:08:14 INFO - 'PAGER': '/bin/cat', 06:08:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:08:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:08:14 INFO - 'PWD': '/builds/slave/test', 06:08:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:08:14 INFO - 'SHELL': '/bin/bash', 06:08:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kJR4iXT1Fx/Listeners', 06:08:14 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 06:08:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:08:14 INFO - 'USER': 'cltbld', 06:08:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:08:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:08:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:08:14 INFO - 'XPC_FLAGS': '0x0', 06:08:14 INFO - 'XPC_SERVICE_NAME': '0', 06:08:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:08:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:08:14 INFO - Downloading/unpacking psutil>=3.1.1 06:08: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 06:08: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 06:08: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 06:08: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 06:08:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:08:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:08:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:08:17 INFO - Installing collected packages: psutil 06:08:17 INFO - Running setup.py install for psutil 06:08:17 INFO - building 'psutil._psutil_osx' extension 06:08:17 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 06:08:17 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 06:08:18 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 06:08:18 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 06:08:18 INFO - building 'psutil._psutil_posix' extension 06:08:18 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 06:08:18 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:08:18 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:08:18 INFO - ^ 06:08:18 INFO - 1 warning generated. 06:08:18 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 06:08:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:08:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:08:18 INFO - Successfully installed psutil 06:08:18 INFO - Cleaning up... 06:08:18 INFO - Return code: 0 06:08:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:08:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:08:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:08:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:08:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:08:18 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 0x1044bfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044c3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8d04b4ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041cfd30>, '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 0x104183db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1041c4be8>, '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.A2NmusmDVI/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.kJR4iXT1Fx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 06:08:18 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 06:08:18 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 06:08:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.A2NmusmDVI/Render', 06:08:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:08:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:08:18 INFO - 'HOME': '/Users/cltbld', 06:08:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:08:18 INFO - 'LOGNAME': 'cltbld', 06:08:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:08:18 INFO - 'MOZ_NO_REMOTE': '1', 06:08:18 INFO - 'NO_EM_RESTART': '1', 06:08:18 INFO - 'PAGER': '/bin/cat', 06:08:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:08:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:08:18 INFO - 'PWD': '/builds/slave/test', 06:08:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:08:18 INFO - 'SHELL': '/bin/bash', 06:08:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kJR4iXT1Fx/Listeners', 06:08:18 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 06:08:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:08:18 INFO - 'USER': 'cltbld', 06:08:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:08:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:08:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:08:18 INFO - 'XPC_FLAGS': '0x0', 06:08:18 INFO - 'XPC_SERVICE_NAME': '0', 06:08:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:08:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:08:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:08:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:08:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:08:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:08:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:08:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:08:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:08:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:08:20 INFO - Installing collected packages: mozsystemmonitor 06:08:20 INFO - Running setup.py install for mozsystemmonitor 06:08:20 INFO - Successfully installed mozsystemmonitor 06:08:20 INFO - Cleaning up... 06:08:20 INFO - Return code: 0 06:08:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:08:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:08:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:08:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:08:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:08:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1044bfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044c3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8d04b4ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041cfd30>, '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 0x104183db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1041c4be8>, '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.A2NmusmDVI/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.kJR4iXT1Fx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 06:08:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 06:08:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 06:08:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.A2NmusmDVI/Render', 06:08:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:08:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:08:20 INFO - 'HOME': '/Users/cltbld', 06:08:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:08:20 INFO - 'LOGNAME': 'cltbld', 06:08:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:08:20 INFO - 'MOZ_NO_REMOTE': '1', 06:08:20 INFO - 'NO_EM_RESTART': '1', 06:08:20 INFO - 'PAGER': '/bin/cat', 06:08:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:08:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:08:20 INFO - 'PWD': '/builds/slave/test', 06:08:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:08:20 INFO - 'SHELL': '/bin/bash', 06:08:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kJR4iXT1Fx/Listeners', 06:08:20 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 06:08:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:08:20 INFO - 'USER': 'cltbld', 06:08:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:08:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:08:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:08:20 INFO - 'XPC_FLAGS': '0x0', 06:08:20 INFO - 'XPC_SERVICE_NAME': '0', 06:08:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:08:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:08:20 INFO - Downloading/unpacking jsonschema==2.5.1 06:08:20 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 06:08:20 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 06:08:20 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 06:08:20 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 06:08:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:08:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:08:22 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 06:08:22 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 06:08:22 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 06:08:22 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 06:08:22 INFO - Downloading functools32-3.2.3-2.tar.gz 06:08:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:08:22 INFO - warning: no files found matching '*.txt' 06:08:22 INFO - no previously-included directories found matching 'build' 06:08:22 INFO - no previously-included directories found matching 'dist' 06:08:22 INFO - no previously-included directories found matching '.git*' 06:08:22 INFO - Installing collected packages: jsonschema, functools32 06:08:22 INFO - Running setup.py install for functools32 06:08:22 INFO - warning: no files found matching '*.txt' 06:08:22 INFO - no previously-included directories found matching 'build' 06:08:22 INFO - no previously-included directories found matching 'dist' 06:08:22 INFO - no previously-included directories found matching '.git*' 06:08:22 INFO - Successfully installed jsonschema functools32 06:08:22 INFO - Cleaning up... 06:08:22 INFO - Return code: 0 06:08:22 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:08:22 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')]} 06:08:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:08:22 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')]} 06:08:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:08:22 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 0x1044bfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044c3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8d04b4ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041cfd30>, '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 0x104183db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1041c4be8>, '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.A2NmusmDVI/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.kJR4iXT1Fx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 06:08:22 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 06:08:22 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 06:08:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.A2NmusmDVI/Render', 06:08:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:08:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:08:22 INFO - 'HOME': '/Users/cltbld', 06:08:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:08:22 INFO - 'LOGNAME': 'cltbld', 06:08:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:08:22 INFO - 'MOZ_NO_REMOTE': '1', 06:08:22 INFO - 'NO_EM_RESTART': '1', 06:08:22 INFO - 'PAGER': '/bin/cat', 06:08:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:08:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:08:22 INFO - 'PWD': '/builds/slave/test', 06:08:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:08:22 INFO - 'SHELL': '/bin/bash', 06:08:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kJR4iXT1Fx/Listeners', 06:08:22 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 06:08:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:08:22 INFO - 'USER': 'cltbld', 06:08:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:08:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:08:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:08:22 INFO - 'XPC_FLAGS': '0x0', 06:08:22 INFO - 'XPC_SERVICE_NAME': '0', 06:08:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:08:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:08:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:08:23 INFO - Cleaning up... 06:08:23 INFO - Return code: 0 06:08:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:08:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:08:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:08:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:08:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:08:23 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 0x1044bfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044c3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8d04b4ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041cfd30>, '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 0x104183db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1041c4be8>, '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.A2NmusmDVI/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.kJR4iXT1Fx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 06:08:23 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 06:08:23 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 06:08:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.A2NmusmDVI/Render', 06:08:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:08:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:08:23 INFO - 'HOME': '/Users/cltbld', 06:08:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:08:23 INFO - 'LOGNAME': 'cltbld', 06:08:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:08:23 INFO - 'MOZ_NO_REMOTE': '1', 06:08:23 INFO - 'NO_EM_RESTART': '1', 06:08:23 INFO - 'PAGER': '/bin/cat', 06:08:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:08:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:08:23 INFO - 'PWD': '/builds/slave/test', 06:08:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:08:23 INFO - 'SHELL': '/bin/bash', 06:08:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kJR4iXT1Fx/Listeners', 06:08:23 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 06:08:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:08:23 INFO - 'USER': 'cltbld', 06:08:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:08:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:08:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:08:23 INFO - 'XPC_FLAGS': '0x0', 06:08:23 INFO - 'XPC_SERVICE_NAME': '0', 06:08:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:08:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:08:23 INFO - Downloading/unpacking blobuploader==1.2.4 06:08:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:08:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:08:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:08:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:08:24 INFO - Downloading blobuploader-1.2.4.tar.gz 06:08:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:08:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:08:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:08:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:08:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:08:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:08:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:08:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:08:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:08:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:08:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:08:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:08:25 INFO - Downloading docopt-0.6.1.tar.gz 06:08:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:08:26 INFO - Installing collected packages: blobuploader, requests, docopt 06:08:26 INFO - Running setup.py install for blobuploader 06:08:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:08:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:08:26 INFO - Running setup.py install for requests 06:08:26 INFO - Running setup.py install for docopt 06:08:26 INFO - Successfully installed blobuploader requests docopt 06:08:26 INFO - Cleaning up... 06:08:26 INFO - Return code: 0 06:08:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:08:26 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')]} 06:08:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:08:26 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')]} 06:08:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:08:26 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 0x1044bfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044c3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8d04b4ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041cfd30>, '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 0x104183db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1041c4be8>, '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.A2NmusmDVI/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.kJR4iXT1Fx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 06:08:26 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 06:08:26 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 06:08:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.A2NmusmDVI/Render', 06:08:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:08:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:08:26 INFO - 'HOME': '/Users/cltbld', 06:08:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:08:26 INFO - 'LOGNAME': 'cltbld', 06:08:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:08:26 INFO - 'MOZ_NO_REMOTE': '1', 06:08:26 INFO - 'NO_EM_RESTART': '1', 06:08:26 INFO - 'PAGER': '/bin/cat', 06:08:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:08:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:08:26 INFO - 'PWD': '/builds/slave/test', 06:08:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:08:26 INFO - 'SHELL': '/bin/bash', 06:08:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kJR4iXT1Fx/Listeners', 06:08:26 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 06:08:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:08:26 INFO - 'USER': 'cltbld', 06:08:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:08:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:08:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:08:26 INFO - 'XPC_FLAGS': '0x0', 06:08:26 INFO - 'XPC_SERVICE_NAME': '0', 06:08:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:08:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:08:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:08:27 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-_4FyWc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:08:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:08:27 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-TtzWv3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:08:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:08:27 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-xsmRr5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:08:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:08:27 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-05Dx9H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:08:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:08:27 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-FyvAWx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:08:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:08:27 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-bWspRm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:08:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:08:27 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-naQbVC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:08:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:08:27 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-3_I_PR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:08:28 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-fgXzxM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:08:28 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-RcSdDV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:08:28 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-g0MyhJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:08:28 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-94qyoe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:08:28 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-Qi0YD6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:08:28 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-6CIyYe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:08:28 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-GtvhTO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:08:28 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-M7TBsH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:08:28 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-ClyhKc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:08:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:08:29 INFO - Running setup.py install for manifestparser 06:08:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:08:29 INFO - Running setup.py install for mozcrash 06:08:29 INFO - Running setup.py install for mozdebug 06:08:29 INFO - Running setup.py install for mozdevice 06:08:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:08:29 INFO - Running setup.py install for mozfile 06:08:29 INFO - Running setup.py install for mozhttpd 06:08:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:08:29 INFO - Running setup.py install for mozinfo 06:08:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:08:30 INFO - Running setup.py install for mozInstall 06:08:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:08:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:08:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:08:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:08:30 INFO - Running setup.py install for mozleak 06:08:30 INFO - Running setup.py install for mozlog 06:08:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:08:30 INFO - Running setup.py install for moznetwork 06:08:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:08:30 INFO - Running setup.py install for mozprocess 06:08:30 INFO - Running setup.py install for mozprofile 06:08:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:08:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:08:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:08:31 INFO - Running setup.py install for mozrunner 06:08:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:08:31 INFO - Running setup.py install for mozscreenshot 06:08:31 INFO - Running setup.py install for moztest 06:08:31 INFO - Running setup.py install for mozversion 06:08:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:08:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:08:31 INFO - Cleaning up... 06:08:31 INFO - Return code: 0 06:08:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:08:31 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')]} 06:08:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:08:31 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')]} 06:08:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:08:31 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 0x1044bfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044c3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8d04b4ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041cfd30>, '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 0x104183db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1041c4be8>, '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.A2NmusmDVI/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.kJR4iXT1Fx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 06:08:31 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 06:08:31 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 06:08:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.A2NmusmDVI/Render', 06:08:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:08:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:08:31 INFO - 'HOME': '/Users/cltbld', 06:08:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:08:31 INFO - 'LOGNAME': 'cltbld', 06:08:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:08:31 INFO - 'MOZ_NO_REMOTE': '1', 06:08:31 INFO - 'NO_EM_RESTART': '1', 06:08:31 INFO - 'PAGER': '/bin/cat', 06:08:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:08:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:08:31 INFO - 'PWD': '/builds/slave/test', 06:08:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:08:31 INFO - 'SHELL': '/bin/bash', 06:08:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kJR4iXT1Fx/Listeners', 06:08:31 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 06:08:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:08:31 INFO - 'USER': 'cltbld', 06:08:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:08:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:08:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:08:31 INFO - 'XPC_FLAGS': '0x0', 06:08:31 INFO - 'XPC_SERVICE_NAME': '0', 06:08:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:08:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:08:32 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-9k9fmC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:08:32 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)) 06:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:08:32 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-H4bkao-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:08:32 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)) 06:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:08:32 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-GPAouF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:08:32 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)) 06:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:08:32 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-sSU2QI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:08:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:08:32 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-awkFov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:08:32 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)) 06:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:08:32 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-Ut1SVp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:08:32 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)) 06:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:08:32 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-aiYWZp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:08:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 06:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:08:32 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-00B5jz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:08:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 06:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:08:32 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-galoBc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:08:32 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)) 06:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:08:32 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-wDesA5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:08:33 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)) 06:08:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:08:33 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-KiIRow-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:08:33 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)) 06:08:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:08:33 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-yqAnZm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:08:33 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)) 06:08:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:08:33 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-4fdH1H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:08:33 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)) 06:08:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:08:33 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-X2ma43-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:08:33 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)) 06:08:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:08:33 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-DPUoAD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:08:33 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)) 06:08:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:08:33 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-zOladU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:08:33 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)) 06:08:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:08:33 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-G06Cf8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:08:33 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)) 06:08:33 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)) 06:08:33 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)) 06:08:33 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)) 06:08:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:08:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:08:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:08:33 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 06:08:33 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 06:08:33 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 06:08:33 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 06:08:35 INFO - Downloading blessings-1.6.tar.gz 06:08:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:08:35 INFO - Installing collected packages: blessings 06:08:35 INFO - Running setup.py install for blessings 06:08:35 INFO - Successfully installed blessings 06:08:35 INFO - Cleaning up... 06:08:35 INFO - Return code: 0 06:08:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:08:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:08:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:08:35 INFO - Reading from file tmpfile_stdout 06:08:35 INFO - Current package versions: 06:08:35 INFO - blessings == 1.6 06:08:35 INFO - blobuploader == 1.2.4 06:08:35 INFO - docopt == 0.6.1 06:08:35 INFO - functools32 == 3.2.3-2 06:08:35 INFO - jsonschema == 2.5.1 06:08:35 INFO - manifestparser == 1.1 06:08:35 INFO - mozInstall == 1.12 06:08:35 INFO - mozcrash == 1.0 06:08:35 INFO - mozdebug == 0.1 06:08:35 INFO - mozdevice == 0.50 06:08:35 INFO - mozfile == 1.2 06:08:35 INFO - mozhttpd == 0.7 06:08:35 INFO - mozinfo == 0.9 06:08:35 INFO - mozleak == 0.1 06:08:35 INFO - mozlog == 3.4 06:08:35 INFO - moznetwork == 0.27 06:08:35 INFO - mozprocess == 0.25 06:08:35 INFO - mozprofile == 0.28 06:08:35 INFO - mozrunner == 6.13 06:08:35 INFO - mozscreenshot == 0.1 06:08:35 INFO - mozsystemmonitor == 0.3 06:08:35 INFO - moztest == 0.8 06:08:35 INFO - mozversion == 1.4 06:08:35 INFO - psutil == 3.1.1 06:08:35 INFO - requests == 1.2.3 06:08:35 INFO - wsgiref == 0.1.2 06:08:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:08:35 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')]} 06:08:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:08:35 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')]} 06:08:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:08:35 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 0x1044bfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044c3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8d04b4ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041cfd30>, '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 0x104183db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1041c4be8>, '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.A2NmusmDVI/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.kJR4iXT1Fx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 06:08:35 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 06:08:35 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 06:08:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.A2NmusmDVI/Render', 06:08:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:08:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:08:35 INFO - 'HOME': '/Users/cltbld', 06:08:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:08:35 INFO - 'LOGNAME': 'cltbld', 06:08:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:08:35 INFO - 'MOZ_NO_REMOTE': '1', 06:08:35 INFO - 'NO_EM_RESTART': '1', 06:08:35 INFO - 'PAGER': '/bin/cat', 06:08:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:08:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:08:35 INFO - 'PWD': '/builds/slave/test', 06:08:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:08:35 INFO - 'SHELL': '/bin/bash', 06:08:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kJR4iXT1Fx/Listeners', 06:08:35 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 06:08:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:08:35 INFO - 'USER': 'cltbld', 06:08:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:08:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:08:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:08:35 INFO - 'XPC_FLAGS': '0x0', 06:08:35 INFO - 'XPC_SERVICE_NAME': '0', 06:08:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:08:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:08:36 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)) 06:08:36 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)) 06:08:36 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)) 06:08:36 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)) 06:08:36 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)) 06:08:36 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)) 06:08:36 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)) 06:08:36 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)) 06:08:36 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)) 06:08:36 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)) 06:08:36 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)) 06:08:36 INFO - Cleaning up... 06:08:36 INFO - Return code: 0 06:08:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:08:36 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')]} 06:08:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:08:36 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')]} 06:08:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:08:36 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 0x1044bfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044c3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8d04b4ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041cfd30>, '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 0x104183db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1041c4be8>, '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.A2NmusmDVI/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.kJR4iXT1Fx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 06:08:36 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 06:08:36 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 06:08:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.A2NmusmDVI/Render', 06:08:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:08:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:08:36 INFO - 'HOME': '/Users/cltbld', 06:08:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:08:36 INFO - 'LOGNAME': 'cltbld', 06:08:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:08:36 INFO - 'MOZ_NO_REMOTE': '1', 06:08:36 INFO - 'NO_EM_RESTART': '1', 06:08:36 INFO - 'PAGER': '/bin/cat', 06:08:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:08:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:08:36 INFO - 'PWD': '/builds/slave/test', 06:08:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:08:36 INFO - 'SHELL': '/bin/bash', 06:08:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kJR4iXT1Fx/Listeners', 06:08:36 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 06:08:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:08:36 INFO - 'USER': 'cltbld', 06:08:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:08:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:08:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:08:36 INFO - 'XPC_FLAGS': '0x0', 06:08:36 INFO - 'XPC_SERVICE_NAME': '0', 06:08:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:08:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:08:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:08:36 INFO - Cleaning up... 06:08:36 INFO - Return code: 0 06:08:36 INFO - Running post-action listener: _resource_record_post_action 06:08:36 INFO - Running post-action listener: _start_resource_monitoring 06:08:36 INFO - Starting resource monitoring. 06:08:36 INFO - [mozharness: 2017-05-15 13:08:36.560433Z] Finished create-virtualenv step (success) 06:08:36 INFO - [mozharness: 2017-05-15 13:08:36.561452Z] Running install step. 06:08:36 INFO - Running pre-action listener: _resource_record_pre_action 06:08:36 INFO - Running main action method: install 06:08:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:08:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:08:36 INFO - Reading from file tmpfile_stdout 06:08:36 INFO - Detecting whether we're running mozinstall >=1.0... 06:08:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:08:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:08:36 INFO - Reading from file tmpfile_stdout 06:08:36 INFO - Output received: 06:08:36 INFO - Usage: mozinstall [options] installer 06:08:36 INFO - Options: 06:08:36 INFO - -h, --help show this help message and exit 06:08:36 INFO - -d DEST, --destination=DEST 06:08:36 INFO - Directory to install application into. [default: 06:08:36 INFO - "/builds/slave/test"] 06:08:36 INFO - --app=APP Application being installed. [default: firefox] 06:08:36 INFO - mkdir: /builds/slave/test/build/application 06:08:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 06:08:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 06:08:58 INFO - Reading from file tmpfile_stdout 06:08:58 INFO - Output received: 06:08:58 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 06:08:58 INFO - Running post-action listener: _resource_record_post_action 06:08:58 INFO - [mozharness: 2017-05-15 13:08:58.087993Z] Finished install step (success) 06:08:58 INFO - [mozharness: 2017-05-15 13:08:58.088127Z] Running run-tests step. 06:08:58 INFO - Running pre-action listener: _resource_record_pre_action 06:08:58 INFO - Running main action method: run_tests 06:08:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:08:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:08:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:08:58 INFO - 2017-05-15 06:08:58.101 screenresolution[1623:7313] starting screenresolution argv=screenresolution get 06:08:58 INFO - 2017-05-15 06:08:58.104 screenresolution[1623:7313] Display 0: 1600x1200x32@60 06:08:58 INFO - 2017-05-15 06:08:58.109 screenresolution[1624:7315] starting screenresolution argv=screenresolution list 06:08:58 INFO - Available Modes on Display 0 06:08:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:08:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:08:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:08:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:08:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:08:58 INFO - Intel Iris: 06:08:58 INFO - Chipset Model: Intel Iris 06:08:58 INFO - Type: GPU 06:08:58 INFO - Bus: Built-In 06:08:58 INFO - VRAM (Dynamic, Max): 1536 MB 06:08:58 INFO - Vendor: Intel (0x8086) 06:08:58 INFO - Device ID: 0x0a2e 06:08:58 INFO - Revision ID: 0x0009 06:08:58 INFO - Displays: 06:08:58 INFO - Display: 06:08:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:08:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:08:58 INFO - Display Serial Number: 1600x1200 60 06:08:58 INFO - Main Display: Yes 06:08:58 INFO - Mirror: Off 06:08:58 INFO - Online: Yes 06:08:58 INFO - Rotation: Supported 06:08:58 INFO - Return code: 0 06:08:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:08:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:08:58 INFO - Python 2.7.3 06:08:58 INFO - Return code: 0 06:08:58 INFO - grabbing minidump binary from tooltool 06:08:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:08:58 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 0x1041cfd30>, '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 0x104183db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1041c4be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:08:58 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 06:08:58 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 06:08:58 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 06:08:58 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:08:58 INFO - Return code: 0 06:08:58 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:08:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:08:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:08:58 INFO - ENV: RUST_BACKTRACE is now 1 06:08:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:08:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:08:58 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/RNpQdgWsSoqEFN1nzzdpoA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0034', '--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 06:08:58 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/RNpQdgWsSoqEFN1nzzdpoA/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0034 --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 06:08:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.A2NmusmDVI/Render', 06:08:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:08:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:08:58 INFO - 'HOME': '/Users/cltbld', 06:08:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:08:58 INFO - 'LOGNAME': 'cltbld', 06:08:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:08:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:08:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:08:58 INFO - 'MOZ_NO_REMOTE': '1', 06:08:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:08:58 INFO - 'NO_EM_RESTART': '1', 06:08:58 INFO - 'PAGER': '/bin/cat', 06:08:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:08:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:08:58 INFO - 'PWD': '/builds/slave/test', 06:08:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:08:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:08:58 INFO - 'RUST_BACKTRACE': '1', 06:08:58 INFO - 'SHELL': '/bin/bash', 06:08:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kJR4iXT1Fx/Listeners', 06:08:58 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 06:08:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:08:58 INFO - 'USER': 'cltbld', 06:08:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:08:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:08:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:08:58 INFO - 'XPC_FLAGS': '0x0', 06:08:58 INFO - 'XPC_SERVICE_NAME': '0', 06:08:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:08:58 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/RNpQdgWsSoqEFN1nzzdpoA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0034', '--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 06:08:58 INFO - mozversion application_buildid: 20170515050616 06:08:58 INFO - mozversion application_changeset: df404e72597d2754290a95a18782ec31bc0027b3 06:08:58 INFO - mozversion application_display_name: Nightly 06:08:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:08:58 INFO - mozversion application_name: Firefox 06:08:58 INFO - mozversion application_remotingname: firefox 06:08:58 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 06:08:58 INFO - mozversion application_vendor: Mozilla 06:08:58 INFO - mozversion application_version: 55.0a1 06:08:58 INFO - mozversion platform_buildid: 20170515050616 06:08:58 INFO - mozversion platform_changeset: df404e72597d2754290a95a18782ec31bc0027b3 06:08:58 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 06:08:58 INFO - mozversion platform_version: 55.0a1 06:08:58 INFO - using testdate: 1494853738 06:08:58 INFO - actual date: 1494853738 06:08:58 INFO - starting webserver on 'localhost:49219' 06:08:58 INFO - SUITE-START | Running 2 tests 06:08:58 INFO - TEST-START | dromaeo_css 06:08:58 INFO - operating with platform_type : mac_ 06:08:58 INFO - Initialising browser for dromaeo_css test... 06:08:58 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmp0RlXji/profile http://localhost:49219/getInfo.html) 06:08:59 INFO - PID 1634 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2932: String.localeCompare is deprecated; use String.prototype.localeCompare instead 06:09:01 INFO - PID 1634 | 2017-05-15 06:09:01.560 plugin-container[1636:7496] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x973f, name = 'com.apple.tsm.portname' 06:09:01 INFO - PID 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:09:01 INFO - PID 1634 | 2017-05-15 06:09:01.565 plugin-container[1636:7496] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c13, name = 'com.apple.CFPasteboardClient' 06:09:01 INFO - PID 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:09:01 INFO - PID 1634 | 2017-05-15 06:09:01.565 plugin-container[1636:7496] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:09:01 INFO - PID 1634 | __metrics Screen width/height:1600/1200 06:09:01 INFO - PID 1634 | colorDepth:24 06:09:01 INFO - PID 1634 | Browser inner width/height: 1024/691 06:09:01 INFO - PID 1634 | __metrics 06:09:01 INFO - PID 1634 | 2017-05-15 06:09:01.988 plugin-container[1637:7544] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 06:09:01 INFO - PID 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:09:01 INFO - PID 1634 | 2017-05-15 06:09:01.988 plugin-container[1637:7544] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x990b, name = 'com.apple.CFPasteboardClient' 06:09:01 INFO - PID 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:09:01 INFO - PID 1634 | 2017-05-15 06:09:01.988 plugin-container[1637:7544] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:09:02 INFO - TEST-INFO | 1634: exit 0 06:09:02 INFO - Browser initialized. 06:09:02 INFO - Running cycle 1/1 for dromaeo_css test... 06:09:02 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmp0RlXji/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:09:04 INFO - PID 1638 | 2017-05-15 06:09:04.598 plugin-container[1639:7649] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x923f, name = 'com.apple.tsm.portname' 06:09:04 INFO - PID 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:09:04 INFO - PID 1638 | 2017-05-15 06:09:04.599 plugin-container[1639:7649] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x971b, name = 'com.apple.CFPasteboardClient' 06:09:04 INFO - PID 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:09:04 INFO - PID 1638 | 2017-05-15 06:09:04.599 plugin-container[1639:7649] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:09:05 INFO - PID 1638 | 2017-05-15 06:09:05.317 plugin-container[1640:7694] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9043, name = 'com.apple.tsm.portname' 06:09:05 INFO - PID 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:09:05 INFO - PID 1638 | 2017-05-15 06:09:05.318 plugin-container[1640:7694] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4317, name = 'com.apple.CFPasteboardClient' 06:09:05 INFO - PID 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:09:05 INFO - PID 1638 | 2017-05-15 06:09:05.318 plugin-container[1640:7694] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:09:14 INFO - PID 1638 | RSS: Main: 234553344 06:09:14 INFO - PID 1638 | 06:12:17 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49219/tests/dromaeo/cssquery-ext.html) 06:12:19 INFO - PID 1638 | RSS: Main: 255688704 06:12:19 INFO - PID 1638 | 06:15:21 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-ext.html (next: http://localhost:49219/tests/dromaeo/cssquery-jquery.html) 06:15:22 INFO - PID 1638 | RSS: Main: 264785920 06:15:22 INFO - PID 1638 | 06:18:24 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49219/tests/dromaeo/cssquery-mootools.html) 06:18:24 INFO - PID 1638 | RSS: Main: 268775424 06:18:24 INFO - PID 1638 | 06:21:26 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49219/tests/dromaeo/cssquery-prototype.html) 06:21:27 INFO - PID 1638 | RSS: Main: 266964992 06:21:27 INFO - PID 1638 | 06:24:29 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49219/tests/dromaeo/cssquery-yui.html) 06:24:30 INFO - PID 1638 | RSS: Main: 268025856 06:24:30 INFO - PID 1638 | 06:27:32 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-yui.html (next: http://localhost:49219/tests/dromaeo/cssquery-dojo.html) 06:27:32 INFO - PID 1638 | RSS: Main: 272371712 06:27:32 INFO - PID 1638 | 06:27:32 INFO - PID 1638 | __start_tp_report 06:27:32 INFO - PID 1638 | _x_x_mozilla_page_load 06:27:32 INFO - PID 1638 | _x_x_mozilla_page_load_details 06:27:32 INFO - PID 1638 | |i|pagename|runs| 06:27:32 INFO - PID 1638 | |0;dojo.html;2709.2278700570337;3096.721295083442;3147.2446612812932;3170.476871316233;3205.246767009754;5176.50823171799;5296.205569164623;5421.972890135548;5451.127819548873;5460.699661518614;4088.8977775555627;4652.906941861161;5367.194920761887;5394.136938089907;5419.647722898009;4759.095771803363;4774.188387974053;4802.303665968432;4910.459849416561;4980.377452818398;4864.14877396456;4886.633502487328;4901.65688401812;4943.456219815817;4943.678660887049;4706.647001474886;4850.102730994759;4912.164931961575;4919.040787046527;4923.236898280772;4239.618434340909;4804.519548045202;4868.099401610678;4917.213245880677;4958.603311735071;4787.521247875219;4816.759162041884;4824.903501929977;4830.879228019293;4834.879128021792;5022.096022715909;5338.386085600167;5382.3541175059245;5414.052540805383;5498.560115190815;5790.420957904218;5996.4603185713495;6018.729157187893;6046.637201767908;6074.696265186767;5981.671008094553;6002.849928751816;6032.125341825455;6040.426159514839;6045.72794224261;5419.241306217133;5577.442255774471;5578.9163162552595;5591.077472217079;5697.886042279131;6119.724612392453;6132.722655450988;6139.47814435769;6144.941444616104;6151.323354431009;4137.586241375899;5891.528677705859;5979.133025711248;5989.041753319448;6038.486728628028;5831.125331200354;5968.134620480006;6035.849103772441;6054.878902421927;6072.787452439144;5856.0630299151935;5950.732217050157;5952.5833191676165;5953.523718102541;5965.075413310944;5953.255843019622;5993.310769261503;6015.5488338373925;6036.63780173191;6061.484773794277;4814.061258054716;4837.9032419351415;4858.17411040124;4890.437599676083;4893.975530122327;4231.915361692749;4267.295896177158;4283.785810709452;4290.50659174199;4333.025069359369;5519.558435325244;5613.915791263134;5647.830565083054;5736.541076713853;5815.3021637402935;5973.581849270597;5995.160677505153;6066.75732970685;6122.234720659918;6132.938670613326;5664.915026274609;5701.144828275709;5739.655620662774;5743.167240750068;5753.568482363839;5083.779892825695;5263.947360526346;5282.788688452419;5312.442193569696;5320.973395133;5973.462388384893;6071.574989750852;6106.175666284995;6140.54698561739;6148.1699970504305;5985.521158307238;6015.248093988252;6057.18228039209;6073;6113.510919126548;5253.844154286051;6113.172264937315;6115.301188036123;6117.705611546597;6208.286047104641;4612.031473390625;5985.102234664835;6005.6396616203165;6038.788642397671;6043.425874541911;4939.333190019302;5944.702764861826;6071.817845464643;6082.47577255434;6112.510999120149;5667.801626943037;5998.430149135826;6000.669963152069;6033.728482218223;6043.8186854394435;4556.362109304634;5870.0607902735355;5944.524438045032;5997.970010149921;6000.489958353589;4857;5616.775328986958;5863.530917526502;5908.7045647718305;5948.851278718067;5874.177615133799;5875.911861321998;5892.027815410581;5894.233749612523;5912.940870591411;4603.240465323184;5394.433584473731;5435.320584926884;5813.273340832396;5869.589128760946 06:27:32 INFO - PID 1638 | |1;ext.html;722.4870342057139;750.2160242546538;790.1545346479265;1060.475064842902;1142.6229344316375;37360.626393736056;38404.23191536168;38512.22975540488;38661.613383866184;38671.453141874365;38368.232635347274;38485.42271865924;38795.03012424691;38935.22129557407;38978.61021389785;7396.260373962587;7580.886286705704;7947.642356093989;8120.959395203015;8162.551059691715;8024.43828931975;8074.71738489153;8168.591570421455;8188.713395031175;8199.672013119469;8169.550674712888;8175.918240817575;8207;8213.589320533949;8214.63034163461;8128.918710812875;8133.593320334019;8163.836723265502;8172.67309307627;8194.467359621605;7904.130545639975;7973.760787176394;7980.04239491259;7989.161138080476;8003.399745019147;34110.829445852614;36044.91865244047;36251.912442626766;36257.09357266063;36455.4531682025;3494.702950249232;3495.405781017233;3516.613172551017;3521.3133438979407;3526.400511912981;29221.85389073041;29631.814727411092;29782.702172978425;29786.55320170199;29811.70188298111;3000.9549856752164;3043.3456806786426;3050.0392376401364;3052.9847350763325;3056.877724891002;232130;246362;246370;299078;300007;44957.55042449599;45244;45283;45309.86725331893;45406.77296613496;46467;47078.529214707414;47167;47184.52815471801;47223.87492562843;13797.724045519033;13906.513272035632;13911.79132313016;13917.512887048904;13943.860561394458;11335.376554289429;11349.659510214708;11362.375069371265;11375.829362559569;11406.771864562828;12816.359182041047;12946.482140714454;12955.740885182244;12981.740365192643;13055;5650.406707295757;5665.065264231355;5671.206031155559;5683.573731970118;5699.401562835926;877.7322916510526;903.6475774447971;910.3672947301694;930.9906900931039;952.0859974424629;23485.530289394115;23498.882505587364;23683.881580592333;23798.76201238;23915.40211494692;1029.6859457865423;1044.9216308776872;1047.522226208979;1050.579768092769;1063.160103518212;11653.941730291293;12163.644032053951;12211.606616949537;12231.571894983634;12263.080268979862;286948.5652571766;288559;290671;291014;291326;51186.48813511892;51565.484345156816;51674.22488662673;51846;51867.481325186265;1397.329281944652;1423.1247782613618;1432.0333774702242;1439.5969128643997;1450.1589078334541;19563.804361956198;20969.164742147394;23053.65419518675;23118.537629246985;23751.52496950086;36418.63581364152;36440;36446;36538.99999999894;36634.8168259157;230488;238068.8096559506;254959;255796;256276;31236.9067082651;31450.89921852724;32063.839680801448;32322.35355292834;33265.83367083149;41155;41696.95757606084;43674.56325436705;43887.56112438835;44001;27355;27942.44115117727;28032.439351212455;28449.146525603435;28529.001484948756;21281.78718212798;27211.86394068017;27801.582976255777;28036;28127.57808632913;35432.82283588566;35494.64505354913;35620.82189589035;35961;36170.27659446744;23379;24989.875455604906;27598.034068807494;28704.18481953772;28871.556422179227;21937.890310548344;23412;23690.881545592852;23818.642720359556;23963.640545391485 06:27:32 INFO - PID 1638 | |2;jquery.html;393.9684825213983;397.6520544523542;398.3486998757031;401.66862338570684;426.2030003892721;16702.164891755394;20274;20537.281195158135;20750.48123796906;21107.788922110773;20782;21247.02541644171;21413.14347426105;21555.922203889782;21724.13103475863;5820.417958204177;5904;5950.5834591578605;5961.314448838374;5985.491233245158;5644.181593668915;5825.242718446596;5848.18706201079;5921.230240068807;5939.940600594004;5813.6802475863815;5819.67991760453;5824.592278540504;5828.941710582903;5834.620749651281;5509.283793106911;5707.942920570783;5757.42425757423;5790.71046447676;5842.006858833987;5138.691678499302;5146.0479811235045;5151.8454446366295;5158.561522270612;5170.741462926839;19918.904460254682;24353.512929741482;25734;26172;26462;2062.5462398272357;2097.7168082308845;2105.8525903186783;2108.515041540439;2112.8309735221146;12885.935570322183;14284.92857535716;16513.357862742025;17101.10643649597;17415.47753567395;2015.3651599746179;2017.7489956227087;2079.043639925636;2081.916723331065;2084.4997200671883;109543;122632.5473490534;140016;140232;142587.86118208338;11322.886771132184;11639.6508104757;11743.471543780379;11947.238284751824;11951.402429878472;15378;15417.922910385376;15432.768508472382;15530.689386212212;15860;7027.262137475594;7408.444366672521;8336.124706905688;8360.623771930155;8508.532030738244;6930.826729331706;7420.406367490587;7830.412719046094;7866.410019248579;7950.6422211000645;7432.145303290083;7462.843259294818;7569.621518924032;7582.734604288823;7606.543607383574;2146.903389347483;2149.8172655324474;2157.6547752359547;2169.2732934466817;2326.93019209424;714.5498336048256;726.3862036579083;829.4981536170651;833.5373867503472;856.195176534056;15811.920940395454;20038.198472061245;20321.390358289274;20515;20530.945257781786;906.2976193450129;929.5599052487221;936.3071327217809;939.4410325856242;942.6323733743845;10082.24383171265;10503.579856805794;10515.369077855354;10535.84196237057;11157.684630738522;506510;526102.7389726053;542356;542496;550879;120620.3968980167;122682;123324;124029;125022;982.5332966840693;1122.1079242002627;1159.2812456277163;1162.610525473959;1175.4534141624356;20644.587108258052;20666.68999965032;20700.482487937923;20901.1639534423;20967.895160524098;39260.69980552589;48420.75789621029;51653.741731291106;53367.199492006876;53521.99999999844;67482.66258668675;67727.66136169288;67777;68171.65914170397;68368.3163168362;8226.177382261725;8268.958655206685;8409.117042710672;8464.619092140993;8505.872411913702;12816.401499168804;12965.351732413154;13098.803517947048;13229.3385330735;13382.531611393944;11256.493457794255;11499.310041397543;11526.481308340977;11657.125715571197;11681.415929203677;7886.72396466121;9830.901690983284;9863.753406164617;9993.950030249802;10127;9596.616135354509;11193.66419007431;11358.204925655125;11426.314421134759;11606.883931160917;10463.68608941733;10539.840617531912;11737.88262117402;12294.508219671114;12481.251124932533;11512.237063104962;11533;11767.941160294144;11770.941145294219;11798.528058877891 06:27:32 INFO - PID 1638 | |3;mootools.html;1324.9735005299892;1400.075949873084;1414.2787178538942;1420.4957240179735;1420.616433562938;11241.494132764024;11287.153463490251;11774.999125074368;11815.468303926322;11819.763604727901;11572.247803892762;11708.356040417773;11726.12054095942;11759.412029398518;11769.882301176985;2458.0659349447187;2491.426971796488;2502.7121880983645;2506.8997240110375;2515.748425157479;2423.8303318767694;2461.3108329667634;2473.146764366291;2477.492114116602;2481.8262721609494;2278.7466893208752;2348.154664320843;2440.9023639054417;2460.3603063203605;2501.6622755927897;2416.891239894209;2423.3699238198105;2431.185552839801;2463.519613675334;2465.7287698353166;2368.336865677613;2371.632396978471;2372.750861159588;2393.2102406206086;2483.217786397297;11200.103991680646;11219.60731374398;11296.943515282454;11301.660950171508;11302.208845380826;6298.401651843067;6531.640759758211;6591.340865913418;6592.373724496165;6616.20605527335;3385.238321377695;3692.6861216796337;4184.184084103601;4197.055662475949;4272.2950713132295;1191.5769901684864;1191.8033524468453;1225.4179264849215;1240.7270400511873;1245.3773113443278;15676.92161539185;15928.362865485367;15961.521154365388;16030.438934637232;16066.357345706158;4542;4985.80340718225;5257.027449921777;5285.630005899627;5521.199426083195;10490.475476226158;11308.208425410143;11355.829662555068;11387.08903287735;11401.258918170292;6273.8745225095245;6325.082862984841;6347.365263473708;6353.3329000455215;6376.043593461018;5838.095095260242;5905.557083218776;5959.165716799652;6032.577719559588;6049.3950604939155;4153.833846646161;4219.873403797891;4233.089885674594;4261.637760790306;4262.722923009995;1218.0925210718071;1248.063952035973;1260.357217818919;1267.1879212725707;1267.271318991565;722.1334398721555;739.0503203383734;744.4007573902952;745.2547452547452;747.910250769911;6085.756569737161;6204.844878877974;6257.186565746424;6563.967180164164;6872.209695884937;527.6229042199856;543.3289886989564;544.2516539757833;553.5765139668157;556.7077284425628;3030.1364111228195;3318.8008719476425;3668.789299531148;3904.9261453100057;4417.757023363682;14034.859651403356;14252.358643861051;14507.564773056825;14560;14610.561683149523;11861.347625880488;13987.160770353812;14037.78943315851;14205.928970355082;14252.572422827332;6785.762498312535;6850.554713943678;6881.380675739208;7266.164391094986;8133.674653013813;5921.378255283305;6621.949336958578;6949.734283605794;7018.508704390643;7143.4999550031;10887.074598658886;12352.876471235173;12533.435995380047;12756.425960831599;12985.350732463528;11960.76078478443;13133.737325253633;13192.274424906722;13238.470461181445;13609.047366684237;7889.6449659766595;7912.338408934188;7934.087579928382;8023.117457079833;8032.598370081356;8714.38999270045;10150.238732094975;10270.229732769923;10559;10608.310459820086;8989.28085753125;8994.325425593237;9066.637334506546;9116.225120864801;9144;8567.914320856711;8891.19979201875;8896.110388961311;8939.642414303355;8984.056674049392;8008;8080.878786818085;8086.5148091114725;8093.312068474245;8093.959530202312;5832.12518122285;5849.473547380756;5911.704414779158;6042.3655516170165;6042.577019608585;5933.495652869555;6042.697865106639;6098.5426093042315;6122.816315510542;6288.151099601495 06:27:32 INFO - PID 1638 | |4;prototype.html;312.68262713345956;353.79303107682006;367.142721744726;369.1067616368388;369.2891184469896;3996.7002474814394;4830.4881461639925;4935.395996301208;4943.480934501879;4954.603631709464;4500.684952053358;4787.569118779309;4799.792095636011;4812.326274321589;4822.204336284518;3434.759566830323;3564.5187899633606;3715.2198038411966;3740.4763333133383;3760.661540461358;3763.3978563429855;3796.7721936683747;3800.9239815203678;3813.923721525568;3823.3691440912216;3763.8118094095184;3769.9434508482395;3780;3785.261873934584;3823.350030494809;3573.749837511375;3764.284785890672;3781.2437512497336;3795.7532760370627;3836.136869204435;3421.897343079687;3426.229098452828;3435.57055368079;3437.759356845022;3464.7055000325004;4558.726476411426;4590.288505281686;4598.655100867449;4600.677952543323;4608.815647374101;4363.847265345697;4559.498455169929;4581.060882519109;4581.633469322446;4584.4498660161;3877.4959255296912;4958.132326842824;5251.737413129328;5276.287701160332;5285.4450282720145;3953.881383558498;3960.32674445345;3966.3257246268204;3967.4247234150885;3970.285348637273;4118.732282401665;5052.372766161612;5305.946940530584;5541.5289700375515;5593.188987596775;3828.885133446001;3867.43922131295;3874.8837534874;3875.0506125998745;3894.2600905827803;3925.548561915359;3928.823202955874;3936.4095385692376;3941.3693808990997;3967.8809635710977;4417.911641767147;4433.135538569948;4438.400815889813;4451.86644400662;4459.063596644741;4785.832495862628;5055.51972562606;5214.348206474191;5288.603354748409;5370.301860758154;1182.266994463421;1399.734050530396;1425;1425.5081996711117;1430.170501109375;989.6204461763142;1022.4223313827664;1029.7219750667277;1034.886162522122;1038.7195457226508;4230.534641189467;4863.8569936065;4868.464468908417;4905.779239934212;4942.233953737176;4989.426215985136;5426.457354264542;5430.49732922263;5433.130699088127;5447.291852059207;3924.8814087985356;4268.935965960513;4852.369192005017;4958.181899986462;4964.578010869117;2325.383773300077;2409.7228818685726;2780.972190278071;2848.5727140929025;2849.5583184606003;5600.6919619420505;5609.9902017636405;5612.382637909826;5613.831585052455;5641.52047075995;5351.545118664879;5567.66594004361;5596.468335508121;5607;5623.8594035149445;5596.160575913564;5596.341737674572;5596.636218645775;5606.299212598426;5628.212050312877;4395.208862404797;4629.935114923654;5142.382914050338;5433.1035379161995;5457.481539253765;4812.930439910872;4973.154563724248;5365.678059316453;5367.033933891937;5390.353157621111;5384.730763461733;5603.551715862803;5619.943800562106;5658.688772117573;5668.461496157858;3061.4336347774843;3225.7420404740124;3330.6502817204814;3595.3887839067943;3716.1267102230413;4846.927296090632;4939.407271127488;5456.508914197741;5463.4263402342185;5510.834674959758;4613.446386433649;5243.423223445342;5283.339582552181;5318.388385335581;5346.812861549827;4550.590446859798;5292.417834038176;5345.919811202757;5377.220303056113;5391.7304134793885;5211.426743058185;5225.190095535198;5228.790848366023;5247.265382846328;5260.369815092454;4511.774411279357;5090.0403344713295;5203.985222881502;5276.103062479465;5323.1749078892835;5163.328767260232;5166.579190722581;5180.248863914787;5223.712695801767;5229.947700522946 06:27:32 INFO - PID 1638 | |5;yui.html;768.277818850281;799.9360051195904;805.2390490986016;813.6013353456805;816.0411516468148;13337.599872003842;13891.888648908089;14318.57044288673;15604.297806598703;16191.433299834509;12502.249865008083;13545.729085418287;13652.24912629805;16562.420315288968;16572.41996530122;936.1340759797195;946.7775072857908;956.6125719083782;959.9824186362478;960.4573416019962;963.0417734354327;968.9127978481936;970.5195928015642;971.2861047130353;973.4013581647272;920.4017388697367;925.8703781470599;986.5461887531744;998.7003898830358;1010.8837483689359;1011.3123076308108;1011.6206422591529;1012.9594816207343;1014.5586669798661;1016.2530540053056;722.2272168779408;729.3800269770705;743.7954562495303;745.9104907411091;749.1272667342539;16048.75926861098;16307.103109328982;16470.258838352303;16653.583660408465;16742.83257167437;16177.272022759003;16673;16824.57938551546;16843.073630950414;17066.914665426593;10980.450977451095;13824.170549767046;13912.443502259897;14007.929960350235;14065.789013164811;3462.3767721810063;3511.3328467591077;3511.631278715749;3512.2799826035603;3516.1033936346066;120072;120708;120711.39644301722;122138;122893.38553307265;12634.178778379559;12799.936000319938;12833.230006199658;12974.221546707227;12982.935085324512;20558;24829.255122345996;24903.377415564755;25298.494030119295;25329.113481028075;7462.440316976248;7495.138059123271;7523.623818809147;7540.924590754132;7561.508501947355;3431.571053618298;3712.5730540987593;3713.350163721338;3781.810909454516;3787.3561494545443;4098.918021639551;4135.545090040093;4151.8546850860075;4184.476940382452;4191.706580539333;853.7438768369465;856.8414843254018;859.5616235719828;868.2966796894535;868.9608967596473;570.4523657289049;580.1384943359109;591.9881602367929;608.9817305480842;631.4222486424961;9072.95463522678;10697.946510267555;10960.780784384267;11556.94221528887;11920.225185362924;570.6091327440716;573.7418161827194;583.4078410413434;593.2791658135386;596.4631831351732;3888.494495715539;4996.2505624156665;5134.589232861362;5216.217567364925;5336.67979921206;102887.4855625717;106155.9384406146;106465.4676726627;106880;108557.45721271343;70192.18791264047;95084.52457737667;97450.02549974552;98166.50916745514;99091;78096.60951695319;84080.57959710284;85861.57069214739;89878.55060724786;90211.54894225487;11301.049854339357;12132;12144.635660930186;12145.45345459474;12214.511419543118;40446.79776601098;40625;40738.185236294514;41104.56134035294;41576;53082.753379728754;68568.6571567139;72603.63698181475;79143;79278;14687.485937992118;16525.008499490068;16648.417305394254;17425.851428000402;17650.735238971683;17911.641767164845;18236.726449103007;18595.81404185941;18642.813571864106;18757;16651.333946642484;16687.332506699597;16945.23746431389;17484.562885927444;17525.948443093454;16901.830981690517;17037.97772133676;17113.401030963858;17137;17201.913990429966;15644.374225031326;15798.131102789457;16299.59251018734;16336.591585210464;16402.015879047296;9020.278377729897;11353.54585816558;11393;11619.360935148648;12042.63872083839;10940.1794865387;13438.328083595976;13455.192688438725;13495.122817016863;13516.324183790968 06:27:32 INFO - PID 1638 | __end_tp_report 06:27:32 INFO - PID 1638 | __start_cc_report 06:27:32 INFO - PID 1638 | _x_x_mozilla_cycle_collect,360 06:27:32 INFO - PID 1638 | __end_cc_report 06:27:32 INFO - PID 1638 | __startTimestamp1494854852684__endTimestamp 06:27:32 INFO - PID 1638 | 06:27:32 INFO - PID 1638 | ------- Summary: start ------- 06:27:32 INFO - PID 1638 | Number of tests: 6 06:27:32 INFO - PID 1638 | 06:27:32 INFO - PID 1638 | [#0] dojo.html Cycles:165 Average:5462.95 Median:5739.66 stddev:702.61 (12.2%) stddev-sans-first:670.74 06:27:32 INFO - PID 1638 | Values: 2709.2 3096.7 3147.2 3170.5 3205.2 5176.5 5296.2 5422.0 5451.1 5460.7 4088.9 4652.9 5367.2 5394.1 5419.6 4759.1 4774.2 4802.3 4910.5 4980.4 4864.1 4886.6 4901.7 4943.5 4943.7 4706.6 4850.1 4912.2 4919.0 4923.2 4239.6 4804.5 4868.1 4917.2 4958.6 4787.5 4816.8 4824.9 4830.9 4834.9 5022.1 5338.4 5382.4 5414.1 5498.6 5790.4 5996.5 6018.7 6046.6 6074.7 5981.7 6002.8 6032.1 6040.4 6045.7 5419.2 5577.4 5578.9 5591.1 5697.9 6119.7 6132.7 6139.5 6144.9 6151.3 4137.6 5891.5 5979.1 5989.0 6038.5 5831.1 5968.1 6035.8 6054.9 6072.8 5856.1 5950.7 5952.6 5953.5 5965.1 5953.3 5993.3 6015.5 6036.6 6061.5 4814.1 4837.9 4858.2 4890.4 4894.0 4231.9 4267.3 4283.8 4290.5 4333.0 5519.6 5613.9 5647.8 5736.5 5815.3 5973.6 5995.2 6066.8 6122.2 6132.9 5664.9 5701.1 5739.7 5743.2 5753.6 5083.8 5263.9 5282.8 5312.4 5321.0 5973.5 6071.6 6106.2 6140.5 6148.2 5985.5 6015.2 6057.2 6073.0 6113.5 5253.8 6113.2 6115.3 6117.7 6208.3 4612.0 5985.1 6005.6 6038.8 6043.4 4939.3 5944.7 6071.8 6082.5 6112.5 5667.8 5998.4 6000.7 6033.7 6043.8 4556.4 5870.1 5944.5 5998.0 6000.5 4857.0 5616.8 5863.5 5908.7 5948.9 5874.2 5875.9 5892.0 5894.2 5912.9 4603.2 5394.4 5435.3 5813.3 5869.6 06:27:32 INFO - PID 1638 | 06:27:32 INFO - PID 1638 | [#1] ext.html Cycles:180 Average:41464.17 Median:32794.09 stddev:70221.19 (214.1%) stddev-sans-first:70351.55 06:27:32 INFO - PID 1638 | Values: 722.5 750.2 790.2 1060.5 1142.6 37360.6 38404.2 38512.2 38661.6 38671.5 38368.2 38485.4 38795.0 38935.2 38978.6 7396.3 7580.9 7947.6 8121.0 8162.6 8024.4 8074.7 8168.6 8188.7 8199.7 8169.6 8175.9 8207.0 8213.6 8214.6 8128.9 8133.6 8163.8 8172.7 8194.5 7904.1 7973.8 7980.0 7989.2 8003.4 34110.8 36044.9 36251.9 36257.1 36455.5 3494.7 3495.4 3516.6 3521.3 3526.4 29221.9 29631.8 29782.7 29786.6 29811.7 3001.0 3043.3 3050.0 3053.0 3056.9 232130.0 246362.0 246370.0 299078.0 300007.0 44957.6 45244.0 45283.0 45309.9 45406.8 46467.0 47078.5 47167.0 47184.5 47223.9 13797.7 13906.5 13911.8 13917.5 13943.9 11335.4 11349.7 11362.4 11375.8 11406.8 12816.4 12946.5 12955.7 12981.7 13055.0 5650.4 5665.1 5671.2 5683.6 5699.4 877.7 903.6 910.4 931.0 952.1 23485.5 23498.9 23683.9 23798.8 23915.4 1029.7 1044.9 1047.5 1050.6 1063.2 11653.9 12163.6 12211.6 12231.6 12263.1 286948.6 288559.0 290671.0 291014.0 291326.0 51186.5 51565.5 51674.2 51846.0 51867.5 1397.3 1423.1 1432.0 1439.6 1450.2 19563.8 20969.2 23053.7 23118.5 23751.5 36418.6 36440.0 36446.0 36539.0 36634.8 230488.0 238068.8 254959.0 255796.0 256276.0 31236.9 31450.9 32063.8 32322.4 33265.8 41155.0 41697.0 43674.6 43887.6 44001.0 27355.0 27942.4 28032.4 28449.1 28529.0 21281.8 27211.9 27801.6 28036.0 28127.6 35432.8 35494.6 35620.8 35961.0 36170.3 23379.0 24989.9 27598.0 28704.2 28871.6 21937.9 23412.0 23690.9 23818.6 23963.6 06:27:32 INFO - PID 1638 | 06:27:32 INFO - PID 1638 | [#2] jquery.html Cycles:180 Average:33395.27 Median:11856.79 stddev:89798.91 (757.4%) stddev-sans-first:90016.63 06:27:32 INFO - PID 1638 | Values: 394.0 397.7 398.3 401.7 426.2 16702.2 20274.0 20537.3 20750.5 21107.8 20782.0 21247.0 21413.1 21555.9 21724.1 5820.4 5904.0 5950.6 5961.3 5985.5 5644.2 5825.2 5848.2 5921.2 5939.9 5813.7 5819.7 5824.6 5828.9 5834.6 5509.3 5707.9 5757.4 5790.7 5842.0 5138.7 5146.0 5151.8 5158.6 5170.7 19918.9 24353.5 25734.0 26172.0 26462.0 2062.5 2097.7 2105.9 2108.5 2112.8 12885.9 14284.9 16513.4 17101.1 17415.5 2015.4 2017.7 2079.0 2081.9 2084.5 109543.0 122632.5 140016.0 140232.0 142587.9 11322.9 11639.7 11743.5 11947.2 11951.4 15378.0 15417.9 15432.8 15530.7 15860.0 7027.3 7408.4 8336.1 8360.6 8508.5 6930.8 7420.4 7830.4 7866.4 7950.6 7432.1 7462.8 7569.6 7582.7 7606.5 2146.9 2149.8 2157.7 2169.3 2326.9 714.5 726.4 829.5 833.5 856.2 15811.9 20038.2 20321.4 20515.0 20530.9 906.3 929.6 936.3 939.4 942.6 10082.2 10503.6 10515.4 10535.8 11157.7 506510.0 526102.7 542356.0 542496.0 550879.0 120620.4 122682.0 123324.0 124029.0 125022.0 982.5 1122.1 1159.3 1162.6 1175.5 20644.6 20666.7 20700.5 20901.2 20967.9 39260.7 48420.8 51653.7 53367.2 53522.0 67482.7 67727.7 67777.0 68171.7 68368.3 8226.2 8269.0 8409.1 8464.6 8505.9 12816.4 12965.4 13098.8 13229.3 13382.5 11256.5 11499.3 11526.5 11657.1 11681.4 7886.7 9830.9 9863.8 9994.0 10127.0 9596.6 11193.7 11358.2 11426.3 11606.9 10463.7 10539.8 11737.9 12294.5 12481.3 11512.2 11533.0 11767.9 11770.9 11798.5 06:27:32 INFO - PID 1638 | 06:27:32 INFO - PID 1638 | [#3] mootools.html Cycles:180 Average:6793.25 Median:3352.02 stddev:4299.90 (128.3%) stddev-sans-first:4292.33 06:27:32 INFO - PID 1638 | Values: 1325.0 1400.1 1414.3 1420.5 1420.6 11241.5 11287.2 11775.0 11815.5 11819.8 11572.2 11708.4 11726.1 11759.4 11769.9 2458.1 2491.4 2502.7 2506.9 2515.7 2423.8 2461.3 2473.1 2477.5 2481.8 2278.7 2348.2 2440.9 2460.4 2501.7 2416.9 2423.4 2431.2 2463.5 2465.7 2368.3 2371.6 2372.8 2393.2 2483.2 11200.1 11219.6 11296.9 11301.7 11302.2 6298.4 6531.6 6591.3 6592.4 6616.2 3385.2 3692.7 4184.2 4197.1 4272.3 1191.6 1191.8 1225.4 1240.7 1245.4 15676.9 15928.4 15961.5 16030.4 16066.4 4542.0 4985.8 5257.0 5285.6 5521.2 10490.5 11308.2 11355.8 11387.1 11401.3 6273.9 6325.1 6347.4 6353.3 6376.0 5838.1 5905.6 5959.2 6032.6 6049.4 4153.8 4219.9 4233.1 4261.6 4262.7 1218.1 1248.1 1260.4 1267.2 1267.3 722.1 739.1 744.4 745.3 747.9 6085.8 6204.8 6257.2 6564.0 6872.2 527.6 543.3 544.3 553.6 556.7 3030.1 3318.8 3668.8 3904.9 4417.8 14034.9 14252.4 14507.6 14560.0 14610.6 11861.3 13987.2 14037.8 14205.9 14252.6 6785.8 6850.6 6881.4 7266.2 8133.7 5921.4 6621.9 6949.7 7018.5 7143.5 10887.1 12352.9 12533.4 12756.4 12985.4 11960.8 13133.7 13192.3 13238.5 13609.0 7889.6 7912.3 7934.1 8023.1 8032.6 8714.4 10150.2 10270.2 10559.0 10608.3 8989.3 8994.3 9066.6 9116.2 9144.0 8567.9 8891.2 8896.1 8939.6 8984.1 8008.0 8080.9 8086.5 8093.3 8094.0 5832.1 5849.5 5911.7 6042.4 6042.6 5933.5 6042.7 6098.5 6122.8 6288.2 06:27:32 INFO - PID 1638 | 06:27:32 INFO - PID 1638 | [#4] prototype.html Cycles:180 Average:4321.07 Median:4707.88 stddev:1284.31 (27.3%) stddev-sans-first:1252.18 06:27:32 INFO - PID 1638 | Values: 312.7 353.8 367.1 369.1 369.3 3996.7 4830.5 4935.4 4943.5 4954.6 4500.7 4787.6 4799.8 4812.3 4822.2 3434.8 3564.5 3715.2 3740.5 3760.7 3763.4 3796.8 3800.9 3813.9 3823.4 3763.8 3769.9 3780.0 3785.3 3823.4 3573.7 3764.3 3781.2 3795.8 3836.1 3421.9 3426.2 3435.6 3437.8 3464.7 4558.7 4590.3 4598.7 4600.7 4608.8 4363.8 4559.5 4581.1 4581.6 4584.4 3877.5 4958.1 5251.7 5276.3 5285.4 3953.9 3960.3 3966.3 3967.4 3970.3 4118.7 5052.4 5305.9 5541.5 5593.2 3828.9 3867.4 3874.9 3875.1 3894.3 3925.5 3928.8 3936.4 3941.4 3967.9 4417.9 4433.1 4438.4 4451.9 4459.1 4785.8 5055.5 5214.3 5288.6 5370.3 1182.3 1399.7 1425.0 1425.5 1430.2 989.6 1022.4 1029.7 1034.9 1038.7 4230.5 4863.9 4868.5 4905.8 4942.2 4989.4 5426.5 5430.5 5433.1 5447.3 3924.9 4268.9 4852.4 4958.2 4964.6 2325.4 2409.7 2781.0 2848.6 2849.6 5600.7 5610.0 5612.4 5613.8 5641.5 5351.5 5567.7 5596.5 5607.0 5623.9 5596.2 5596.3 5596.6 5606.3 5628.2 4395.2 4629.9 5142.4 5433.1 5457.5 4812.9 4973.2 5365.7 5367.0 5390.4 5384.7 5603.6 5619.9 5658.7 5668.5 3061.4 3225.7 3330.7 3595.4 3716.1 4846.9 4939.4 5456.5 5463.4 5510.8 4613.4 5243.4 5283.3 5318.4 5346.8 4550.6 5292.4 5345.9 5377.2 5391.7 5211.4 5225.2 5228.8 5247.3 5260.4 4511.8 5090.0 5204.0 5276.1 5323.2 5163.3 5166.6 5180.2 5223.7 5229.9 06:27:32 INFO - PID 1638 | 06:27:32 INFO - PID 1638 | [#5] yui.html Cycles:180 Average:22020.08 Median:24866.32 stddev:31389.70 (126.2%) stddev-sans-first:31437.20 06:27:32 INFO - PID 1638 | Values: 768.3 799.9 805.2 813.6 816.0 13337.6 13891.9 14318.6 15604.3 16191.4 12502.2 13545.7 13652.2 16562.4 16572.4 936.1 946.8 956.6 960.0 960.5 963.0 968.9 970.5 971.3 973.4 920.4 925.9 986.5 998.7 1010.9 1011.3 1011.6 1013.0 1014.6 1016.3 722.2 729.4 743.8 745.9 749.1 16048.8 16307.1 16470.3 16653.6 16742.8 16177.3 16673.0 16824.6 16843.1 17066.9 10980.5 13824.2 13912.4 14007.9 14065.8 3462.4 3511.3 3511.6 3512.3 3516.1 120072.0 120708.0 120711.4 122138.0 122893.4 12634.2 12799.9 12833.2 12974.2 12982.9 20558.0 24829.3 24903.4 25298.5 25329.1 7462.4 7495.1 7523.6 7540.9 7561.5 3431.6 3712.6 3713.4 3781.8 3787.4 4098.9 4135.5 4151.9 4184.5 4191.7 853.7 856.8 859.6 868.3 869.0 570.5 580.1 592.0 609.0 631.4 9073.0 10697.9 10960.8 11556.9 11920.2 570.6 573.7 583.4 593.3 596.5 3888.5 4996.3 5134.6 5216.2 5336.7 102887.5 106155.9 106465.5 106880.0 108557.5 70192.2 95084.5 97450.0 98166.5 99091.0 78096.6 84080.6 85861.6 89878.6 90211.5 11301.0 12132.0 12144.6 12145.5 12214.5 40446.8 40625.0 40738.2 41104.6 41576.0 53082.8 68568.7 72603.6 79143.0 79278.0 14687.5 16525.0 16648.4 17425.9 17650.7 17911.6 18236.7 18595.8 18642.8 18757.0 16651.3 16687.3 16945.2 17484.6 17525.9 16901.8 17038.0 17113.4 17137.0 17201.9 15644.4 15798.1 16299.6 16336.6 16402.0 9020.3 11353.5 11393.0 11619.4 12042.6 10940.2 13438.3 13455.2 13495.1 13516.3 06:27:32 INFO - PID 1638 | -------- Summary: end -------- 06:27:32 INFO - PID 1638 | 06:27:33 INFO - TEST-INFO | 1638: exit 0 06:27:33 INFO - TEST-OK | dromaeo_css | took 1114670ms 06:27:33 INFO - TEST-START | kraken 06:27:33 INFO - operating with platform_type : mac_ 06:27:33 INFO - Initialising browser for kraken test... 06:27:33 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmppHugNZ/profile http://localhost:49219/getInfo.html) 06:27:33 INFO - PID 1652 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2932: String.localeCompare is deprecated; use String.prototype.localeCompare instead 06:27:35 INFO - PID 1652 | 2017-05-15 06:27:35.923 plugin-container[1653:10147] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 06:27:35 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:27:35 INFO - PID 1652 | 2017-05-15 06:27:35.926 plugin-container[1653:10147] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 06:27:35 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:27:35 INFO - PID 1652 | 2017-05-15 06:27:35.926 plugin-container[1653:10147] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:27:36 INFO - PID 1652 | __metrics Screen width/height:1600/1200 06:27:36 INFO - PID 1652 | colorDepth:24 06:27:36 INFO - PID 1652 | Browser inner width/height: 1024/691 06:27:36 INFO - PID 1652 | __metrics 06:27:36 INFO - PID 1652 | 2017-05-15 06:27:36.486 plugin-container[1654:10202] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x903f, name = 'com.apple.tsm.portname' 06:27:36 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:27:36 INFO - PID 1652 | 2017-05-15 06:27:36.487 plugin-container[1654:10202] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9413, name = 'com.apple.CFPasteboardClient' 06:27:36 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:27:36 INFO - PID 1652 | 2017-05-15 06:27:36.487 plugin-container[1654:10202] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:27:37 INFO - TEST-INFO | 1652: exit 0 06:27:37 INFO - Browser initialized. 06:27:37 INFO - Running cycle 1/1 for kraken test... 06:27:37 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmppHugNZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:27:38 INFO - PID 1655 | 2017-05-15 06:27:38.974 plugin-container[1656:10295] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x923f, name = 'com.apple.tsm.portname' 06:27:38 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:27:38 INFO - PID 1655 | 2017-05-15 06:27:38.975 plugin-container[1656:10295] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9813, name = 'com.apple.CFPasteboardClient' 06:27:38 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:27:38 INFO - PID 1655 | 2017-05-15 06:27:38.975 plugin-container[1656:10295] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:27:39 INFO - PID 1655 | 2017-05-15 06:27:39.687 plugin-container[1657:10342] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 06:27:39 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:27:39 INFO - PID 1655 | 2017-05-15 06:27:39.688 plugin-container[1657:10342] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980b, name = 'com.apple.CFPasteboardClient' 06:27:39 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:27:39 INFO - PID 1655 | 2017-05-15 06:27:39.688 plugin-container[1657:10342] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:27:49 INFO - PID 1655 | RSS: Main: 236118016 06:27:49 INFO - PID 1655 | 06:27:52 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-beat-detection) 06:27:53 INFO - PID 1655 | RSS: Main: 248115200 06:27:53 INFO - PID 1655 | 06:27:55 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-dft) 06:27:56 INFO - PID 1655 | RSS: Main: 256471040 06:27:56 INFO - PID 1655 | 06:28:00 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-fft) 06:28:00 INFO - PID 1655 | RSS: Main: 258494464 06:28:00 INFO - PID 1655 | 06:28:02 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-oscillator) 06:28:03 INFO - PID 1655 | RSS: Main: 259325952 06:28:03 INFO - PID 1655 | 06:28:05 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49219/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:28:06 INFO - PID 1655 | RSS: Main: 259727360 06:28:06 INFO - PID 1655 | 06:28:09 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49219/tests/kraken/driver.html?testName=imaging-darkroom) 06:28:09 INFO - PID 1655 | RSS: Main: 265490432 06:28:09 INFO - PID 1655 | 06:28:13 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49219/tests/kraken/driver.html?testName=imaging-desaturate) 06:28:13 INFO - PID 1655 | RSS: Main: 266088448 06:28:13 INFO - PID 1655 | 06:28:17 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49219/tests/kraken/driver.html?testName=json-parse-financial) 06:28:17 INFO - PID 1655 | RSS: Main: 266027008 06:28:17 INFO - PID 1655 | 06:28:19 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49219/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:28:19 INFO - PID 1655 | RSS: Main: 265355264 06:28:19 INFO - PID 1655 | 06:28:21 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:28:22 INFO - PID 1655 | RSS: Main: 265728000 06:28:22 INFO - PID 1655 | 06:28:24 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:28:25 INFO - PID 1655 | RSS: Main: 265568256 06:28:25 INFO - PID 1655 | 06:28:28 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:28:29 INFO - PID 1655 | RSS: Main: 265854976 06:28:29 INFO - PID 1655 | 06:28:32 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:28:33 INFO - PID 1655 | RSS: Main: 263069696 06:28:33 INFO - PID 1655 | 06:28:35 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49219/tests/kraken/driver.html?testName=ai-astar) 06:28:35 INFO - PID 1655 | RSS: Main: 266203136 06:28:35 INFO - PID 1655 | 06:28:35 INFO - PID 1655 | __start_tp_report 06:28:35 INFO - PID 1655 | _x_x_mozilla_page_load 06:28:35 INFO - PID 1655 | _x_x_mozilla_page_load_details 06:28:35 INFO - PID 1655 | |i|pagename|runs| 06:28:35 INFO - PID 1655 | |0;ai-astar;108;125;110;172;176;125;137;174;154;120 06:28:35 INFO - PID 1655 | |1;audio-beat-detection;139;115;125;191;150;105;105;151;173;131 06:28:35 INFO - PID 1655 | |2;audio-dft;231;238;237;277;221;310;260;231;227;227 06:28:35 INFO - PID 1655 | |3;audio-fft;90;64;60;90;82;73;72;87;102;86 06:28:35 INFO - PID 1655 | |4;audio-oscillator;88;76;125;90;76;71;92;74;71;82 06:28:35 INFO - PID 1655 | |5;imaging-gaussian-blur;140;142;107;91;92;92;92;90;91;92 06:28:35 INFO - PID 1655 | |6;imaging-darkroom;98;101;99;103;99;100;139;99;106;156 06:28:35 INFO - PID 1655 | |7;imaging-desaturate;118;140;167;105;108;109;135;110;160;107 06:28:35 INFO - PID 1655 | |8;json-parse-financial;53;49;52;52;52;51;54;55;52;52 06:28:35 INFO - PID 1655 | |9;json-stringify-tinderbox;61;61;58;56;72;87;90;71;62;65 06:28:35 INFO - PID 1655 | |10;stanford-crypto-aes;95;114;115;76;85;100;89;89;82;117 06:28:35 INFO - PID 1655 | |11;stanford-crypto-ccm;149;157;157;156;170;165;169;159;194;153 06:28:35 INFO - PID 1655 | |12;stanford-crypto-pbkdf2;202;195;198;204;227;201;259;208;227;243 06:28:35 INFO - PID 1655 | |13;stanford-crypto-sha256-iterative;70;72;74;73;75;71;75;74;72;72 06:28:35 INFO - PID 1655 | __end_tp_report 06:28:35 INFO - PID 1655 | __start_cc_report 06:28:35 INFO - PID 1655 | _x_x_mozilla_cycle_collect,961 06:28:35 INFO - PID 1655 | __end_cc_report 06:28:35 INFO - PID 1655 | __startTimestamp1494854915378__endTimestamp 06:28:35 INFO - PID 1655 | 06:28:35 INFO - PID 1655 | ------- Summary: start ------- 06:28:35 INFO - PID 1655 | Number of tests: 14 06:28:35 INFO - PID 1655 | 06:28:35 INFO - PID 1655 | [#0] ai-astar Cycles:10 Average:140.10 Median:145.50 stddev:26.78 (18.4%) stddev-sans-first:25.76 06:28:35 INFO - PID 1655 | Values: 108.0 125.0 110.0 172.0 176.0 125.0 137.0 174.0 154.0 120.0 06:28:35 INFO - PID 1655 | 06:28:35 INFO - PID 1655 | [#1] audio-beat-detection Cycles:10 Average:138.50 Median:144.50 stddev:28.42 (19.7%) stddev-sans-first:30.15 06:28:35 INFO - PID 1655 | Values: 139.0 115.0 125.0 191.0 150.0 105.0 105.0 151.0 173.0 131.0 06:28:35 INFO - PID 1655 | 06:28:35 INFO - PID 1655 | [#2] audio-dft Cycles:10 Average:245.90 Median:237.50 stddev:28.27 (11.9%) stddev-sans-first:29.47 06:28:35 INFO - PID 1655 | Values: 231.0 238.0 237.0 277.0 221.0 310.0 260.0 231.0 227.0 227.0 06:28:35 INFO - PID 1655 | 06:28:35 INFO - PID 1655 | [#3] audio-fft Cycles:10 Average:80.60 Median:84.00 stddev:13.07 (15.6%) stddev-sans-first:13.42 06:28:35 INFO - PID 1655 | Values: 90.0 64.0 60.0 90.0 82.0 73.0 72.0 87.0 102.0 86.0 06:28:35 INFO - PID 1655 | 06:28:35 INFO - PID 1655 | [#4] audio-oscillator Cycles:10 Average:84.50 Median:79.00 stddev:16.21 (20.5%) stddev-sans-first:17.14 06:28:35 INFO - PID 1655 | Values: 88.0 76.0 125.0 90.0 76.0 71.0 92.0 74.0 71.0 82.0 06:28:35 INFO - PID 1655 | 06:28:35 INFO - PID 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:102.90 Median:91.50 stddev:20.67 (22.6%) stddev-sans-first:17.02 06:28:35 INFO - PID 1655 | Values: 140.0 142.0 107.0 91.0 92.0 92.0 92.0 90.0 91.0 92.0 06:28:35 INFO - PID 1655 | 06:28:35 INFO - PID 1655 | [#6] imaging-darkroom Cycles:10 Average:110.00 Median:127.00 stddev:20.30 (16.0%) stddev-sans-first:21.07 06:28:35 INFO - PID 1655 | Values: 98.0 101.0 99.0 103.0 99.0 100.0 139.0 99.0 106.0 156.0 06:28:35 INFO - PID 1655 | 06:28:35 INFO - PID 1655 | [#7] imaging-desaturate Cycles:10 Average:125.90 Median:126.50 stddev:23.21 (18.3%) stddev-sans-first:24.44 06:28:35 INFO - PID 1655 | Values: 118.0 140.0 167.0 105.0 108.0 109.0 135.0 110.0 160.0 107.0 06:28:35 INFO - PID 1655 | 06:28:35 INFO - PID 1655 | [#8] json-parse-financial Cycles:10 Average:52.20 Median:52.00 stddev:1.62 (3.1%) stddev-sans-first:1.69 06:28:35 INFO - PID 1655 | Values: 53.0 49.0 52.0 52.0 52.0 51.0 54.0 55.0 52.0 52.0 06:28:35 INFO - PID 1655 | 06:28:35 INFO - PID 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:68.30 Median:68.00 stddev:11.81 (17.4%) stddev-sans-first:12.23 06:28:35 INFO - PID 1655 | Values: 61.0 61.0 58.0 56.0 72.0 87.0 90.0 71.0 62.0 65.0 06:28:35 INFO - PID 1655 | 06:28:35 INFO - PID 1655 | [#10] stanford-crypto-aes Cycles:10 Average:96.20 Median:83.50 stddev:14.75 (17.7%) stddev-sans-first:15.64 06:28:35 INFO - PID 1655 | Values: 95.0 114.0 115.0 76.0 85.0 100.0 89.0 89.0 82.0 117.0 06:28:35 INFO - PID 1655 | 06:28:35 INFO - PID 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:162.90 Median:162.00 stddev:12.84 (7.9%) stddev-sans-first:12.59 06:28:35 INFO - PID 1655 | Values: 149.0 157.0 157.0 156.0 170.0 165.0 169.0 159.0 194.0 153.0 06:28:35 INFO - PID 1655 | 06:28:35 INFO - PID 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:216.40 Median:217.50 stddev:21.63 (9.9%) stddev-sans-first:22.31 06:28:35 INFO - PID 1655 | Values: 202.0 195.0 198.0 204.0 227.0 201.0 259.0 208.0 227.0 243.0 06:28:35 INFO - PID 1655 | 06:28:35 INFO - PID 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.80 Median:73.50 stddev:1.69 (2.3%) stddev-sans-first:1.45 06:28:35 INFO - PID 1655 | Values: 70.0 72.0 74.0 73.0 75.0 71.0 75.0 74.0 72.0 72.0 06:28:35 INFO - PID 1655 | -------- Summary: end -------- 06:28:35 INFO - PID 1655 | 06:28:36 INFO - TEST-INFO | 1655: exit 0 06:28:36 INFO - TEST-OK | kraken | took 62895ms 06:28:36 INFO - SUITE-END | took 1177s 06:28:36 INFO - Completed test suite (00:19:37) 06:28:36 INFO - javascript benchmark 06:28:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7248.102753582901, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2709.2278700570337, 3096.721295083442, 3147.2446612812932, 3170.476871316233, 3205.246767009754, 5176.50823171799, 5296.205569164623, 5421.972890135548, 5451.127819548873, 5460.699661518614, 4088.8977775555627, 4652.906941861161, 5367.194920761887, 5394.136938089907, 5419.647722898009, 4759.095771803363, 4774.188387974053, 4802.303665968432, 4910.459849416561, 4980.377452818398, 4864.14877396456, 4886.633502487328, 4901.65688401812, 4943.456219815817, 4943.678660887049, 4706.647001474886, 4850.102730994759, 4912.164931961575, 4919.040787046527, 4923.236898280772, 4239.618434340909, 4804.519548045202, 4868.099401610678, 4917.213245880677, 4958.603311735071, 4787.521247875219, 4816.759162041884, 4824.903501929977, 4830.879228019293, 4834.879128021792, 5022.096022715909, 5338.386085600167, 5382.3541175059245, 5414.052540805383, 5498.560115190815, 5790.420957904218, 5996.4603185713495, 6018.729157187893, 6046.637201767908, 6074.696265186767, 5981.671008094553, 6002.849928751816, 6032.125341825455, 6040.426159514839, 6045.72794224261, 5419.241306217133, 5577.442255774471, 5578.9163162552595, 5591.077472217079, 5697.886042279131, 6119.724612392453, 6132.722655450988, 6139.47814435769, 6144.941444616104, 6151.323354431009, 4137.586241375899, 5891.528677705859, 5979.133025711248, 5989.041753319448, 6038.486728628028, 5831.125331200354, 5968.134620480006, 6035.849103772441, 6054.878902421927, 6072.787452439144, 5856.0630299151935, 5950.732217050157, 5952.5833191676165, 5953.523718102541, 5965.075413310944, 5953.255843019622, 5993.310769261503, 6015.5488338373925, 6036.63780173191, 6061.484773794277, 4814.061258054716, 4837.9032419351415, 4858.17411040124, 4890.437599676083, 4893.975530122327, 4231.915361692749, 4267.295896177158, 4283.785810709452, 4290.50659174199, 4333.025069359369, 5519.558435325244, 5613.915791263134, 5647.830565083054, 5736.541076713853, 5815.3021637402935, 5973.581849270597, 5995.160677505153, 6066.75732970685, 6122.234720659918, 6132.938670613326, 5664.915026274609, 5701.144828275709, 5739.655620662774, 5743.167240750068, 5753.568482363839, 5083.779892825695, 5263.947360526346, 5282.788688452419, 5312.442193569696, 5320.973395133, 5973.462388384893, 6071.574989750852, 6106.175666284995, 6140.54698561739, 6148.1699970504305, 5985.521158307238, 6015.248093988252, 6057.18228039209, 6073.0, 6113.510919126548, 5253.844154286051, 6113.172264937315, 6115.301188036123, 6117.705611546597, 6208.286047104641, 4612.031473390625, 5985.102234664835, 6005.6396616203165, 6038.788642397671, 6043.425874541911, 4939.333190019302, 5944.702764861826, 6071.817845464643, 6082.47577255434, 6112.510999120149, 5667.801626943037, 5998.430149135826, 6000.669963152069, 6033.728482218223, 6043.8186854394435, 4556.362109304634, 5870.0607902735355, 5944.524438045032, 5997.970010149921, 6000.489958353589, 4857.0, 5616.775328986958, 5863.530917526502, 5908.7045647718305, 5948.851278718067, 5874.177615133799, 5875.911861321998, 5892.027815410581, 5894.233749612523, 5912.940870591411, 4603.240465323184, 5394.433584473731, 5435.320584926884, 5813.273340832396, 5869.589128760946], "value": 5417.245921414674, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [722.4870342057139, 750.2160242546538, 790.1545346479265, 1060.475064842902, 1142.6229344316375, 37360.626393736056, 38404.23191536168, 38512.22975540488, 38661.613383866184, 38671.453141874365, 38368.232635347274, 38485.42271865924, 38795.03012424691, 38935.22129557407, 38978.61021389785, 7396.260373962587, 7580.886286705704, 7947.642356093989, 8120.959395203015, 8162.551059691715, 8024.43828931975, 8074.71738489153, 8168.591570421455, 8188.713395031175, 8199.672013119469, 8169.550674712888, 8175.918240817575, 8207.0, 8213.589320533949, 8214.63034163461, 8128.918710812875, 8133.593320334019, 8163.836723265502, 8172.67309307627, 8194.467359621605, 7904.130545639975, 7973.760787176394, 7980.04239491259, 7989.161138080476, 8003.399745019147, 34110.829445852614, 36044.91865244047, 36251.912442626766, 36257.09357266063, 36455.4531682025, 3494.702950249232, 3495.405781017233, 3516.613172551017, 3521.3133438979407, 3526.400511912981, 29221.85389073041, 29631.814727411092, 29782.702172978425, 29786.55320170199, 29811.70188298111, 3000.9549856752164, 3043.3456806786426, 3050.0392376401364, 3052.9847350763325, 3056.877724891002, 232130.0, 246362.0, 246370.0, 299078.0, 300007.0, 44957.55042449599, 45244.0, 45283.0, 45309.86725331893, 45406.77296613496, 46467.0, 47078.529214707414, 47167.0, 47184.52815471801, 47223.87492562843, 13797.724045519033, 13906.513272035632, 13911.79132313016, 13917.512887048904, 13943.860561394458, 11335.376554289429, 11349.659510214708, 11362.375069371265, 11375.829362559569, 11406.771864562828, 12816.359182041047, 12946.482140714454, 12955.740885182244, 12981.740365192643, 13055.0, 5650.406707295757, 5665.065264231355, 5671.206031155559, 5683.573731970118, 5699.401562835926, 877.7322916510526, 903.6475774447971, 910.3672947301694, 930.9906900931039, 952.0859974424629, 23485.530289394115, 23498.882505587364, 23683.881580592333, 23798.76201238, 23915.40211494692, 1029.6859457865423, 1044.9216308776872, 1047.522226208979, 1050.579768092769, 1063.160103518212, 11653.941730291293, 12163.644032053951, 12211.606616949537, 12231.571894983634, 12263.080268979862, 286948.5652571766, 288559.0, 290671.0, 291014.0, 291326.0, 51186.48813511892, 51565.484345156816, 51674.22488662673, 51846.0, 51867.481325186265, 1397.329281944652, 1423.1247782613618, 1432.0333774702242, 1439.5969128643997, 1450.1589078334541, 19563.804361956198, 20969.164742147394, 23053.65419518675, 23118.537629246985, 23751.52496950086, 36418.63581364152, 36440.0, 36446.0, 36538.99999999894, 36634.8168259157, 230488.0, 238068.8096559506, 254959.0, 255796.0, 256276.0, 31236.9067082651, 31450.89921852724, 32063.839680801448, 32322.35355292834, 33265.83367083149, 41155.0, 41696.95757606084, 43674.56325436705, 43887.56112438835, 44001.0, 27355.0, 27942.44115117727, 28032.439351212455, 28449.146525603435, 28529.001484948756, 21281.78718212798, 27211.86394068017, 27801.582976255777, 28036.0, 28127.57808632913, 35432.82283588566, 35494.64505354913, 35620.82189589035, 35961.0, 36170.27659446744, 23379.0, 24989.875455604906, 27598.034068807494, 28704.18481953772, 28871.556422179227, 21937.890310548344, 23412.0, 23690.881545592852, 23818.642720359556, 23963.640545391485], "value": 16628.668910410543, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [393.9684825213983, 397.6520544523542, 398.3486998757031, 401.66862338570684, 426.2030003892721, 16702.164891755394, 20274.0, 20537.281195158135, 20750.48123796906, 21107.788922110773, 20782.0, 21247.02541644171, 21413.14347426105, 21555.922203889782, 21724.13103475863, 5820.417958204177, 5904.0, 5950.5834591578605, 5961.314448838374, 5985.491233245158, 5644.181593668915, 5825.242718446596, 5848.18706201079, 5921.230240068807, 5939.940600594004, 5813.6802475863815, 5819.67991760453, 5824.592278540504, 5828.941710582903, 5834.620749651281, 5509.283793106911, 5707.942920570783, 5757.42425757423, 5790.71046447676, 5842.006858833987, 5138.691678499302, 5146.0479811235045, 5151.8454446366295, 5158.561522270612, 5170.741462926839, 19918.904460254682, 24353.512929741482, 25734.0, 26172.0, 26462.0, 2062.5462398272357, 2097.7168082308845, 2105.8525903186783, 2108.515041540439, 2112.8309735221146, 12885.935570322183, 14284.92857535716, 16513.357862742025, 17101.10643649597, 17415.47753567395, 2015.3651599746179, 2017.7489956227087, 2079.043639925636, 2081.916723331065, 2084.4997200671883, 109543.0, 122632.5473490534, 140016.0, 140232.0, 142587.86118208338, 11322.886771132184, 11639.6508104757, 11743.471543780379, 11947.238284751824, 11951.402429878472, 15378.0, 15417.922910385376, 15432.768508472382, 15530.689386212212, 15860.0, 7027.262137475594, 7408.444366672521, 8336.124706905688, 8360.623771930155, 8508.532030738244, 6930.826729331706, 7420.406367490587, 7830.412719046094, 7866.410019248579, 7950.6422211000645, 7432.145303290083, 7462.843259294818, 7569.621518924032, 7582.734604288823, 7606.543607383574, 2146.903389347483, 2149.8172655324474, 2157.6547752359547, 2169.2732934466817, 2326.93019209424, 714.5498336048256, 726.3862036579083, 829.4981536170651, 833.5373867503472, 856.195176534056, 15811.920940395454, 20038.198472061245, 20321.390358289274, 20515.0, 20530.945257781786, 906.2976193450129, 929.5599052487221, 936.3071327217809, 939.4410325856242, 942.6323733743845, 10082.24383171265, 10503.579856805794, 10515.369077855354, 10535.84196237057, 11157.684630738522, 506510.0, 526102.7389726053, 542356.0, 542496.0, 550879.0, 120620.3968980167, 122682.0, 123324.0, 124029.0, 125022.0, 982.5332966840693, 1122.1079242002627, 1159.2812456277163, 1162.610525473959, 1175.4534141624356, 20644.587108258052, 20666.68999965032, 20700.482487937923, 20901.1639534423, 20967.895160524098, 39260.69980552589, 48420.75789621029, 51653.741731291106, 53367.199492006876, 53521.99999999844, 67482.66258668675, 67727.66136169288, 67777.0, 68171.65914170397, 68368.3163168362, 8226.177382261725, 8268.958655206685, 8409.117042710672, 8464.619092140993, 8505.872411913702, 12816.401499168804, 12965.351732413154, 13098.803517947048, 13229.3385330735, 13382.531611393944, 11256.493457794255, 11499.310041397543, 11526.481308340977, 11657.125715571197, 11681.415929203677, 7886.72396466121, 9830.901690983284, 9863.753406164617, 9993.950030249802, 10127.0, 9596.616135354509, 11193.66419007431, 11358.204925655125, 11426.314421134759, 11606.883931160917, 10463.68608941733, 10539.840617531912, 11737.88262117402, 12294.508219671114, 12481.251124932533, 11512.237063104962, 11533.0, 11767.941160294144, 11770.941145294219, 11798.528058877891], "value": 9837.575761604401, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1324.9735005299892, 1400.075949873084, 1414.2787178538942, 1420.4957240179735, 1420.616433562938, 11241.494132764024, 11287.153463490251, 11774.999125074368, 11815.468303926322, 11819.763604727901, 11572.247803892762, 11708.356040417773, 11726.12054095942, 11759.412029398518, 11769.882301176985, 2458.0659349447187, 2491.426971796488, 2502.7121880983645, 2506.8997240110375, 2515.748425157479, 2423.8303318767694, 2461.3108329667634, 2473.146764366291, 2477.492114116602, 2481.8262721609494, 2278.7466893208752, 2348.154664320843, 2440.9023639054417, 2460.3603063203605, 2501.6622755927897, 2416.891239894209, 2423.3699238198105, 2431.185552839801, 2463.519613675334, 2465.7287698353166, 2368.336865677613, 2371.632396978471, 2372.750861159588, 2393.2102406206086, 2483.217786397297, 11200.103991680646, 11219.60731374398, 11296.943515282454, 11301.660950171508, 11302.208845380826, 6298.401651843067, 6531.640759758211, 6591.340865913418, 6592.373724496165, 6616.20605527335, 3385.238321377695, 3692.6861216796337, 4184.184084103601, 4197.055662475949, 4272.2950713132295, 1191.5769901684864, 1191.8033524468453, 1225.4179264849215, 1240.7270400511873, 1245.3773113443278, 15676.92161539185, 15928.362865485367, 15961.521154365388, 16030.438934637232, 16066.357345706158, 4542.0, 4985.80340718225, 5257.027449921777, 5285.630005899627, 5521.199426083195, 10490.475476226158, 11308.208425410143, 11355.829662555068, 11387.08903287735, 11401.258918170292, 6273.8745225095245, 6325.082862984841, 6347.365263473708, 6353.3329000455215, 6376.043593461018, 5838.095095260242, 5905.557083218776, 5959.165716799652, 6032.577719559588, 6049.3950604939155, 4153.833846646161, 4219.873403797891, 4233.089885674594, 4261.637760790306, 4262.722923009995, 1218.0925210718071, 1248.063952035973, 1260.357217818919, 1267.1879212725707, 1267.271318991565, 722.1334398721555, 739.0503203383734, 744.4007573902952, 745.2547452547452, 747.910250769911, 6085.756569737161, 6204.844878877974, 6257.186565746424, 6563.967180164164, 6872.209695884937, 527.6229042199856, 543.3289886989564, 544.2516539757833, 553.5765139668157, 556.7077284425628, 3030.1364111228195, 3318.8008719476425, 3668.789299531148, 3904.9261453100057, 4417.757023363682, 14034.859651403356, 14252.358643861051, 14507.564773056825, 14560.0, 14610.561683149523, 11861.347625880488, 13987.160770353812, 14037.78943315851, 14205.928970355082, 14252.572422827332, 6785.762498312535, 6850.554713943678, 6881.380675739208, 7266.164391094986, 8133.674653013813, 5921.378255283305, 6621.949336958578, 6949.734283605794, 7018.508704390643, 7143.4999550031, 10887.074598658886, 12352.876471235173, 12533.435995380047, 12756.425960831599, 12985.350732463528, 11960.76078478443, 13133.737325253633, 13192.274424906722, 13238.470461181445, 13609.047366684237, 7889.6449659766595, 7912.338408934188, 7934.087579928382, 8023.117457079833, 8032.598370081356, 8714.38999270045, 10150.238732094975, 10270.229732769923, 10559.0, 10608.310459820086, 8989.28085753125, 8994.325425593237, 9066.637334506546, 9116.225120864801, 9144.0, 8567.914320856711, 8891.19979201875, 8896.110388961311, 8939.642414303355, 8984.056674049392, 8008.0, 8080.878786818085, 8086.5148091114725, 8093.312068474245, 8093.959530202312, 5832.12518122285, 5849.473547380756, 5911.704414779158, 6042.3655516170165, 6042.577019608585, 5933.495652869555, 6042.697865106639, 6098.5426093042315, 6122.816315510542, 6288.151099601495], "value": 5095.261796102454, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [312.68262713345956, 353.79303107682006, 367.142721744726, 369.1067616368388, 369.2891184469896, 3996.7002474814394, 4830.4881461639925, 4935.395996301208, 4943.480934501879, 4954.603631709464, 4500.684952053358, 4787.569118779309, 4799.792095636011, 4812.326274321589, 4822.204336284518, 3434.759566830323, 3564.5187899633606, 3715.2198038411966, 3740.4763333133383, 3760.661540461358, 3763.3978563429855, 3796.7721936683747, 3800.9239815203678, 3813.923721525568, 3823.3691440912216, 3763.8118094095184, 3769.9434508482395, 3780.0, 3785.261873934584, 3823.350030494809, 3573.749837511375, 3764.284785890672, 3781.2437512497336, 3795.7532760370627, 3836.136869204435, 3421.897343079687, 3426.229098452828, 3435.57055368079, 3437.759356845022, 3464.7055000325004, 4558.726476411426, 4590.288505281686, 4598.655100867449, 4600.677952543323, 4608.815647374101, 4363.847265345697, 4559.498455169929, 4581.060882519109, 4581.633469322446, 4584.4498660161, 3877.4959255296912, 4958.132326842824, 5251.737413129328, 5276.287701160332, 5285.4450282720145, 3953.881383558498, 3960.32674445345, 3966.3257246268204, 3967.4247234150885, 3970.285348637273, 4118.732282401665, 5052.372766161612, 5305.946940530584, 5541.5289700375515, 5593.188987596775, 3828.885133446001, 3867.43922131295, 3874.8837534874, 3875.0506125998745, 3894.2600905827803, 3925.548561915359, 3928.823202955874, 3936.4095385692376, 3941.3693808990997, 3967.8809635710977, 4417.911641767147, 4433.135538569948, 4438.400815889813, 4451.86644400662, 4459.063596644741, 4785.832495862628, 5055.51972562606, 5214.348206474191, 5288.603354748409, 5370.301860758154, 1182.266994463421, 1399.734050530396, 1425.0, 1425.5081996711117, 1430.170501109375, 989.6204461763142, 1022.4223313827664, 1029.7219750667277, 1034.886162522122, 1038.7195457226508, 4230.534641189467, 4863.8569936065, 4868.464468908417, 4905.779239934212, 4942.233953737176, 4989.426215985136, 5426.457354264542, 5430.49732922263, 5433.130699088127, 5447.291852059207, 3924.8814087985356, 4268.935965960513, 4852.369192005017, 4958.181899986462, 4964.578010869117, 2325.383773300077, 2409.7228818685726, 2780.972190278071, 2848.5727140929025, 2849.5583184606003, 5600.6919619420505, 5609.9902017636405, 5612.382637909826, 5613.831585052455, 5641.52047075995, 5351.545118664879, 5567.66594004361, 5596.468335508121, 5607.0, 5623.8594035149445, 5596.160575913564, 5596.341737674572, 5596.636218645775, 5606.299212598426, 5628.212050312877, 4395.208862404797, 4629.935114923654, 5142.382914050338, 5433.1035379161995, 5457.481539253765, 4812.930439910872, 4973.154563724248, 5365.678059316453, 5367.033933891937, 5390.353157621111, 5384.730763461733, 5603.551715862803, 5619.943800562106, 5658.688772117573, 5668.461496157858, 3061.4336347774843, 3225.7420404740124, 3330.6502817204814, 3595.3887839067943, 3716.1267102230413, 4846.927296090632, 4939.407271127488, 5456.508914197741, 5463.4263402342185, 5510.834674959758, 4613.446386433649, 5243.423223445342, 5283.339582552181, 5318.388385335581, 5346.812861549827, 4550.590446859798, 5292.417834038176, 5345.919811202757, 5377.220303056113, 5391.7304134793885, 5211.426743058185, 5225.190095535198, 5228.790848366023, 5247.265382846328, 5260.369815092454, 4511.774411279357, 5090.0403344713295, 5203.985222881502, 5276.103062479465, 5323.1749078892835, 5163.328767260232, 5166.579190722581, 5180.248863914787, 5223.712695801767, 5229.947700522946], "value": 3943.241092121957, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [768.277818850281, 799.9360051195904, 805.2390490986016, 813.6013353456805, 816.0411516468148, 13337.599872003842, 13891.888648908089, 14318.57044288673, 15604.297806598703, 16191.433299834509, 12502.249865008083, 13545.729085418287, 13652.24912629805, 16562.420315288968, 16572.41996530122, 936.1340759797195, 946.7775072857908, 956.6125719083782, 959.9824186362478, 960.4573416019962, 963.0417734354327, 968.9127978481936, 970.5195928015642, 971.2861047130353, 973.4013581647272, 920.4017388697367, 925.8703781470599, 986.5461887531744, 998.7003898830358, 1010.8837483689359, 1011.3123076308108, 1011.6206422591529, 1012.9594816207343, 1014.5586669798661, 1016.2530540053056, 722.2272168779408, 729.3800269770705, 743.7954562495303, 745.9104907411091, 749.1272667342539, 16048.75926861098, 16307.103109328982, 16470.258838352303, 16653.583660408465, 16742.83257167437, 16177.272022759003, 16673.0, 16824.57938551546, 16843.073630950414, 17066.914665426593, 10980.450977451095, 13824.170549767046, 13912.443502259897, 14007.929960350235, 14065.789013164811, 3462.3767721810063, 3511.3328467591077, 3511.631278715749, 3512.2799826035603, 3516.1033936346066, 120072.0, 120708.0, 120711.39644301722, 122138.0, 122893.38553307265, 12634.178778379559, 12799.936000319938, 12833.230006199658, 12974.221546707227, 12982.935085324512, 20558.0, 24829.255122345996, 24903.377415564755, 25298.494030119295, 25329.113481028075, 7462.440316976248, 7495.138059123271, 7523.623818809147, 7540.924590754132, 7561.508501947355, 3431.571053618298, 3712.5730540987593, 3713.350163721338, 3781.810909454516, 3787.3561494545443, 4098.918021639551, 4135.545090040093, 4151.8546850860075, 4184.476940382452, 4191.706580539333, 853.7438768369465, 856.8414843254018, 859.5616235719828, 868.2966796894535, 868.9608967596473, 570.4523657289049, 580.1384943359109, 591.9881602367929, 608.9817305480842, 631.4222486424961, 9072.95463522678, 10697.946510267555, 10960.780784384267, 11556.94221528887, 11920.225185362924, 570.6091327440716, 573.7418161827194, 583.4078410413434, 593.2791658135386, 596.4631831351732, 3888.494495715539, 4996.2505624156665, 5134.589232861362, 5216.217567364925, 5336.67979921206, 102887.4855625717, 106155.9384406146, 106465.4676726627, 106880.0, 108557.45721271343, 70192.18791264047, 95084.52457737667, 97450.02549974552, 98166.50916745514, 99091.0, 78096.60951695319, 84080.57959710284, 85861.57069214739, 89878.55060724786, 90211.54894225487, 11301.049854339357, 12132.0, 12144.635660930186, 12145.45345459474, 12214.511419543118, 40446.79776601098, 40625.0, 40738.185236294514, 41104.56134035294, 41576.0, 53082.753379728754, 68568.6571567139, 72603.63698181475, 79143.0, 79278.0, 14687.485937992118, 16525.008499490068, 16648.417305394254, 17425.851428000402, 17650.735238971683, 17911.641767164845, 18236.726449103007, 18595.81404185941, 18642.813571864106, 18757.0, 16651.333946642484, 16687.332506699597, 16945.23746431389, 17484.562885927444, 17525.948443093454, 16901.830981690517, 17037.97772133676, 17113.401030963858, 17137.0, 17201.913990429966, 15644.374225031326, 15798.131102789457, 16299.59251018734, 16336.591585210464, 16402.015879047296, 9020.278377729897, 11353.54585816558, 11393.0, 11619.360935148648, 12042.63872083839, 10940.1794865387, 13438.328083595976, 13455.192688438725, 13495.122817016863, 13516.324183790968], "value": 8142.588152702184, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1697.2000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 125.0, 110.0, 172.0, 176.0, 125.0, 137.0, 174.0, 154.0, 120.0], "value": 140.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 115.0, 125.0, 191.0, 150.0, 105.0, 105.0, 151.0, 173.0, 131.0], "value": 138.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [231.0, 238.0, 237.0, 277.0, 221.0, 310.0, 260.0, 231.0, 227.0, 227.0], "value": 245.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 64.0, 60.0, 90.0, 82.0, 73.0, 72.0, 87.0, 102.0, 86.0], "value": 80.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 76.0, 125.0, 90.0, 76.0, 71.0, 92.0, 74.0, 71.0, 82.0], "value": 84.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 142.0, 107.0, 91.0, 92.0, 92.0, 92.0, 90.0, 91.0, 92.0], "value": 102.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 101.0, 99.0, 103.0, 99.0, 100.0, 139.0, 99.0, 106.0, 156.0], "value": 110.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 140.0, 167.0, 105.0, 108.0, 109.0, 135.0, 110.0, 160.0, 107.0], "value": 125.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 49.0, 52.0, 52.0, 52.0, 51.0, 54.0, 55.0, 52.0, 52.0], "value": 52.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 58.0, 56.0, 72.0, 87.0, 90.0, 71.0, 62.0, 65.0], "value": 68.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 114.0, 115.0, 76.0, 85.0, 100.0, 89.0, 89.0, 82.0, 117.0], "value": 96.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 157.0, 157.0, 156.0, 170.0, 165.0, 169.0, 159.0, 194.0, 153.0], "value": 162.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 195.0, 198.0, 204.0, 227.0, 201.0, 259.0, 208.0, 227.0, 243.0], "value": 216.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 72.0, 74.0, 73.0, 75.0, 71.0, 75.0, 74.0, 72.0, 72.0], "value": 72.8, "unit": "score"}]}]} 06:28:36 INFO - Return code: 0 06:28:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:28:36 INFO - # TBPL SUCCESS # 06:28:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:28:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:28:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:28:36 INFO - 2017-05-15 06:28:36.706 screenresolution[1662:10727] starting screenresolution argv=screenresolution get 06:28:36 INFO - 2017-05-15 06:28:36.709 screenresolution[1662:10727] Display 0: 1600x1200x32@60 06:28:36 INFO - 2017-05-15 06:28:36.715 screenresolution[1663:10730] starting screenresolution argv=screenresolution list 06:28:36 INFO - Available Modes on Display 0 06:28:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:28:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:28:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:28:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:28:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:28:36 INFO - Intel Iris: 06:28:36 INFO - Chipset Model: Intel Iris 06:28:36 INFO - Type: GPU 06:28:36 INFO - Bus: Built-In 06:28:36 INFO - VRAM (Dynamic, Max): 1536 MB 06:28:36 INFO - Vendor: Intel (0x8086) 06:28:36 INFO - Device ID: 0x0a2e 06:28:36 INFO - Revision ID: 0x0009 06:28:36 INFO - Displays: 06:28:36 INFO - Display: 06:28:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:28:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:28:36 INFO - Display Serial Number: 1600x1200 60 06:28:36 INFO - Main Display: Yes 06:28:36 INFO - Mirror: Off 06:28:36 INFO - Online: Yes 06:28:36 INFO - Rotation: Supported 06:28:36 INFO - Return code: 0 06:28:36 INFO - Running post-action listener: _resource_record_post_action 06:28:36 INFO - [mozharness: 2017-05-15 13:28:36.957238Z] Finished run-tests step (success) 06:28:36 INFO - Running post-run listener: _resource_record_post_run 06:28:37 INFO - Total resource usage - Wall time: 1199s; CPU: 54.0%; Read bytes: 39599104; Write bytes: 533175296; Read time: 388; Write time: 1956 06:28:37 INFO - TinderboxPrint: CPU usage
54.3% 06:28:37 INFO - TinderboxPrint: I/O read bytes / time
39,599,104 / 388 06:28:37 INFO - TinderboxPrint: I/O write bytes / time
533,175,296 / 1,956 06:28:37 INFO - TinderboxPrint: CPU idle
2,190.7 (45.7%) 06:28:37 INFO - TinderboxPrint: CPU system
969.8 (20.2%) 06:28:37 INFO - TinderboxPrint: CPU user
1,637.2 (34.1%) 06:28:37 INFO - TinderboxPrint: Swap in / out
254,455,808 / 0 06:28:37 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 210010624; Write bytes: 210319360; Read time: 16617; Write time: 537 06:28:37 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 37596160; Write bytes: 312140800; Read time: 366; Write time: 1385 06:28:37 INFO - Running post-run listener: _upload_blobber_files 06:28:37 INFO - Blob upload gear active. 06:28:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:28:37 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 06:28:37 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'] 06:28:37 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 06:28:37 INFO - (blobuploader) - INFO - Open directory for files ... 06:28:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:28:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:28:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:28:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:28:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:28:38 INFO - (blobuploader) - INFO - Done attempting. 06:28:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:28:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:28:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:28:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:28:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:28:39 INFO - (blobuploader) - INFO - Done attempting. 06:28:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:28:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:28:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:28:40 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:28:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:28:40 INFO - (blobuploader) - INFO - Done attempting. 06:28:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:28:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:28:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:28:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:28:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:28:42 INFO - (blobuploader) - INFO - Done attempting. 06:28:42 INFO - (blobuploader) - INFO - Iteration through files over. 06:28:42 INFO - Return code: 0 06:28:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:28:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:28:42 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6dcd6e50cb5b6f173ae5f0ad11fa2e2424d30dedd83bd3f8bc23cb0a42390023a87d3b65f68dba44b6978da1bdd12684a8ef47c746803c2d9e39408be8e20eee", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f13db04c62d0a148f6397aed2a2fc12e4a6e2bd19645fa3efee1dde88ed5ca0f75ff0f20d3293b21e6b33cc18495f328a38c27b8b9e405bba62b9ab381378279", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e23754d9cd07c0eb607e6afa254963bd34433eef9910ae5b27c13a40a2bca1c3e422db5ede4b0699d24b700979a48229526c615428ff578d4af56ffea594eec0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d540f9eb639e77eb501ce1bd2e26d4f3783a5fad0b08d36246dc9f5926e9ef5f4bdee7b86d6595971647ffdb5498faf00ad19e6fa6a453d3107846a84c95620"} 06:28:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:28:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:28:42 INFO - Contents: 06:28:42 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6dcd6e50cb5b6f173ae5f0ad11fa2e2424d30dedd83bd3f8bc23cb0a42390023a87d3b65f68dba44b6978da1bdd12684a8ef47c746803c2d9e39408be8e20eee", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f13db04c62d0a148f6397aed2a2fc12e4a6e2bd19645fa3efee1dde88ed5ca0f75ff0f20d3293b21e6b33cc18495f328a38c27b8b9e405bba62b9ab381378279", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e23754d9cd07c0eb607e6afa254963bd34433eef9910ae5b27c13a40a2bca1c3e422db5ede4b0699d24b700979a48229526c615428ff578d4af56ffea594eec0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d540f9eb639e77eb501ce1bd2e26d4f3783a5fad0b08d36246dc9f5926e9ef5f4bdee7b86d6595971647ffdb5498faf00ad19e6fa6a453d3107846a84c95620"} 06:28:42 INFO - Running post-run listener: copy_logs_to_upload_dir 06:28:42 INFO - Copying logs to upload dir... 06:28:42 INFO - mkdir: /builds/slave/test/build/upload/logs 06:28:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1282.971257 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-05-15 06:28:42.563826) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-15 06:28:42.567412) ========= 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.A2NmusmDVI/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.kJR4iXT1Fx/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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/6dcd6e50cb5b6f173ae5f0ad11fa2e2424d30dedd83bd3f8bc23cb0a42390023a87d3b65f68dba44b6978da1bdd12684a8ef47c746803c2d9e39408be8e20eee", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f13db04c62d0a148f6397aed2a2fc12e4a6e2bd19645fa3efee1dde88ed5ca0f75ff0f20d3293b21e6b33cc18495f328a38c27b8b9e405bba62b9ab381378279", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e23754d9cd07c0eb607e6afa254963bd34433eef9910ae5b27c13a40a2bca1c3e422db5ede4b0699d24b700979a48229526c615428ff578d4af56ffea594eec0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d540f9eb639e77eb501ce1bd2e26d4f3783a5fad0b08d36246dc9f5926e9ef5f4bdee7b86d6595971647ffdb5498faf00ad19e6fa6a453d3107846a84c95620"} build_url:https://queue.taskcluster.net/v1/task/RNpQdgWsSoqEFN1nzzdpoA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011760 build_url: 'https://queue.taskcluster.net/v1/task/RNpQdgWsSoqEFN1nzzdpoA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6dcd6e50cb5b6f173ae5f0ad11fa2e2424d30dedd83bd3f8bc23cb0a42390023a87d3b65f68dba44b6978da1bdd12684a8ef47c746803c2d9e39408be8e20eee", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f13db04c62d0a148f6397aed2a2fc12e4a6e2bd19645fa3efee1dde88ed5ca0f75ff0f20d3293b21e6b33cc18495f328a38c27b8b9e405bba62b9ab381378279", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e23754d9cd07c0eb607e6afa254963bd34433eef9910ae5b27c13a40a2bca1c3e422db5ede4b0699d24b700979a48229526c615428ff578d4af56ffea594eec0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d540f9eb639e77eb501ce1bd2e26d4f3783a5fad0b08d36246dc9f5926e9ef5f4bdee7b86d6595971647ffdb5498faf00ad19e6fa6a453d3107846a84c95620"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-15 06:28:42.601575) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 06:28:42.601898) ========= 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.A2NmusmDVI/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.kJR4iXT1Fx/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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.004833 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 06:28:42.651124) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-15 06:28:42.651495) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-15 06:28:42.651885) ========= ========= Total master_lag: 0.18 =========