builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0188 starttime: 1495458139.03 results: success (0) revision: a55de96fd138dd7d80024625a7b803a9a4c647ee ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-22 06:02:19.026702) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-22 06:02:19.027165) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-22 06:02:19.027440) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-22 06:02:19.051410) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 06:02:19.051789) ========= 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.cUFNZpjl31/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.yLloEyc57B/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-22 06:02:19-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 13.0M=0.001s 2017-05-22 06:02:19 (13.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.135844 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 06:02:19.209558) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 06:02:19.209891) ========= 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.cUFNZpjl31/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.yLloEyc57B/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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.021656 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 06:02:19.258824) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-22 06:02:19.259162) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a55de96fd138dd7d80024625a7b803a9a4c647ee --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 a55de96fd138dd7d80024625a7b803a9a4c647ee --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.cUFNZpjl31/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.yLloEyc57B/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-22 06:02:19,336 truncating revision to first 12 chars 2017-05-22 06:02:19,336 Setting DEBUG logging. 2017-05-22 06:02:19,336 attempt 1/10 2017-05-22 06:02:19,336 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a55de96fd138?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-22 06:02:20,068 unpacking tar archive at: mozilla-central-a55de96fd138/testing/mozharness/ program finished with exit code 0 elapsedTime=1.000765 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-22 06:02:20.282447) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-22 06:02:20.283007) ========= script_repo_revision: a55de96fd138dd7d80024625a7b803a9a4c647ee ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-22 06:02:20.283620) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-22 06:02:20.284096) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-22 06:02:20.303408) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-05-22 06:02:20.303773) ========= /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.cUFNZpjl31/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.yLloEyc57B/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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:02:20 INFO - MultiFileLogger online at 20170522 06:02:20 in /builds/slave/test 06:02:20 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:02:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:02:20 INFO - {'append_to_log': False, 06:02:20 INFO - 'base_work_dir': '/builds/slave/test', 06:02:20 INFO - 'blob_upload_branch': 'Firefox', 06:02:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:02:20 INFO - 'branch': 'Firefox', 06:02:20 INFO - 'buildbot_json_path': 'buildprops.json', 06:02:20 INFO - 'config_files': ('talos/mac_config.py',), 06:02:20 INFO - 'default_actions': ('clobber', 06:02:20 INFO - 'read-buildbot-config', 06:02:20 INFO - 'download-and-extract', 06:02:20 INFO - 'populate-webroot', 06:02:20 INFO - 'create-virtualenv', 06:02:20 INFO - 'install', 06:02:20 INFO - 'run-tests'), 06:02:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:02:20 INFO - 'download_minidump_stackwalk': True, 06:02:20 INFO - 'download_symbols': 'ondemand', 06:02:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:02:20 INFO - 'tooltool.py': '/tools/tooltool.py', 06:02:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:02:20 INFO - '/tools/misc-python/virtualenv.py')}, 06:02:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:02:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:02:20 INFO - 'gecko_profile': False, 06:02:20 INFO - 'gecko_profile_interval': 0, 06:02:20 INFO - 'installer_path': 'installer.exe', 06:02:20 INFO - 'log_level': 'info', 06:02:20 INFO - 'log_name': 'talos', 06:02:20 INFO - 'log_to_console': True, 06:02:20 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:02:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:02:20 INFO - 'opt_config_files': (), 06:02:20 INFO - 'pip_index': False, 06:02:20 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:02:20 INFO - 'cmd': ('bash', 06:02:20 INFO - '-c', 06:02:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:02:20 INFO - 'enabled': True, 06:02:20 INFO - 'halt_on_failure': False, 06:02:20 INFO - 'name': 'check_screen_resolution'},), 06:02:20 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:02:20 INFO - 'cmd': ('bash', 06:02:20 INFO - '-c', 06:02:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:02:20 INFO - 'enabled': True, 06:02:20 INFO - 'halt_on_failure': False, 06:02:20 INFO - 'name': 'check_screen_resolution'},), 06:02:20 INFO - 'run_cmd_checks_enabled': True, 06:02:20 INFO - 'suite': 'dromaeojs-e10s', 06:02:20 INFO - 'system_bits': '32', 06:02:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:02:20 INFO - 'title': 't-yosemite-r7-0188', 06:02:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:02:20 INFO - 'use_talos_json': True, 06:02:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:02:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:02:20 INFO - 'work_dir': 'build'} 06:02:20 INFO - [mozharness: 2017-05-22 13:02:20.508678Z] Running clobber step. 06:02:20 INFO - Running pre-action listener: _resource_record_pre_action 06:02:20 INFO - Running main action method: clobber 06:02:20 INFO - rmtree: /builds/slave/test/build 06:02:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:02:22 INFO - Running post-action listener: _resource_record_post_action 06:02:22 INFO - [mozharness: 2017-05-22 13:02:22.329030Z] Finished clobber step (success) 06:02:22 INFO - [mozharness: 2017-05-22 13:02:22.329177Z] Running read-buildbot-config step. 06:02:22 INFO - Running pre-action listener: _resource_record_pre_action 06:02:22 INFO - Running main action method: read_buildbot_config 06:02:22 INFO - Using buildbot properties: 06:02:22 INFO - { 06:02:22 INFO - "project": "", 06:02:22 INFO - "product": "firefox", 06:02:22 INFO - "who": "cbook@mozilla.com", 06:02:22 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 06:02:22 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 06:02:22 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 06:02:22 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 06:02:22 INFO - "stage_platform": "macosx64", 06:02:22 INFO - "basedir": "/builds/slave/test", 06:02:22 INFO - "buildnumber": 79, 06:02:22 INFO - "platform": "yosemite_r7", 06:02:22 INFO - "parent_task_id": "AaMTRejTQ9OyomyvcjX0oQ", 06:02:22 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 06:02:22 INFO - "slavebuilddir": "test", 06:02:22 INFO - "taskId": "PmjhvGSgRwawyv2jjdCBkA", 06:02:22 INFO - "branch": "mozilla-central", 06:02:22 INFO - "script_repo_revision": "production", 06:02:22 INFO - "revision": "a55de96fd138dd7d80024625a7b803a9a4c647ee", 06:02:22 INFO - "slavename": "t-yosemite-r7-0188", 06:02:22 INFO - "repo_path": "mozilla-central" 06:02:22 INFO - } 06:02:22 INFO - Finding installer, test and symbols from parent task. 06:02:22 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PmjhvGSgRwawyv2jjdCBkA'}, attempt #1 06:02:22 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PmjhvGSgRwawyv2jjdCBkA'}, attempt #1 06:02:23 INFO - Task dependencies: KbT84vtuQQigX4yxbEdAhg AaMTRejTQ9OyomyvcjX0oQ 06:02:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AaMTRejTQ9OyomyvcjX0oQ'}, attempt #1 06:02:23 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/AaMTRejTQ9OyomyvcjX0oQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 06:02:23 INFO - Set test_url: https://queue.taskcluster.net/v1/task/AaMTRejTQ9OyomyvcjX0oQ/artifacts/public/build/test_packages.json 06:02:23 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/AaMTRejTQ9OyomyvcjX0oQ/artifacts/public/build/target.crashreporter-symbols.zip 06:02:23 INFO - Running post-action listener: _resource_record_post_action 06:02:23 INFO - [mozharness: 2017-05-22 13:02:23.689282Z] Finished read-buildbot-config step (success) 06:02:23 INFO - [mozharness: 2017-05-22 13:02:23.689432Z] Running download-and-extract step. 06:02:23 INFO - Running pre-action listener: _resource_record_pre_action 06:02:23 INFO - Running main action method: download_and_extract 06:02:23 INFO - mkdir: /builds/slave/test/build/tests 06:02: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:02:23 INFO - trying https://queue.taskcluster.net/v1/task/AaMTRejTQ9OyomyvcjX0oQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 06:02:23 INFO - Downloading https://queue.taskcluster.net/v1/task/AaMTRejTQ9OyomyvcjX0oQ/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:02:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AaMTRejTQ9OyomyvcjX0oQ/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:02:24 INFO - Downloaded 1325 bytes. 06:02:24 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 06:02:24 INFO - Using the following test package requirements: 06:02:24 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:02:24 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 06:02:24 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 06:02:24 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:02:24 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 06:02:24 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:02:24 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 06:02:24 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:02:24 INFO - u'jsshell-mac.zip'], 06:02:24 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:02:24 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 06:02:24 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 06:02:24 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:02:24 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 06:02:24 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:02:24 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 06:02:24 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:02:24 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 06:02:24 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:02:24 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 06:02:24 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:02:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AaMTRejTQ9OyomyvcjX0oQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 06:02:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AaMTRejTQ9OyomyvcjX0oQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:02:24 INFO - Fetch https://queue.taskcluster.net/v1/task/AaMTRejTQ9OyomyvcjX0oQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 06:02:26 INFO - Content-Length response header: 57622677 06:02:26 INFO - Bytes received: 57622677 06:02:44 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:02:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AaMTRejTQ9OyomyvcjX0oQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 06:02:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AaMTRejTQ9OyomyvcjX0oQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:02:44 INFO - Fetch https://queue.taskcluster.net/v1/task/AaMTRejTQ9OyomyvcjX0oQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 06:02:45 INFO - Content-Length response header: 57622677 06:02:45 INFO - Bytes received: 57622677 06:03:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AaMTRejTQ9OyomyvcjX0oQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 06:03:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AaMTRejTQ9OyomyvcjX0oQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 06:03:03 INFO - Fetch https://queue.taskcluster.net/v1/task/AaMTRejTQ9OyomyvcjX0oQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 06:03:04 INFO - Content-Length response header: 14410000 06:03:04 INFO - Bytes received: 14410000 06:03:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:03:04 INFO - trying https://queue.taskcluster.net/v1/task/AaMTRejTQ9OyomyvcjX0oQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 06:03:04 INFO - Downloading https://queue.taskcluster.net/v1/task/AaMTRejTQ9OyomyvcjX0oQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 06:03:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AaMTRejTQ9OyomyvcjX0oQ/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:03:06 INFO - Downloaded 68004619 bytes. 06:03:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AaMTRejTQ9OyomyvcjX0oQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 06:03:06 INFO - mkdir: /builds/slave/test/properties 06:03:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:03:06 INFO - Writing to file /builds/slave/test/properties/build_url 06:03:06 INFO - Contents: 06:03:06 INFO - build_url:https://queue.taskcluster.net/v1/task/AaMTRejTQ9OyomyvcjX0oQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 06:03:06 INFO - Running post-action listener: _resource_record_post_action 06:03:06 INFO - Running post-action listener: set_extra_try_arguments 06:03:06 INFO - [mozharness: 2017-05-22 13:03:06.949672Z] Finished download-and-extract step (success) 06:03:06 INFO - [mozharness: 2017-05-22 13:03:06.949815Z] Running populate-webroot step. 06:03:06 INFO - Running pre-action listener: _resource_record_pre_action 06:03:06 INFO - Running main action method: populate_webroot 06:03:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:03:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:03:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:03:06 INFO - '--tppagecycles', 06:03:06 INFO - '7'], 06:03:06 INFO - 'tests': ['tsvgm']}}, 06:03:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:03:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:03:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:03:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:03:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:03:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:03:06 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 06:03:06 INFO - 'talos_options': ['--disable-e10s'], 06:03:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:03:06 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 06:03:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:03:06 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 06:03:06 INFO - 'talos_options': ['--disable-e10s'], 06:03:06 INFO - 'tests': ['damp', 'tps']}, 06:03:06 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 06:03:06 INFO - 'tests': ['damp', 'tps']}, 06:03:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:03:06 INFO - 'tests': ['dromaeo_dom']}, 06:03:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:03:06 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:03:06 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 06:03:06 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 06:03:06 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 06:03:06 INFO - 'tests': ['ts_paint', 'tp5o']}, 06:03:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:03:06 INFO - 'tests': ['a11yr', 06:03:06 INFO - 'ts_paint', 06:03:06 INFO - 'tpaint', 06:03:06 INFO - 'sessionrestore', 06:03:06 INFO - 'sessionrestore_no_auto_restore', 06:03:06 INFO - 'tabpaint']}, 06:03:06 INFO - 'other-e10s': {'tests': ['a11yr', 06:03:06 INFO - 'ts_paint', 06:03:06 INFO - 'tpaint', 06:03:06 INFO - 'sessionrestore', 06:03:06 INFO - 'sessionrestore_no_auto_restore', 06:03:06 INFO - 'tabpaint']}, 06:03:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:03:06 INFO - 'ts_paint', 06:03:06 INFO - 'tpaint', 06:03:06 INFO - 'sessionrestore', 06:03:06 INFO - 'sessionrestore_no_auto_restore', 06:03:06 INFO - 'tabpaint']}, 06:03:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:03:06 INFO - 'ts_paint', 06:03:06 INFO - 'tpaint', 06:03:06 INFO - 'sessionrestore', 06:03:06 INFO - 'sessionrestore_no_auto_restore', 06:03:06 INFO - 'tabpaint']}, 06:03:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:03:06 INFO - 'tests': ['a11yr', 06:03:06 INFO - 'ts_paint', 06:03:06 INFO - 'tpaint', 06:03:06 INFO - 'sessionrestore', 06:03:06 INFO - 'sessionrestore_no_auto_restore', 06:03:06 INFO - 'tabpaint']}, 06:03:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:03:06 INFO - 'tests': ['a11yr', 06:03:06 INFO - 'ts_paint', 06:03:06 INFO - 'tpaint', 06:03:06 INFO - 'sessionrestore', 06:03:06 INFO - 'sessionrestore_no_auto_restore', 06:03:06 INFO - 'tabpaint']}, 06:03:06 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 06:03:06 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 06:03:06 INFO - 'bloom_basic_ref']}, 06:03:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:03:06 INFO - 'tests': ['tsvgx', 06:03:06 INFO - 'tsvgr_opacity', 06:03:06 INFO - 'tart', 06:03:06 INFO - 'tscrollx', 06:03:06 INFO - 'cart', 06:03:06 INFO - 'tsvg_static']}, 06:03:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:03:06 INFO - 'tsvgr_opacity', 06:03:06 INFO - 'tart', 06:03:06 INFO - 'tscrollx', 06:03:06 INFO - 'cart', 06:03:06 INFO - 'tsvg_static']}, 06:03:06 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 06:03:06 INFO - 'talos_options': ['--disable-e10s'], 06:03:06 INFO - 'tests': ['tp5o']}, 06:03:06 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 06:03:06 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 06:03:06 INFO - 'talos_options': ['--disable-e10s', 06:03:06 INFO - '--xperf_path', 06:03:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:03:06 INFO - 'tests': ['tp5n']}, 06:03:06 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 06:03:06 INFO - 'talos_options': ['--xperf_path', 06:03:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:03:06 INFO - 'tests': ['tp5n']}}, 06:03:06 INFO - 'talos.zip': {'path': '', 06:03:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:03:06 INFO - Running post-action listener: _resource_record_post_action 06:03:06 INFO - [mozharness: 2017-05-22 13:03:06.971667Z] Finished populate-webroot step (success) 06:03:06 INFO - [mozharness: 2017-05-22 13:03:06.971808Z] Running create-virtualenv step. 06:03:06 INFO - Running pre-action listener: _resource_record_pre_action 06:03:06 INFO - Running main action method: create_virtualenv 06:03:06 INFO - Creating virtualenv /builds/slave/test/build/venv 06:03:06 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:03:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:03:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:03:07 INFO - Using real prefix '/tools/python27' 06:03:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:03:07 INFO - Installing distribute.............................................................................................................................................................................................done. 06:03:10 INFO - Installing pip.................done. 06:03:10 INFO - Return code: 0 06:03:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:03:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:03:11 INFO - Reading from file tmpfile_stdout 06:03:11 INFO - Output received: 06:03:11 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:03:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:03:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:03:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:03:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:03:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:03:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107e0cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e10768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1c9db0a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b1cd30>, '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 0x107ad0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b11be8>, '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.cUFNZpjl31/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.yLloEyc57B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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:03:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 06:03:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 06:03:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cUFNZpjl31/Render', 06:03:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:03:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:03:11 INFO - 'HOME': '/Users/cltbld', 06:03:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:03:11 INFO - 'LOGNAME': 'cltbld', 06:03:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:03:11 INFO - 'MOZ_NO_REMOTE': '1', 06:03:11 INFO - 'NO_EM_RESTART': '1', 06:03:11 INFO - 'PAGER': '/bin/cat', 06:03:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:03:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:03:11 INFO - 'PWD': '/builds/slave/test', 06:03:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:03:11 INFO - 'SHELL': '/bin/bash', 06:03:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yLloEyc57B/Listeners', 06:03:11 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 06:03:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:03:11 INFO - 'USER': 'cltbld', 06:03:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:03:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:03:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:03:11 INFO - 'XPC_FLAGS': '0x0', 06:03:11 INFO - 'XPC_SERVICE_NAME': '0', 06:03:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:03:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:03:11 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:03:11 INFO - Cleaning up... 06:03:11 INFO - Return code: 0 06:03:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:03:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:03:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:03:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:03:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:03:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107e0cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e10768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1c9db0a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b1cd30>, '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 0x107ad0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b11be8>, '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.cUFNZpjl31/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.yLloEyc57B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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:03:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 06:03:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 06:03:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cUFNZpjl31/Render', 06:03:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:03:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:03:11 INFO - 'HOME': '/Users/cltbld', 06:03:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:03:11 INFO - 'LOGNAME': 'cltbld', 06:03:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:03:11 INFO - 'MOZ_NO_REMOTE': '1', 06:03:11 INFO - 'NO_EM_RESTART': '1', 06:03:11 INFO - 'PAGER': '/bin/cat', 06:03:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:03:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:03:11 INFO - 'PWD': '/builds/slave/test', 06:03:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:03:11 INFO - 'SHELL': '/bin/bash', 06:03:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yLloEyc57B/Listeners', 06:03:11 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 06:03:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:03:11 INFO - 'USER': 'cltbld', 06:03:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:03:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:03:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:03:11 INFO - 'XPC_FLAGS': '0x0', 06:03:11 INFO - 'XPC_SERVICE_NAME': '0', 06:03:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:03:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:03:11 INFO - Downloading/unpacking psutil>=3.1.1 06:03:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:03:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:03:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:03:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:03:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:03:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:03:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:03:13 INFO - Installing collected packages: psutil 06:03:13 INFO - Running setup.py install for psutil 06:03:13 INFO - building 'psutil._psutil_osx' extension 06:03:13 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:03:13 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:03:13 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:03:14 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:03:14 INFO - building 'psutil._psutil_posix' extension 06:03:14 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:03:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:03:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:03:14 INFO - ^ 06:03:14 INFO - 1 warning generated. 06:03:14 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:03:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:03:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:03:14 INFO - Successfully installed psutil 06:03:14 INFO - Cleaning up... 06:03:14 INFO - Return code: 0 06:03:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:03: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:03:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:03: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:03:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:03: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107e0cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e10768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1c9db0a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b1cd30>, '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 0x107ad0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b11be8>, '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.cUFNZpjl31/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.yLloEyc57B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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:03: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 06:03: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 mozsystemmonitor==0.3 06:03:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cUFNZpjl31/Render', 06:03:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:03:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:03:14 INFO - 'HOME': '/Users/cltbld', 06:03:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:03:14 INFO - 'LOGNAME': 'cltbld', 06:03:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:03:14 INFO - 'MOZ_NO_REMOTE': '1', 06:03:14 INFO - 'NO_EM_RESTART': '1', 06:03:14 INFO - 'PAGER': '/bin/cat', 06:03:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:03:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:03:14 INFO - 'PWD': '/builds/slave/test', 06:03:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:03:14 INFO - 'SHELL': '/bin/bash', 06:03:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yLloEyc57B/Listeners', 06:03:14 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 06:03:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:03:14 INFO - 'USER': 'cltbld', 06:03:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:03:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:03:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:03:14 INFO - 'XPC_FLAGS': '0x0', 06:03:14 INFO - 'XPC_SERVICE_NAME': '0', 06:03:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:03:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:03:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:03: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:03: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:03: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:03: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:03:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:03:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:03:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:03:16 INFO - Installing collected packages: mozsystemmonitor 06:03:16 INFO - Running setup.py install for mozsystemmonitor 06:03:16 INFO - Successfully installed mozsystemmonitor 06:03:16 INFO - Cleaning up... 06:03:16 INFO - Return code: 0 06:03:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:03:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:03:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:03:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:03:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:03:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107e0cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e10768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1c9db0a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b1cd30>, '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 0x107ad0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b11be8>, '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.cUFNZpjl31/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.yLloEyc57B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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:03:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 06:03:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 06:03:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cUFNZpjl31/Render', 06:03:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:03:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:03:17 INFO - 'HOME': '/Users/cltbld', 06:03:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:03:17 INFO - 'LOGNAME': 'cltbld', 06:03:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:03:17 INFO - 'MOZ_NO_REMOTE': '1', 06:03:17 INFO - 'NO_EM_RESTART': '1', 06:03:17 INFO - 'PAGER': '/bin/cat', 06:03:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:03:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:03:17 INFO - 'PWD': '/builds/slave/test', 06:03:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:03:17 INFO - 'SHELL': '/bin/bash', 06:03:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yLloEyc57B/Listeners', 06:03:17 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 06:03:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:03:17 INFO - 'USER': 'cltbld', 06:03:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:03:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:03:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:03:17 INFO - 'XPC_FLAGS': '0x0', 06:03:17 INFO - 'XPC_SERVICE_NAME': '0', 06:03:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:03:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:03:17 INFO - Downloading/unpacking jsonschema==2.5.1 06:03:17 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:03:17 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:03:17 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:03:17 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:03:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:03:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:03:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:03:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:03:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:03:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:03:19 INFO - Downloading functools32-3.2.3-2.tar.gz 06:03:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:03:19 INFO - warning: no files found matching '*.txt' 06:03:19 INFO - no previously-included directories found matching 'build' 06:03:19 INFO - no previously-included directories found matching 'dist' 06:03:19 INFO - no previously-included directories found matching '.git*' 06:03:19 INFO - Installing collected packages: jsonschema, functools32 06:03:19 INFO - Running setup.py install for functools32 06:03:19 INFO - warning: no files found matching '*.txt' 06:03:19 INFO - no previously-included directories found matching 'build' 06:03:19 INFO - no previously-included directories found matching 'dist' 06:03:19 INFO - no previously-included directories found matching '.git*' 06:03:19 INFO - Successfully installed jsonschema functools32 06:03:19 INFO - Cleaning up... 06:03:19 INFO - Return code: 0 06:03:19 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:03:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:03:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:03:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:03:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:03:19 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 0x107e0cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e10768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1c9db0a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b1cd30>, '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 0x107ad0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b11be8>, '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.cUFNZpjl31/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.yLloEyc57B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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:03:19 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:03:19 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:03:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cUFNZpjl31/Render', 06:03:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:03:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:03:19 INFO - 'HOME': '/Users/cltbld', 06:03:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:03:19 INFO - 'LOGNAME': 'cltbld', 06:03:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:03:19 INFO - 'MOZ_NO_REMOTE': '1', 06:03:19 INFO - 'NO_EM_RESTART': '1', 06:03:19 INFO - 'PAGER': '/bin/cat', 06:03:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:03:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:03:19 INFO - 'PWD': '/builds/slave/test', 06:03:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:03:19 INFO - 'SHELL': '/bin/bash', 06:03:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yLloEyc57B/Listeners', 06:03:19 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 06:03:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:03:19 INFO - 'USER': 'cltbld', 06:03:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:03:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:03:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:03:19 INFO - 'XPC_FLAGS': '0x0', 06:03:19 INFO - 'XPC_SERVICE_NAME': '0', 06:03:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:03:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:03:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:03:19 INFO - Cleaning up... 06:03:19 INFO - Return code: 0 06:03:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:03:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:03:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:03:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:03:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:03:19 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 0x107e0cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e10768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1c9db0a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b1cd30>, '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 0x107ad0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b11be8>, '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.cUFNZpjl31/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.yLloEyc57B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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:03:19 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:03:19 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:03:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cUFNZpjl31/Render', 06:03:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:03:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:03:19 INFO - 'HOME': '/Users/cltbld', 06:03:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:03:19 INFO - 'LOGNAME': 'cltbld', 06:03:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:03:19 INFO - 'MOZ_NO_REMOTE': '1', 06:03:19 INFO - 'NO_EM_RESTART': '1', 06:03:19 INFO - 'PAGER': '/bin/cat', 06:03:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:03:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:03:19 INFO - 'PWD': '/builds/slave/test', 06:03:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:03:19 INFO - 'SHELL': '/bin/bash', 06:03:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yLloEyc57B/Listeners', 06:03:19 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 06:03:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:03:19 INFO - 'USER': 'cltbld', 06:03:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:03:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:03:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:03:19 INFO - 'XPC_FLAGS': '0x0', 06:03:19 INFO - 'XPC_SERVICE_NAME': '0', 06:03:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:03:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:03:20 INFO - Downloading/unpacking blobuploader==1.2.4 06:03: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:03: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:03: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:03: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:03:21 INFO - Downloading blobuploader-1.2.4.tar.gz 06:03:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:03:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:03:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:03:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:03:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:03:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:03:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:03:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:03: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:03: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:03: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:03: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:03:22 INFO - Downloading docopt-0.6.1.tar.gz 06:03:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:03:22 INFO - Installing collected packages: blobuploader, requests, docopt 06:03:22 INFO - Running setup.py install for blobuploader 06:03:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:03:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:03:22 INFO - Running setup.py install for requests 06:03:23 INFO - Running setup.py install for docopt 06:03:23 INFO - Successfully installed blobuploader requests docopt 06:03:23 INFO - Cleaning up... 06:03:23 INFO - Return code: 0 06:03:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:03: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:03:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:03: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:03:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:03:23 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 0x107e0cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e10768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1c9db0a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b1cd30>, '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 0x107ad0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b11be8>, '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.cUFNZpjl31/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.yLloEyc57B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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:03:23 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:03:23 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:03:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cUFNZpjl31/Render', 06:03:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:03:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:03:23 INFO - 'HOME': '/Users/cltbld', 06:03:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:03:23 INFO - 'LOGNAME': 'cltbld', 06:03:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:03:23 INFO - 'MOZ_NO_REMOTE': '1', 06:03:23 INFO - 'NO_EM_RESTART': '1', 06:03:23 INFO - 'PAGER': '/bin/cat', 06:03:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:03:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:03:23 INFO - 'PWD': '/builds/slave/test', 06:03:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:03:23 INFO - 'SHELL': '/bin/bash', 06:03:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yLloEyc57B/Listeners', 06:03:23 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 06:03:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:03:23 INFO - 'USER': 'cltbld', 06:03:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:03:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:03:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:03:23 INFO - 'XPC_FLAGS': '0x0', 06:03:23 INFO - 'XPC_SERVICE_NAME': '0', 06:03:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:03:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:03:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:03:23 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-39HuaG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:03:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:03:23 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-t3txsG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:03:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:03:23 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-H18tzc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:03:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:03:24 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-kDmW4V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:03:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:03:24 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-Ue60Vr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:03:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:03:24 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-v5mIVP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:03:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:03:24 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-w03bFB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:03:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:03:24 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-IFzsb_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:03:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:03:24 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-Hk7OU7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:03:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:03:24 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-aVNf79-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:03:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:03:24 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-IKuM7d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:03:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:03:24 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-6jsmFC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:03:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:03:25 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-ghlqQp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:03:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:03:25 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-dqiPvR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:03:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:03:25 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-k7cLPX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:03:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:03:25 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-4vafY6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:03:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:03:25 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-RZPa2W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:03:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:03:25 INFO - Running setup.py install for manifestparser 06:03:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:03:25 INFO - Running setup.py install for mozcrash 06:03:25 INFO - Running setup.py install for mozdebug 06:03:25 INFO - Running setup.py install for mozdevice 06:03:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:03:26 INFO - Running setup.py install for mozfile 06:03:26 INFO - Running setup.py install for mozhttpd 06:03:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:03:26 INFO - Running setup.py install for mozinfo 06:03:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:03:26 INFO - Running setup.py install for mozInstall 06:03:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:03:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:03:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:03:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:03:26 INFO - Running setup.py install for mozleak 06:03:26 INFO - Running setup.py install for mozlog 06:03:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:03:27 INFO - Running setup.py install for moznetwork 06:03:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:03:27 INFO - Running setup.py install for mozprocess 06:03:27 INFO - Running setup.py install for mozprofile 06:03:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:03:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:03:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:03:27 INFO - Running setup.py install for mozrunner 06:03:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:03:27 INFO - Running setup.py install for mozscreenshot 06:03:27 INFO - Running setup.py install for moztest 06:03:27 INFO - Running setup.py install for mozversion 06:03:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:03:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:03:28 INFO - Cleaning up... 06:03:28 INFO - Return code: 0 06:03:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:03:28 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:03:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:03:28 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:03:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:03:28 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 0x107e0cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e10768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1c9db0a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b1cd30>, '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 0x107ad0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b11be8>, '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.cUFNZpjl31/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.yLloEyc57B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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:03:28 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:03:28 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:03:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cUFNZpjl31/Render', 06:03:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:03:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:03:28 INFO - 'HOME': '/Users/cltbld', 06:03:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:03:28 INFO - 'LOGNAME': 'cltbld', 06:03:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:03:28 INFO - 'MOZ_NO_REMOTE': '1', 06:03:28 INFO - 'NO_EM_RESTART': '1', 06:03:28 INFO - 'PAGER': '/bin/cat', 06:03:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:03:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:03:28 INFO - 'PWD': '/builds/slave/test', 06:03:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:03:28 INFO - 'SHELL': '/bin/bash', 06:03:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yLloEyc57B/Listeners', 06:03:28 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 06:03:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:03:28 INFO - 'USER': 'cltbld', 06:03:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:03:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:03:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:03:28 INFO - 'XPC_FLAGS': '0x0', 06:03:28 INFO - 'XPC_SERVICE_NAME': '0', 06:03:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:03:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:03:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:03:28 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-bpMRJE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:03:28 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:03:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:03:28 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-FW6yOH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:03:28 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:03:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:03:28 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-TSmUY2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:03:28 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:03:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:03:28 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-P0JRwN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:03:28 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:03:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:03:28 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-9MeFja-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:03:28 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:03:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:03:28 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-3F4lkS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:03:29 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:03:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:03:29 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-wYsLzk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:03:29 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:03:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:03:29 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-fDI7y1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:03:29 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:03:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:03:29 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-Wt5z7E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:03:29 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:03:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:03:29 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-yPFcII-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:03:29 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:03:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:03:29 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-REGaLu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:03:29 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:03:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:03:29 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-XgeuqD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:03:29 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:03:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:03:29 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-ZN0ZYY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:03:29 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:03:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:03:29 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-v4zr6m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:03:29 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:03:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:03:29 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-8y8wpa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:03:29 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:03:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:03:30 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-e3o5oi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:03:30 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:03:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:03:30 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-Tykh6x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:03:30 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:03:30 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:03:30 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:03:30 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:03:30 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:03:30 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:03:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:03:30 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:03:30 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:03:30 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:03:30 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:03:31 INFO - Downloading blessings-1.6.tar.gz 06:03:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:03:31 INFO - Installing collected packages: blessings 06:03:31 INFO - Running setup.py install for blessings 06:03:31 INFO - Successfully installed blessings 06:03:31 INFO - Cleaning up... 06:03:31 INFO - Return code: 0 06:03:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:03:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:03:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:03:32 INFO - Reading from file tmpfile_stdout 06:03:32 INFO - Current package versions: 06:03:32 INFO - blessings == 1.6 06:03:32 INFO - blobuploader == 1.2.4 06:03:32 INFO - docopt == 0.6.1 06:03:32 INFO - functools32 == 3.2.3-2 06:03:32 INFO - jsonschema == 2.5.1 06:03:32 INFO - manifestparser == 1.1 06:03:32 INFO - mozInstall == 1.12 06:03:32 INFO - mozcrash == 1.0 06:03:32 INFO - mozdebug == 0.1 06:03:32 INFO - mozdevice == 0.50 06:03:32 INFO - mozfile == 1.2 06:03:32 INFO - mozhttpd == 0.7 06:03:32 INFO - mozinfo == 0.9 06:03:32 INFO - mozleak == 0.1 06:03:32 INFO - mozlog == 3.4 06:03:32 INFO - moznetwork == 0.27 06:03:32 INFO - mozprocess == 0.25 06:03:32 INFO - mozprofile == 0.28 06:03:32 INFO - mozrunner == 6.13 06:03:32 INFO - mozscreenshot == 0.1 06:03:32 INFO - mozsystemmonitor == 0.3 06:03:32 INFO - moztest == 0.8 06:03:32 INFO - mozversion == 1.4 06:03:32 INFO - psutil == 3.1.1 06:03:32 INFO - requests == 1.2.3 06:03:32 INFO - wsgiref == 0.1.2 06:03:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:03:32 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:03:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:03:32 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:03:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:03:32 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 0x107e0cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e10768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1c9db0a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b1cd30>, '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 0x107ad0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b11be8>, '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.cUFNZpjl31/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.yLloEyc57B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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:03:32 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:03:32 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:03:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cUFNZpjl31/Render', 06:03:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:03:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:03:32 INFO - 'HOME': '/Users/cltbld', 06:03:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:03:32 INFO - 'LOGNAME': 'cltbld', 06:03:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:03:32 INFO - 'MOZ_NO_REMOTE': '1', 06:03:32 INFO - 'NO_EM_RESTART': '1', 06:03:32 INFO - 'PAGER': '/bin/cat', 06:03:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:03:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:03:32 INFO - 'PWD': '/builds/slave/test', 06:03:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:03:32 INFO - 'SHELL': '/bin/bash', 06:03:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yLloEyc57B/Listeners', 06:03:32 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 06:03:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:03:32 INFO - 'USER': 'cltbld', 06:03:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:03:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:03:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:03:32 INFO - 'XPC_FLAGS': '0x0', 06:03:32 INFO - 'XPC_SERVICE_NAME': '0', 06:03:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:03:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:03:32 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:03:32 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:03:32 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:03:32 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:03:32 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:03:32 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:03:32 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:03:32 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:03:32 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:03:32 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:03:32 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:03:32 INFO - Cleaning up... 06:03:32 INFO - Return code: 0 06:03:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:03:32 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:03:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:03:32 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:03:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:03:32 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 0x107e0cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e10768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1c9db0a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b1cd30>, '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 0x107ad0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b11be8>, '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.cUFNZpjl31/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.yLloEyc57B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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:03:32 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:03:32 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:03:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cUFNZpjl31/Render', 06:03:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:03:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:03:32 INFO - 'HOME': '/Users/cltbld', 06:03:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:03:32 INFO - 'LOGNAME': 'cltbld', 06:03:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:03:32 INFO - 'MOZ_NO_REMOTE': '1', 06:03:32 INFO - 'NO_EM_RESTART': '1', 06:03:32 INFO - 'PAGER': '/bin/cat', 06:03:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:03:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:03:32 INFO - 'PWD': '/builds/slave/test', 06:03:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:03:32 INFO - 'SHELL': '/bin/bash', 06:03:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yLloEyc57B/Listeners', 06:03:32 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 06:03:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:03:32 INFO - 'USER': 'cltbld', 06:03:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:03:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:03:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:03:32 INFO - 'XPC_FLAGS': '0x0', 06:03:32 INFO - 'XPC_SERVICE_NAME': '0', 06:03:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:03:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:03:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:03:32 INFO - Cleaning up... 06:03:32 INFO - Return code: 0 06:03:32 INFO - Running post-action listener: _resource_record_post_action 06:03:32 INFO - Running post-action listener: _start_resource_monitoring 06:03:32 INFO - Starting resource monitoring. 06:03:32 INFO - [mozharness: 2017-05-22 13:03:32.728324Z] Finished create-virtualenv step (success) 06:03:32 INFO - [mozharness: 2017-05-22 13:03:32.729338Z] Running install step. 06:03:32 INFO - Running pre-action listener: _resource_record_pre_action 06:03:32 INFO - Running main action method: install 06:03:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:03:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:03:32 INFO - Reading from file tmpfile_stdout 06:03:32 INFO - Detecting whether we're running mozinstall >=1.0... 06:03:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:03:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:03:33 INFO - Reading from file tmpfile_stdout 06:03:33 INFO - Output received: 06:03:33 INFO - Usage: mozinstall [options] installer 06:03:33 INFO - Options: 06:03:33 INFO - -h, --help show this help message and exit 06:03:33 INFO - -d DEST, --destination=DEST 06:03:33 INFO - Directory to install application into. [default: 06:03:33 INFO - "/builds/slave/test"] 06:03:33 INFO - --app=APP Application being installed. [default: firefox] 06:03:33 INFO - mkdir: /builds/slave/test/build/application 06:03:33 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:03:33 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:03:54 INFO - Reading from file tmpfile_stdout 06:03:54 INFO - Output received: 06:03:54 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 06:03:54 INFO - Running post-action listener: _resource_record_post_action 06:03:54 INFO - [mozharness: 2017-05-22 13:03:54.746781Z] Finished install step (success) 06:03:54 INFO - [mozharness: 2017-05-22 13:03:54.746916Z] Running run-tests step. 06:03:54 INFO - Running pre-action listener: _resource_record_pre_action 06:03:54 INFO - Running main action method: run_tests 06:03:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:03:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:03:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:03:54 INFO - 2017-05-22 06:03:54.761 screenresolution[2892:47195] starting screenresolution argv=screenresolution get 06:03:54 INFO - 2017-05-22 06:03:54.764 screenresolution[2892:47195] Display 0: 1600x1200x32@60 06:03:54 INFO - 2017-05-22 06:03:54.770 screenresolution[2893:47198] starting screenresolution argv=screenresolution list 06:03:54 INFO - Available Modes on Display 0 06:03:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:03:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:03:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:03:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:03:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:03:54 INFO - Intel Iris: 06:03:54 INFO - Chipset Model: Intel Iris 06:03:54 INFO - Type: GPU 06:03:54 INFO - Bus: Built-In 06:03:54 INFO - VRAM (Dynamic, Max): 1536 MB 06:03:54 INFO - Vendor: Intel (0x8086) 06:03:54 INFO - Device ID: 0x0a2e 06:03:54 INFO - Revision ID: 0x0009 06:03:54 INFO - Displays: 06:03:54 INFO - Display: 06:03:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:03:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:03:54 INFO - Display Serial Number: 1600x1200 60 06:03:55 INFO - Main Display: Yes 06:03:55 INFO - Mirror: Off 06:03:55 INFO - Online: Yes 06:03:55 INFO - Rotation: Supported 06:03:55 INFO - Return code: 0 06:03:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:03:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:03:55 INFO - Python 2.7.3 06:03:55 INFO - Return code: 0 06:03:55 INFO - grabbing minidump binary from tooltool 06:03:55 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:03:55 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 0x107b1cd30>, '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 0x107ad0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b11be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:03:55 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:03:55 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:03:55 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:03:55 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:03:55 INFO - Return code: 0 06:03:55 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:03:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:03:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:03:55 INFO - ENV: RUST_BACKTRACE is now 1 06:03:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:03:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:03:55 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/AaMTRejTQ9OyomyvcjX0oQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0188', '--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:03:55 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/AaMTRejTQ9OyomyvcjX0oQ/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0188 --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:03:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cUFNZpjl31/Render', 06:03:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:03:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:03:55 INFO - 'HOME': '/Users/cltbld', 06:03:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:03:55 INFO - 'LOGNAME': 'cltbld', 06:03:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:03:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:03:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:03:55 INFO - 'MOZ_NO_REMOTE': '1', 06:03:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:03:55 INFO - 'NO_EM_RESTART': '1', 06:03:55 INFO - 'PAGER': '/bin/cat', 06:03:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:03:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:03:55 INFO - 'PWD': '/builds/slave/test', 06:03:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:03:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:03:55 INFO - 'RUST_BACKTRACE': '1', 06:03:55 INFO - 'SHELL': '/bin/bash', 06:03:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yLloEyc57B/Listeners', 06:03:55 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 06:03:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:03:55 INFO - 'USER': 'cltbld', 06:03:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:03:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:03:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:03:55 INFO - 'XPC_FLAGS': '0x0', 06:03:55 INFO - 'XPC_SERVICE_NAME': '0', 06:03:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:03:55 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/AaMTRejTQ9OyomyvcjX0oQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0188', '--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:03:55 INFO - mozversion application_buildid: 20170522040621 06:03:55 INFO - mozversion application_changeset: a55de96fd138dd7d80024625a7b803a9a4c647ee 06:03:55 INFO - mozversion application_display_name: Nightly 06:03:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:03:55 INFO - mozversion application_name: Firefox 06:03:55 INFO - mozversion application_remotingname: firefox 06:03:55 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 06:03:55 INFO - mozversion application_vendor: Mozilla 06:03:55 INFO - mozversion application_version: 55.0a1 06:03:55 INFO - mozversion platform_buildid: 20170522040621 06:03:55 INFO - mozversion platform_changeset: a55de96fd138dd7d80024625a7b803a9a4c647ee 06:03:55 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 06:03:55 INFO - mozversion platform_version: 55.0a1 06:03:55 INFO - using testdate: 1495458235 06:03:55 INFO - actual date: 1495458235 06:03:55 INFO - starting webserver on 'localhost:61541' 06:03:55 INFO - SUITE-START | Running 2 tests 06:03:55 INFO - TEST-START | dromaeo_css 06:03:55 INFO - operating with platform_type : mac_ 06:03:55 INFO - Initialising browser for dromaeo_css test... 06:03:55 INFO - TEST-INFO | started process 2903 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/tmp6i9vxU/profile http://localhost:61541/getInfo.html) 06:03:55 INFO - PID 2903 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2978: String.localeCompare is deprecated; use String.prototype.localeCompare instead 06:03:58 INFO - PID 2903 | 2017-05-22 06:03:58.018 plugin-container[2905:47363] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4e4f, name = 'com.apple.tsm.portname' 06:03:58 INFO - PID 2903 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:03:58 INFO - PID 2903 | 2017-05-22 06:03:58.023 plugin-container[2905:47363] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0xa30f, name = 'com.apple.CFPasteboardClient' 06:03:58 INFO - PID 2903 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:03:58 INFO - PID 2903 | 2017-05-22 06:03:58.023 plugin-container[2905:47363] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:03:58 INFO - PID 2903 | __metrics Screen width/height:1600/1200 06:03:58 INFO - PID 2903 | colorDepth:24 06:03:58 INFO - PID 2903 | Browser inner width/height: 1024/691 06:03:58 INFO - PID 2903 | __metrics 06:03:59 INFO - TEST-INFO | 2903: exit 0 06:03:59 INFO - Browser initialized. 06:03:59 INFO - Running cycle 1/1 for dromaeo_css test... 06:03:59 INFO - TEST-INFO | started process 2907 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/tmp6i9vxU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:04:00 INFO - PID 2907 | 2017-05-22 06:04:00.981 plugin-container[2908:47507] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.tsm.portname' 06:04:00 INFO - PID 2907 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:04:00 INFO - PID 2907 | 2017-05-22 06:04:00.982 plugin-container[2908:47507] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4643, name = 'com.apple.CFPasteboardClient' 06:04:00 INFO - PID 2907 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:04:00 INFO - PID 2907 | 2017-05-22 06:04:00.982 plugin-container[2908:47507] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:04:01 INFO - PID 2907 | 2017-05-22 06:04:01.752 plugin-container[2909:47552] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 06:04:01 INFO - PID 2907 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:04:01 INFO - PID 2907 | 2017-05-22 06:04:01.752 plugin-container[2909:47552] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 06:04:01 INFO - PID 2907 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:04:01 INFO - PID 2907 | 2017-05-22 06:04:01.752 plugin-container[2909:47552] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:04:11 INFO - PID 2907 | RSS: Main: 241782784 06:04:11 INFO - PID 2907 | 06:07:14 INFO - PID 2907 | Cycle 1(1): loaded http://localhost:61541/tests/dromaeo/cssquery-dojo.html (next: http://localhost:61541/tests/dromaeo/cssquery-ext.html) 06:07:16 INFO - PID 2907 | RSS: Main: 263184384 06:07:16 INFO - PID 2907 | 06:10:18 INFO - PID 2907 | Cycle 1(1): loaded http://localhost:61541/tests/dromaeo/cssquery-ext.html (next: http://localhost:61541/tests/dromaeo/cssquery-jquery.html) 06:10:18 INFO - PID 2907 | RSS: Main: 269946880 06:10:18 INFO - PID 2907 | 06:13:20 INFO - PID 2907 | Cycle 1(1): loaded http://localhost:61541/tests/dromaeo/cssquery-jquery.html (next: http://localhost:61541/tests/dromaeo/cssquery-mootools.html) 06:13:21 INFO - PID 2907 | RSS: Main: 274763776 06:13:21 INFO - PID 2907 | 06:16:22 INFO - PID 2907 | Cycle 1(1): loaded http://localhost:61541/tests/dromaeo/cssquery-mootools.html (next: http://localhost:61541/tests/dromaeo/cssquery-prototype.html) 06:16:23 INFO - PID 2907 | RSS: Main: 272486400 06:16:23 INFO - PID 2907 | 06:19:26 INFO - PID 2907 | Cycle 1(1): loaded http://localhost:61541/tests/dromaeo/cssquery-prototype.html (next: http://localhost:61541/tests/dromaeo/cssquery-yui.html) 06:19:26 INFO - PID 2907 | RSS: Main: 274182144 06:19:26 INFO - PID 2907 | 06:22:28 INFO - PID 2907 | Cycle 1(1): loaded http://localhost:61541/tests/dromaeo/cssquery-yui.html (next: http://localhost:61541/tests/dromaeo/cssquery-dojo.html) 06:22:28 INFO - PID 2907 | RSS: Main: 275906560 06:22:28 INFO - PID 2907 | 06:22:28 INFO - PID 2907 | __start_tp_report 06:22:28 INFO - PID 2907 | _x_x_mozilla_page_load 06:22:28 INFO - PID 2907 | _x_x_mozilla_page_load_details 06:22:28 INFO - PID 2907 | |i|pagename|runs| 06:22:28 INFO - PID 2907 | |0;dojo.html;3607.1523192049867;3612.1330880588666;3650.215203731198;3661.688756455701;3681.9263614727706;6102.640737559642;6216.347283535231;6272.059191121334;6279.15231443755;6325.746970121201;6250.687465626712;6255.781047663333;6263.812085637438;6288.496920246381;6317.3050964394;5490.121580547113;5493.697846618435;5526.253955715987;5582.720863956806;5617.775288988456;5416.891662166754;5575.944240557583;5655.4910058094765;5702.885942281152;5709.800156994506;5563.13771365439;5574.665520068809;5676.744546495397;5681.034224181899;5704.942950570483;5432;5517.2551705519645;5537.197106419587;5557.6109672322955;5566.415526369713;5533.280673512458;5563.527100196488;5569.192467092249;5569.637973531708;5587.692676902769;6064.939350606482;6332.746690132389;6334.651594162319;6342.334054924213;6352.555321127523;6550.377714117151;6612.107365505692;6647.700853461557;6771.8984215236815;6787.694553745093;6845.110135682379;6882.346177113166;6917.51577389583;6927.3419025192525;6929.341712537301;6369.490440764727;6394.776182833579;6401.071844582602;6402.455791257702;6409.230892292908;6790.626515541692;6861;6968.547044442095;7044.753433629799;7057.36483716468;6628.602283862983;6664.900026499607;6716.227633822075;6717.126773519509;6789.388954994074;6876.793696189123;6879.89680154798;6893.93106068943;6911.3779759821855;6933.306669333027;6542.084108224853;6635.435987940982;6669.799906002828;6778.593284402853;6810.182778066664;6715.2613212546585;6764.763233286812;6780.796576102724;6815.727370905208;6837.487188460886;5408.107662235687;5409.56723462122;5441.891162176735;5444.591655625764;5467.589930755209;4785.521447855187;4812.711237325771;4830.227163653799;4843.07981483517;4862.659613826998;6287.968560157261;6319;6333.588316759488;6343.936560634427;6381.64900930444;6918.654067296615;6921.861562768717;6926.411255043277;6940.930590694028;6943.236244013155;6236.126942228001;6311.211098612674;6342.302346741854;6344;6365.458935990493;5935.970320148458;5997.880042399128;6014.127951447016;6113.144159817629;6131.509479241739;7041.718331266795;7070.6111163884425;7086.822829429306;7091.716331346688;7106.111736032996;6845.828854278683;6911.516193866381;6921.446284297345;6928.307169283032;6936.20233673134;6813.7955861324235;6894.24163342022;7052.823679408056;7079.82300442493;7093.290670932865;5663.584103974006;6681.899771503528;6712.026756120433;6725.024871393718;6734.8653026940165;6808.046873437822;6853.280405557344;6869.690863911036;6874.9312506876295;6910.516263861682;6608.107905432705;6623.1389919312105;6627.867442651216;6637;6638.269790322964;5731.684757338219;6490.448311893353;6495.610263384212;6574.211094668671;6600.80197594073;6410.551261411656;6431.710573024132;6546.312637173029;6570.08018877348;6592.7033283503315;6437.549371543947;6448.613083215022;6518.348165183444;6607.405333519814;6610.867782644416;5114.846554603368;6216.502679785697;6510.9674451629335;6537.803865884031;6557.967210163919 06:22:28 INFO - PID 2907 | |1;ext.html;728.5555810955316;970.6748239339821;1112.760756437366;1123.544964289463;1225.3566877389364;37173.25653486932;37981.86054418365;38016.85949421522;38018.80990595047;38045.85862424125;37604.62395376052;37958.24083518328;37996.81001594988;38009.42985855215;38029;7664.540127592334;7726.150123486426;7873.724419645314;8620.612072456754;8657.134286571356;8539.871901921477;8628.65485380584;8658.87011694825;8659.220670139686;8663.740087797376;8619.95690021549;8636.222739953402;8682.609282582267;8699.869501957475;8713.433626814269;8634.266087382548;8638.870416943751;8675.479471231714;8692.695755648585;8694.434861734031;6428.646424446653;6451.322611125811;6521.282658907517;6539.607623542555;6562.80311590653;37110.628893711255;37272.81363593192;37313.62686373156;37338.43992340117;37354.06614834624;3530.8411121499594;3567.3935430976794;3570.946435803465;3576.8390422431053;3577.5528058992627;30665.540016899766;30690.9258175965;30814.845925770453;30932.536011959837;30946.226344341347;3121.2352973521406;3139.6232452105664;3141.9214519637194;3159.526071089332;3170.6036745406823;303634.48182758945;306913.46543267363;308518;309026.99999999773;310725;38459;45465.54534454613;47277;47546.52453475421;47738.806529836336;43615.127697445874;45023.874403139525;48829.26756098661;49465.75267123641;49632.75918102004;13898.374573144232;13994.790078148835;14072.437102515989;14085.647858803406;14287.78568321476;11046.392448415254;11051.889481105087;11161.83257251142;11245.887541124648;11259.324440533594;13563.321833908463;13624.591262262147;13631.591052268448;13678.863211367958;13737.450501980009;5884.499817515559;6162.291336496271;6164.075388691732;6164.630122192682;6186.443220110212;966.246327864253;966.8694726211871;1029.7271223125877;1038.787048655024;1052.6631477927142;20448.79551204469;20564.58870822575;21455.141794328363;23764.930578124025;24312.14907478265;1087.456271864068;1090.8363745438248;1124.3422597780227;1143.6054561176381;1145.707844499664;12225.44985475638;12243.755124897452;12290.66955890102;12305.876941230652;12356.320402377776;330808.6919130826;332196;332447;332819.33590331895;335148.324258382;42489.36265956013;51990.480095199324;52025.43923682295;52217.7389113052;52425.73787131116;1342.536824795444;1494.3873012065;1525.8779297656383;1529.0825504697316;1533.6242620557812;22518.87405629679;22810.20164294242;23843.403914901573;23902.402439939142;23907.641385379582;35247.647523524436;35270.29459410849;35397;35432.114197145274;35435;244453;247854;248309;274350;275100;29391;35801.46297805483;35805.64194358023;35975.280494389444;36294.455583166804;44268.893277668314;44408.33387499126;45807.54192458033;45833;46381.07237855291;30945.8452707735;31009.534856976712;31197.064088077394;31360.68639313669;31756.364872702878;29844.955426559965;30763.692363076083;31097.798024302403;31180;31470.37059258848;36818.26363472769;36822.26355472822;37195.81402092972;37603.24793504169;37762.811185943894;30359.544606830474;30435;31073;31431.528527071656;31560.210994724395;19800;20500;23356.532869342856;24374.26877193687;24512.387190320384 06:22:28 INFO - PID 2907 | |2;jquery.html;421.3578158975598;423.94488716466867;429.2723833102888;449.1578290704928;453.12772912144135;19091.809081909178;20936.895315523438;21740.13039478421;21830.017649205805;22836.429089272737;22726.659100113513;22900.427489312777;23069.884650576765;23234.65148022781;23260.65109023366;5897.911531327044;6050.42520960508;6123.448889599935;6131.50947924165;6139.570230083896;5886.20536227608;6076.966915624333;6085.665288409135;6126.23422072241;6164.691765411734;5988.610740301889;6015.458608725214;6065.575409721321;6099.085137229406;6104.786332478364;5883.941160588403;6040.184575082351;6043;6050.24371953508;6051.1830902828215;5144;5231.895362092737;5380.381256155553;5384.59615528833;5454.781808727646;26169;26779.928802847862;26934.865325673443;27153;27452.039178628653;2151.9784802151935;2192.2875065603585;2204.922827701039;2211.2481756202806;2212.2478357358577;17516.91241543784;17717.37989170386;17837.108144592847;17941.372051978116;18175.818241817546;2165.5993641176433;2178.7712290209615;2213.7454192767877;2225.2767850448668;2227.4765430123907;156210;159603.20198398933;159907.80184396246;162995;163744;11042.944785276022;12121.757564848655;12510.124291299704;12733.745325093632;12783.296918669377;16714.16429178536;16734.916325418297;16764.83235167657;16776.161191940355;16926.322947082226;8845.955770221108;8903.421277616933;8944.329175311748;9088.636454541876;9111.17999380059;6987.580745155307;8420.915790842135;8480.024797148408;8493.27807136388;8584.570771461402;6933.965330173317;7054.082969214072;7366.447516436288;8049.235322644339;8098.7165449209;1972.3589833304234;2188.2231807708035;2250.347399254231;2259.2770313499523;2420.891059902309;935.4621092871625;978.2320878110648;980.8136454073846;981.097390400833;987.244757760314;20309.49226269355;20584.794152058286;20716.896415518127;20836.166553338;21169.470763231042;967.4388854464394;991.4646091110719;1003.4631472162504;1005.1205195453978;1033.9741506462399;8801.207891289814;8811.735647930573;10097.697069087939;10155.543000564992;10630.893691062991;493591;539372;566566;568657;571546;103102.96897031083;121720.17419738711;122231;123686.38156809159;132431.33784331018;1192.9522819087313;1220.0910321810309;1259.6661884600405;1266.936653167338;1268.7272236469203;17656;21665.675014875105;21917.452063698536;22325.553488929807;22351.888240558692;57401.308391598104;57504.1374379393;57759.4224057754;57913.13130303133;58626.70686646539;49228.01543969172;67562;73533.63233184014;75158.6242068808;84001.73997390167;7025.859482810213;8798.164174403422;9028.458292502472;9033.548322583976;9114.31642626819;14072.155670659793;14134.646633834236;14304.141751494528;14529.709405812036;14584.343704533108;11687.474063667325;11884.524619015143;11891.28652280866;12008.579699710817;12037.638870833889;10420.843687344544;11019.449027548751;11144.832827507757;11386;11438.027767639844;11781.469833857325;11957.266196401299;12074.698132546406;12095.455704493143;12158.993766028507;8789.384743067923;8813.559322034002;9021.007689154056;9171.128742769426;9203.76990575241;11670.299782013106;11960.41187643686;12079.335636539723;12124.757504849676;12226.20529665569 06:22:28 INFO - PID 2907 | |3;mootools.html;1180.929144251345;1213.6905089202257;1376.421902800825;1383.8721442024748;1387.36181356576;9422.858657120149;9992.650257240997;12168.209066410689;12193.08551858612;12205.206661567003;11799.70500737482;12011.339376334297;12011.519539218421;12013.03895688345;12038.157328986952;2494.326531836403;2557.3734435063343;2557.884895179712;2564.3204550794053;2569.5888657814753;2489.5269898719275;2542.4533725249066;2547.2994926395204;2550.6684131062934;2559.3345730110213;2196.1325276515768;2465.7410971847876;2470;2499.5375855466705;2516.245126462063;2464.112919349033;2475.938101547467;2487.8631675257852;2488.4276616378243;2496.5131799299143;2312.8149748020287;2428.623563347684;2471.480988992314;2482.7765501104805;2487.2040946897;11533.019693326078;11674.182807203499;11754.823677644843;11768.411579420994;11777;6315.715792789336;6563.474922006228;6566.080748695186;6580.769673061468;6614.603123812539;3873.3028054950096;4214.894627634303;4261.4460120184485;4305.63402110821;4357.2592659248;1185.3954483213538;1192.3859212505479;1234.1299383934395;1246.1463897230335;1250.6810763255403;14285.92855535839;15045.022073565293;15509.612259693486;16348.673026539522;16363.42728004526;5342.2253773202665;5407.702576358338;5588.720563971785;5592.97203513988;5595.748191331319;11652.242604230698;11833.349165795964;11835.17153799243;11846.407679615984;11850.288982661068;6368.172137622079;6439.64581947988;6447.065175549613;6450.580712253689;6454.386833250834;4744.786484608201;5564.610477266633;5978.282606087298;6106.358832322631;6151.292601350902;3655.597884232732;4344.826206951687;4468.731876087445;4497.6851620386915;4505.932411013838;1258.6916205529703;1260.8802163794426;1278.130871007705;1279.9232046077266;1293.624848793858;721.264310403396;793.2860425616991;798.3692882622779;814.1573471457014;816.1552792859362;5761.366249712528;6171.858206231862;6278.246610406781;6292.119103325538;6354.030250622322;592.3306663470252;592.7273454211025;609.6616377910302;616.1435604509735;619.9349068347849;3354.9329013419597;3556.342076715764;3898.9610103899167;3923.9197230174746;3967.0479085019388;14377.209253491159;14717.779233311509;14793.260336983109;14868.776968345483;14903.254837258311;12735.872641273654;14265.643358915902;14768.261586920826;14821.555353339418;14840.480583179537;8161.0821456957565;8572.442791218551;8575.871361929576;8611.526366049802;8647.567621618893;5623.634463759842;6613.470922326299;6928.618925958919;6981.79054628362;7134.393576546052;13092.083554151119;13189.604311870658;13218.471261149447;13310.600681979557;13396.397162127923;13285.93712502978;13393.531226407029;13562.728745425233;13637.795433068313;13753.587392378246;9256.814863702823;9350.438973661601;9404.38871473352;9426.717198484057;9484.620615175316;10592.947035264775;10752.892471075502;10773.73065673333;10778;10781.02970732638;7802.180771018961;8877.955610221907;9080.455172689659;9187.219086377732;9209.585568649292;8772.956135219283;8825.38222324431;8881.046169842624;8916.33127515426;9050.909490905007;7148;8194.83610327802;8332.250097491255;8346.582670866554;8361.247487726134;4816.446108697544;6004;6059.606125601822;6082.36135205797;6145.231846019247;6120.54095942815;6274.780382686584;6323.146375239284;6357.745690172342;6404.9039264412 06:22:28 INFO - PID 2907 | |4;prototype.html;351.595664985267;392.5250446959179;397.72556935714374;401.889480392892;403.69924406317296;4744.667873248874;4779.904401911964;5215.58275337973;6064.120702498135;6066.393360663931;5300.390455097665;5467.835964921049;6015.639061656304;6153.969230153843;6160.706251687151;4409.603135717784;4482.125985432842;4515;4526.117407105616;4539.795709193094;3706.7405281630295;4494.887627809315;4495.145922274757;4517.909641807162;4529.818807247706;3718.888433347004;4404.405405270296;4471.5081341052455;4477.171723231197;4493.831848546767;3780.962190378102;4416.690831641787;4439.66702497314;4464.486584042844;4490.550944905516;3799.5820459749407;3813;3832.4826148470156;3832.9808350958065;3861.6717579005817;5243.973780131113;5796.536277097822;6030.03519436888;6058.697065146725;6066.181065556138;4529.932051019237;5278.841634750926;5345.1982202669915;5396.91904621431;5420.457954204548;5471.5896307776675;6568.376004279586;6587.73649054042;6592.406683398517;6608.240052393988;4373.387725718402;4373.846915357947;4375.518692943773;4385.934210986838;4397.670174736875;6664.733410663568;6779.389854913032;7008.929910700878;7027.472939529555;7045.365917067437;4402.8679139625865;4436.911261774746;4439.578240067189;4447.3773671685985;4450.287953927356;4121.464209652726;4154.293770059097;4362.825486980549;4424.026714122888;4523.864284071483;4586.747728874944;5252.896891652719;5298.88723368097;5333.253344531769;5348.438413966555;5158.200478925773;5930.55520835939;6628.110988368292;6650.040731874366;6668.533202675863;1504.7592385218313;1524.7116186822118;1530.3496014193922;1531.5481932829841;1534.5473085439821;979.7844474215661;989.5645915797027;992.6575331510618;994.5325696922434;997.3816233935007;4919.7540122993705;5069.645124841226;5477.287952566141;5814.5639077068545;6195.845103872439;6744.932550674529;6852.211995620568;6854.320691430631;6911.408221916781;6937.895931561031;5617.662940223599;6049.213602231682;6084.756609735649;6141.170941922873;6175.876482470326;2993.835339056373;3152.5271209318785;3305.9173520662175;3386.3745402206587;3547.4635635520212;5879.970600146972;6156.322804491502;6486.935130648728;7134.571925684476;7184.209736928934;6554.573952693059;7200.639968001579;7201.89197162043;7209.747658831916;7230.493865429475;5483.588730845282;6927.134108236471;7117.932310153518;7133.429325653935;7147.428205743528;5784.623999440023;6376;6982.6508674567085;7021.613811240431;7044.225135235018;6524.575902566318;6724.831879203059;6826.64480754139;6836.213835408992;6868.484863635147;5208.0365132450615;5615.494605485526;6859.657017149023;6964.860702786017;7103.360697537247;4027.677785777072;4099.262132816122;4142.5443201247235;4453.421055262795;4477.059817438374;5137.743112844418;6443.904536228946;6980.277691700342;6985.5808651481075;7006.9649651751415;6152.538559608137;6641.933580664131;6659.068870027638;6661.533692641662;6677.929841749074;5820.76716931318;6510.153680021567;6647.800565983028;6662.167229096363;6739.831504212434;6442.549021568445;6453.612783232833;6461.709223084878;6513.674316284072;6540.182477190351;6270.773875274212;6589.374009469093;6639.867202655823;6656.700448479927;6687.866242675022;5038.773255203598;5470.425605311544;6412.39082287182;6457.838554036187;6504.707288171949 06:22:28 INFO - PID 2907 | |5;yui.html;787.6337503061079;815.388458656008;819.9221073997971;835.0438747633959;851.6635928808123;14475.276236188174;15035.624109397271;15992.280347384367;16043.438479653214;16352.83647163528;16748.916255418735;17312.56718582036;17551.2101955412;17574;17649.64700705985;954.7517645412173;969.781799095205;971.9659811906585;975.2929126383351;975.9849756253487;957.8754761880946;961.8364877970727;962.74006018375;964.0937518732404;969.8981606931277;1004.3672486333636;1007.9092881640651;1009.142229105262;1009.878814542256;1010.434156872154;1006.9093781559658;1008.1632245236473;1008.5461542305992;1009.1069403577814;1011.039512463163;742.0835268443521;742.0983505041401;742.9517081389746;743.4238465189468;744.5011842065829;16672.49982500527;17105.57236069096;17347.913260433616;17350.305987760476;17400;14319.14085154894;16218.75671864923;16717.582060448465;16933.830661693348;16967.406140785013;14047.719045619133;14317.570472885725;14564.708705825824;14567.344469498898;14595.562133136023;3025.3495498467937;3069.2480342316276;3487.7558570900046;3596.7122630189783;3646.5988741238443;107273.46363268212;109678;111734.88265117326;111770.44114779455;114120;13135.605931822061;13300.600981970556;13396.397162127532;13438.596842094752;13450.26023568713;25080.247592572254;25334.239972800846;25433.618495722578;25494.74505254924;25996.8700156498;7664.271894170045;7986.281234688906;8014.278714915686;8027.438079334391;8030.478018928751;3880.902977425587;3946.072672921861;3965.5836137205756;3969.325214713506;3973.384125460558;3987.501562304712;4157.064660451374;4288.871333859989;4291.184674911757;4294.506131794821;743.8884167374937;844.8225872566707;871.7123349294718;874.0429229993148;881.8544940084814;623.3626787693858;655.6590572902064;656.6322859198864;657.8651376467812;680.7038938061959;10283;10594.576216951236;11135.944320278346;11819;11995.940020300017;647.6535053746223;649.6394501051867;651.5504302031583;656.6440300778876;668.7391917152314;5469.769301907039;5984.670843103584;6029.57792954489;6078.422549857756;6092;81314.59342703367;98905.53830615894;100430.49784751175;106927;108893.45553272183;93113;94417;94472.58291125638;94588.12236592743;95288.52355738316;64472.35527644664;82382.17617823865;83100.58449707834;83395.91510212293;85491;10028.398296101965;12301.384930753606;12491.125621206791;12508.56220032331;12569.051796971484;38895.44418223353;39182.2163556733;39599.20801583894;39726.60273397229;39845.80077099596;38026.80986595049;51366.22950655669;51465.485345147565;51526;51632.74183629058;15760.527184184493;15920;16367.345306187619;16887.240074196936;17021;17395.652086958442;18097.2460929697;18384;18514.537136571693;18516.629667406847;16034.679306413573;16336.346546138022;16623;16739.66520669604;16766.832331676527;16036.358545658042;16861.747073794148;17091.572710682332;17427.39004134849;17440;15827.683446331239;16318.020918744438;16630.08534530564;16921.407750728664;16988.150592470574;11691.356975366096;11754.823677644672;11852.822207666719;12053.216540924812;12076.275423474619;11814.704632384259;12365.319907405179;12988.870111298766;13281.933590331986;13402.128861623964 06:22:28 INFO - PID 2907 | __end_tp_report 06:22:28 INFO - PID 2907 | __start_cc_report 06:22:28 INFO - PID 2907 | _x_x_mozilla_cycle_collect,346 06:22:28 INFO - PID 2907 | __end_cc_report 06:22:28 INFO - PID 2907 | __startTimestamp1495459348883__endTimestamp 06:22:28 INFO - PID 2907 | 06:22:28 INFO - PID 2907 | ------- Summary: start ------- 06:22:28 INFO - PID 2907 | Number of tests: 6 06:22:28 INFO - PID 2907 | 06:22:28 INFO - PID 2907 | [#0] dojo.html Cycles:165 Average:6265.53 Median:6448.61 stddev:732.47 (11.4%) stddev-sans-first:704.41 06:22:28 INFO - PID 2907 | Values: 3607.2 3612.1 3650.2 3661.7 3681.9 6102.6 6216.3 6272.1 6279.2 6325.7 6250.7 6255.8 6263.8 6288.5 6317.3 5490.1 5493.7 5526.3 5582.7 5617.8 5416.9 5575.9 5655.5 5702.9 5709.8 5563.1 5574.7 5676.7 5681.0 5704.9 5432.0 5517.3 5537.2 5557.6 5566.4 5533.3 5563.5 5569.2 5569.6 5587.7 6064.9 6332.7 6334.7 6342.3 6352.6 6550.4 6612.1 6647.7 6771.9 6787.7 6845.1 6882.3 6917.5 6927.3 6929.3 6369.5 6394.8 6401.1 6402.5 6409.2 6790.6 6861.0 6968.5 7044.8 7057.4 6628.6 6664.9 6716.2 6717.1 6789.4 6876.8 6879.9 6893.9 6911.4 6933.3 6542.1 6635.4 6669.8 6778.6 6810.2 6715.3 6764.8 6780.8 6815.7 6837.5 5408.1 5409.6 5441.9 5444.6 5467.6 4785.5 4812.7 4830.2 4843.1 4862.7 6288.0 6319.0 6333.6 6343.9 6381.6 6918.7 6921.9 6926.4 6940.9 6943.2 6236.1 6311.2 6342.3 6344.0 6365.5 5936.0 5997.9 6014.1 6113.1 6131.5 7041.7 7070.6 7086.8 7091.7 7106.1 6845.8 6911.5 6921.4 6928.3 6936.2 6813.8 6894.2 7052.8 7079.8 7093.3 5663.6 6681.9 6712.0 6725.0 6734.9 6808.0 6853.3 6869.7 6874.9 6910.5 6608.1 6623.1 6627.9 6637.0 6638.3 5731.7 6490.4 6495.6 6574.2 6600.8 6410.6 6431.7 6546.3 6570.1 6592.7 6437.5 6448.6 6518.3 6607.4 6610.9 5114.8 6216.5 6511.0 6537.8 6558.0 06:22:28 INFO - PID 2907 | 06:22:28 INFO - PID 2907 | [#1] ext.html Cycles:180 Average:44668.25 Median:333983.83 stddev:79063.95 (23.7%) stddev-sans-first:79216.92 06:22:28 INFO - PID 2907 | Values: 728.6 970.7 1112.8 1123.5 1225.4 37173.3 37981.9 38016.9 38018.8 38045.9 37604.6 37958.2 37996.8 38009.4 38029.0 7664.5 7726.2 7873.7 8620.6 8657.1 8539.9 8628.7 8658.9 8659.2 8663.7 8620.0 8636.2 8682.6 8699.9 8713.4 8634.3 8638.9 8675.5 8692.7 8694.4 6428.6 6451.3 6521.3 6539.6 6562.8 37110.6 37272.8 37313.6 37338.4 37354.1 3530.8 3567.4 3570.9 3576.8 3577.6 30665.5 30690.9 30814.8 30932.5 30946.2 3121.2 3139.6 3141.9 3159.5 3170.6 303634.5 306913.5 308518.0 309027.0 310725.0 38459.0 45465.5 47277.0 47546.5 47738.8 43615.1 45023.9 48829.3 49465.8 49632.8 13898.4 13994.8 14072.4 14085.6 14287.8 11046.4 11051.9 11161.8 11245.9 11259.3 13563.3 13624.6 13631.6 13678.9 13737.5 5884.5 6162.3 6164.1 6164.6 6186.4 966.2 966.9 1029.7 1038.8 1052.7 20448.8 20564.6 21455.1 23764.9 24312.1 1087.5 1090.8 1124.3 1143.6 1145.7 12225.4 12243.8 12290.7 12305.9 12356.3 330808.7 332196.0 332447.0 332819.3 335148.3 42489.4 51990.5 52025.4 52217.7 52425.7 1342.5 1494.4 1525.9 1529.1 1533.6 22518.9 22810.2 23843.4 23902.4 23907.6 35247.6 35270.3 35397.0 35432.1 35435.0 244453.0 247854.0 248309.0 274350.0 275100.0 29391.0 35801.5 35805.6 35975.3 36294.5 44268.9 44408.3 45807.5 45833.0 46381.1 30945.8 31009.5 31197.1 31360.7 31756.4 29845.0 30763.7 31097.8 31180.0 31470.4 36818.3 36822.3 37195.8 37603.2 37762.8 30359.5 30435.0 31073.0 31431.5 31560.2 19800.0 20500.0 23356.5 24374.3 24512.4 06:22:28 INFO - PID 2907 | 06:22:28 INFO - PID 2907 | [#2] jquery.html Cycles:180 Average:35274.86 Median:22868.43 stddev:93031.59 (406.8%) stddev-sans-first:93255.76 06:22:28 INFO - PID 2907 | Values: 421.4 423.9 429.3 449.2 453.1 19091.8 20936.9 21740.1 21830.0 22836.4 22726.7 22900.4 23069.9 23234.7 23260.7 5897.9 6050.4 6123.4 6131.5 6139.6 5886.2 6077.0 6085.7 6126.2 6164.7 5988.6 6015.5 6065.6 6099.1 6104.8 5883.9 6040.2 6043.0 6050.2 6051.2 5144.0 5231.9 5380.4 5384.6 5454.8 26169.0 26779.9 26934.9 27153.0 27452.0 2152.0 2192.3 2204.9 2211.2 2212.2 17516.9 17717.4 17837.1 17941.4 18175.8 2165.6 2178.8 2213.7 2225.3 2227.5 156210.0 159603.2 159907.8 162995.0 163744.0 11042.9 12121.8 12510.1 12733.7 12783.3 16714.2 16734.9 16764.8 16776.2 16926.3 8846.0 8903.4 8944.3 9088.6 9111.2 6987.6 8420.9 8480.0 8493.3 8584.6 6934.0 7054.1 7366.4 8049.2 8098.7 1972.4 2188.2 2250.3 2259.3 2420.9 935.5 978.2 980.8 981.1 987.2 20309.5 20584.8 20716.9 20836.2 21169.5 967.4 991.5 1003.5 1005.1 1034.0 8801.2 8811.7 10097.7 10155.5 10630.9 493591.0 539372.0 566566.0 568657.0 571546.0 103103.0 121720.2 122231.0 123686.4 132431.3 1193.0 1220.1 1259.7 1266.9 1268.7 17656.0 21665.7 21917.5 22325.6 22351.9 57401.3 57504.1 57759.4 57913.1 58626.7 49228.0 67562.0 73533.6 75158.6 84001.7 7025.9 8798.2 9028.5 9033.5 9114.3 14072.2 14134.6 14304.1 14529.7 14584.3 11687.5 11884.5 11891.3 12008.6 12037.6 10420.8 11019.4 11144.8 11386.0 11438.0 11781.5 11957.3 12074.7 12095.5 12159.0 8789.4 8813.6 9021.0 9171.1 9203.8 11670.3 11960.4 12079.3 12124.8 12226.2 06:22:28 INFO - PID 2907 | 06:22:28 INFO - PID 2907 | [#3] mootools.html Cycles:180 Average:6983.24 Median:3764.45 stddev:4427.38 (117.6%) stddev-sans-first:4418.32 06:22:28 INFO - PID 2907 | Values: 1180.9 1213.7 1376.4 1383.9 1387.4 9422.9 9992.7 12168.2 12193.1 12205.2 11799.7 12011.3 12011.5 12013.0 12038.2 2494.3 2557.4 2557.9 2564.3 2569.6 2489.5 2542.5 2547.3 2550.7 2559.3 2196.1 2465.7 2470.0 2499.5 2516.2 2464.1 2475.9 2487.9 2488.4 2496.5 2312.8 2428.6 2471.5 2482.8 2487.2 11533.0 11674.2 11754.8 11768.4 11777.0 6315.7 6563.5 6566.1 6580.8 6614.6 3873.3 4214.9 4261.4 4305.6 4357.3 1185.4 1192.4 1234.1 1246.1 1250.7 14285.9 15045.0 15509.6 16348.7 16363.4 5342.2 5407.7 5588.7 5593.0 5595.7 11652.2 11833.3 11835.2 11846.4 11850.3 6368.2 6439.6 6447.1 6450.6 6454.4 4744.8 5564.6 5978.3 6106.4 6151.3 3655.6 4344.8 4468.7 4497.7 4505.9 1258.7 1260.9 1278.1 1279.9 1293.6 721.3 793.3 798.4 814.2 816.2 5761.4 6171.9 6278.2 6292.1 6354.0 592.3 592.7 609.7 616.1 619.9 3354.9 3556.3 3899.0 3923.9 3967.0 14377.2 14717.8 14793.3 14868.8 14903.3 12735.9 14265.6 14768.3 14821.6 14840.5 8161.1 8572.4 8575.9 8611.5 8647.6 5623.6 6613.5 6928.6 6981.8 7134.4 13092.1 13189.6 13218.5 13310.6 13396.4 13285.9 13393.5 13562.7 13637.8 13753.6 9256.8 9350.4 9404.4 9426.7 9484.6 10592.9 10752.9 10773.7 10778.0 10781.0 7802.2 8878.0 9080.5 9187.2 9209.6 8773.0 8825.4 8881.0 8916.3 9050.9 7148.0 8194.8 8332.3 8346.6 8361.2 4816.4 6004.0 6059.6 6082.4 6145.2 6120.5 6274.8 6323.1 6357.7 6404.9 06:22:28 INFO - PID 2907 | 06:22:28 INFO - PID 2907 | [#4] prototype.html Cycles:180 Average:5245.27 Median:5817.67 stddev:1695.14 (29.1%) stddev-sans-first:1659.63 06:22:28 INFO - PID 2907 | Values: 351.6 392.5 397.7 401.9 403.7 4744.7 4779.9 5215.6 6064.1 6066.4 5300.4 5467.8 6015.6 6154.0 6160.7 4409.6 4482.1 4515.0 4526.1 4539.8 3706.7 4494.9 4495.1 4517.9 4529.8 3718.9 4404.4 4471.5 4477.2 4493.8 3781.0 4416.7 4439.7 4464.5 4490.6 3799.6 3813.0 3832.5 3833.0 3861.7 5244.0 5796.5 6030.0 6058.7 6066.2 4529.9 5278.8 5345.2 5396.9 5420.5 5471.6 6568.4 6587.7 6592.4 6608.2 4373.4 4373.8 4375.5 4385.9 4397.7 6664.7 6779.4 7008.9 7027.5 7045.4 4402.9 4436.9 4439.6 4447.4 4450.3 4121.5 4154.3 4362.8 4424.0 4523.9 4586.7 5252.9 5298.9 5333.3 5348.4 5158.2 5930.6 6628.1 6650.0 6668.5 1504.8 1524.7 1530.3 1531.5 1534.5 979.8 989.6 992.7 994.5 997.4 4919.8 5069.6 5477.3 5814.6 6195.8 6744.9 6852.2 6854.3 6911.4 6937.9 5617.7 6049.2 6084.8 6141.2 6175.9 2993.8 3152.5 3305.9 3386.4 3547.5 5880.0 6156.3 6486.9 7134.6 7184.2 6554.6 7200.6 7201.9 7209.7 7230.5 5483.6 6927.1 7117.9 7133.4 7147.4 5784.6 6376.0 6982.7 7021.6 7044.2 6524.6 6724.8 6826.6 6836.2 6868.5 5208.0 5615.5 6859.7 6964.9 7103.4 4027.7 4099.3 4142.5 4453.4 4477.1 5137.7 6443.9 6980.3 6985.6 7007.0 6152.5 6641.9 6659.1 6661.5 6677.9 5820.8 6510.2 6647.8 6662.2 6739.8 6442.5 6453.6 6461.7 6513.7 6540.2 6270.8 6589.4 6639.9 6656.7 6687.9 5038.8 5470.4 6412.4 6457.8 6504.7 06:22:28 INFO - PID 2907 | 06:22:28 INFO - PID 2907 | [#5] yui.html Cycles:180 Average:21045.39 Median:18449.27 stddev:28905.29 (156.7%) stddev-sans-first:28946.36 06:22:28 INFO - PID 2907 | Values: 787.6 815.4 819.9 835.0 851.7 14475.3 15035.6 15992.3 16043.4 16352.8 16748.9 17312.6 17551.2 17574.0 17649.6 954.8 969.8 972.0 975.3 976.0 957.9 961.8 962.7 964.1 969.9 1004.4 1007.9 1009.1 1009.9 1010.4 1006.9 1008.2 1008.5 1009.1 1011.0 742.1 742.1 743.0 743.4 744.5 16672.5 17105.6 17347.9 17350.3 17400.0 14319.1 16218.8 16717.6 16933.8 16967.4 14047.7 14317.6 14564.7 14567.3 14595.6 3025.3 3069.2 3487.8 3596.7 3646.6 107273.5 109678.0 111734.9 111770.4 114120.0 13135.6 13300.6 13396.4 13438.6 13450.3 25080.2 25334.2 25433.6 25494.7 25996.9 7664.3 7986.3 8014.3 8027.4 8030.5 3880.9 3946.1 3965.6 3969.3 3973.4 3987.5 4157.1 4288.9 4291.2 4294.5 743.9 844.8 871.7 874.0 881.9 623.4 655.7 656.6 657.9 680.7 10283.0 10594.6 11135.9 11819.0 11995.9 647.7 649.6 651.6 656.6 668.7 5469.8 5984.7 6029.6 6078.4 6092.0 81314.6 98905.5 100430.5 106927.0 108893.5 93113.0 94417.0 94472.6 94588.1 95288.5 64472.4 82382.2 83100.6 83395.9 85491.0 10028.4 12301.4 12491.1 12508.6 12569.1 38895.4 39182.2 39599.2 39726.6 39845.8 38026.8 51366.2 51465.5 51526.0 51632.7 15760.5 15920.0 16367.3 16887.2 17021.0 17395.7 18097.2 18384.0 18514.5 18516.6 16034.7 16336.3 16623.0 16739.7 16766.8 16036.4 16861.7 17091.6 17427.4 17440.0 15827.7 16318.0 16630.1 16921.4 16988.2 11691.4 11754.8 11852.8 12053.2 12076.3 11814.7 12365.3 12988.9 13281.9 13402.1 06:22:28 INFO - PID 2907 | -------- Summary: end -------- 06:22:28 INFO - PID 2907 | 06:22:29 INFO - TEST-INFO | 2907: exit 0 06:22:29 INFO - TEST-OK | dromaeo_css | took 1114188ms 06:22:29 INFO - TEST-START | kraken 06:22:29 INFO - operating with platform_type : mac_ 06:22:29 INFO - Initialising browser for kraken test... 06:22:29 INFO - TEST-INFO | started process 2914 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/tmpQWkwE1/profile http://localhost:61541/getInfo.html) 06:22:30 INFO - PID 2914 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2978: String.localeCompare is deprecated; use String.prototype.localeCompare instead 06:22:31 INFO - PID 2914 | 2017-05-22 06:22:31.834 plugin-container[2915:49575] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 06:22:31 INFO - PID 2914 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:22:31 INFO - PID 2914 | 2017-05-22 06:22:31.837 plugin-container[2915:49575] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c17, name = 'com.apple.CFPasteboardClient' 06:22:31 INFO - PID 2914 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:22:31 INFO - PID 2914 | 2017-05-22 06:22:31.837 plugin-container[2915:49575] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:22:32 INFO - PID 2914 | __metrics Screen width/height:1600/1200 06:22:32 INFO - PID 2914 | colorDepth:24 06:22:32 INFO - PID 2914 | Browser inner width/height: 1024/691 06:22:32 INFO - PID 2914 | __metrics 06:22:33 INFO - TEST-INFO | 2914: exit 0 06:22:33 INFO - Browser initialized. 06:22:33 INFO - Running cycle 1/1 for kraken test... 06:22:33 INFO - TEST-INFO | started process 2916 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/tmpQWkwE1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:22:34 INFO - PID 2916 | 2017-05-22 06:22:34.875 plugin-container[2917:49682] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9743, name = 'com.apple.tsm.portname' 06:22:34 INFO - PID 2916 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:22:34 INFO - PID 2916 | 2017-05-22 06:22:34.876 plugin-container[2917:49682] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9927, name = 'com.apple.CFPasteboardClient' 06:22:34 INFO - PID 2916 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:22:34 INFO - PID 2916 | 2017-05-22 06:22:34.876 plugin-container[2917:49682] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:22:35 INFO - PID 2916 | 2017-05-22 06:22:35.645 plugin-container[2918:49727] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 06:22:35 INFO - PID 2916 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:22:35 INFO - PID 2916 | 2017-05-22 06:22:35.646 plugin-container[2918:49727] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0b, name = 'com.apple.CFPasteboardClient' 06:22:35 INFO - PID 2916 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:22:35 INFO - PID 2916 | 2017-05-22 06:22:35.646 plugin-container[2918:49727] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:22:44 INFO - PID 2916 | RSS: Main: 255602688 06:22:44 INFO - PID 2916 | 06:22:47 INFO - PID 2916 | Cycle 1(1): loaded http://localhost:61541/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:61541/tests/kraken/driver.html?testName=audio-beat-detection) 06:22:48 INFO - PID 2916 | RSS: Main: 263094272 06:22:48 INFO - PID 2916 | 06:22:51 INFO - PID 2916 | Cycle 1(1): loaded http://localhost:61541/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:61541/tests/kraken/driver.html?testName=audio-dft) 06:22:51 INFO - PID 2916 | RSS: Main: 271097856 06:22:51 INFO - PID 2916 | 06:22:55 INFO - PID 2916 | Cycle 1(1): loaded http://localhost:61541/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:61541/tests/kraken/driver.html?testName=audio-fft) 06:22:55 INFO - PID 2916 | RSS: Main: 266727424 06:22:55 INFO - PID 2916 | 06:22:57 INFO - PID 2916 | Cycle 1(1): loaded http://localhost:61541/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:61541/tests/kraken/driver.html?testName=audio-oscillator) 06:22:58 INFO - PID 2916 | RSS: Main: 267862016 06:22:58 INFO - PID 2916 | 06:23:00 INFO - PID 2916 | Cycle 1(1): loaded http://localhost:61541/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:61541/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:23:01 INFO - PID 2916 | RSS: Main: 268169216 06:23:01 INFO - PID 2916 | 06:23:04 INFO - PID 2916 | Cycle 1(1): loaded http://localhost:61541/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:61541/tests/kraken/driver.html?testName=imaging-darkroom) 06:23:04 INFO - PID 2916 | RSS: Main: 278601728 06:23:04 INFO - PID 2916 | 06:23:07 INFO - PID 2916 | Cycle 1(1): loaded http://localhost:61541/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:61541/tests/kraken/driver.html?testName=imaging-desaturate) 06:23:08 INFO - PID 2916 | RSS: Main: 278847488 06:23:08 INFO - PID 2916 | 06:23:11 INFO - PID 2916 | Cycle 1(1): loaded http://localhost:61541/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:61541/tests/kraken/driver.html?testName=json-parse-financial) 06:23:11 INFO - PID 2916 | RSS: Main: 277803008 06:23:11 INFO - PID 2916 | 06:23:13 INFO - PID 2916 | Cycle 1(1): loaded http://localhost:61541/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:61541/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:23:14 INFO - PID 2916 | RSS: Main: 278073344 06:23:14 INFO - PID 2916 | 06:23:15 INFO - PID 2916 | Cycle 1(1): loaded http://localhost:61541/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:61541/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:23:16 INFO - PID 2916 | RSS: Main: 278175744 06:23:16 INFO - PID 2916 | 06:23:18 INFO - PID 2916 | Cycle 1(1): loaded http://localhost:61541/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:61541/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:23:19 INFO - PID 2916 | RSS: Main: 277262336 06:23:19 INFO - PID 2916 | 06:23:22 INFO - PID 2916 | Cycle 1(1): loaded http://localhost:61541/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:61541/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:23:23 INFO - PID 2916 | RSS: Main: 277573632 06:23:23 INFO - PID 2916 | 06:23:26 INFO - PID 2916 | Cycle 1(1): loaded http://localhost:61541/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:61541/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:23:26 INFO - PID 2916 | RSS: Main: 277741568 06:23:26 INFO - PID 2916 | 06:23:28 INFO - PID 2916 | Cycle 1(1): loaded http://localhost:61541/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:61541/tests/kraken/driver.html?testName=ai-astar) 06:23:28 INFO - PID 2916 | RSS: Main: 279633920 06:23:28 INFO - PID 2916 | 06:23:28 INFO - PID 2916 | __start_tp_report 06:23:28 INFO - PID 2916 | _x_x_mozilla_page_load 06:23:28 INFO - PID 2916 | _x_x_mozilla_page_load_details 06:23:28 INFO - PID 2916 | |i|pagename|runs| 06:23:28 INFO - PID 2916 | |0;ai-astar;136;166;167;114;115;116;115;114;114;115 06:23:28 INFO - PID 2916 | |1;audio-beat-detection;146;113;111;151;113;113;112;114;151;148 06:23:28 INFO - PID 2916 | |2;audio-dft;197;200;199;195;206;213;216;240;284;203 06:23:28 INFO - PID 2916 | |3;audio-fft;77;58;58;88;78;83;74;58;83;83 06:23:28 INFO - PID 2916 | |4;audio-oscillator;82;72;86;69;65;73;87;85;104;112 06:23:28 INFO - PID 2916 | |5;imaging-gaussian-blur;90;91;90;92;91;91;90;91;103;90 06:23:28 INFO - PID 2916 | |6;imaging-darkroom;97;96;98;98;97;120;148;113;91;154 06:23:28 INFO - PID 2916 | |7;imaging-desaturate;99;130;90;92;91;92;89;94;91;91 06:23:28 INFO - PID 2916 | |8;json-parse-financial;51;52;51;51;49;49;50;53;51;49 06:23:28 INFO - PID 2916 | |9;json-stringify-tinderbox;62;56;57;72;73;83;83;48;66;79 06:23:28 INFO - PID 2916 | |10;stanford-crypto-aes;85;83;86;82;81;84;83;85;83;81 06:23:28 INFO - PID 2916 | |11;stanford-crypto-ccm;138;142;147;153;155;140;164;159;180;139 06:23:28 INFO - PID 2916 | |12;stanford-crypto-pbkdf2;188;184;188;184;189;174;201;224;190;181 06:23:28 INFO - PID 2916 | |13;stanford-crypto-sha256-iterative;70;70;69;58;86;99;96;68;66;71 06:23:28 INFO - PID 2916 | __end_tp_report 06:23:28 INFO - PID 2916 | __start_cc_report 06:23:28 INFO - PID 2916 | _x_x_mozilla_cycle_collect,924 06:23:28 INFO - PID 2916 | __end_cc_report 06:23:28 INFO - PID 2916 | __startTimestamp1495459408745__endTimestamp 06:23:28 INFO - PID 2916 | 06:23:28 INFO - PID 2916 | ------- Summary: start ------- 06:23:28 INFO - PID 2916 | Number of tests: 14 06:23:28 INFO - PID 2916 | 06:23:28 INFO - PID 2916 | [#0] ai-astar Cycles:10 Average:127.20 Median:115.50 stddev:21.76 (18.8%) stddev-sans-first:22.85 06:23:28 INFO - PID 2916 | Values: 136.0 166.0 167.0 114.0 115.0 116.0 115.0 114.0 114.0 115.0 06:23:28 INFO - PID 2916 | 06:23:28 INFO - PID 2916 | [#1] audio-beat-detection Cycles:10 Average:127.20 Median:130.00 stddev:18.83 (14.5%) stddev-sans-first:18.70 06:23:28 INFO - PID 2916 | Values: 146.0 113.0 111.0 151.0 113.0 113.0 112.0 114.0 151.0 148.0 06:23:28 INFO - PID 2916 | 06:23:28 INFO - PID 2916 | [#2] audio-dft Cycles:10 Average:215.30 Median:209.50 stddev:27.53 (13.1%) stddev-sans-first:28.39 06:23:28 INFO - PID 2916 | Values: 197.0 200.0 199.0 195.0 206.0 213.0 216.0 240.0 284.0 203.0 06:23:28 INFO - PID 2916 | 06:23:28 INFO - PID 2916 | [#3] audio-fft Cycles:10 Average:74.00 Median:80.50 stddev:11.70 (14.5%) stddev-sans-first:12.36 06:23:28 INFO - PID 2916 | Values: 77.0 58.0 58.0 88.0 78.0 83.0 74.0 58.0 83.0 83.0 06:23:28 INFO - PID 2916 | 06:23:28 INFO - PID 2916 | [#4] audio-oscillator Cycles:10 Average:83.50 Median:77.50 stddev:15.09 (19.5%) stddev-sans-first:16.00 06:23:28 INFO - PID 2916 | Values: 82.0 72.0 86.0 69.0 65.0 73.0 87.0 85.0 104.0 112.0 06:23:28 INFO - PID 2916 | 06:23:28 INFO - PID 2916 | [#5] imaging-gaussian-blur Cycles:10 Average:91.90 Median:91.00 stddev:3.96 (4.3%) stddev-sans-first:4.14 06:23:28 INFO - PID 2916 | Values: 90.0 91.0 90.0 92.0 91.0 91.0 90.0 91.0 103.0 90.0 06:23:28 INFO - PID 2916 | 06:23:28 INFO - PID 2916 | [#6] imaging-darkroom Cycles:10 Average:111.20 Median:96.50 stddev:22.75 (23.6%) stddev-sans-first:23.54 06:23:28 INFO - PID 2916 | Values: 97.0 96.0 98.0 98.0 97.0 120.0 148.0 113.0 91.0 154.0 06:23:28 INFO - PID 2916 | 06:23:28 INFO - PID 2916 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:91.50 stddev:12.30 (13.4%) stddev-sans-first:12.99 06:23:28 INFO - PID 2916 | Values: 99.0 130.0 90.0 92.0 91.0 92.0 89.0 94.0 91.0 91.0 06:23:28 INFO - PID 2916 | 06:23:28 INFO - PID 2916 | [#8] json-parse-financial Cycles:10 Average:50.60 Median:51.00 stddev:1.35 (2.6%) stddev-sans-first:1.42 06:23:28 INFO - PID 2916 | Values: 51.0 52.0 51.0 51.0 49.0 49.0 50.0 53.0 51.0 49.0 06:23:28 INFO - PID 2916 | 06:23:28 INFO - PID 2916 | [#9] json-stringify-tinderbox Cycles:10 Average:67.90 Median:72.50 stddev:12.10 (16.7%) stddev-sans-first:12.64 06:23:28 INFO - PID 2916 | Values: 62.0 56.0 57.0 72.0 73.0 83.0 83.0 48.0 66.0 79.0 06:23:28 INFO - PID 2916 | 06:23:28 INFO - PID 2916 | [#10] stanford-crypto-aes Cycles:10 Average:83.30 Median:83.50 stddev:1.70 (2.0%) stddev-sans-first:1.69 06:23:28 INFO - PID 2916 | Values: 85.0 83.0 86.0 82.0 81.0 84.0 83.0 85.0 83.0 81.0 06:23:28 INFO - PID 2916 | 06:23:28 INFO - PID 2916 | [#11] stanford-crypto-ccm Cycles:10 Average:151.70 Median:154.00 stddev:13.42 (8.7%) stddev-sans-first:13.28 06:23:28 INFO - PID 2916 | Values: 138.0 142.0 147.0 153.0 155.0 140.0 164.0 159.0 180.0 139.0 06:23:28 INFO - PID 2916 | 06:23:28 INFO - PID 2916 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:190.30 Median:188.50 stddev:13.72 (7.3%) stddev-sans-first:14.53 06:23:28 INFO - PID 2916 | Values: 188.0 184.0 188.0 184.0 189.0 174.0 201.0 224.0 190.0 181.0 06:23:28 INFO - PID 2916 | 06:23:28 INFO - PID 2916 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.30 Median:70.50 stddev:13.57 (19.3%) stddev-sans-first:14.26 06:23:28 INFO - PID 2916 | Values: 70.0 70.0 69.0 58.0 86.0 99.0 96.0 68.0 66.0 71.0 06:23:28 INFO - PID 2916 | -------- Summary: end -------- 06:23:28 INFO - PID 2916 | 06:23:29 INFO - TEST-INFO | 2916: exit 0 06:23:29 INFO - TEST-OK | kraken | took 60027ms 06:23:29 INFO - SUITE-END | took 1174s 06:23:29 INFO - Completed test suite (00:19:34) 06:23:29 INFO - javascript benchmark 06:23:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7812.294004578098, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3607.1523192049867, 3612.1330880588666, 3650.215203731198, 3661.688756455701, 3681.9263614727706, 6102.640737559642, 6216.347283535231, 6272.059191121334, 6279.15231443755, 6325.746970121201, 6250.687465626712, 6255.781047663333, 6263.812085637438, 6288.496920246381, 6317.3050964394, 5490.121580547113, 5493.697846618435, 5526.253955715987, 5582.720863956806, 5617.775288988456, 5416.891662166754, 5575.944240557583, 5655.4910058094765, 5702.885942281152, 5709.800156994506, 5563.13771365439, 5574.665520068809, 5676.744546495397, 5681.034224181899, 5704.942950570483, 5432.0, 5517.2551705519645, 5537.197106419587, 5557.6109672322955, 5566.415526369713, 5533.280673512458, 5563.527100196488, 5569.192467092249, 5569.637973531708, 5587.692676902769, 6064.939350606482, 6332.746690132389, 6334.651594162319, 6342.334054924213, 6352.555321127523, 6550.377714117151, 6612.107365505692, 6647.700853461557, 6771.8984215236815, 6787.694553745093, 6845.110135682379, 6882.346177113166, 6917.51577389583, 6927.3419025192525, 6929.341712537301, 6369.490440764727, 6394.776182833579, 6401.071844582602, 6402.455791257702, 6409.230892292908, 6790.626515541692, 6861.0, 6968.547044442095, 7044.753433629799, 7057.36483716468, 6628.602283862983, 6664.900026499607, 6716.227633822075, 6717.126773519509, 6789.388954994074, 6876.793696189123, 6879.89680154798, 6893.93106068943, 6911.3779759821855, 6933.306669333027, 6542.084108224853, 6635.435987940982, 6669.799906002828, 6778.593284402853, 6810.182778066664, 6715.2613212546585, 6764.763233286812, 6780.796576102724, 6815.727370905208, 6837.487188460886, 5408.107662235687, 5409.56723462122, 5441.891162176735, 5444.591655625764, 5467.589930755209, 4785.521447855187, 4812.711237325771, 4830.227163653799, 4843.07981483517, 4862.659613826998, 6287.968560157261, 6319.0, 6333.588316759488, 6343.936560634427, 6381.64900930444, 6918.654067296615, 6921.861562768717, 6926.411255043277, 6940.930590694028, 6943.236244013155, 6236.126942228001, 6311.211098612674, 6342.302346741854, 6344.0, 6365.458935990493, 5935.970320148458, 5997.880042399128, 6014.127951447016, 6113.144159817629, 6131.509479241739, 7041.718331266795, 7070.6111163884425, 7086.822829429306, 7091.716331346688, 7106.111736032996, 6845.828854278683, 6911.516193866381, 6921.446284297345, 6928.307169283032, 6936.20233673134, 6813.7955861324235, 6894.24163342022, 7052.823679408056, 7079.82300442493, 7093.290670932865, 5663.584103974006, 6681.899771503528, 6712.026756120433, 6725.024871393718, 6734.8653026940165, 6808.046873437822, 6853.280405557344, 6869.690863911036, 6874.9312506876295, 6910.516263861682, 6608.107905432705, 6623.1389919312105, 6627.867442651216, 6637.0, 6638.269790322964, 5731.684757338219, 6490.448311893353, 6495.610263384212, 6574.211094668671, 6600.80197594073, 6410.551261411656, 6431.710573024132, 6546.312637173029, 6570.08018877348, 6592.7033283503315, 6437.549371543947, 6448.613083215022, 6518.348165183444, 6607.405333519814, 6610.867782644416, 5114.846554603368, 6216.502679785697, 6510.9674451629335, 6537.803865884031, 6557.967210163919], "value": 6217.155581807338, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [728.5555810955316, 970.6748239339821, 1112.760756437366, 1123.544964289463, 1225.3566877389364, 37173.25653486932, 37981.86054418365, 38016.85949421522, 38018.80990595047, 38045.85862424125, 37604.62395376052, 37958.24083518328, 37996.81001594988, 38009.42985855215, 38029.0, 7664.540127592334, 7726.150123486426, 7873.724419645314, 8620.612072456754, 8657.134286571356, 8539.871901921477, 8628.65485380584, 8658.87011694825, 8659.220670139686, 8663.740087797376, 8619.95690021549, 8636.222739953402, 8682.609282582267, 8699.869501957475, 8713.433626814269, 8634.266087382548, 8638.870416943751, 8675.479471231714, 8692.695755648585, 8694.434861734031, 6428.646424446653, 6451.322611125811, 6521.282658907517, 6539.607623542555, 6562.80311590653, 37110.628893711255, 37272.81363593192, 37313.62686373156, 37338.43992340117, 37354.06614834624, 3530.8411121499594, 3567.3935430976794, 3570.946435803465, 3576.8390422431053, 3577.5528058992627, 30665.540016899766, 30690.9258175965, 30814.845925770453, 30932.536011959837, 30946.226344341347, 3121.2352973521406, 3139.6232452105664, 3141.9214519637194, 3159.526071089332, 3170.6036745406823, 303634.48182758945, 306913.46543267363, 308518.0, 309026.99999999773, 310725.0, 38459.0, 45465.54534454613, 47277.0, 47546.52453475421, 47738.806529836336, 43615.127697445874, 45023.874403139525, 48829.26756098661, 49465.75267123641, 49632.75918102004, 13898.374573144232, 13994.790078148835, 14072.437102515989, 14085.647858803406, 14287.78568321476, 11046.392448415254, 11051.889481105087, 11161.83257251142, 11245.887541124648, 11259.324440533594, 13563.321833908463, 13624.591262262147, 13631.591052268448, 13678.863211367958, 13737.450501980009, 5884.499817515559, 6162.291336496271, 6164.075388691732, 6164.630122192682, 6186.443220110212, 966.246327864253, 966.8694726211871, 1029.7271223125877, 1038.787048655024, 1052.6631477927142, 20448.79551204469, 20564.58870822575, 21455.141794328363, 23764.930578124025, 24312.14907478265, 1087.456271864068, 1090.8363745438248, 1124.3422597780227, 1143.6054561176381, 1145.707844499664, 12225.44985475638, 12243.755124897452, 12290.66955890102, 12305.876941230652, 12356.320402377776, 330808.6919130826, 332196.0, 332447.0, 332819.33590331895, 335148.324258382, 42489.36265956013, 51990.480095199324, 52025.43923682295, 52217.7389113052, 52425.73787131116, 1342.536824795444, 1494.3873012065, 1525.8779297656383, 1529.0825504697316, 1533.6242620557812, 22518.87405629679, 22810.20164294242, 23843.403914901573, 23902.402439939142, 23907.641385379582, 35247.647523524436, 35270.29459410849, 35397.0, 35432.114197145274, 35435.0, 244453.0, 247854.0, 248309.0, 274350.0, 275100.0, 29391.0, 35801.46297805483, 35805.64194358023, 35975.280494389444, 36294.455583166804, 44268.893277668314, 44408.33387499126, 45807.54192458033, 45833.0, 46381.07237855291, 30945.8452707735, 31009.534856976712, 31197.064088077394, 31360.68639313669, 31756.364872702878, 29844.955426559965, 30763.692363076083, 31097.798024302403, 31180.0, 31470.37059258848, 36818.26363472769, 36822.26355472822, 37195.81402092972, 37603.24793504169, 37762.811185943894, 30359.544606830474, 30435.0, 31073.0, 31431.528527071656, 31560.210994724395, 19800.0, 20500.0, 23356.532869342856, 24374.26877193687, 24512.387190320384], "value": 17287.621347013697, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [421.3578158975598, 423.94488716466867, 429.2723833102888, 449.1578290704928, 453.12772912144135, 19091.809081909178, 20936.895315523438, 21740.13039478421, 21830.017649205805, 22836.429089272737, 22726.659100113513, 22900.427489312777, 23069.884650576765, 23234.65148022781, 23260.65109023366, 5897.911531327044, 6050.42520960508, 6123.448889599935, 6131.50947924165, 6139.570230083896, 5886.20536227608, 6076.966915624333, 6085.665288409135, 6126.23422072241, 6164.691765411734, 5988.610740301889, 6015.458608725214, 6065.575409721321, 6099.085137229406, 6104.786332478364, 5883.941160588403, 6040.184575082351, 6043.0, 6050.24371953508, 6051.1830902828215, 5144.0, 5231.895362092737, 5380.381256155553, 5384.59615528833, 5454.781808727646, 26169.0, 26779.928802847862, 26934.865325673443, 27153.0, 27452.039178628653, 2151.9784802151935, 2192.2875065603585, 2204.922827701039, 2211.2481756202806, 2212.2478357358577, 17516.91241543784, 17717.37989170386, 17837.108144592847, 17941.372051978116, 18175.818241817546, 2165.5993641176433, 2178.7712290209615, 2213.7454192767877, 2225.2767850448668, 2227.4765430123907, 156210.0, 159603.20198398933, 159907.80184396246, 162995.0, 163744.0, 11042.944785276022, 12121.757564848655, 12510.124291299704, 12733.745325093632, 12783.296918669377, 16714.16429178536, 16734.916325418297, 16764.83235167657, 16776.161191940355, 16926.322947082226, 8845.955770221108, 8903.421277616933, 8944.329175311748, 9088.636454541876, 9111.17999380059, 6987.580745155307, 8420.915790842135, 8480.024797148408, 8493.27807136388, 8584.570771461402, 6933.965330173317, 7054.082969214072, 7366.447516436288, 8049.235322644339, 8098.7165449209, 1972.3589833304234, 2188.2231807708035, 2250.347399254231, 2259.2770313499523, 2420.891059902309, 935.4621092871625, 978.2320878110648, 980.8136454073846, 981.097390400833, 987.244757760314, 20309.49226269355, 20584.794152058286, 20716.896415518127, 20836.166553338, 21169.470763231042, 967.4388854464394, 991.4646091110719, 1003.4631472162504, 1005.1205195453978, 1033.9741506462399, 8801.207891289814, 8811.735647930573, 10097.697069087939, 10155.543000564992, 10630.893691062991, 493591.0, 539372.0, 566566.0, 568657.0, 571546.0, 103102.96897031083, 121720.17419738711, 122231.0, 123686.38156809159, 132431.33784331018, 1192.9522819087313, 1220.0910321810309, 1259.6661884600405, 1266.936653167338, 1268.7272236469203, 17656.0, 21665.675014875105, 21917.452063698536, 22325.553488929807, 22351.888240558692, 57401.308391598104, 57504.1374379393, 57759.4224057754, 57913.13130303133, 58626.70686646539, 49228.01543969172, 67562.0, 73533.63233184014, 75158.6242068808, 84001.73997390167, 7025.859482810213, 8798.164174403422, 9028.458292502472, 9033.548322583976, 9114.31642626819, 14072.155670659793, 14134.646633834236, 14304.141751494528, 14529.709405812036, 14584.343704533108, 11687.474063667325, 11884.524619015143, 11891.28652280866, 12008.579699710817, 12037.638870833889, 10420.843687344544, 11019.449027548751, 11144.832827507757, 11386.0, 11438.027767639844, 11781.469833857325, 11957.266196401299, 12074.698132546406, 12095.455704493143, 12158.993766028507, 8789.384743067923, 8813.559322034002, 9021.007689154056, 9171.128742769426, 9203.76990575241, 11670.299782013106, 11960.41187643686, 12079.335636539723, 12124.757504849676, 12226.20529665569], "value": 10403.578545772822, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1180.929144251345, 1213.6905089202257, 1376.421902800825, 1383.8721442024748, 1387.36181356576, 9422.858657120149, 9992.650257240997, 12168.209066410689, 12193.08551858612, 12205.206661567003, 11799.70500737482, 12011.339376334297, 12011.519539218421, 12013.03895688345, 12038.157328986952, 2494.326531836403, 2557.3734435063343, 2557.884895179712, 2564.3204550794053, 2569.5888657814753, 2489.5269898719275, 2542.4533725249066, 2547.2994926395204, 2550.6684131062934, 2559.3345730110213, 2196.1325276515768, 2465.7410971847876, 2470.0, 2499.5375855466705, 2516.245126462063, 2464.112919349033, 2475.938101547467, 2487.8631675257852, 2488.4276616378243, 2496.5131799299143, 2312.8149748020287, 2428.623563347684, 2471.480988992314, 2482.7765501104805, 2487.2040946897, 11533.019693326078, 11674.182807203499, 11754.823677644843, 11768.411579420994, 11777.0, 6315.715792789336, 6563.474922006228, 6566.080748695186, 6580.769673061468, 6614.603123812539, 3873.3028054950096, 4214.894627634303, 4261.4460120184485, 4305.63402110821, 4357.2592659248, 1185.3954483213538, 1192.3859212505479, 1234.1299383934395, 1246.1463897230335, 1250.6810763255403, 14285.92855535839, 15045.022073565293, 15509.612259693486, 16348.673026539522, 16363.42728004526, 5342.2253773202665, 5407.702576358338, 5588.720563971785, 5592.97203513988, 5595.748191331319, 11652.242604230698, 11833.349165795964, 11835.17153799243, 11846.407679615984, 11850.288982661068, 6368.172137622079, 6439.64581947988, 6447.065175549613, 6450.580712253689, 6454.386833250834, 4744.786484608201, 5564.610477266633, 5978.282606087298, 6106.358832322631, 6151.292601350902, 3655.597884232732, 4344.826206951687, 4468.731876087445, 4497.6851620386915, 4505.932411013838, 1258.6916205529703, 1260.8802163794426, 1278.130871007705, 1279.9232046077266, 1293.624848793858, 721.264310403396, 793.2860425616991, 798.3692882622779, 814.1573471457014, 816.1552792859362, 5761.366249712528, 6171.858206231862, 6278.246610406781, 6292.119103325538, 6354.030250622322, 592.3306663470252, 592.7273454211025, 609.6616377910302, 616.1435604509735, 619.9349068347849, 3354.9329013419597, 3556.342076715764, 3898.9610103899167, 3923.9197230174746, 3967.0479085019388, 14377.209253491159, 14717.779233311509, 14793.260336983109, 14868.776968345483, 14903.254837258311, 12735.872641273654, 14265.643358915902, 14768.261586920826, 14821.555353339418, 14840.480583179537, 8161.0821456957565, 8572.442791218551, 8575.871361929576, 8611.526366049802, 8647.567621618893, 5623.634463759842, 6613.470922326299, 6928.618925958919, 6981.79054628362, 7134.393576546052, 13092.083554151119, 13189.604311870658, 13218.471261149447, 13310.600681979557, 13396.397162127923, 13285.93712502978, 13393.531226407029, 13562.728745425233, 13637.795433068313, 13753.587392378246, 9256.814863702823, 9350.438973661601, 9404.38871473352, 9426.717198484057, 9484.620615175316, 10592.947035264775, 10752.892471075502, 10773.73065673333, 10778.0, 10781.02970732638, 7802.180771018961, 8877.955610221907, 9080.455172689659, 9187.219086377732, 9209.585568649292, 8772.956135219283, 8825.38222324431, 8881.046169842624, 8916.33127515426, 9050.909490905007, 7148.0, 8194.83610327802, 8332.250097491255, 8346.582670866554, 8361.247487726134, 4816.446108697544, 6004.0, 6059.606125601822, 6082.36135205797, 6145.231846019247, 6120.54095942815, 6274.780382686584, 6323.146375239284, 6357.745690172342, 6404.9039264412], "value": 5229.882671342235, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.595664985267, 392.5250446959179, 397.72556935714374, 401.889480392892, 403.69924406317296, 4744.667873248874, 4779.904401911964, 5215.58275337973, 6064.120702498135, 6066.393360663931, 5300.390455097665, 5467.835964921049, 6015.639061656304, 6153.969230153843, 6160.706251687151, 4409.603135717784, 4482.125985432842, 4515.0, 4526.117407105616, 4539.795709193094, 3706.7405281630295, 4494.887627809315, 4495.145922274757, 4517.909641807162, 4529.818807247706, 3718.888433347004, 4404.405405270296, 4471.5081341052455, 4477.171723231197, 4493.831848546767, 3780.962190378102, 4416.690831641787, 4439.66702497314, 4464.486584042844, 4490.550944905516, 3799.5820459749407, 3813.0, 3832.4826148470156, 3832.9808350958065, 3861.6717579005817, 5243.973780131113, 5796.536277097822, 6030.03519436888, 6058.697065146725, 6066.181065556138, 4529.932051019237, 5278.841634750926, 5345.1982202669915, 5396.91904621431, 5420.457954204548, 5471.5896307776675, 6568.376004279586, 6587.73649054042, 6592.406683398517, 6608.240052393988, 4373.387725718402, 4373.846915357947, 4375.518692943773, 4385.934210986838, 4397.670174736875, 6664.733410663568, 6779.389854913032, 7008.929910700878, 7027.472939529555, 7045.365917067437, 4402.8679139625865, 4436.911261774746, 4439.578240067189, 4447.3773671685985, 4450.287953927356, 4121.464209652726, 4154.293770059097, 4362.825486980549, 4424.026714122888, 4523.864284071483, 4586.747728874944, 5252.896891652719, 5298.88723368097, 5333.253344531769, 5348.438413966555, 5158.200478925773, 5930.55520835939, 6628.110988368292, 6650.040731874366, 6668.533202675863, 1504.7592385218313, 1524.7116186822118, 1530.3496014193922, 1531.5481932829841, 1534.5473085439821, 979.7844474215661, 989.5645915797027, 992.6575331510618, 994.5325696922434, 997.3816233935007, 4919.7540122993705, 5069.645124841226, 5477.287952566141, 5814.5639077068545, 6195.845103872439, 6744.932550674529, 6852.211995620568, 6854.320691430631, 6911.408221916781, 6937.895931561031, 5617.662940223599, 6049.213602231682, 6084.756609735649, 6141.170941922873, 6175.876482470326, 2993.835339056373, 3152.5271209318785, 3305.9173520662175, 3386.3745402206587, 3547.4635635520212, 5879.970600146972, 6156.322804491502, 6486.935130648728, 7134.571925684476, 7184.209736928934, 6554.573952693059, 7200.639968001579, 7201.89197162043, 7209.747658831916, 7230.493865429475, 5483.588730845282, 6927.134108236471, 7117.932310153518, 7133.429325653935, 7147.428205743528, 5784.623999440023, 6376.0, 6982.6508674567085, 7021.613811240431, 7044.225135235018, 6524.575902566318, 6724.831879203059, 6826.64480754139, 6836.213835408992, 6868.484863635147, 5208.0365132450615, 5615.494605485526, 6859.657017149023, 6964.860702786017, 7103.360697537247, 4027.677785777072, 4099.262132816122, 4142.5443201247235, 4453.421055262795, 4477.059817438374, 5137.743112844418, 6443.904536228946, 6980.277691700342, 6985.5808651481075, 7006.9649651751415, 6152.538559608137, 6641.933580664131, 6659.068870027638, 6661.533692641662, 6677.929841749074, 5820.76716931318, 6510.153680021567, 6647.800565983028, 6662.167229096363, 6739.831504212434, 6442.549021568445, 6453.612783232833, 6461.709223084878, 6513.674316284072, 6540.182477190351, 6270.773875274212, 6589.374009469093, 6639.867202655823, 6656.700448479927, 6687.866242675022, 5038.773255203598, 5470.425605311544, 6412.39082287182, 6457.838554036187, 6504.707288171949], "value": 4720.319662743861, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [787.6337503061079, 815.388458656008, 819.9221073997971, 835.0438747633959, 851.6635928808123, 14475.276236188174, 15035.624109397271, 15992.280347384367, 16043.438479653214, 16352.83647163528, 16748.916255418735, 17312.56718582036, 17551.2101955412, 17574.0, 17649.64700705985, 954.7517645412173, 969.781799095205, 971.9659811906585, 975.2929126383351, 975.9849756253487, 957.8754761880946, 961.8364877970727, 962.74006018375, 964.0937518732404, 969.8981606931277, 1004.3672486333636, 1007.9092881640651, 1009.142229105262, 1009.878814542256, 1010.434156872154, 1006.9093781559658, 1008.1632245236473, 1008.5461542305992, 1009.1069403577814, 1011.039512463163, 742.0835268443521, 742.0983505041401, 742.9517081389746, 743.4238465189468, 744.5011842065829, 16672.49982500527, 17105.57236069096, 17347.913260433616, 17350.305987760476, 17400.0, 14319.14085154894, 16218.75671864923, 16717.582060448465, 16933.830661693348, 16967.406140785013, 14047.719045619133, 14317.570472885725, 14564.708705825824, 14567.344469498898, 14595.562133136023, 3025.3495498467937, 3069.2480342316276, 3487.7558570900046, 3596.7122630189783, 3646.5988741238443, 107273.46363268212, 109678.0, 111734.88265117326, 111770.44114779455, 114120.0, 13135.605931822061, 13300.600981970556, 13396.397162127532, 13438.596842094752, 13450.26023568713, 25080.247592572254, 25334.239972800846, 25433.618495722578, 25494.74505254924, 25996.8700156498, 7664.271894170045, 7986.281234688906, 8014.278714915686, 8027.438079334391, 8030.478018928751, 3880.902977425587, 3946.072672921861, 3965.5836137205756, 3969.325214713506, 3973.384125460558, 3987.501562304712, 4157.064660451374, 4288.871333859989, 4291.184674911757, 4294.506131794821, 743.8884167374937, 844.8225872566707, 871.7123349294718, 874.0429229993148, 881.8544940084814, 623.3626787693858, 655.6590572902064, 656.6322859198864, 657.8651376467812, 680.7038938061959, 10283.0, 10594.576216951236, 11135.944320278346, 11819.0, 11995.940020300017, 647.6535053746223, 649.6394501051867, 651.5504302031583, 656.6440300778876, 668.7391917152314, 5469.769301907039, 5984.670843103584, 6029.57792954489, 6078.422549857756, 6092.0, 81314.59342703367, 98905.53830615894, 100430.49784751175, 106927.0, 108893.45553272183, 93113.0, 94417.0, 94472.58291125638, 94588.12236592743, 95288.52355738316, 64472.35527644664, 82382.17617823865, 83100.58449707834, 83395.91510212293, 85491.0, 10028.398296101965, 12301.384930753606, 12491.125621206791, 12508.56220032331, 12569.051796971484, 38895.44418223353, 39182.2163556733, 39599.20801583894, 39726.60273397229, 39845.80077099596, 38026.80986595049, 51366.22950655669, 51465.485345147565, 51526.0, 51632.74183629058, 15760.527184184493, 15920.0, 16367.345306187619, 16887.240074196936, 17021.0, 17395.652086958442, 18097.2460929697, 18384.0, 18514.537136571693, 18516.629667406847, 16034.679306413573, 16336.346546138022, 16623.0, 16739.66520669604, 16766.832331676527, 16036.358545658042, 16861.747073794148, 17091.572710682332, 17427.39004134849, 17440.0, 15827.683446331239, 16318.020918744438, 16630.08534530564, 16921.407750728664, 16988.150592470574, 11691.356975366096, 11754.823677644672, 11852.822207666719, 12053.216540924812, 12076.275423474619, 11814.704632384259, 12365.319907405179, 12988.870111298766, 13281.933590331986, 13402.128861623964], "value": 8235.07276482171, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1545.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 166.0, 167.0, 114.0, 115.0, 116.0, 115.0, 114.0, 114.0, 115.0], "value": 127.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 113.0, 111.0, 151.0, 113.0, 113.0, 112.0, 114.0, 151.0, 148.0], "value": 127.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 200.0, 199.0, 195.0, 206.0, 213.0, 216.0, 240.0, 284.0, 203.0], "value": 215.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 58.0, 58.0, 88.0, 78.0, 83.0, 74.0, 58.0, 83.0, 83.0], "value": 74.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 72.0, 86.0, 69.0, 65.0, 73.0, 87.0, 85.0, 104.0, 112.0], "value": 83.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 91.0, 90.0, 92.0, 91.0, 91.0, 90.0, 91.0, 103.0, 90.0], "value": 91.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 98.0, 98.0, 97.0, 120.0, 148.0, 113.0, 91.0, 154.0], "value": 111.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 130.0, 90.0, 92.0, 91.0, 92.0, 89.0, 94.0, 91.0, 91.0], "value": 95.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 52.0, 51.0, 51.0, 49.0, 49.0, 50.0, 53.0, 51.0, 49.0], "value": 50.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 56.0, 57.0, 72.0, 73.0, 83.0, 83.0, 48.0, 66.0, 79.0], "value": 67.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 86.0, 82.0, 81.0, 84.0, 83.0, 85.0, 83.0, 81.0], "value": 83.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 142.0, 147.0, 153.0, 155.0, 140.0, 164.0, 159.0, 180.0, 139.0], "value": 151.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 184.0, 188.0, 184.0, 189.0, 174.0, 201.0, 224.0, 190.0, 181.0], "value": 190.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 69.0, 58.0, 86.0, 99.0, 96.0, 68.0, 66.0, 71.0], "value": 75.3, "unit": "score"}]}]} 06:23:29 INFO - Return code: 0 06:23:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:23:30 INFO - # TBPL SUCCESS # 06:23:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:23:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:23:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:23:30 INFO - 2017-05-22 06:23:30.038 screenresolution[2923:50116] starting screenresolution argv=screenresolution get 06:23:30 INFO - 2017-05-22 06:23:30.041 screenresolution[2923:50116] Display 0: 1600x1200x32@60 06:23:30 INFO - 2017-05-22 06:23:30.047 screenresolution[2924:50119] starting screenresolution argv=screenresolution list 06:23:30 INFO - Available Modes on Display 0 06:23:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:23:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:23:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:23:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:23:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:23:30 INFO - Intel Iris: 06:23:30 INFO - Chipset Model: Intel Iris 06:23:30 INFO - Type: GPU 06:23:30 INFO - Bus: Built-In 06:23:30 INFO - VRAM (Dynamic, Max): 1536 MB 06:23:30 INFO - Vendor: Intel (0x8086) 06:23:30 INFO - Device ID: 0x0a2e 06:23:30 INFO - Revision ID: 0x0009 06:23:30 INFO - Displays: 06:23:30 INFO - Display: 06:23:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:23:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:23:30 INFO - Display Serial Number: 1600x1200 60 06:23:30 INFO - Main Display: Yes 06:23:30 INFO - Mirror: Off 06:23:30 INFO - Online: Yes 06:23:30 INFO - Rotation: Supported 06:23:30 INFO - Return code: 0 06:23:30 INFO - Running post-action listener: _resource_record_post_action 06:23:30 INFO - [mozharness: 2017-05-22 13:23:30.281937Z] Finished run-tests step (success) 06:23:30 INFO - Running post-run listener: _resource_record_post_run 06:23:30 INFO - Total resource usage - Wall time: 1197s; CPU: 54.0%; Read bytes: 3739648; Write bytes: 565935104; Read time: 14; Write time: 1747 06:23:30 INFO - TinderboxPrint: CPU usage
54.0% 06:23:30 INFO - TinderboxPrint: I/O read bytes / time
3,739,648 / 14 06:23:30 INFO - TinderboxPrint: I/O write bytes / time
565,935,104 / 1,747 06:23:30 INFO - TinderboxPrint: CPU idle
2,203.1 (46.0%) 06:23:30 INFO - TinderboxPrint: CPU system
962.6 (20.1%) 06:23:30 INFO - TinderboxPrint: CPU user
1,624.0 (33.9%) 06:23:30 INFO - TinderboxPrint: Swap in / out
248,479,744 / 0 06:23:30 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 0; Write bytes: 249198592; Read time: 0; Write time: 511 06:23:30 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 475136; Write bytes: 315225088; Read time: 3; Write time: 1230 06:23:30 INFO - Running post-run listener: _upload_blobber_files 06:23:30 INFO - Blob upload gear active. 06:23:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:23:30 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:23:30 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:23:30 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:23:31 INFO - (blobuploader) - INFO - Open directory for files ... 06:23:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:23:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:23:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:23:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:23:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:23:32 INFO - (blobuploader) - INFO - Done attempting. 06:23:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:23:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:23:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:23:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:23:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:23:33 INFO - (blobuploader) - INFO - Done attempting. 06:23:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:23:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:23:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:23:33 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:23:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:23:33 INFO - (blobuploader) - INFO - Done attempting. 06:23:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:23:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:23:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:23:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:23:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:23:36 INFO - (blobuploader) - INFO - Done attempting. 06:23:36 INFO - (blobuploader) - INFO - Iteration through files over. 06:23:36 INFO - Return code: 0 06:23:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:23:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:23:36 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/41693caa7b1f37c44f691df4cc20ed7049f99a75221fee3663aee421edd18094ab7b253444d893a2b662e304bcc2cafe7e716a14c8cfc1e9f91df657c39edad2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a3e0ac79d98f7e914bf9baab258978fd609008efc7640654bd12ee61f46b320a9a61c6d1e7aa9eda2e62a36865c737ff3b8534cb803ba9f8ab85af474de4fb73", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8022eabe2cad536c42d2dcc2f10f925926d057d3733479ca387b80bef98b3c1ae8ab1874dfeccc4f95550e24877e4ede1551c3bd709aa13fa98282705737f65e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e7430e7a6f83c1395c8c1c73b434238af8194f8cfb5b4b320e7764ecbbd21baf518249fafd238f91f75eb02f089a414936b5b00d1830af872f8c62e96781313e"} 06:23:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:23:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:23:36 INFO - Contents: 06:23:36 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/41693caa7b1f37c44f691df4cc20ed7049f99a75221fee3663aee421edd18094ab7b253444d893a2b662e304bcc2cafe7e716a14c8cfc1e9f91df657c39edad2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a3e0ac79d98f7e914bf9baab258978fd609008efc7640654bd12ee61f46b320a9a61c6d1e7aa9eda2e62a36865c737ff3b8534cb803ba9f8ab85af474de4fb73", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8022eabe2cad536c42d2dcc2f10f925926d057d3733479ca387b80bef98b3c1ae8ab1874dfeccc4f95550e24877e4ede1551c3bd709aa13fa98282705737f65e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e7430e7a6f83c1395c8c1c73b434238af8194f8cfb5b4b320e7764ecbbd21baf518249fafd238f91f75eb02f089a414936b5b00d1830af872f8c62e96781313e"} 06:23:36 INFO - Running post-run listener: copy_logs_to_upload_dir 06:23:36 INFO - Copying logs to upload dir... 06:23:36 INFO - mkdir: /builds/slave/test/build/upload/logs 06:23:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.956845 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-05-22 06:23:36.331156) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-22 06:23:36.335249) ========= 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.cUFNZpjl31/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.yLloEyc57B/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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/41693caa7b1f37c44f691df4cc20ed7049f99a75221fee3663aee421edd18094ab7b253444d893a2b662e304bcc2cafe7e716a14c8cfc1e9f91df657c39edad2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a3e0ac79d98f7e914bf9baab258978fd609008efc7640654bd12ee61f46b320a9a61c6d1e7aa9eda2e62a36865c737ff3b8534cb803ba9f8ab85af474de4fb73", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8022eabe2cad536c42d2dcc2f10f925926d057d3733479ca387b80bef98b3c1ae8ab1874dfeccc4f95550e24877e4ede1551c3bd709aa13fa98282705737f65e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e7430e7a6f83c1395c8c1c73b434238af8194f8cfb5b4b320e7764ecbbd21baf518249fafd238f91f75eb02f089a414936b5b00d1830af872f8c62e96781313e"} build_url:https://queue.taskcluster.net/v1/task/AaMTRejTQ9OyomyvcjX0oQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011416 build_url: 'https://queue.taskcluster.net/v1/task/AaMTRejTQ9OyomyvcjX0oQ/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/41693caa7b1f37c44f691df4cc20ed7049f99a75221fee3663aee421edd18094ab7b253444d893a2b662e304bcc2cafe7e716a14c8cfc1e9f91df657c39edad2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a3e0ac79d98f7e914bf9baab258978fd609008efc7640654bd12ee61f46b320a9a61c6d1e7aa9eda2e62a36865c737ff3b8534cb803ba9f8ab85af474de4fb73", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8022eabe2cad536c42d2dcc2f10f925926d057d3733479ca387b80bef98b3c1ae8ab1874dfeccc4f95550e24877e4ede1551c3bd709aa13fa98282705737f65e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e7430e7a6f83c1395c8c1c73b434238af8194f8cfb5b4b320e7764ecbbd21baf518249fafd238f91f75eb02f089a414936b5b00d1830af872f8c62e96781313e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-22 06:23:36.368498) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 06:23:36.372874) ========= 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.cUFNZpjl31/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.yLloEyc57B/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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.005357 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 06:23:36.421507) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-22 06:23:36.423837) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-22 06:23:36.424158) ========= ========= Total master_lag: 0.21 =========