builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0030 starttime: 1495402016.47 results: success (0) revision: 74566d5345f4cab06c5683d4b620124104801e65 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-21 14:26:56.473955) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-21 14:26:56.474584) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-21 14:26:56.474875) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-21 14:26:56.601214) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-21 14:26:56.601609) ========= 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.sTzpip1n6I/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.KRbGfUd4TQ/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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-21 14:26:56-- 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% 77.4M=0s 2017-05-21 14:26:56 (77.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.122410 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-21 14:26:56.746452) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-21 14:26:56.746764) ========= 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.sTzpip1n6I/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.KRbGfUd4TQ/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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.029492 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-21 14:26:56.797210) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-21 14:26:56.797547) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 74566d5345f4cab06c5683d4b620124104801e65 --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 74566d5345f4cab06c5683d4b620124104801e65 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.sTzpip1n6I/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.KRbGfUd4TQ/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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-21 14:26:56,893 truncating revision to first 12 chars 2017-05-21 14:26:56,893 Setting DEBUG logging. 2017-05-21 14:26:56,893 attempt 1/10 2017-05-21 14:26:56,893 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/74566d5345f4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-21 14:26:58,081 unpacking tar archive at: mozilla-central-74566d5345f4/testing/mozharness/ program finished with exit code 0 elapsedTime=1.467586 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-21 14:26:58.286862) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-21 14:26:58.287210) ========= script_repo_revision: 74566d5345f4cab06c5683d4b620124104801e65 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-21 14:26:58.287613) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-21 14:26:58.287891) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-21 14:26:58.306866) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2017-05-21 14:26:58.307286) ========= /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.sTzpip1n6I/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.KRbGfUd4TQ/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 14:26:58 INFO - MultiFileLogger online at 20170521 14:26:58 in /builds/slave/test 14:26:58 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 14:26:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:26:58 INFO - {'append_to_log': False, 14:26:58 INFO - 'base_work_dir': '/builds/slave/test', 14:26:58 INFO - 'blob_upload_branch': 'Firefox', 14:26:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:26:58 INFO - 'branch': 'Firefox', 14:26:58 INFO - 'buildbot_json_path': 'buildprops.json', 14:26:58 INFO - 'config_files': ('talos/mac_config.py',), 14:26:58 INFO - 'default_actions': ('clobber', 14:26:58 INFO - 'read-buildbot-config', 14:26:58 INFO - 'download-and-extract', 14:26:58 INFO - 'populate-webroot', 14:26:58 INFO - 'create-virtualenv', 14:26:58 INFO - 'install', 14:26:58 INFO - 'run-tests'), 14:26:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:26:58 INFO - 'download_minidump_stackwalk': True, 14:26:58 INFO - 'download_symbols': 'ondemand', 14:26:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:26:58 INFO - 'tooltool.py': '/tools/tooltool.py', 14:26:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:26:58 INFO - '/tools/misc-python/virtualenv.py')}, 14:26:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:26:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:26:58 INFO - 'gecko_profile': False, 14:26:58 INFO - 'gecko_profile_interval': 0, 14:26:58 INFO - 'installer_path': 'installer.exe', 14:26:58 INFO - 'log_level': 'info', 14:26:58 INFO - 'log_name': 'talos', 14:26:58 INFO - 'log_to_console': True, 14:26:58 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:26:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:26:58 INFO - 'opt_config_files': (), 14:26:58 INFO - 'pip_index': False, 14:26:58 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:26:58 INFO - 'cmd': ('bash', 14:26:58 INFO - '-c', 14:26:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:26:58 INFO - 'enabled': True, 14:26:58 INFO - 'halt_on_failure': False, 14:26:58 INFO - 'name': 'check_screen_resolution'},), 14:26:58 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:26:58 INFO - 'cmd': ('bash', 14:26:58 INFO - '-c', 14:26:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:26:58 INFO - 'enabled': True, 14:26:58 INFO - 'halt_on_failure': False, 14:26:58 INFO - 'name': 'check_screen_resolution'},), 14:26:58 INFO - 'run_cmd_checks_enabled': True, 14:26:58 INFO - 'suite': 'dromaeojs-e10s', 14:26:58 INFO - 'system_bits': '32', 14:26:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:26:58 INFO - 'title': 't-yosemite-r7-0030', 14:26:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:26:58 INFO - 'use_talos_json': True, 14:26:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:26:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:26:58 INFO - 'work_dir': 'build'} 14:26:58 INFO - [mozharness: 2017-05-21 21:26:58.519330Z] Running clobber step. 14:26:58 INFO - Running pre-action listener: _resource_record_pre_action 14:26:58 INFO - Running main action method: clobber 14:26:58 INFO - rmtree: /builds/slave/test/build 14:26:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:27:03 INFO - Running post-action listener: _resource_record_post_action 14:27:03 INFO - [mozharness: 2017-05-21 21:27:03.404060Z] Finished clobber step (success) 14:27:03 INFO - [mozharness: 2017-05-21 21:27:03.404140Z] Running read-buildbot-config step. 14:27:03 INFO - Running pre-action listener: _resource_record_pre_action 14:27:03 INFO - Running main action method: read_buildbot_config 14:27:03 INFO - Using buildbot properties: 14:27:03 INFO - { 14:27:03 INFO - "project": "", 14:27:03 INFO - "product": "firefox", 14:27:03 INFO - "who": "philringnalda@gmail.com", 14:27:03 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 14:27:03 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 14:27:03 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 14:27:03 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 14:27:03 INFO - "stage_platform": "macosx64", 14:27:03 INFO - "basedir": "/builds/slave/test", 14:27:03 INFO - "buildnumber": 221, 14:27:03 INFO - "platform": "yosemite_r7", 14:27:03 INFO - "parent_task_id": "YOno8HMTT9qu3rlWyT1woA", 14:27:03 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 14:27:03 INFO - "slavebuilddir": "test", 14:27:03 INFO - "taskId": "JQYyhGYDQ5alvtwL4ET6Rg", 14:27:03 INFO - "branch": "mozilla-central", 14:27:03 INFO - "script_repo_revision": "production", 14:27:03 INFO - "revision": "74566d5345f4cab06c5683d4b620124104801e65", 14:27:03 INFO - "slavename": "t-yosemite-r7-0030", 14:27:03 INFO - "repo_path": "mozilla-central" 14:27:03 INFO - } 14:27:03 INFO - Finding installer, test and symbols from parent task. 14:27:03 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JQYyhGYDQ5alvtwL4ET6Rg'}, attempt #1 14:27:04 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JQYyhGYDQ5alvtwL4ET6Rg'}, attempt #1 14:27:04 INFO - Task dependencies: KmBXe-VQR5WnsH57XBr_7A YOno8HMTT9qu3rlWyT1woA 14:27:04 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YOno8HMTT9qu3rlWyT1woA'}, attempt #1 14:27:05 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/YOno8HMTT9qu3rlWyT1woA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:27:05 INFO - Set test_url: https://queue.taskcluster.net/v1/task/YOno8HMTT9qu3rlWyT1woA/artifacts/public/build/test_packages.json 14:27:05 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/YOno8HMTT9qu3rlWyT1woA/artifacts/public/build/target.crashreporter-symbols.zip 14:27:05 INFO - Running post-action listener: _resource_record_post_action 14:27:05 INFO - [mozharness: 2017-05-21 21:27:05.246440Z] Finished read-buildbot-config step (success) 14:27:05 INFO - [mozharness: 2017-05-21 21:27:05.246576Z] Running download-and-extract step. 14:27:05 INFO - Running pre-action listener: _resource_record_pre_action 14:27:05 INFO - Running main action method: download_and_extract 14:27:05 INFO - mkdir: /builds/slave/test/build/tests 14:27:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:27:05 INFO - trying https://queue.taskcluster.net/v1/task/YOno8HMTT9qu3rlWyT1woA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 14:27:05 INFO - Downloading https://queue.taskcluster.net/v1/task/YOno8HMTT9qu3rlWyT1woA/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 14:27:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YOno8HMTT9qu3rlWyT1woA/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 14:27:06 INFO - Downloaded 1325 bytes. 14:27:06 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 14:27:06 INFO - Using the following test package requirements: 14:27:06 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:27:06 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 14:27:06 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 14:27:06 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:27:06 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 14:27:06 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:27:06 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 14:27:06 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:27:06 INFO - u'jsshell-mac.zip'], 14:27:06 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:27:06 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 14:27:06 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 14:27:06 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:27:06 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 14:27:06 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:27:06 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 14:27:06 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:27:06 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 14:27:06 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:27:06 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 14:27:06 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:27:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YOno8HMTT9qu3rlWyT1woA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 14:27:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YOno8HMTT9qu3rlWyT1woA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:27:06 INFO - Fetch https://queue.taskcluster.net/v1/task/YOno8HMTT9qu3rlWyT1woA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 14:27:07 INFO - Content-Length response header: 57622691 14:27:07 INFO - Bytes received: 57622691 14:27:26 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 14:27:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YOno8HMTT9qu3rlWyT1woA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 14:27:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YOno8HMTT9qu3rlWyT1woA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:27:26 INFO - Fetch https://queue.taskcluster.net/v1/task/YOno8HMTT9qu3rlWyT1woA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 14:27:27 INFO - Content-Length response header: 57622691 14:27:27 INFO - Bytes received: 57622691 14:27:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YOno8HMTT9qu3rlWyT1woA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 14:27:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YOno8HMTT9qu3rlWyT1woA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 14:27:44 INFO - Fetch https://queue.taskcluster.net/v1/task/YOno8HMTT9qu3rlWyT1woA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 14:27:45 INFO - Content-Length response header: 14410000 14:27:45 INFO - Bytes received: 14410000 14:27:46 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')]} 14:27:46 INFO - trying https://queue.taskcluster.net/v1/task/YOno8HMTT9qu3rlWyT1woA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:27:46 INFO - Downloading https://queue.taskcluster.net/v1/task/YOno8HMTT9qu3rlWyT1woA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 14:27:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YOno8HMTT9qu3rlWyT1woA/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 14:27:48 INFO - Downloaded 68055300 bytes. 14:27:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YOno8HMTT9qu3rlWyT1woA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:27:48 INFO - mkdir: /builds/slave/test/properties 14:27:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:27:48 INFO - Writing to file /builds/slave/test/properties/build_url 14:27:48 INFO - Contents: 14:27:48 INFO - build_url:https://queue.taskcluster.net/v1/task/YOno8HMTT9qu3rlWyT1woA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:27:48 INFO - Running post-action listener: _resource_record_post_action 14:27:48 INFO - Running post-action listener: set_extra_try_arguments 14:27:48 INFO - [mozharness: 2017-05-21 21:27:48.111033Z] Finished download-and-extract step (success) 14:27:48 INFO - [mozharness: 2017-05-21 21:27:48.111182Z] Running populate-webroot step. 14:27:48 INFO - Running pre-action listener: _resource_record_pre_action 14:27:48 INFO - Running main action method: populate_webroot 14:27:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:27:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:27:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:27:48 INFO - '--tppagecycles', 14:27:48 INFO - '7'], 14:27:48 INFO - 'tests': ['tsvgm']}}, 14:27:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:27:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:27:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:27:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:27:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:27:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:27:48 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:27:48 INFO - 'talos_options': ['--disable-e10s'], 14:27:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:27:48 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:27:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:27:48 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:27:48 INFO - 'talos_options': ['--disable-e10s'], 14:27:48 INFO - 'tests': ['damp', 'tps']}, 14:27:48 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:27:48 INFO - 'tests': ['damp', 'tps']}, 14:27:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:27:48 INFO - 'tests': ['dromaeo_dom']}, 14:27:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:27:48 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:27:48 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 14:27:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 14:27:48 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 14:27:48 INFO - 'tests': ['ts_paint', 'tp5o']}, 14:27:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:27:48 INFO - 'tests': ['a11yr', 14:27:48 INFO - 'ts_paint', 14:27:48 INFO - 'tpaint', 14:27:48 INFO - 'sessionrestore', 14:27:48 INFO - 'sessionrestore_no_auto_restore', 14:27:48 INFO - 'tabpaint']}, 14:27:48 INFO - 'other-e10s': {'tests': ['a11yr', 14:27:48 INFO - 'ts_paint', 14:27:48 INFO - 'tpaint', 14:27:48 INFO - 'sessionrestore', 14:27:48 INFO - 'sessionrestore_no_auto_restore', 14:27:48 INFO - 'tabpaint']}, 14:27:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:27:48 INFO - 'ts_paint', 14:27:48 INFO - 'tpaint', 14:27:48 INFO - 'sessionrestore', 14:27:48 INFO - 'sessionrestore_no_auto_restore', 14:27:48 INFO - 'tabpaint']}, 14:27:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:27:48 INFO - 'ts_paint', 14:27:48 INFO - 'tpaint', 14:27:48 INFO - 'sessionrestore', 14:27:48 INFO - 'sessionrestore_no_auto_restore', 14:27:48 INFO - 'tabpaint']}, 14:27:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:27:48 INFO - 'tests': ['a11yr', 14:27:48 INFO - 'ts_paint', 14:27:48 INFO - 'tpaint', 14:27:48 INFO - 'sessionrestore', 14:27:48 INFO - 'sessionrestore_no_auto_restore', 14:27:48 INFO - 'tabpaint']}, 14:27:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:27:48 INFO - 'tests': ['a11yr', 14:27:48 INFO - 'ts_paint', 14:27:48 INFO - 'tpaint', 14:27:48 INFO - 'sessionrestore', 14:27:48 INFO - 'sessionrestore_no_auto_restore', 14:27:48 INFO - 'tabpaint']}, 14:27:48 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 14:27:48 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 14:27:48 INFO - 'bloom_basic_ref']}, 14:27:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:27:48 INFO - 'tests': ['tsvgx', 14:27:48 INFO - 'tsvgr_opacity', 14:27:48 INFO - 'tart', 14:27:48 INFO - 'tscrollx', 14:27:48 INFO - 'cart', 14:27:48 INFO - 'tsvg_static']}, 14:27:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:27:48 INFO - 'tsvgr_opacity', 14:27:48 INFO - 'tart', 14:27:48 INFO - 'tscrollx', 14:27:48 INFO - 'cart', 14:27:48 INFO - 'tsvg_static']}, 14:27:48 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:27:48 INFO - 'talos_options': ['--disable-e10s'], 14:27:48 INFO - 'tests': ['tp5o']}, 14:27:48 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:27:48 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:27:48 INFO - 'talos_options': ['--disable-e10s', 14:27:48 INFO - '--xperf_path', 14:27:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:27:48 INFO - 'tests': ['tp5n']}, 14:27:48 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:27:48 INFO - 'talos_options': ['--xperf_path', 14:27:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:27:48 INFO - 'tests': ['tp5n']}}, 14:27:48 INFO - 'talos.zip': {'path': '', 14:27:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:27:48 INFO - Running post-action listener: _resource_record_post_action 14:27:48 INFO - [mozharness: 2017-05-21 21:27:48.132518Z] Finished populate-webroot step (success) 14:27:48 INFO - [mozharness: 2017-05-21 21:27:48.132654Z] Running create-virtualenv step. 14:27:48 INFO - Running pre-action listener: _resource_record_pre_action 14:27:48 INFO - Running main action method: create_virtualenv 14:27:48 INFO - Creating virtualenv /builds/slave/test/build/venv 14:27:48 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 14:27:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:27:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:27:48 INFO - Using real prefix '/tools/python27' 14:27:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:27:49 INFO - Installing distribute.............................................................................................................................................................................................done. 14:27:51 INFO - Installing pip.................done. 14:27:51 INFO - Return code: 0 14:27:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:27:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:27:52 INFO - Reading from file tmpfile_stdout 14:27:52 INFO - Output received: 14:27:52 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) 14:27:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:27:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:27:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:27:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:27:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:27:52 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 0x10096cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100970768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ada46cdb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10067cd30>, '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 0x100630db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100671be8>, '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.sTzpip1n6I/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.KRbGfUd4TQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 14:27:52 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 14:27:52 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 14:27:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sTzpip1n6I/Render', 14:27:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:27:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:27:52 INFO - 'HOME': '/Users/cltbld', 14:27:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:27:52 INFO - 'LOGNAME': 'cltbld', 14:27:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:27:52 INFO - 'MOZ_NO_REMOTE': '1', 14:27:52 INFO - 'NO_EM_RESTART': '1', 14:27:52 INFO - 'PAGER': '/bin/cat', 14:27:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:27:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:27:52 INFO - 'PWD': '/builds/slave/test', 14:27:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:27:52 INFO - 'SHELL': '/bin/bash', 14:27:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KRbGfUd4TQ/Listeners', 14:27:52 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 14:27:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:27:52 INFO - 'USER': 'cltbld', 14:27:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:27:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:27:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:27:52 INFO - 'XPC_FLAGS': '0x0', 14:27:52 INFO - 'XPC_SERVICE_NAME': '0', 14:27:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:27:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:27:52 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 14:27:52 INFO - Cleaning up... 14:27:52 INFO - Return code: 0 14:27:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:27:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:27:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:27:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:27:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:27:52 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 0x10096cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100970768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ada46cdb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10067cd30>, '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 0x100630db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100671be8>, '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.sTzpip1n6I/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.KRbGfUd4TQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 14:27:52 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 14:27:52 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 14:27:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sTzpip1n6I/Render', 14:27:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:27:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:27:52 INFO - 'HOME': '/Users/cltbld', 14:27:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:27:52 INFO - 'LOGNAME': 'cltbld', 14:27:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:27:52 INFO - 'MOZ_NO_REMOTE': '1', 14:27:52 INFO - 'NO_EM_RESTART': '1', 14:27:52 INFO - 'PAGER': '/bin/cat', 14:27:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:27:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:27:52 INFO - 'PWD': '/builds/slave/test', 14:27:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:27:52 INFO - 'SHELL': '/bin/bash', 14:27:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KRbGfUd4TQ/Listeners', 14:27:52 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 14:27:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:27:52 INFO - 'USER': 'cltbld', 14:27:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:27:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:27:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:27:52 INFO - 'XPC_FLAGS': '0x0', 14:27:52 INFO - 'XPC_SERVICE_NAME': '0', 14:27:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:27:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:27:52 INFO - Downloading/unpacking psutil>=3.1.1 14:27:52 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 14:27:52 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 14:27:52 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 14:27:52 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 14:27:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:27:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:27:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:27:54 INFO - Installing collected packages: psutil 14:27:54 INFO - Running setup.py install for psutil 14:27:54 INFO - building 'psutil._psutil_osx' extension 14:27:54 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 14:27:55 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 14:27:55 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 14:27:55 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 14:27:55 INFO - building 'psutil._psutil_posix' extension 14:27:55 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 14:27:55 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:27:55 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:27:55 INFO - ^ 14:27:55 INFO - 1 warning generated. 14:27:55 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 14:27:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:27:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:27:56 INFO - Successfully installed psutil 14:27:56 INFO - Cleaning up... 14:27:56 INFO - Return code: 0 14:27:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:27:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:27:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:27:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:27:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:27:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10096cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100970768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ada46cdb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10067cd30>, '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 0x100630db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100671be8>, '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.sTzpip1n6I/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.KRbGfUd4TQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 14:27:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:27:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:27:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sTzpip1n6I/Render', 14:27:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:27:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:27:56 INFO - 'HOME': '/Users/cltbld', 14:27:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:27:56 INFO - 'LOGNAME': 'cltbld', 14:27:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:27:56 INFO - 'MOZ_NO_REMOTE': '1', 14:27:56 INFO - 'NO_EM_RESTART': '1', 14:27:56 INFO - 'PAGER': '/bin/cat', 14:27:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:27:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:27:56 INFO - 'PWD': '/builds/slave/test', 14:27:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:27:56 INFO - 'SHELL': '/bin/bash', 14:27:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KRbGfUd4TQ/Listeners', 14:27:56 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 14:27:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:27:56 INFO - 'USER': 'cltbld', 14:27:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:27:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:27:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:27:56 INFO - 'XPC_FLAGS': '0x0', 14:27:56 INFO - 'XPC_SERVICE_NAME': '0', 14:27:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:27:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:27:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:27:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:27:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:27:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:27:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:27:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:27:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:27:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:27:57 INFO - Installing collected packages: mozsystemmonitor 14:27:57 INFO - Running setup.py install for mozsystemmonitor 14:27:57 INFO - Successfully installed mozsystemmonitor 14:27:57 INFO - Cleaning up... 14:27:58 INFO - Return code: 0 14:27:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:27:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:27:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:27:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:27:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:27:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10096cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100970768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ada46cdb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10067cd30>, '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 0x100630db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100671be8>, '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.sTzpip1n6I/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.KRbGfUd4TQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 14:27:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:27:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 14:27:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sTzpip1n6I/Render', 14:27:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:27:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:27:58 INFO - 'HOME': '/Users/cltbld', 14:27:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:27:58 INFO - 'LOGNAME': 'cltbld', 14:27:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:27:58 INFO - 'MOZ_NO_REMOTE': '1', 14:27:58 INFO - 'NO_EM_RESTART': '1', 14:27:58 INFO - 'PAGER': '/bin/cat', 14:27:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:27:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:27:58 INFO - 'PWD': '/builds/slave/test', 14:27:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:27:58 INFO - 'SHELL': '/bin/bash', 14:27:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KRbGfUd4TQ/Listeners', 14:27:58 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 14:27:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:27:58 INFO - 'USER': 'cltbld', 14:27:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:27:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:27:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:27:58 INFO - 'XPC_FLAGS': '0x0', 14:27:58 INFO - 'XPC_SERVICE_NAME': '0', 14:27:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:27:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:27:58 INFO - Downloading/unpacking jsonschema==2.5.1 14:27:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:27:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:27:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:27:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:27:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:27:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:27:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:27:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:27:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:27:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:27:59 INFO - Downloading functools32-3.2.3-2.tar.gz 14:27:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:27:59 INFO - warning: no files found matching '*.txt' 14:27:59 INFO - no previously-included directories found matching 'build' 14:27:59 INFO - no previously-included directories found matching 'dist' 14:27:59 INFO - no previously-included directories found matching '.git*' 14:27:59 INFO - Installing collected packages: jsonschema, functools32 14:28:00 INFO - Running setup.py install for functools32 14:28:00 INFO - warning: no files found matching '*.txt' 14:28:00 INFO - no previously-included directories found matching 'build' 14:28:00 INFO - no previously-included directories found matching 'dist' 14:28:00 INFO - no previously-included directories found matching '.git*' 14:28:00 INFO - Successfully installed jsonschema functools32 14:28:00 INFO - Cleaning up... 14:28:00 INFO - Return code: 0 14:28:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:28:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:28:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:28:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:28:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:28:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10096cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100970768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ada46cdb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10067cd30>, '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 0x100630db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100671be8>, '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.sTzpip1n6I/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.KRbGfUd4TQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 14:28:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:28:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 14:28:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sTzpip1n6I/Render', 14:28:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:28:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:28:00 INFO - 'HOME': '/Users/cltbld', 14:28:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:28:00 INFO - 'LOGNAME': 'cltbld', 14:28:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:28:00 INFO - 'MOZ_NO_REMOTE': '1', 14:28:00 INFO - 'NO_EM_RESTART': '1', 14:28:00 INFO - 'PAGER': '/bin/cat', 14:28:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:28:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:28:00 INFO - 'PWD': '/builds/slave/test', 14:28:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:28:00 INFO - 'SHELL': '/bin/bash', 14:28:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KRbGfUd4TQ/Listeners', 14:28:00 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 14:28:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:28:00 INFO - 'USER': 'cltbld', 14:28:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:28:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:28:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:28:00 INFO - 'XPC_FLAGS': '0x0', 14:28:00 INFO - 'XPC_SERVICE_NAME': '0', 14:28:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:28:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:28:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:28:00 INFO - Cleaning up... 14:28:00 INFO - Return code: 0 14:28:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:28:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:28:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:28:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:28:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:28:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10096cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100970768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ada46cdb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10067cd30>, '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 0x100630db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100671be8>, '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.sTzpip1n6I/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.KRbGfUd4TQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 14:28:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:28:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:28:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sTzpip1n6I/Render', 14:28:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:28:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:28:00 INFO - 'HOME': '/Users/cltbld', 14:28:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:28:00 INFO - 'LOGNAME': 'cltbld', 14:28:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:28:00 INFO - 'MOZ_NO_REMOTE': '1', 14:28:00 INFO - 'NO_EM_RESTART': '1', 14:28:00 INFO - 'PAGER': '/bin/cat', 14:28:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:28:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:28:00 INFO - 'PWD': '/builds/slave/test', 14:28:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:28:00 INFO - 'SHELL': '/bin/bash', 14:28:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KRbGfUd4TQ/Listeners', 14:28:00 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 14:28:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:28:00 INFO - 'USER': 'cltbld', 14:28:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:28:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:28:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:28:00 INFO - 'XPC_FLAGS': '0x0', 14:28:00 INFO - 'XPC_SERVICE_NAME': '0', 14:28:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:28:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:28:00 INFO - Downloading/unpacking blobuploader==1.2.4 14:28:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:28:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:28:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:28:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:28:02 INFO - Downloading blobuploader-1.2.4.tar.gz 14:28:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:28:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:28:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:28:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:28:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:28:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:28:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:28:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:28:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:28:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:28:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:28:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:28:03 INFO - Downloading docopt-0.6.1.tar.gz 14:28:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:28:03 INFO - Installing collected packages: blobuploader, requests, docopt 14:28:03 INFO - Running setup.py install for blobuploader 14:28:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:28:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:28:03 INFO - Running setup.py install for requests 14:28:03 INFO - Running setup.py install for docopt 14:28:03 INFO - Successfully installed blobuploader requests docopt 14:28:03 INFO - Cleaning up... 14:28:03 INFO - Return code: 0 14:28:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:28:03 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')]} 14:28:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:28:03 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')]} 14:28:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:28:03 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 0x10096cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100970768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ada46cdb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10067cd30>, '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 0x100630db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100671be8>, '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.sTzpip1n6I/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.KRbGfUd4TQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 14:28:03 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 14:28:03 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 14:28:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sTzpip1n6I/Render', 14:28:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:28:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:28:03 INFO - 'HOME': '/Users/cltbld', 14:28:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:28:03 INFO - 'LOGNAME': 'cltbld', 14:28:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:28:03 INFO - 'MOZ_NO_REMOTE': '1', 14:28:03 INFO - 'NO_EM_RESTART': '1', 14:28:03 INFO - 'PAGER': '/bin/cat', 14:28:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:28:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:28:03 INFO - 'PWD': '/builds/slave/test', 14:28:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:28:03 INFO - 'SHELL': '/bin/bash', 14:28:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KRbGfUd4TQ/Listeners', 14:28:03 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 14:28:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:28:03 INFO - 'USER': 'cltbld', 14:28:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:28:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:28:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:28:03 INFO - 'XPC_FLAGS': '0x0', 14:28:03 INFO - 'XPC_SERVICE_NAME': '0', 14:28:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:28:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:28:04 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-3Vp0vo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:28:04 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-aCC6Qc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:28:04 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-gC7Tje-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:28:04 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-zVMwkY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:28:04 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-EfHisD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:28:04 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-ZEX5nn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:28:04 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-qTQWu8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:28:04 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-FIGizZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:28:05 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-wZwuuM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:28:05 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-YJuxOb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:28:05 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-H_NY9W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:28:05 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-nu6Apo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:28:05 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-vUgvJx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:28:05 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-u_JTp6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:28:05 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-84VqrK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:28:05 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-OP5zNy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:28:05 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-w3DUCN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:28:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:28:05 INFO - Running setup.py install for manifestparser 14:28:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:28:06 INFO - Running setup.py install for mozcrash 14:28:06 INFO - Running setup.py install for mozdebug 14:28:06 INFO - Running setup.py install for mozdevice 14:28:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:28:06 INFO - Running setup.py install for mozfile 14:28:06 INFO - Running setup.py install for mozhttpd 14:28:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:28:06 INFO - Running setup.py install for mozinfo 14:28:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:28:07 INFO - Running setup.py install for mozInstall 14:28:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:28:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:28:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:28:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:28:07 INFO - Running setup.py install for mozleak 14:28:07 INFO - Running setup.py install for mozlog 14:28:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:28:07 INFO - Running setup.py install for moznetwork 14:28:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:28:07 INFO - Running setup.py install for mozprocess 14:28:07 INFO - Running setup.py install for mozprofile 14:28:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:28:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:28:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:28:07 INFO - Running setup.py install for mozrunner 14:28:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:28:08 INFO - Running setup.py install for mozscreenshot 14:28:08 INFO - Running setup.py install for moztest 14:28:08 INFO - Running setup.py install for mozversion 14:28:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:28:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:28:08 INFO - Cleaning up... 14:28:08 INFO - Return code: 0 14:28:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:28:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:28:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:28:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:28:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:28:08 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 0x10096cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100970768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ada46cdb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10067cd30>, '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 0x100630db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100671be8>, '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.sTzpip1n6I/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.KRbGfUd4TQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 14:28:08 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 14:28:08 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 14:28:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sTzpip1n6I/Render', 14:28:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:28:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:28:08 INFO - 'HOME': '/Users/cltbld', 14:28:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:28:08 INFO - 'LOGNAME': 'cltbld', 14:28:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:28:08 INFO - 'MOZ_NO_REMOTE': '1', 14:28:08 INFO - 'NO_EM_RESTART': '1', 14:28:08 INFO - 'PAGER': '/bin/cat', 14:28:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:28:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:28:08 INFO - 'PWD': '/builds/slave/test', 14:28:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:28:08 INFO - 'SHELL': '/bin/bash', 14:28:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KRbGfUd4TQ/Listeners', 14:28:08 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 14:28:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:28:08 INFO - 'USER': 'cltbld', 14:28:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:28:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:28:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:28:08 INFO - 'XPC_FLAGS': '0x0', 14:28:08 INFO - 'XPC_SERVICE_NAME': '0', 14:28:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:28:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:28:08 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-pCxYa1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:28:08 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)) 14:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:28:08 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-vKDgDR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:28:09 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)) 14:28:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:28:09 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-cMOmSY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:28:09 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)) 14:28:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:28:09 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-eY5Zrr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:28:09 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)) 14:28:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:28:09 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-1k31oF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:28:09 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)) 14:28:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:28:09 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-wqPMD7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:28:09 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)) 14:28:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:28:09 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-OY5okz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:28:09 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)) 14:28:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:28:09 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-p9ui8v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:28:09 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)) 14:28:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:28:09 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-UaT_rK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:28:09 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)) 14:28:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:28:09 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-AMtIvd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:28:09 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)) 14:28:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:28:09 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-uJRAz1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:28:09 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)) 14:28:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:28:10 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-nVA_IC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:28:10 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)) 14:28:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:28:10 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-gWdwZ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:28:10 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)) 14:28:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:28:10 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-qIcrTJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:28:10 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)) 14:28:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:28:10 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-o961T_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:28:10 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)) 14:28:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:28:10 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-nnxsZU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:28:10 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)) 14:28:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:28:10 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-i5BVYn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:28:10 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)) 14:28:10 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)) 14:28:10 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)) 14:28:10 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)) 14:28:10 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)) 14:28:10 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)) 14:28:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:28:10 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 14:28:10 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 14:28:10 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 14:28:10 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 14:28:12 INFO - Downloading blessings-1.6.tar.gz 14:28:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:28:12 INFO - Installing collected packages: blessings 14:28:12 INFO - Running setup.py install for blessings 14:28:12 INFO - Successfully installed blessings 14:28:12 INFO - Cleaning up... 14:28:12 INFO - Return code: 0 14:28:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:28:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:28:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:28:12 INFO - Reading from file tmpfile_stdout 14:28:12 INFO - Current package versions: 14:28:12 INFO - blessings == 1.6 14:28:12 INFO - blobuploader == 1.2.4 14:28:12 INFO - docopt == 0.6.1 14:28:12 INFO - functools32 == 3.2.3-2 14:28:12 INFO - jsonschema == 2.5.1 14:28:12 INFO - manifestparser == 1.1 14:28:12 INFO - mozInstall == 1.12 14:28:12 INFO - mozcrash == 1.0 14:28:12 INFO - mozdebug == 0.1 14:28:12 INFO - mozdevice == 0.50 14:28:12 INFO - mozfile == 1.2 14:28:12 INFO - mozhttpd == 0.7 14:28:12 INFO - mozinfo == 0.9 14:28:12 INFO - mozleak == 0.1 14:28:12 INFO - mozlog == 3.4 14:28:12 INFO - moznetwork == 0.27 14:28:12 INFO - mozprocess == 0.25 14:28:12 INFO - mozprofile == 0.28 14:28:12 INFO - mozrunner == 6.13 14:28:12 INFO - mozscreenshot == 0.1 14:28:12 INFO - mozsystemmonitor == 0.3 14:28:12 INFO - moztest == 0.8 14:28:12 INFO - mozversion == 1.4 14:28:12 INFO - psutil == 3.1.1 14:28:12 INFO - requests == 1.2.3 14:28:12 INFO - wsgiref == 0.1.2 14:28:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:28:12 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')]} 14:28:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:28:12 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')]} 14:28:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:28:12 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 0x10096cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100970768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ada46cdb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10067cd30>, '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 0x100630db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100671be8>, '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.sTzpip1n6I/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.KRbGfUd4TQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 14:28:12 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 14:28:12 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 14:28:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sTzpip1n6I/Render', 14:28:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:28:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:28:12 INFO - 'HOME': '/Users/cltbld', 14:28:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:28:12 INFO - 'LOGNAME': 'cltbld', 14:28:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:28:12 INFO - 'MOZ_NO_REMOTE': '1', 14:28:12 INFO - 'NO_EM_RESTART': '1', 14:28:12 INFO - 'PAGER': '/bin/cat', 14:28:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:28:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:28:12 INFO - 'PWD': '/builds/slave/test', 14:28:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:28:12 INFO - 'SHELL': '/bin/bash', 14:28:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KRbGfUd4TQ/Listeners', 14:28:12 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 14:28:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:28:12 INFO - 'USER': 'cltbld', 14:28:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:28:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:28:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:28:12 INFO - 'XPC_FLAGS': '0x0', 14:28:12 INFO - 'XPC_SERVICE_NAME': '0', 14:28:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:28:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:28:12 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)) 14:28:12 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)) 14:28:12 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)) 14:28:12 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)) 14:28:12 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)) 14:28:12 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)) 14:28:12 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)) 14:28:12 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)) 14:28:12 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)) 14:28:12 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)) 14:28:12 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)) 14:28:12 INFO - Cleaning up... 14:28:12 INFO - Return code: 0 14:28:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:28:12 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')]} 14:28:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:28:12 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')]} 14:28:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:28:12 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 0x10096cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100970768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ada46cdb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10067cd30>, '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 0x100630db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100671be8>, '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.sTzpip1n6I/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.KRbGfUd4TQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 14:28:12 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 14:28:12 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 14:28:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sTzpip1n6I/Render', 14:28:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:28:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:28:12 INFO - 'HOME': '/Users/cltbld', 14:28:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:28:12 INFO - 'LOGNAME': 'cltbld', 14:28:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:28:12 INFO - 'MOZ_NO_REMOTE': '1', 14:28:12 INFO - 'NO_EM_RESTART': '1', 14:28:12 INFO - 'PAGER': '/bin/cat', 14:28:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:28:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:28:12 INFO - 'PWD': '/builds/slave/test', 14:28:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:28:12 INFO - 'SHELL': '/bin/bash', 14:28:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KRbGfUd4TQ/Listeners', 14:28:12 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 14:28:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:28:12 INFO - 'USER': 'cltbld', 14:28:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:28:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:28:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:28:12 INFO - 'XPC_FLAGS': '0x0', 14:28:12 INFO - 'XPC_SERVICE_NAME': '0', 14:28:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:28:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:28:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:28:13 INFO - Cleaning up... 14:28:13 INFO - Return code: 0 14:28:13 INFO - Running post-action listener: _resource_record_post_action 14:28:13 INFO - Running post-action listener: _start_resource_monitoring 14:28:13 INFO - Starting resource monitoring. 14:28:13 INFO - [mozharness: 2017-05-21 21:28:13.270685Z] Finished create-virtualenv step (success) 14:28:13 INFO - [mozharness: 2017-05-21 21:28:13.271673Z] Running install step. 14:28:13 INFO - Running pre-action listener: _resource_record_pre_action 14:28:13 INFO - Running main action method: install 14:28:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:28:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:28:13 INFO - Reading from file tmpfile_stdout 14:28:13 INFO - Detecting whether we're running mozinstall >=1.0... 14:28:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:28:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:28:13 INFO - Reading from file tmpfile_stdout 14:28:13 INFO - Output received: 14:28:13 INFO - Usage: mozinstall [options] installer 14:28:13 INFO - Options: 14:28:13 INFO - -h, --help show this help message and exit 14:28:13 INFO - -d DEST, --destination=DEST 14:28:13 INFO - Directory to install application into. [default: 14:28:13 INFO - "/builds/slave/test"] 14:28:13 INFO - --app=APP Application being installed. [default: firefox] 14:28:13 INFO - mkdir: /builds/slave/test/build/application 14:28:13 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'] 14:28:13 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 14:28:35 INFO - Reading from file tmpfile_stdout 14:28:35 INFO - Output received: 14:28:35 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:28:35 INFO - Running post-action listener: _resource_record_post_action 14:28:35 INFO - [mozharness: 2017-05-21 21:28:35.164328Z] Finished install step (success) 14:28:35 INFO - [mozharness: 2017-05-21 21:28:35.164481Z] Running run-tests step. 14:28:35 INFO - Running pre-action listener: _resource_record_pre_action 14:28:35 INFO - Running main action method: run_tests 14:28:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:28:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:28:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:28:35 INFO - 2017-05-21 14:28:35.179 screenresolution[1637:8491] starting screenresolution argv=screenresolution get 14:28:35 INFO - 2017-05-21 14:28:35.181 screenresolution[1637:8491] Display 0: 1600x1200x32@60 14:28:35 INFO - 2017-05-21 14:28:35.187 screenresolution[1638:8493] starting screenresolution argv=screenresolution list 14:28:35 INFO - Available Modes on Display 0 14:28:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:28:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:28:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:28:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:28:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:28:35 INFO - Intel Iris: 14:28:35 INFO - Chipset Model: Intel Iris 14:28:35 INFO - Type: GPU 14:28:35 INFO - Bus: Built-In 14:28:35 INFO - VRAM (Dynamic, Max): 1536 MB 14:28:35 INFO - Vendor: Intel (0x8086) 14:28:35 INFO - Device ID: 0x0a2e 14:28:35 INFO - Revision ID: 0x0009 14:28:35 INFO - Displays: 14:28:35 INFO - Display: 14:28:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:28:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:28:35 INFO - Display Serial Number: 1600x1200 60 14:28:35 INFO - Main Display: Yes 14:28:35 INFO - Mirror: Off 14:28:35 INFO - Online: Yes 14:28:35 INFO - Rotation: Supported 14:28:35 INFO - Return code: 0 14:28:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:28:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:28:35 INFO - Python 2.7.3 14:28:35 INFO - Return code: 0 14:28:35 INFO - grabbing minidump binary from tooltool 14:28:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:28:35 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 0x10067cd30>, '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 0x100630db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100671be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:28:35 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 14:28:35 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 14:28:35 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 14:28:35 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:28:35 INFO - Return code: 0 14:28:35 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:28:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:28:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:28:35 INFO - ENV: RUST_BACKTRACE is now 1 14:28:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:28:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:28:35 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/YOno8HMTT9qu3rlWyT1woA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0030', '--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 14:28:35 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/YOno8HMTT9qu3rlWyT1woA/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0030 --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 14:28:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sTzpip1n6I/Render', 14:28:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:28:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:28:35 INFO - 'HOME': '/Users/cltbld', 14:28:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:28:35 INFO - 'LOGNAME': 'cltbld', 14:28:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:28:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:28:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:28:35 INFO - 'MOZ_NO_REMOTE': '1', 14:28:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:28:35 INFO - 'NO_EM_RESTART': '1', 14:28:35 INFO - 'PAGER': '/bin/cat', 14:28:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:28:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:28:35 INFO - 'PWD': '/builds/slave/test', 14:28:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:28:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:28:35 INFO - 'RUST_BACKTRACE': '1', 14:28:35 INFO - 'SHELL': '/bin/bash', 14:28:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KRbGfUd4TQ/Listeners', 14:28:35 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 14:28:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:28:35 INFO - 'USER': 'cltbld', 14:28:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:28:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:28:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:28:35 INFO - 'XPC_FLAGS': '0x0', 14:28:35 INFO - 'XPC_SERVICE_NAME': '0', 14:28:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:28:35 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/YOno8HMTT9qu3rlWyT1woA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0030', '--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 14:28:35 INFO - mozversion application_buildid: 20170521130843 14:28:35 INFO - mozversion application_changeset: 74566d5345f4cab06c5683d4b620124104801e65 14:28:35 INFO - mozversion application_display_name: Nightly 14:28:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:28:35 INFO - mozversion application_name: Firefox 14:28:35 INFO - mozversion application_remotingname: firefox 14:28:35 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:28:35 INFO - mozversion application_vendor: Mozilla 14:28:35 INFO - mozversion application_version: 55.0a1 14:28:35 INFO - mozversion platform_buildid: 20170521130843 14:28:35 INFO - mozversion platform_changeset: 74566d5345f4cab06c5683d4b620124104801e65 14:28:35 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:28:35 INFO - mozversion platform_version: 55.0a1 14:28:35 INFO - using testdate: 1495402115 14:28:35 INFO - actual date: 1495402115 14:28:35 INFO - starting webserver on 'localhost:49228' 14:28:35 INFO - SUITE-START | Running 2 tests 14:28:35 INFO - TEST-START | dromaeo_css 14:28:35 INFO - operating with platform_type : mac_ 14:28:35 INFO - Initialising browser for dromaeo_css test... 14:28:35 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/tmpHGyGUT/profile http://localhost:49228/getInfo.html) 14:28:36 INFO - PID 1648 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2970: String.localeCompare is deprecated; use String.prototype.localeCompare instead 14:28:38 INFO - PID 1648 | 2017-05-21 14:28:38.359 plugin-container[1650:8668] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c3b, name = 'com.apple.tsm.portname' 14:28:38 INFO - PID 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:28:38 INFO - PID 1648 | 2017-05-21 14:28:38.362 plugin-container[1650:8668] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4e07, name = 'com.apple.CFPasteboardClient' 14:28:38 INFO - PID 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:28:38 INFO - PID 1648 | 2017-05-21 14:28:38.362 plugin-container[1650:8668] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:28:38 INFO - PID 1648 | __metrics Screen width/height:1600/1200 14:28:38 INFO - PID 1648 | colorDepth:24 14:28:38 INFO - PID 1648 | Browser inner width/height: 1024/691 14:28:38 INFO - PID 1648 | __metrics 14:28:38 INFO - PID 1648 | 2017-05-21 14:28:38.998 plugin-container[1652:8731] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a47, name = 'com.apple.tsm.portname' 14:28:38 INFO - PID 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:28:39 INFO - PID 1648 | 2017-05-21 14:28:38.998 plugin-container[1652:8731] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9823, name = 'com.apple.CFPasteboardClient' 14:28:39 INFO - PID 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:28:39 INFO - PID 1648 | 2017-05-21 14:28:38.998 plugin-container[1652:8731] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:28:39 INFO - TEST-INFO | 1648: exit 0 14:28:39 INFO - Browser initialized. 14:28:39 INFO - Running cycle 1/1 for dromaeo_css test... 14:28:39 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/tmpHGyGUT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:28:41 INFO - PID 1653 | 2017-05-21 14:28:41.656 plugin-container[1654:8823] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x973b, name = 'com.apple.tsm.portname' 14:28:41 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:28:41 INFO - PID 1653 | 2017-05-21 14:28:41.656 plugin-container[1654:8823] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4717, name = 'com.apple.CFPasteboardClient' 14:28:41 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:28:41 INFO - PID 1653 | 2017-05-21 14:28:41.656 plugin-container[1654:8823] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:28:42 INFO - PID 1653 | 2017-05-21 14:28:42.151 plugin-container[1655:8869] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x953f, name = 'com.apple.tsm.portname' 14:28:42 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:28:42 INFO - PID 1653 | 2017-05-21 14:28:42.151 plugin-container[1655:8869] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4623, name = 'com.apple.CFPasteboardClient' 14:28:42 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:28:42 INFO - PID 1653 | 2017-05-21 14:28:42.151 plugin-container[1655:8869] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:28:51 INFO - PID 1653 | RSS: Main: 240205824 14:28:51 INFO - PID 1653 | 14:31:55 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49228/tests/dromaeo/cssquery-ext.html) 14:31:56 INFO - PID 1653 | RSS: Main: 260845568 14:31:56 INFO - PID 1653 | 14:34:58 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-ext.html (next: http://localhost:49228/tests/dromaeo/cssquery-jquery.html) 14:34:59 INFO - PID 1653 | RSS: Main: 268402688 14:34:59 INFO - PID 1653 | 14:38:00 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49228/tests/dromaeo/cssquery-mootools.html) 14:38:01 INFO - PID 1653 | RSS: Main: 272760832 14:38:01 INFO - PID 1653 | 14:41:03 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49228/tests/dromaeo/cssquery-prototype.html) 14:41:03 INFO - PID 1653 | RSS: Main: 277794816 14:41:03 INFO - PID 1653 | 14:44:06 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49228/tests/dromaeo/cssquery-yui.html) 14:44:07 INFO - PID 1653 | RSS: Main: 279449600 14:44:07 INFO - PID 1653 | 14:47:08 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-yui.html (next: http://localhost:49228/tests/dromaeo/cssquery-dojo.html) 14:47:09 INFO - PID 1653 | RSS: Main: 280596480 14:47:09 INFO - PID 1653 | 14:47:09 INFO - PID 1653 | __start_tp_report 14:47:09 INFO - PID 1653 | _x_x_mozilla_page_load 14:47:09 INFO - PID 1653 | _x_x_mozilla_page_load_details 14:47:09 INFO - PID 1653 | |i|pagename|runs| 14:47:09 INFO - PID 1653 | |0;dojo.html;3567.857285708572;3619.8914032579014;3637.636236376361;3656.1042544576576;3666.3400587894184;6149.907751383722;6250.749970001195;6298.433141017307;6308.148399966003;6320.715567799449;6213.875722485547;6290.087937249096;6290.150829637997;6321.3994670506345;6411.134496842925;5404.540614047811;5493.807716729915;5503.6147469677135;5524.972375138139;5549.112142057272;5526.806561770339;5614.326280846304;5616.719164041802;5673.12066629671;5673.6879471629045;5401.972990135064;5549.916751248735;5563.66618002917;5586.469285417878;5602.831915042555;5428.4842939920645;5458.6724796511935;5499.450054994509;5517.586181036439;5520.889582208373;5561.193626924073;5569.331680198362;5573.219749235111;5574.526165275956;5584.776608935678;5196.480351964811;5201.375834899798;5528.612997090206;5815.302163740336;6357.141785858942;5521.834344969617;5979.850503737398;6392.904106438361;6885.724571017154;6948.200956889972;6595.142631457928;6831.9658401707675;6978.092847929736;6986.231514533397;6991.196012458581;6463.4506066983895;6506.934930650727;6512.316206798313;6514.543981921267;6532.314107018789;6883.793486195422;7092.326229008236;7159.069320988238;7169.103862017248;7201.063861697946;6607.702653380609;6682.799516014527;6729.49528785343;6769.458443324522;6799.762008329685;6974.546654467444;6985.511014229057;7001.36987671112;7004.859902801915;7008.088948436671;5771.8557036074435;6630.403263706289;6669.699863506154;6706.027625994301;6879.793606191823;6913.930860691429;6916.861662766716;6925;6930.099087118642;6943.6875340609795;5373.704446255415;5424.538914192259;5466.863328416742;5490.505854473036;5506.972465137648;4860.246661767432;4890.266460031024;4939.135651261015;4966.230234313687;4970.030843985388;6378.97936330185;6380.808575742735;6412.7114279857515;6430.003349480838;6436.67816609177;6995.4053905417595;7006.754763583352;7007.964960175166;7017;7042.7887163385185;6314.589551679126;6323.494120470351;6326.936730632635;6397.040443933447;6412.358764123644;6114;6129.450483694044;6151;6161.322254551996;6172.382761723881;7107.857842843217;7154.213036566078;7157.926311053279;7163.462740294394;7171.964140179266;5016.974915125401;6856.862862742817;6959.234484206632;7016.649167541705;7017.157941046903;5920.79277225295;7015.228324884157;7085;7129.037579926851;7146.071010768567;6864.3822056013205;6878.931210687829;6893.172819261721;6896.862062758817;6901.516893817385;6453.967730161507;6749;6844.452443804584;7033.261507541839;7037.437005039482;6626.701798418986;6648.634325112165;6726.159230096238;6748.561343512656;6760.93239067603;6751.93248067513;6768.357006084414;6775.220849602359;6779.15260592426;6833.350831670983;5493.670379777066;6426.550141490051;6467.417932386108;6475.352464753675;6595.373439523238;5087.389513258433;6690.765823196165;6733.52865299444;6755.2231493378895;6793.2867048961125;6556.147700798866;6565.409113179646;6591.176102987127;6613.867722645617;6615.669216539058 14:47:09 INFO - PID 1653 | |1;ext.html;792.5482474989259;814.1166833985126;839.777458973372;1218.8537375514945;1277.080502038532;36657;37591.684291049816;37622.811885940566;37697.05757356064;37756.433653495216;36848.63151368486;37126.70056548021;37139.814300928454;37266;37292.62707372926;6839.760608378708;7627.466077374572;7758.612069396536;8055;8255.174482551756;8214.589270536479;8317.126701696325;8335;8346.791330216733;8350.248477637011;8242.340612750966;8290.41967062306;8308.459950103224;8365.958170209171;8371.204735550113;8289.751307460785;8291.792705182359;8297.00435947687;8331.16688331168;8335.541545215041;8130;8183;8190.918090819075;8196.754097377088;8207.71273005448;36178.63821361779;36226.913192604;36433;36460.81769591162;36483;3470.1498132957586;3505.3164632896596;3507.1232191952013;3508.105433114565;3516.226430185355;30029.399412011637;30087.548686769715;30117;30156.69843301561;30255.69744302573;3077.661457239702;3111.222194451387;3116.40788250234;3118.9220269493217;3127.6559578446354;243510;291641;293836;294612;294675;46990;47177.58467245988;47185.5281447188;47195.5280447198;47237;47953.520464795605;48439;48472.27291590629;48483.27275090876;48842.755786221554;13904.4438222472;14017;14072.437102515785;14076.929615351857;14084.71830563383;11258.099352051817;11320.433978301216;11465.31208127515;11472.942635286938;11497.540098396137;13277.668058298426;13350.866491334962;13375.33123343829;13481.393337299845;13557.118787278796;5990.850228744229;6027.216461860018;6036.3360030396625;6038.128180264132;6066.908996365058;995.2088089968493;1002.8194924913586;1008.7427705935087;1041.9843702344472;1050.753073027838;20543.58912821735;21012.264570740255;22978.19576314821;24042.240660605876;24234;962.9648128262146;1072.2738483768842;1092.9125669946545;1136.238720057547;1143.7083543696303;9211.723648290563;11248.381339026268;12174.878251217551;12205.901468867845;12275.772422757653;292453;292895;293878;294285;294589;51833.2225016625;51851;51923.96152076937;52006.21990670143;52206.738966304925;1487.367868655817;1530.0743050454314;1530.4107918451477;1531.2190782700902;1546.4587394411865;20291.797082028992;22880.542389152455;23443.648345274494;23467.53064938726;23489.942952566835;37412.43881341837;37462.438063428526;37514.81242593769;37531;37535;247742;247765;248341;249043;250988;27429.72570274272;34964;35792.28415431625;35792.82103589465;35824.82087589546;42842.78578607087;43351.78324108486;44752.55247447484;44830.77584612056;44972.10055798931;29986.700132999264;30207.848960755935;30730.539041914835;30796.53805192879;30965.690343097183;30176.5473517893;30194.54708179423;30868.536971945887;31093.8445307772;31173.064808055795;36928.07679808026;37094.88715338544;37122;37271.62728372682;37274.6272537282;26109.608355874298;29713.108606741833;29795.404091918474;30066.24834379158;31045.68954310518;22049.22827701023;24341.634875477237;24427.877860610584;24451;24592 14:47:09 INFO - PID 1653 | |2;jquery.html;405.5802244676759;438.1583836395135;443.32835753832927;449.5661686472553;463.6453113368274;20463.488412789688;21324.786752132473;21682.89158554205;22618.54762904741;22762.203322883703;22138.225162119328;23340.94965726542;23495;23531.764682353172;23673;6066.484348830355;6281.780137695182;6338.461230795365;6340.619562826222;6660.933390666104;6909.205441374231;6992.650367481606;7008.229094799568;7031.1211098612675;7031.437485001187;6832.590044597315;6968.547044442121;7002.229754726976;7006.54457460266;7017.6140312282805;6918.861622767567;6928.965355173192;6954.4436445084275;6999.2650771669005;7015.333543313377;6123.969380153116;6616.636085015322;6814.318568143146;6827.726890924357;6838.14523184602;22207.66688499674;29480.557791633142;29647.70352296471;29655.55516667252;29986;2175.641019231825;2237.8209743220505;2264.569731750962;2277.3737222263844;2286.5084006938587;18540.90729546357;18734.1569629367;19248.711269330972;19377.612447751108;19655.017249137487;1986.255154317131;2315.2475445480286;2356.7996720278798;2375.8930848111877;2382.4996750682376;127408;132956.33521832427;136024;166017;167462;18913.243470261037;25778.09776657808;25797.871010644827;25799.484010319688;26218.73781262201;40979;41237.587624123975;41339.58660413417;41491.1701765963;41492.58507414947;10821.350718956888;10881.347119172875;10974.890251097388;11168.66494005181;11191.048760855418;8526.488410695378;8579.142085791496;8645.3948223625;8759.16787905148;8811.206991370807;11413;11710.473028713728;11735.178537502294;11845.822312665317;11860.466279017292;3953.3081710700512;4570.360149579062;4695.295705644113;4711.175544279806;4755.310479980452;891.9331050171133;908.5457271364318;944.8425678543866;953.8140062687849;955.6273053509137;18432.723509147374;20914.686279705817;21327.893360533097;25103.497930041663;27020.784064717136;991.9256055795844;995.6017592962872;997.4340285751301;1006.6225165562914;1014.771676372825;9476.336656433983;10953.397563134104;10987.505562249558;11038.558457661764;11077.612283570037;564690;565725;566409.1679541576;568142.5928703549;577337;115066.42466787613;118236.63526729589;119092;120663;130826.03760943594;1057.962971296001;1244.7510497900457;1245.7757603631253;1260.2312589320507;1265.1966001589058;19025.42923712291;20320.898395507927;21186.576268474237;21349.25277615276;21374.251901183383;37300.62699372972;44565.777171113936;50247.49752502574;50391;51141.74429127831;68943.31056689369;69195.65402173158;69439.44484441333;69642.30357696358;69659.30340696528;8938.776530586787;8999.37004409685;9009.684661036832;9093.681721139728;9098.226650734498;12442.626721198378;13207.60377188686;13400.06199566021;13766.587002389944;14763.188024658746;10291.552329969245;11985;12235.75528489443;12246.142770006014;12339.383030848601;10617.787644246917;10635.627753028606;10848.728781780519;11511;11894.881051189377;9087.36388452802;10892;11132.719737230322;12356.938215308866;12415.37923103823;8905.243054340453;9010.684626038057;9084.772880678036;9219.585118669542;9251.213646840093;9499.477528735986;11936.940315298716;12021.639350819489;12245.448954796877;12301.815472768094 14:47:09 INFO - PID 1653 | |3;mootools.html;1360.2586590308283;1411.6941828808356;1421.5593166118497;1432.7994080828685;1447.1678784698802;11686.181967262295;11690.707732306697;12201.389930503483;12222.1444498885;12365.814512782315;11958.342291173982;12088.214266072699;12225;12287.139900206988;12333.938330308336;2464.667269918556;2509.3099397665696;2510.573202555561;2512.4472616024445;2512.6733524641772;2452.1907770435714;2496.6005439129744;2497.7502249775057;2503.4492411669403;2513.861737604431;1897.383350411122;2328.1851352026824;2338.9766102339013;2347.084636991575;2348.43637526994;2386.4033991502124;2414.3239892830034;2455.84037037592;2481.689788776403;2482.3049546127204;2058.073866759964;2112.767595564487;2410.614301711718;2426.0780903256828;2459.569575324329;11630.476628551736;11677.007454366389;11725.472353744102;11798.46906889192;11845.703857403634;6258.092576576371;6509;6513.120728701612;6514;6526.510511711593;3167.889123880653;3428.8285585720864;4114.444549985744;4131.5455299916985;4186.372044193365;1240.4046057892172;1248.2822377133184;1266.8416447944007;1268.9365531723377;1287.1890708853457;16522;16648.33406663732;16648.417305394374;16660.75008874868;16706.99999999988;5055.772490237948;5134.897302053938;5271.947280527223;5331.200319952039;5332.706701131475;11774.293542387484;11805.288233206136;11832.17174797756;11832.34922079294;11842.111841611913;6277.811665650038;6286.214223222098;6286.874262514724;6288.937110628926;6351.96824015877;5871.295444546682;5874.794382196688;5908.763649454059;6038.396160383926;6046.969765151146;4296.484421869396;4388.188185185687;4394.857337092379;4413.801378937956;4472.508024117345;1233.5929143382662;1247.5259401427581;1249.5813902342636;1255.6313618156253;1265.1902782219388;761.9619019049026;774.4656187230793;788.6293442082213;790.115071120349;793.7698067560343;6761.797146085625;7001.179693279682;7010.859782804316;7087.858242835115;7089.291070892973;947.1806887042796;959.7600599850037;961.8316794560924;963.2775418436173;967.4533888353058;3169.762267829922;3848.2111167210655;3849.576546579874;3911.589283125231;3961.7821019844187;14569.708605828037;15099.54701358961;15170.469033583993;15220.847791522165;15273.236338183046;15072.849271507364;15081.924590377197;15089.943703940618;15103.39586416553;15114.790816734634;8283.50298982063;8656.78358041054;8793.340499462565;8819.470831749988;8865.556722163996;6640.50196235294;6725.798226053226;6807.251202367636;6815.250322464426;6820.147481564804;13309.733805324033;13408.195508269535;13664.453421863014;13680.65798355049;13695.452181913011;13779.035467517178;13946.372413241226;13975.231362274766;14084.436622535393;14122.43510259578;6930.930690693029;7106.928930710626;7954.84090318202;8426.957865210634;8440.620172092393;8486.19381158789;10637.989391007843;10796.67609971702;10833.241673082808;10933.56265749361;9219.907800921905;9237.676681316121;9323;9326.626934922528;9427.3400861939;8209.548474833942;8658.48049117055;8919.331050171133;9113.407628504392;9116.453012818987;7066.646667666493;8290.336773058261;8318.958405207935;8410.915890841014;8428.410011299151;6049.213602231682;6065.272167339947;6127.387261273837;6157.076438534255;6242.375762423721;6111.938880611315;6188.071789231652;6239.064140378798;6240.7503699851495;6330.208723909512 14:47:09 INFO - PID 1653 | |4;prototype.html;338.97627166098374;398.7746922988511;403.0407629840978;410.39261892399264;413.56369030672636;5092.3889133303965;6299.031610085645;6344.953082741348;6424.8435281649345;6462.838429039265;6072.969635151829;6213.906791398133;6243.906341404882;6259.374062593738;6268.68656567171;3953.9406908896317;4422.911541769163;4548.590626843584;4561.429821272341;4586.44962604488;4143.854965076223;4391.494978077514;4558.635309175258;4564.475085365176;4568.977155114219;4097.959020409788;4537.047220083787;4542.364069030338;4552.635789136878;4561.315802629599;4411.757353345581;4505.459344878603;4508.165989291959;4508.39136716544;4567.177907976563;3191.0586377018835;3334.965670940724;3855.8457661693496;3875.147467557133;3915.11909820291;6086.226539545466;6112.969435152796;6114.449699527064;6114.938850611481;6159.4456498915315;4790.065937142259;5246;5308.044551980642;5312.300063979528;5514.724263786795;5289.629725919187;5822.563307751935;6500.577462464925;6675;6705.524784547391;3946.3093958557424;4218.683598730107;4323.610875021263;4404.471463424377;4404.955950440487;6956.060931774196;7093.609851458168;7108.755967705631;7198.676059577279;7221.602811845294;4434.846939795611;4435.977820110878;4457.509673935864;4473.597376236155;4501.212287849613;3969.4442778011103;4409.404730361424;4552.613027892667;4559.224931761609;4684.718916864999;5287.391949925808;5360.65155764874;5427.728613569306;5432.592555558349;5456.645318054314;5541.612087153861;6782.525223234326;6791.505868708876;6795.063406929002;6819.488538359643;1527.2440142129494;1555.2534783304077;1560.2198900549724;1562.7187106320744;1570.175657779652;956.583886009588;996.7607774134301;1006.33079002464;1010.1867148468517;1010.2776514791959;6314.810555683337;6319.778807741799;6345.841353966095;6350.618962862243;6375.6174629522375;6021.458068773743;6651.769422656824;6671.499637527205;6934.410575101072;7027.710199371534;5016.874578135576;5305.522502974751;6279.529035322278;6332;6366.26787919386;3528.8588456461453;3630.346537623253;3680.245549662313;3680.8159592020293;3686.8063502216137;5758.942410575841;6425.614463132133;7167.964160179165;7171.677274522659;7226.819329516804;5725.656460612377;7058.566324984236;7189.784306470814;7207.135143782675;7238.6018768968215;6019.638821670626;7096.964515177391;7122.6438678066925;7239.4932354734665;7250.60121693312;5614.747336369955;7007.859842803217;7034.894476582753;7035.261297563682;7050.717971281297;6326.588771729823;6403.29563747978;6646.102776125162;6706.759249538851;6889.621070841152;6129.601575897553;6873.5875847449315;7247.710091596277;7274.599897005715;7285.271472852673;3580.9104772380897;3981.40278958147;4317.812601534478;4500.459944806644;4513.142502924434;6771.273325302016;6988.860222795617;7105.111861017373;7115.82210444743;7139.785806425815;5601.831945041656;5602.411746766694;5912.674802885883;6880.621565813728;6884.862302754017;5370.140777475585;6495.577787443801;6755.459563235027;6851.246362899978;6881.483888708267;5274.969136882405;6657;6711.832204194934;6715.328467153246;6745.730170793114;4651.209294420024;5354.518093371616;5745.816589695889;6423.678816059271;6859.416949559343;6586.80239592813;6702.631355275506;6717.227518835396;6721.395074443323;6725.831854203684 14:47:09 INFO - PID 1653 | |5;yui.html;774.2451110167586;790.0519376747902;800.1958032177661;819.9095203379505;833.1793183714041;13884.514042008544;14441.855581444182;15670.686586268268;16282.185890705461;16780.58048548787;14765.852341476582;17825.108744562785;18009.279628814864;18018.549536261566;18627.16177772;959.740869965109;988.8665030220936;1016.11090295991;1021.0606242257104;1024.6106479537766;1027.933184343019;1030.6856408795315;1031.097789434245;1032.2155162076801;1039.1842403713088;1055.5566662001938;1059.7032830807386;1069.8983596558353;1081.999150785524;1086.1745073743973;1060.2737125069314;1063.1176123817213;1071.2394200117888;1076.7308172956762;1078.7788503356794;789.135896193667;789.6051974012994;790.2887401338784;791.1969351108572;793.7182300283432;16672.583185420463;17224.224909879085;17251.65496690059;17368.39210627622;17393.391231306843;16557.503274901774;16659.16704164787;17028.65942681152;17309.826901730947;17310.740338894928;14145.292735363293;14392;14436.350364233635;14455.566333010027;14459.349329280207;3083.830389328586;3480.4779283107737;3497.3529896969144;3567.7324200685052;3568.8572457101686;103793;111318;118834;120312;121165;13357.265350405627;13653;13653.795193072112;13660.385282662304;13697.931510342385;25164.874175629004;25491;25726.228213153634;26006.479870402487;26038;7838.098618658813;7972.641231144612;7975.322097621651;7979.241972012649;8009.519428834288;3897.493325867619;3899.7465164764767;3925.4896863408144;3959.6436320731273;3966.4050392440786;4117.423560701504;4226.513950895686;4256.893577660583;4261;4273.658107351466;876.1919498655698;912.8539433690578;913.6436789652041;915.1534830281962;917.7751450894574;585.6515373352848;631.5957787016313;659.4921910129269;660.3033799341645;663.3664850068174;11235.775284494428;11330.433478326051;12017.33904635236;12109.0918181135;13410.731785364238;573.3607028163651;579.5450571301592;615.8491169663461;621.2296752027454;634.1755717567145;5328.067588172132;5582.8325150245555;5675.886482270332;5709.600327977085;5722.113072473773;99001;100453.49319760209;101873.49063254785;102062;102172.97827021783;70916.93624595636;85776.14223857681;89137.55431222932;89770;90153.54923225474;58979.705101475076;62236.44408889723;73635;80208;82679;9852.852207217042;11258.072901045623;11420.143489238175;11429.314241145557;11539.423028848356;40124.3981340286;40295.597044029186;40591.79704101461;40797;40968;52432.7378363121;53174.67063323448;53246.20130697965;53278;53370.73314633402;14250.358733857209;14654;16129.516114516584;16850.831491685418;17025.48923532296;17624.64750704953;17876.28494860243;18479.53801154982;18615.069246537347;18778;16151.111688857225;16258.918705406397;17163;17248.827511725223;17296.394626188023;13925.442982280596;15699.686006280039;16354.754678680067;16617.3353065876;16950;13884.861151388357;15728.528144155694;16538.173091345627;16639.41762038323;16894.66210675804;10727.141828653534;10906.61826836057;11295.717607059889;11688;11758.88241117612;13006.739865202453;13461.596152115451;13600.455981760619;13824.930875345559;13856.584302470941 14:47:09 INFO - PID 1653 | __end_tp_report 14:47:09 INFO - PID 1653 | __start_cc_report 14:47:09 INFO - PID 1653 | _x_x_mozilla_cycle_collect,336 14:47:09 INFO - PID 1653 | __end_cc_report 14:47:09 INFO - PID 1653 | __startTimestamp1495403229216__endTimestamp 14:47:09 INFO - PID 1653 | 14:47:09 INFO - PID 1653 | ------- Summary: start ------- 14:47:09 INFO - PID 1653 | Number of tests: 6 14:47:09 INFO - PID 1653 | 14:47:09 INFO - PID 1653 | [#0] dojo.html Cycles:165 Average:6269.84 Median:6475.35 stddev:783.41 (12.1%) stddev-sans-first:756.60 14:47:09 INFO - PID 1653 | Values: 3567.9 3619.9 3637.6 3656.1 3666.3 6149.9 6250.7 6298.4 6308.1 6320.7 6213.9 6290.1 6290.2 6321.4 6411.1 5404.5 5493.8 5503.6 5525.0 5549.1 5526.8 5614.3 5616.7 5673.1 5673.7 5402.0 5549.9 5563.7 5586.5 5602.8 5428.5 5458.7 5499.5 5517.6 5520.9 5561.2 5569.3 5573.2 5574.5 5584.8 5196.5 5201.4 5528.6 5815.3 6357.1 5521.8 5979.9 6392.9 6885.7 6948.2 6595.1 6832.0 6978.1 6986.2 6991.2 6463.5 6506.9 6512.3 6514.5 6532.3 6883.8 7092.3 7159.1 7169.1 7201.1 6607.7 6682.8 6729.5 6769.5 6799.8 6974.5 6985.5 7001.4 7004.9 7008.1 5771.9 6630.4 6669.7 6706.0 6879.8 6913.9 6916.9 6925.0 6930.1 6943.7 5373.7 5424.5 5466.9 5490.5 5507.0 4860.2 4890.3 4939.1 4966.2 4970.0 6379.0 6380.8 6412.7 6430.0 6436.7 6995.4 7006.8 7008.0 7017.0 7042.8 6314.6 6323.5 6326.9 6397.0 6412.4 6114.0 6129.5 6151.0 6161.3 6172.4 7107.9 7154.2 7157.9 7163.5 7172.0 5017.0 6856.9 6959.2 7016.6 7017.2 5920.8 7015.2 7085.0 7129.0 7146.1 6864.4 6878.9 6893.2 6896.9 6901.5 6454.0 6749.0 6844.5 7033.3 7037.4 6626.7 6648.6 6726.2 6748.6 6760.9 6751.9 6768.4 6775.2 6779.2 6833.4 5493.7 6426.6 6467.4 6475.4 6595.4 5087.4 6690.8 6733.5 6755.2 6793.3 6556.1 6565.4 6591.2 6613.9 6615.7 14:47:09 INFO - PID 1653 | 14:47:09 INFO - PID 1653 | [#1] ext.html Cycles:180 Average:42656.12 Median:19217.07 stddev:72278.71 (376.1%) stddev-sans-first:72413.12 14:47:09 INFO - PID 1653 | Values: 792.5 814.1 839.8 1218.9 1277.1 36657.0 37591.7 37622.8 37697.1 37756.4 36848.6 37126.7 37139.8 37266.0 37292.6 6839.8 7627.5 7758.6 8055.0 8255.2 8214.6 8317.1 8335.0 8346.8 8350.2 8242.3 8290.4 8308.5 8366.0 8371.2 8289.8 8291.8 8297.0 8331.2 8335.5 8130.0 8183.0 8190.9 8196.8 8207.7 36178.6 36226.9 36433.0 36460.8 36483.0 3470.1 3505.3 3507.1 3508.1 3516.2 30029.4 30087.5 30117.0 30156.7 30255.7 3077.7 3111.2 3116.4 3118.9 3127.7 243510.0 291641.0 293836.0 294612.0 294675.0 46990.0 47177.6 47185.5 47195.5 47237.0 47953.5 48439.0 48472.3 48483.3 48842.8 13904.4 14017.0 14072.4 14076.9 14084.7 11258.1 11320.4 11465.3 11472.9 11497.5 13277.7 13350.9 13375.3 13481.4 13557.1 5990.9 6027.2 6036.3 6038.1 6066.9 995.2 1002.8 1008.7 1042.0 1050.8 20543.6 21012.3 22978.2 24042.2 24234.0 963.0 1072.3 1092.9 1136.2 1143.7 9211.7 11248.4 12174.9 12205.9 12275.8 292453.0 292895.0 293878.0 294285.0 294589.0 51833.2 51851.0 51924.0 52006.2 52206.7 1487.4 1530.1 1530.4 1531.2 1546.5 20291.8 22880.5 23443.6 23467.5 23489.9 37412.4 37462.4 37514.8 37531.0 37535.0 247742.0 247765.0 248341.0 249043.0 250988.0 27429.7 34964.0 35792.3 35792.8 35824.8 42842.8 43351.8 44752.6 44830.8 44972.1 29986.7 30207.8 30730.5 30796.5 30965.7 30176.5 30194.5 30868.5 31093.8 31173.1 36928.1 37094.9 37122.0 37271.6 37274.6 26109.6 29713.1 29795.4 30066.2 31045.7 22049.2 24341.6 24427.9 24451.0 24592.0 14:47:09 INFO - PID 1653 | 14:47:09 INFO - PID 1653 | [#2] jquery.html Cycles:180 Average:36628.89 Median:25787.98 stddev:95300.66 (369.6%) stddev-sans-first:95529.19 14:47:09 INFO - PID 1653 | Values: 405.6 438.2 443.3 449.6 463.6 20463.5 21324.8 21682.9 22618.5 22762.2 22138.2 23340.9 23495.0 23531.8 23673.0 6066.5 6281.8 6338.5 6340.6 6660.9 6909.2 6992.7 7008.2 7031.1 7031.4 6832.6 6968.5 7002.2 7006.5 7017.6 6918.9 6929.0 6954.4 6999.3 7015.3 6124.0 6616.6 6814.3 6827.7 6838.1 22207.7 29480.6 29647.7 29655.6 29986.0 2175.6 2237.8 2264.6 2277.4 2286.5 18540.9 18734.2 19248.7 19377.6 19655.0 1986.3 2315.2 2356.8 2375.9 2382.5 127408.0 132956.3 136024.0 166017.0 167462.0 18913.2 25778.1 25797.9 25799.5 26218.7 40979.0 41237.6 41339.6 41491.2 41492.6 10821.4 10881.3 10974.9 11168.7 11191.0 8526.5 8579.1 8645.4 8759.2 8811.2 11413.0 11710.5 11735.2 11845.8 11860.5 3953.3 4570.4 4695.3 4711.2 4755.3 891.9 908.5 944.8 953.8 955.6 18432.7 20914.7 21327.9 25103.5 27020.8 991.9 995.6 997.4 1006.6 1014.8 9476.3 10953.4 10987.5 11038.6 11077.6 564690.0 565725.0 566409.2 568142.6 577337.0 115066.4 118236.6 119092.0 120663.0 130826.0 1058.0 1244.8 1245.8 1260.2 1265.2 19025.4 20320.9 21186.6 21349.3 21374.3 37300.6 44565.8 50247.5 50391.0 51141.7 68943.3 69195.7 69439.4 69642.3 69659.3 8938.8 8999.4 9009.7 9093.7 9098.2 12442.6 13207.6 13400.1 13766.6 14763.2 10291.6 11985.0 12235.8 12246.1 12339.4 10617.8 10635.6 10848.7 11511.0 11894.9 9087.4 10892.0 11132.7 12356.9 12415.4 8905.2 9010.7 9084.8 9219.6 9251.2 9499.5 11936.9 12021.6 12245.4 12301.8 14:47:09 INFO - PID 1653 | 14:47:09 INFO - PID 1653 | [#3] mootools.html Cycles:180 Average:7076.10 Median:3299.30 stddev:4582.33 (138.9%) stddev-sans-first:4575.05 14:47:09 INFO - PID 1653 | Values: 1360.3 1411.7 1421.6 1432.8 1447.2 11686.2 11690.7 12201.4 12222.1 12365.8 11958.3 12088.2 12225.0 12287.1 12333.9 2464.7 2509.3 2510.6 2512.4 2512.7 2452.2 2496.6 2497.8 2503.4 2513.9 1897.4 2328.2 2339.0 2347.1 2348.4 2386.4 2414.3 2455.8 2481.7 2482.3 2058.1 2112.8 2410.6 2426.1 2459.6 11630.5 11677.0 11725.5 11798.5 11845.7 6258.1 6509.0 6513.1 6514.0 6526.5 3167.9 3428.8 4114.4 4131.5 4186.4 1240.4 1248.3 1266.8 1268.9 1287.2 16522.0 16648.3 16648.4 16660.8 16707.0 5055.8 5134.9 5271.9 5331.2 5332.7 11774.3 11805.3 11832.2 11832.3 11842.1 6277.8 6286.2 6286.9 6288.9 6352.0 5871.3 5874.8 5908.8 6038.4 6047.0 4296.5 4388.2 4394.9 4413.8 4472.5 1233.6 1247.5 1249.6 1255.6 1265.2 762.0 774.5 788.6 790.1 793.8 6761.8 7001.2 7010.9 7087.9 7089.3 947.2 959.8 961.8 963.3 967.5 3169.8 3848.2 3849.6 3911.6 3961.8 14569.7 15099.5 15170.5 15220.8 15273.2 15072.8 15081.9 15089.9 15103.4 15114.8 8283.5 8656.8 8793.3 8819.5 8865.6 6640.5 6725.8 6807.3 6815.3 6820.1 13309.7 13408.2 13664.5 13680.7 13695.5 13779.0 13946.4 13975.2 14084.4 14122.4 6930.9 7106.9 7954.8 8427.0 8440.6 8486.2 10638.0 10796.7 10833.2 10933.6 9219.9 9237.7 9323.0 9326.6 9427.3 8209.5 8658.5 8919.3 9113.4 9116.5 7066.6 8290.3 8319.0 8410.9 8428.4 6049.2 6065.3 6127.4 6157.1 6242.4 6111.9 6188.1 6239.1 6240.8 6330.2 14:47:09 INFO - PID 1653 | 14:47:09 INFO - PID 1653 | [#4] prototype.html Cycles:180 Average:5351.46 Median:6020.55 stddev:1729.69 (28.7%) stddev-sans-first:1693.13 14:47:09 INFO - PID 1653 | Values: 339.0 398.8 403.0 410.4 413.6 5092.4 6299.0 6345.0 6424.8 6462.8 6073.0 6213.9 6243.9 6259.4 6268.7 3953.9 4422.9 4548.6 4561.4 4586.4 4143.9 4391.5 4558.6 4564.5 4569.0 4098.0 4537.0 4542.4 4552.6 4561.3 4411.8 4505.5 4508.2 4508.4 4567.2 3191.1 3335.0 3855.8 3875.1 3915.1 6086.2 6113.0 6114.4 6114.9 6159.4 4790.1 5246.0 5308.0 5312.3 5514.7 5289.6 5822.6 6500.6 6675.0 6705.5 3946.3 4218.7 4323.6 4404.5 4405.0 6956.1 7093.6 7108.8 7198.7 7221.6 4434.8 4436.0 4457.5 4473.6 4501.2 3969.4 4409.4 4552.6 4559.2 4684.7 5287.4 5360.7 5427.7 5432.6 5456.6 5541.6 6782.5 6791.5 6795.1 6819.5 1527.2 1555.3 1560.2 1562.7 1570.2 956.6 996.8 1006.3 1010.2 1010.3 6314.8 6319.8 6345.8 6350.6 6375.6 6021.5 6651.8 6671.5 6934.4 7027.7 5016.9 5305.5 6279.5 6332.0 6366.3 3528.9 3630.3 3680.2 3680.8 3686.8 5758.9 6425.6 7168.0 7171.7 7226.8 5725.7 7058.6 7189.8 7207.1 7238.6 6019.6 7097.0 7122.6 7239.5 7250.6 5614.7 7007.9 7034.9 7035.3 7050.7 6326.6 6403.3 6646.1 6706.8 6889.6 6129.6 6873.6 7247.7 7274.6 7285.3 3580.9 3981.4 4317.8 4500.5 4513.1 6771.3 6988.9 7105.1 7115.8 7139.8 5601.8 5602.4 5912.7 6880.6 6884.9 5370.1 6495.6 6755.5 6851.2 6881.5 5275.0 6657.0 6711.8 6715.3 6745.7 4651.2 5354.5 5745.8 6423.7 6859.4 6586.8 6702.6 6717.2 6721.4 6725.8 14:47:09 INFO - PID 1653 | 14:47:09 INFO - PID 1653 | [#5] yui.html Cycles:180 Average:20860.68 Median:17380.89 stddev:28514.88 (164.1%) stddev-sans-first:28554.99 14:47:09 INFO - PID 1653 | Values: 774.2 790.1 800.2 819.9 833.2 13884.5 14441.9 15670.7 16282.2 16780.6 14765.9 17825.1 18009.3 18018.5 18627.2 959.7 988.9 1016.1 1021.1 1024.6 1027.9 1030.7 1031.1 1032.2 1039.2 1055.6 1059.7 1069.9 1082.0 1086.2 1060.3 1063.1 1071.2 1076.7 1078.8 789.1 789.6 790.3 791.2 793.7 16672.6 17224.2 17251.7 17368.4 17393.4 16557.5 16659.2 17028.7 17309.8 17310.7 14145.3 14392.0 14436.4 14455.6 14459.3 3083.8 3480.5 3497.4 3567.7 3568.9 103793.0 111318.0 118834.0 120312.0 121165.0 13357.3 13653.0 13653.8 13660.4 13697.9 25164.9 25491.0 25726.2 26006.5 26038.0 7838.1 7972.6 7975.3 7979.2 8009.5 3897.5 3899.7 3925.5 3959.6 3966.4 4117.4 4226.5 4256.9 4261.0 4273.7 876.2 912.9 913.6 915.2 917.8 585.7 631.6 659.5 660.3 663.4 11235.8 11330.4 12017.3 12109.1 13410.7 573.4 579.5 615.8 621.2 634.2 5328.1 5582.8 5675.9 5709.6 5722.1 99001.0 100453.5 101873.5 102062.0 102173.0 70916.9 85776.1 89137.6 89770.0 90153.5 58979.7 62236.4 73635.0 80208.0 82679.0 9852.9 11258.1 11420.1 11429.3 11539.4 40124.4 40295.6 40591.8 40797.0 40968.0 52432.7 53174.7 53246.2 53278.0 53370.7 14250.4 14654.0 16129.5 16850.8 17025.5 17624.6 17876.3 18479.5 18615.1 18778.0 16151.1 16258.9 17163.0 17248.8 17296.4 13925.4 15699.7 16354.8 16617.3 16950.0 13884.9 15728.5 16538.2 16639.4 16894.7 10727.1 10906.6 11295.7 11688.0 11758.9 13006.7 13461.6 13600.5 13824.9 13856.6 14:47:09 INFO - PID 1653 | -------- Summary: end -------- 14:47:09 INFO - PID 1653 | 14:47:09 INFO - TEST-INFO | 1653: exit 0 14:47:09 INFO - TEST-OK | dromaeo_css | took 1114064ms 14:47:09 INFO - TEST-START | kraken 14:47:09 INFO - operating with platform_type : mac_ 14:47:09 INFO - Initialising browser for kraken test... 14:47:09 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/tmpcQyTuZ/profile http://localhost:49228/getInfo.html) 14:47:10 INFO - PID 1664 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2970: String.localeCompare is deprecated; use String.prototype.localeCompare instead 14:47:12 INFO - PID 1664 | 2017-05-21 14:47:12.473 plugin-container[1665:11176] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x973f, name = 'com.apple.tsm.portname' 14:47:12 INFO - PID 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:47:12 INFO - PID 1664 | 2017-05-21 14:47:12.478 plugin-container[1665:11176] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 14:47:12 INFO - PID 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:47:12 INFO - PID 1664 | 2017-05-21 14:47:12.478 plugin-container[1665:11176] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:47:12 INFO - PID 1664 | __metrics Screen width/height:1600/1200 14:47:12 INFO - PID 1664 | colorDepth:24 14:47:12 INFO - PID 1664 | Browser inner width/height: 1024/691 14:47:12 INFO - PID 1664 | __metrics 14:47:13 INFO - TEST-INFO | 1664: exit 0 14:47:13 INFO - Browser initialized. 14:47:13 INFO - Running cycle 1/1 for kraken test... 14:47:13 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/tmpcQyTuZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:47:15 INFO - PID 1667 | 2017-05-21 14:47:15.609 plugin-container[1668:11317] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 14:47:15 INFO - PID 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:47:15 INFO - PID 1667 | 2017-05-21 14:47:15.609 plugin-container[1668:11317] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a1b, name = 'com.apple.CFPasteboardClient' 14:47:15 INFO - PID 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:47:15 INFO - PID 1667 | 2017-05-21 14:47:15.609 plugin-container[1668:11317] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:47:16 INFO - PID 1667 | 2017-05-21 14:47:16.374 plugin-container[1669:11364] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9643, name = 'com.apple.tsm.portname' 14:47:16 INFO - PID 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:47:16 INFO - PID 1667 | 2017-05-21 14:47:16.374 plugin-container[1669:11364] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980b, name = 'com.apple.CFPasteboardClient' 14:47:16 INFO - PID 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:47:16 INFO - PID 1667 | 2017-05-21 14:47:16.374 plugin-container[1669:11364] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:47:25 INFO - PID 1667 | RSS: Main: 242245632 14:47:25 INFO - PID 1667 | 14:47:28 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection) 14:47:29 INFO - PID 1667 | RSS: Main: 254361600 14:47:29 INFO - PID 1667 | 14:47:31 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-dft) 14:47:32 INFO - PID 1667 | RSS: Main: 263929856 14:47:32 INFO - PID 1667 | 14:47:35 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-fft) 14:47:36 INFO - PID 1667 | RSS: Main: 265166848 14:47:36 INFO - PID 1667 | 14:47:38 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator) 14:47:38 INFO - PID 1667 | RSS: Main: 267489280 14:47:38 INFO - PID 1667 | 14:47:40 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:47:41 INFO - PID 1667 | RSS: Main: 267669504 14:47:41 INFO - PID 1667 | 14:47:44 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom) 14:47:44 INFO - PID 1667 | RSS: Main: 277966848 14:47:44 INFO - PID 1667 | 14:47:47 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate) 14:47:48 INFO - PID 1667 | RSS: Main: 277348352 14:47:48 INFO - PID 1667 | 14:47:51 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial) 14:47:51 INFO - PID 1667 | RSS: Main: 276676608 14:47:51 INFO - PID 1667 | 14:47:53 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:47:53 INFO - PID 1667 | RSS: Main: 276529152 14:47:53 INFO - PID 1667 | 14:47:55 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:47:56 INFO - PID 1667 | RSS: Main: 276160512 14:47:56 INFO - PID 1667 | 14:47:58 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:47:58 INFO - PID 1667 | RSS: Main: 275988480 14:47:58 INFO - PID 1667 | 14:48:01 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:48:02 INFO - PID 1667 | RSS: Main: 275660800 14:48:02 INFO - PID 1667 | 14:48:05 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:48:06 INFO - PID 1667 | RSS: Main: 275988480 14:48:06 INFO - PID 1667 | 14:48:08 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49228/tests/kraken/driver.html?testName=ai-astar) 14:48:08 INFO - PID 1667 | RSS: Main: 277864448 14:48:08 INFO - PID 1667 | 14:48:08 INFO - PID 1667 | __start_tp_report 14:48:08 INFO - PID 1667 | _x_x_mozilla_page_load 14:48:08 INFO - PID 1667 | _x_x_mozilla_page_load_details 14:48:08 INFO - PID 1667 | |i|pagename|runs| 14:48:08 INFO - PID 1667 | |0;ai-astar;103;115;114;114;114;115;114;114;116;114 14:48:08 INFO - PID 1667 | |1;audio-beat-detection;176;133;114;150;112;114;118;95;150;122 14:48:08 INFO - PID 1667 | |2;audio-dft;197;194;194;205;199;209;216;206;200;195 14:48:08 INFO - PID 1667 | |3;audio-fft;70;57;58;82;59;59;57;57;75;60 14:48:08 INFO - PID 1667 | |4;audio-oscillator;88;69;86;71;69;67;87;69;68;91 14:48:08 INFO - PID 1667 | |5;imaging-gaussian-blur;88;89;89;87;89;89;89;89;89;88 14:48:08 INFO - PID 1667 | |6;imaging-darkroom;96;96;99;95;97;95;95;100;96;96 14:48:08 INFO - PID 1667 | |7;imaging-desaturate;94;95;88;93;95;91;92;89;117;148 14:48:08 INFO - PID 1667 | |8;json-parse-financial;50;52;49;50;51;48;51;52;50;50 14:48:08 INFO - PID 1667 | |9;json-stringify-tinderbox;65;58;65;63;65;59;55;55;54;57 14:48:08 INFO - PID 1667 | |10;stanford-crypto-aes;85;86;82;85;84;85;81;84;81;84 14:48:08 INFO - PID 1667 | |11;stanford-crypto-ccm;155;138;154;132;153;149;159;173;198;137 14:48:08 INFO - PID 1667 | |12;stanford-crypto-pbkdf2;189;185;220;232;186;186;185;177;182;189 14:48:08 INFO - PID 1667 | |13;stanford-crypto-sha256-iterative;67;71;66;70;71;69;75;73;99;67 14:48:08 INFO - PID 1667 | __end_tp_report 14:48:08 INFO - PID 1667 | __start_cc_report 14:48:08 INFO - PID 1667 | _x_x_mozilla_cycle_collect,908 14:48:08 INFO - PID 1667 | __end_cc_report 14:48:08 INFO - PID 1667 | __startTimestamp1495403288306__endTimestamp 14:48:08 INFO - PID 1667 | 14:48:08 INFO - PID 1667 | ------- Summary: start ------- 14:48:08 INFO - PID 1667 | Number of tests: 14 14:48:08 INFO - PID 1667 | 14:48:08 INFO - PID 1667 | [#0] ai-astar Cycles:10 Average:113.30 Median:114.00 stddev:3.68 (3.2%) stddev-sans-first:0.73 14:48:08 INFO - PID 1667 | Values: 103.0 115.0 114.0 114.0 114.0 115.0 114.0 114.0 116.0 114.0 14:48:08 INFO - PID 1667 | 14:48:08 INFO - PID 1667 | [#1] audio-beat-detection Cycles:10 Average:128.40 Median:141.50 stddev:23.96 (16.9%) stddev-sans-first:18.20 14:48:08 INFO - PID 1667 | Values: 176.0 133.0 114.0 150.0 112.0 114.0 118.0 95.0 150.0 122.0 14:48:08 INFO - PID 1667 | 14:48:08 INFO - PID 1667 | [#2] audio-dft Cycles:10 Average:201.50 Median:202.50 stddev:7.32 (3.6%) stddev-sans-first:7.58 14:48:08 INFO - PID 1667 | Values: 197.0 194.0 194.0 205.0 199.0 209.0 216.0 206.0 200.0 195.0 14:48:08 INFO - PID 1667 | 14:48:08 INFO - PID 1667 | [#3] audio-fft Cycles:10 Average:63.40 Median:59.50 stddev:8.98 (15.1%) stddev-sans-first:9.21 14:48:08 INFO - PID 1667 | Values: 70.0 57.0 58.0 82.0 59.0 59.0 57.0 57.0 75.0 60.0 14:48:08 INFO - PID 1667 | 14:48:08 INFO - PID 1667 | [#4] audio-oscillator Cycles:10 Average:76.50 Median:78.50 stddev:10.02 (12.8%) stddev-sans-first:9.73 14:48:08 INFO - PID 1667 | Values: 88.0 69.0 86.0 71.0 69.0 67.0 87.0 69.0 68.0 91.0 14:48:08 INFO - PID 1667 | 14:48:08 INFO - PID 1667 | [#5] imaging-gaussian-blur Cycles:10 Average:88.60 Median:89.00 stddev:0.70 (0.8%) stddev-sans-first:0.71 14:48:08 INFO - PID 1667 | Values: 88.0 89.0 89.0 87.0 89.0 89.0 89.0 89.0 89.0 88.0 14:48:08 INFO - PID 1667 | 14:48:08 INFO - PID 1667 | [#6] imaging-darkroom Cycles:10 Average:96.50 Median:96.00 stddev:1.72 (1.8%) stddev-sans-first:1.81 14:48:08 INFO - PID 1667 | Values: 96.0 96.0 99.0 95.0 97.0 95.0 95.0 100.0 96.0 96.0 14:48:08 INFO - PID 1667 | 14:48:08 INFO - PID 1667 | [#7] imaging-desaturate Cycles:10 Average:100.20 Median:92.50 stddev:18.67 (20.2%) stddev-sans-first:19.67 14:48:08 INFO - PID 1667 | Values: 94.0 95.0 88.0 93.0 95.0 91.0 92.0 89.0 117.0 148.0 14:48:08 INFO - PID 1667 | 14:48:08 INFO - PID 1667 | [#8] json-parse-financial Cycles:10 Average:50.30 Median:50.50 stddev:1.25 (2.5%) stddev-sans-first:1.32 14:48:08 INFO - PID 1667 | Values: 50.0 52.0 49.0 50.0 51.0 48.0 51.0 52.0 50.0 50.0 14:48:08 INFO - PID 1667 | 14:48:08 INFO - PID 1667 | [#9] json-stringify-tinderbox Cycles:10 Average:59.60 Median:61.00 stddev:4.50 (7.4%) stddev-sans-first:4.33 14:48:08 INFO - PID 1667 | Values: 65.0 58.0 65.0 63.0 65.0 59.0 55.0 55.0 54.0 57.0 14:48:08 INFO - PID 1667 | 14:48:08 INFO - PID 1667 | [#10] stanford-crypto-aes Cycles:10 Average:83.70 Median:84.50 stddev:1.77 (2.1%) stddev-sans-first:1.81 14:48:08 INFO - PID 1667 | Values: 85.0 86.0 82.0 85.0 84.0 85.0 81.0 84.0 81.0 84.0 14:48:08 INFO - PID 1667 | 14:48:08 INFO - PID 1667 | [#11] stanford-crypto-ccm Cycles:10 Average:154.80 Median:154.50 stddev:19.36 (12.5%) stddev-sans-first:20.53 14:48:08 INFO - PID 1667 | Values: 155.0 138.0 154.0 132.0 153.0 149.0 159.0 173.0 198.0 137.0 14:48:08 INFO - PID 1667 | 14:48:08 INFO - PID 1667 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:193.10 Median:187.50 stddev:17.90 (9.5%) stddev-sans-first:18.93 14:48:08 INFO - PID 1667 | Values: 189.0 185.0 220.0 232.0 186.0 186.0 185.0 177.0 182.0 189.0 14:48:08 INFO - PID 1667 | 14:48:08 INFO - PID 1667 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.80 Median:71.00 stddev:9.62 (13.6%) stddev-sans-first:9.98 14:48:08 INFO - PID 1667 | Values: 67.0 71.0 66.0 70.0 71.0 69.0 75.0 73.0 99.0 67.0 14:48:08 INFO - PID 1667 | -------- Summary: end -------- 14:48:08 INFO - PID 1667 | 14:48:09 INFO - TEST-INFO | 1667: exit 0 14:48:09 INFO - TEST-OK | kraken | took 59301ms 14:48:09 INFO - SUITE-END | took 1173s 14:48:09 INFO - Completed test suite (00:19:33) 14:48:09 INFO - javascript benchmark 14:48:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7982.756578161183, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3567.857285708572, 3619.8914032579014, 3637.636236376361, 3656.1042544576576, 3666.3400587894184, 6149.907751383722, 6250.749970001195, 6298.433141017307, 6308.148399966003, 6320.715567799449, 6213.875722485547, 6290.087937249096, 6290.150829637997, 6321.3994670506345, 6411.134496842925, 5404.540614047811, 5493.807716729915, 5503.6147469677135, 5524.972375138139, 5549.112142057272, 5526.806561770339, 5614.326280846304, 5616.719164041802, 5673.12066629671, 5673.6879471629045, 5401.972990135064, 5549.916751248735, 5563.66618002917, 5586.469285417878, 5602.831915042555, 5428.4842939920645, 5458.6724796511935, 5499.450054994509, 5517.586181036439, 5520.889582208373, 5561.193626924073, 5569.331680198362, 5573.219749235111, 5574.526165275956, 5584.776608935678, 5196.480351964811, 5201.375834899798, 5528.612997090206, 5815.302163740336, 6357.141785858942, 5521.834344969617, 5979.850503737398, 6392.904106438361, 6885.724571017154, 6948.200956889972, 6595.142631457928, 6831.9658401707675, 6978.092847929736, 6986.231514533397, 6991.196012458581, 6463.4506066983895, 6506.934930650727, 6512.316206798313, 6514.543981921267, 6532.314107018789, 6883.793486195422, 7092.326229008236, 7159.069320988238, 7169.103862017248, 7201.063861697946, 6607.702653380609, 6682.799516014527, 6729.49528785343, 6769.458443324522, 6799.762008329685, 6974.546654467444, 6985.511014229057, 7001.36987671112, 7004.859902801915, 7008.088948436671, 5771.8557036074435, 6630.403263706289, 6669.699863506154, 6706.027625994301, 6879.793606191823, 6913.930860691429, 6916.861662766716, 6925.0, 6930.099087118642, 6943.6875340609795, 5373.704446255415, 5424.538914192259, 5466.863328416742, 5490.505854473036, 5506.972465137648, 4860.246661767432, 4890.266460031024, 4939.135651261015, 4966.230234313687, 4970.030843985388, 6378.97936330185, 6380.808575742735, 6412.7114279857515, 6430.003349480838, 6436.67816609177, 6995.4053905417595, 7006.754763583352, 7007.964960175166, 7017.0, 7042.7887163385185, 6314.589551679126, 6323.494120470351, 6326.936730632635, 6397.040443933447, 6412.358764123644, 6114.0, 6129.450483694044, 6151.0, 6161.322254551996, 6172.382761723881, 7107.857842843217, 7154.213036566078, 7157.926311053279, 7163.462740294394, 7171.964140179266, 5016.974915125401, 6856.862862742817, 6959.234484206632, 7016.649167541705, 7017.157941046903, 5920.79277225295, 7015.228324884157, 7085.0, 7129.037579926851, 7146.071010768567, 6864.3822056013205, 6878.931210687829, 6893.172819261721, 6896.862062758817, 6901.516893817385, 6453.967730161507, 6749.0, 6844.452443804584, 7033.261507541839, 7037.437005039482, 6626.701798418986, 6648.634325112165, 6726.159230096238, 6748.561343512656, 6760.93239067603, 6751.93248067513, 6768.357006084414, 6775.220849602359, 6779.15260592426, 6833.350831670983, 5493.670379777066, 6426.550141490051, 6467.417932386108, 6475.352464753675, 6595.373439523238, 5087.389513258433, 6690.765823196165, 6733.52865299444, 6755.2231493378895, 6793.2867048961125, 6556.147700798866, 6565.409113179646, 6591.176102987127, 6613.867722645617, 6615.669216539058], "value": 6219.013834175042, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [792.5482474989259, 814.1166833985126, 839.777458973372, 1218.8537375514945, 1277.080502038532, 36657.0, 37591.684291049816, 37622.811885940566, 37697.05757356064, 37756.433653495216, 36848.63151368486, 37126.70056548021, 37139.814300928454, 37266.0, 37292.62707372926, 6839.760608378708, 7627.466077374572, 7758.612069396536, 8055.0, 8255.174482551756, 8214.589270536479, 8317.126701696325, 8335.0, 8346.791330216733, 8350.248477637011, 8242.340612750966, 8290.41967062306, 8308.459950103224, 8365.958170209171, 8371.204735550113, 8289.751307460785, 8291.792705182359, 8297.00435947687, 8331.16688331168, 8335.541545215041, 8130.0, 8183.0, 8190.918090819075, 8196.754097377088, 8207.71273005448, 36178.63821361779, 36226.913192604, 36433.0, 36460.81769591162, 36483.0, 3470.1498132957586, 3505.3164632896596, 3507.1232191952013, 3508.105433114565, 3516.226430185355, 30029.399412011637, 30087.548686769715, 30117.0, 30156.69843301561, 30255.69744302573, 3077.661457239702, 3111.222194451387, 3116.40788250234, 3118.9220269493217, 3127.6559578446354, 243510.0, 291641.0, 293836.0, 294612.0, 294675.0, 46990.0, 47177.58467245988, 47185.5281447188, 47195.5280447198, 47237.0, 47953.520464795605, 48439.0, 48472.27291590629, 48483.27275090876, 48842.755786221554, 13904.4438222472, 14017.0, 14072.437102515785, 14076.929615351857, 14084.71830563383, 11258.099352051817, 11320.433978301216, 11465.31208127515, 11472.942635286938, 11497.540098396137, 13277.668058298426, 13350.866491334962, 13375.33123343829, 13481.393337299845, 13557.118787278796, 5990.850228744229, 6027.216461860018, 6036.3360030396625, 6038.128180264132, 6066.908996365058, 995.2088089968493, 1002.8194924913586, 1008.7427705935087, 1041.9843702344472, 1050.753073027838, 20543.58912821735, 21012.264570740255, 22978.19576314821, 24042.240660605876, 24234.0, 962.9648128262146, 1072.2738483768842, 1092.9125669946545, 1136.238720057547, 1143.7083543696303, 9211.723648290563, 11248.381339026268, 12174.878251217551, 12205.901468867845, 12275.772422757653, 292453.0, 292895.0, 293878.0, 294285.0, 294589.0, 51833.2225016625, 51851.0, 51923.96152076937, 52006.21990670143, 52206.738966304925, 1487.367868655817, 1530.0743050454314, 1530.4107918451477, 1531.2190782700902, 1546.4587394411865, 20291.797082028992, 22880.542389152455, 23443.648345274494, 23467.53064938726, 23489.942952566835, 37412.43881341837, 37462.438063428526, 37514.81242593769, 37531.0, 37535.0, 247742.0, 247765.0, 248341.0, 249043.0, 250988.0, 27429.72570274272, 34964.0, 35792.28415431625, 35792.82103589465, 35824.82087589546, 42842.78578607087, 43351.78324108486, 44752.55247447484, 44830.77584612056, 44972.10055798931, 29986.700132999264, 30207.848960755935, 30730.539041914835, 30796.53805192879, 30965.690343097183, 30176.5473517893, 30194.54708179423, 30868.536971945887, 31093.8445307772, 31173.064808055795, 36928.07679808026, 37094.88715338544, 37122.0, 37271.62728372682, 37274.6272537282, 26109.608355874298, 29713.108606741833, 29795.404091918474, 30066.24834379158, 31045.68954310518, 22049.22827701023, 24341.634875477237, 24427.877860610584, 24451.0, 24592.0], "value": 17124.904571941523, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [405.5802244676759, 438.1583836395135, 443.32835753832927, 449.5661686472553, 463.6453113368274, 20463.488412789688, 21324.786752132473, 21682.89158554205, 22618.54762904741, 22762.203322883703, 22138.225162119328, 23340.94965726542, 23495.0, 23531.764682353172, 23673.0, 6066.484348830355, 6281.780137695182, 6338.461230795365, 6340.619562826222, 6660.933390666104, 6909.205441374231, 6992.650367481606, 7008.229094799568, 7031.1211098612675, 7031.437485001187, 6832.590044597315, 6968.547044442121, 7002.229754726976, 7006.54457460266, 7017.6140312282805, 6918.861622767567, 6928.965355173192, 6954.4436445084275, 6999.2650771669005, 7015.333543313377, 6123.969380153116, 6616.636085015322, 6814.318568143146, 6827.726890924357, 6838.14523184602, 22207.66688499674, 29480.557791633142, 29647.70352296471, 29655.55516667252, 29986.0, 2175.641019231825, 2237.8209743220505, 2264.569731750962, 2277.3737222263844, 2286.5084006938587, 18540.90729546357, 18734.1569629367, 19248.711269330972, 19377.612447751108, 19655.017249137487, 1986.255154317131, 2315.2475445480286, 2356.7996720278798, 2375.8930848111877, 2382.4996750682376, 127408.0, 132956.33521832427, 136024.0, 166017.0, 167462.0, 18913.243470261037, 25778.09776657808, 25797.871010644827, 25799.484010319688, 26218.73781262201, 40979.0, 41237.587624123975, 41339.58660413417, 41491.1701765963, 41492.58507414947, 10821.350718956888, 10881.347119172875, 10974.890251097388, 11168.66494005181, 11191.048760855418, 8526.488410695378, 8579.142085791496, 8645.3948223625, 8759.16787905148, 8811.206991370807, 11413.0, 11710.473028713728, 11735.178537502294, 11845.822312665317, 11860.466279017292, 3953.3081710700512, 4570.360149579062, 4695.295705644113, 4711.175544279806, 4755.310479980452, 891.9331050171133, 908.5457271364318, 944.8425678543866, 953.8140062687849, 955.6273053509137, 18432.723509147374, 20914.686279705817, 21327.893360533097, 25103.497930041663, 27020.784064717136, 991.9256055795844, 995.6017592962872, 997.4340285751301, 1006.6225165562914, 1014.771676372825, 9476.336656433983, 10953.397563134104, 10987.505562249558, 11038.558457661764, 11077.612283570037, 564690.0, 565725.0, 566409.1679541576, 568142.5928703549, 577337.0, 115066.42466787613, 118236.63526729589, 119092.0, 120663.0, 130826.03760943594, 1057.962971296001, 1244.7510497900457, 1245.7757603631253, 1260.2312589320507, 1265.1966001589058, 19025.42923712291, 20320.898395507927, 21186.576268474237, 21349.25277615276, 21374.251901183383, 37300.62699372972, 44565.777171113936, 50247.49752502574, 50391.0, 51141.74429127831, 68943.31056689369, 69195.65402173158, 69439.44484441333, 69642.30357696358, 69659.30340696528, 8938.776530586787, 8999.37004409685, 9009.684661036832, 9093.681721139728, 9098.226650734498, 12442.626721198378, 13207.60377188686, 13400.06199566021, 13766.587002389944, 14763.188024658746, 10291.552329969245, 11985.0, 12235.75528489443, 12246.142770006014, 12339.383030848601, 10617.787644246917, 10635.627753028606, 10848.728781780519, 11511.0, 11894.881051189377, 9087.36388452802, 10892.0, 11132.719737230322, 12356.938215308866, 12415.37923103823, 8905.243054340453, 9010.684626038057, 9084.772880678036, 9219.585118669542, 9251.213646840093, 9499.477528735986, 11936.940315298716, 12021.639350819489, 12245.448954796877, 12301.815472768094], "value": 11508.312023254612, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1360.2586590308283, 1411.6941828808356, 1421.5593166118497, 1432.7994080828685, 1447.1678784698802, 11686.181967262295, 11690.707732306697, 12201.389930503483, 12222.1444498885, 12365.814512782315, 11958.342291173982, 12088.214266072699, 12225.0, 12287.139900206988, 12333.938330308336, 2464.667269918556, 2509.3099397665696, 2510.573202555561, 2512.4472616024445, 2512.6733524641772, 2452.1907770435714, 2496.6005439129744, 2497.7502249775057, 2503.4492411669403, 2513.861737604431, 1897.383350411122, 2328.1851352026824, 2338.9766102339013, 2347.084636991575, 2348.43637526994, 2386.4033991502124, 2414.3239892830034, 2455.84037037592, 2481.689788776403, 2482.3049546127204, 2058.073866759964, 2112.767595564487, 2410.614301711718, 2426.0780903256828, 2459.569575324329, 11630.476628551736, 11677.007454366389, 11725.472353744102, 11798.46906889192, 11845.703857403634, 6258.092576576371, 6509.0, 6513.120728701612, 6514.0, 6526.510511711593, 3167.889123880653, 3428.8285585720864, 4114.444549985744, 4131.5455299916985, 4186.372044193365, 1240.4046057892172, 1248.2822377133184, 1266.8416447944007, 1268.9365531723377, 1287.1890708853457, 16522.0, 16648.33406663732, 16648.417305394374, 16660.75008874868, 16706.99999999988, 5055.772490237948, 5134.897302053938, 5271.947280527223, 5331.200319952039, 5332.706701131475, 11774.293542387484, 11805.288233206136, 11832.17174797756, 11832.34922079294, 11842.111841611913, 6277.811665650038, 6286.214223222098, 6286.874262514724, 6288.937110628926, 6351.96824015877, 5871.295444546682, 5874.794382196688, 5908.763649454059, 6038.396160383926, 6046.969765151146, 4296.484421869396, 4388.188185185687, 4394.857337092379, 4413.801378937956, 4472.508024117345, 1233.5929143382662, 1247.5259401427581, 1249.5813902342636, 1255.6313618156253, 1265.1902782219388, 761.9619019049026, 774.4656187230793, 788.6293442082213, 790.115071120349, 793.7698067560343, 6761.797146085625, 7001.179693279682, 7010.859782804316, 7087.858242835115, 7089.291070892973, 947.1806887042796, 959.7600599850037, 961.8316794560924, 963.2775418436173, 967.4533888353058, 3169.762267829922, 3848.2111167210655, 3849.576546579874, 3911.589283125231, 3961.7821019844187, 14569.708605828037, 15099.54701358961, 15170.469033583993, 15220.847791522165, 15273.236338183046, 15072.849271507364, 15081.924590377197, 15089.943703940618, 15103.39586416553, 15114.790816734634, 8283.50298982063, 8656.78358041054, 8793.340499462565, 8819.470831749988, 8865.556722163996, 6640.50196235294, 6725.798226053226, 6807.251202367636, 6815.250322464426, 6820.147481564804, 13309.733805324033, 13408.195508269535, 13664.453421863014, 13680.65798355049, 13695.452181913011, 13779.035467517178, 13946.372413241226, 13975.231362274766, 14084.436622535393, 14122.43510259578, 6930.930690693029, 7106.928930710626, 7954.84090318202, 8426.957865210634, 8440.620172092393, 8486.19381158789, 10637.989391007843, 10796.67609971702, 10833.241673082808, 10933.56265749361, 9219.907800921905, 9237.676681316121, 9323.0, 9326.626934922528, 9427.3400861939, 8209.548474833942, 8658.48049117055, 8919.331050171133, 9113.407628504392, 9116.453012818987, 7066.646667666493, 8290.336773058261, 8318.958405207935, 8410.915890841014, 8428.410011299151, 6049.213602231682, 6065.272167339947, 6127.387261273837, 6157.076438534255, 6242.375762423721, 6111.938880611315, 6188.071789231652, 6239.064140378798, 6240.7503699851495, 6330.208723909512], "value": 5310.885988244253, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.97627166098374, 398.7746922988511, 403.0407629840978, 410.39261892399264, 413.56369030672636, 5092.3889133303965, 6299.031610085645, 6344.953082741348, 6424.8435281649345, 6462.838429039265, 6072.969635151829, 6213.906791398133, 6243.906341404882, 6259.374062593738, 6268.68656567171, 3953.9406908896317, 4422.911541769163, 4548.590626843584, 4561.429821272341, 4586.44962604488, 4143.854965076223, 4391.494978077514, 4558.635309175258, 4564.475085365176, 4568.977155114219, 4097.959020409788, 4537.047220083787, 4542.364069030338, 4552.635789136878, 4561.315802629599, 4411.757353345581, 4505.459344878603, 4508.165989291959, 4508.39136716544, 4567.177907976563, 3191.0586377018835, 3334.965670940724, 3855.8457661693496, 3875.147467557133, 3915.11909820291, 6086.226539545466, 6112.969435152796, 6114.449699527064, 6114.938850611481, 6159.4456498915315, 4790.065937142259, 5246.0, 5308.044551980642, 5312.300063979528, 5514.724263786795, 5289.629725919187, 5822.563307751935, 6500.577462464925, 6675.0, 6705.524784547391, 3946.3093958557424, 4218.683598730107, 4323.610875021263, 4404.471463424377, 4404.955950440487, 6956.060931774196, 7093.609851458168, 7108.755967705631, 7198.676059577279, 7221.602811845294, 4434.846939795611, 4435.977820110878, 4457.509673935864, 4473.597376236155, 4501.212287849613, 3969.4442778011103, 4409.404730361424, 4552.613027892667, 4559.224931761609, 4684.718916864999, 5287.391949925808, 5360.65155764874, 5427.728613569306, 5432.592555558349, 5456.645318054314, 5541.612087153861, 6782.525223234326, 6791.505868708876, 6795.063406929002, 6819.488538359643, 1527.2440142129494, 1555.2534783304077, 1560.2198900549724, 1562.7187106320744, 1570.175657779652, 956.583886009588, 996.7607774134301, 1006.33079002464, 1010.1867148468517, 1010.2776514791959, 6314.810555683337, 6319.778807741799, 6345.841353966095, 6350.618962862243, 6375.6174629522375, 6021.458068773743, 6651.769422656824, 6671.499637527205, 6934.410575101072, 7027.710199371534, 5016.874578135576, 5305.522502974751, 6279.529035322278, 6332.0, 6366.26787919386, 3528.8588456461453, 3630.346537623253, 3680.245549662313, 3680.8159592020293, 3686.8063502216137, 5758.942410575841, 6425.614463132133, 7167.964160179165, 7171.677274522659, 7226.819329516804, 5725.656460612377, 7058.566324984236, 7189.784306470814, 7207.135143782675, 7238.6018768968215, 6019.638821670626, 7096.964515177391, 7122.6438678066925, 7239.4932354734665, 7250.60121693312, 5614.747336369955, 7007.859842803217, 7034.894476582753, 7035.261297563682, 7050.717971281297, 6326.588771729823, 6403.29563747978, 6646.102776125162, 6706.759249538851, 6889.621070841152, 6129.601575897553, 6873.5875847449315, 7247.710091596277, 7274.599897005715, 7285.271472852673, 3580.9104772380897, 3981.40278958147, 4317.812601534478, 4500.459944806644, 4513.142502924434, 6771.273325302016, 6988.860222795617, 7105.111861017373, 7115.82210444743, 7139.785806425815, 5601.831945041656, 5602.411746766694, 5912.674802885883, 6880.621565813728, 6884.862302754017, 5370.140777475585, 6495.577787443801, 6755.459563235027, 6851.246362899978, 6881.483888708267, 5274.969136882405, 6657.0, 6711.832204194934, 6715.328467153246, 6745.730170793114, 4651.209294420024, 5354.518093371616, 5745.816589695889, 6423.678816059271, 6859.416949559343, 6586.80239592813, 6702.631355275506, 6717.227518835396, 6721.395074443323, 6725.831854203684], "value": 4812.772173463604, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [774.2451110167586, 790.0519376747902, 800.1958032177661, 819.9095203379505, 833.1793183714041, 13884.514042008544, 14441.855581444182, 15670.686586268268, 16282.185890705461, 16780.58048548787, 14765.852341476582, 17825.108744562785, 18009.279628814864, 18018.549536261566, 18627.16177772, 959.740869965109, 988.8665030220936, 1016.11090295991, 1021.0606242257104, 1024.6106479537766, 1027.933184343019, 1030.6856408795315, 1031.097789434245, 1032.2155162076801, 1039.1842403713088, 1055.5566662001938, 1059.7032830807386, 1069.8983596558353, 1081.999150785524, 1086.1745073743973, 1060.2737125069314, 1063.1176123817213, 1071.2394200117888, 1076.7308172956762, 1078.7788503356794, 789.135896193667, 789.6051974012994, 790.2887401338784, 791.1969351108572, 793.7182300283432, 16672.583185420463, 17224.224909879085, 17251.65496690059, 17368.39210627622, 17393.391231306843, 16557.503274901774, 16659.16704164787, 17028.65942681152, 17309.826901730947, 17310.740338894928, 14145.292735363293, 14392.0, 14436.350364233635, 14455.566333010027, 14459.349329280207, 3083.830389328586, 3480.4779283107737, 3497.3529896969144, 3567.7324200685052, 3568.8572457101686, 103793.0, 111318.0, 118834.0, 120312.0, 121165.0, 13357.265350405627, 13653.0, 13653.795193072112, 13660.385282662304, 13697.931510342385, 25164.874175629004, 25491.0, 25726.228213153634, 26006.479870402487, 26038.0, 7838.098618658813, 7972.641231144612, 7975.322097621651, 7979.241972012649, 8009.519428834288, 3897.493325867619, 3899.7465164764767, 3925.4896863408144, 3959.6436320731273, 3966.4050392440786, 4117.423560701504, 4226.513950895686, 4256.893577660583, 4261.0, 4273.658107351466, 876.1919498655698, 912.8539433690578, 913.6436789652041, 915.1534830281962, 917.7751450894574, 585.6515373352848, 631.5957787016313, 659.4921910129269, 660.3033799341645, 663.3664850068174, 11235.775284494428, 11330.433478326051, 12017.33904635236, 12109.0918181135, 13410.731785364238, 573.3607028163651, 579.5450571301592, 615.8491169663461, 621.2296752027454, 634.1755717567145, 5328.067588172132, 5582.8325150245555, 5675.886482270332, 5709.600327977085, 5722.113072473773, 99001.0, 100453.49319760209, 101873.49063254785, 102062.0, 102172.97827021783, 70916.93624595636, 85776.14223857681, 89137.55431222932, 89770.0, 90153.54923225474, 58979.705101475076, 62236.44408889723, 73635.0, 80208.0, 82679.0, 9852.852207217042, 11258.072901045623, 11420.143489238175, 11429.314241145557, 11539.423028848356, 40124.3981340286, 40295.597044029186, 40591.79704101461, 40797.0, 40968.0, 52432.7378363121, 53174.67063323448, 53246.20130697965, 53278.0, 53370.73314633402, 14250.358733857209, 14654.0, 16129.516114516584, 16850.831491685418, 17025.48923532296, 17624.64750704953, 17876.28494860243, 18479.53801154982, 18615.069246537347, 18778.0, 16151.111688857225, 16258.918705406397, 17163.0, 17248.827511725223, 17296.394626188023, 13925.442982280596, 15699.686006280039, 16354.754678680067, 16617.3353065876, 16950.0, 13884.861151388357, 15728.528144155694, 16538.173091345627, 16639.41762038323, 16894.66210675804, 10727.141828653534, 10906.61826836057, 11295.717607059889, 11688.0, 11758.88241117612, 13006.739865202453, 13461.596152115451, 13600.455981760619, 13824.930875345559, 13856.584302470941], "value": 8259.668805955698, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1482.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 115.0, 114.0, 114.0, 114.0, 115.0, 114.0, 114.0, 116.0, 114.0], "value": 113.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 133.0, 114.0, 150.0, 112.0, 114.0, 118.0, 95.0, 150.0, 122.0], "value": 128.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 194.0, 194.0, 205.0, 199.0, 209.0, 216.0, 206.0, 200.0, 195.0], "value": 201.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 57.0, 58.0, 82.0, 59.0, 59.0, 57.0, 57.0, 75.0, 60.0], "value": 63.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 69.0, 86.0, 71.0, 69.0, 67.0, 87.0, 69.0, 68.0, 91.0], "value": 76.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 89.0, 87.0, 89.0, 89.0, 89.0, 89.0, 89.0, 88.0], "value": 88.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 99.0, 95.0, 97.0, 95.0, 95.0, 100.0, 96.0, 96.0], "value": 96.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 88.0, 93.0, 95.0, 91.0, 92.0, 89.0, 117.0, 148.0], "value": 100.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 52.0, 49.0, 50.0, 51.0, 48.0, 51.0, 52.0, 50.0, 50.0], "value": 50.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 58.0, 65.0, 63.0, 65.0, 59.0, 55.0, 55.0, 54.0, 57.0], "value": 59.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 82.0, 85.0, 84.0, 85.0, 81.0, 84.0, 81.0, 84.0], "value": 83.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 138.0, 154.0, 132.0, 153.0, 149.0, 159.0, 173.0, 198.0, 137.0], "value": 154.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 185.0, 220.0, 232.0, 186.0, 186.0, 185.0, 177.0, 182.0, 189.0], "value": 193.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 71.0, 66.0, 70.0, 71.0, 69.0, 75.0, 73.0, 99.0, 67.0], "value": 72.8, "unit": "score"}]}]} 14:48:09 INFO - Return code: 0 14:48:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:48:09 INFO - # TBPL SUCCESS # 14:48:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:48:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:48:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:48:09 INFO - 2017-05-21 14:48:09.621 screenresolution[1674:11754] starting screenresolution argv=screenresolution get 14:48:09 INFO - 2017-05-21 14:48:09.623 screenresolution[1674:11754] Display 0: 1600x1200x32@60 14:48:09 INFO - 2017-05-21 14:48:09.629 screenresolution[1675:11757] starting screenresolution argv=screenresolution list 14:48:09 INFO - Available Modes on Display 0 14:48:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:48:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:48:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:48:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:48:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:48:09 INFO - Intel Iris: 14:48:09 INFO - Chipset Model: Intel Iris 14:48:09 INFO - Type: GPU 14:48:09 INFO - Bus: Built-In 14:48:09 INFO - VRAM (Dynamic, Max): 1536 MB 14:48:09 INFO - Vendor: Intel (0x8086) 14:48:09 INFO - Device ID: 0x0a2e 14:48:09 INFO - Revision ID: 0x0009 14:48:09 INFO - Displays: 14:48:09 INFO - Display: 14:48:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:48:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:48:09 INFO - Display Serial Number: 1600x1200 60 14:48:09 INFO - Main Display: Yes 14:48:09 INFO - Mirror: Off 14:48:09 INFO - Online: Yes 14:48:09 INFO - Rotation: Supported 14:48:09 INFO - Return code: 0 14:48:09 INFO - Running post-action listener: _resource_record_post_action 14:48:09 INFO - [mozharness: 2017-05-21 21:48:09.865657Z] Finished run-tests step (success) 14:48:09 INFO - Running post-run listener: _resource_record_post_run 14:48:09 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 35515904; Write bytes: 568802304; Read time: 341; Write time: 1678 14:48:09 INFO - TinderboxPrint: CPU usage
54.2% 14:48:09 INFO - TinderboxPrint: I/O read bytes / time
35,515,904 / 341 14:48:09 INFO - TinderboxPrint: I/O write bytes / time
568,802,304 / 1,678 14:48:09 INFO - TinderboxPrint: CPU idle
2,192.3 (45.8%) 14:48:09 INFO - TinderboxPrint: CPU system
963.1 (20.1%) 14:48:09 INFO - TinderboxPrint: CPU user
1,630.2 (34.1%) 14:48:09 INFO - TinderboxPrint: Swap in / out
276,566,016 / 0 14:48:09 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 227574272; Write bytes: 221849600; Read time: 16844; Write time: 435 14:48:09 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 34524672; Write bytes: 320754688; Read time: 326; Write time: 1179 14:48:10 INFO - Running post-run listener: _upload_blobber_files 14:48:10 INFO - Blob upload gear active. 14:48:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:48:10 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 14:48:10 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'] 14:48:10 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 14:48:10 INFO - (blobuploader) - INFO - Open directory for files ... 14:48:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:48:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:48:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:48:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:48:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:48:11 INFO - (blobuploader) - INFO - Done attempting. 14:48:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:48:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:48:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:48:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:48:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:48:13 INFO - (blobuploader) - INFO - Done attempting. 14:48:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:48:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:48:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:48:14 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:48:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:48:14 INFO - (blobuploader) - INFO - Done attempting. 14:48:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:48:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:48:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:48:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:48:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:48:15 INFO - (blobuploader) - INFO - Done attempting. 14:48:15 INFO - (blobuploader) - INFO - Iteration through files over. 14:48:15 INFO - Return code: 0 14:48:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:48:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:48:15 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1651b4e4b6a5d04479c8c7f137909a682b139a0a838c1998a463e326cde9535160991c5ef6fe40b8b5c3d99d9a72289381e99c200e9bf790aa66de0c14977e9e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b337466e5087c608f339a36d6fe3f1e43090f71ed4d1726110b262698fbe585ec311cab2473430f3586a27a7e4f7491cc7dc27bf86d568fb168ec49abcf4aebd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dd486154a99c2404f9399655c893601bd687d0dafa43111e6a72fdec3ad49e8431302606427ad78da7cfb92464bd2f4d01a0feb5af3c5c455f91a6d9efd530de", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/69aedab60533d5821164da960b4410469784ea6395acf4b9c2bc837da54a20523f20438ab7af3277c4f7d7aac231d70feacac558cea33d0fa43cb39dff7f55ef"} 14:48:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:48:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:48:15 INFO - Contents: 14:48:15 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1651b4e4b6a5d04479c8c7f137909a682b139a0a838c1998a463e326cde9535160991c5ef6fe40b8b5c3d99d9a72289381e99c200e9bf790aa66de0c14977e9e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b337466e5087c608f339a36d6fe3f1e43090f71ed4d1726110b262698fbe585ec311cab2473430f3586a27a7e4f7491cc7dc27bf86d568fb168ec49abcf4aebd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dd486154a99c2404f9399655c893601bd687d0dafa43111e6a72fdec3ad49e8431302606427ad78da7cfb92464bd2f4d01a0feb5af3c5c455f91a6d9efd530de", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/69aedab60533d5821164da960b4410469784ea6395acf4b9c2bc837da54a20523f20438ab7af3277c4f7d7aac231d70feacac558cea33d0fa43cb39dff7f55ef"} 14:48:15 INFO - Running post-run listener: copy_logs_to_upload_dir 14:48:15 INFO - Copying logs to upload dir... 14:48:15 INFO - mkdir: /builds/slave/test/build/upload/logs 14:48:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.591750 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2017-05-21 14:48:15.938645) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-21 14:48:15.942621) ========= 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.sTzpip1n6I/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.KRbGfUd4TQ/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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/1651b4e4b6a5d04479c8c7f137909a682b139a0a838c1998a463e326cde9535160991c5ef6fe40b8b5c3d99d9a72289381e99c200e9bf790aa66de0c14977e9e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b337466e5087c608f339a36d6fe3f1e43090f71ed4d1726110b262698fbe585ec311cab2473430f3586a27a7e4f7491cc7dc27bf86d568fb168ec49abcf4aebd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dd486154a99c2404f9399655c893601bd687d0dafa43111e6a72fdec3ad49e8431302606427ad78da7cfb92464bd2f4d01a0feb5af3c5c455f91a6d9efd530de", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/69aedab60533d5821164da960b4410469784ea6395acf4b9c2bc837da54a20523f20438ab7af3277c4f7d7aac231d70feacac558cea33d0fa43cb39dff7f55ef"} build_url:https://queue.taskcluster.net/v1/task/YOno8HMTT9qu3rlWyT1woA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011158 build_url: 'https://queue.taskcluster.net/v1/task/YOno8HMTT9qu3rlWyT1woA/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/1651b4e4b6a5d04479c8c7f137909a682b139a0a838c1998a463e326cde9535160991c5ef6fe40b8b5c3d99d9a72289381e99c200e9bf790aa66de0c14977e9e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b337466e5087c608f339a36d6fe3f1e43090f71ed4d1726110b262698fbe585ec311cab2473430f3586a27a7e4f7491cc7dc27bf86d568fb168ec49abcf4aebd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dd486154a99c2404f9399655c893601bd687d0dafa43111e6a72fdec3ad49e8431302606427ad78da7cfb92464bd2f4d01a0feb5af3c5c455f91a6d9efd530de", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/69aedab60533d5821164da960b4410469784ea6395acf4b9c2bc837da54a20523f20438ab7af3277c4f7d7aac231d70feacac558cea33d0fa43cb39dff7f55ef"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-21 14:48:15.979064) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-21 14:48:15.979435) ========= 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.sTzpip1n6I/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.KRbGfUd4TQ/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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.005277 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-21 14:48:16.029371) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-21 14:48:16.029690) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-21 14:48:16.030017) ========= ========= Total master_lag: 0.17 =========