builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0086 starttime: 1495833605.44 results: success (0) revision: bce03a8eac301bcd9408b22333b1a67c3eaed057 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-26 14:20:05.444501) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-26 14:20:05.445125) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-26 14:20:05.445567) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-26 14:20:05.472982) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 14:20:05.473409) ========= 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.PVcOFd9JGp/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.56KAhhZ6ku/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-26 14:20:05-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.1M=0.001s 2017-05-26 14:20:05 (14.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.138854 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 14:20:05.635856) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 14:20:05.636183) ========= 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.PVcOFd9JGp/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.56KAhhZ6ku/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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.035277 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 14:20:05.692775) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-26 14:20:05.693132) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev bce03a8eac301bcd9408b22333b1a67c3eaed057 --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 bce03a8eac301bcd9408b22333b1a67c3eaed057 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.PVcOFd9JGp/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.56KAhhZ6ku/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-26 14:20:05,785 truncating revision to first 12 chars 2017-05-26 14:20:05,785 Setting DEBUG logging. 2017-05-26 14:20:05,785 attempt 1/10 2017-05-26 14:20:05,785 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/bce03a8eac30?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-26 14:20:06,942 unpacking tar archive at: mozilla-central-bce03a8eac30/testing/mozharness/ program finished with exit code 0 elapsedTime=1.438994 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-26 14:20:07.154227) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-26 14:20:07.154565) ========= script_repo_revision: bce03a8eac301bcd9408b22333b1a67c3eaed057 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-26 14:20:07.154940) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-26 14:20:07.155212) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-26 14:20:07.174080) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2017-05-26 14:20:07.174378) ========= /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.PVcOFd9JGp/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.56KAhhZ6ku/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 14:20:07 INFO - MultiFileLogger online at 20170526 14:20:07 in /builds/slave/test 14:20:07 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 14:20:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:20:07 INFO - {'append_to_log': False, 14:20:07 INFO - 'base_work_dir': '/builds/slave/test', 14:20:07 INFO - 'blob_upload_branch': 'Firefox', 14:20:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:20:07 INFO - 'branch': 'Firefox', 14:20:07 INFO - 'buildbot_json_path': 'buildprops.json', 14:20:07 INFO - 'config_files': ('talos/mac_config.py',), 14:20:07 INFO - 'default_actions': ('clobber', 14:20:07 INFO - 'read-buildbot-config', 14:20:07 INFO - 'download-and-extract', 14:20:07 INFO - 'populate-webroot', 14:20:07 INFO - 'create-virtualenv', 14:20:07 INFO - 'install', 14:20:07 INFO - 'run-tests'), 14:20:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:20:07 INFO - 'download_minidump_stackwalk': True, 14:20:07 INFO - 'download_symbols': 'ondemand', 14:20:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:20:07 INFO - 'tooltool.py': '/tools/tooltool.py', 14:20:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:20:07 INFO - '/tools/misc-python/virtualenv.py')}, 14:20:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:20:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:20:07 INFO - 'gecko_profile': False, 14:20:07 INFO - 'gecko_profile_interval': 0, 14:20:07 INFO - 'installer_path': 'installer.exe', 14:20:07 INFO - 'log_level': 'info', 14:20:07 INFO - 'log_name': 'talos', 14:20:07 INFO - 'log_to_console': True, 14:20:07 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:20:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:20:07 INFO - 'opt_config_files': (), 14:20:07 INFO - 'pip_index': False, 14:20:07 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:20:07 INFO - 'cmd': ('bash', 14:20:07 INFO - '-c', 14:20:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:20:07 INFO - 'enabled': True, 14:20:07 INFO - 'halt_on_failure': False, 14:20:07 INFO - 'name': 'check_screen_resolution'},), 14:20:07 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:20:07 INFO - 'cmd': ('bash', 14:20:07 INFO - '-c', 14:20:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:20:07 INFO - 'enabled': True, 14:20:07 INFO - 'halt_on_failure': False, 14:20:07 INFO - 'name': 'check_screen_resolution'},), 14:20:07 INFO - 'run_cmd_checks_enabled': True, 14:20:07 INFO - 'suite': 'dromaeojs-e10s', 14:20:07 INFO - 'system_bits': '32', 14:20:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:20:07 INFO - 'title': 't-yosemite-r7-0086', 14:20:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:20:07 INFO - 'use_talos_json': True, 14:20:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:20:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:20:07 INFO - 'work_dir': 'build'} 14:20:07 INFO - [mozharness: 2017-05-26 21:20:07.383178Z] Running clobber step. 14:20:07 INFO - Running pre-action listener: _resource_record_pre_action 14:20:07 INFO - Running main action method: clobber 14:20:07 INFO - rmtree: /builds/slave/test/build 14:20:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:20:09 INFO - Running post-action listener: _resource_record_post_action 14:20:09 INFO - [mozharness: 2017-05-26 21:20:09.880892Z] Finished clobber step (success) 14:20:09 INFO - [mozharness: 2017-05-26 21:20:09.881025Z] Running read-buildbot-config step. 14:20:09 INFO - Running pre-action listener: _resource_record_pre_action 14:20:09 INFO - Running main action method: read_buildbot_config 14:20:09 INFO - Using buildbot properties: 14:20:09 INFO - { 14:20:09 INFO - "project": "", 14:20:09 INFO - "product": "firefox", 14:20:09 INFO - "who": "ryanvm@gmail.com", 14:20:09 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 14:20:09 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 14:20:09 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 14:20:09 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 14:20:09 INFO - "stage_platform": "macosx64", 14:20:09 INFO - "basedir": "/builds/slave/test", 14:20:09 INFO - "buildnumber": 81, 14:20:09 INFO - "platform": "yosemite_r7", 14:20:09 INFO - "parent_task_id": "A2TejudkSj2FXizebDW0sQ", 14:20:09 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 14:20:09 INFO - "slavebuilddir": "test", 14:20:09 INFO - "taskId": "VkWr8YFNRX2O5R7dNaZoBQ", 14:20:09 INFO - "branch": "mozilla-central", 14:20:09 INFO - "script_repo_revision": "production", 14:20:09 INFO - "revision": "bce03a8eac301bcd9408b22333b1a67c3eaed057", 14:20:09 INFO - "slavename": "t-yosemite-r7-0086", 14:20:09 INFO - "repo_path": "mozilla-central" 14:20:09 INFO - } 14:20:09 INFO - Finding installer, test and symbols from parent task. 14:20:09 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VkWr8YFNRX2O5R7dNaZoBQ'}, attempt #1 14:20:10 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VkWr8YFNRX2O5R7dNaZoBQ'}, attempt #1 14:20:10 INFO - Task dependencies: OmyqSjvhQFuWOy-g4S-QeQ A2TejudkSj2FXizebDW0sQ 14:20:10 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A2TejudkSj2FXizebDW0sQ'}, attempt #1 14:20:11 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/A2TejudkSj2FXizebDW0sQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:20:11 INFO - Set test_url: https://queue.taskcluster.net/v1/task/A2TejudkSj2FXizebDW0sQ/artifacts/public/build/test_packages.json 14:20:11 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/A2TejudkSj2FXizebDW0sQ/artifacts/public/build/target.crashreporter-symbols.zip 14:20:11 INFO - Running post-action listener: _resource_record_post_action 14:20:11 INFO - [mozharness: 2017-05-26 21:20:11.261406Z] Finished read-buildbot-config step (success) 14:20:11 INFO - [mozharness: 2017-05-26 21:20:11.261523Z] Running download-and-extract step. 14:20:11 INFO - Running pre-action listener: _resource_record_pre_action 14:20:11 INFO - Running main action method: download_and_extract 14:20:11 INFO - mkdir: /builds/slave/test/build/tests 14:20:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:20:11 INFO - trying https://queue.taskcluster.net/v1/task/A2TejudkSj2FXizebDW0sQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 14:20:11 INFO - Downloading https://queue.taskcluster.net/v1/task/A2TejudkSj2FXizebDW0sQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 14:20:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A2TejudkSj2FXizebDW0sQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 14:20:12 INFO - Downloaded 1325 bytes. 14:20:12 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 14:20:12 INFO - Using the following test package requirements: 14:20:12 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:20:12 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 14:20:12 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 14:20:12 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:20:12 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 14:20:12 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:20:12 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 14:20:12 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:20:12 INFO - u'jsshell-mac.zip'], 14:20:12 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:20:12 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 14:20:12 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 14:20:12 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:20:12 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 14:20:12 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:20:12 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 14:20:12 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:20:12 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 14:20:12 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:20:12 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 14:20:12 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:20:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A2TejudkSj2FXizebDW0sQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 14:20:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A2TejudkSj2FXizebDW0sQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:20:12 INFO - Fetch https://queue.taskcluster.net/v1/task/A2TejudkSj2FXizebDW0sQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 14:20:13 INFO - Content-Length response header: 57627927 14:20:13 INFO - Bytes received: 57627927 14:20:32 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 14:20:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A2TejudkSj2FXizebDW0sQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 14:20:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A2TejudkSj2FXizebDW0sQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:20:32 INFO - Fetch https://queue.taskcluster.net/v1/task/A2TejudkSj2FXizebDW0sQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 14:20:34 INFO - Content-Length response header: 57627927 14:20:34 INFO - Bytes received: 57627927 14:20:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A2TejudkSj2FXizebDW0sQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 14:20:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A2TejudkSj2FXizebDW0sQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 14:20:51 INFO - Fetch https://queue.taskcluster.net/v1/task/A2TejudkSj2FXizebDW0sQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 14:20:53 INFO - Content-Length response header: 14411888 14:20:53 INFO - Bytes received: 14411888 14:20:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:20:53 INFO - trying https://queue.taskcluster.net/v1/task/A2TejudkSj2FXizebDW0sQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:20:53 INFO - Downloading https://queue.taskcluster.net/v1/task/A2TejudkSj2FXizebDW0sQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 14:20:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A2TejudkSj2FXizebDW0sQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 14:20:55 INFO - Downloaded 67279073 bytes. 14:20:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/A2TejudkSj2FXizebDW0sQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:20:55 INFO - mkdir: /builds/slave/test/properties 14:20:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:20:55 INFO - Writing to file /builds/slave/test/properties/build_url 14:20:55 INFO - Contents: 14:20:55 INFO - build_url:https://queue.taskcluster.net/v1/task/A2TejudkSj2FXizebDW0sQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:20:55 INFO - Running post-action listener: _resource_record_post_action 14:20:55 INFO - Running post-action listener: set_extra_try_arguments 14:20:55 INFO - [mozharness: 2017-05-26 21:20:55.497922Z] Finished download-and-extract step (success) 14:20:55 INFO - [mozharness: 2017-05-26 21:20:55.498042Z] Running populate-webroot step. 14:20:55 INFO - Running pre-action listener: _resource_record_pre_action 14:20:55 INFO - Running main action method: populate_webroot 14:20:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:20:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:20:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:20:55 INFO - '--tppagecycles', 14:20:55 INFO - '7'], 14:20:55 INFO - 'tests': ['tsvgm']}}, 14:20:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:20:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:20:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:20:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:20:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:20:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:20:55 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:20:55 INFO - 'talos_options': ['--disable-e10s'], 14:20:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:20:55 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:20:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:20:55 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:20:55 INFO - 'talos_options': ['--disable-e10s'], 14:20:55 INFO - 'tests': ['damp', 'tps']}, 14:20:55 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:20:55 INFO - 'tests': ['damp', 'tps']}, 14:20:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:20:55 INFO - 'tests': ['dromaeo_dom']}, 14:20:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:20:55 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:20:55 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 14:20:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 14:20:55 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 14:20:55 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 14:20:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:20:55 INFO - 'tests': ['a11yr', 14:20:55 INFO - 'ts_paint', 14:20:55 INFO - 'tpaint', 14:20:55 INFO - 'sessionrestore', 14:20:55 INFO - 'sessionrestore_no_auto_restore', 14:20:55 INFO - 'tabpaint']}, 14:20:55 INFO - 'other-e10s': {'tests': ['a11yr', 14:20:55 INFO - 'ts_paint', 14:20:55 INFO - 'tpaint', 14:20:55 INFO - 'sessionrestore', 14:20:55 INFO - 'sessionrestore_no_auto_restore', 14:20:55 INFO - 'tabpaint']}, 14:20:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:20:55 INFO - 'ts_paint', 14:20:55 INFO - 'tpaint', 14:20:55 INFO - 'sessionrestore', 14:20:55 INFO - 'sessionrestore_no_auto_restore', 14:20:55 INFO - 'tabpaint']}, 14:20:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:20:55 INFO - 'ts_paint', 14:20:55 INFO - 'tpaint', 14:20:55 INFO - 'sessionrestore', 14:20:55 INFO - 'sessionrestore_no_auto_restore', 14:20:55 INFO - 'tabpaint']}, 14:20:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:20:55 INFO - 'tests': ['a11yr', 14:20:55 INFO - 'ts_paint', 14:20:55 INFO - 'tpaint', 14:20:55 INFO - 'sessionrestore', 14:20:55 INFO - 'sessionrestore_no_auto_restore', 14:20:55 INFO - 'tabpaint']}, 14:20:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:20:55 INFO - 'tests': ['a11yr', 14:20:55 INFO - 'ts_paint', 14:20:55 INFO - 'tpaint', 14:20:55 INFO - 'sessionrestore', 14:20:55 INFO - 'sessionrestore_no_auto_restore', 14:20:55 INFO - 'tabpaint']}, 14:20:55 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 14:20:55 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 14:20:55 INFO - 'bloom_basic_ref']}, 14:20:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:20:55 INFO - 'tests': ['tsvgx', 14:20:55 INFO - 'tsvgr_opacity', 14:20:55 INFO - 'tart', 14:20:55 INFO - 'tscrollx', 14:20:55 INFO - 'cart', 14:20:55 INFO - 'tsvg_static']}, 14:20:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:20:55 INFO - 'tsvgr_opacity', 14:20:55 INFO - 'tart', 14:20:55 INFO - 'tscrollx', 14:20:55 INFO - 'cart', 14:20:55 INFO - 'tsvg_static']}, 14:20:55 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:20:55 INFO - 'talos_options': ['--disable-e10s'], 14:20:55 INFO - 'tests': ['tp5o']}, 14:20:55 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:20:55 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:20:55 INFO - 'talos_options': ['--disable-e10s', 14:20:55 INFO - '--xperf_path', 14:20:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:20:55 INFO - 'tests': ['tp5n']}, 14:20:55 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:20:55 INFO - 'talos_options': ['--xperf_path', 14:20:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:20:55 INFO - 'tests': ['tp5n']}}, 14:20:55 INFO - 'talos.zip': {'path': '', 14:20:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:20:55 INFO - Running post-action listener: _resource_record_post_action 14:20:55 INFO - [mozharness: 2017-05-26 21:20:55.519926Z] Finished populate-webroot step (success) 14:20:55 INFO - [mozharness: 2017-05-26 21:20:55.520062Z] Running create-virtualenv step. 14:20:55 INFO - Running pre-action listener: _resource_record_pre_action 14:20:55 INFO - Running main action method: create_virtualenv 14:20:55 INFO - Creating virtualenv /builds/slave/test/build/venv 14:20:55 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 14:20:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:20:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:20:55 INFO - Using real prefix '/tools/python27' 14:20:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:20:56 INFO - Installing distribute.............................................................................................................................................................................................done. 14:20:59 INFO - Installing pip.................done. 14:20:59 INFO - Return code: 0 14:20:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:20:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:20:59 INFO - Reading from file tmpfile_stdout 14:20:59 INFO - Output received: 14:20:59 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 14:20:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:20: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')]} 14:20:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:20: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')]} 14:20:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:20:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10352cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103530768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0a347fc40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10323cd30>, '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 0x1031f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103231be8>, '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.PVcOFd9JGp/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.56KAhhZ6ku/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:20:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:20:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:20:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PVcOFd9JGp/Render', 14:20:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:20:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:20:59 INFO - 'HOME': '/Users/cltbld', 14:20:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:20:59 INFO - 'LOGNAME': 'cltbld', 14:20:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:20:59 INFO - 'MOZ_NO_REMOTE': '1', 14:20:59 INFO - 'NO_EM_RESTART': '1', 14:20:59 INFO - 'PAGER': '/bin/cat', 14:20:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:20:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:20:59 INFO - 'PWD': '/builds/slave/test', 14:20:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:20:59 INFO - 'SHELL': '/bin/bash', 14:20:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.56KAhhZ6ku/Listeners', 14:20:59 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 14:20:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:20:59 INFO - 'USER': 'cltbld', 14:20:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:20:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:20:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:20:59 INFO - 'XPC_FLAGS': '0x0', 14:20:59 INFO - 'XPC_SERVICE_NAME': '0', 14:20:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:20:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:20:59 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 14:20:59 INFO - Cleaning up... 14:20:59 INFO - Return code: 0 14:20:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:20: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')]} 14:20:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:20: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')]} 14:20:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:20:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10352cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103530768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0a347fc40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10323cd30>, '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 0x1031f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103231be8>, '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.PVcOFd9JGp/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.56KAhhZ6ku/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:20:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:20:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:20:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PVcOFd9JGp/Render', 14:20:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:20:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:20:59 INFO - 'HOME': '/Users/cltbld', 14:20:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:20:59 INFO - 'LOGNAME': 'cltbld', 14:20:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:20:59 INFO - 'MOZ_NO_REMOTE': '1', 14:20:59 INFO - 'NO_EM_RESTART': '1', 14:20:59 INFO - 'PAGER': '/bin/cat', 14:20:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:20:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:20:59 INFO - 'PWD': '/builds/slave/test', 14:20:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:20:59 INFO - 'SHELL': '/bin/bash', 14:20:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.56KAhhZ6ku/Listeners', 14:20:59 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 14:20:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:20:59 INFO - 'USER': 'cltbld', 14:20:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:20:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:20:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:20:59 INFO - 'XPC_FLAGS': '0x0', 14:20:59 INFO - 'XPC_SERVICE_NAME': '0', 14:20:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:21:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:21:00 INFO - Downloading/unpacking psutil>=3.1.1 14:21:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:21:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:21:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:21:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:21:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:21:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:21:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:21:01 INFO - Installing collected packages: psutil 14:21:01 INFO - Running setup.py install for psutil 14:21:02 INFO - building 'psutil._psutil_osx' extension 14:21:02 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 14:21:02 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 14:21:02 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 14:21:02 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 14:21:02 INFO - building 'psutil._psutil_posix' extension 14:21:02 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 14:21:02 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:21:02 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:21:02 INFO - ^ 14:21:02 INFO - 1 warning generated. 14:21:02 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 14:21:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:21:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:21:03 INFO - Successfully installed psutil 14:21:03 INFO - Cleaning up... 14:21:03 INFO - Return code: 0 14:21:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:21:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:21:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:21:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:21:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:21: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10352cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103530768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0a347fc40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10323cd30>, '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 0x1031f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103231be8>, '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.PVcOFd9JGp/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.56KAhhZ6ku/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:21: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:21: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 mozsystemmonitor==0.3 14:21:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PVcOFd9JGp/Render', 14:21:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:21:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:21:03 INFO - 'HOME': '/Users/cltbld', 14:21:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:21:03 INFO - 'LOGNAME': 'cltbld', 14:21:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:21:03 INFO - 'MOZ_NO_REMOTE': '1', 14:21:03 INFO - 'NO_EM_RESTART': '1', 14:21:03 INFO - 'PAGER': '/bin/cat', 14:21:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:21:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:21:03 INFO - 'PWD': '/builds/slave/test', 14:21:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:21:03 INFO - 'SHELL': '/bin/bash', 14:21:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.56KAhhZ6ku/Listeners', 14:21:03 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 14:21:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:21:03 INFO - 'USER': 'cltbld', 14:21:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:21:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:21:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:21:03 INFO - 'XPC_FLAGS': '0x0', 14:21:03 INFO - 'XPC_SERVICE_NAME': '0', 14:21:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:21:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:21:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:21: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 14:21: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 14:21: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 14:21: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 14:21:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:21:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:21:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:21:06 INFO - Installing collected packages: mozsystemmonitor 14:21:06 INFO - Running setup.py install for mozsystemmonitor 14:21:06 INFO - Successfully installed mozsystemmonitor 14:21:06 INFO - Cleaning up... 14:21:06 INFO - Return code: 0 14:21:06 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:21:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:21:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:21:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:21:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:21:06 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 0x10352cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103530768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0a347fc40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10323cd30>, '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 0x1031f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103231be8>, '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.PVcOFd9JGp/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.56KAhhZ6ku/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:21:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:21:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 14:21:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PVcOFd9JGp/Render', 14:21:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:21:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:21:06 INFO - 'HOME': '/Users/cltbld', 14:21:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:21:06 INFO - 'LOGNAME': 'cltbld', 14:21:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:21:06 INFO - 'MOZ_NO_REMOTE': '1', 14:21:06 INFO - 'NO_EM_RESTART': '1', 14:21:06 INFO - 'PAGER': '/bin/cat', 14:21:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:21:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:21:06 INFO - 'PWD': '/builds/slave/test', 14:21:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:21:06 INFO - 'SHELL': '/bin/bash', 14:21:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.56KAhhZ6ku/Listeners', 14:21:06 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 14:21:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:21:06 INFO - 'USER': 'cltbld', 14:21:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:21:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:21:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:21:06 INFO - 'XPC_FLAGS': '0x0', 14:21:06 INFO - 'XPC_SERVICE_NAME': '0', 14:21:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:21:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:21:06 INFO - Downloading/unpacking jsonschema==2.5.1 14:21:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:21:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:21:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:21:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:21:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:21:08 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:21:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:21:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:21:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:21:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:21:08 INFO - Downloading functools32-3.2.3-2.tar.gz 14:21:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:21:08 INFO - warning: no files found matching '*.txt' 14:21:08 INFO - no previously-included directories found matching 'build' 14:21:08 INFO - no previously-included directories found matching 'dist' 14:21:08 INFO - no previously-included directories found matching '.git*' 14:21:08 INFO - Installing collected packages: jsonschema, functools32 14:21:08 INFO - Running setup.py install for functools32 14:21:08 INFO - warning: no files found matching '*.txt' 14:21:08 INFO - no previously-included directories found matching 'build' 14:21:08 INFO - no previously-included directories found matching 'dist' 14:21:08 INFO - no previously-included directories found matching '.git*' 14:21:08 INFO - Successfully installed jsonschema functools32 14:21:08 INFO - Cleaning up... 14:21:08 INFO - Return code: 0 14:21:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:21:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:21:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:21:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:21:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:21:08 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 0x10352cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103530768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0a347fc40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10323cd30>, '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 0x1031f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103231be8>, '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.PVcOFd9JGp/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.56KAhhZ6ku/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:21:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:21:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 14:21:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PVcOFd9JGp/Render', 14:21:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:21:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:21:08 INFO - 'HOME': '/Users/cltbld', 14:21:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:21:08 INFO - 'LOGNAME': 'cltbld', 14:21:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:21:08 INFO - 'MOZ_NO_REMOTE': '1', 14:21:08 INFO - 'NO_EM_RESTART': '1', 14:21:08 INFO - 'PAGER': '/bin/cat', 14:21:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:21:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:21:08 INFO - 'PWD': '/builds/slave/test', 14:21:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:21:08 INFO - 'SHELL': '/bin/bash', 14:21:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.56KAhhZ6ku/Listeners', 14:21:08 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 14:21:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:21:08 INFO - 'USER': 'cltbld', 14:21:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:21:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:21:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:21:08 INFO - 'XPC_FLAGS': '0x0', 14:21:08 INFO - 'XPC_SERVICE_NAME': '0', 14:21:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:21:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:21:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:21:08 INFO - Cleaning up... 14:21:08 INFO - Return code: 0 14:21:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:21:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:21:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:21:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:21:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:21:08 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 0x10352cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103530768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0a347fc40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10323cd30>, '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 0x1031f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103231be8>, '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.PVcOFd9JGp/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.56KAhhZ6ku/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:21:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:21:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:21:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PVcOFd9JGp/Render', 14:21:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:21:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:21:08 INFO - 'HOME': '/Users/cltbld', 14:21:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:21:08 INFO - 'LOGNAME': 'cltbld', 14:21:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:21:08 INFO - 'MOZ_NO_REMOTE': '1', 14:21:08 INFO - 'NO_EM_RESTART': '1', 14:21:08 INFO - 'PAGER': '/bin/cat', 14:21:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:21:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:21:08 INFO - 'PWD': '/builds/slave/test', 14:21:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:21:08 INFO - 'SHELL': '/bin/bash', 14:21:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.56KAhhZ6ku/Listeners', 14:21:08 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 14:21:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:21:08 INFO - 'USER': 'cltbld', 14:21:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:21:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:21:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:21:08 INFO - 'XPC_FLAGS': '0x0', 14:21:08 INFO - 'XPC_SERVICE_NAME': '0', 14:21:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:21:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:21:09 INFO - Downloading/unpacking blobuploader==1.2.4 14:21:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:21:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:21:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:21:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:21:10 INFO - Downloading blobuploader-1.2.4.tar.gz 14:21:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:21:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:21:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:21:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:21:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:21:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:21:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:21:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:21:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:21:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:21:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:21:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:21:11 INFO - Downloading docopt-0.6.1.tar.gz 14:21:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:21:11 INFO - Installing collected packages: blobuploader, requests, docopt 14:21:11 INFO - Running setup.py install for blobuploader 14:21:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:21:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:21:11 INFO - Running setup.py install for requests 14:21:12 INFO - Running setup.py install for docopt 14:21:12 INFO - Successfully installed blobuploader requests docopt 14:21:12 INFO - Cleaning up... 14:21:12 INFO - Return code: 0 14:21:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:21:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:21:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:21:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:21:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:21:12 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 0x10352cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103530768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0a347fc40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10323cd30>, '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 0x1031f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103231be8>, '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.PVcOFd9JGp/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.56KAhhZ6ku/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:21:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:21:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:21:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PVcOFd9JGp/Render', 14:21:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:21:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:21:12 INFO - 'HOME': '/Users/cltbld', 14:21:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:21:12 INFO - 'LOGNAME': 'cltbld', 14:21:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:21:12 INFO - 'MOZ_NO_REMOTE': '1', 14:21:12 INFO - 'NO_EM_RESTART': '1', 14:21:12 INFO - 'PAGER': '/bin/cat', 14:21:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:21:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:21:12 INFO - 'PWD': '/builds/slave/test', 14:21:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:21:12 INFO - 'SHELL': '/bin/bash', 14:21:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.56KAhhZ6ku/Listeners', 14:21:12 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 14:21:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:21:12 INFO - 'USER': 'cltbld', 14:21:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:21:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:21:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:21:12 INFO - 'XPC_FLAGS': '0x0', 14:21:12 INFO - 'XPC_SERVICE_NAME': '0', 14:21:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:21:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:21:12 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-2G18NN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:21:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:21:13 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-evX39q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:21:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:21:13 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-wMAecB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:21:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:21:13 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-fCvNFQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:21:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:21:13 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-Mh9h7M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:21:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:21:13 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-L3NHk0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:21:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:21:13 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-JiqEwx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:21:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:21:13 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-UremFo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:21:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:21:13 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-RQWcAm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:21:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:21:13 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-zo4f2Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:21:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:21:13 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-PWFYci-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:21:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:21:14 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-wCk5ET-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:21:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:21:14 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-3plD15-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:21:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:21:14 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-sb8Alj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:21:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:21:14 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-t4ipAS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:21:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:21:14 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-5SZEEb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:21:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:21:14 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-l436qY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:21:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:21:14 INFO - Running setup.py install for manifestparser 14:21:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:21:14 INFO - Running setup.py install for mozcrash 14:21:14 INFO - Running setup.py install for mozdebug 14:21:15 INFO - Running setup.py install for mozdevice 14:21:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:21:15 INFO - Running setup.py install for mozfile 14:21:15 INFO - Running setup.py install for mozhttpd 14:21:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:21:15 INFO - Running setup.py install for mozinfo 14:21:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:21:15 INFO - Running setup.py install for mozInstall 14:21:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:21:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:21:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:21:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:21:15 INFO - Running setup.py install for mozleak 14:21:15 INFO - Running setup.py install for mozlog 14:21:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:21:16 INFO - Running setup.py install for moznetwork 14:21:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:21:16 INFO - Running setup.py install for mozprocess 14:21:16 INFO - Running setup.py install for mozprofile 14:21:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:21:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:21:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:21:16 INFO - Running setup.py install for mozrunner 14:21:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:21:16 INFO - Running setup.py install for mozscreenshot 14:21:16 INFO - Running setup.py install for moztest 14:21:17 INFO - Running setup.py install for mozversion 14:21:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:21:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:21:17 INFO - Cleaning up... 14:21:17 INFO - Return code: 0 14:21:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:21: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')]} 14:21:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:21: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')]} 14:21:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:21:17 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 0x10352cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103530768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0a347fc40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10323cd30>, '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 0x1031f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103231be8>, '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.PVcOFd9JGp/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.56KAhhZ6ku/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:21:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:21:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:21:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PVcOFd9JGp/Render', 14:21:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:21:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:21:17 INFO - 'HOME': '/Users/cltbld', 14:21:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:21:17 INFO - 'LOGNAME': 'cltbld', 14:21:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:21:17 INFO - 'MOZ_NO_REMOTE': '1', 14:21:17 INFO - 'NO_EM_RESTART': '1', 14:21:17 INFO - 'PAGER': '/bin/cat', 14:21:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:21:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:21:17 INFO - 'PWD': '/builds/slave/test', 14:21:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:21:17 INFO - 'SHELL': '/bin/bash', 14:21:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.56KAhhZ6ku/Listeners', 14:21:17 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 14:21:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:21:17 INFO - 'USER': 'cltbld', 14:21:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:21:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:21:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:21:17 INFO - 'XPC_FLAGS': '0x0', 14:21:17 INFO - 'XPC_SERVICE_NAME': '0', 14:21:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:21:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:21:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:21:17 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-r5JGvi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:21:17 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 14:21:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:21:17 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-bwnKIG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:21:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:21:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:21:17 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-PD3EIL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:21:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:21:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:21:17 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-sa0wwb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:21:17 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)) 14:21:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:21:17 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-fpX9rD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:21:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 14:21:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:21:18 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-a3W0Db-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:21:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 14:21:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:21:18 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-oVrXaI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:21:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 14:21:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:21:18 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-iuywgH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:21:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 14:21:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:21:18 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-ocguaf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:21:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 14:21:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:21:18 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-6X83sU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:21:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:21:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:21:18 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-otYLLR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:21:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 14:21:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:21:18 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-sUNTbn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:21:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 14:21:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:21:18 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-TuWn8A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:21:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 14:21:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:21:18 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-sPHZSw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:21:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 14:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:21:19 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-uQqS2b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:21:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 14:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:21:19 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-fLfOot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:21:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 14:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:21:19 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-Lspwyw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:21:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 14:21:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:21:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:21:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:21:19 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)) 14:21:19 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)) 14:21:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:21:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:21:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:21:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:21:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:21:20 INFO - Downloading blessings-1.6.tar.gz 14:21:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:21:20 INFO - Installing collected packages: blessings 14:21:20 INFO - Running setup.py install for blessings 14:21:21 INFO - Successfully installed blessings 14:21:21 INFO - Cleaning up... 14:21:21 INFO - Return code: 0 14:21:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:21:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:21:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:21:21 INFO - Reading from file tmpfile_stdout 14:21:21 INFO - Current package versions: 14:21:21 INFO - blessings == 1.6 14:21:21 INFO - blobuploader == 1.2.4 14:21:21 INFO - docopt == 0.6.1 14:21:21 INFO - functools32 == 3.2.3-2 14:21:21 INFO - jsonschema == 2.5.1 14:21:21 INFO - manifestparser == 1.1 14:21:21 INFO - mozInstall == 1.12 14:21:21 INFO - mozcrash == 1.0 14:21:21 INFO - mozdebug == 0.1 14:21:21 INFO - mozdevice == 0.51 14:21:21 INFO - mozfile == 1.2 14:21:21 INFO - mozhttpd == 0.7 14:21:21 INFO - mozinfo == 0.9 14:21:21 INFO - mozleak == 0.1 14:21:21 INFO - mozlog == 3.4 14:21:21 INFO - moznetwork == 0.27 14:21:21 INFO - mozprocess == 0.25 14:21:21 INFO - mozprofile == 0.28 14:21:21 INFO - mozrunner == 6.13 14:21:21 INFO - mozscreenshot == 0.1 14:21:21 INFO - mozsystemmonitor == 0.3 14:21:21 INFO - moztest == 0.8 14:21:21 INFO - mozversion == 1.4 14:21:21 INFO - psutil == 3.1.1 14:21:21 INFO - requests == 1.2.3 14:21:21 INFO - wsgiref == 0.1.2 14:21:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:21:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:21:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:21:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:21:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:21:21 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 0x10352cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103530768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0a347fc40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10323cd30>, '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 0x1031f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103231be8>, '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.PVcOFd9JGp/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.56KAhhZ6ku/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:21:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 14:21:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:21:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PVcOFd9JGp/Render', 14:21:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:21:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:21:21 INFO - 'HOME': '/Users/cltbld', 14:21:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:21:21 INFO - 'LOGNAME': 'cltbld', 14:21:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:21:21 INFO - 'MOZ_NO_REMOTE': '1', 14:21:21 INFO - 'NO_EM_RESTART': '1', 14:21:21 INFO - 'PAGER': '/bin/cat', 14:21:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:21:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:21:21 INFO - 'PWD': '/builds/slave/test', 14:21:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:21:21 INFO - 'SHELL': '/bin/bash', 14:21:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.56KAhhZ6ku/Listeners', 14:21:21 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 14:21:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:21:21 INFO - 'USER': 'cltbld', 14:21:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:21:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:21:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:21:21 INFO - 'XPC_FLAGS': '0x0', 14:21:21 INFO - 'XPC_SERVICE_NAME': '0', 14:21:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:21:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:21:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:21:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 14:21:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 14:21:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:21:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 14:21:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 14:21:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 14:21:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 14:21:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 14:21:21 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 14:21:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:21:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:21:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:21:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:21:23 INFO - Downloading marionette_driver-1.4.0.tar.gz 14:21:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 14:21:23 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:21:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:21:23 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)) 14:21:23 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)) 14:21:23 INFO - Installing collected packages: marionette-driver 14:21:23 INFO - Running setup.py install for marionette-driver 14:21:23 INFO - Successfully installed marionette-driver 14:21:23 INFO - Cleaning up... 14:21:23 INFO - Return code: 0 14:21:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:21:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:21:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:21:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:21:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:21:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10352cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103530768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0a347fc40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10323cd30>, '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 0x1031f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103231be8>, '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.PVcOFd9JGp/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.56KAhhZ6ku/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:21:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:21:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:21:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PVcOFd9JGp/Render', 14:21:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:21:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:21:23 INFO - 'HOME': '/Users/cltbld', 14:21:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:21:23 INFO - 'LOGNAME': 'cltbld', 14:21:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:21:23 INFO - 'MOZ_NO_REMOTE': '1', 14:21:23 INFO - 'NO_EM_RESTART': '1', 14:21:23 INFO - 'PAGER': '/bin/cat', 14:21:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:21:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:21:23 INFO - 'PWD': '/builds/slave/test', 14:21:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:21:23 INFO - 'SHELL': '/bin/bash', 14:21:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.56KAhhZ6ku/Listeners', 14:21:23 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 14:21:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:21:23 INFO - 'USER': 'cltbld', 14:21:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:21:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:21:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:21:23 INFO - 'XPC_FLAGS': '0x0', 14:21:23 INFO - 'XPC_SERVICE_NAME': '0', 14:21:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:21:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:21:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:21:23 INFO - Cleaning up... 14:21:23 INFO - Return code: 0 14:21:23 INFO - Running post-action listener: _resource_record_post_action 14:21:23 INFO - Running post-action listener: _start_resource_monitoring 14:21:23 INFO - Starting resource monitoring. 14:21:23 INFO - [mozharness: 2017-05-26 21:21:23.758149Z] Finished create-virtualenv step (success) 14:21:23 INFO - [mozharness: 2017-05-26 21:21:23.759206Z] Running install step. 14:21:23 INFO - Running pre-action listener: _resource_record_pre_action 14:21:23 INFO - Running main action method: install 14:21:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:21:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:21:24 INFO - Reading from file tmpfile_stdout 14:21:24 INFO - Detecting whether we're running mozinstall >=1.0... 14:21:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:21:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:21:24 INFO - Reading from file tmpfile_stdout 14:21:24 INFO - Output received: 14:21:24 INFO - Usage: mozinstall [options] installer 14:21:24 INFO - Options: 14:21:24 INFO - -h, --help show this help message and exit 14:21:24 INFO - -d DEST, --destination=DEST 14:21:24 INFO - Directory to install application into. [default: 14:21:24 INFO - "/builds/slave/test"] 14:21:24 INFO - --app=APP Application being installed. [default: firefox] 14:21:24 INFO - mkdir: /builds/slave/test/build/application 14:21:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 14:21:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 14:21:45 INFO - Reading from file tmpfile_stdout 14:21:45 INFO - Output received: 14:21:45 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:21:45 INFO - Running post-action listener: _resource_record_post_action 14:21:45 INFO - [mozharness: 2017-05-26 21:21:45.589901Z] Finished install step (success) 14:21:45 INFO - [mozharness: 2017-05-26 21:21:45.590048Z] Running run-tests step. 14:21:45 INFO - Running pre-action listener: _resource_record_pre_action 14:21:45 INFO - Running main action method: run_tests 14:21:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:21:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:21:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:21:45 INFO - 2017-05-26 14:21:45.604 screenresolution[1620:7341] starting screenresolution argv=screenresolution get 14:21:45 INFO - 2017-05-26 14:21:45.607 screenresolution[1620:7341] Display 0: 1600x1200x32@60 14:21:45 INFO - 2017-05-26 14:21:45.613 screenresolution[1621:7343] starting screenresolution argv=screenresolution list 14:21:45 INFO - Available Modes on Display 0 14:21:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:21:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:21:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:21:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:21:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:21:45 INFO - Intel Iris: 14:21:45 INFO - Chipset Model: Intel Iris 14:21:45 INFO - Type: GPU 14:21:45 INFO - Bus: Built-In 14:21:45 INFO - VRAM (Dynamic, Max): 1536 MB 14:21:45 INFO - Vendor: Intel (0x8086) 14:21:45 INFO - Device ID: 0x0a2e 14:21:45 INFO - Revision ID: 0x0009 14:21:45 INFO - Displays: 14:21:45 INFO - Display: 14:21:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:21:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:21:45 INFO - Display Serial Number: 1600x1200 60 14:21:45 INFO - Main Display: Yes 14:21:45 INFO - Mirror: Off 14:21:45 INFO - Online: Yes 14:21:45 INFO - Rotation: Supported 14:21:45 INFO - Return code: 0 14:21:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:21:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:21:45 INFO - Python 2.7.3 14:21:45 INFO - Return code: 0 14:21:45 INFO - grabbing minidump binary from tooltool 14:21:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:21:45 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 0x10323cd30>, '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 0x1031f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103231be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:21:45 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 14:21:45 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 14:21:45 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 14:21:45 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:21:45 INFO - Return code: 0 14:21:45 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:21:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:21:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:21:45 INFO - ENV: RUST_BACKTRACE is now 1 14:21:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:21:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:21:45 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/A2TejudkSj2FXizebDW0sQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0086', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 14:21:45 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/A2TejudkSj2FXizebDW0sQ/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0086 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 14:21:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PVcOFd9JGp/Render', 14:21:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:21:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:21:45 INFO - 'HOME': '/Users/cltbld', 14:21:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:21:45 INFO - 'LOGNAME': 'cltbld', 14:21:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:21:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:21:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:21:45 INFO - 'MOZ_NO_REMOTE': '1', 14:21:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:21:45 INFO - 'NO_EM_RESTART': '1', 14:21:45 INFO - 'PAGER': '/bin/cat', 14:21:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:21:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:21:45 INFO - 'PWD': '/builds/slave/test', 14:21:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:21:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:21:45 INFO - 'RUST_BACKTRACE': '1', 14:21:45 INFO - 'SHELL': '/bin/bash', 14:21:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.56KAhhZ6ku/Listeners', 14:21:45 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 14:21:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:21:45 INFO - 'USER': 'cltbld', 14:21:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:21:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:21:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:21:45 INFO - 'XPC_FLAGS': '0x0', 14:21:45 INFO - 'XPC_SERVICE_NAME': '0', 14:21:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:21:45 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/A2TejudkSj2FXizebDW0sQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0086', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 14:21:46 INFO - mozversion application_buildid: 20170526124724 14:21:46 INFO - mozversion application_changeset: bce03a8eac301bcd9408b22333b1a67c3eaed057 14:21:46 INFO - mozversion application_display_name: Nightly 14:21:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:21:46 INFO - mozversion application_name: Firefox 14:21:46 INFO - mozversion application_remotingname: firefox 14:21:46 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:21:46 INFO - mozversion application_vendor: Mozilla 14:21:46 INFO - mozversion application_version: 55.0a1 14:21:46 INFO - mozversion platform_buildid: 20170526124724 14:21:46 INFO - mozversion platform_changeset: bce03a8eac301bcd9408b22333b1a67c3eaed057 14:21:46 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:21:46 INFO - mozversion platform_version: 55.0a1 14:21:46 INFO - using testdate: 1495833706 14:21:46 INFO - actual date: 1495833706 14:21:46 INFO - starting webserver on 'localhost:49228' 14:21:46 INFO - SUITE-START | Running 2 tests 14:21:46 INFO - TEST-START | dromaeo_css 14:21:46 INFO - operating with platform_type : mac_ 14:21:46 INFO - Initialising browser for dromaeo_css test... 14:21:46 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/tmpcr14Ib/profile 14:21:46 INFO - TEST-INFO | started process 1631 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 14:22:19 INFO - TEST-INFO | 1631: killed by SIGTERM 14:22:19 INFO - Browser initialized. 14:22:19 INFO - Running cycle 1/1 for dromaeo_css test... 14:22:19 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/tmpcr14Ib/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:22:21 INFO - PID 1641 | 2017-05-26 14:22:21.608 plugin-container[1642:7919] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9643, name = 'com.apple.tsm.portname' 14:22:21 INFO - PID 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:22:21 INFO - PID 1641 | 2017-05-26 14:22:21.609 plugin-container[1642:7919] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0f, name = 'com.apple.CFPasteboardClient' 14:22:21 INFO - PID 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:22:21 INFO - PID 1641 | 2017-05-26 14:22:21.609 plugin-container[1642:7919] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:22:22 INFO - PID 1641 | 2017-05-26 14:22:22.286 plugin-container[1643:7964] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4843, name = 'com.apple.tsm.portname' 14:22:22 INFO - PID 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:22:22 INFO - PID 1641 | 2017-05-26 14:22:22.286 plugin-container[1643:7964] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x451b, name = 'com.apple.CFPasteboardClient' 14:22:22 INFO - PID 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:22:22 INFO - PID 1641 | 2017-05-26 14:22:22.286 plugin-container[1643:7964] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:22:31 INFO - PID 1641 | RSS: Main: 242900992 14:22:31 INFO - PID 1641 | 14:25:33 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49228/tests/dromaeo/cssquery-ext.html) 14:25:35 INFO - PID 1641 | RSS: Main: 255160320 14:25:35 INFO - PID 1641 | 14:28:37 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-ext.html (next: http://localhost:49228/tests/dromaeo/cssquery-jquery.html) 14:28:37 INFO - PID 1641 | RSS: Main: 269053952 14:28:37 INFO - PID 1641 | 14:31:39 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49228/tests/dromaeo/cssquery-mootools.html) 14:31:40 INFO - PID 1641 | RSS: Main: 269242368 14:31:40 INFO - PID 1641 | 14:34:41 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49228/tests/dromaeo/cssquery-prototype.html) 14:34:42 INFO - PID 1641 | RSS: Main: 272867328 14:34:42 INFO - PID 1641 | 14:37:45 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49228/tests/dromaeo/cssquery-yui.html) 14:37:45 INFO - PID 1641 | RSS: Main: 274108416 14:37:45 INFO - PID 1641 | 14:40:47 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-yui.html (next: http://localhost:49228/tests/dromaeo/cssquery-dojo.html) 14:40:47 INFO - PID 1641 | RSS: Main: 275906560 14:40:47 INFO - PID 1641 | 14:40:47 INFO - PID 1641 | __start_tp_report 14:40:47 INFO - PID 1641 | _x_x_mozilla_page_load 14:40:47 INFO - PID 1641 | _x_x_mozilla_page_load_details 14:40:47 INFO - PID 1641 | |i|pagename|runs| 14:40:47 INFO - PID 1641 | |0;dojo.html;3270.9672903270966;3528.682418582329;3558.395072837617;3569.5359603251572;3587.354276230279;6082.087686846975;6102.359252278502;6164.568480206387;6172.259328880541;6181.84545386365;6108.55456054386;6132.049587189147;6136.785212517563;6160.691965401735;6170.506359491241;5259.342582177228;5336.706481143555;5337.599680024004;5346.625736198468;5359.1425371940495;5423.050966080939;5478.972605136969;5486.231927530129;5519.69641669708;5547.25112109866;5280.339957505312;5392.865178370533;5406.432324605899;5458.699771512565;5475.425080366544;5356.035913535562;5373.3014708088085;5391.757370918279;5424.783008679648;5441.537469315113;5305.098133317345;5375.059364611177;5382.703951282678;5382.811601593926;5401.378841433207;6123.571350005502;6190.969045154746;6220.844478888019;6221.813345599639;6226.688665566749;6319.5892267002955;6375.394337537927;6429.742810287583;6546.509011824132;6575.868482630369;6646.93353066468;6675.432588230006;6678;6697.464202863808;6722.93277067228;6009.819705408845;6271.62370257786;6290.276618188876;6292.465140463066;6310.5267104967315;6569.770058047945;6662.300458451889;6715.966420167868;6762.797116086525;6814.965925170342;6246.15676883624;6310.589811662319;6408.871822563616;6458.61248325102;6459.257185423642;6508.121403610547;6553.180852393451;6626.734930602818;6629.03878937561;6653.833654158685;6479.416852483299;6550.639714815636;6560.573562718408;6578.440832529289;6593.571417857824;5458.344998600114;6330.873382532323;6544.770933017321;6558.344165583403;6592.76925307612;5239.895202095937;5242.370915490165;5273.499017593323;5295.417504074549;5318.787248510093;3942.8817135485983;4112.3420252759415;4718.575328220477;4756;4817.0606731686985;5968.403159684084;6259.248890133122;6270.028145637342;6276.748930042839;6285.2457705075685;6454.096426500294;6533.607983520909;6580.078788969548;6682.9331706682315;6696.1629796275465;6079.939200607937;6228.56400051992;6261.311255761862;6265.248170219512;6291.496680265568;5844.386339434383;5886.234789477427;5887.381824908323;5976.910346344808;6011.398860113954;6400;6594.769183078473;6713.295104014203;6836.589804611739;6845;6703.162104736908;6710.429613482908;6722.865542689021;6734.764283250062;6750.5274630775375;6768;6847.315268473113;6869.244383117753;6884.896726549206;6904.516683832084;4775.355327030946;6314;6554.049662798772;6555.541112122106;6572.211334639874;6538;6565.212174539086;6635.966820165868;6638.933610663831;6681.198256209286;6437.613743175424;6448.419642232034;6451.580647257913;6454.612723236621;6465.191851018622;6149.96925015372;6299.181106456132;6363.1091647168505;6430.453411460078;6445.903311450238;5188.7405629719115;6173.56785025044;6330.113784070326;6341.90487142684;6386.16979792624;6295.024271237966;6313.589616674902;6340.841478963062;6351.71417286214;6399.424051835357;6267.404596563501;6275.121482992294;6328.335524769833;6336.683165841781;6344.619322840645 14:40:47 INFO - PID 1641 | |1;ext.html;722.8084557592238;795.8169620987172;844.5903736687708;1135.0182092489993;1140.7547377313876;37374;38243.23513529728;38251.61748382519;38260.23479530408;38450.42324365137;41198.79400603;41675.54135605254;41734.7913260434;41801;41833.7908310458;8168;8380.539070351128;8416.1163077877;8585.742427727178;8605.139486051376;8331.66673333066;8424.241818236358;8425.325973922103;8428.620712067972;8477.025142108643;8424.87362689557;8433.578321083922;8488.151184881524;8497.830043399128;8518.74443766688;8412;8414.83170336593;8459.238668519802;8459.915400845974;8467.66129354828;8210.712625058153;8239.752807415787;8261.793455163608;8277.130901255341;8277.379196560221;36801.63198368036;36917.446238306446;36992.26015479702;37029.70396036153;37258.88223353304;3109.5491153782573;3576.7317451191007;3604.7296452765886;3607.5310209672575;3610.963890361089;30438.391232175232;30613.693863061308;30655.080347589606;30719;30779.846100769577;3111.1910903165112;3151.3539724356683;3162.7153556179824;3166.319241363095;3177.5710279112254;218660;220710.89644551676;230990;291537;293130.8778017208;41771;44113;46558;46738.298925516145;46761.06477870423;39398;48716.51283487191;48756.5124348752;48843.511564884604;48921.75539122282;13690.794638080437;13762.793558096637;13870.4451821926;13884.930575347058;13887.722245555235;11156;11197.328160310244;11219.046381057538;11231.606893758679;11248.493817778219;13486.39311230997;13501.189928604315;13512.108304837331;13536.458541658223;13602;5013.170192879573;5968.791092311748;5988.431099045583;6025.548083893726;6036.788712395045;973.634886917406;991.74214704176;999.8450240212778;1023.2070145637191;1034.891336409666;22798.65802012971;24123.552586844846;24201.031958721807;24425.511489770106;24533.264002079966;1101.862267216598;1106.4633652678444;1131.0207137509358;1136.812425949709;1138.1350173867754;9656.95171524152;12353.567625133077;12410.379481025913;12418.254904705747;12452.81320780189;284109;285351;285467;285975;286894;47010.76494617505;54129;54154.72922635441;54315.72842135843;54480.637984050714;1458.2125652147934;1479.0681870421565;1505.8343582205948;1518.3698765012396;1527.8013858198585;24023.3154534864;24185.39536511601;24325.87837060874;24478.87760561186;24829.25512234636;38898;38953.80523097367;38955.415668765556;39007.804960975016;39033.80483097566;251258;251597;251702;251913;252818;30236.488175591574;32083.518747218342;35355.646443536265;35628;35750.28499430049;43847.34228986504;44252.77873610611;45935.31097033614;46018.539814602766;46032.769836150605;30115.64479598467;31015.379692406477;31460;31604.525932111497;31794;28055.158345249674;30157.944471943378;30962.53556196614;31375.21561960969;31609.683903160672;30834.53748193734;31399.529007064455;37939.430908536946;38226.61773382342;38227.42658860175;29935.55096673595;30128.849355753082;30968.380632387678;31968.840155799073;32584.185395365304;24626.507469850145;24810;24929.13902674341;24943.625845612693;25196 14:40:47 INFO - PID 1641 | |2;jquery.html;417.36560428149215;428.1620443529485;431.67192933370666;447.47645255051566;448.3722788096665;18943.71584426233;19676.40970770879;20716.067776950036;22267.665985010237;22408.55182896341;21899.562008759814;22255.22106726265;22449.081619389457;22499.325020249376;22507.887460562713;6014.578979471427;6270.46701030413;6323.588966717171;6326.715297811586;6349.61902285862;5833.737481813308;6104.603200791957;6167.568270221086;6217.347178546255;6234.438900498954;6041.214642096544;6093.482054025413;6097.115918191859;6108.144859719643;6114.480269177126;5867.88264234715;6089.512838972893;6124.081387791823;6134.5092392608485;6136.478399336062;5454.754536045887;5491.258680078178;5497.202905578709;5498.147787093007;5509.779608815643;19290.614187716168;26392.868035659892;27010.864945675145;27558.862205689045;27785.444291114265;2165.85921915075;2220.544788318391;2230.7992280694816;2233.9553208935895;2237.2393386248755;17137.40019099338;17917.641647167115;17994.010329431876;18058.458246252634;18236.99696516691;2118.3750793515796;2136.1455417832835;2152.0638522242716;2162.0270878104757;2163.6646319820297;122378.99999999911;129473;162354;162451.8812554372;166538;11669.35818529972;11993.580224692269;12200.572979945659;12638.873611263954;12887.806682899763;16467.423640172536;16467.917660411862;16525.173741312887;16552.83447165513;16553.586160345847;8643.481391116553;8821.911780882238;8822.691490690991;8879.822403551892;8905.332100092519;8270.338372930151;8321.417500774887;8342.624581893708;8460.746177614681;8464.15358464161;6514;7863.410244231705;7994.960025199837;7995.600219988977;8072;2155.224119317045;2176.3579743975565;2178.7821217878086;2180.760116387196;2349.0603758496736;938.1650331205233;959.7264779537937;962.708753167471;964.9035096490295;1005.2762011351815;20394.592108157754;20962.790372096388;21025.33274377614;21164.153433862473;21637.675434868488;983.3853841349156;992.1715367668078;1006.9597216111273;1008.4755926917961;1017.7913527726946;10436.165106791439;10729.356238625707;10755.946220269005;10758.892411075789;10795.676129716121;510798;521307.3934630303;523714;535073;541687;122209;126220;127390.72609273974;127845.36077319554;128313.35843320724;1039.4387031003173;1128.4527004402858;1161.1291531351487;1193.8925496705338;1195.9940200298943;15594.610134746723;17818.020008899635;17942.55143621368;18206.908965455088;18408.35570755017;43961.56038439574;46623.6012919613;48335.758321208166;49069.75465122652;49070.50929490802;65732.01401978878;65907;66143.66928165521;66616.66691666705;66812.3318766806;8805.735827925173;8816.735497935073;9028.909710902806;9111.225545828678;9125.498097604695;13788.310584470535;13847.515336963157;14131.788023179866;14320.928395357956;14472.927635361755;11648.41757912084;11753.588624398106;11783.057355411374;11823.822642660194;11872.703182420162;10576.418296993432;10657.57369705203;10743.516541755484;10756.731081723019;11338.263012904135;9751;11451.141164412536;11480.425978700865;11886.870747278994;12077.27536347822;7311.780646580611;8792.648294068165;8957.417767845069;9180.036096209802;9191.908080919373;10925.56297748113;10961.945190274317;11197.496112675111;11200.327980321206;11305.321680699184 14:40:47 INFO - PID 1641 | |3;mootools.html;1395.6789938314187;1453.9200343981083;1457.861503157201;1467.875230605399;1475.0633347824132;11545.480453379598;11655.242409243394;11768.941155294233;12215.755684886308;12221.450034748435;10449.477526123701;10576.365418074922;10596.576136954513;10611.893881061187;10618.097461715763;2416.7462416446283;2464.7658472445087;2470.407102295445;2474.1216868011866;2479.7148327942245;2407.9398015049587;2437.8049756019477;2440.1215562397524;2441.1944058460667;2459.938501537467;2344.531093781242;2389.6893403857475;2390.0917651292484;2393.3537944754908;2396.101461951768;2380.5833979053596;2386.1052105460367;2399.5560821248123;2410.2648692148887;2412.264259400882;1987.3839109875985;2315.8610483370885;2381.1070848431837;2381.9047238110456;2394.904203831845;11398.316101033965;11406.08751299894;11521.020713239384;11541.13441491883;11560.190786644938;6328.778492752777;6574.572652777554;6577.901331480031;6583.670816459158;6625.436837868739;3996.9600303996876;4200.222958752641;4220.725652832556;4266.91466170678;4271.209826182167;1156.1213477756964;1165.959191428296;1223.424990254579;1264.0456455376225;1277.948882044717;15954.28205730745;16148.869579129465;16411.83588164115;16420.6715865682;16476.67046659072;5858.091995740667;5877.500412464903;5927.081302398135;6005.6396616203165;6037.758489660452;13639.181649101085;13648;13649.454021839214;13651.883957986527;13660.65848353799;5802.767889284466;6283.6858157091965;6448.161738973903;6485.935140648628;6567.179102612174;5885.529157667376;5887.2052272943465;5952.0476723724;5994.790182343597;6020.939790602126;4526.162659907862;4566.995261042565;4587.495375508692;4596.563326483979;4632.837850675211;1375.642332993409;1412.7951447040327;1414.299921538845;1434.419060280588;1438.2305466575333;800.2237829305565;818.5579786915118;829.0838623321143;829.9045260256524;857.9871301930476;5433.26650902131;6149.4270010237415;6301.306856245809;6350.364963503613;6420.678966051679;529.7762169388725;538.7817933736843;581.4563383236688;591.9496842768327;611.8195132435942;3823.1589050408866;3971.8808435746973;4067.9796601016806;4412.911741765147;4538.841140560131;13842.792358114637;14954.850451495564;14982.550523484313;15079.924600376928;15128.243587820565;14673.853261467462;14783.673673423395;14854.034487758261;14889.255537223313;14897.18065506386;7397.519161254501;8210.917890821134;8658.393912426196;8672.306215502746;8693.521856297964;6139.723712432862;6269.404406581368;6697.464202863662;7018.087648605649;7106.289371063059;12382.318972456602;12580.370981450707;12748.362581871055;12828.230306181658;12917.870821291666;11587.652370428901;13140.60578182656;13189.538366157522;13201.273929933946;13248.801267980794;9142.131497507726;9404.38871473352;9420.95289523548;9427.858582121411;9439.905600943903;10398.376097434179;10580.470976450993;10626.309289896131;10636.202284828514;10644.84032739494;9008.549572521479;9067.138621830916;9129.49787761653;9135.40619859707;9181.173694367539;8874.556272186235;8905.421147625384;8931.866022009805;8950.820983580423;8990.910090899008;8116.066652334821;8188.976377952756;8201.42590018693;8286.04710458305;8288.088310285984;5973.611715238496;6005.879882402415;6071.119687645355;6121.051237058263;6206.534509911684;6029.336772955059;6039.033754599243;6201.596896201732;6264.248290205204;6337.112804207322 14:40:47 INFO - PID 1641 | |4;prototype.html;335.6391878730364;380.6079737869995;389.0817670298098;401.27369461275094;408.80173116038725;4599.58603725665;5315.36215654121;5832.650040997543;6095.811316793227;6330.62016279023;5267.86830329242;5732.914006289899;6093.634381937087;6187.938120618793;6223.599690069732;4498.482674492426;4528.705634133779;4538.387317712116;4552.635789136878;4555.840545580905;4468.19572476954;4525.818967241306;4541;4552.908941821178;4556.088782243548;3806.6764325032404;4382.145481631083;4480.6639502037315;4499.055198408339;4521.909561808762;4473.507914129443;4473.619742321906;4484.618807401374;4489.304157855538;4504.346869703907;3485.982570087159;3786.8674596388996;3862.1696335287766;3866.627347291699;3875.6318149775725;4951.405831300231;6013.037913933794;6031.4270144336215;6121.265448146207;6146.692665366759;5072.416672082721;5379.112446446332;5390.029794636964;5396.514313711746;5396.865078373033;6522.380373864523;6531.902021469682;6598.73605055802;6609.603423794588;6636.966815165893;3636.872569503436;4383.495897971742;4394.60448559628;4396.670249731283;4398.746357288158;5761.625494342897;6710.463162947001;6980;7001.614911179883;7112.146542414892;4376.168527979674;4391.341298805205;4408.515063343029;4419.779011049434;4421.314696222091;4293.635041021485;4476.052916981123;4488.730676159441;4490.797914093873;4501.639868810487;4238.321868501025;4842.6440596633;4954;5315.840524784263;5354.41101478837;5753.683547404849;6603.900941485882;6615.933840661628;6630.044136979603;6657.13457250564;1358.3004752552442;1516.4919751883242;1530.1584128729155;1541.4836029929875;1544.5984044148602;800.7597720683772;916.3173435790379;973.1873885305707;1007.8992100790009;1027.4143738069229;5228.503292187236;5516.91724624131;5840.328362238312;6226.937730622726;6251.6874156292915;5970;6448;6780.796576102724;6832.726690932406;6867.450603951672;5249.160134378558;5543.695096769717;6286.339934306923;6293;6293;3390.864060539747;3552.236269202134;3607.6572725591027;3622.728295377857;3625.7099432045375;6450.838729031718;6766.8646627068165;6862.828429289208;7066.611336376549;7082.079329687108;7092.503524753321;7097.574145551283;7100.538464999759;7111.53775004623;7112.217656057829;5764.625299355528;6816.522843400914;6989.37095661393;7070.823229419306;7073.964630176816;5128.538431541179;6248.937510624835;6761.729530818712;6863.568215892054;6878.8624227516175;5830.387809279964;6174.598651087665;6601.943689009735;6643.767468138785;6829.453643708392;7153.320434558708;7157.498975071903;7174.928250717426;7189.676464559003;7206.6396680164735;3941.231459865299;4439.200943830012;4461.776911154494;4464.665150113819;4471.374007638999;5497.175423686479;6806.523543351917;6829.214640316427;6884.827879303058;6952.687129079102;5833.854153646193;6116.633002019715;6411.786739917593;6575.243846957661;6709.496787740842;6441.935580644134;6603.867922641424;6632.635205063768;6642.501812363996;6690.76582319636;6547.377999090078;6560.475161987125;6562.278149403657;6579.111819904443;6586.670666466754;6324.6521441321165;6477.967610161919;6607.546339805235;6722.1261236039;6728.899066513908;6522.869542609216;6527.314631963575;6533.314002029719;6533.67331633407;6561.212654481493 14:40:47 INFO - PID 1641 | |5;yui.html;748.3040772081964;820.4175035724635;838.2665167978015;854.6325080215508;863.25328590769;14881.404743810235;15487.535373938772;16800.99194048358;17244.82755172448;17302;15096.54710358691;18372.44882653519;18536.165872535734;18574.349897753582;18765.718514222262;958.8226178157046;966.5457235099492;973.6543527047902;995.581855620641;1001.4592120255054;979.4368238262991;980.3235767320562;981.0336818234034;981.4994352880046;987.4667679453087;930.1953809954408;1021.7394564386071;1022.8261195596766;1026.420072658949;1027.1577306608583;1015.6343716262139;1016.8017236638858;1017.3234798858713;1018.3380802478374;1026.4508487958944;745.5191401545973;758.7344429449648;759.7530802489158;761.1855314813153;761.7676608634364;14908.70182596342;17325.04712240826;17449.912750436295;17738.822611773845;17930.282788688437;16895.746563801553;16966.406175783788;17041.14794260282;17258.827411725848;17345.826541734546;12054.15620906537;13466;14250.287485625677;14532;14831.703365932728;3552.715782737375;3572.214112895159;3572.8392222350058;3588.049166970755;3592.0301518590095;124514.37742811319;138392.30803846018;146464.26767866092;147558;148275.25862370728;13421.261830599215;13525.32373381327;13602.31988400576;13760.105593136414;13760.65598360049;26368.208953731035;26435;26519.204423867315;26643.600345994826;26699.866500667373;5855.355910849803;7496.362809161174;7516.774496765106;7574.962125189449;7657.961710191414;3207.903762887117;3221.275213077039;3808.086059345737;3811.7331786774657;3836.6547010769164;3718.8140592970785;4415.757133357632;4469.284914413678;4472.597466228055;4712.717236965793;890.6348397157135;938.554186761283;950.2303134461108;959.2901253072815;963.5712108111823;637.9776707815204;652.3509108437136;654.5876098058193;663.316783712767;678.4402867634221;11411.942940285246;11714.941425292818;12488.875111248772;12696.492140314283;12846.164999275205;630.1555915073823;639.3287048598952;647.1263793878321;669.8024082895557;674.184237073147;5065.8733531662;5592.273004509388;5787.623804452697;5829.300483941955;5856.1508581255475;113518.43240783908;148627;152502;152694;153414.93170136533;132545;132621;133166.33416832853;134459.32770336085;134496.9825452619;97770.02229977751;98464;98622.50688746654;100379;100905;11467.483963221508;12931.482740690268;13098.803517947048;13101.541446049343;13124.475020999053;43574;44001.89995250016;44373.66878993635;44488.777556112014;44620.77689611531;58865.70567147137;59332.81334373202;59629.70185149046;59885;60140.09789853243;16963.7455438171;17113.828861711223;17321.65356692834;17441.04074275927;17473.38843140484;18342.991135487675;18514.351997680016;19419.51451213731;19444.8055519443;19732.309369172584;15774.763378549102;16809.663806724042;17511.737323939895;18650;18664.7200291993;17617.647647047244;18325.175367108244;18532.980686062398;18549.35077272343;18558.44324670262;17133.314667413662;17246.741298880275;17945.820541794936;18154.636907261516;18348.26606935761;11774.234674746114;12016.459259333176;12540;12568.685782855502;12640.6839829005;13217.801732974205;13760;13874.791878122038;13916.51292204768;13924 14:40:47 INFO - PID 1641 | __end_tp_report 14:40:47 INFO - PID 1641 | __start_cc_report 14:40:47 INFO - PID 1641 | _x_x_mozilla_cycle_collect,396 14:40:47 INFO - PID 1641 | __end_cc_report 14:40:47 INFO - PID 1641 | __startTimestamp1495834847685__endTimestamp 14:40:47 INFO - PID 1641 | 14:40:47 INFO - PID 1641 | ------- Summary: start ------- 14:40:47 INFO - PID 1641 | Number of tests: 6 14:40:47 INFO - PID 1641 | 14:40:47 INFO - PID 1641 | [#0] dojo.html Cycles:165 Average:6063.73 Median:6295.02 stddev:725.12 (11.5%) stddev-sans-first:693.46 14:40:47 INFO - PID 1641 | Values: 3271.0 3528.7 3558.4 3569.5 3587.4 6082.1 6102.4 6164.6 6172.3 6181.8 6108.6 6132.0 6136.8 6160.7 6170.5 5259.3 5336.7 5337.6 5346.6 5359.1 5423.1 5479.0 5486.2 5519.7 5547.3 5280.3 5392.9 5406.4 5458.7 5475.4 5356.0 5373.3 5391.8 5424.8 5441.5 5305.1 5375.1 5382.7 5382.8 5401.4 6123.6 6191.0 6220.8 6221.8 6226.7 6319.6 6375.4 6429.7 6546.5 6575.9 6646.9 6675.4 6678.0 6697.5 6722.9 6009.8 6271.6 6290.3 6292.5 6310.5 6569.8 6662.3 6716.0 6762.8 6815.0 6246.2 6310.6 6408.9 6458.6 6459.3 6508.1 6553.2 6626.7 6629.0 6653.8 6479.4 6550.6 6560.6 6578.4 6593.6 5458.3 6330.9 6544.8 6558.3 6592.8 5239.9 5242.4 5273.5 5295.4 5318.8 3942.9 4112.3 4718.6 4756.0 4817.1 5968.4 6259.2 6270.0 6276.7 6285.2 6454.1 6533.6 6580.1 6682.9 6696.2 6079.9 6228.6 6261.3 6265.2 6291.5 5844.4 5886.2 5887.4 5976.9 6011.4 6400.0 6594.8 6713.3 6836.6 6845.0 6703.2 6710.4 6722.9 6734.8 6750.5 6768.0 6847.3 6869.2 6884.9 6904.5 4775.4 6314.0 6554.0 6555.5 6572.2 6538.0 6565.2 6636.0 6638.9 6681.2 6437.6 6448.4 6451.6 6454.6 6465.2 6150.0 6299.2 6363.1 6430.5 6445.9 5188.7 6173.6 6330.1 6341.9 6386.2 6295.0 6313.6 6340.8 6351.7 6399.4 6267.4 6275.1 6328.3 6336.7 6344.6 14:40:47 INFO - PID 1641 | 14:40:47 INFO - PID 1641 | [#1] ext.html Cycles:180 Average:41932.42 Median:32026.18 stddev:68927.16 (215.2%) stddev-sans-first:69051.07 14:40:47 INFO - PID 1641 | Values: 722.8 795.8 844.6 1135.0 1140.8 37374.0 38243.2 38251.6 38260.2 38450.4 41198.8 41675.5 41734.8 41801.0 41833.8 8168.0 8380.5 8416.1 8585.7 8605.1 8331.7 8424.2 8425.3 8428.6 8477.0 8424.9 8433.6 8488.2 8497.8 8518.7 8412.0 8414.8 8459.2 8459.9 8467.7 8210.7 8239.8 8261.8 8277.1 8277.4 36801.6 36917.4 36992.3 37029.7 37258.9 3109.5 3576.7 3604.7 3607.5 3611.0 30438.4 30613.7 30655.1 30719.0 30779.8 3111.2 3151.4 3162.7 3166.3 3177.6 218660.0 220710.9 230990.0 291537.0 293130.9 41771.0 44113.0 46558.0 46738.3 46761.1 39398.0 48716.5 48756.5 48843.5 48921.8 13690.8 13762.8 13870.4 13884.9 13887.7 11156.0 11197.3 11219.0 11231.6 11248.5 13486.4 13501.2 13512.1 13536.5 13602.0 5013.2 5968.8 5988.4 6025.5 6036.8 973.6 991.7 999.8 1023.2 1034.9 22798.7 24123.6 24201.0 24425.5 24533.3 1101.9 1106.5 1131.0 1136.8 1138.1 9657.0 12353.6 12410.4 12418.3 12452.8 284109.0 285351.0 285467.0 285975.0 286894.0 47010.8 54129.0 54154.7 54315.7 54480.6 1458.2 1479.1 1505.8 1518.4 1527.8 24023.3 24185.4 24325.9 24478.9 24829.3 38898.0 38953.8 38955.4 39007.8 39033.8 251258.0 251597.0 251702.0 251913.0 252818.0 30236.5 32083.5 35355.6 35628.0 35750.3 43847.3 44252.8 45935.3 46018.5 46032.8 30115.6 31015.4 31460.0 31604.5 31794.0 28055.2 30157.9 30962.5 31375.2 31609.7 30834.5 31399.5 37939.4 38226.6 38227.4 29935.6 30128.8 30968.4 31968.8 32584.2 24626.5 24810.0 24929.1 24943.6 25196.0 14:40:47 INFO - PID 1641 | 14:40:47 INFO - PID 1641 | [#2] jquery.html Cycles:180 Average:33937.57 Median:12249.23 stddev:89289.23 (728.9%) stddev-sans-first:89504.24 14:40:47 INFO - PID 1641 | Values: 417.4 428.2 431.7 447.5 448.4 18943.7 19676.4 20716.1 22267.7 22408.6 21899.6 22255.2 22449.1 22499.3 22507.9 6014.6 6270.5 6323.6 6326.7 6349.6 5833.7 6104.6 6167.6 6217.3 6234.4 6041.2 6093.5 6097.1 6108.1 6114.5 5867.9 6089.5 6124.1 6134.5 6136.5 5454.8 5491.3 5497.2 5498.1 5509.8 19290.6 26392.9 27010.9 27558.9 27785.4 2165.9 2220.5 2230.8 2234.0 2237.2 17137.4 17917.6 17994.0 18058.5 18237.0 2118.4 2136.1 2152.1 2162.0 2163.7 122379.0 129473.0 162354.0 162451.9 166538.0 11669.4 11993.6 12200.6 12638.9 12887.8 16467.4 16467.9 16525.2 16552.8 16553.6 8643.5 8821.9 8822.7 8879.8 8905.3 8270.3 8321.4 8342.6 8460.7 8464.2 6514.0 7863.4 7995.0 7995.6 8072.0 2155.2 2176.4 2178.8 2180.8 2349.1 938.2 959.7 962.7 964.9 1005.3 20394.6 20962.8 21025.3 21164.2 21637.7 983.4 992.2 1007.0 1008.5 1017.8 10436.2 10729.4 10755.9 10758.9 10795.7 510798.0 521307.4 523714.0 535073.0 541687.0 122209.0 126220.0 127390.7 127845.4 128313.4 1039.4 1128.5 1161.1 1193.9 1196.0 15594.6 17818.0 17942.6 18206.9 18408.4 43961.6 46623.6 48335.8 49069.8 49070.5 65732.0 65907.0 66143.7 66616.7 66812.3 8805.7 8816.7 9028.9 9111.2 9125.5 13788.3 13847.5 14131.8 14320.9 14472.9 11648.4 11753.6 11783.1 11823.8 11872.7 10576.4 10657.6 10743.5 10756.7 11338.3 9751.0 11451.1 11480.4 11886.9 12077.3 7311.8 8792.6 8957.4 9180.0 9191.9 10925.6 10961.9 11197.5 11200.3 11305.3 14:40:47 INFO - PID 1641 | 14:40:47 INFO - PID 1641 | [#3] mootools.html Cycles:180 Average:7048.16 Median:3897.52 stddev:4469.94 (114.7%) stddev-sans-first:4462.30 14:40:47 INFO - PID 1641 | Values: 1395.7 1453.9 1457.9 1467.9 1475.1 11545.5 11655.2 11768.9 12215.8 12221.5 10449.5 10576.4 10596.6 10611.9 10618.1 2416.7 2464.8 2470.4 2474.1 2479.7 2407.9 2437.8 2440.1 2441.2 2459.9 2344.5 2389.7 2390.1 2393.4 2396.1 2380.6 2386.1 2399.6 2410.3 2412.3 1987.4 2315.9 2381.1 2381.9 2394.9 11398.3 11406.1 11521.0 11541.1 11560.2 6328.8 6574.6 6577.9 6583.7 6625.4 3997.0 4200.2 4220.7 4266.9 4271.2 1156.1 1166.0 1223.4 1264.0 1277.9 15954.3 16148.9 16411.8 16420.7 16476.7 5858.1 5877.5 5927.1 6005.6 6037.8 13639.2 13648.0 13649.5 13651.9 13660.7 5802.8 6283.7 6448.2 6485.9 6567.2 5885.5 5887.2 5952.0 5994.8 6020.9 4526.2 4567.0 4587.5 4596.6 4632.8 1375.6 1412.8 1414.3 1434.4 1438.2 800.2 818.6 829.1 829.9 858.0 5433.3 6149.4 6301.3 6350.4 6420.7 529.8 538.8 581.5 591.9 611.8 3823.2 3971.9 4068.0 4412.9 4538.8 13842.8 14954.9 14982.6 15079.9 15128.2 14673.9 14783.7 14854.0 14889.3 14897.2 7397.5 8210.9 8658.4 8672.3 8693.5 6139.7 6269.4 6697.5 7018.1 7106.3 12382.3 12580.4 12748.4 12828.2 12917.9 11587.7 13140.6 13189.5 13201.3 13248.8 9142.1 9404.4 9421.0 9427.9 9439.9 10398.4 10580.5 10626.3 10636.2 10644.8 9008.5 9067.1 9129.5 9135.4 9181.2 8874.6 8905.4 8931.9 8950.8 8990.9 8116.1 8189.0 8201.4 8286.0 8288.1 5973.6 6005.9 6071.1 6121.1 6206.5 6029.3 6039.0 6201.6 6264.2 6337.1 14:40:47 INFO - PID 1641 | 14:40:47 INFO - PID 1641 | [#4] prototype.html Cycles:180 Average:5308.03 Median:5991.52 stddev:1700.83 (28.4%) stddev-sans-first:1664.15 14:40:47 INFO - PID 1641 | Values: 335.6 380.6 389.1 401.3 408.8 4599.6 5315.4 5832.7 6095.8 6330.6 5267.9 5732.9 6093.6 6187.9 6223.6 4498.5 4528.7 4538.4 4552.6 4555.8 4468.2 4525.8 4541.0 4552.9 4556.1 3806.7 4382.1 4480.7 4499.1 4521.9 4473.5 4473.6 4484.6 4489.3 4504.3 3486.0 3786.9 3862.2 3866.6 3875.6 4951.4 6013.0 6031.4 6121.3 6146.7 5072.4 5379.1 5390.0 5396.5 5396.9 6522.4 6531.9 6598.7 6609.6 6637.0 3636.9 4383.5 4394.6 4396.7 4398.7 5761.6 6710.5 6980.0 7001.6 7112.1 4376.2 4391.3 4408.5 4419.8 4421.3 4293.6 4476.1 4488.7 4490.8 4501.6 4238.3 4842.6 4954.0 5315.8 5354.4 5753.7 6603.9 6615.9 6630.0 6657.1 1358.3 1516.5 1530.2 1541.5 1544.6 800.8 916.3 973.2 1007.9 1027.4 5228.5 5516.9 5840.3 6226.9 6251.7 5970.0 6448.0 6780.8 6832.7 6867.5 5249.2 5543.7 6286.3 6293.0 6293.0 3390.9 3552.2 3607.7 3622.7 3625.7 6450.8 6766.9 6862.8 7066.6 7082.1 7092.5 7097.6 7100.5 7111.5 7112.2 5764.6 6816.5 6989.4 7070.8 7074.0 5128.5 6248.9 6761.7 6863.6 6878.9 5830.4 6174.6 6601.9 6643.8 6829.5 7153.3 7157.5 7174.9 7189.7 7206.6 3941.2 4439.2 4461.8 4464.7 4471.4 5497.2 6806.5 6829.2 6884.8 6952.7 5833.9 6116.6 6411.8 6575.2 6709.5 6441.9 6603.9 6632.6 6642.5 6690.8 6547.4 6560.5 6562.3 6579.1 6586.7 6324.7 6478.0 6607.5 6722.1 6728.9 6522.9 6527.3 6533.3 6533.7 6561.2 14:40:47 INFO - PID 1641 | 14:40:47 INFO - PID 1641 | [#5] yui.html Cycles:180 Average:25475.98 Median:18523.67 stddev:39357.59 (212.5%) stddev-sans-first:39424.21 14:40:47 INFO - PID 1641 | Values: 748.3 820.4 838.3 854.6 863.3 14881.4 15487.5 16801.0 17244.8 17302.0 15096.5 18372.4 18536.2 18574.3 18765.7 958.8 966.5 973.7 995.6 1001.5 979.4 980.3 981.0 981.5 987.5 930.2 1021.7 1022.8 1026.4 1027.2 1015.6 1016.8 1017.3 1018.3 1026.5 745.5 758.7 759.8 761.2 761.8 14908.7 17325.0 17449.9 17738.8 17930.3 16895.7 16966.4 17041.1 17258.8 17345.8 12054.2 13466.0 14250.3 14532.0 14831.7 3552.7 3572.2 3572.8 3588.0 3592.0 124514.4 138392.3 146464.3 147558.0 148275.3 13421.3 13525.3 13602.3 13760.1 13760.7 26368.2 26435.0 26519.2 26643.6 26699.9 5855.4 7496.4 7516.8 7575.0 7658.0 3207.9 3221.3 3808.1 3811.7 3836.7 3718.8 4415.8 4469.3 4472.6 4712.7 890.6 938.6 950.2 959.3 963.6 638.0 652.4 654.6 663.3 678.4 11411.9 11714.9 12488.9 12696.5 12846.2 630.2 639.3 647.1 669.8 674.2 5065.9 5592.3 5787.6 5829.3 5856.2 113518.4 148627.0 152502.0 152694.0 153414.9 132545.0 132621.0 133166.3 134459.3 134497.0 97770.0 98464.0 98622.5 100379.0 100905.0 11467.5 12931.5 13098.8 13101.5 13124.5 43574.0 44001.9 44373.7 44488.8 44620.8 58865.7 59332.8 59629.7 59885.0 60140.1 16963.7 17113.8 17321.7 17441.0 17473.4 18343.0 18514.4 19419.5 19444.8 19732.3 15774.8 16809.7 17511.7 18650.0 18664.7 17617.6 18325.2 18533.0 18549.4 18558.4 17133.3 17246.7 17945.8 18154.6 18348.3 11774.2 12016.5 12540.0 12568.7 12640.7 13217.8 13760.0 13874.8 13916.5 13924.0 14:40:47 INFO - PID 1641 | -------- Summary: end -------- 14:40:47 INFO - PID 1641 | 14:40:48 INFO - TEST-INFO | 1641: exit 0 14:40:48 INFO - TEST-OK | dromaeo_css | took 1141927ms 14:40:48 INFO - TEST-START | kraken 14:40:48 INFO - operating with platform_type : mac_ 14:40:48 INFO - Initialising browser for kraken test... 14:40:48 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/tmpq4ImOy/profile 14:40:48 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 14:41:21 INFO - TEST-INFO | 1651: killed by SIGTERM 14:41:21 INFO - Browser initialized. 14:41:21 INFO - Running cycle 1/1 for kraken test... 14:41:21 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/tmpq4ImOy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:41:23 INFO - PID 1655 | 2017-05-26 14:41:23.654 plugin-container[1656:10552] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 14:41:23 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:41:23 INFO - PID 1655 | 2017-05-26 14:41:23.655 plugin-container[1656:10552] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 14:41:23 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:41:23 INFO - PID 1655 | 2017-05-26 14:41:23.655 plugin-container[1656:10552] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:41:24 INFO - PID 1655 | 2017-05-26 14:41:24.268 plugin-container[1657:10597] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464b, name = 'com.apple.tsm.portname' 14:41:24 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:41:24 INFO - PID 1655 | 2017-05-26 14:41:24.268 plugin-container[1657:10597] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0b, name = 'com.apple.CFPasteboardClient' 14:41:24 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:41:24 INFO - PID 1655 | 2017-05-26 14:41:24.268 plugin-container[1657:10597] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:41:33 INFO - PID 1655 | RSS: Main: 242855936 14:41:33 INFO - PID 1655 | 14:41:36 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection) 14:41:37 INFO - PID 1655 | RSS: Main: 256909312 14:41:37 INFO - PID 1655 | 14:41:39 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-dft) 14:41:40 INFO - PID 1655 | RSS: Main: 265699328 14:41:40 INFO - PID 1655 | 14:41:44 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-fft) 14:41:44 INFO - PID 1655 | RSS: Main: 268836864 14:41:44 INFO - PID 1655 | 14:41:46 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator) 14:41:47 INFO - PID 1655 | RSS: Main: 269602816 14:41:47 INFO - PID 1655 | 14:41:49 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:41:49 INFO - PID 1655 | RSS: Main: 271507456 14:41:49 INFO - PID 1655 | 14:41:52 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom) 14:41:53 INFO - PID 1655 | RSS: Main: 276623360 14:41:53 INFO - PID 1655 | 14:41:56 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate) 14:41:57 INFO - PID 1655 | RSS: Main: 279437312 14:41:57 INFO - PID 1655 | 14:42:00 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial) 14:42:01 INFO - PID 1655 | RSS: Main: 278773760 14:42:01 INFO - PID 1655 | 14:42:02 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:42:03 INFO - PID 1655 | RSS: Main: 278343680 14:42:03 INFO - PID 1655 | 14:42:04 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:42:05 INFO - PID 1655 | RSS: Main: 278192128 14:42:05 INFO - PID 1655 | 14:42:07 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:42:08 INFO - PID 1655 | RSS: Main: 277790720 14:42:08 INFO - PID 1655 | 14:42:11 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:42:12 INFO - PID 1655 | RSS: Main: 278237184 14:42:12 INFO - PID 1655 | 14:42:15 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:42:15 INFO - PID 1655 | RSS: Main: 277958656 14:42:15 INFO - PID 1655 | 14:42:17 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49228/tests/kraken/driver.html?testName=ai-astar) 14:42:18 INFO - PID 1655 | RSS: Main: 279875584 14:42:18 INFO - PID 1655 | 14:42:18 INFO - PID 1655 | __start_tp_report 14:42:18 INFO - PID 1655 | _x_x_mozilla_page_load 14:42:18 INFO - PID 1655 | _x_x_mozilla_page_load_details 14:42:18 INFO - PID 1655 | |i|pagename|runs| 14:42:18 INFO - PID 1655 | |0;ai-astar;101;116;117;130;114;114;114;115;116;114 14:42:18 INFO - PID 1655 | |1;audio-beat-detection;149;115;110;152;127;100;136;105;156;146 14:42:18 INFO - PID 1655 | |2;audio-dft;275;239;201;216;230;239;275;230;269;275 14:42:18 INFO - PID 1655 | |3;audio-fft;84;63;64;85;62;60;93;54;92;51 14:42:18 INFO - PID 1655 | |4;audio-oscillator;82;70;78;82;69;69;87;72;95;84 14:42:18 INFO - PID 1655 | |5;imaging-gaussian-blur;91;92;90;94;91;91;91;90;90;90 14:42:18 INFO - PID 1655 | |6;imaging-darkroom;97;98;97;121;88;98;105;155;98;96 14:42:18 INFO - PID 1655 | |7;imaging-desaturate;91;95;76;142;143;110;141;91;113;132 14:42:18 INFO - PID 1655 | |8;json-parse-financial;50;52;51;52;53;52;51;52;51;51 14:42:18 INFO - PID 1655 | |9;json-stringify-tinderbox;56;55;58;62;63;65;69;58;55;55 14:42:18 INFO - PID 1655 | |10;stanford-crypto-aes;87;86;85;77;100;113;113;110;146;118 14:42:18 INFO - PID 1655 | |11;stanford-crypto-ccm;145;137;148;153;153;134;150;158;155;154 14:42:18 INFO - PID 1655 | |12;stanford-crypto-pbkdf2;195;188;185;187;177;186;185;204;181;185 14:42:18 INFO - PID 1655 | |13;stanford-crypto-sha256-iterative;60;89;71;80;91;97;99;99;82;68 14:42:18 INFO - PID 1655 | __end_tp_report 14:42:18 INFO - PID 1655 | __start_cc_report 14:42:18 INFO - PID 1655 | _x_x_mozilla_cycle_collect,1058 14:42:18 INFO - PID 1655 | __end_cc_report 14:42:18 INFO - PID 1655 | __startTimestamp1495834938123__endTimestamp 14:42:18 INFO - PID 1655 | 14:42:18 INFO - PID 1655 | ------- Summary: start ------- 14:42:18 INFO - PID 1655 | Number of tests: 14 14:42:18 INFO - PID 1655 | 14:42:18 INFO - PID 1655 | [#0] ai-astar Cycles:10 Average:115.10 Median:115.50 stddev:6.92 (6.0%) stddev-sans-first:5.12 14:42:18 INFO - PID 1655 | Values: 101.0 116.0 117.0 130.0 114.0 114.0 114.0 115.0 116.0 114.0 14:42:18 INFO - PID 1655 | 14:42:18 INFO - PID 1655 | [#1] audio-beat-detection Cycles:10 Average:129.60 Median:141.00 stddev:21.00 (14.9%) stddev-sans-first:21.07 14:42:18 INFO - PID 1655 | Values: 149.0 115.0 110.0 152.0 127.0 100.0 136.0 105.0 156.0 146.0 14:42:18 INFO - PID 1655 | 14:42:18 INFO - PID 1655 | [#2] audio-dft Cycles:10 Average:244.90 Median:254.00 stddev:27.03 (10.6%) stddev-sans-first:26.38 14:42:18 INFO - PID 1655 | Values: 275.0 239.0 201.0 216.0 230.0 239.0 275.0 230.0 269.0 275.0 14:42:18 INFO - PID 1655 | 14:42:18 INFO - PID 1655 | [#3] audio-fft Cycles:10 Average:70.80 Median:74.00 stddev:15.96 (21.6%) stddev-sans-first:16.20 14:42:18 INFO - PID 1655 | Values: 84.0 63.0 64.0 85.0 62.0 60.0 93.0 54.0 92.0 51.0 14:42:18 INFO - PID 1655 | 14:42:18 INFO - PID 1655 | [#4] audio-oscillator Cycles:10 Average:78.80 Median:82.00 stddev:8.78 (10.7%) stddev-sans-first:9.23 14:42:18 INFO - PID 1655 | Values: 82.0 70.0 78.0 82.0 69.0 69.0 87.0 72.0 95.0 84.0 14:42:18 INFO - PID 1655 | 14:42:18 INFO - PID 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:91.00 Median:91.00 stddev:1.25 (1.4%) stddev-sans-first:1.32 14:42:18 INFO - PID 1655 | Values: 91.0 92.0 90.0 94.0 91.0 91.0 91.0 90.0 90.0 90.0 14:42:18 INFO - PID 1655 | 14:42:18 INFO - PID 1655 | [#6] imaging-darkroom Cycles:10 Average:105.30 Median:97.00 stddev:19.44 (20.0%) stddev-sans-first:20.38 14:42:18 INFO - PID 1655 | Values: 97.0 98.0 97.0 121.0 88.0 98.0 105.0 155.0 98.0 96.0 14:42:18 INFO - PID 1655 | 14:42:18 INFO - PID 1655 | [#7] imaging-desaturate Cycles:10 Average:113.40 Median:109.50 stddev:24.84 (22.7%) stddev-sans-first:24.99 14:42:18 INFO - PID 1655 | Values: 91.0 95.0 76.0 142.0 143.0 110.0 141.0 91.0 113.0 132.0 14:42:18 INFO - PID 1655 | 14:42:18 INFO - PID 1655 | [#8] json-parse-financial Cycles:10 Average:51.50 Median:52.00 stddev:0.85 (1.6%) stddev-sans-first:0.71 14:42:18 INFO - PID 1655 | Values: 50.0 52.0 51.0 52.0 53.0 52.0 51.0 52.0 51.0 51.0 14:42:18 INFO - PID 1655 | 14:42:18 INFO - PID 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:59.60 Median:60.00 stddev:4.90 (8.2%) stddev-sans-first:5.02 14:42:18 INFO - PID 1655 | Values: 56.0 55.0 58.0 62.0 63.0 65.0 69.0 58.0 55.0 55.0 14:42:18 INFO - PID 1655 | 14:42:18 INFO - PID 1655 | [#10] stanford-crypto-aes Cycles:10 Average:103.50 Median:111.50 stddev:20.75 (18.6%) stddev-sans-first:21.13 14:42:18 INFO - PID 1655 | Values: 87.0 86.0 85.0 77.0 100.0 113.0 113.0 110.0 146.0 118.0 14:42:18 INFO - PID 1655 | 14:42:18 INFO - PID 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:148.70 Median:153.00 stddev:7.89 (5.2%) stddev-sans-first:8.25 14:42:18 INFO - PID 1655 | Values: 145.0 137.0 148.0 153.0 153.0 134.0 150.0 158.0 155.0 154.0 14:42:18 INFO - PID 1655 | 14:42:18 INFO - PID 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.30 Median:186.50 stddev:7.47 (4.0%) stddev-sans-first:7.38 14:42:18 INFO - PID 1655 | Values: 195.0 188.0 185.0 187.0 177.0 186.0 185.0 204.0 181.0 185.0 14:42:18 INFO - PID 1655 | 14:42:18 INFO - PID 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:83.60 Median:90.00 stddev:13.79 (15.3%) stddev-sans-first:11.69 14:42:18 INFO - PID 1655 | Values: 60.0 89.0 71.0 80.0 91.0 97.0 99.0 99.0 82.0 68.0 14:42:18 INFO - PID 1655 | -------- Summary: end -------- 14:42:18 INFO - PID 1655 | 14:42:18 INFO - TEST-INFO | 1655: exit 0 14:42:18 INFO - TEST-OK | kraken | took 90687ms 14:42:18 INFO - SUITE-END | took 1232s 14:42:19 INFO - Completed test suite (00:20:33) 14:42:19 INFO - javascript benchmark 14:42:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7849.254053120841, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3270.9672903270966, 3528.682418582329, 3558.395072837617, 3569.5359603251572, 3587.354276230279, 6082.087686846975, 6102.359252278502, 6164.568480206387, 6172.259328880541, 6181.84545386365, 6108.55456054386, 6132.049587189147, 6136.785212517563, 6160.691965401735, 6170.506359491241, 5259.342582177228, 5336.706481143555, 5337.599680024004, 5346.625736198468, 5359.1425371940495, 5423.050966080939, 5478.972605136969, 5486.231927530129, 5519.69641669708, 5547.25112109866, 5280.339957505312, 5392.865178370533, 5406.432324605899, 5458.699771512565, 5475.425080366544, 5356.035913535562, 5373.3014708088085, 5391.757370918279, 5424.783008679648, 5441.537469315113, 5305.098133317345, 5375.059364611177, 5382.703951282678, 5382.811601593926, 5401.378841433207, 6123.571350005502, 6190.969045154746, 6220.844478888019, 6221.813345599639, 6226.688665566749, 6319.5892267002955, 6375.394337537927, 6429.742810287583, 6546.509011824132, 6575.868482630369, 6646.93353066468, 6675.432588230006, 6678.0, 6697.464202863808, 6722.93277067228, 6009.819705408845, 6271.62370257786, 6290.276618188876, 6292.465140463066, 6310.5267104967315, 6569.770058047945, 6662.300458451889, 6715.966420167868, 6762.797116086525, 6814.965925170342, 6246.15676883624, 6310.589811662319, 6408.871822563616, 6458.61248325102, 6459.257185423642, 6508.121403610547, 6553.180852393451, 6626.734930602818, 6629.03878937561, 6653.833654158685, 6479.416852483299, 6550.639714815636, 6560.573562718408, 6578.440832529289, 6593.571417857824, 5458.344998600114, 6330.873382532323, 6544.770933017321, 6558.344165583403, 6592.76925307612, 5239.895202095937, 5242.370915490165, 5273.499017593323, 5295.417504074549, 5318.787248510093, 3942.8817135485983, 4112.3420252759415, 4718.575328220477, 4756.0, 4817.0606731686985, 5968.403159684084, 6259.248890133122, 6270.028145637342, 6276.748930042839, 6285.2457705075685, 6454.096426500294, 6533.607983520909, 6580.078788969548, 6682.9331706682315, 6696.1629796275465, 6079.939200607937, 6228.56400051992, 6261.311255761862, 6265.248170219512, 6291.496680265568, 5844.386339434383, 5886.234789477427, 5887.381824908323, 5976.910346344808, 6011.398860113954, 6400.0, 6594.769183078473, 6713.295104014203, 6836.589804611739, 6845.0, 6703.162104736908, 6710.429613482908, 6722.865542689021, 6734.764283250062, 6750.5274630775375, 6768.0, 6847.315268473113, 6869.244383117753, 6884.896726549206, 6904.516683832084, 4775.355327030946, 6314.0, 6554.049662798772, 6555.541112122106, 6572.211334639874, 6538.0, 6565.212174539086, 6635.966820165868, 6638.933610663831, 6681.198256209286, 6437.613743175424, 6448.419642232034, 6451.580647257913, 6454.612723236621, 6465.191851018622, 6149.96925015372, 6299.181106456132, 6363.1091647168505, 6430.453411460078, 6445.903311450238, 5188.7405629719115, 6173.56785025044, 6330.113784070326, 6341.90487142684, 6386.16979792624, 6295.024271237966, 6313.589616674902, 6340.841478963062, 6351.71417286214, 6399.424051835357, 6267.404596563501, 6275.121482992294, 6328.335524769833, 6336.683165841781, 6344.619322840645], "value": 6015.490618964947, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [722.8084557592238, 795.8169620987172, 844.5903736687708, 1135.0182092489993, 1140.7547377313876, 37374.0, 38243.23513529728, 38251.61748382519, 38260.23479530408, 38450.42324365137, 41198.79400603, 41675.54135605254, 41734.7913260434, 41801.0, 41833.7908310458, 8168.0, 8380.539070351128, 8416.1163077877, 8585.742427727178, 8605.139486051376, 8331.66673333066, 8424.241818236358, 8425.325973922103, 8428.620712067972, 8477.025142108643, 8424.87362689557, 8433.578321083922, 8488.151184881524, 8497.830043399128, 8518.74443766688, 8412.0, 8414.83170336593, 8459.238668519802, 8459.915400845974, 8467.66129354828, 8210.712625058153, 8239.752807415787, 8261.793455163608, 8277.130901255341, 8277.379196560221, 36801.63198368036, 36917.446238306446, 36992.26015479702, 37029.70396036153, 37258.88223353304, 3109.5491153782573, 3576.7317451191007, 3604.7296452765886, 3607.5310209672575, 3610.963890361089, 30438.391232175232, 30613.693863061308, 30655.080347589606, 30719.0, 30779.846100769577, 3111.1910903165112, 3151.3539724356683, 3162.7153556179824, 3166.319241363095, 3177.5710279112254, 218660.0, 220710.89644551676, 230990.0, 291537.0, 293130.8778017208, 41771.0, 44113.0, 46558.0, 46738.298925516145, 46761.06477870423, 39398.0, 48716.51283487191, 48756.5124348752, 48843.511564884604, 48921.75539122282, 13690.794638080437, 13762.793558096637, 13870.4451821926, 13884.930575347058, 13887.722245555235, 11156.0, 11197.328160310244, 11219.046381057538, 11231.606893758679, 11248.493817778219, 13486.39311230997, 13501.189928604315, 13512.108304837331, 13536.458541658223, 13602.0, 5013.170192879573, 5968.791092311748, 5988.431099045583, 6025.548083893726, 6036.788712395045, 973.634886917406, 991.74214704176, 999.8450240212778, 1023.2070145637191, 1034.891336409666, 22798.65802012971, 24123.552586844846, 24201.031958721807, 24425.511489770106, 24533.264002079966, 1101.862267216598, 1106.4633652678444, 1131.0207137509358, 1136.812425949709, 1138.1350173867754, 9656.95171524152, 12353.567625133077, 12410.379481025913, 12418.254904705747, 12452.81320780189, 284109.0, 285351.0, 285467.0, 285975.0, 286894.0, 47010.76494617505, 54129.0, 54154.72922635441, 54315.72842135843, 54480.637984050714, 1458.2125652147934, 1479.0681870421565, 1505.8343582205948, 1518.3698765012396, 1527.8013858198585, 24023.3154534864, 24185.39536511601, 24325.87837060874, 24478.87760561186, 24829.25512234636, 38898.0, 38953.80523097367, 38955.415668765556, 39007.804960975016, 39033.80483097566, 251258.0, 251597.0, 251702.0, 251913.0, 252818.0, 30236.488175591574, 32083.518747218342, 35355.646443536265, 35628.0, 35750.28499430049, 43847.34228986504, 44252.77873610611, 45935.31097033614, 46018.539814602766, 46032.769836150605, 30115.64479598467, 31015.379692406477, 31460.0, 31604.525932111497, 31794.0, 28055.158345249674, 30157.944471943378, 30962.53556196614, 31375.21561960969, 31609.683903160672, 30834.53748193734, 31399.529007064455, 37939.430908536946, 38226.61773382342, 38227.42658860175, 29935.55096673595, 30128.849355753082, 30968.380632387678, 31968.840155799073, 32584.185395365304, 24626.507469850145, 24810.0, 24929.13902674341, 24943.625845612693, 25196.0], "value": 17266.861823304556, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [417.36560428149215, 428.1620443529485, 431.67192933370666, 447.47645255051566, 448.3722788096665, 18943.71584426233, 19676.40970770879, 20716.067776950036, 22267.665985010237, 22408.55182896341, 21899.562008759814, 22255.22106726265, 22449.081619389457, 22499.325020249376, 22507.887460562713, 6014.578979471427, 6270.46701030413, 6323.588966717171, 6326.715297811586, 6349.61902285862, 5833.737481813308, 6104.603200791957, 6167.568270221086, 6217.347178546255, 6234.438900498954, 6041.214642096544, 6093.482054025413, 6097.115918191859, 6108.144859719643, 6114.480269177126, 5867.88264234715, 6089.512838972893, 6124.081387791823, 6134.5092392608485, 6136.478399336062, 5454.754536045887, 5491.258680078178, 5497.202905578709, 5498.147787093007, 5509.779608815643, 19290.614187716168, 26392.868035659892, 27010.864945675145, 27558.862205689045, 27785.444291114265, 2165.85921915075, 2220.544788318391, 2230.7992280694816, 2233.9553208935895, 2237.2393386248755, 17137.40019099338, 17917.641647167115, 17994.010329431876, 18058.458246252634, 18236.99696516691, 2118.3750793515796, 2136.1455417832835, 2152.0638522242716, 2162.0270878104757, 2163.6646319820297, 122378.99999999911, 129473.0, 162354.0, 162451.8812554372, 166538.0, 11669.35818529972, 11993.580224692269, 12200.572979945659, 12638.873611263954, 12887.806682899763, 16467.423640172536, 16467.917660411862, 16525.173741312887, 16552.83447165513, 16553.586160345847, 8643.481391116553, 8821.911780882238, 8822.691490690991, 8879.822403551892, 8905.332100092519, 8270.338372930151, 8321.417500774887, 8342.624581893708, 8460.746177614681, 8464.15358464161, 6514.0, 7863.410244231705, 7994.960025199837, 7995.600219988977, 8072.0, 2155.224119317045, 2176.3579743975565, 2178.7821217878086, 2180.760116387196, 2349.0603758496736, 938.1650331205233, 959.7264779537937, 962.708753167471, 964.9035096490295, 1005.2762011351815, 20394.592108157754, 20962.790372096388, 21025.33274377614, 21164.153433862473, 21637.675434868488, 983.3853841349156, 992.1715367668078, 1006.9597216111273, 1008.4755926917961, 1017.7913527726946, 10436.165106791439, 10729.356238625707, 10755.946220269005, 10758.892411075789, 10795.676129716121, 510798.0, 521307.3934630303, 523714.0, 535073.0, 541687.0, 122209.0, 126220.0, 127390.72609273974, 127845.36077319554, 128313.35843320724, 1039.4387031003173, 1128.4527004402858, 1161.1291531351487, 1193.8925496705338, 1195.9940200298943, 15594.610134746723, 17818.020008899635, 17942.55143621368, 18206.908965455088, 18408.35570755017, 43961.56038439574, 46623.6012919613, 48335.758321208166, 49069.75465122652, 49070.50929490802, 65732.01401978878, 65907.0, 66143.66928165521, 66616.66691666705, 66812.3318766806, 8805.735827925173, 8816.735497935073, 9028.909710902806, 9111.225545828678, 9125.498097604695, 13788.310584470535, 13847.515336963157, 14131.788023179866, 14320.928395357956, 14472.927635361755, 11648.41757912084, 11753.588624398106, 11783.057355411374, 11823.822642660194, 11872.703182420162, 10576.418296993432, 10657.57369705203, 10743.516541755484, 10756.731081723019, 11338.263012904135, 9751.0, 11451.141164412536, 11480.425978700865, 11886.870747278994, 12077.27536347822, 7311.780646580611, 8792.648294068165, 8957.417767845069, 9180.036096209802, 9191.908080919373, 10925.56297748113, 10961.945190274317, 11197.496112675111, 11200.327980321206, 11305.321680699184], "value": 10207.5050069379, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1395.6789938314187, 1453.9200343981083, 1457.861503157201, 1467.875230605399, 1475.0633347824132, 11545.480453379598, 11655.242409243394, 11768.941155294233, 12215.755684886308, 12221.450034748435, 10449.477526123701, 10576.365418074922, 10596.576136954513, 10611.893881061187, 10618.097461715763, 2416.7462416446283, 2464.7658472445087, 2470.407102295445, 2474.1216868011866, 2479.7148327942245, 2407.9398015049587, 2437.8049756019477, 2440.1215562397524, 2441.1944058460667, 2459.938501537467, 2344.531093781242, 2389.6893403857475, 2390.0917651292484, 2393.3537944754908, 2396.101461951768, 2380.5833979053596, 2386.1052105460367, 2399.5560821248123, 2410.2648692148887, 2412.264259400882, 1987.3839109875985, 2315.8610483370885, 2381.1070848431837, 2381.9047238110456, 2394.904203831845, 11398.316101033965, 11406.08751299894, 11521.020713239384, 11541.13441491883, 11560.190786644938, 6328.778492752777, 6574.572652777554, 6577.901331480031, 6583.670816459158, 6625.436837868739, 3996.9600303996876, 4200.222958752641, 4220.725652832556, 4266.91466170678, 4271.209826182167, 1156.1213477756964, 1165.959191428296, 1223.424990254579, 1264.0456455376225, 1277.948882044717, 15954.28205730745, 16148.869579129465, 16411.83588164115, 16420.6715865682, 16476.67046659072, 5858.091995740667, 5877.500412464903, 5927.081302398135, 6005.6396616203165, 6037.758489660452, 13639.181649101085, 13648.0, 13649.454021839214, 13651.883957986527, 13660.65848353799, 5802.767889284466, 6283.6858157091965, 6448.161738973903, 6485.935140648628, 6567.179102612174, 5885.529157667376, 5887.2052272943465, 5952.0476723724, 5994.790182343597, 6020.939790602126, 4526.162659907862, 4566.995261042565, 4587.495375508692, 4596.563326483979, 4632.837850675211, 1375.642332993409, 1412.7951447040327, 1414.299921538845, 1434.419060280588, 1438.2305466575333, 800.2237829305565, 818.5579786915118, 829.0838623321143, 829.9045260256524, 857.9871301930476, 5433.26650902131, 6149.4270010237415, 6301.306856245809, 6350.364963503613, 6420.678966051679, 529.7762169388725, 538.7817933736843, 581.4563383236688, 591.9496842768327, 611.8195132435942, 3823.1589050408866, 3971.8808435746973, 4067.9796601016806, 4412.911741765147, 4538.841140560131, 13842.792358114637, 14954.850451495564, 14982.550523484313, 15079.924600376928, 15128.243587820565, 14673.853261467462, 14783.673673423395, 14854.034487758261, 14889.255537223313, 14897.18065506386, 7397.519161254501, 8210.917890821134, 8658.393912426196, 8672.306215502746, 8693.521856297964, 6139.723712432862, 6269.404406581368, 6697.464202863662, 7018.087648605649, 7106.289371063059, 12382.318972456602, 12580.370981450707, 12748.362581871055, 12828.230306181658, 12917.870821291666, 11587.652370428901, 13140.60578182656, 13189.538366157522, 13201.273929933946, 13248.801267980794, 9142.131497507726, 9404.38871473352, 9420.95289523548, 9427.858582121411, 9439.905600943903, 10398.376097434179, 10580.470976450993, 10626.309289896131, 10636.202284828514, 10644.84032739494, 9008.549572521479, 9067.138621830916, 9129.49787761653, 9135.40619859707, 9181.173694367539, 8874.556272186235, 8905.421147625384, 8931.866022009805, 8950.820983580423, 8990.910090899008, 8116.066652334821, 8188.976377952756, 8201.42590018693, 8286.04710458305, 8288.088310285984, 5973.611715238496, 6005.879882402415, 6071.119687645355, 6121.051237058263, 6206.534509911684, 6029.336772955059, 6039.033754599243, 6201.596896201732, 6264.248290205204, 6337.112804207322], "value": 5282.187231574439, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.6391878730364, 380.6079737869995, 389.0817670298098, 401.27369461275094, 408.80173116038725, 4599.58603725665, 5315.36215654121, 5832.650040997543, 6095.811316793227, 6330.62016279023, 5267.86830329242, 5732.914006289899, 6093.634381937087, 6187.938120618793, 6223.599690069732, 4498.482674492426, 4528.705634133779, 4538.387317712116, 4552.635789136878, 4555.840545580905, 4468.19572476954, 4525.818967241306, 4541.0, 4552.908941821178, 4556.088782243548, 3806.6764325032404, 4382.145481631083, 4480.6639502037315, 4499.055198408339, 4521.909561808762, 4473.507914129443, 4473.619742321906, 4484.618807401374, 4489.304157855538, 4504.346869703907, 3485.982570087159, 3786.8674596388996, 3862.1696335287766, 3866.627347291699, 3875.6318149775725, 4951.405831300231, 6013.037913933794, 6031.4270144336215, 6121.265448146207, 6146.692665366759, 5072.416672082721, 5379.112446446332, 5390.029794636964, 5396.514313711746, 5396.865078373033, 6522.380373864523, 6531.902021469682, 6598.73605055802, 6609.603423794588, 6636.966815165893, 3636.872569503436, 4383.495897971742, 4394.60448559628, 4396.670249731283, 4398.746357288158, 5761.625494342897, 6710.463162947001, 6980.0, 7001.614911179883, 7112.146542414892, 4376.168527979674, 4391.341298805205, 4408.515063343029, 4419.779011049434, 4421.314696222091, 4293.635041021485, 4476.052916981123, 4488.730676159441, 4490.797914093873, 4501.639868810487, 4238.321868501025, 4842.6440596633, 4954.0, 5315.840524784263, 5354.41101478837, 5753.683547404849, 6603.900941485882, 6615.933840661628, 6630.044136979603, 6657.13457250564, 1358.3004752552442, 1516.4919751883242, 1530.1584128729155, 1541.4836029929875, 1544.5984044148602, 800.7597720683772, 916.3173435790379, 973.1873885305707, 1007.8992100790009, 1027.4143738069229, 5228.503292187236, 5516.91724624131, 5840.328362238312, 6226.937730622726, 6251.6874156292915, 5970.0, 6448.0, 6780.796576102724, 6832.726690932406, 6867.450603951672, 5249.160134378558, 5543.695096769717, 6286.339934306923, 6293.0, 6293.0, 3390.864060539747, 3552.236269202134, 3607.6572725591027, 3622.728295377857, 3625.7099432045375, 6450.838729031718, 6766.8646627068165, 6862.828429289208, 7066.611336376549, 7082.079329687108, 7092.503524753321, 7097.574145551283, 7100.538464999759, 7111.53775004623, 7112.217656057829, 5764.625299355528, 6816.522843400914, 6989.37095661393, 7070.823229419306, 7073.964630176816, 5128.538431541179, 6248.937510624835, 6761.729530818712, 6863.568215892054, 6878.8624227516175, 5830.387809279964, 6174.598651087665, 6601.943689009735, 6643.767468138785, 6829.453643708392, 7153.320434558708, 7157.498975071903, 7174.928250717426, 7189.676464559003, 7206.6396680164735, 3941.231459865299, 4439.200943830012, 4461.776911154494, 4464.665150113819, 4471.374007638999, 5497.175423686479, 6806.523543351917, 6829.214640316427, 6884.827879303058, 6952.687129079102, 5833.854153646193, 6116.633002019715, 6411.786739917593, 6575.243846957661, 6709.496787740842, 6441.935580644134, 6603.867922641424, 6632.635205063768, 6642.501812363996, 6690.76582319636, 6547.377999090078, 6560.475161987125, 6562.278149403657, 6579.111819904443, 6586.670666466754, 6324.6521441321165, 6477.967610161919, 6607.546339805235, 6722.1261236039, 6728.899066513908, 6522.869542609216, 6527.314631963575, 6533.314002029719, 6533.67331633407, 6561.212654481493], "value": 4767.555331409551, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [748.3040772081964, 820.4175035724635, 838.2665167978015, 854.6325080215508, 863.25328590769, 14881.404743810235, 15487.535373938772, 16800.99194048358, 17244.82755172448, 17302.0, 15096.54710358691, 18372.44882653519, 18536.165872535734, 18574.349897753582, 18765.718514222262, 958.8226178157046, 966.5457235099492, 973.6543527047902, 995.581855620641, 1001.4592120255054, 979.4368238262991, 980.3235767320562, 981.0336818234034, 981.4994352880046, 987.4667679453087, 930.1953809954408, 1021.7394564386071, 1022.8261195596766, 1026.420072658949, 1027.1577306608583, 1015.6343716262139, 1016.8017236638858, 1017.3234798858713, 1018.3380802478374, 1026.4508487958944, 745.5191401545973, 758.7344429449648, 759.7530802489158, 761.1855314813153, 761.7676608634364, 14908.70182596342, 17325.04712240826, 17449.912750436295, 17738.822611773845, 17930.282788688437, 16895.746563801553, 16966.406175783788, 17041.14794260282, 17258.827411725848, 17345.826541734546, 12054.15620906537, 13466.0, 14250.287485625677, 14532.0, 14831.703365932728, 3552.715782737375, 3572.214112895159, 3572.8392222350058, 3588.049166970755, 3592.0301518590095, 124514.37742811319, 138392.30803846018, 146464.26767866092, 147558.0, 148275.25862370728, 13421.261830599215, 13525.32373381327, 13602.31988400576, 13760.105593136414, 13760.65598360049, 26368.208953731035, 26435.0, 26519.204423867315, 26643.600345994826, 26699.866500667373, 5855.355910849803, 7496.362809161174, 7516.774496765106, 7574.962125189449, 7657.961710191414, 3207.903762887117, 3221.275213077039, 3808.086059345737, 3811.7331786774657, 3836.6547010769164, 3718.8140592970785, 4415.757133357632, 4469.284914413678, 4472.597466228055, 4712.717236965793, 890.6348397157135, 938.554186761283, 950.2303134461108, 959.2901253072815, 963.5712108111823, 637.9776707815204, 652.3509108437136, 654.5876098058193, 663.316783712767, 678.4402867634221, 11411.942940285246, 11714.941425292818, 12488.875111248772, 12696.492140314283, 12846.164999275205, 630.1555915073823, 639.3287048598952, 647.1263793878321, 669.8024082895557, 674.184237073147, 5065.8733531662, 5592.273004509388, 5787.623804452697, 5829.300483941955, 5856.1508581255475, 113518.43240783908, 148627.0, 152502.0, 152694.0, 153414.93170136533, 132545.0, 132621.0, 133166.33416832853, 134459.32770336085, 134496.9825452619, 97770.02229977751, 98464.0, 98622.50688746654, 100379.0, 100905.0, 11467.483963221508, 12931.482740690268, 13098.803517947048, 13101.541446049343, 13124.475020999053, 43574.0, 44001.89995250016, 44373.66878993635, 44488.777556112014, 44620.77689611531, 58865.70567147137, 59332.81334373202, 59629.70185149046, 59885.0, 60140.09789853243, 16963.7455438171, 17113.828861711223, 17321.65356692834, 17441.04074275927, 17473.38843140484, 18342.991135487675, 18514.351997680016, 19419.51451213731, 19444.8055519443, 19732.309369172584, 15774.763378549102, 16809.663806724042, 17511.737323939895, 18650.0, 18664.7200291993, 17617.647647047244, 18325.175367108244, 18532.980686062398, 18549.35077272343, 18558.44324670262, 17133.314667413662, 17246.741298880275, 17945.820541794936, 18154.636907261516, 18348.26606935761, 11774.234674746114, 12016.459259333176, 12540.0, 12568.685782855502, 12640.6839829005, 13217.801732974205, 13760.0, 13874.791878122038, 13916.51292204768, 13924.0], "value": 8758.422765336534, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1583.0999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 116.0, 117.0, 130.0, 114.0, 114.0, 114.0, 115.0, 116.0, 114.0], "value": 115.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 115.0, 110.0, 152.0, 127.0, 100.0, 136.0, 105.0, 156.0, 146.0], "value": 129.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [275.0, 239.0, 201.0, 216.0, 230.0, 239.0, 275.0, 230.0, 269.0, 275.0], "value": 244.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 63.0, 64.0, 85.0, 62.0, 60.0, 93.0, 54.0, 92.0, 51.0], "value": 70.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 70.0, 78.0, 82.0, 69.0, 69.0, 87.0, 72.0, 95.0, 84.0], "value": 78.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 90.0, 94.0, 91.0, 91.0, 91.0, 90.0, 90.0, 90.0], "value": 91.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 98.0, 97.0, 121.0, 88.0, 98.0, 105.0, 155.0, 98.0, 96.0], "value": 105.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 95.0, 76.0, 142.0, 143.0, 110.0, 141.0, 91.0, 113.0, 132.0], "value": 113.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 52.0, 51.0, 52.0, 53.0, 52.0, 51.0, 52.0, 51.0, 51.0], "value": 51.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 58.0, 62.0, 63.0, 65.0, 69.0, 58.0, 55.0, 55.0], "value": 59.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 85.0, 77.0, 100.0, 113.0, 113.0, 110.0, 146.0, 118.0], "value": 103.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 137.0, 148.0, 153.0, 153.0, 134.0, 150.0, 158.0, 155.0, 154.0], "value": 148.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 188.0, 185.0, 187.0, 177.0, 186.0, 185.0, 204.0, 181.0, 185.0], "value": 187.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 89.0, 71.0, 80.0, 91.0, 97.0, 99.0, 99.0, 82.0, 68.0], "value": 83.6, "unit": "score"}]}]} 14:42:19 INFO - Return code: 0 14:42:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:42:19 INFO - # TBPL SUCCESS # 14:42:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:42:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:42:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:42:19 INFO - 2017-05-26 14:42:19.470 screenresolution[1662:10974] starting screenresolution argv=screenresolution get 14:42:19 INFO - 2017-05-26 14:42:19.473 screenresolution[1662:10974] Display 0: 1600x1200x32@60 14:42:19 INFO - 2017-05-26 14:42:19.479 screenresolution[1663:10977] starting screenresolution argv=screenresolution list 14:42:19 INFO - Available Modes on Display 0 14:42:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:42:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:42:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:42:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:42:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:42:19 INFO - Intel Iris: 14:42:19 INFO - Chipset Model: Intel Iris 14:42:19 INFO - Type: GPU 14:42:19 INFO - Bus: Built-In 14:42:19 INFO - VRAM (Dynamic, Max): 1536 MB 14:42:19 INFO - Vendor: Intel (0x8086) 14:42:19 INFO - Device ID: 0x0a2e 14:42:19 INFO - Revision ID: 0x0009 14:42:19 INFO - Displays: 14:42:19 INFO - Display: 14:42:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:42:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:42:19 INFO - Display Serial Number: 1600x1200 60 14:42:19 INFO - Main Display: Yes 14:42:19 INFO - Mirror: Off 14:42:19 INFO - Online: Yes 14:42:19 INFO - Rotation: Supported 14:42:19 INFO - Return code: 0 14:42:19 INFO - Running post-action listener: _resource_record_post_action 14:42:19 INFO - [mozharness: 2017-05-26 21:42:19.713633Z] Finished run-tests step (success) 14:42:19 INFO - Running post-run listener: _resource_record_post_run 14:42:19 INFO - Total resource usage - Wall time: 1255s; CPU: 53.0%; Read bytes: 46917120; Write bytes: 565990400; Read time: 516; Write time: 1833 14:42:19 INFO - TinderboxPrint: CPU usage
53.5% 14:42:19 INFO - TinderboxPrint: I/O read bytes / time
46,917,120 / 516 14:42:19 INFO - TinderboxPrint: I/O write bytes / time
565,990,400 / 1,833 14:42:19 INFO - TinderboxPrint: CPU idle
2,335.9 (46.5%) 14:42:19 INFO - TinderboxPrint: CPU system
1,019.2 (20.3%) 14:42:19 INFO - TinderboxPrint: CPU user
1,666.5 (33.2%) 14:42:19 INFO - TinderboxPrint: Swap in / out
285,638,656 / 0 14:42:19 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 228258304; Write bytes: 229787648; Read time: 16917; Write time: 476 14:42:19 INFO - run-tests - Wall time: 1234s; CPU: 53.0%; Read bytes: 45872640; Write bytes: 317737984; Read time: 496; Write time: 1315 14:42:20 INFO - Running post-run listener: _upload_blobber_files 14:42:20 INFO - Blob upload gear active. 14:42:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:42:20 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 14:42:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:42:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:42:20 INFO - (blobuploader) - INFO - Open directory for files ... 14:42:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:42:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:42:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:42:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:42:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:42:21 INFO - (blobuploader) - INFO - Done attempting. 14:42:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:42:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:42:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:42:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:42:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:42:22 INFO - (blobuploader) - INFO - Done attempting. 14:42:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:42:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:42:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:42:23 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:42:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:42:23 INFO - (blobuploader) - INFO - Done attempting. 14:42:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:42:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:42:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:42:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:42:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:42:25 INFO - (blobuploader) - INFO - Done attempting. 14:42:25 INFO - (blobuploader) - INFO - Iteration through files over. 14:42:25 INFO - Return code: 0 14:42:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:42:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:42:25 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/49aa03e5f8153c853da50ddcd21957e5006b5e757a865fc5319135cb54d7e25da00d5811fe290ee3b9ec7ecbd0f6c411212f69240646f9f3d41d7c181eb4317c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/04df2333d99a7a5bbba42504d9ae4c6b5002c2d8793e9fe715ad96ab48747b1e101b8a93a01c9bd3ed7732809d6619ba38b626917e82ca441d4cba892318c8e1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a000806e346a8482131208648dc60379e83bcc2a69b614d9684107a13b170b290685e8476bd94cb9e0d4d9d5420f21e864fd118ab065c872eae31630300ad47f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e8a25349c53c21034a7d3f20c31e2208820f4a1bcf7a768b1f43dc246536145debb3b3649d5188d1ab83f898f14014f1540239f22b6f1816053b2728da0f5818"} 14:42:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:42:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:42:25 INFO - Contents: 14:42:25 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/49aa03e5f8153c853da50ddcd21957e5006b5e757a865fc5319135cb54d7e25da00d5811fe290ee3b9ec7ecbd0f6c411212f69240646f9f3d41d7c181eb4317c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/04df2333d99a7a5bbba42504d9ae4c6b5002c2d8793e9fe715ad96ab48747b1e101b8a93a01c9bd3ed7732809d6619ba38b626917e82ca441d4cba892318c8e1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a000806e346a8482131208648dc60379e83bcc2a69b614d9684107a13b170b290685e8476bd94cb9e0d4d9d5420f21e864fd118ab065c872eae31630300ad47f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e8a25349c53c21034a7d3f20c31e2208820f4a1bcf7a768b1f43dc246536145debb3b3649d5188d1ab83f898f14014f1540239f22b6f1816053b2728da0f5818"} 14:42:25 INFO - Running post-run listener: copy_logs_to_upload_dir 14:42:25 INFO - Copying logs to upload dir... 14:42:25 INFO - mkdir: /builds/slave/test/build/upload/logs 14:42:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1338.736176 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2017-05-26 14:42:25.948446) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-26 14:42:25.951871) ========= 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.PVcOFd9JGp/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.56KAhhZ6ku/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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/49aa03e5f8153c853da50ddcd21957e5006b5e757a865fc5319135cb54d7e25da00d5811fe290ee3b9ec7ecbd0f6c411212f69240646f9f3d41d7c181eb4317c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/04df2333d99a7a5bbba42504d9ae4c6b5002c2d8793e9fe715ad96ab48747b1e101b8a93a01c9bd3ed7732809d6619ba38b626917e82ca441d4cba892318c8e1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a000806e346a8482131208648dc60379e83bcc2a69b614d9684107a13b170b290685e8476bd94cb9e0d4d9d5420f21e864fd118ab065c872eae31630300ad47f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e8a25349c53c21034a7d3f20c31e2208820f4a1bcf7a768b1f43dc246536145debb3b3649d5188d1ab83f898f14014f1540239f22b6f1816053b2728da0f5818"} build_url:https://queue.taskcluster.net/v1/task/A2TejudkSj2FXizebDW0sQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011013 build_url: 'https://queue.taskcluster.net/v1/task/A2TejudkSj2FXizebDW0sQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/49aa03e5f8153c853da50ddcd21957e5006b5e757a865fc5319135cb54d7e25da00d5811fe290ee3b9ec7ecbd0f6c411212f69240646f9f3d41d7c181eb4317c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/04df2333d99a7a5bbba42504d9ae4c6b5002c2d8793e9fe715ad96ab48747b1e101b8a93a01c9bd3ed7732809d6619ba38b626917e82ca441d4cba892318c8e1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a000806e346a8482131208648dc60379e83bcc2a69b614d9684107a13b170b290685e8476bd94cb9e0d4d9d5420f21e864fd118ab065c872eae31630300ad47f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e8a25349c53c21034a7d3f20c31e2208820f4a1bcf7a768b1f43dc246536145debb3b3649d5188d1ab83f898f14014f1540239f22b6f1816053b2728da0f5818"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-26 14:42:25.985570) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 14:42:25.985894) ========= 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.PVcOFd9JGp/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.56KAhhZ6ku/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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.005172 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 14:42:26.034338) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-26 14:42:26.034678) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-26 14:42:26.035003) ========= ========= Total master_lag: 0.17 =========