builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0287 starttime: 1497441002.35 results: success (0) revision: ad3f1138ce6f199408ad58d65c7476636e924909 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-14 04:50:02.345788) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-14 04:50:02.346367) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-14 04:50:02.346693) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-14 04:50:02.370394) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 04:50:02.370727) ========= 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.RSevgheyQy/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.fmVI0oIJAO/Listeners TMPDIR=/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-14 04:50:02-- 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% 36.3M=0s 2017-06-14 04:50:02 (36.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.272827 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 04:50:02.691182) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 04:50:02.691541) ========= 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.RSevgheyQy/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.fmVI0oIJAO/Listeners TMPDIR=/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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.021847 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 04:50:02.743716) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-14 04:50:02.744604) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ad3f1138ce6f199408ad58d65c7476636e924909 --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 ad3f1138ce6f199408ad58d65c7476636e924909 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RSevgheyQy/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.fmVI0oIJAO/Listeners TMPDIR=/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-14 04:50:02,822 truncating revision to first 12 chars 2017-06-14 04:50:02,822 Setting DEBUG logging. 2017-06-14 04:50:02,822 attempt 1/10 2017-06-14 04:50:02,822 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ad3f1138ce6f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-14 04:50:05,073 unpacking tar archive at: mozilla-central-ad3f1138ce6f/testing/mozharness/ program finished with exit code 0 elapsedTime=2.508609 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-14 04:50:05.281906) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-14 04:50:05.282715) ========= script_repo_revision: ad3f1138ce6f199408ad58d65c7476636e924909 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-14 04:50:05.283866) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-14 04:50:05.284569) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-14 04:50:05.305319) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 16 secs) (at 2017-06-14 04:50:05.306197) ========= /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.RSevgheyQy/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.fmVI0oIJAO/Listeners TMPDIR=/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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 04:50:05 INFO - MultiFileLogger online at 20170614 04:50:05 in /builds/slave/test 04:50:05 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 04:50:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:50:05 INFO - {'append_to_log': False, 04:50:05 INFO - 'base_work_dir': '/builds/slave/test', 04:50:05 INFO - 'blob_upload_branch': 'Firefox', 04:50:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:50:05 INFO - 'branch': 'Firefox', 04:50:05 INFO - 'buildbot_json_path': 'buildprops.json', 04:50:05 INFO - 'config_files': ('talos/mac_config.py',), 04:50:05 INFO - 'default_actions': ('clobber', 04:50:05 INFO - 'read-buildbot-config', 04:50:05 INFO - 'download-and-extract', 04:50:05 INFO - 'populate-webroot', 04:50:05 INFO - 'create-virtualenv', 04:50:05 INFO - 'install', 04:50:05 INFO - 'setup-mitmproxy', 04:50:05 INFO - 'run-tests'), 04:50:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:50:05 INFO - 'download_minidump_stackwalk': True, 04:50:05 INFO - 'download_symbols': 'ondemand', 04:50:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:50:05 INFO - 'tooltool.py': '/tools/tooltool.py', 04:50:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:50:05 INFO - '/tools/misc-python/virtualenv.py')}, 04:50:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:50:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:50:05 INFO - 'gecko_profile': False, 04:50:05 INFO - 'gecko_profile_interval': 0, 04:50:05 INFO - 'installer_path': 'installer.exe', 04:50:05 INFO - 'log_level': 'info', 04:50:05 INFO - 'log_name': 'talos', 04:50:05 INFO - 'log_to_console': True, 04:50:05 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:50:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:50:05 INFO - 'opt_config_files': (), 04:50:05 INFO - 'pip_index': False, 04:50:05 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:50:05 INFO - 'cmd': ('bash', 04:50:05 INFO - '-c', 04:50:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:50:05 INFO - 'enabled': True, 04:50:05 INFO - 'halt_on_failure': False, 04:50:05 INFO - 'name': 'check_screen_resolution'},), 04:50:05 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:50:05 INFO - 'cmd': ('bash', 04:50:05 INFO - '-c', 04:50:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:50:05 INFO - 'enabled': True, 04:50:05 INFO - 'halt_on_failure': False, 04:50:05 INFO - 'name': 'check_screen_resolution'},), 04:50:05 INFO - 'run_cmd_checks_enabled': True, 04:50:05 INFO - 'suite': 'dromaeojs-e10s', 04:50:05 INFO - 'system_bits': '32', 04:50:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:50:05 INFO - 'title': 't-yosemite-r7-0287', 04:50:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:50:05 INFO - 'use_talos_json': True, 04:50:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:50:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:50:05 INFO - 'work_dir': 'build'} 04:50:05 INFO - [mozharness: 2017-06-14 11:50:05.509020Z] Running clobber step. 04:50:05 INFO - Running pre-action listener: _resource_record_pre_action 04:50:05 INFO - Running main action method: clobber 04:50:05 INFO - rmtree: /builds/slave/test/build 04:50:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:50:07 INFO - Running post-action listener: _resource_record_post_action 04:50:07 INFO - [mozharness: 2017-06-14 11:50:07.794446Z] Finished clobber step (success) 04:50:07 INFO - [mozharness: 2017-06-14 11:50:07.794663Z] Running read-buildbot-config step. 04:50:07 INFO - Running pre-action listener: _resource_record_pre_action 04:50:07 INFO - Running main action method: read_buildbot_config 04:50:07 INFO - Using buildbot properties: 04:50:07 INFO - { 04:50:07 INFO - "project": "", 04:50:07 INFO - "product": "firefox", 04:50:07 INFO - "who": "cbook@mozilla.com", 04:50:07 INFO - "installer_path": "public/build/firefox-56.0a1.en-US.mac.dmg", 04:50:07 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 04:50:07 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 04:50:07 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 04:50:07 INFO - "stage_platform": "macosx64", 04:50:07 INFO - "basedir": "/builds/slave/test", 04:50:07 INFO - "buildnumber": 25, 04:50:07 INFO - "platform": "yosemite_r7", 04:50:07 INFO - "parent_task_id": "MvKG-EcGSX-BAyzK5trNBg", 04:50:07 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 04:50:07 INFO - "slavebuilddir": "test", 04:50:07 INFO - "taskId": "RQQDogS-RzSXI0tOWf7I4w", 04:50:07 INFO - "branch": "mozilla-central", 04:50:07 INFO - "script_repo_revision": "production", 04:50:07 INFO - "revision": "ad3f1138ce6f199408ad58d65c7476636e924909", 04:50:07 INFO - "slavename": "t-yosemite-r7-0287", 04:50:07 INFO - "repo_path": "mozilla-central" 04:50:07 INFO - } 04:50:07 INFO - Finding installer, test and symbols from parent task. 04:50:07 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RQQDogS-RzSXI0tOWf7I4w'}, attempt #1 04:50:08 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RQQDogS-RzSXI0tOWf7I4w'}, attempt #1 04:50:09 INFO - Task dependencies: DIAt7cUbTM2HHyyffgMelw MvKG-EcGSX-BAyzK5trNBg 04:50:09 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MvKG-EcGSX-BAyzK5trNBg'}, attempt #1 04:50:09 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/MvKG-EcGSX-BAyzK5trNBg/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 04:50:09 INFO - Set test_url: https://queue.taskcluster.net/v1/task/MvKG-EcGSX-BAyzK5trNBg/artifacts/public/build/test_packages.json 04:50:09 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/MvKG-EcGSX-BAyzK5trNBg/artifacts/public/build/target.crashreporter-symbols.zip 04:50:09 INFO - Running post-action listener: _resource_record_post_action 04:50:09 INFO - [mozharness: 2017-06-14 11:50:09.497140Z] Finished read-buildbot-config step (success) 04:50:09 INFO - [mozharness: 2017-06-14 11:50:09.497251Z] Running download-and-extract step. 04:50:09 INFO - Running pre-action listener: _resource_record_pre_action 04:50:09 INFO - Running main action method: download_and_extract 04:50:09 INFO - mkdir: /builds/slave/test/build/tests 04:50:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:50:09 INFO - trying https://queue.taskcluster.net/v1/task/MvKG-EcGSX-BAyzK5trNBg/artifacts/public/build/firefox-56.0a1.en-US.mac.test_packages.json 04:50:09 INFO - Downloading https://queue.taskcluster.net/v1/task/MvKG-EcGSX-BAyzK5trNBg/artifacts/public/build/firefox-56.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-56.0a1.en-US.mac.test_packages.json 04:50:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MvKG-EcGSX-BAyzK5trNBg/artifacts/public/build/firefox-56.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-56.0a1.en-US.mac.test_packages.json'}, attempt #1 04:50:10 INFO - Downloaded 1328 bytes. 04:50:10 INFO - Reading from file /builds/slave/test/build/firefox-56.0a1.en-US.mac.test_packages.json 04:50:10 INFO - Using the following test package requirements: 04:50:10 INFO - {u'awsy': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 04:50:10 INFO - u'firefox-56.0a1.en-US.mac.awsy.tests.zip'], 04:50:10 INFO - u'common': [u'firefox-56.0a1.en-US.mac.common.tests.zip'], 04:50:10 INFO - u'cppunittest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 04:50:10 INFO - u'firefox-56.0a1.en-US.mac.cppunittest.tests.zip'], 04:50:10 INFO - u'gtest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 04:50:10 INFO - u'firefox-56.0a1.en-US.mac.gtest.tests.zip'], 04:50:10 INFO - u'jittest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 04:50:10 INFO - u'jsshell-mac.zip'], 04:50:10 INFO - u'mochitest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 04:50:10 INFO - u'firefox-56.0a1.en-US.mac.mochitest.tests.zip'], 04:50:10 INFO - u'mozbase': [u'firefox-56.0a1.en-US.mac.common.tests.zip'], 04:50:10 INFO - u'reftest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 04:50:10 INFO - u'firefox-56.0a1.en-US.mac.reftest.tests.zip'], 04:50:10 INFO - u'talos': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 04:50:10 INFO - u'firefox-56.0a1.en-US.mac.talos.tests.zip'], 04:50:10 INFO - u'web-platform': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 04:50:10 INFO - u'firefox-56.0a1.en-US.mac.web-platform.tests.tar.gz'], 04:50:10 INFO - u'xpcshell': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 04:50:10 INFO - u'firefox-56.0a1.en-US.mac.xpcshell.tests.zip']} 04:50:10 INFO - Downloading packages: [u'firefox-56.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:50:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MvKG-EcGSX-BAyzK5trNBg/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip 04:50:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MvKG-EcGSX-BAyzK5trNBg/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:50:10 INFO - Fetch https://queue.taskcluster.net/v1/task/MvKG-EcGSX-BAyzK5trNBg/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip into memory 04:50:12 INFO - Content-Length response header: 59590919 04:50:12 INFO - Bytes received: 59590919 04:50:30 INFO - Downloading packages: [u'firefox-56.0a1.en-US.mac.common.tests.zip', u'firefox-56.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:50:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MvKG-EcGSX-BAyzK5trNBg/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip 04:50:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MvKG-EcGSX-BAyzK5trNBg/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:50:30 INFO - Fetch https://queue.taskcluster.net/v1/task/MvKG-EcGSX-BAyzK5trNBg/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip into memory 04:50:32 INFO - Content-Length response header: 59590919 04:50:32 INFO - Bytes received: 59590919 04:50:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MvKG-EcGSX-BAyzK5trNBg/artifacts/public/build/firefox-56.0a1.en-US.mac.talos.tests.zip 04:50:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MvKG-EcGSX-BAyzK5trNBg/artifacts/public/build/firefox-56.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:50:49 INFO - Fetch https://queue.taskcluster.net/v1/task/MvKG-EcGSX-BAyzK5trNBg/artifacts/public/build/firefox-56.0a1.en-US.mac.talos.tests.zip into memory 04:50:50 INFO - Content-Length response header: 14450022 04:50:50 INFO - Bytes received: 14450022 04:50:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:50:50 INFO - trying https://queue.taskcluster.net/v1/task/MvKG-EcGSX-BAyzK5trNBg/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 04:50:50 INFO - Downloading https://queue.taskcluster.net/v1/task/MvKG-EcGSX-BAyzK5trNBg/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg 04:50:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MvKG-EcGSX-BAyzK5trNBg/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg'}, attempt #1 04:50:52 INFO - Downloaded 69623727 bytes. 04:50:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MvKG-EcGSX-BAyzK5trNBg/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 04:50:52 INFO - mkdir: /builds/slave/test/properties 04:50:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:50:52 INFO - Writing to file /builds/slave/test/properties/build_url 04:50:52 INFO - Contents: 04:50:52 INFO - build_url:https://queue.taskcluster.net/v1/task/MvKG-EcGSX-BAyzK5trNBg/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 04:50:52 INFO - Running post-action listener: _resource_record_post_action 04:50:52 INFO - Running post-action listener: set_extra_try_arguments 04:50:52 INFO - [mozharness: 2017-06-14 11:50:52.573621Z] Finished download-and-extract step (success) 04:50:52 INFO - [mozharness: 2017-06-14 11:50:52.573752Z] Running populate-webroot step. 04:50:52 INFO - Running pre-action listener: _resource_record_pre_action 04:50:52 INFO - Running main action method: populate_webroot 04:50:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:50:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:50:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:50:52 INFO - '--tppagecycles', 04:50:52 INFO - '7'], 04:50:52 INFO - 'tests': ['tsvgm']}}, 04:50:52 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:50:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:50:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:50:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:50:52 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:50:52 INFO - 'talos_options': ['--disable-e10s'], 04:50:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:50:52 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:50:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:50:52 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:50:52 INFO - 'talos_options': ['--disable-e10s'], 04:50:52 INFO - 'tests': ['damp', 'tps']}, 04:50:52 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:50:52 INFO - 'tests': ['damp', 'tps']}, 04:50:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:50:52 INFO - 'tests': ['dromaeo_dom']}, 04:50:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:50:52 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:50:52 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 04:50:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 04:50:52 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 04:50:52 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 04:50:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:50:52 INFO - 'tests': ['a11yr', 04:50:52 INFO - 'ts_paint', 04:50:52 INFO - 'tpaint', 04:50:52 INFO - 'sessionrestore', 04:50:52 INFO - 'sessionrestore_no_auto_restore', 04:50:52 INFO - 'tabpaint']}, 04:50:52 INFO - 'other-e10s': {'tests': ['a11yr', 04:50:52 INFO - 'ts_paint', 04:50:52 INFO - 'tpaint', 04:50:52 INFO - 'sessionrestore', 04:50:52 INFO - 'sessionrestore_no_auto_restore', 04:50:52 INFO - 'tabpaint']}, 04:50:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:50:52 INFO - 'ts_paint', 04:50:52 INFO - 'tpaint', 04:50:52 INFO - 'sessionrestore', 04:50:52 INFO - 'sessionrestore_no_auto_restore', 04:50:52 INFO - 'tabpaint']}, 04:50:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:50:52 INFO - 'ts_paint', 04:50:52 INFO - 'tpaint', 04:50:52 INFO - 'sessionrestore', 04:50:52 INFO - 'sessionrestore_no_auto_restore', 04:50:52 INFO - 'tabpaint']}, 04:50:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:50:52 INFO - 'tests': ['a11yr', 04:50:52 INFO - 'ts_paint', 04:50:52 INFO - 'tpaint', 04:50:52 INFO - 'sessionrestore', 04:50:52 INFO - 'sessionrestore_no_auto_restore', 04:50:52 INFO - 'tabpaint']}, 04:50:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:50:52 INFO - 'tests': ['a11yr', 04:50:52 INFO - 'ts_paint', 04:50:52 INFO - 'tpaint', 04:50:52 INFO - 'sessionrestore', 04:50:52 INFO - 'sessionrestore_no_auto_restore', 04:50:52 INFO - 'tabpaint']}, 04:50:52 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 04:50:52 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 04:50:52 INFO - 'bloom_basic_ref']}, 04:50:52 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:50:52 INFO - 'talos_options': ['--mitmproxy', 04:50:52 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:50:52 INFO - '--firstNonBlankPaint'], 04:50:52 INFO - 'tests': ['quantum_pageload_google', 04:50:52 INFO - 'quantum_pageload_youtube', 04:50:52 INFO - 'quantum_pageload_amazon', 04:50:52 INFO - 'quantum_pageload_facebook']}, 04:50:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:50:52 INFO - 'tests': ['tsvgx', 04:50:52 INFO - 'tsvgr_opacity', 04:50:52 INFO - 'tart', 04:50:52 INFO - 'tscrollx', 04:50:52 INFO - 'cart', 04:50:52 INFO - 'tsvg_static']}, 04:50:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:50:52 INFO - 'tsvgr_opacity', 04:50:52 INFO - 'tart', 04:50:52 INFO - 'tscrollx', 04:50:52 INFO - 'cart', 04:50:52 INFO - 'tsvg_static']}, 04:50:52 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:50:52 INFO - 'talos_options': ['--disable-e10s'], 04:50:52 INFO - 'tests': ['tp5o']}, 04:50:52 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:50:52 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:50:52 INFO - 'talos_options': ['--disable-e10s', 04:50:52 INFO - '--xperf_path', 04:50:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:50:52 INFO - 'tests': ['tp5n']}, 04:50:52 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:50:52 INFO - 'talos_options': ['--xperf_path', 04:50:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:50:52 INFO - 'tests': ['tp5n']}}, 04:50:52 INFO - 'talos.zip': {'path': '', 04:50:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:50:52 INFO - Running post-action listener: _resource_record_post_action 04:50:52 INFO - [mozharness: 2017-06-14 11:50:52.595438Z] Finished populate-webroot step (success) 04:50:52 INFO - [mozharness: 2017-06-14 11:50:52.595548Z] Running create-virtualenv step. 04:50:52 INFO - Running pre-action listener: _resource_record_pre_action 04:50:52 INFO - Running main action method: create_virtualenv 04:50:52 INFO - Creating virtualenv /builds/slave/test/build/venv 04:50:52 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:50:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:50:52 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 04:50:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:50:52 INFO - Using real prefix '/tools/python27' 04:50:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:50:53 INFO - Installing distribute.............................................................................................................................................................................................done. 04:50:56 INFO - Installing pip.................done. 04:50:56 INFO - Return code: 0 04:50:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:50:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:50:56 INFO - Reading from file tmpfile_stdout 04:50:56 INFO - Output received: 04:50:56 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 04:50:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:50: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')]} 04:50:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:50: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')]} 04:50:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:50:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c761c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c765768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f812847eac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4b7d30>, '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 0x10c481cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4a8be8>, '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.RSevgheyQy/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.fmVI0oIJAO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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 04:50:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:50:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:50:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RSevgheyQy/Render', 04:50:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:50:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:50:56 INFO - 'HOME': '/Users/cltbld', 04:50:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:50:56 INFO - 'LOGNAME': 'cltbld', 04:50:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:56 INFO - 'MOZ_NO_REMOTE': '1', 04:50:56 INFO - 'NO_EM_RESTART': '1', 04:50:56 INFO - 'PAGER': '/bin/cat', 04:50:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:50:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:56 INFO - 'PWD': '/builds/slave/test', 04:50:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:50:56 INFO - 'SHELL': '/bin/bash', 04:50:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fmVI0oIJAO/Listeners', 04:50:56 INFO - 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/', 04:50:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:50:56 INFO - 'USER': 'cltbld', 04:50:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:50:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:50:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:56 INFO - 'XPC_FLAGS': '0x0', 04:50:56 INFO - 'XPC_SERVICE_NAME': '0', 04:50:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:50:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:50:56 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:50:56 INFO - Cleaning up... 04:50:56 INFO - Return code: 0 04:50:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:50: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')]} 04:50:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:50: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')]} 04:50:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:50:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c761c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c765768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f812847eac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4b7d30>, '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 0x10c481cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4a8be8>, '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.RSevgheyQy/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.fmVI0oIJAO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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 04:50:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:50:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:50:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RSevgheyQy/Render', 04:50:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:50:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:50:56 INFO - 'HOME': '/Users/cltbld', 04:50:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:50:56 INFO - 'LOGNAME': 'cltbld', 04:50:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:56 INFO - 'MOZ_NO_REMOTE': '1', 04:50:56 INFO - 'NO_EM_RESTART': '1', 04:50:56 INFO - 'PAGER': '/bin/cat', 04:50:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:50:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:56 INFO - 'PWD': '/builds/slave/test', 04:50:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:50:56 INFO - 'SHELL': '/bin/bash', 04:50:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fmVI0oIJAO/Listeners', 04:50:56 INFO - 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/', 04:50:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:50:56 INFO - 'USER': 'cltbld', 04:50:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:50:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:50:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:56 INFO - 'XPC_FLAGS': '0x0', 04:50:56 INFO - 'XPC_SERVICE_NAME': '0', 04:50:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:50:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:50:57 INFO - Downloading/unpacking psutil>=3.1.1 04:50:57 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 04:50:57 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 04:50:57 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 04:50:57 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 04:50:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:50:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:50:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:50:59 INFO - Installing collected packages: psutil 04:50:59 INFO - Running setup.py install for psutil 04:50:59 INFO - building 'psutil._psutil_osx' extension 04:50:59 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 04:50:59 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 04:50:59 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 04:50:59 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 04:50:59 INFO - building 'psutil._psutil_posix' extension 04:50:59 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 04:50:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:50:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:50:59 INFO - ^ 04:50:59 INFO - 1 warning generated. 04:50:59 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 04:50:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:50:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:50:59 INFO - Successfully installed psutil 04:50:59 INFO - Cleaning up... 04:50:59 INFO - Return code: 0 04:50:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:50:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:50:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:51: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')]} 04:51:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:51:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c761c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c765768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f812847eac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4b7d30>, '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 0x10c481cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4a8be8>, '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.RSevgheyQy/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.fmVI0oIJAO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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 04:51:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:51:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:51:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RSevgheyQy/Render', 04:51:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:51:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:51:00 INFO - 'HOME': '/Users/cltbld', 04:51:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:51:00 INFO - 'LOGNAME': 'cltbld', 04:51:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:51:00 INFO - 'MOZ_NO_REMOTE': '1', 04:51:00 INFO - 'NO_EM_RESTART': '1', 04:51:00 INFO - 'PAGER': '/bin/cat', 04:51:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:51:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:51:00 INFO - 'PWD': '/builds/slave/test', 04:51:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:51:00 INFO - 'SHELL': '/bin/bash', 04:51:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fmVI0oIJAO/Listeners', 04:51:00 INFO - 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/', 04:51:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:51:00 INFO - 'USER': 'cltbld', 04:51:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:51:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:51:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:51:00 INFO - 'XPC_FLAGS': '0x0', 04:51:00 INFO - 'XPC_SERVICE_NAME': '0', 04:51:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:51:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:51:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:51:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:51:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:51:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:51:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:51:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:51:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:51:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:51:02 INFO - Installing collected packages: mozsystemmonitor 04:51:02 INFO - Running setup.py install for mozsystemmonitor 04:51:03 INFO - Successfully installed mozsystemmonitor 04:51:03 INFO - Cleaning up... 04:51:03 INFO - Return code: 0 04:51:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:51: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')]} 04:51:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:51: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')]} 04:51:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:51:03 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 0x10c761c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c765768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f812847eac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4b7d30>, '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 0x10c481cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4a8be8>, '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.RSevgheyQy/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.fmVI0oIJAO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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 04:51:03 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 04:51:03 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 04:51:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RSevgheyQy/Render', 04:51:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:51:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:51:03 INFO - 'HOME': '/Users/cltbld', 04:51:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:51:03 INFO - 'LOGNAME': 'cltbld', 04:51:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:51:03 INFO - 'MOZ_NO_REMOTE': '1', 04:51:03 INFO - 'NO_EM_RESTART': '1', 04:51:03 INFO - 'PAGER': '/bin/cat', 04:51:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:51:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:51:03 INFO - 'PWD': '/builds/slave/test', 04:51:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:51:03 INFO - 'SHELL': '/bin/bash', 04:51:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fmVI0oIJAO/Listeners', 04:51:03 INFO - 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/', 04:51:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:51:03 INFO - 'USER': 'cltbld', 04:51:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:51:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:51:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:51:03 INFO - 'XPC_FLAGS': '0x0', 04:51:03 INFO - 'XPC_SERVICE_NAME': '0', 04:51:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:51:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:51:03 INFO - Downloading/unpacking jsonschema==2.5.1 04:51:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:51:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:51:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:51:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:51:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:51:04 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:51:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:51:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:51:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:51:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:51:05 INFO - Downloading functools32-3.2.3-2.tar.gz 04:51:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:51:05 INFO - warning: no files found matching '*.txt' 04:51:05 INFO - no previously-included directories found matching 'build' 04:51:05 INFO - no previously-included directories found matching 'dist' 04:51:05 INFO - no previously-included directories found matching '.git*' 04:51:05 INFO - Installing collected packages: jsonschema, functools32 04:51:05 INFO - Running setup.py install for functools32 04:51:05 INFO - warning: no files found matching '*.txt' 04:51:05 INFO - no previously-included directories found matching 'build' 04:51:05 INFO - no previously-included directories found matching 'dist' 04:51:05 INFO - no previously-included directories found matching '.git*' 04:51:05 INFO - Successfully installed jsonschema functools32 04:51:05 INFO - Cleaning up... 04:51:05 INFO - Return code: 0 04:51:05 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:51: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')]} 04:51:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:51: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')]} 04:51:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:51:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c761c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c765768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f812847eac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4b7d30>, '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 0x10c481cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4a8be8>, '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.RSevgheyQy/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.fmVI0oIJAO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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 04:51:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:51:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:51:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RSevgheyQy/Render', 04:51:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:51:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:51:05 INFO - 'HOME': '/Users/cltbld', 04:51:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:51:05 INFO - 'LOGNAME': 'cltbld', 04:51:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:51:05 INFO - 'MOZ_NO_REMOTE': '1', 04:51:05 INFO - 'NO_EM_RESTART': '1', 04:51:05 INFO - 'PAGER': '/bin/cat', 04:51:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:51:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:51:05 INFO - 'PWD': '/builds/slave/test', 04:51:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:51:05 INFO - 'SHELL': '/bin/bash', 04:51:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fmVI0oIJAO/Listeners', 04:51:05 INFO - 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/', 04:51:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:51:05 INFO - 'USER': 'cltbld', 04:51:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:51:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:51:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:51:05 INFO - 'XPC_FLAGS': '0x0', 04:51:05 INFO - 'XPC_SERVICE_NAME': '0', 04:51:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:51:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:51:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:51:05 INFO - Cleaning up... 04:51:05 INFO - Return code: 0 04:51:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:51: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')]} 04:51:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:51: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')]} 04:51:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:51:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c761c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c765768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f812847eac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4b7d30>, '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 0x10c481cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4a8be8>, '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.RSevgheyQy/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.fmVI0oIJAO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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 04:51:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:51:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:51:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RSevgheyQy/Render', 04:51:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:51:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:51:05 INFO - 'HOME': '/Users/cltbld', 04:51:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:51:05 INFO - 'LOGNAME': 'cltbld', 04:51:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:51:05 INFO - 'MOZ_NO_REMOTE': '1', 04:51:05 INFO - 'NO_EM_RESTART': '1', 04:51:05 INFO - 'PAGER': '/bin/cat', 04:51:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:51:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:51:05 INFO - 'PWD': '/builds/slave/test', 04:51:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:51:05 INFO - 'SHELL': '/bin/bash', 04:51:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fmVI0oIJAO/Listeners', 04:51:05 INFO - 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/', 04:51:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:51:05 INFO - 'USER': 'cltbld', 04:51:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:51:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:51:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:51:05 INFO - 'XPC_FLAGS': '0x0', 04:51:05 INFO - 'XPC_SERVICE_NAME': '0', 04:51:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:51:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:51:05 INFO - Downloading/unpacking blobuploader==1.2.4 04:51:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:51:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:51:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:51:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:51:07 INFO - Downloading blobuploader-1.2.4.tar.gz 04:51:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:51:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:51:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:51:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:51:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:51:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:51:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:51:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:51:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:51:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:51:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:51:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:51:08 INFO - Downloading docopt-0.6.1.tar.gz 04:51:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:51:08 INFO - Installing collected packages: blobuploader, requests, docopt 04:51:08 INFO - Running setup.py install for blobuploader 04:51:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:51:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:51:08 INFO - Running setup.py install for requests 04:51:08 INFO - Running setup.py install for docopt 04:51:09 INFO - Successfully installed blobuploader requests docopt 04:51:09 INFO - Cleaning up... 04:51:09 INFO - Return code: 0 04:51:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:51:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:51:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:51:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:51:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:51:09 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 0x10c761c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c765768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f812847eac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4b7d30>, '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 0x10c481cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4a8be8>, '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.RSevgheyQy/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.fmVI0oIJAO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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 04:51:09 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 04:51:09 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 04:51:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RSevgheyQy/Render', 04:51:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:51:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:51:09 INFO - 'HOME': '/Users/cltbld', 04:51:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:51:09 INFO - 'LOGNAME': 'cltbld', 04:51:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:51:09 INFO - 'MOZ_NO_REMOTE': '1', 04:51:09 INFO - 'NO_EM_RESTART': '1', 04:51:09 INFO - 'PAGER': '/bin/cat', 04:51:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:51:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:51:09 INFO - 'PWD': '/builds/slave/test', 04:51:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:51:09 INFO - 'SHELL': '/bin/bash', 04:51:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fmVI0oIJAO/Listeners', 04:51:09 INFO - 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/', 04:51:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:51:09 INFO - 'USER': 'cltbld', 04:51:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:51:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:51:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:51:09 INFO - 'XPC_FLAGS': '0x0', 04:51:09 INFO - 'XPC_SERVICE_NAME': '0', 04:51:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:51:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:51:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:51:09 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-l6ztRA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:51:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:51:09 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-ZOoh3w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:51:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:51:09 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-PpyC4h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:51:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:51:09 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-jyqri2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:51:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:51:09 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-N8EEO5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:51:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:51:09 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-QSzKJF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:51:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:51:09 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-YuNBuL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:51:10 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-9h1PwV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:51:10 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-Z3ynRK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:51:10 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-0LN3Sz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:51:10 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-eCzUPO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:51:10 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-bUL7Oc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:51:10 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-1Wapy8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:51:10 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-4xdSpp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:51:10 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-FJ3XYJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:51:10 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-zGe0SC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:51:11 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-b6mTgx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:51:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:51:11 INFO - Running setup.py install for manifestparser 04:51:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:51:11 INFO - Running setup.py install for mozcrash 04:51:11 INFO - Running setup.py install for mozdebug 04:51:11 INFO - Running setup.py install for mozdevice 04:51:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:51:11 INFO - Running setup.py install for mozfile 04:51:11 INFO - Running setup.py install for mozhttpd 04:51:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:51:11 INFO - Running setup.py install for mozinfo 04:51:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:51:12 INFO - Running setup.py install for mozInstall 04:51:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:51:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:51:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:51:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:51:12 INFO - Running setup.py install for mozleak 04:51:12 INFO - Running setup.py install for mozlog 04:51:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:51:12 INFO - Running setup.py install for moznetwork 04:51:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:51:12 INFO - Running setup.py install for mozprocess 04:51:12 INFO - Running setup.py install for mozprofile 04:51:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:51:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:51:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:51:13 INFO - Running setup.py install for mozrunner 04:51:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:51:13 INFO - Running setup.py install for mozscreenshot 04:51:13 INFO - Running setup.py install for moztest 04:51:13 INFO - Running setup.py install for mozversion 04:51:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:51:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:51:13 INFO - Cleaning up... 04:51:13 INFO - Return code: 0 04:51:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:51:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:51:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:51:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:51:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:51:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c761c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c765768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f812847eac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4b7d30>, '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 0x10c481cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4a8be8>, '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.RSevgheyQy/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.fmVI0oIJAO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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 04:51:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:51:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:51:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RSevgheyQy/Render', 04:51:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:51:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:51:13 INFO - 'HOME': '/Users/cltbld', 04:51:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:51:13 INFO - 'LOGNAME': 'cltbld', 04:51:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:51:13 INFO - 'MOZ_NO_REMOTE': '1', 04:51:13 INFO - 'NO_EM_RESTART': '1', 04:51:13 INFO - 'PAGER': '/bin/cat', 04:51:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:51:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:51:13 INFO - 'PWD': '/builds/slave/test', 04:51:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:51:13 INFO - 'SHELL': '/bin/bash', 04:51:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fmVI0oIJAO/Listeners', 04:51:13 INFO - 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/', 04:51:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:51:13 INFO - 'USER': 'cltbld', 04:51:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:51:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:51:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:51:13 INFO - 'XPC_FLAGS': '0x0', 04:51:13 INFO - 'XPC_SERVICE_NAME': '0', 04:51:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:51:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:51:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:51:13 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-kND_7g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:51:14 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)) 04:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:51:14 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-4aprCK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:51:14 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)) 04:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:51:14 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-Op7s6H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:51:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:51:14 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-mblSLR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:51:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:51:14 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-QnLoqR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:51:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:51:14 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-LjaRhY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:51:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:51:14 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-tyPDZf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:51:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:51:14 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-s6yFtM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:51:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:51:14 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-3ZS8jz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:51:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:51:14 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-FOXtnU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:51:15 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)) 04:51:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:51:15 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-UmFbk2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:51:15 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)) 04:51:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:51:15 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-n43ASZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:51:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:51:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:51:15 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-wKiIPI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:51:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:51:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:51:15 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-tmEowb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:51:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:51:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:51:15 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-8B1D3R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:51:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:51:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:51:15 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-LqvPy1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:51:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:51:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:51:15 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-5fAfFF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:51:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:51:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:51:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:51:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:51:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:51:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:51:15 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:51:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:51:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:51:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:51:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:51:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:51:17 INFO - Downloading blessings-1.6.tar.gz 04:51:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:51:17 INFO - Installing collected packages: blessings 04:51:17 INFO - Running setup.py install for blessings 04:51:17 INFO - Successfully installed blessings 04:51:17 INFO - Cleaning up... 04:51:17 INFO - Return code: 0 04:51:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:51:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:51:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:51:17 INFO - Reading from file tmpfile_stdout 04:51:17 INFO - Current package versions: 04:51:17 INFO - blessings == 1.6 04:51:17 INFO - blobuploader == 1.2.4 04:51:17 INFO - docopt == 0.6.1 04:51:17 INFO - functools32 == 3.2.3-2 04:51:17 INFO - jsonschema == 2.5.1 04:51:17 INFO - manifestparser == 1.1 04:51:17 INFO - mozInstall == 1.13 04:51:17 INFO - mozcrash == 1.0 04:51:17 INFO - mozdebug == 0.1 04:51:17 INFO - mozdevice == 0.51 04:51:17 INFO - mozfile == 1.2 04:51:17 INFO - mozhttpd == 0.7 04:51:17 INFO - mozinfo == 0.10 04:51:17 INFO - mozleak == 0.1 04:51:17 INFO - mozlog == 3.4 04:51:17 INFO - moznetwork == 0.27 04:51:17 INFO - mozprocess == 0.25 04:51:17 INFO - mozprofile == 0.28 04:51:17 INFO - mozrunner == 6.13 04:51:17 INFO - mozscreenshot == 0.1 04:51:17 INFO - mozsystemmonitor == 0.3 04:51:17 INFO - moztest == 0.8 04:51:17 INFO - mozversion == 1.4 04:51:17 INFO - psutil == 3.1.1 04:51:17 INFO - requests == 1.2.3 04:51:17 INFO - wsgiref == 0.1.2 04:51:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:51:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:51:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:51:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:51:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:51:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c761c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c765768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f812847eac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4b7d30>, '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 0x10c481cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4a8be8>, '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.RSevgheyQy/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.fmVI0oIJAO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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 04:51:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:51:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:51:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RSevgheyQy/Render', 04:51:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:51:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:51:17 INFO - 'HOME': '/Users/cltbld', 04:51:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:51:17 INFO - 'LOGNAME': 'cltbld', 04:51:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:51:17 INFO - 'MOZ_NO_REMOTE': '1', 04:51:17 INFO - 'NO_EM_RESTART': '1', 04:51:17 INFO - 'PAGER': '/bin/cat', 04:51:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:51:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:51:17 INFO - 'PWD': '/builds/slave/test', 04:51:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:51:17 INFO - 'SHELL': '/bin/bash', 04:51:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fmVI0oIJAO/Listeners', 04:51:17 INFO - 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/', 04:51:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:51:17 INFO - 'USER': 'cltbld', 04:51:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:51:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:51:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:51:17 INFO - 'XPC_FLAGS': '0x0', 04:51:17 INFO - 'XPC_SERVICE_NAME': '0', 04:51:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:51:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:51:18 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)) 04:51:18 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)) 04:51:18 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)) 04:51:18 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)) 04:51:18 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)) 04:51:18 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)) 04:51:18 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)) 04:51:18 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)) 04:51:18 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)) 04:51:18 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 04:51:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:51:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:51:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:51:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:51:19 INFO - Downloading marionette_driver-1.4.0.tar.gz 04:51:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 04:51:19 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)) 04:51:19 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)) 04:51:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 04:51:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 04:51:19 INFO - Installing collected packages: marionette-driver 04:51:19 INFO - Running setup.py install for marionette-driver 04:51:19 INFO - Successfully installed marionette-driver 04:51:19 INFO - Cleaning up... 04:51:19 INFO - Return code: 0 04:51:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:51:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:51:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:51:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:51:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:51:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c761c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c765768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f812847eac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4b7d30>, '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 0x10c481cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4a8be8>, '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.RSevgheyQy/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.fmVI0oIJAO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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 04:51:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:51:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:51:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RSevgheyQy/Render', 04:51:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:51:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:51:19 INFO - 'HOME': '/Users/cltbld', 04:51:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:51:19 INFO - 'LOGNAME': 'cltbld', 04:51:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:51:19 INFO - 'MOZ_NO_REMOTE': '1', 04:51:19 INFO - 'NO_EM_RESTART': '1', 04:51:19 INFO - 'PAGER': '/bin/cat', 04:51:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:51:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:51:19 INFO - 'PWD': '/builds/slave/test', 04:51:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:51:19 INFO - 'SHELL': '/bin/bash', 04:51:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fmVI0oIJAO/Listeners', 04:51:19 INFO - 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/', 04:51:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:51:19 INFO - 'USER': 'cltbld', 04:51:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:51:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:51:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:51:19 INFO - 'XPC_FLAGS': '0x0', 04:51:19 INFO - 'XPC_SERVICE_NAME': '0', 04:51:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:51:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:51:20 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:51:20 INFO - Cleaning up... 04:51:20 INFO - Return code: 0 04:51:20 INFO - Running post-action listener: _resource_record_post_action 04:51:20 INFO - Running post-action listener: _start_resource_monitoring 04:51:20 INFO - Starting resource monitoring. 04:51:20 INFO - [mozharness: 2017-06-14 11:51:20.099498Z] Finished create-virtualenv step (success) 04:51:20 INFO - [mozharness: 2017-06-14 11:51:20.100444Z] Running install step. 04:51:20 INFO - Running pre-action listener: _resource_record_pre_action 04:51:20 INFO - Running main action method: install 04:51:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:51:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:51:20 INFO - Reading from file tmpfile_stdout 04:51:20 INFO - Detecting whether we're running mozinstall >=1.0... 04:51:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:51:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:51:20 INFO - Reading from file tmpfile_stdout 04:51:20 INFO - Output received: 04:51:20 INFO - Usage: mozinstall [options] installer 04:51:20 INFO - Options: 04:51:20 INFO - -h, --help show this help message and exit 04:51:20 INFO - -d DEST, --destination=DEST 04:51:20 INFO - Directory to install application into. [default: 04:51:20 INFO - "/builds/slave/test"] 04:51:20 INFO - --app=APP Application being installed. [default: firefox] 04:51:20 INFO - mkdir: /builds/slave/test/build/application 04:51:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:51:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:51:42 INFO - Reading from file tmpfile_stdout 04:51:42 INFO - Output received: 04:51:42 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:51:42 INFO - Running post-action listener: _resource_record_post_action 04:51:42 INFO - [mozharness: 2017-06-14 11:51:42.411731Z] Finished install step (success) 04:51:42 INFO - [mozharness: 2017-06-14 11:51:42.411906Z] Running setup-mitmproxy step. 04:51:42 INFO - Running pre-action listener: _resource_record_pre_action 04:51:42 INFO - Running main action method: setup_mitmproxy 04:51:42 INFO - Skipping: mitmproxy is not required 04:51:42 INFO - Running post-action listener: _resource_record_post_action 04:51:42 INFO - [mozharness: 2017-06-14 11:51:42.412541Z] Finished setup-mitmproxy step (success) 04:51:42 INFO - [mozharness: 2017-06-14 11:51:42.412680Z] Running run-tests step. 04:51:42 INFO - Running pre-action listener: _resource_record_pre_action 04:51:42 INFO - Running main action method: run_tests 04:51:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:51:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:51:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:51:42 INFO - 2017-06-14 04:51:42.427 screenresolution[1996:21169] starting screenresolution argv=screenresolution get 04:51:42 INFO - 2017-06-14 04:51:42.429 screenresolution[1996:21169] Display 0: 1600x1200x32@60 04:51:42 INFO - 2017-06-14 04:51:42.435 screenresolution[1997:21172] starting screenresolution argv=screenresolution list 04:51:42 INFO - Available Modes on Display 0 04:51:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:51:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:51:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:51:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:51:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:51:42 INFO - Intel Iris: 04:51:42 INFO - Chipset Model: Intel Iris 04:51:42 INFO - Type: GPU 04:51:42 INFO - Bus: Built-In 04:51:42 INFO - VRAM (Dynamic, Max): 1536 MB 04:51:42 INFO - Vendor: Intel (0x8086) 04:51:42 INFO - Device ID: 0x0a2e 04:51:42 INFO - Revision ID: 0x0009 04:51:42 INFO - Displays: 04:51:42 INFO - Display: 04:51:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:51:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:51:42 INFO - Display Serial Number: 1600x1200 60 04:51:42 INFO - Main Display: Yes 04:51:42 INFO - Mirror: Off 04:51:42 INFO - Online: Yes 04:51:42 INFO - Rotation: Supported 04:51:42 INFO - Return code: 0 04:51:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:51:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:51:42 INFO - Python 2.7.3 04:51:42 INFO - Return code: 0 04:51:42 INFO - grabbing minidump binary from tooltool 04:51:42 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')]} 04:51:42 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 0x10c4b7d30>, '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 0x10c481cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4a8be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:51:42 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 04:51:42 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 04:51:42 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 04:51:42 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:51:42 INFO - Return code: 0 04:51:42 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:51:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:51:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:51:42 INFO - ENV: RUST_BACKTRACE is now 1 04:51:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:51:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:51:42 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/MvKG-EcGSX-BAyzK5trNBg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0287', '--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 04:51:42 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/MvKG-EcGSX-BAyzK5trNBg/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0287 --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 04:51:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RSevgheyQy/Render', 04:51:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:51:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:51:42 INFO - 'HOME': '/Users/cltbld', 04:51:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:51:42 INFO - 'LOGNAME': 'cltbld', 04:51:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:51:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:51:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:51:42 INFO - 'MOZ_NO_REMOTE': '1', 04:51:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:51:42 INFO - 'NO_EM_RESTART': '1', 04:51:42 INFO - 'PAGER': '/bin/cat', 04:51:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:51:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:51:42 INFO - 'PWD': '/builds/slave/test', 04:51:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:51:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:51:42 INFO - 'RUST_BACKTRACE': '1', 04:51:42 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:51:42 INFO - 'SHELL': '/bin/bash', 04:51:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fmVI0oIJAO/Listeners', 04:51:42 INFO - 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/', 04:51:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:51:42 INFO - 'USER': 'cltbld', 04:51:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:51:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:51:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:51:42 INFO - 'XPC_FLAGS': '0x0', 04:51:42 INFO - 'XPC_SERVICE_NAME': '0', 04:51:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:51:42 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/MvKG-EcGSX-BAyzK5trNBg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0287', '--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 04:51:43 INFO - mozversion application_buildid: 20170614034451 04:51:43 INFO - mozversion application_changeset: ad3f1138ce6f199408ad58d65c7476636e924909 04:51:43 INFO - mozversion application_display_name: Nightly 04:51:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:51:43 INFO - mozversion application_name: Firefox 04:51:43 INFO - mozversion application_remotingname: firefox 04:51:43 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:51:43 INFO - mozversion application_vendor: Mozilla 04:51:43 INFO - mozversion application_version: 56.0a1 04:51:43 INFO - mozversion platform_buildid: 20170614034451 04:51:43 INFO - mozversion platform_changeset: ad3f1138ce6f199408ad58d65c7476636e924909 04:51:43 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:51:43 INFO - mozversion platform_version: 56.0a1 04:51:43 INFO - using testdate: 1497441103 04:51:43 INFO - actual date: 1497441103 04:51:43 INFO - starting webserver on 'localhost:54483' 04:51:43 INFO - SUITE-START | Running 2 tests 04:51:43 INFO - TEST-START | dromaeo_css 04:51:43 INFO - operating with platform_type : mac_ 04:51:43 INFO - Initialising browser for dromaeo_css test... 04:51:43 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:54483/getInfo.html -foreground -profile /var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/tmp8MWH1G/profile 04:51:43 INFO - TEST-INFO | started process 2007 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:54483/getInfo.html -foreground) 04:52:16 INFO - TEST-INFO | 2007: killed by SIGTERM 04:52:16 INFO - Browser initialized. 04:52:16 INFO - Running cycle 1/1 for dromaeo_css test... 04:52:16 INFO - TEST-INFO | started process 2014 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/tmp8MWH1G/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:52:18 INFO - PID 2014 | 2017-06-14 04:52:18.384 plugin-container[2015:21652] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 04:52:18 INFO - PID 2014 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:52:18 INFO - PID 2014 | 2017-06-14 04:52:18.384 plugin-container[2015:21652] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f0b, name = 'com.apple.CFPasteboardClient' 04:52:18 INFO - PID 2014 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:52:18 INFO - PID 2014 | 2017-06-14 04:52:18.384 plugin-container[2015:21652] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:52:19 INFO - PID 2014 | 2017-06-14 04:52:19.232 plugin-container[2016:21703] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4743, name = 'com.apple.tsm.portname' 04:52:19 INFO - PID 2014 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:52:19 INFO - PID 2014 | 2017-06-14 04:52:19.232 plugin-container[2016:21703] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0b, name = 'com.apple.CFPasteboardClient' 04:52:19 INFO - PID 2014 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:52:19 INFO - PID 2014 | 2017-06-14 04:52:19.232 plugin-container[2016:21703] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:52:28 INFO - PID 2014 | RSS: Main: 254808064 04:52:28 INFO - PID 2014 | 04:55:30 INFO - PID 2014 | Cycle 1(1): loaded http://localhost:54483/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54483/tests/dromaeo/cssquery-ext.html) 04:55:32 INFO - PID 2014 | RSS: Main: 271347712 04:55:32 INFO - PID 2014 | 04:58:34 INFO - PID 2014 | Cycle 1(1): loaded http://localhost:54483/tests/dromaeo/cssquery-ext.html (next: http://localhost:54483/tests/dromaeo/cssquery-jquery.html) 04:58:34 INFO - PID 2014 | RSS: Main: 275197952 04:58:34 INFO - PID 2014 | 05:01:36 INFO - PID 2014 | Cycle 1(1): loaded http://localhost:54483/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54483/tests/dromaeo/cssquery-mootools.html) 05:01:36 INFO - PID 2014 | RSS: Main: 276930560 05:01:36 INFO - PID 2014 | 05:04:38 INFO - PID 2014 | Cycle 1(1): loaded http://localhost:54483/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54483/tests/dromaeo/cssquery-prototype.html) 05:04:39 INFO - PID 2014 | RSS: Main: 278364160 05:04:39 INFO - PID 2014 | 05:07:42 INFO - PID 2014 | Cycle 1(1): loaded http://localhost:54483/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54483/tests/dromaeo/cssquery-yui.html) 05:07:42 INFO - PID 2014 | RSS: Main: 280924160 05:07:42 INFO - PID 2014 | 05:10:44 INFO - PID 2014 | Cycle 1(1): loaded http://localhost:54483/tests/dromaeo/cssquery-yui.html (next: http://localhost:54483/tests/dromaeo/cssquery-dojo.html) 05:10:44 INFO - PID 2014 | RSS: Main: 281915392 05:10:44 INFO - PID 2014 | 05:10:44 INFO - PID 2014 | __start_tp_report 05:10:44 INFO - PID 2014 | _x_x_mozilla_page_load 05:10:44 INFO - PID 2014 | _x_x_mozilla_page_load_details 05:10:44 INFO - PID 2014 | |i|pagename|runs| 05:10:44 INFO - PID 2014 | |0;dojo.html;2563.551378508761;3235.320582677637;3378;3486.7559270851034;3544.9291014179717;6054.909176362347;6084.178635884155;6131.570790044688;6140.416660417259;6146.9242882495555;5107.719075450848;6119.296280927695;6132.0801879718065;6232.532560058003;6236;5426.348838139429;5438.91841622375;5447.863803404907;5482.314710661168;5539.113741801312;5517.172424136392;5552.472515111078;5556.472135147154;5570.108782594785;5578.246936663539;5302.072137375943;5431.83704488864;5464.9453505464835;5469.371022332403;5488.615796894219;5376.032314183445;5411.756470958816;5444.210589464506;5464.7540860660965;5493.395726470085;5337.092694241988;5384.73076346185;5426.186072089179;5463.289772329612;5471.37079235885;6162.568620196588;6209.316975132731;6234;6255.093011513351;6267.341929097471;6347.841303967438;6644.568103073285;6655.900161497581;6675.46596272297;6684.097646817666;6493.772717954849;6605.9669701651665;6658.101156343929;6664.400203981617;6709.664516774191;6250.187475628185;6334.208223972004;6348.238211414659;6348.746050157988;6350.237971443456;6817.522773405814;6899.965500172568;6917.827054323581;6922.965385173143;6923.6884340204815;6346.936530634634;6502.349765023554;6513.674316284167;6535.477161827042;6538.248101468297;6560.376764207393;6567.540272180997;6585.539012269095;6589.472842172615;6596.736130554724;5367.355917289872;6515.315891831384;6548.312427195171;6548.377904099103;6564.113844630913;6563.3764792344655;6568.540202185801;6585.80242592723;6596.604203747695;6685.799426017227;5289.259503669489;5325.068113080196;5326.600504962066;5346.064438723208;5355.598330125256;3929.292727309112;4694.835680751157;4736.550027747358;4747.7626118693925;4786.617070634341;6182.412670796267;6238.189035425366;6347.777827776005;6350.491960643138;6364.554481186365;6558.606483610999;6564.409203171737;6566.343365663491;6577.704003319862;6664.633445160563;5560.304961879765;6316.052592111128;6343.111964324999;6356.77751278703;6370.235571731422;4702.600278976257;5950.256217972754;5976.073708575178;5984.192134061933;5995.730192141364;6904.3095690430555;6920.169579650374;6960.686769095403;6977.755778547727;6995.300469952964;6595.670216489252;6637;6796.218434880052;6810.523263371715;6827.078344423439;5655.462731040545;6793.388595026471;6836.897446538206;6922.169339679271;6932.549384290005;6514.446272066927;6606.042123892104;6611.471082313499;6615.4530505754665;6638.734450621921;6701.0953521274005;6724.49566282521;6724.73101075971;6727.327267273234;6733.461323094239;6458.967705161632;6468.6442245676935;6469.4824414045825;6470.611763294218;6508.446782023581;6296.5277604178955;6352.936470635419;6376.968115159394;6403.0395440682405;6448.355164483514;6164.568480206342;6274.435300822948;6363.968180159255;6369.14016607752;6381.840453988688;6425.518086143465;6445.387688169616;6461.127747753993;6473.2232132144445;6474.449671777952;6315.5894866833505;6337.081123237012;6337.6197428154455;6351.110844481684;6365.936340636535 05:10:44 INFO - PID 2014 | |1;ext.html;702.971881124755;768.5273556762593;803.7548547692951;805.907994667226;839.680921249925;29995.700042999564;36527.26945461089;37248.62751372489;37436.62563374366;37476.81261593691;37058;37417.81291093548;37754.244915101684;37770.43344349837;37847.43228851563;8043.597820109;8096.825960235762;8153.510789352628;8166.142555031725;8204.220599043112;7994.160613135624;8096.757097287091;8107.878381824277;8123.512589244635;8124.146964568724;8050;8095.947526821505;8101.675932962675;8128.39037072219;8134.633941472618;6496.837579060514;8070.636821343053;8080.71717489885;8089.191080891893;8110.067342255657;7900.446968712193;7939.801504962365;7949.880751788728;7962.323202527792;7970.920290797134;35413.114672133146;35425.29149416997;35523.82238088793;35636.28727425437;35663.286734265166;3478.286951175003;3505.684488396031;3508.8245587720767;3510;3510.859565617398;28639.57040644392;28895.71104288972;28978;29012.564811527845;29084;2983.4778913690016;3071.7389021933163;3075.938481230385;3080.476319025771;3090.1502086926052;219575.70636440307;236722;255887;287280.25439491094;315044.42477787693;45677.314840277424;46396.536034639896;46586;46681.76659116751;46750.766246168554;48399;48686.26970595444;48690.26964595534;48805.755971219914;48841;13420;13754.449822007007;13764.31178441074;13862;13924.930375348058;11202.327860328407;11304.773904521864;11306.152038597138;11388.145889058354;11428.257163284527;10753.085987691135;10776.94611526953;13129.868701313055;13226.404811783494;13273.601791946257;5649.231876380626;5743.052396354555;5747.798827041117;5751.626144300607;5771.575938106005;833.6998680475024;968.1915600473683;983.6262220356219;1042.629866397418;1045.4563626913962;22375.552488950456;22726.431839204153;22784.31647050591;22852.771472285065;23676.289711308687;1079.983800242997;1110.0130765928898;1141.9828702569469;1151.2516864038441;1160.6982184632056;11663;11782.175247732748;11815.645530634094;12101.69745756363;15814.841851581337;341916;343404;344070;346133;346455;54182.45817541853;54240.45759542433;54419.45580544223;54420.45579544233;54693.45306546963;1407.5917983784793;1473.9257177194;1482.1922052481423;1490.3889405343757;1491.8284397294233;27520;28825.56761648535;29350.559741603465;29374.825007000334;29438.11685649434;37024.25951481009;37138;37144.44283335698;37172;37259.44110838394;239241;241584;242493;266169;301098;31065.8446707765;31329.686703132676;31887;31989.520157198127;32063.67936320607;38690.41964370593;39175.41236881506;39248.60751392449;39300;41603.37594936139;26941.0570630027;28029.299267518476;28693.13920582386;29077.563836542045;29615.555766663947;27973.160805175878;28146.29634259078;29250.853745731136;29822;30192.396152077275;34787.652123479456;34817.30365392729;34865.65134348624;34885.65114348824;34929.825350873085;26352.736472635796;28558.857205713837;28804;31046.689533105284;31670.049898503083;18590.53523661919;23181.768182318636;23827.285181444586;23840.52318953646;23961.760382395954 05:10:44 INFO - PID 2014 | |2;jquery.html;430.2578052858818;485.1267718107406;492.9605631549474;503.09191908604964;522.3835873669069;21949.99999999998;22660.3790550599;22721.204757833457;22744.31767046987;22759.886200568995;22422.215222467217;24466.3883402915;24825.75174248257;24843.254702358914;24852.378690532747;6294.559380843331;6468.191476065492;6508.381703738138;6515.511336649746;6517.283098859121;6373.139626150457;6470.191226096738;6495.642739649317;6514.739410423577;6524.575902566342;6030.577859549833;6236.844078898019;6268.65522396268;6426.196725409324;6469.353064693517;6228.346023667517;6328.462080723098;6332;6334.461570766513;6351.491880649537;5775.5668324875805;5782.3350314713925;5798.9130163047585;5801.796937107223;5810.5932584719085;28001.85999070012;29469.852650736608;29852.850735746182;29889.850550747105;29946.850265748533;2218.611742944994;2291.576058429196;2312.8496647717843;2317.721873375189;2318.258157389636;18827.435176944575;19443.222271109138;19497.317593884287;19499.610007799907;19599.608007839764;1964.1946801811332;1985.9602807943904;2170.3597438755605;2188.7701791311842;2235.8770267635273;156995;160052.19973900056;165656;191929.04035479872;194502;14024.579262622137;14178.432862685377;14826.258687065603;15199.62000949963;15225.847741522442;16692.499225023268;19575.412737617895;19624.803751962296;19626.607467850565;19665.311714090225;8241;9389.859152112724;9596.66411675602;9707.854382184272;9728.513574321256;8050.194980501903;8690.304775618062;9059.184673379295;9126.726198213932;9243.26053915685;7767.262110099531;7943.960280198562;9237.90762092384;9312.44125352481;9392.8591071134;2230.9553808924056;2365.5978483657477;2573.6782902137234;2601.583746600573;2604.5832666773226;918.4902379179485;954.2509130332655;985.6845809340942;987.1313244344931;994.2394068537575;19784.882280049565;20982.47543811417;21085.68371474429;22000.559988800454;22052.338429847132;967.7677357434166;971.455984648599;1024.5184763161303;1029.120102312527;1030.2685093583489;10841.349519028883;11020.779584408427;13982.091164074303;15082.170480623452;15506.612334691497;557724;582152;594536;594930;596401;156998;163160;166848;169047;170434;977.4184360305607;1221.419825582841;1256.0767835640797;1259.3073809404973;1267.283984548727;22663.093476260765;22796.316110516713;23665.053397863892;23877.16429924944;23904.282871513882;70224.94662579964;72407.63796181197;73239;73350.63324683343;73992.63003684947;79137.20862791299;81352;93788;96333.51833240788;101413;10993.560257589606;11520.423978801195;11590.94204528972;11763.647090586954;11799.410029498662;18714.71927921055;19024.52438689044;19057.237710491427;19122.713159302344;19153.808461915203;14815.185164815608;15036.624084397978;15184.164870931763;15324.463643772415;15349.232538372813;13080.542181023618;13203.471861125447;13716.794248086486;14147.151170929777;14342.354594043085;15615;15722.449714259947;15824.971376860467;15914.52256432309;16033.759493607371;10047;11859.991900688537;11959;12015.879841201826;12016.819747703617;12778.23330600167;15125.621859453162;15146.848531514544;15199.93600447958;15322.310496027929 05:10:44 INFO - PID 2014 | |3;mootools.html;1253.316942266465;1330.8203392542007;1434.1896828292022;1459.0589070049825;1475.5499572630345;10577.31247468915;13451.596452106429;13506.662333441669;13552.593422197337;13575.728485430285;13026.218426894393;13526.797098043537;13540.458381664721;13579.04946653732;13784.241866697328;2510.4728007118483;2575.369034586529;2577.510273048115;2580.6903171619433;2602.908898188564;2610.517054344952;2711.1459890134643;2719.4697034078363;2727.5363188258043;2727.740864617868;2246.9325920222336;2284.7372552156467;2347.6830627865193;2349.7297810751725;2632.7630513253803;2677.732226777326;2697.0021092195916;2699.2172270041665;2703.8648067596737;2704.337437327867;2602.9349266268305;2695.514807334679;2713.050432348682;2723.237493501823;2727.7817774577984;12835.87164128356;13113.934430327883;13135.934320328433;13174.802377964243;13221.603351899459;7229.4939354245225;7443.85112297751;7497.962510187469;7498.47510674253;7523.510971786816;4498.235299998975;4535.478419981711;4632.837850675211;4660.440747110335;4739.052189562067;1359.0894100952385;1380.0508284245154;1382.046387992282;1409.2319685771176;1429.1282317786142;19456.02719864001;19470.51323716904;19500.12249448764;19635.21459141647;19681.803181968142;6329.367063293634;6371.171747672773;6386.042093685985;6545.574537655038;6565.507586930999;13812.309384530734;13918.51285205013;13930.164190148624;13939.721205576034;13962.720745585031;7082.858342833115;7326.96336518314;7330.193678695339;7338.266173382725;7345.19202887682;5163.561097306696;5801.8549536261935;6365.14070600463;6709.161354830646;6795.524313298021;4744.620430365562;4839.951600484021;5056.6713163644245;5068.898622027613;5069.239614057847;1453.6147920800997;1464.8608382203674;1465.1648560320516;1480.0897448069359;1485.0569888120906;795.9124496305518;814.7596459044596;831.9251267385964;854.3208149521146;873.9949058582554;6883.242843287235;7125.643717814089;7153.213146553875;7171.748988785367;7218.989341492195;639.6705696566272;675.0886303490347;708.6704682322745;710.1903829634222;710.6340234779093;3981.3629819228786;4695.906081878344;4743.905121897543;4937.1606826839525;5274.868128296746;15668.373265069498;17956.19197136132;17980.460586182435;18136.91178529266;18272.725909111374;17644.823551764573;17723.822761772473;17791.911040444975;18095.81904180968;18266.26934922588;9301;9340.346175767767;9343.34596578233;9450.669226577036;9486.383385079947;6453.580517266362;7454.291842274975;7853.214678532101;7950.284474397332;8495.915040849512;15732.842671573138;15916.965397249141;16229.269682864535;16269.942453740468;16380.918095409446;15275.923620382271;16180;16205.59486012859;16575.922565033234;16726.749098763285;9218.631254749735;10581;10827.458627068458;10844.295120817122;10858.945705271422;9953.850692239477;11433.59982400612;11827.231229970024;12052.0960927929;12174.817377739164;9486.95256523713;9588.37675551087;9812.26408019387;9948.054934781185;9954.850677239992;8854.335924805537;9507.762305942406;9623.547699236855;9716.514174291398;9737.756556086155;7618.352440042659;9065.18413342776;9065.27477801761;9067.591958362022;9087.909120908971;6592.637404942582;6641.9704945731555;6837.62393068386;6854.971754236904;6879.931200687929;6694.725248790381;6842.8973565397555;7016.263292354433;7090.858182836418;7112.537685050455 05:10:44 INFO - PID 2014 | |4;prototype.html;412.430845433302;427.6236911517868;442.5375482620661;467.91343601433715;469.99060018799617;4874.171390863553;5443.700596467193;6348.777792777254;6366;6380.808575742723;4806.4232292125;6161.322254551996;6224;6244.313125556185;6264.780732674358;4680.204365257914;4749.548792864673;4757.762111894392;4768.785404656799;4792.736399498043;3660.8352624131976;4655.930161047587;4658.836940707076;4703.341532185496;4707.717536947777;4493.2136876046725;4651.465081515636;4676.672632915697;4705.247160454328;4712.764361781898;3903.4144878268203;4662.300654901758;4666.883327916796;4709.882252943669;4724.5039270876405;4081.8775436736946;4082.1835632873544;4106.1582375613225;4128.4632997710105;4135.462389889325;4988.351514303117;5913.8817223655715;5976.97011514944;6048.60684055535;6097.57316987811;5049.914268432268;5072.2391641253735;5609;5613.326400831886;5674.262345895048;6170.660613666247;6407.647579383132;6768.93231067688;6784.389404953528;6795.301174706324;3966.484357033567;4065.4714887064492;4157.438745769313;4729.62163026957;4737.265723812815;6063.787767428163;6486.935130648728;7227;7229.638518074128;7300.12398512182;3963.246983073207;4712.31671407644;4812.205986012269;4829.420469543607;4831.033793241296;4670.602998745145;4890.535399137148;4929.7781599828095;4930.531599498042;4935.580475659609;5154.433012368637;5447.373552041566;5629.943700563023;5636.18275350072;5637.774489020393;6769.836550521394;6781.525293229427;6796.524243302922;6811.591304521745;6841.657917104125;1536.178144692584;1585.5084923673699;1665.8334166583245;1671.557037385094;1678.152532970842;884.367677110856;1090.9781804363868;1117.8379135025539;1134.6879608107672;1155.7226265696172;6448.389900786252;6454.612723236621;6480.546361754727;6486.708098135595;6549;6952.096227490394;6981.825454363681;6991.6853741580735;6992.79021629352;7062.3996960258955;5224.346956630421;5777.7977770777825;6442.48884358026;6451.516136289703;6460.773872914519;3511.4549598176723;3574.3208790330273;3625.818709064536;3645.7630254033934;3647.1246900744163;6145.508359331243;7212.619835454392;7227.421806255487;7236.3487286143445;7315.817104572428;7198.208197098315;7228.204897461275;7229.710811567478;7230.855382892312;7252.419806415474;5929.962256605077;6375.394337537927;6976.058232138596;7018.438524918097;7220.927790722026;5804.709764511842;6210.5652604319075;6599.373059559334;6975.267596902252;7040.753573624898;5655.406182350794;5952.523798096228;6390.616563006234;6862.416694581017;6880.621565813928;7208.56748595086;7211.963940180475;7215.170255420694;7216.603086830275;7216.7834964951135;4018.8995275118355;4043.199392570974;4766.046790641816;4786.593139583175;4793.640476964172;5791.507721843522;6340.175777148941;6447.516436267205;6967.930320696728;6983.952407138767;5736.856578585569;6249.875022495994;6651.235107962646;6669.533132680665;6679.866402672016;5587.77648894056;5866.853328666817;6695.4308883745425;6698.799036028927;6704.396604305636;5441.619086663895;6477.1579694639395;6517.348265173445;6560.245571759118;6560.475161987125;6080.7567697291615;6403.135576697273;6706.128203333536;6708.362705542965;6718.648473034399;4868.318435418973;5986.4013598639785;6525.64108974011;6558.770443034471;6565.96717016431 05:10:44 INFO - PID 2014 | |5;yui.html;748.5134662469394;829.5644786487097;829.572770023438;861.840824091597;864.2912811494575;14541.92729036356;14623.780643290333;16872.662546749074;17132.486025419243;17455.301787928467;13514.729705405885;13558.118722283069;13601.591952241424;13611;14672.706545869076;674.2482132422339;723.9276072392745;793.6111305460312;979.8824141103078;980.745006298364;1012.9819531370962;1013.4223492609216;1016.1464369929295;1018.1294992781177;1021.0555236406332;1053.378506681058;1058.4654749351566;1059.7032830807348;1062.6227689170348;1062.6387028410338;950.4297421547051;1050.2228351020267;1050.5535147562293;1058.936463812166;1059.1262208677872;776.0260872604845;777.2344240922687;779.9899130625853;781.2656103262915;783.3693876429472;17028.318867245296;17264.050477223747;17353.913230433893;17553.29786808539;17602.11989400525;14122.646933826634;16602;17677.558061048578;17755.73366399505;18118.184681689356;12965.740685186338;14138.434462621482;14890.181040042793;14991;15011.474598389004;3598.298331825295;3631.2555926035097;3633.836477358499;3642.307961487309;3643.7267204959735;100023.9997600022;140723;145589.36026599313;154287;158580;13575.592732218049;13682.452701891812;14013.43946242139;14293.78559321611;14297.213653249171;26264.868675656882;26345.473090538082;26460;26788.732112679012;26808;7078.4691148163065;8121.79695507617;8126.065502467292;8149.062857771313;8151.062627797761;3958.485396898385;4002.2795896738867;4028.073543085048;4033.294173519681;4042.231975924624;4603.355530225771;4679;4684.391029166187;4684.625229981593;4690.015096829636;929.3169520402499;950.0452045694034;953.4994128082812;977.8337682593976;979.7305740921161;572.9971350143306;643.8892909730677;663.5355251324092;683.825624465768;687.2097088348339;11098.389588572545;12157.270563766202;12562.81155782664;12581.056420768478;12669.93665031669;575.9107338362561;605.9515238780887;650.8763334966342;668.5687731413211;673.0610797936948;5305.549028332635;5466.911195174714;5538.944610553924;5643.5767317451355;5932.525397968152;132201.67798322087;134365;134528.32735836259;138207;139421.60578394288;86530.56734716285;95125;103673;111882;114723;69414.9587756184;78709.60645196852;86510.13489865146;87040;92464.6130308046;10514.421706806199;10812.297200681931;10905.345679258953;11193.72015699614;11306.208565400671;38000.23999520049;46978;48315.75842120767;49752.75123624359;50022;64636;65020.34979650143;67831;68649.65675171593;69606.60786784135;14463;14861.182634955181;14966.551003469913;15178.544643660707;15180.089194648357;16122.919385402998;16207.594810129842;16314.836851631806;16318.918405407898;16462;14893.851061489246;15006.174660393346;15081.170535620646;15201.6199595011;15546.144962027198;14632.780508292171;14918.104913705212;15130.167840768863;15539.922300388425;15604.297806598957;14909.701805964038;15046.172460514777;15881.364745410054;16711;17388.82611173923;11388.259763115371;11774.411279436164;11831.763364732486;11842.111841612086;12153.635390938285;12042;12340.938295308466;12686.682832929251;13165.341732913508;13811.17132972025 05:10:44 INFO - PID 2014 | __end_tp_report 05:10:44 INFO - PID 2014 | __start_cc_report 05:10:44 INFO - PID 2014 | _x_x_mozilla_cycle_collect,460 05:10:44 INFO - PID 2014 | __end_cc_report 05:10:44 INFO - PID 2014 | __startTimestamp1497442244650__endTimestamp 05:10:44 INFO - PID 2014 | 05:10:44 INFO - PID 2014 | ------- Summary: start ------- 05:10:44 INFO - PID 2014 | Number of tests: 6 05:10:44 INFO - PID 2014 | 05:10:44 INFO - PID 2014 | [#0] dojo.html Cycles:165 Average:6108.24 Median:6351.11 stddev:766.59 (12.1%) stddev-sans-first:716.74 05:10:44 INFO - PID 2014 | Values: 2563.6 3235.3 3378.0 3486.8 3544.9 6054.9 6084.2 6131.6 6140.4 6146.9 5107.7 6119.3 6132.1 6232.5 6236.0 5426.3 5438.9 5447.9 5482.3 5539.1 5517.2 5552.5 5556.5 5570.1 5578.2 5302.1 5431.8 5464.9 5469.4 5488.6 5376.0 5411.8 5444.2 5464.8 5493.4 5337.1 5384.7 5426.2 5463.3 5471.4 6162.6 6209.3 6234.0 6255.1 6267.3 6347.8 6644.6 6655.9 6675.5 6684.1 6493.8 6606.0 6658.1 6664.4 6709.7 6250.2 6334.2 6348.2 6348.7 6350.2 6817.5 6900.0 6917.8 6923.0 6923.7 6346.9 6502.3 6513.7 6535.5 6538.2 6560.4 6567.5 6585.5 6589.5 6596.7 5367.4 6515.3 6548.3 6548.4 6564.1 6563.4 6568.5 6585.8 6596.6 6685.8 5289.3 5325.1 5326.6 5346.1 5355.6 3929.3 4694.8 4736.6 4747.8 4786.6 6182.4 6238.2 6347.8 6350.5 6364.6 6558.6 6564.4 6566.3 6577.7 6664.6 5560.3 6316.1 6343.1 6356.8 6370.2 4702.6 5950.3 5976.1 5984.2 5995.7 6904.3 6920.2 6960.7 6977.8 6995.3 6595.7 6637.0 6796.2 6810.5 6827.1 5655.5 6793.4 6836.9 6922.2 6932.5 6514.4 6606.0 6611.5 6615.5 6638.7 6701.1 6724.5 6724.7 6727.3 6733.5 6459.0 6468.6 6469.5 6470.6 6508.4 6296.5 6352.9 6377.0 6403.0 6448.4 6164.6 6274.4 6364.0 6369.1 6381.8 6425.5 6445.4 6461.1 6473.2 6474.4 6315.6 6337.1 6337.6 6351.1 6365.9 05:10:44 INFO - PID 2014 | 05:10:44 INFO - PID 2014 | [#1] ext.html Cycles:180 Average:43440.96 Median:345101.50 stddev:76774.08 (22.2%) stddev-sans-first:76922.39 05:10:44 INFO - PID 2014 | Values: 703.0 768.5 803.8 805.9 839.7 29995.7 36527.3 37248.6 37436.6 37476.8 37058.0 37417.8 37754.2 37770.4 37847.4 8043.6 8096.8 8153.5 8166.1 8204.2 7994.2 8096.8 8107.9 8123.5 8124.1 8050.0 8095.9 8101.7 8128.4 8134.6 6496.8 8070.6 8080.7 8089.2 8110.1 7900.4 7939.8 7949.9 7962.3 7970.9 35413.1 35425.3 35523.8 35636.3 35663.3 3478.3 3505.7 3508.8 3510.0 3510.9 28639.6 28895.7 28978.0 29012.6 29084.0 2983.5 3071.7 3075.9 3080.5 3090.2 219575.7 236722.0 255887.0 287280.3 315044.4 45677.3 46396.5 46586.0 46681.8 46750.8 48399.0 48686.3 48690.3 48805.8 48841.0 13420.0 13754.4 13764.3 13862.0 13924.9 11202.3 11304.8 11306.2 11388.1 11428.3 10753.1 10776.9 13129.9 13226.4 13273.6 5649.2 5743.1 5747.8 5751.6 5771.6 833.7 968.2 983.6 1042.6 1045.5 22375.6 22726.4 22784.3 22852.8 23676.3 1080.0 1110.0 1142.0 1151.3 1160.7 11663.0 11782.2 11815.6 12101.7 15814.8 341916.0 343404.0 344070.0 346133.0 346455.0 54182.5 54240.5 54419.5 54420.5 54693.5 1407.6 1473.9 1482.2 1490.4 1491.8 27520.0 28825.6 29350.6 29374.8 29438.1 37024.3 37138.0 37144.4 37172.0 37259.4 239241.0 241584.0 242493.0 266169.0 301098.0 31065.8 31329.7 31887.0 31989.5 32063.7 38690.4 39175.4 39248.6 39300.0 41603.4 26941.1 28029.3 28693.1 29077.6 29615.6 27973.2 28146.3 29250.9 29822.0 30192.4 34787.7 34817.3 34865.7 34885.7 34929.8 26352.7 28558.9 28804.0 31046.7 31670.0 18590.5 23181.8 23827.3 23840.5 23961.8 05:10:44 INFO - PID 2014 | 05:10:44 INFO - PID 2014 | [#2] jquery.html Cycles:180 Average:40133.83 Median:22752.10 stddev:100563.65 (442.0%) stddev-sans-first:100801.57 05:10:44 INFO - PID 2014 | Values: 430.3 485.1 493.0 503.1 522.4 21950.0 22660.4 22721.2 22744.3 22759.9 22422.2 24466.4 24825.8 24843.3 24852.4 6294.6 6468.2 6508.4 6515.5 6517.3 6373.1 6470.2 6495.6 6514.7 6524.6 6030.6 6236.8 6268.7 6426.2 6469.4 6228.3 6328.5 6332.0 6334.5 6351.5 5775.6 5782.3 5798.9 5801.8 5810.6 28001.9 29469.9 29852.9 29889.9 29946.9 2218.6 2291.6 2312.8 2317.7 2318.3 18827.4 19443.2 19497.3 19499.6 19599.6 1964.2 1986.0 2170.4 2188.8 2235.9 156995.0 160052.2 165656.0 191929.0 194502.0 14024.6 14178.4 14826.3 15199.6 15225.8 16692.5 19575.4 19624.8 19626.6 19665.3 8241.0 9389.9 9596.7 9707.9 9728.5 8050.2 8690.3 9059.2 9126.7 9243.3 7767.3 7944.0 9237.9 9312.4 9392.9 2231.0 2365.6 2573.7 2601.6 2604.6 918.5 954.3 985.7 987.1 994.2 19784.9 20982.5 21085.7 22000.6 22052.3 967.8 971.5 1024.5 1029.1 1030.3 10841.3 11020.8 13982.1 15082.2 15506.6 557724.0 582152.0 594536.0 594930.0 596401.0 156998.0 163160.0 166848.0 169047.0 170434.0 977.4 1221.4 1256.1 1259.3 1267.3 22663.1 22796.3 23665.1 23877.2 23904.3 70224.9 72407.6 73239.0 73350.6 73992.6 79137.2 81352.0 93788.0 96333.5 101413.0 10993.6 11520.4 11590.9 11763.6 11799.4 18714.7 19024.5 19057.2 19122.7 19153.8 14815.2 15036.6 15184.2 15324.5 15349.2 13080.5 13203.5 13716.8 14147.2 14342.4 15615.0 15722.4 15825.0 15914.5 16033.8 10047.0 11860.0 11959.0 12015.9 12016.8 12778.2 15125.6 15146.8 15199.9 15322.3 05:10:44 INFO - PID 2014 | 05:10:44 INFO - PID 2014 | [#3] mootools.html Cycles:180 Average:8029.16 Median:2727.64 stddev:5352.04 (196.2%) stddev-sans-first:5342.84 05:10:44 INFO - PID 2014 | Values: 1253.3 1330.8 1434.2 1459.1 1475.5 10577.3 13451.6 13506.7 13552.6 13575.7 13026.2 13526.8 13540.5 13579.0 13784.2 2510.5 2575.4 2577.5 2580.7 2602.9 2610.5 2711.1 2719.5 2727.5 2727.7 2246.9 2284.7 2347.7 2349.7 2632.8 2677.7 2697.0 2699.2 2703.9 2704.3 2602.9 2695.5 2713.1 2723.2 2727.8 12835.9 13113.9 13135.9 13174.8 13221.6 7229.5 7443.9 7498.0 7498.5 7523.5 4498.2 4535.5 4632.8 4660.4 4739.1 1359.1 1380.1 1382.0 1409.2 1429.1 19456.0 19470.5 19500.1 19635.2 19681.8 6329.4 6371.2 6386.0 6545.6 6565.5 13812.3 13918.5 13930.2 13939.7 13962.7 7082.9 7327.0 7330.2 7338.3 7345.2 5163.6 5801.9 6365.1 6709.2 6795.5 4744.6 4840.0 5056.7 5068.9 5069.2 1453.6 1464.9 1465.2 1480.1 1485.1 795.9 814.8 831.9 854.3 874.0 6883.2 7125.6 7153.2 7171.7 7219.0 639.7 675.1 708.7 710.2 710.6 3981.4 4695.9 4743.9 4937.2 5274.9 15668.4 17956.2 17980.5 18136.9 18272.7 17644.8 17723.8 17791.9 18095.8 18266.3 9301.0 9340.3 9343.3 9450.7 9486.4 6453.6 7454.3 7853.2 7950.3 8495.9 15732.8 15917.0 16229.3 16269.9 16380.9 15275.9 16180.0 16205.6 16575.9 16726.7 9218.6 10581.0 10827.5 10844.3 10858.9 9953.9 11433.6 11827.2 12052.1 12174.8 9487.0 9588.4 9812.3 9948.1 9954.9 8854.3 9507.8 9623.5 9716.5 9737.8 7618.4 9065.2 9065.3 9067.6 9087.9 6592.6 6642.0 6837.6 6855.0 6879.9 6694.7 6842.9 7016.3 7090.9 7112.5 05:10:44 INFO - PID 2014 | 05:10:44 INFO - PID 2014 | [#4] prototype.html Cycles:180 Average:5419.66 Median:5964.75 stddev:1682.35 (28.2%) stddev-sans-first:1644.55 05:10:44 INFO - PID 2014 | Values: 412.4 427.6 442.5 467.9 470.0 4874.2 5443.7 6348.8 6366.0 6380.8 4806.4 6161.3 6224.0 6244.3 6264.8 4680.2 4749.5 4757.8 4768.8 4792.7 3660.8 4655.9 4658.8 4703.3 4707.7 4493.2 4651.5 4676.7 4705.2 4712.8 3903.4 4662.3 4666.9 4709.9 4724.5 4081.9 4082.2 4106.2 4128.5 4135.5 4988.4 5913.9 5977.0 6048.6 6097.6 5049.9 5072.2 5609.0 5613.3 5674.3 6170.7 6407.6 6768.9 6784.4 6795.3 3966.5 4065.5 4157.4 4729.6 4737.3 6063.8 6486.9 7227.0 7229.6 7300.1 3963.2 4712.3 4812.2 4829.4 4831.0 4670.6 4890.5 4929.8 4930.5 4935.6 5154.4 5447.4 5629.9 5636.2 5637.8 6769.8 6781.5 6796.5 6811.6 6841.7 1536.2 1585.5 1665.8 1671.6 1678.2 884.4 1091.0 1117.8 1134.7 1155.7 6448.4 6454.6 6480.5 6486.7 6549.0 6952.1 6981.8 6991.7 6992.8 7062.4 5224.3 5777.8 6442.5 6451.5 6460.8 3511.5 3574.3 3625.8 3645.8 3647.1 6145.5 7212.6 7227.4 7236.3 7315.8 7198.2 7228.2 7229.7 7230.9 7252.4 5930.0 6375.4 6976.1 7018.4 7220.9 5804.7 6210.6 6599.4 6975.3 7040.8 5655.4 5952.5 6390.6 6862.4 6880.6 7208.6 7212.0 7215.2 7216.6 7216.8 4018.9 4043.2 4766.0 4786.6 4793.6 5791.5 6340.2 6447.5 6967.9 6984.0 5736.9 6249.9 6651.2 6669.5 6679.9 5587.8 5866.9 6695.4 6698.8 6704.4 5441.6 6477.2 6517.3 6560.2 6560.5 6080.8 6403.1 6706.1 6708.4 6718.6 4868.3 5986.4 6525.6 6558.8 6566.0 05:10:44 INFO - PID 2014 | 05:10:44 INFO - PID 2014 | [#5] yui.html Cycles:180 Average:23680.13 Median:16950.49 stddev:36054.52 (212.7%) stddev-sans-first:36114.55 05:10:44 INFO - PID 2014 | Values: 748.5 829.6 829.6 861.8 864.3 14541.9 14623.8 16872.7 17132.5 17455.3 13514.7 13558.1 13601.6 13611.0 14672.7 674.2 723.9 793.6 979.9 980.7 1013.0 1013.4 1016.1 1018.1 1021.1 1053.4 1058.5 1059.7 1062.6 1062.6 950.4 1050.2 1050.6 1058.9 1059.1 776.0 777.2 780.0 781.3 783.4 17028.3 17264.1 17353.9 17553.3 17602.1 14122.6 16602.0 17677.6 17755.7 18118.2 12965.7 14138.4 14890.2 14991.0 15011.5 3598.3 3631.3 3633.8 3642.3 3643.7 100024.0 140723.0 145589.4 154287.0 158580.0 13575.6 13682.5 14013.4 14293.8 14297.2 26264.9 26345.5 26460.0 26788.7 26808.0 7078.5 8121.8 8126.1 8149.1 8151.1 3958.5 4002.3 4028.1 4033.3 4042.2 4603.4 4679.0 4684.4 4684.6 4690.0 929.3 950.0 953.5 977.8 979.7 573.0 643.9 663.5 683.8 687.2 11098.4 12157.3 12562.8 12581.1 12669.9 575.9 606.0 650.9 668.6 673.1 5305.5 5466.9 5538.9 5643.6 5932.5 132201.7 134365.0 134528.3 138207.0 139421.6 86530.6 95125.0 103673.0 111882.0 114723.0 69415.0 78709.6 86510.1 87040.0 92464.6 10514.4 10812.3 10905.3 11193.7 11306.2 38000.2 46978.0 48315.8 49752.8 50022.0 64636.0 65020.3 67831.0 68649.7 69606.6 14463.0 14861.2 14966.6 15178.5 15180.1 16122.9 16207.6 16314.8 16318.9 16462.0 14893.9 15006.2 15081.2 15201.6 15546.1 14632.8 14918.1 15130.2 15539.9 15604.3 14909.7 15046.2 15881.4 16711.0 17388.8 11388.3 11774.4 11831.8 11842.1 12153.6 12042.0 12340.9 12686.7 13165.3 13811.2 05:10:44 INFO - PID 2014 | -------- Summary: end -------- 05:10:44 INFO - PID 2014 | 05:10:45 INFO - TEST-INFO | 2014: exit 0 05:10:45 INFO - TEST-OK | dromaeo_css | took 1142231ms 05:10:45 INFO - TEST-START | kraken 05:10:45 INFO - operating with platform_type : mac_ 05:10:45 INFO - Initialising browser for kraken test... 05:10:45 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:54483/getInfo.html -foreground -profile /var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/tmpAjNHhl/profile 05:10:45 INFO - TEST-INFO | started process 2022 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:54483/getInfo.html -foreground) 05:11:18 INFO - TEST-INFO | 2022: killed by SIGTERM 05:11:18 INFO - Browser initialized. 05:11:18 INFO - Running cycle 1/1 for kraken test... 05:11:18 INFO - TEST-INFO | started process 2026 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/tmpAjNHhl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:11:20 INFO - PID 2026 | 2017-06-14 05:11:20.782 plugin-container[2027:24213] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x483b, name = 'com.apple.tsm.portname' 05:11:20 INFO - PID 2026 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:11:20 INFO - PID 2026 | 2017-06-14 05:11:20.783 plugin-container[2027:24213] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f0b, name = 'com.apple.CFPasteboardClient' 05:11:20 INFO - PID 2026 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:11:20 INFO - PID 2026 | 2017-06-14 05:11:20.783 plugin-container[2027:24213] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:11:21 INFO - PID 2026 | 2017-06-14 05:11:21.612 plugin-container[2028:24262] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 05:11:21 INFO - PID 2026 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:11:21 INFO - PID 2026 | 2017-06-14 05:11:21.612 plugin-container[2028:24262] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0b, name = 'com.apple.CFPasteboardClient' 05:11:21 INFO - PID 2026 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:11:21 INFO - PID 2026 | 2017-06-14 05:11:21.612 plugin-container[2028:24262] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:11:30 INFO - PID 2026 | RSS: Main: 255025152 05:11:30 INFO - PID 2026 | 05:11:33 INFO - PID 2026 | Cycle 1(1): loaded http://localhost:54483/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54483/tests/kraken/driver.html?testName=audio-beat-detection) 05:11:34 INFO - PID 2026 | RSS: Main: 267100160 05:11:34 INFO - PID 2026 | 05:11:37 INFO - PID 2026 | Cycle 1(1): loaded http://localhost:54483/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54483/tests/kraken/driver.html?testName=audio-dft) 05:11:37 INFO - PID 2026 | RSS: Main: 278384640 05:11:37 INFO - PID 2026 | 05:11:41 INFO - PID 2026 | Cycle 1(1): loaded http://localhost:54483/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54483/tests/kraken/driver.html?testName=audio-fft) 05:11:42 INFO - PID 2026 | RSS: Main: 281341952 05:11:42 INFO - PID 2026 | 05:11:43 INFO - PID 2026 | Cycle 1(1): loaded http://localhost:54483/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54483/tests/kraken/driver.html?testName=audio-oscillator) 05:11:44 INFO - PID 2026 | RSS: Main: 282271744 05:11:44 INFO - PID 2026 | 05:11:46 INFO - PID 2026 | Cycle 1(1): loaded http://localhost:54483/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54483/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:11:47 INFO - PID 2026 | RSS: Main: 282636288 05:11:47 INFO - PID 2026 | 05:11:50 INFO - PID 2026 | Cycle 1(1): loaded http://localhost:54483/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54483/tests/kraken/driver.html?testName=imaging-darkroom) 05:11:50 INFO - PID 2026 | RSS: Main: 288350208 05:11:50 INFO - PID 2026 | 05:11:53 INFO - PID 2026 | Cycle 1(1): loaded http://localhost:54483/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54483/tests/kraken/driver.html?testName=imaging-desaturate) 05:11:54 INFO - PID 2026 | RSS: Main: 288387072 05:11:54 INFO - PID 2026 | 05:11:57 INFO - PID 2026 | Cycle 1(1): loaded http://localhost:54483/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54483/tests/kraken/driver.html?testName=json-parse-financial) 05:11:58 INFO - PID 2026 | RSS: Main: 288120832 05:11:58 INFO - PID 2026 | 05:11:59 INFO - PID 2026 | Cycle 1(1): loaded http://localhost:54483/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54483/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:12:00 INFO - PID 2026 | RSS: Main: 288169984 05:12:00 INFO - PID 2026 | 05:12:02 INFO - PID 2026 | Cycle 1(1): loaded http://localhost:54483/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54483/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:12:02 INFO - PID 2026 | RSS: Main: 288268288 05:12:02 INFO - PID 2026 | 05:12:04 INFO - PID 2026 | Cycle 1(1): loaded http://localhost:54483/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54483/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:12:05 INFO - PID 2026 | RSS: Main: 288198656 05:12:05 INFO - PID 2026 | 05:12:08 INFO - PID 2026 | Cycle 1(1): loaded http://localhost:54483/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54483/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:12:09 INFO - PID 2026 | RSS: Main: 288477184 05:12:09 INFO - PID 2026 | 05:12:12 INFO - PID 2026 | Cycle 1(1): loaded http://localhost:54483/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54483/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:12:12 INFO - PID 2026 | RSS: Main: 288768000 05:12:12 INFO - PID 2026 | 05:12:14 INFO - PID 2026 | Cycle 1(1): loaded http://localhost:54483/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54483/tests/kraken/driver.html?testName=ai-astar) 05:12:14 INFO - PID 2026 | RSS: Main: 290209792 05:12:14 INFO - PID 2026 | 05:12:14 INFO - PID 2026 | __start_tp_report 05:12:14 INFO - PID 2026 | _x_x_mozilla_page_load 05:12:14 INFO - PID 2026 | _x_x_mozilla_page_load_details 05:12:14 INFO - PID 2026 | |i|pagename|runs| 05:12:14 INFO - PID 2026 | |0;ai-astar;105;114;128;121;112;94;127;169;168;122 05:12:14 INFO - PID 2026 | |1;audio-beat-detection;181;147;136;155;145;162;132;109;156;110 05:12:14 INFO - PID 2026 | |2;audio-dft;204;225;246;281;274;199;202;194;196;200 05:12:14 INFO - PID 2026 | |3;audio-fft;77;82;74;125;59;59;59;67;101;57 05:12:14 INFO - PID 2026 | |4;audio-oscillator;79;101;145;102;89;87;110;72;73;112 05:12:14 INFO - PID 2026 | |5;imaging-gaussian-blur;88;93;88;98;134;122;91;89;89;89 05:12:14 INFO - PID 2026 | |6;imaging-darkroom;96;125;96;97;97;97;106;98;98;97 05:12:14 INFO - PID 2026 | |7;imaging-desaturate;92;94;92;102;91;91;93;91;109;91 05:12:14 INFO - PID 2026 | |8;json-parse-financial;47;48;51;75;75;70;75;72;74;74 05:12:14 INFO - PID 2026 | |9;json-stringify-tinderbox;56;54;63;59;58;63;57;55;57;59 05:12:14 INFO - PID 2026 | |10;stanford-crypto-aes;81;79;82;79;76;81;80;81;80;81 05:12:14 INFO - PID 2026 | |11;stanford-crypto-ccm;134;141;139;125;160;155;135;153;152;178 05:12:14 INFO - PID 2026 | |12;stanford-crypto-pbkdf2;174;179;180;179;181;181;179;180;163;201 05:12:14 INFO - PID 2026 | |13;stanford-crypto-sha256-iterative;69;62;68;66;63;64;65;57;84;86 05:12:14 INFO - PID 2026 | __end_tp_report 05:12:14 INFO - PID 2026 | __start_cc_report 05:12:14 INFO - PID 2026 | _x_x_mozilla_cycle_collect,1153 05:12:14 INFO - PID 2026 | __end_cc_report 05:12:14 INFO - PID 2026 | __startTimestamp1497442334733__endTimestamp 05:12:14 INFO - PID 2026 | 05:12:14 INFO - PID 2026 | ------- Summary: start ------- 05:12:14 INFO - PID 2026 | Number of tests: 14 05:12:14 INFO - PID 2026 | 05:12:14 INFO - PID 2026 | [#0] ai-astar Cycles:10 Average:126.00 Median:127.50 stddev:24.64 (19.3%) stddev-sans-first:24.93 05:12:14 INFO - PID 2026 | Values: 105.0 114.0 128.0 121.0 112.0 94.0 127.0 169.0 168.0 122.0 05:12:14 INFO - PID 2026 | 05:12:14 INFO - PID 2026 | [#1] audio-beat-detection Cycles:10 Average:143.30 Median:151.00 stddev:22.49 (14.9%) stddev-sans-first:19.28 05:12:14 INFO - PID 2026 | Values: 181.0 147.0 136.0 155.0 145.0 162.0 132.0 109.0 156.0 110.0 05:12:14 INFO - PID 2026 | 05:12:14 INFO - PID 2026 | [#2] audio-dft Cycles:10 Average:222.10 Median:214.50 stddev:33.28 (15.5%) stddev-sans-first:34.65 05:12:14 INFO - PID 2026 | Values: 204.0 225.0 246.0 281.0 274.0 199.0 202.0 194.0 196.0 200.0 05:12:14 INFO - PID 2026 | 05:12:14 INFO - PID 2026 | [#3] audio-fft Cycles:10 Average:76.00 Median:63.00 stddev:22.05 (35.0%) stddev-sans-first:23.39 05:12:14 INFO - PID 2026 | Values: 77.0 82.0 74.0 125.0 59.0 59.0 59.0 67.0 101.0 57.0 05:12:14 INFO - PID 2026 | 05:12:14 INFO - PID 2026 | [#4] audio-oscillator Cycles:10 Average:97.00 Median:72.50 stddev:22.18 (30.6%) stddev-sans-first:22.55 05:12:14 INFO - PID 2026 | Values: 79.0 101.0 145.0 102.0 89.0 87.0 110.0 72.0 73.0 112.0 05:12:14 INFO - PID 2026 | 05:12:14 INFO - PID 2026 | [#5] imaging-gaussian-blur Cycles:10 Average:98.10 Median:89.00 stddev:16.29 (18.3%) stddev-sans-first:16.87 05:12:14 INFO - PID 2026 | Values: 88.0 93.0 88.0 98.0 134.0 122.0 91.0 89.0 89.0 89.0 05:12:14 INFO - PID 2026 | 05:12:14 INFO - PID 2026 | [#6] imaging-darkroom Cycles:10 Average:100.70 Median:97.00 stddev:9.02 (9.3%) stddev-sans-first:9.40 05:12:14 INFO - PID 2026 | Values: 96.0 125.0 96.0 97.0 97.0 97.0 106.0 98.0 98.0 97.0 05:12:14 INFO - PID 2026 | 05:12:14 INFO - PID 2026 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:91.50 stddev:6.06 (6.6%) stddev-sans-first:6.35 05:12:14 INFO - PID 2026 | Values: 92.0 94.0 92.0 102.0 91.0 91.0 93.0 91.0 109.0 91.0 05:12:14 INFO - PID 2026 | 05:12:14 INFO - PID 2026 | [#8] json-parse-financial Cycles:10 Average:66.10 Median:74.00 stddev:12.17 (16.4%) stddev-sans-first:10.77 05:12:14 INFO - PID 2026 | Values: 47.0 48.0 51.0 75.0 75.0 70.0 75.0 72.0 74.0 74.0 05:12:14 INFO - PID 2026 | 05:12:14 INFO - PID 2026 | [#9] json-stringify-tinderbox Cycles:10 Average:58.10 Median:58.50 stddev:3.03 (5.2%) stddev-sans-first:3.12 05:12:14 INFO - PID 2026 | Values: 56.0 54.0 63.0 59.0 58.0 63.0 57.0 55.0 57.0 59.0 05:12:14 INFO - PID 2026 | 05:12:14 INFO - PID 2026 | [#10] stanford-crypto-aes Cycles:10 Average:80.00 Median:81.00 stddev:1.70 (2.1%) stddev-sans-first:1.76 05:12:14 INFO - PID 2026 | Values: 81.0 79.0 82.0 79.0 76.0 81.0 80.0 81.0 80.0 81.0 05:12:14 INFO - PID 2026 | 05:12:14 INFO - PID 2026 | [#11] stanford-crypto-ccm Cycles:10 Average:147.20 Median:152.50 stddev:15.46 (10.1%) stddev-sans-first:15.64 05:12:14 INFO - PID 2026 | Values: 134.0 141.0 139.0 125.0 160.0 155.0 135.0 153.0 152.0 178.0 05:12:14 INFO - PID 2026 | 05:12:14 INFO - PID 2026 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.70 Median:180.00 stddev:9.25 (5.1%) stddev-sans-first:9.58 05:12:14 INFO - PID 2026 | Values: 174.0 179.0 180.0 179.0 181.0 181.0 179.0 180.0 163.0 201.0 05:12:14 INFO - PID 2026 | 05:12:14 INFO - PID 2026 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.40 Median:67.00 stddev:9.37 (14.0%) stddev-sans-first:9.94 05:12:14 INFO - PID 2026 | Values: 69.0 62.0 68.0 66.0 63.0 64.0 65.0 57.0 84.0 86.0 05:12:14 INFO - PID 2026 | -------- Summary: end -------- 05:12:14 INFO - PID 2026 | 05:12:15 INFO - TEST-INFO | 2026: exit 0 05:12:15 INFO - TEST-OK | kraken | took 90441ms 05:12:15 INFO - SUITE-END | took 1232s 05:12:15 INFO - Completed test suite (00:20:32) 05:12:15 INFO - javascript benchmark 05:12:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8154.114555544817, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2563.551378508761, 3235.320582677637, 3378.0, 3486.7559270851034, 3544.9291014179717, 6054.909176362347, 6084.178635884155, 6131.570790044688, 6140.416660417259, 6146.9242882495555, 5107.719075450848, 6119.296280927695, 6132.0801879718065, 6232.532560058003, 6236.0, 5426.348838139429, 5438.91841622375, 5447.863803404907, 5482.314710661168, 5539.113741801312, 5517.172424136392, 5552.472515111078, 5556.472135147154, 5570.108782594785, 5578.246936663539, 5302.072137375943, 5431.83704488864, 5464.9453505464835, 5469.371022332403, 5488.615796894219, 5376.032314183445, 5411.756470958816, 5444.210589464506, 5464.7540860660965, 5493.395726470085, 5337.092694241988, 5384.73076346185, 5426.186072089179, 5463.289772329612, 5471.37079235885, 6162.568620196588, 6209.316975132731, 6234.0, 6255.093011513351, 6267.341929097471, 6347.841303967438, 6644.568103073285, 6655.900161497581, 6675.46596272297, 6684.097646817666, 6493.772717954849, 6605.9669701651665, 6658.101156343929, 6664.400203981617, 6709.664516774191, 6250.187475628185, 6334.208223972004, 6348.238211414659, 6348.746050157988, 6350.237971443456, 6817.522773405814, 6899.965500172568, 6917.827054323581, 6922.965385173143, 6923.6884340204815, 6346.936530634634, 6502.349765023554, 6513.674316284167, 6535.477161827042, 6538.248101468297, 6560.376764207393, 6567.540272180997, 6585.539012269095, 6589.472842172615, 6596.736130554724, 5367.355917289872, 6515.315891831384, 6548.312427195171, 6548.377904099103, 6564.113844630913, 6563.3764792344655, 6568.540202185801, 6585.80242592723, 6596.604203747695, 6685.799426017227, 5289.259503669489, 5325.068113080196, 5326.600504962066, 5346.064438723208, 5355.598330125256, 3929.292727309112, 4694.835680751157, 4736.550027747358, 4747.7626118693925, 4786.617070634341, 6182.412670796267, 6238.189035425366, 6347.777827776005, 6350.491960643138, 6364.554481186365, 6558.606483610999, 6564.409203171737, 6566.343365663491, 6577.704003319862, 6664.633445160563, 5560.304961879765, 6316.052592111128, 6343.111964324999, 6356.77751278703, 6370.235571731422, 4702.600278976257, 5950.256217972754, 5976.073708575178, 5984.192134061933, 5995.730192141364, 6904.3095690430555, 6920.169579650374, 6960.686769095403, 6977.755778547727, 6995.300469952964, 6595.670216489252, 6637.0, 6796.218434880052, 6810.523263371715, 6827.078344423439, 5655.462731040545, 6793.388595026471, 6836.897446538206, 6922.169339679271, 6932.549384290005, 6514.446272066927, 6606.042123892104, 6611.471082313499, 6615.4530505754665, 6638.734450621921, 6701.0953521274005, 6724.49566282521, 6724.73101075971, 6727.327267273234, 6733.461323094239, 6458.967705161632, 6468.6442245676935, 6469.4824414045825, 6470.611763294218, 6508.446782023581, 6296.5277604178955, 6352.936470635419, 6376.968115159394, 6403.0395440682405, 6448.355164483514, 6164.568480206342, 6274.435300822948, 6363.968180159255, 6369.14016607752, 6381.840453988688, 6425.518086143465, 6445.387688169616, 6461.127747753993, 6473.2232132144445, 6474.449671777952, 6315.5894866833505, 6337.081123237012, 6337.6197428154455, 6351.110844481684, 6365.936340636535], "value": 6052.835957356147, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [702.971881124755, 768.5273556762593, 803.7548547692951, 805.907994667226, 839.680921249925, 29995.700042999564, 36527.26945461089, 37248.62751372489, 37436.62563374366, 37476.81261593691, 37058.0, 37417.81291093548, 37754.244915101684, 37770.43344349837, 37847.43228851563, 8043.597820109, 8096.825960235762, 8153.510789352628, 8166.142555031725, 8204.220599043112, 7994.160613135624, 8096.757097287091, 8107.878381824277, 8123.512589244635, 8124.146964568724, 8050.0, 8095.947526821505, 8101.675932962675, 8128.39037072219, 8134.633941472618, 6496.837579060514, 8070.636821343053, 8080.71717489885, 8089.191080891893, 8110.067342255657, 7900.446968712193, 7939.801504962365, 7949.880751788728, 7962.323202527792, 7970.920290797134, 35413.114672133146, 35425.29149416997, 35523.82238088793, 35636.28727425437, 35663.286734265166, 3478.286951175003, 3505.684488396031, 3508.8245587720767, 3510.0, 3510.859565617398, 28639.57040644392, 28895.71104288972, 28978.0, 29012.564811527845, 29084.0, 2983.4778913690016, 3071.7389021933163, 3075.938481230385, 3080.476319025771, 3090.1502086926052, 219575.70636440307, 236722.0, 255887.0, 287280.25439491094, 315044.42477787693, 45677.314840277424, 46396.536034639896, 46586.0, 46681.76659116751, 46750.766246168554, 48399.0, 48686.26970595444, 48690.26964595534, 48805.755971219914, 48841.0, 13420.0, 13754.449822007007, 13764.31178441074, 13862.0, 13924.930375348058, 11202.327860328407, 11304.773904521864, 11306.152038597138, 11388.145889058354, 11428.257163284527, 10753.085987691135, 10776.94611526953, 13129.868701313055, 13226.404811783494, 13273.601791946257, 5649.231876380626, 5743.052396354555, 5747.798827041117, 5751.626144300607, 5771.575938106005, 833.6998680475024, 968.1915600473683, 983.6262220356219, 1042.629866397418, 1045.4563626913962, 22375.552488950456, 22726.431839204153, 22784.31647050591, 22852.771472285065, 23676.289711308687, 1079.983800242997, 1110.0130765928898, 1141.9828702569469, 1151.2516864038441, 1160.6982184632056, 11663.0, 11782.175247732748, 11815.645530634094, 12101.69745756363, 15814.841851581337, 341916.0, 343404.0, 344070.0, 346133.0, 346455.0, 54182.45817541853, 54240.45759542433, 54419.45580544223, 54420.45579544233, 54693.45306546963, 1407.5917983784793, 1473.9257177194, 1482.1922052481423, 1490.3889405343757, 1491.8284397294233, 27520.0, 28825.56761648535, 29350.559741603465, 29374.825007000334, 29438.11685649434, 37024.25951481009, 37138.0, 37144.44283335698, 37172.0, 37259.44110838394, 239241.0, 241584.0, 242493.0, 266169.0, 301098.0, 31065.8446707765, 31329.686703132676, 31887.0, 31989.520157198127, 32063.67936320607, 38690.41964370593, 39175.41236881506, 39248.60751392449, 39300.0, 41603.37594936139, 26941.0570630027, 28029.299267518476, 28693.13920582386, 29077.563836542045, 29615.555766663947, 27973.160805175878, 28146.29634259078, 29250.853745731136, 29822.0, 30192.396152077275, 34787.652123479456, 34817.30365392729, 34865.65134348624, 34885.65114348824, 34929.825350873085, 26352.736472635796, 28558.857205713837, 28804.0, 31046.689533105284, 31670.049898503083, 18590.53523661919, 23181.768182318636, 23827.285181444586, 23840.52318953646, 23961.760382395954], "value": 16853.822438873263, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [430.2578052858818, 485.1267718107406, 492.9605631549474, 503.09191908604964, 522.3835873669069, 21949.99999999998, 22660.3790550599, 22721.204757833457, 22744.31767046987, 22759.886200568995, 22422.215222467217, 24466.3883402915, 24825.75174248257, 24843.254702358914, 24852.378690532747, 6294.559380843331, 6468.191476065492, 6508.381703738138, 6515.511336649746, 6517.283098859121, 6373.139626150457, 6470.191226096738, 6495.642739649317, 6514.739410423577, 6524.575902566342, 6030.577859549833, 6236.844078898019, 6268.65522396268, 6426.196725409324, 6469.353064693517, 6228.346023667517, 6328.462080723098, 6332.0, 6334.461570766513, 6351.491880649537, 5775.5668324875805, 5782.3350314713925, 5798.9130163047585, 5801.796937107223, 5810.5932584719085, 28001.85999070012, 29469.852650736608, 29852.850735746182, 29889.850550747105, 29946.850265748533, 2218.611742944994, 2291.576058429196, 2312.8496647717843, 2317.721873375189, 2318.258157389636, 18827.435176944575, 19443.222271109138, 19497.317593884287, 19499.610007799907, 19599.608007839764, 1964.1946801811332, 1985.9602807943904, 2170.3597438755605, 2188.7701791311842, 2235.8770267635273, 156995.0, 160052.19973900056, 165656.0, 191929.04035479872, 194502.0, 14024.579262622137, 14178.432862685377, 14826.258687065603, 15199.62000949963, 15225.847741522442, 16692.499225023268, 19575.412737617895, 19624.803751962296, 19626.607467850565, 19665.311714090225, 8241.0, 9389.859152112724, 9596.66411675602, 9707.854382184272, 9728.513574321256, 8050.194980501903, 8690.304775618062, 9059.184673379295, 9126.726198213932, 9243.26053915685, 7767.262110099531, 7943.960280198562, 9237.90762092384, 9312.44125352481, 9392.8591071134, 2230.9553808924056, 2365.5978483657477, 2573.6782902137234, 2601.583746600573, 2604.5832666773226, 918.4902379179485, 954.2509130332655, 985.6845809340942, 987.1313244344931, 994.2394068537575, 19784.882280049565, 20982.47543811417, 21085.68371474429, 22000.559988800454, 22052.338429847132, 967.7677357434166, 971.455984648599, 1024.5184763161303, 1029.120102312527, 1030.2685093583489, 10841.349519028883, 11020.779584408427, 13982.091164074303, 15082.170480623452, 15506.612334691497, 557724.0, 582152.0, 594536.0, 594930.0, 596401.0, 156998.0, 163160.0, 166848.0, 169047.0, 170434.0, 977.4184360305607, 1221.419825582841, 1256.0767835640797, 1259.3073809404973, 1267.283984548727, 22663.093476260765, 22796.316110516713, 23665.053397863892, 23877.16429924944, 23904.282871513882, 70224.94662579964, 72407.63796181197, 73239.0, 73350.63324683343, 73992.63003684947, 79137.20862791299, 81352.0, 93788.0, 96333.51833240788, 101413.0, 10993.560257589606, 11520.423978801195, 11590.94204528972, 11763.647090586954, 11799.410029498662, 18714.71927921055, 19024.52438689044, 19057.237710491427, 19122.713159302344, 19153.808461915203, 14815.185164815608, 15036.624084397978, 15184.164870931763, 15324.463643772415, 15349.232538372813, 13080.542181023618, 13203.471861125447, 13716.794248086486, 14147.151170929777, 14342.354594043085, 15615.0, 15722.449714259947, 15824.971376860467, 15914.52256432309, 16033.759493607371, 10047.0, 11859.991900688537, 11959.0, 12015.879841201826, 12016.819747703617, 12778.23330600167, 15125.621859453162, 15146.848531514544, 15199.93600447958, 15322.310496027929], "value": 11782.871121899729, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1253.316942266465, 1330.8203392542007, 1434.1896828292022, 1459.0589070049825, 1475.5499572630345, 10577.31247468915, 13451.596452106429, 13506.662333441669, 13552.593422197337, 13575.728485430285, 13026.218426894393, 13526.797098043537, 13540.458381664721, 13579.04946653732, 13784.241866697328, 2510.4728007118483, 2575.369034586529, 2577.510273048115, 2580.6903171619433, 2602.908898188564, 2610.517054344952, 2711.1459890134643, 2719.4697034078363, 2727.5363188258043, 2727.740864617868, 2246.9325920222336, 2284.7372552156467, 2347.6830627865193, 2349.7297810751725, 2632.7630513253803, 2677.732226777326, 2697.0021092195916, 2699.2172270041665, 2703.8648067596737, 2704.337437327867, 2602.9349266268305, 2695.514807334679, 2713.050432348682, 2723.237493501823, 2727.7817774577984, 12835.87164128356, 13113.934430327883, 13135.934320328433, 13174.802377964243, 13221.603351899459, 7229.4939354245225, 7443.85112297751, 7497.962510187469, 7498.47510674253, 7523.510971786816, 4498.235299998975, 4535.478419981711, 4632.837850675211, 4660.440747110335, 4739.052189562067, 1359.0894100952385, 1380.0508284245154, 1382.046387992282, 1409.2319685771176, 1429.1282317786142, 19456.02719864001, 19470.51323716904, 19500.12249448764, 19635.21459141647, 19681.803181968142, 6329.367063293634, 6371.171747672773, 6386.042093685985, 6545.574537655038, 6565.507586930999, 13812.309384530734, 13918.51285205013, 13930.164190148624, 13939.721205576034, 13962.720745585031, 7082.858342833115, 7326.96336518314, 7330.193678695339, 7338.266173382725, 7345.19202887682, 5163.561097306696, 5801.8549536261935, 6365.14070600463, 6709.161354830646, 6795.524313298021, 4744.620430365562, 4839.951600484021, 5056.6713163644245, 5068.898622027613, 5069.239614057847, 1453.6147920800997, 1464.8608382203674, 1465.1648560320516, 1480.0897448069359, 1485.0569888120906, 795.9124496305518, 814.7596459044596, 831.9251267385964, 854.3208149521146, 873.9949058582554, 6883.242843287235, 7125.643717814089, 7153.213146553875, 7171.748988785367, 7218.989341492195, 639.6705696566272, 675.0886303490347, 708.6704682322745, 710.1903829634222, 710.6340234779093, 3981.3629819228786, 4695.906081878344, 4743.905121897543, 4937.1606826839525, 5274.868128296746, 15668.373265069498, 17956.19197136132, 17980.460586182435, 18136.91178529266, 18272.725909111374, 17644.823551764573, 17723.822761772473, 17791.911040444975, 18095.81904180968, 18266.26934922588, 9301.0, 9340.346175767767, 9343.34596578233, 9450.669226577036, 9486.383385079947, 6453.580517266362, 7454.291842274975, 7853.214678532101, 7950.284474397332, 8495.915040849512, 15732.842671573138, 15916.965397249141, 16229.269682864535, 16269.942453740468, 16380.918095409446, 15275.923620382271, 16180.0, 16205.59486012859, 16575.922565033234, 16726.749098763285, 9218.631254749735, 10581.0, 10827.458627068458, 10844.295120817122, 10858.945705271422, 9953.850692239477, 11433.59982400612, 11827.231229970024, 12052.0960927929, 12174.817377739164, 9486.95256523713, 9588.37675551087, 9812.26408019387, 9948.054934781185, 9954.850677239992, 8854.335924805537, 9507.762305942406, 9623.547699236855, 9716.514174291398, 9737.756556086155, 7618.352440042659, 9065.18413342776, 9065.27477801761, 9067.591958362022, 9087.909120908971, 6592.637404942582, 6641.9704945731555, 6837.62393068386, 6854.971754236904, 6879.931200687929, 6694.725248790381, 6842.8973565397555, 7016.263292354433, 7090.858182836418, 7112.537685050455], "value": 5894.73406643902, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [412.430845433302, 427.6236911517868, 442.5375482620661, 467.91343601433715, 469.99060018799617, 4874.171390863553, 5443.700596467193, 6348.777792777254, 6366.0, 6380.808575742723, 4806.4232292125, 6161.322254551996, 6224.0, 6244.313125556185, 6264.780732674358, 4680.204365257914, 4749.548792864673, 4757.762111894392, 4768.785404656799, 4792.736399498043, 3660.8352624131976, 4655.930161047587, 4658.836940707076, 4703.341532185496, 4707.717536947777, 4493.2136876046725, 4651.465081515636, 4676.672632915697, 4705.247160454328, 4712.764361781898, 3903.4144878268203, 4662.300654901758, 4666.883327916796, 4709.882252943669, 4724.5039270876405, 4081.8775436736946, 4082.1835632873544, 4106.1582375613225, 4128.4632997710105, 4135.462389889325, 4988.351514303117, 5913.8817223655715, 5976.97011514944, 6048.60684055535, 6097.57316987811, 5049.914268432268, 5072.2391641253735, 5609.0, 5613.326400831886, 5674.262345895048, 6170.660613666247, 6407.647579383132, 6768.93231067688, 6784.389404953528, 6795.301174706324, 3966.484357033567, 4065.4714887064492, 4157.438745769313, 4729.62163026957, 4737.265723812815, 6063.787767428163, 6486.935130648728, 7227.0, 7229.638518074128, 7300.12398512182, 3963.246983073207, 4712.31671407644, 4812.205986012269, 4829.420469543607, 4831.033793241296, 4670.602998745145, 4890.535399137148, 4929.7781599828095, 4930.531599498042, 4935.580475659609, 5154.433012368637, 5447.373552041566, 5629.943700563023, 5636.18275350072, 5637.774489020393, 6769.836550521394, 6781.525293229427, 6796.524243302922, 6811.591304521745, 6841.657917104125, 1536.178144692584, 1585.5084923673699, 1665.8334166583245, 1671.557037385094, 1678.152532970842, 884.367677110856, 1090.9781804363868, 1117.8379135025539, 1134.6879608107672, 1155.7226265696172, 6448.389900786252, 6454.612723236621, 6480.546361754727, 6486.708098135595, 6549.0, 6952.096227490394, 6981.825454363681, 6991.6853741580735, 6992.79021629352, 7062.3996960258955, 5224.346956630421, 5777.7977770777825, 6442.48884358026, 6451.516136289703, 6460.773872914519, 3511.4549598176723, 3574.3208790330273, 3625.818709064536, 3645.7630254033934, 3647.1246900744163, 6145.508359331243, 7212.619835454392, 7227.421806255487, 7236.3487286143445, 7315.817104572428, 7198.208197098315, 7228.204897461275, 7229.710811567478, 7230.855382892312, 7252.419806415474, 5929.962256605077, 6375.394337537927, 6976.058232138596, 7018.438524918097, 7220.927790722026, 5804.709764511842, 6210.5652604319075, 6599.373059559334, 6975.267596902252, 7040.753573624898, 5655.406182350794, 5952.523798096228, 6390.616563006234, 6862.416694581017, 6880.621565813928, 7208.56748595086, 7211.963940180475, 7215.170255420694, 7216.603086830275, 7216.7834964951135, 4018.8995275118355, 4043.199392570974, 4766.046790641816, 4786.593139583175, 4793.640476964172, 5791.507721843522, 6340.175777148941, 6447.516436267205, 6967.930320696728, 6983.952407138767, 5736.856578585569, 6249.875022495994, 6651.235107962646, 6669.533132680665, 6679.866402672016, 5587.77648894056, 5866.853328666817, 6695.4308883745425, 6698.799036028927, 6704.396604305636, 5441.619086663895, 6477.1579694639395, 6517.348265173445, 6560.245571759118, 6560.475161987125, 6080.7567697291615, 6403.135576697273, 6706.128203333536, 6708.362705542965, 6718.648473034399, 4868.318435418973, 5986.4013598639785, 6525.64108974011, 6558.770443034471, 6565.96717016431], "value": 4913.330739851715, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [748.5134662469394, 829.5644786487097, 829.572770023438, 861.840824091597, 864.2912811494575, 14541.92729036356, 14623.780643290333, 16872.662546749074, 17132.486025419243, 17455.301787928467, 13514.729705405885, 13558.118722283069, 13601.591952241424, 13611.0, 14672.706545869076, 674.2482132422339, 723.9276072392745, 793.6111305460312, 979.8824141103078, 980.745006298364, 1012.9819531370962, 1013.4223492609216, 1016.1464369929295, 1018.1294992781177, 1021.0555236406332, 1053.378506681058, 1058.4654749351566, 1059.7032830807348, 1062.6227689170348, 1062.6387028410338, 950.4297421547051, 1050.2228351020267, 1050.5535147562293, 1058.936463812166, 1059.1262208677872, 776.0260872604845, 777.2344240922687, 779.9899130625853, 781.2656103262915, 783.3693876429472, 17028.318867245296, 17264.050477223747, 17353.913230433893, 17553.29786808539, 17602.11989400525, 14122.646933826634, 16602.0, 17677.558061048578, 17755.73366399505, 18118.184681689356, 12965.740685186338, 14138.434462621482, 14890.181040042793, 14991.0, 15011.474598389004, 3598.298331825295, 3631.2555926035097, 3633.836477358499, 3642.307961487309, 3643.7267204959735, 100023.9997600022, 140723.0, 145589.36026599313, 154287.0, 158580.0, 13575.592732218049, 13682.452701891812, 14013.43946242139, 14293.78559321611, 14297.213653249171, 26264.868675656882, 26345.473090538082, 26460.0, 26788.732112679012, 26808.0, 7078.4691148163065, 8121.79695507617, 8126.065502467292, 8149.062857771313, 8151.062627797761, 3958.485396898385, 4002.2795896738867, 4028.073543085048, 4033.294173519681, 4042.231975924624, 4603.355530225771, 4679.0, 4684.391029166187, 4684.625229981593, 4690.015096829636, 929.3169520402499, 950.0452045694034, 953.4994128082812, 977.8337682593976, 979.7305740921161, 572.9971350143306, 643.8892909730677, 663.5355251324092, 683.825624465768, 687.2097088348339, 11098.389588572545, 12157.270563766202, 12562.81155782664, 12581.056420768478, 12669.93665031669, 575.9107338362561, 605.9515238780887, 650.8763334966342, 668.5687731413211, 673.0610797936948, 5305.549028332635, 5466.911195174714, 5538.944610553924, 5643.5767317451355, 5932.525397968152, 132201.67798322087, 134365.0, 134528.32735836259, 138207.0, 139421.60578394288, 86530.56734716285, 95125.0, 103673.0, 111882.0, 114723.0, 69414.9587756184, 78709.60645196852, 86510.13489865146, 87040.0, 92464.6130308046, 10514.421706806199, 10812.297200681931, 10905.345679258953, 11193.72015699614, 11306.208565400671, 38000.23999520049, 46978.0, 48315.75842120767, 49752.75123624359, 50022.0, 64636.0, 65020.34979650143, 67831.0, 68649.65675171593, 69606.60786784135, 14463.0, 14861.182634955181, 14966.551003469913, 15178.544643660707, 15180.089194648357, 16122.919385402998, 16207.594810129842, 16314.836851631806, 16318.918405407898, 16462.0, 14893.851061489246, 15006.174660393346, 15081.170535620646, 15201.6199595011, 15546.144962027198, 14632.780508292171, 14918.104913705212, 15130.167840768863, 15539.922300388425, 15604.297806598957, 14909.701805964038, 15046.172460514777, 15881.364745410054, 16711.0, 17388.82611173923, 11388.259763115371, 11774.411279436164, 11831.763364732486, 11842.111841612086, 12153.635390938285, 12042.0, 12340.938295308466, 12686.682832929251, 13165.341732913508, 13811.17132972025], "value": 8442.748982121104, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1557.3000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 114.0, 128.0, 121.0, 112.0, 94.0, 127.0, 169.0, 168.0, 122.0], "value": 126.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 147.0, 136.0, 155.0, 145.0, 162.0, 132.0, 109.0, 156.0, 110.0], "value": 143.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [204.0, 225.0, 246.0, 281.0, 274.0, 199.0, 202.0, 194.0, 196.0, 200.0], "value": 222.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 82.0, 74.0, 125.0, 59.0, 59.0, 59.0, 67.0, 101.0, 57.0], "value": 76.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 101.0, 145.0, 102.0, 89.0, 87.0, 110.0, 72.0, 73.0, 112.0], "value": 97.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 93.0, 88.0, 98.0, 134.0, 122.0, 91.0, 89.0, 89.0, 89.0], "value": 98.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 125.0, 96.0, 97.0, 97.0, 97.0, 106.0, 98.0, 98.0, 97.0], "value": 100.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 94.0, 92.0, 102.0, 91.0, 91.0, 93.0, 91.0, 109.0, 91.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [47.0, 48.0, 51.0, 75.0, 75.0, 70.0, 75.0, 72.0, 74.0, 74.0], "value": 66.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 63.0, 59.0, 58.0, 63.0, 57.0, 55.0, 57.0, 59.0], "value": 58.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 79.0, 82.0, 79.0, 76.0, 81.0, 80.0, 81.0, 80.0, 81.0], "value": 80.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 141.0, 139.0, 125.0, 160.0, 155.0, 135.0, 153.0, 152.0, 178.0], "value": 147.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 179.0, 180.0, 179.0, 181.0, 181.0, 179.0, 180.0, 163.0, 201.0], "value": 179.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 62.0, 68.0, 66.0, 63.0, 64.0, 65.0, 57.0, 84.0, 86.0], "value": 68.4, "unit": "score"}]}]} 05:12:16 INFO - Return code: 0 05:12:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:12:16 INFO - # TBPL SUCCESS # 05:12:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:12:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:12:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:12:16 INFO - 2017-06-14 05:12:16.123 screenresolution[2034:24691] starting screenresolution argv=screenresolution get 05:12:16 INFO - 2017-06-14 05:12:16.126 screenresolution[2034:24691] Display 0: 1600x1200x32@60 05:12:16 INFO - 2017-06-14 05:12:16.132 screenresolution[2035:24693] starting screenresolution argv=screenresolution list 05:12:16 INFO - Available Modes on Display 0 05:12:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:12:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:12:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:12:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:12:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:12:16 INFO - Intel Iris: 05:12:16 INFO - Chipset Model: Intel Iris 05:12:16 INFO - Type: GPU 05:12:16 INFO - Bus: Built-In 05:12:16 INFO - VRAM (Dynamic, Max): 1536 MB 05:12:16 INFO - Vendor: Intel (0x8086) 05:12:16 INFO - Device ID: 0x0a2e 05:12:16 INFO - Revision ID: 0x0009 05:12:16 INFO - Displays: 05:12:16 INFO - Display: 05:12:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:12:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:12:16 INFO - Display Serial Number: 1600x1200 60 05:12:16 INFO - Main Display: Yes 05:12:16 INFO - Mirror: Off 05:12:16 INFO - Online: Yes 05:12:16 INFO - Rotation: Supported 05:12:16 INFO - Return code: 0 05:12:16 INFO - Running post-action listener: _resource_record_post_action 05:12:16 INFO - [mozharness: 2017-06-14 12:12:16.375303Z] Finished run-tests step (success) 05:12:16 INFO - Running post-run listener: _resource_record_post_run 05:12:16 INFO - Total resource usage - Wall time: 1255s; CPU: 54.0%; Read bytes: 1863680; Write bytes: 572999680; Read time: 18; Write time: 1776 05:12:16 INFO - TinderboxPrint: CPU usage
53.5% 05:12:16 INFO - TinderboxPrint: I/O read bytes / time
1,863,680 / 18 05:12:16 INFO - TinderboxPrint: I/O write bytes / time
572,999,680 / 1,776 05:12:16 INFO - TinderboxPrint: CPU idle
2,332.9 (46.5%) 05:12:16 INFO - TinderboxPrint: CPU system
1,021.1 (20.3%) 05:12:16 INFO - TinderboxPrint: CPU user
1,667.7 (33.2%) 05:12:16 INFO - TinderboxPrint: Swap in / out
254,435,328 / 0 05:12:16 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 248484352; Write bytes: 243627520; Read time: 17829; Write time: 520 05:12:16 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 05:12:16 INFO - run-tests - Wall time: 1234s; CPU: 54.0%; Read bytes: 962560; Write bytes: 314978816; Read time: 15; Write time: 1209 05:12:16 INFO - Running post-run listener: _upload_blobber_files 05:12:16 INFO - Blob upload gear active. 05:12:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:12:16 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 05:12:16 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'] 05:12:16 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 05:12:17 INFO - (blobuploader) - INFO - Open directory for files ... 05:12:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:12:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:12:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:12:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:12:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:12:18 INFO - (blobuploader) - INFO - Done attempting. 05:12:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:12:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:12:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:12:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:12:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:12:19 INFO - (blobuploader) - INFO - Done attempting. 05:12:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:12:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:12:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:12:19 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:12:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:12:19 INFO - (blobuploader) - INFO - Done attempting. 05:12:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:12:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:12:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:12:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:12:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:12:21 INFO - (blobuploader) - INFO - Done attempting. 05:12:21 INFO - (blobuploader) - INFO - Iteration through files over. 05:12:21 INFO - Return code: 0 05:12:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:12:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:12:21 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e59f6cce2fdf40cfd3ae38ae984cd99407b3a2a22ecb41278c194f108f4b6fa731b976f8707ed54da3c4b580f728e7597e2b76849d3ca0a3250ef0500051b5e8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f4f1f91319865326a4fcd09253bd9ea986b7e1432f574ee569c8fb54233eb004139a8558d72b66803ae6b5e2412668f992b83c5d29f47a04f9e44a042e5b8e8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c663911e493eaa28cd4a355597eafd39ec67af910ace7a0c39df404f5e57c5facaa565ee8e3ae035ed6f9b002d41ccfa8141dcc783ecf860fa909bc190bd68d4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/04a5782195bea3b88677c540104c8c6e683bc9b1c4395172192ab71b65bf7822f188a47221def4e0f4f672e710e7d21a9529f54742e9f428ad41314574ee3014"} 05:12:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:12:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:12:21 INFO - Contents: 05:12:21 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e59f6cce2fdf40cfd3ae38ae984cd99407b3a2a22ecb41278c194f108f4b6fa731b976f8707ed54da3c4b580f728e7597e2b76849d3ca0a3250ef0500051b5e8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f4f1f91319865326a4fcd09253bd9ea986b7e1432f574ee569c8fb54233eb004139a8558d72b66803ae6b5e2412668f992b83c5d29f47a04f9e44a042e5b8e8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c663911e493eaa28cd4a355597eafd39ec67af910ace7a0c39df404f5e57c5facaa565ee8e3ae035ed6f9b002d41ccfa8141dcc783ecf860fa909bc190bd68d4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/04a5782195bea3b88677c540104c8c6e683bc9b1c4395172192ab71b65bf7822f188a47221def4e0f4f672e710e7d21a9529f54742e9f428ad41314574ee3014"} 05:12:21 INFO - Running post-run listener: copy_logs_to_upload_dir 05:12:21 INFO - Copying logs to upload dir... 05:12:21 INFO - mkdir: /builds/slave/test/build/upload/logs 05:12:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1336.702999 ========= master_lag: -0.11 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 16 secs) (at 2017-06-14 05:12:21.895751) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-14 05:12:21.898963) ========= 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.RSevgheyQy/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.fmVI0oIJAO/Listeners TMPDIR=/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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/e59f6cce2fdf40cfd3ae38ae984cd99407b3a2a22ecb41278c194f108f4b6fa731b976f8707ed54da3c4b580f728e7597e2b76849d3ca0a3250ef0500051b5e8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f4f1f91319865326a4fcd09253bd9ea986b7e1432f574ee569c8fb54233eb004139a8558d72b66803ae6b5e2412668f992b83c5d29f47a04f9e44a042e5b8e8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c663911e493eaa28cd4a355597eafd39ec67af910ace7a0c39df404f5e57c5facaa565ee8e3ae035ed6f9b002d41ccfa8141dcc783ecf860fa909bc190bd68d4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/04a5782195bea3b88677c540104c8c6e683bc9b1c4395172192ab71b65bf7822f188a47221def4e0f4f672e710e7d21a9529f54742e9f428ad41314574ee3014"} build_url:https://queue.taskcluster.net/v1/task/MvKG-EcGSX-BAyzK5trNBg/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011193 build_url: 'https://queue.taskcluster.net/v1/task/MvKG-EcGSX-BAyzK5trNBg/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e59f6cce2fdf40cfd3ae38ae984cd99407b3a2a22ecb41278c194f108f4b6fa731b976f8707ed54da3c4b580f728e7597e2b76849d3ca0a3250ef0500051b5e8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f4f1f91319865326a4fcd09253bd9ea986b7e1432f574ee569c8fb54233eb004139a8558d72b66803ae6b5e2412668f992b83c5d29f47a04f9e44a042e5b8e8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c663911e493eaa28cd4a355597eafd39ec67af910ace7a0c39df404f5e57c5facaa565ee8e3ae035ed6f9b002d41ccfa8141dcc783ecf860fa909bc190bd68d4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/04a5782195bea3b88677c540104c8c6e683bc9b1c4395172192ab71b65bf7822f188a47221def4e0f4f672e710e7d21a9529f54742e9f428ad41314574ee3014"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-14 05:12:21.936858) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 05:12:21.937541) ========= 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.RSevgheyQy/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.fmVI0oIJAO/Listeners TMPDIR=/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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.005073 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 05:12:21.991385) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-14 05:12:21.992134) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-14 05:12:21.992862) ========= ========= Total master_lag: 0.07 =========