builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0178 starttime: 1495833456.67 results: success (0) revision: ba1a33add29df1cff5931c5bd82725b8ed4990e5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-26 14:17:36.668276) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-26 14:17:36.668774) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-26 14:17:36.669123) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-26 14:17:36.694483) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 14:17:36.694902) ========= 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.PP22TxG5Wk/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.mqu14ounF3/Listeners TMPDIR=/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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:17:36-- 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% 13.5M=0.001s 2017-05-26 14:17:36 (13.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.133271 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 14:17:36.851404) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 14:17:36.851710) ========= 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.PP22TxG5Wk/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.mqu14ounF3/Listeners TMPDIR=/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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.030076 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 14:17:36.902104) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-26 14:17:36.902433) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ba1a33add29df1cff5931c5bd82725b8ed4990e5 --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 ba1a33add29df1cff5931c5bd82725b8ed4990e5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.PP22TxG5Wk/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.mqu14ounF3/Listeners TMPDIR=/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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:17:36,994 truncating revision to first 12 chars 2017-05-26 14:17:36,994 Setting DEBUG logging. 2017-05-26 14:17:36,994 attempt 1/10 2017-05-26 14:17:36,994 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ba1a33add29d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-26 14:17:39,539 unpacking tar archive at: mozilla-central-ba1a33add29d/testing/mozharness/ program finished with exit code 0 elapsedTime=2.813169 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-26 14:17:39.737617) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-26 14:17:39.737953) ========= script_repo_revision: ba1a33add29df1cff5931c5bd82725b8ed4990e5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-26 14:17:39.738316) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-26 14:17:39.738576) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-26 14:17:39.757778) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2017-05-26 14:17:39.758189) ========= /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.PP22TxG5Wk/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.mqu14ounF3/Listeners TMPDIR=/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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:17:39 INFO - MultiFileLogger online at 20170526 14:17:39 in /builds/slave/test 14:17:39 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:17:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:17:39 INFO - {'append_to_log': False, 14:17:39 INFO - 'base_work_dir': '/builds/slave/test', 14:17:39 INFO - 'blob_upload_branch': 'Firefox', 14:17:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:17:39 INFO - 'branch': 'Firefox', 14:17:39 INFO - 'buildbot_json_path': 'buildprops.json', 14:17:39 INFO - 'config_files': ('talos/mac_config.py',), 14:17:39 INFO - 'default_actions': ('clobber', 14:17:39 INFO - 'read-buildbot-config', 14:17:39 INFO - 'download-and-extract', 14:17:39 INFO - 'populate-webroot', 14:17:39 INFO - 'create-virtualenv', 14:17:39 INFO - 'install', 14:17:39 INFO - 'run-tests'), 14:17:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:17:39 INFO - 'download_minidump_stackwalk': True, 14:17:39 INFO - 'download_symbols': 'ondemand', 14:17:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:17:39 INFO - 'tooltool.py': '/tools/tooltool.py', 14:17:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:17:39 INFO - '/tools/misc-python/virtualenv.py')}, 14:17:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:17:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:17:39 INFO - 'gecko_profile': False, 14:17:39 INFO - 'gecko_profile_interval': 0, 14:17:39 INFO - 'installer_path': 'installer.exe', 14:17:39 INFO - 'log_level': 'info', 14:17:39 INFO - 'log_name': 'talos', 14:17:39 INFO - 'log_to_console': True, 14:17:39 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:17:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:17:39 INFO - 'opt_config_files': (), 14:17:39 INFO - 'pip_index': False, 14:17:39 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:17:39 INFO - 'cmd': ('bash', 14:17:39 INFO - '-c', 14:17:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:17:39 INFO - 'enabled': True, 14:17:39 INFO - 'halt_on_failure': False, 14:17:39 INFO - 'name': 'check_screen_resolution'},), 14:17:39 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:17:39 INFO - 'cmd': ('bash', 14:17:39 INFO - '-c', 14:17:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:17:39 INFO - 'enabled': True, 14:17:39 INFO - 'halt_on_failure': False, 14:17:39 INFO - 'name': 'check_screen_resolution'},), 14:17:39 INFO - 'run_cmd_checks_enabled': True, 14:17:39 INFO - 'suite': 'dromaeojs-e10s', 14:17:39 INFO - 'system_bits': '32', 14:17:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:17:39 INFO - 'title': 't-yosemite-r7-0178', 14:17:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:17:39 INFO - 'use_talos_json': True, 14:17:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:17:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:17:39 INFO - 'work_dir': 'build'} 14:17:39 INFO - [mozharness: 2017-05-26 21:17:39.966132Z] Running clobber step. 14:17:39 INFO - Running pre-action listener: _resource_record_pre_action 14:17:39 INFO - Running main action method: clobber 14:17:39 INFO - rmtree: /builds/slave/test/build 14:17:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:17:42 INFO - Running post-action listener: _resource_record_post_action 14:17:42 INFO - [mozharness: 2017-05-26 21:17:42.519731Z] Finished clobber step (success) 14:17:42 INFO - [mozharness: 2017-05-26 21:17:42.519862Z] Running read-buildbot-config step. 14:17:42 INFO - Running pre-action listener: _resource_record_pre_action 14:17:42 INFO - Running main action method: read_buildbot_config 14:17:42 INFO - Using buildbot properties: 14:17:42 INFO - { 14:17:42 INFO - "project": "", 14:17:42 INFO - "product": "firefox", 14:17:42 INFO - "who": "ryanvm@gmail.com", 14:17:42 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 14:17:42 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 14:17:42 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 14:17:42 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 14:17:42 INFO - "stage_platform": "macosx64", 14:17:42 INFO - "basedir": "/builds/slave/test", 14:17:42 INFO - "buildnumber": 2, 14:17:42 INFO - "platform": "yosemite_r7", 14:17:42 INFO - "parent_task_id": "LHz1mKwlT_qbpV2mdtWiyw", 14:17:42 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 14:17:42 INFO - "slavebuilddir": "test", 14:17:42 INFO - "taskId": "EEmaWf-nSBu_bOJRF80XqA", 14:17:42 INFO - "branch": "mozilla-central", 14:17:42 INFO - "script_repo_revision": "production", 14:17:42 INFO - "revision": "ba1a33add29df1cff5931c5bd82725b8ed4990e5", 14:17:42 INFO - "slavename": "t-yosemite-r7-0178", 14:17:42 INFO - "repo_path": "mozilla-central" 14:17:42 INFO - } 14:17:42 INFO - Finding installer, test and symbols from parent task. 14:17:42 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EEmaWf-nSBu_bOJRF80XqA'}, attempt #1 14:17:43 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EEmaWf-nSBu_bOJRF80XqA'}, attempt #1 14:17:43 INFO - Task dependencies: N0LKrkMfQ4-DDrN5eY10lg LHz1mKwlT_qbpV2mdtWiyw 14:17:43 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LHz1mKwlT_qbpV2mdtWiyw'}, attempt #1 14:17:44 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/LHz1mKwlT_qbpV2mdtWiyw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:17:44 INFO - Set test_url: https://queue.taskcluster.net/v1/task/LHz1mKwlT_qbpV2mdtWiyw/artifacts/public/build/test_packages.json 14:17:44 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/LHz1mKwlT_qbpV2mdtWiyw/artifacts/public/build/target.crashreporter-symbols.zip 14:17:44 INFO - Running post-action listener: _resource_record_post_action 14:17:44 INFO - [mozharness: 2017-05-26 21:17:44.293631Z] Finished read-buildbot-config step (success) 14:17:44 INFO - [mozharness: 2017-05-26 21:17:44.293783Z] Running download-and-extract step. 14:17:44 INFO - Running pre-action listener: _resource_record_pre_action 14:17:44 INFO - Running main action method: download_and_extract 14:17:44 INFO - mkdir: /builds/slave/test/build/tests 14:17:44 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:17:44 INFO - trying https://queue.taskcluster.net/v1/task/LHz1mKwlT_qbpV2mdtWiyw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 14:17:44 INFO - Downloading https://queue.taskcluster.net/v1/task/LHz1mKwlT_qbpV2mdtWiyw/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:17:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LHz1mKwlT_qbpV2mdtWiyw/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:17:45 INFO - Downloaded 1325 bytes. 14:17:45 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 14:17:45 INFO - Using the following test package requirements: 14:17:45 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:17:45 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 14:17:45 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 14:17:45 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:17:45 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 14:17:45 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:17:45 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 14:17:45 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:17:45 INFO - u'jsshell-mac.zip'], 14:17:45 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:17:45 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 14:17:45 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 14:17:45 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:17:45 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 14:17:45 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:17:45 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 14:17:45 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:17:45 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 14:17:45 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:17:45 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 14:17:45 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:17:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LHz1mKwlT_qbpV2mdtWiyw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 14:17:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LHz1mKwlT_qbpV2mdtWiyw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:17:45 INFO - Fetch https://queue.taskcluster.net/v1/task/LHz1mKwlT_qbpV2mdtWiyw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 14:17:46 INFO - Content-Length response header: 57631993 14:17:46 INFO - Bytes received: 57631993 14:18:05 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:18:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LHz1mKwlT_qbpV2mdtWiyw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 14:18:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LHz1mKwlT_qbpV2mdtWiyw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:18:05 INFO - Fetch https://queue.taskcluster.net/v1/task/LHz1mKwlT_qbpV2mdtWiyw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 14:18:07 INFO - Content-Length response header: 57631993 14:18:07 INFO - Bytes received: 57631993 14:18:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LHz1mKwlT_qbpV2mdtWiyw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 14:18:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LHz1mKwlT_qbpV2mdtWiyw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 14:18:24 INFO - Fetch https://queue.taskcluster.net/v1/task/LHz1mKwlT_qbpV2mdtWiyw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 14:18:25 INFO - Content-Length response header: 14411888 14:18:25 INFO - Bytes received: 14411888 14:18:25 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:18:25 INFO - trying https://queue.taskcluster.net/v1/task/LHz1mKwlT_qbpV2mdtWiyw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:18:25 INFO - Downloading https://queue.taskcluster.net/v1/task/LHz1mKwlT_qbpV2mdtWiyw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 14:18:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LHz1mKwlT_qbpV2mdtWiyw/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:18:27 INFO - Downloaded 67190599 bytes. 14:18:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LHz1mKwlT_qbpV2mdtWiyw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:18:27 INFO - mkdir: /builds/slave/test/properties 14:18:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:18:27 INFO - Writing to file /builds/slave/test/properties/build_url 14:18:27 INFO - Contents: 14:18:27 INFO - build_url:https://queue.taskcluster.net/v1/task/LHz1mKwlT_qbpV2mdtWiyw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:18:27 INFO - Running post-action listener: _resource_record_post_action 14:18:27 INFO - Running post-action listener: set_extra_try_arguments 14:18:27 INFO - [mozharness: 2017-05-26 21:18:27.724292Z] Finished download-and-extract step (success) 14:18:27 INFO - [mozharness: 2017-05-26 21:18:27.724430Z] Running populate-webroot step. 14:18:27 INFO - Running pre-action listener: _resource_record_pre_action 14:18:27 INFO - Running main action method: populate_webroot 14:18:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:18:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:18:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:18:27 INFO - '--tppagecycles', 14:18:27 INFO - '7'], 14:18:27 INFO - 'tests': ['tsvgm']}}, 14:18:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:18:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:18:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:18:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:18:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:18:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:18:27 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:18:27 INFO - 'talos_options': ['--disable-e10s'], 14:18:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:18:27 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:18:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:18:27 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:18:27 INFO - 'talos_options': ['--disable-e10s'], 14:18:27 INFO - 'tests': ['damp', 'tps']}, 14:18:27 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:18:27 INFO - 'tests': ['damp', 'tps']}, 14:18:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:18:27 INFO - 'tests': ['dromaeo_dom']}, 14:18:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:18:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:18:27 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 14:18:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 14:18:27 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 14:18:27 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 14:18:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:18:27 INFO - 'tests': ['a11yr', 14:18:27 INFO - 'ts_paint', 14:18:27 INFO - 'tpaint', 14:18:27 INFO - 'sessionrestore', 14:18:27 INFO - 'sessionrestore_no_auto_restore', 14:18:27 INFO - 'tabpaint']}, 14:18:27 INFO - 'other-e10s': {'tests': ['a11yr', 14:18:27 INFO - 'ts_paint', 14:18:27 INFO - 'tpaint', 14:18:27 INFO - 'sessionrestore', 14:18:27 INFO - 'sessionrestore_no_auto_restore', 14:18:27 INFO - 'tabpaint']}, 14:18:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:18:27 INFO - 'ts_paint', 14:18:27 INFO - 'tpaint', 14:18:27 INFO - 'sessionrestore', 14:18:27 INFO - 'sessionrestore_no_auto_restore', 14:18:27 INFO - 'tabpaint']}, 14:18:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:18:27 INFO - 'ts_paint', 14:18:27 INFO - 'tpaint', 14:18:27 INFO - 'sessionrestore', 14:18:27 INFO - 'sessionrestore_no_auto_restore', 14:18:27 INFO - 'tabpaint']}, 14:18:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:18:27 INFO - 'tests': ['a11yr', 14:18:27 INFO - 'ts_paint', 14:18:27 INFO - 'tpaint', 14:18:27 INFO - 'sessionrestore', 14:18:27 INFO - 'sessionrestore_no_auto_restore', 14:18:27 INFO - 'tabpaint']}, 14:18:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:18:27 INFO - 'tests': ['a11yr', 14:18:27 INFO - 'ts_paint', 14:18:27 INFO - 'tpaint', 14:18:27 INFO - 'sessionrestore', 14:18:27 INFO - 'sessionrestore_no_auto_restore', 14:18:27 INFO - 'tabpaint']}, 14:18:27 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 14:18:27 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 14:18:27 INFO - 'bloom_basic_ref']}, 14:18:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:18:27 INFO - 'tests': ['tsvgx', 14:18:27 INFO - 'tsvgr_opacity', 14:18:27 INFO - 'tart', 14:18:27 INFO - 'tscrollx', 14:18:27 INFO - 'cart', 14:18:27 INFO - 'tsvg_static']}, 14:18:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:18:27 INFO - 'tsvgr_opacity', 14:18:27 INFO - 'tart', 14:18:27 INFO - 'tscrollx', 14:18:27 INFO - 'cart', 14:18:27 INFO - 'tsvg_static']}, 14:18:27 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:18:27 INFO - 'talos_options': ['--disable-e10s'], 14:18:27 INFO - 'tests': ['tp5o']}, 14:18:27 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:18:27 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:18:27 INFO - 'talos_options': ['--disable-e10s', 14:18:27 INFO - '--xperf_path', 14:18:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:18:27 INFO - 'tests': ['tp5n']}, 14:18:27 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:18:27 INFO - 'talos_options': ['--xperf_path', 14:18:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:18:27 INFO - 'tests': ['tp5n']}}, 14:18:27 INFO - 'talos.zip': {'path': '', 14:18:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:18:27 INFO - Running post-action listener: _resource_record_post_action 14:18:27 INFO - [mozharness: 2017-05-26 21:18:27.745813Z] Finished populate-webroot step (success) 14:18:27 INFO - [mozharness: 2017-05-26 21:18:27.745960Z] Running create-virtualenv step. 14:18:27 INFO - Running pre-action listener: _resource_record_pre_action 14:18:27 INFO - Running main action method: create_virtualenv 14:18:27 INFO - Creating virtualenv /builds/slave/test/build/venv 14:18:27 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:18:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:18:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:18:27 INFO - Using real prefix '/tools/python27' 14:18:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:18:28 INFO - Installing distribute.............................................................................................................................................................................................done. 14:18:31 INFO - Installing pip.................done. 14:18:31 INFO - Return code: 0 14:18:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:18:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:18:31 INFO - Reading from file tmpfile_stdout 14:18:31 INFO - Output received: 14:18:31 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:18:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:18:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:31 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 0x10bbfab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbfe768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb03955aec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b90ad30>, '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 0x10b8bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b8ffbe8>, '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.PP22TxG5Wk/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.mqu14ounF3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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:18:31 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:18:31 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:18:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PP22TxG5Wk/Render', 14:18:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:18:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:18:31 INFO - 'HOME': '/Users/cltbld', 14:18:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:18:31 INFO - 'LOGNAME': 'cltbld', 14:18:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:31 INFO - 'MOZ_NO_REMOTE': '1', 14:18:31 INFO - 'NO_EM_RESTART': '1', 14:18:31 INFO - 'PAGER': '/bin/cat', 14:18:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:18:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:31 INFO - 'PWD': '/builds/slave/test', 14:18:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:18:31 INFO - 'SHELL': '/bin/bash', 14:18:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mqu14ounF3/Listeners', 14:18:31 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 14:18:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:18:31 INFO - 'USER': 'cltbld', 14:18:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:18:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:18:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:31 INFO - 'XPC_FLAGS': '0x0', 14:18:31 INFO - 'XPC_SERVICE_NAME': '0', 14:18:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:18:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:31 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:18:31 INFO - Cleaning up... 14:18:32 INFO - Return code: 0 14:18:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:18:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10bbfab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbfe768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb03955aec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b90ad30>, '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 0x10b8bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b8ffbe8>, '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.PP22TxG5Wk/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.mqu14ounF3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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:18:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:18:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:18:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PP22TxG5Wk/Render', 14:18:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:18:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:18:32 INFO - 'HOME': '/Users/cltbld', 14:18:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:18:32 INFO - 'LOGNAME': 'cltbld', 14:18:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:32 INFO - 'MOZ_NO_REMOTE': '1', 14:18:32 INFO - 'NO_EM_RESTART': '1', 14:18:32 INFO - 'PAGER': '/bin/cat', 14:18:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:18:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:32 INFO - 'PWD': '/builds/slave/test', 14:18:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:18:32 INFO - 'SHELL': '/bin/bash', 14:18:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mqu14ounF3/Listeners', 14:18:32 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 14:18:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:18:32 INFO - 'USER': 'cltbld', 14:18:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:18:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:18:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:32 INFO - 'XPC_FLAGS': '0x0', 14:18:32 INFO - 'XPC_SERVICE_NAME': '0', 14:18:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:18:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:32 INFO - Downloading/unpacking psutil>=3.1.1 14:18:32 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:18:32 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:18:32 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:18:32 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:18:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:18:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:18:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:18:35 INFO - Installing collected packages: psutil 14:18:35 INFO - Running setup.py install for psutil 14:18:35 INFO - building 'psutil._psutil_osx' extension 14:18:35 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:18:35 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:18:36 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:18:36 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:18:36 INFO - building 'psutil._psutil_posix' extension 14:18:36 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:18:36 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:18:36 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:18:36 INFO - ^ 14:18:36 INFO - 1 warning generated. 14:18:36 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:18:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:18:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:18:36 INFO - Successfully installed psutil 14:18:36 INFO - Cleaning up... 14:18:36 INFO - Return code: 0 14:18:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:18:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bbfab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbfe768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb03955aec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b90ad30>, '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 0x10b8bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b8ffbe8>, '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.PP22TxG5Wk/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.mqu14ounF3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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:18:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:18:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:18:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PP22TxG5Wk/Render', 14:18:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:18:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:18:36 INFO - 'HOME': '/Users/cltbld', 14:18:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:18:36 INFO - 'LOGNAME': 'cltbld', 14:18:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:36 INFO - 'MOZ_NO_REMOTE': '1', 14:18:36 INFO - 'NO_EM_RESTART': '1', 14:18:36 INFO - 'PAGER': '/bin/cat', 14:18:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:18:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:36 INFO - 'PWD': '/builds/slave/test', 14:18:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:18:36 INFO - 'SHELL': '/bin/bash', 14:18:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mqu14ounF3/Listeners', 14:18:36 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 14:18:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:18:36 INFO - 'USER': 'cltbld', 14:18:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:18:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:18:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:36 INFO - 'XPC_FLAGS': '0x0', 14:18:36 INFO - 'XPC_SERVICE_NAME': '0', 14:18:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:18:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:18:37 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:18:37 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:18:37 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:18:37 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:18:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:18:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:18:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:18:38 INFO - Installing collected packages: mozsystemmonitor 14:18:38 INFO - Running setup.py install for mozsystemmonitor 14:18:38 INFO - Successfully installed mozsystemmonitor 14:18:38 INFO - Cleaning up... 14:18:38 INFO - Return code: 0 14:18:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:18:38 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:18:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:38 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:18:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:38 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 0x10bbfab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbfe768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb03955aec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b90ad30>, '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 0x10b8bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b8ffbe8>, '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.PP22TxG5Wk/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.mqu14ounF3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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:18:38 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:18:38 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:18:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PP22TxG5Wk/Render', 14:18:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:18:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:18:38 INFO - 'HOME': '/Users/cltbld', 14:18:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:18:38 INFO - 'LOGNAME': 'cltbld', 14:18:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:38 INFO - 'MOZ_NO_REMOTE': '1', 14:18:38 INFO - 'NO_EM_RESTART': '1', 14:18:38 INFO - 'PAGER': '/bin/cat', 14:18:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:18:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:38 INFO - 'PWD': '/builds/slave/test', 14:18:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:18:38 INFO - 'SHELL': '/bin/bash', 14:18:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mqu14ounF3/Listeners', 14:18:38 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 14:18:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:18:38 INFO - 'USER': 'cltbld', 14:18:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:18:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:18:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:38 INFO - 'XPC_FLAGS': '0x0', 14:18:38 INFO - 'XPC_SERVICE_NAME': '0', 14:18:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:18:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:39 INFO - Downloading/unpacking jsonschema==2.5.1 14:18:39 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:18:39 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:18:39 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:18:39 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:18:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:18:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:18:40 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:18:40 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:18:40 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:18:40 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:18:40 INFO - Downloading functools32-3.2.3-2.tar.gz 14:18:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:18:40 INFO - warning: no files found matching '*.txt' 14:18:40 INFO - no previously-included directories found matching 'build' 14:18:40 INFO - no previously-included directories found matching 'dist' 14:18:40 INFO - no previously-included directories found matching '.git*' 14:18:40 INFO - Installing collected packages: jsonschema, functools32 14:18:40 INFO - Running setup.py install for functools32 14:18:41 INFO - warning: no files found matching '*.txt' 14:18:41 INFO - no previously-included directories found matching 'build' 14:18:41 INFO - no previously-included directories found matching 'dist' 14:18:41 INFO - no previously-included directories found matching '.git*' 14:18:41 INFO - Successfully installed jsonschema functools32 14:18:41 INFO - Cleaning up... 14:18:41 INFO - Return code: 0 14:18:41 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:18:41 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:18:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:41 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:18:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:41 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 0x10bbfab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbfe768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb03955aec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b90ad30>, '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 0x10b8bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b8ffbe8>, '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.PP22TxG5Wk/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.mqu14ounF3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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:18:41 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:18:41 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:18:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PP22TxG5Wk/Render', 14:18:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:18:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:18:41 INFO - 'HOME': '/Users/cltbld', 14:18:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:18:41 INFO - 'LOGNAME': 'cltbld', 14:18:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:41 INFO - 'MOZ_NO_REMOTE': '1', 14:18:41 INFO - 'NO_EM_RESTART': '1', 14:18:41 INFO - 'PAGER': '/bin/cat', 14:18:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:18:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:41 INFO - 'PWD': '/builds/slave/test', 14:18:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:18:41 INFO - 'SHELL': '/bin/bash', 14:18:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mqu14ounF3/Listeners', 14:18:41 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 14:18:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:18:41 INFO - 'USER': 'cltbld', 14:18:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:18:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:18:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:41 INFO - 'XPC_FLAGS': '0x0', 14:18:41 INFO - 'XPC_SERVICE_NAME': '0', 14:18:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:18:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:18:41 INFO - Cleaning up... 14:18:41 INFO - Return code: 0 14:18:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:18:41 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:18:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:41 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:18:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:41 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 0x10bbfab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbfe768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb03955aec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b90ad30>, '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 0x10b8bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b8ffbe8>, '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.PP22TxG5Wk/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.mqu14ounF3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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:18:41 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:18:41 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:18:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PP22TxG5Wk/Render', 14:18:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:18:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:18:41 INFO - 'HOME': '/Users/cltbld', 14:18:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:18:41 INFO - 'LOGNAME': 'cltbld', 14:18:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:41 INFO - 'MOZ_NO_REMOTE': '1', 14:18:41 INFO - 'NO_EM_RESTART': '1', 14:18:41 INFO - 'PAGER': '/bin/cat', 14:18:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:18:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:41 INFO - 'PWD': '/builds/slave/test', 14:18:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:18:41 INFO - 'SHELL': '/bin/bash', 14:18:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mqu14ounF3/Listeners', 14:18:41 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 14:18:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:18:41 INFO - 'USER': 'cltbld', 14:18:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:18:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:18:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:41 INFO - 'XPC_FLAGS': '0x0', 14:18:41 INFO - 'XPC_SERVICE_NAME': '0', 14:18:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:18:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:41 INFO - Downloading/unpacking blobuploader==1.2.4 14:18:41 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:18:41 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:18:41 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:18:41 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:18:43 INFO - Downloading blobuploader-1.2.4.tar.gz 14:18:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:18:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:18:43 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:18:43 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:18:43 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:18:43 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:18:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:18:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:18:43 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:18:43 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:18:43 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:18:43 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:18:43 INFO - Downloading docopt-0.6.1.tar.gz 14:18:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:18:44 INFO - Installing collected packages: blobuploader, requests, docopt 14:18:44 INFO - Running setup.py install for blobuploader 14:18:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:18:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:18:44 INFO - Running setup.py install for requests 14:18:44 INFO - Running setup.py install for docopt 14:18:44 INFO - Successfully installed blobuploader requests docopt 14:18:44 INFO - Cleaning up... 14:18:44 INFO - Return code: 0 14:18:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:18:44 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:18:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:44 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:18:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:44 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 0x10bbfab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbfe768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb03955aec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b90ad30>, '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 0x10b8bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b8ffbe8>, '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.PP22TxG5Wk/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.mqu14ounF3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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:18:44 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:18:44 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:18:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PP22TxG5Wk/Render', 14:18:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:18:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:18:44 INFO - 'HOME': '/Users/cltbld', 14:18:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:18:44 INFO - 'LOGNAME': 'cltbld', 14:18:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:44 INFO - 'MOZ_NO_REMOTE': '1', 14:18:44 INFO - 'NO_EM_RESTART': '1', 14:18:44 INFO - 'PAGER': '/bin/cat', 14:18:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:18:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:44 INFO - 'PWD': '/builds/slave/test', 14:18:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:18:44 INFO - 'SHELL': '/bin/bash', 14:18:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mqu14ounF3/Listeners', 14:18:44 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 14:18:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:18:44 INFO - 'USER': 'cltbld', 14:18:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:18:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:18:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:44 INFO - 'XPC_FLAGS': '0x0', 14:18:44 INFO - 'XPC_SERVICE_NAME': '0', 14:18:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:18:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:18:45 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-ymjUQ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:18:45 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-ygzY_j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:18:45 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-NUFvh9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:18:45 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-uk9MRy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:18:45 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-3VdnZE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:18:45 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-UayZcn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:18:45 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-I0JfIm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:18:45 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-D3pZHS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:18:45 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-JKV5XI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:18:46 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-jL3AMr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:18:46 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-kXSPkh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:18:46 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-Tg8vUt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:18:46 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-AHHD6t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:18:46 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-uSNkAk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:18:46 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-PNtyML-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:18:46 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-V10erk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:18:46 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-0hTie_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:18:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:18:46 INFO - Running setup.py install for manifestparser 14:18:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:18:47 INFO - Running setup.py install for mozcrash 14:18:47 INFO - Running setup.py install for mozdebug 14:18:47 INFO - Running setup.py install for mozdevice 14:18:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:18:47 INFO - Running setup.py install for mozfile 14:18:47 INFO - Running setup.py install for mozhttpd 14:18:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:18:47 INFO - Running setup.py install for mozinfo 14:18:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:18:47 INFO - Running setup.py install for mozInstall 14:18:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:18:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:18:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:18:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:18:48 INFO - Running setup.py install for mozleak 14:18:48 INFO - Running setup.py install for mozlog 14:18:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:18:48 INFO - Running setup.py install for moznetwork 14:18:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:18:48 INFO - Running setup.py install for mozprocess 14:18:48 INFO - Running setup.py install for mozprofile 14:18:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:18:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:18:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:18:48 INFO - Running setup.py install for mozrunner 14:18:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:18:48 INFO - Running setup.py install for mozscreenshot 14:18:49 INFO - Running setup.py install for moztest 14:18:49 INFO - Running setup.py install for mozversion 14:18:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:18:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:18:49 INFO - Cleaning up... 14:18:49 INFO - Return code: 0 14:18:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:18:49 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:18:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:49 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:18:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:49 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 0x10bbfab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbfe768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb03955aec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b90ad30>, '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 0x10b8bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b8ffbe8>, '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.PP22TxG5Wk/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.mqu14ounF3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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:18:49 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:18:49 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:18:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PP22TxG5Wk/Render', 14:18:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:18:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:18:49 INFO - 'HOME': '/Users/cltbld', 14:18:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:18:49 INFO - 'LOGNAME': 'cltbld', 14:18:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:49 INFO - 'MOZ_NO_REMOTE': '1', 14:18:49 INFO - 'NO_EM_RESTART': '1', 14:18:49 INFO - 'PAGER': '/bin/cat', 14:18:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:18:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:49 INFO - 'PWD': '/builds/slave/test', 14:18:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:18:49 INFO - 'SHELL': '/bin/bash', 14:18:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mqu14ounF3/Listeners', 14:18:49 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 14:18:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:18:49 INFO - 'USER': 'cltbld', 14:18:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:18:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:18:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:49 INFO - 'XPC_FLAGS': '0x0', 14:18:49 INFO - 'XPC_SERVICE_NAME': '0', 14:18:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:18:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:18:49 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-1xg52h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:18:49 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:18:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:18:49 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-Y6DnHJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:18:49 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:18:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:18:49 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-z6JLaZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:18:50 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:18:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:18:50 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-LWTTvo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:18:50 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:18:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:18:50 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-nJ5Pzt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:18:50 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:18:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:18:50 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-y_minr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:18:50 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:18:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:18:50 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-nnrvNb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:18:50 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:18:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:18:50 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-r4OXze-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:18:50 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:18:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:18:50 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-s6UXH0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:18:50 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:18:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:18:50 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-7f6iUw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:18:50 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:18:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:18:50 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-nlcsbx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:18:50 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:18:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:18:50 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-M0y090-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:18:50 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:18:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:18:50 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-Nj_yau-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:18:51 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:18:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:18:51 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-_oNmqf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:18:51 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:18:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:18:51 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-5fY8p_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:18:51 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:18:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:18:51 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-eH37ZZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:18:51 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:18:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:18:51 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-cHpXCf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:18:51 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:18:51 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:18:51 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:18:51 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:18:51 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:18:51 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:18:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:18:51 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:18:51 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:18:51 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:18:51 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:18:52 INFO - Downloading blessings-1.6.tar.gz 14:18:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:18:52 INFO - Installing collected packages: blessings 14:18:52 INFO - Running setup.py install for blessings 14:18:53 INFO - Successfully installed blessings 14:18:53 INFO - Cleaning up... 14:18:53 INFO - Return code: 0 14:18:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:18:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:18:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:18:53 INFO - Reading from file tmpfile_stdout 14:18:53 INFO - Current package versions: 14:18:53 INFO - blessings == 1.6 14:18:53 INFO - blobuploader == 1.2.4 14:18:53 INFO - docopt == 0.6.1 14:18:53 INFO - functools32 == 3.2.3-2 14:18:53 INFO - jsonschema == 2.5.1 14:18:53 INFO - manifestparser == 1.1 14:18:53 INFO - mozInstall == 1.12 14:18:53 INFO - mozcrash == 1.0 14:18:53 INFO - mozdebug == 0.1 14:18:53 INFO - mozdevice == 0.51 14:18:53 INFO - mozfile == 1.2 14:18:53 INFO - mozhttpd == 0.7 14:18:53 INFO - mozinfo == 0.9 14:18:53 INFO - mozleak == 0.1 14:18:53 INFO - mozlog == 3.4 14:18:53 INFO - moznetwork == 0.27 14:18:53 INFO - mozprocess == 0.25 14:18:53 INFO - mozprofile == 0.28 14:18:53 INFO - mozrunner == 6.13 14:18:53 INFO - mozscreenshot == 0.1 14:18:53 INFO - mozsystemmonitor == 0.3 14:18:53 INFO - moztest == 0.8 14:18:53 INFO - mozversion == 1.4 14:18:53 INFO - psutil == 3.1.1 14:18:53 INFO - requests == 1.2.3 14:18:53 INFO - wsgiref == 0.1.2 14:18:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:18: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:18:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18: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:18:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:53 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 0x10bbfab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbfe768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb03955aec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b90ad30>, '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 0x10b8bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b8ffbe8>, '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.PP22TxG5Wk/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.mqu14ounF3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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:18:53 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:18:53 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:18:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PP22TxG5Wk/Render', 14:18:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:18:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:18:53 INFO - 'HOME': '/Users/cltbld', 14:18:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:18:53 INFO - 'LOGNAME': 'cltbld', 14:18:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:53 INFO - 'MOZ_NO_REMOTE': '1', 14:18:53 INFO - 'NO_EM_RESTART': '1', 14:18:53 INFO - 'PAGER': '/bin/cat', 14:18:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:18:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:53 INFO - 'PWD': '/builds/slave/test', 14:18:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:18:53 INFO - 'SHELL': '/bin/bash', 14:18:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mqu14ounF3/Listeners', 14:18:53 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 14:18:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:18:53 INFO - 'USER': 'cltbld', 14:18:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:18:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:18:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:53 INFO - 'XPC_FLAGS': '0x0', 14:18:53 INFO - 'XPC_SERVICE_NAME': '0', 14:18:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:18:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:53 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:18:53 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:18:53 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:18:53 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:18:53 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:18:53 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:18:53 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:18:53 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:18:53 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:18:53 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 14:18:53 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:18:53 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:18:53 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:18:53 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:18:55 INFO - Downloading marionette_driver-1.4.0.tar.gz 14:18:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 14:18:55 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:18:55 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:18:55 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:18:55 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:18:55 INFO - Installing collected packages: marionette-driver 14:18:55 INFO - Running setup.py install for marionette-driver 14:18:55 INFO - Successfully installed marionette-driver 14:18:55 INFO - Cleaning up... 14:18:55 INFO - Return code: 0 14:18:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:18:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:55 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 0x10bbfab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbfe768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb03955aec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b90ad30>, '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 0x10b8bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b8ffbe8>, '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.PP22TxG5Wk/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.mqu14ounF3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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:18:55 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:18:55 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:18:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PP22TxG5Wk/Render', 14:18:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:18:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:18:55 INFO - 'HOME': '/Users/cltbld', 14:18:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:18:55 INFO - 'LOGNAME': 'cltbld', 14:18:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:55 INFO - 'MOZ_NO_REMOTE': '1', 14:18:55 INFO - 'NO_EM_RESTART': '1', 14:18:55 INFO - 'PAGER': '/bin/cat', 14:18:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:18:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:55 INFO - 'PWD': '/builds/slave/test', 14:18:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:18:55 INFO - 'SHELL': '/bin/bash', 14:18:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mqu14ounF3/Listeners', 14:18:55 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 14:18:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:18:55 INFO - 'USER': 'cltbld', 14:18:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:18:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:18:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:55 INFO - 'XPC_FLAGS': '0x0', 14:18:55 INFO - 'XPC_SERVICE_NAME': '0', 14:18:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:18:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:18:55 INFO - Cleaning up... 14:18:55 INFO - Return code: 0 14:18:55 INFO - Running post-action listener: _resource_record_post_action 14:18:55 INFO - Running post-action listener: _start_resource_monitoring 14:18:55 INFO - Starting resource monitoring. 14:18:55 INFO - [mozharness: 2017-05-26 21:18:55.866252Z] Finished create-virtualenv step (success) 14:18:55 INFO - [mozharness: 2017-05-26 21:18:55.866996Z] Running install step. 14:18:55 INFO - Running pre-action listener: _resource_record_pre_action 14:18:55 INFO - Running main action method: install 14:18:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:18:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:18:56 INFO - Reading from file tmpfile_stdout 14:18:56 INFO - Detecting whether we're running mozinstall >=1.0... 14:18:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:18:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:18:56 INFO - Reading from file tmpfile_stdout 14:18:56 INFO - Output received: 14:18:56 INFO - Usage: mozinstall [options] installer 14:18:56 INFO - Options: 14:18:56 INFO - -h, --help show this help message and exit 14:18:56 INFO - -d DEST, --destination=DEST 14:18:56 INFO - Directory to install application into. [default: 14:18:56 INFO - "/builds/slave/test"] 14:18:56 INFO - --app=APP Application being installed. [default: firefox] 14:18:56 INFO - mkdir: /builds/slave/test/build/application 14:18:56 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:18:56 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:19:17 INFO - Reading from file tmpfile_stdout 14:19:17 INFO - Output received: 14:19:17 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:19:17 INFO - Running post-action listener: _resource_record_post_action 14:19:17 INFO - [mozharness: 2017-05-26 21:19:17.202215Z] Finished install step (success) 14:19:17 INFO - [mozharness: 2017-05-26 21:19:17.202383Z] Running run-tests step. 14:19:17 INFO - Running pre-action listener: _resource_record_pre_action 14:19:17 INFO - Running main action method: run_tests 14:19:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:19:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:19:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:19:17 INFO - 2017-05-26 14:19:17.216 screenresolution[1853:8339] starting screenresolution argv=screenresolution get 14:19:17 INFO - 2017-05-26 14:19:17.219 screenresolution[1853:8339] Display 0: 1600x1200x32@60 14:19:17 INFO - 2017-05-26 14:19:17.225 screenresolution[1854:8342] starting screenresolution argv=screenresolution list 14:19:17 INFO - Available Modes on Display 0 14:19:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:19:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:19:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:19:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:19:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:19:17 INFO - Intel Iris: 14:19:17 INFO - Chipset Model: Intel Iris 14:19:17 INFO - Type: GPU 14:19:17 INFO - Bus: Built-In 14:19:17 INFO - VRAM (Dynamic, Max): 1536 MB 14:19:17 INFO - Vendor: Intel (0x8086) 14:19:17 INFO - Device ID: 0x0a2e 14:19:17 INFO - Revision ID: 0x0009 14:19:17 INFO - Displays: 14:19:17 INFO - Display: 14:19:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:19:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:19:17 INFO - Display Serial Number: 1600x1200 60 14:19:17 INFO - Main Display: Yes 14:19:17 INFO - Mirror: Off 14:19:17 INFO - Online: Yes 14:19:17 INFO - Rotation: Supported 14:19:17 INFO - Return code: 0 14:19:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:19:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:19:17 INFO - Python 2.7.3 14:19:17 INFO - Return code: 0 14:19:17 INFO - grabbing minidump binary from tooltool 14:19: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:19:17 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 0x10b90ad30>, '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 0x10b8bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b8ffbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:19:17 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:19:17 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:19:17 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:19:17 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:19:17 INFO - Return code: 0 14:19:17 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:19:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:19:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:19:17 INFO - ENV: RUST_BACKTRACE is now 1 14:19:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:19:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:19:17 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/LHz1mKwlT_qbpV2mdtWiyw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0178', '--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:19:17 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/LHz1mKwlT_qbpV2mdtWiyw/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0178 --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:19:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PP22TxG5Wk/Render', 14:19:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:19:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:19:17 INFO - 'HOME': '/Users/cltbld', 14:19:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:19:17 INFO - 'LOGNAME': 'cltbld', 14:19:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:19:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:19:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:17 INFO - 'MOZ_NO_REMOTE': '1', 14:19:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:19:17 INFO - 'NO_EM_RESTART': '1', 14:19:17 INFO - 'PAGER': '/bin/cat', 14:19:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:19:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:17 INFO - 'PWD': '/builds/slave/test', 14:19:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:19:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:19:17 INFO - 'RUST_BACKTRACE': '1', 14:19:17 INFO - 'SHELL': '/bin/bash', 14:19:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mqu14ounF3/Listeners', 14:19:17 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 14:19:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:19:17 INFO - 'USER': 'cltbld', 14:19:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:19:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:19:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:17 INFO - 'XPC_FLAGS': '0x0', 14:19:17 INFO - 'XPC_SERVICE_NAME': '0', 14:19:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:19:17 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/LHz1mKwlT_qbpV2mdtWiyw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0178', '--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:19:17 INFO - mozversion application_buildid: 20170526124644 14:19:17 INFO - mozversion application_changeset: ba1a33add29df1cff5931c5bd82725b8ed4990e5 14:19:17 INFO - mozversion application_display_name: Nightly 14:19:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:19:17 INFO - mozversion application_name: Firefox 14:19:17 INFO - mozversion application_remotingname: firefox 14:19:17 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:19:17 INFO - mozversion application_vendor: Mozilla 14:19:17 INFO - mozversion application_version: 55.0a1 14:19:17 INFO - mozversion platform_buildid: 20170526124644 14:19:17 INFO - mozversion platform_changeset: ba1a33add29df1cff5931c5bd82725b8ed4990e5 14:19:17 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:19:17 INFO - mozversion platform_version: 55.0a1 14:19:17 INFO - using testdate: 1495833557 14:19:17 INFO - actual date: 1495833557 14:19:17 INFO - starting webserver on 'localhost:49239' 14:19:17 INFO - SUITE-START | Running 2 tests 14:19:17 INFO - TEST-START | dromaeo_css 14:19:17 INFO - operating with platform_type : mac_ 14:19:17 INFO - Initialising browser for dromaeo_css test... 14:19:17 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49239/getInfo.html -foreground -profile /var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/tmpSUzh1G/profile 14:19:17 INFO - TEST-INFO | started process 1864 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49239/getInfo.html -foreground) 14:19:51 INFO - TEST-INFO | 1864: killed by SIGTERM 14:19:51 INFO - Browser initialized. 14:19:51 INFO - Running cycle 1/1 for dromaeo_css test... 14:19:51 INFO - TEST-INFO | started process 1871 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/tmpSUzh1G/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:19:53 INFO - PID 1871 | 2017-05-26 14:19:53.491 plugin-container[1872:8800] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.tsm.portname' 14:19:53 INFO - PID 1871 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:19:53 INFO - PID 1871 | 2017-05-26 14:19:53.492 plugin-container[1872:8800] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4823, name = 'com.apple.CFPasteboardClient' 14:19:53 INFO - PID 1871 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:19:53 INFO - PID 1871 | 2017-05-26 14:19:53.492 plugin-container[1872:8800] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:19:54 INFO - PID 1871 | 2017-05-26 14:19:54.053 plugin-container[1873:8842] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4543, name = 'com.apple.tsm.portname' 14:19:54 INFO - PID 1871 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:19:54 INFO - PID 1871 | 2017-05-26 14:19:54.053 plugin-container[1873:8842] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x971f, name = 'com.apple.CFPasteboardClient' 14:19:54 INFO - PID 1871 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:19:54 INFO - PID 1871 | 2017-05-26 14:19:54.053 plugin-container[1873:8842] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:20:03 INFO - PID 1871 | RSS: Main: 242950144 14:20:03 INFO - PID 1871 | 14:23:06 INFO - PID 1871 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49239/tests/dromaeo/cssquery-ext.html) 14:23:07 INFO - PID 1871 | RSS: Main: 256282624 14:23:07 INFO - PID 1871 | 14:26:09 INFO - PID 1871 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-ext.html (next: http://localhost:49239/tests/dromaeo/cssquery-jquery.html) 14:26:10 INFO - PID 1871 | RSS: Main: 270233600 14:26:10 INFO - PID 1871 | 14:29:12 INFO - PID 1871 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49239/tests/dromaeo/cssquery-mootools.html) 14:29:12 INFO - PID 1871 | RSS: Main: 271089664 14:29:12 INFO - PID 1871 | 14:32:14 INFO - PID 1871 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49239/tests/dromaeo/cssquery-prototype.html) 14:32:14 INFO - PID 1871 | RSS: Main: 277344256 14:32:14 INFO - PID 1871 | 14:35:17 INFO - PID 1871 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49239/tests/dromaeo/cssquery-yui.html) 14:35:18 INFO - PID 1871 | RSS: Main: 277286912 14:35:18 INFO - PID 1871 | 14:38:19 INFO - PID 1871 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-yui.html (next: http://localhost:49239/tests/dromaeo/cssquery-dojo.html) 14:38:20 INFO - PID 1871 | RSS: Main: 277372928 14:38:20 INFO - PID 1871 | 14:38:20 INFO - PID 1871 | __start_tp_report 14:38:20 INFO - PID 1871 | _x_x_mozilla_page_load 14:38:20 INFO - PID 1871 | _x_x_mozilla_page_load_details 14:38:20 INFO - PID 1871 | |i|pagename|runs| 14:38:20 INFO - PID 1871 | |0;dojo.html;3392.1858753899064;3554.1825380162545;3598.5142005829207;3606.9098272543183;3623.9637603623955;6111.908321375183;6129.969350153248;6174.660393678351;6178.227721534809;6181.1964444622245;5137.6403651744395;6127.556056270624;6132.600268413519;6166.013437849945;6207.720652570634;5331.57347412207;5368.32895888014;5377.3009508764;5420;5503.559715222793;5278.102722537178;5284;5458.072127738294;5485.533729632986;5503.394626591072;5328.813491527778;5432.864178395532;5477.123660214346;5494.725263736817;5522.972385138069;4459.643228541741;5351.464853514656;5412.675239485604;5456.890862182774;5488.259085023511;4485.394471746337;5300.814471493518;5435.402105768402;5453.618246722731;5455.0453670607485;6045.848853778647;6211.440970312694;6224.159738435299;6224.408681175281;6230.968845155745;6427.775027874002;6514.93485065148;6547.214334279917;6636.104125942984;6681.398674119304;6732.596044237362;6743.696533655948;6762.8309292267595;6766.627835469048;6776.085228494188;6222.595531290452;6245.125682404467;6246.312905580383;6252.3747625237565;6266;6680.231773346128;6766.966165169241;6807.931920680829;6871.175458944958;6885.896711549331;5181.844544663666;6254.841305140874;6326.367363263729;6386.393292637192;6580.078788969548;6605.735770569219;6686.96656516724;6700.463962882957;6701;6724.831879202961;5464.836054918359;6546.378094081055;6611.471082313404;6623.2383275923885;6663.200415950117;5553.8333849984565;6385.584936979081;6480.546361754727;6559.639219842955;6663.13379260693;5330.093884039723;5362.115250983544;5377.300950876439;5378.327709036371;5380.811671591476;4092.4475195848777;4709.740964246931;4740.45484769249;4757;4797.496262892416;5783;6229.470495007975;6283.6858157091965;6288.9056664150075;6308.179936608211;5694.9430505695245;6144.539159562962;6649.933500665028;6747.831304217336;6765.35729105744;6286.685665716695;6293.716782744787;6296.811095667045;6300.527460440394;6303.243610766737;5975.37258587842;5980.252468441445;5983.9215666778155;5995.850103747354;6005.309389420273;6582.17722784652;6709.563878347989;6819.624920629413;6823.727050917908;6967.477439191979;5340.49265319794;5359.973200133974;6274.780382686584;6586.4401525870835;6748.089007984056;6824.727010919508;6865.519413640997;6915.031895534528;6929.237783843874;6949.30506949301;6355.396237357629;6456.386643268882;6521.054447105238;6581.506387020897;6596.340365963365;6589.439897608565;6648.20221573395;6698.430633396021;6714.731410743516;6737.3599508046655;6310.968445157745;6533.0853680485725;6539.346065393423;6540.149780528691;6563.573367731082;6278.623282603058;6445.291017988111;6526.543141980016;6533.575317604341;6564.37638424368;6185.628862268279;6379.7129129190225;6385.776497822554;6388.4569811566535;6388.840278993062;5623.915641265303;5902.2327097477055;6348.396902294275;6505.479561635152;6515.674216289071;6346.270178929482;6352.523560732871;6367.490600752021;6371.426571608577;6398.328175541687 14:38:20 INFO - PID 1871 | |1;ext.html;786.4062632712302;807.5840941914914;827.4786884262913;1197.4431889171533;1277.3868543099318;37111.44332835006;38253.808730956305;38306.61693383072;38323;38405.03987400313;38194.236115277745;38538.4219236711;38606.22787544254;38713.22573548527;38770.61229387705;7782.688692452295;7900.407469439786;8139.4709343892755;8361.83276334473;8364.330853531703;8221.506709597414;8234.752957411287;8268.751937441857;8341.332693384515;8365.498070115813;8244.093149753553;8303.543305118215;8338.207870252316;8359.456635318686;8379.83240335193;8312.376571757082;8318.916810831905;8329.66681332746;8355.665773369119;8365.707200247993;8084;8165.550894700789;8171.795705107361;8179.527685016695;8189.959050204711;34886.82556587201;35090.824545877105;35193.824030879936;35205;35298.76454324086;3542.9114272143147;3550.431930891045;3569.161247106928;3590.1563132663805;3593.0658028912408;29105.85447072751;29204;29325.56011659827;29359.41281174386;29514.114576562737;3035.074302337786;3116.7350775184136;3126.1715645353997;3139.230888432326;3151.2437015116207;235567;238956;278802.60598697077;283330;285962;45480;45960;46168.07663846704;46228.613141605805;46229.30656040162;48379.75810120927;48751.756241218565;48824.511754882704;48933.51066489289;49041.50958490441;14297.714045719027;14344.282785860874;14444.927775361057;14473.493427729978;14500;11447.25592836463;11629.1859569831;11723.944844963995;11746.236494627821;11786.58746943853;11266.887331126747;13937.930310348585;13975.930120349332;13999.44002239899;14087.506937257147;5985.371535988746;6097.542684298606;6120.60216085953;6125;6173.81478555644;845.9425717852685;984.675057231112;1009.4851625670814;1017.8880323164447;1042.2235434601118;19967.60064798725;23246.186383476495;24247.75752242455;24304.878475607507;24405.023799048195;989.851522271665;1092.7978324010085;1099.7745462180235;1116.3748300951493;1137.3289759042204;11829.52681892732;12314.692132696755;12443.253404795742;12491.687707807196;12772.936135319263;323268;323682;324887;325011;325630;41896;51717.7414112927;52283.21575176375;52380.47619523832;52390.21414678783;1464.9633759156106;1465.3186268385039;1501.8498150184894;1508.2911031815029;1513.1904431129292;23850.88074559616;23955.76044239605;24059.037638494265;24062.639060414458;24097.51804963926;35692.643073568936;35858.46212306766;35975.64024359723;36235.45646815247;36273.81863090667;268774;269393;269591;270085;270230;33791.324173516885;34999.82500087483;35055.47416788699;35143;35482;41888.79055604825;43453;44061.77969110134;44175;44255.77872110619;25602.498291404052;28592.285192870346;29552.261193470335;30183.69816301809;30677.23306917345;30228.788141685574;30283.545746814256;30744;30754.538681920236;30838.383232334778;36177.638223617425;36898.70854520079;37077.44383834299;37384.626153738114;37401.25197495981;28610;30290.84854575713;31040.379192416476;31353.529697054106;31621.683783161872;23677.763222367557;23704.8814755932;23788.88105559461;23895.522089557762;24084.51830963406 14:38:20 INFO - PID 1871 | |2;jquery.html;412.72966207134317;415.32509671783345;425.10091157202527;434.65662126919733;454.72943598558845;21150.471238219052;21348.146074157034;22001.779982200194;22850.31449056531;23101.653475197887;19520.804791952076;19743.44617968765;22349.553008939853;22475.325740227778;22638;5963.642181469126;6239.2824825145235;6288.465480434145;6291.4337709606125;6291.993281075029;5927.466528012478;6099.939000609981;6126.203593532835;6233.812985610439;6247.562670613059;6105.938940610604;6123.540734444934;6124.846878828021;6132.969335153341;6138.662373569452;5893.086571581412;6062.939370606282;6103.328633850318;6105.297890742577;6113.7860174893885;4635.884102897421;5378.300820893259;5423.376311724123;5427.267318911936;5450.972745136249;26882.327941801417;27336.863315683495;27405.629718514196;27589.034383796472;27595.586066209024;2152.1068756466047;2234.5084081502046;2244.3603572981624;2255.458689914425;2255.7744225577476;17809;18006.819931800645;18124.365647202285;18333.449996500127;18445.9854707991;2137.871727696343;2144.667576525641;2189.9562008759895;2194.3965409512352;2198.296545105567;117562;147862;148831.25584372008;149305.25347373192;150943;12644.988400927903;12671.17637353569;12730.745385092247;12803.61589152327;12969.351532423341;16310.266038028318;16368.83631163697;16410.343586256655;16414.83585164157;16416.835831641532;8801.691940782042;8837.293016558788;8964.14840590143;9105.180533751995;9180.31147663928;7030.718771249093;8547.871781923277;8572.228499435081;8649.264812490883;8716.520591367536;7733.613319334011;7869.134395216522;7870.370370370357;7883;7897.68409263623;1903.8952857592687;2189.6496560550236;2216.3683350245424;2221.544583360407;2248.527809160095;921.594637994833;958.5590628310915;984.084801134937;996.242855429864;1032.3031953431405;19671;19930.90034549818;20329.288474903307;20965.161393544393;21145.259915903178;903.9017593623779;1008.5159123620704;1010.8324884758116;1048.6120135549743;1055.145332280855;10722.463876806129;10736.409497477558;10776;10788.083012943987;10804.891951080388;503634;519939;522037.7796221989;525223;527371;126601.46797064008;127989.36005319914;130299.34850325687;137920;138322;1077.2297807067985;1178.8231765235284;1217.549506682533;1246.0903540415547;1250.4623012104882;21690.783092168876;21755;21925;22163.556728865657;22185.00167492499;53616.463835361144;53659;53881.46118538764;54304.456955431524;54767.17849232185;74228.62885685537;74637.62681186559;74909;75422;75925.24074759182;8738.432001919855;8867.387365864197;9013.369064165445;9075.273978081606;9144.862827057466;11801.35092569917;13221.933890330487;13553.915686745235;13980.510682126076;14222.359993800097;11434.256773309708;11641.126915481203;11723.941380293385;11742.17804753659;11861.5848445304;10925.890741092488;11030.28303160292;11245.606403775828;11309.660710178709;11388.088952883583;11499.137564682514;11866;12110.152289340014;12129.272243665408;12180.939095304468;8742.475451472932;8754.956225219088;8827.293816494537;9020.458772473672;9094.317926155698;9813.304242183707;11929.522819087138;11989.34058626784;12012;12068.275903445821 14:38:20 INFO - PID 1871 | |3;mootools.html;1263.147375521523;1410.3441899516722;1415.9150450972943;1425.8593125499604;1430.1633544376537;11635.301881887082;11922;12102.515899364014;12149.635510934675;12189.451474683638;12040.458179381927;12087.576934807283;12233.510659573629;12263.693407664812;12279.324637144953;2178.6514157734764;2353.9176128835493;2434.2940547241324;2449.5835707929696;2480.900763969439;2410.554047501209;2441.6703744994466;2446.889889954947;2450.583400821856;2453.1046168148587;2398.9280321590295;2425.6604075429364;2434.8782560871973;2439.121916110199;2443.609022556391;2286.965695514569;2365.8817059146972;2380.880955952204;2390.509945461185;2399.3281881073326;2137.700721898935;2232.2968264996634;2368.609179485383;2369.312899259213;2498.2005758157397;12006.279623222546;12073.758524829454;12100.757984840342;12102.455389507493;12144.696382590417;6441.355864413568;6703.966480167616;6706.463482921355;6711.66441677919;6724.462043036546;3382.813945233011;3588.4976103345552;4255.978720106411;4336.7831608419765;4338.240807858612;1214.5324050240633;1264.9620511384674;1271.4087949103712;1274.655842922415;1305.660528262649;15288;15829.812764042743;17027.40404085851;17040.65918681632;17180;5523.834284971377;5524.281843360337;5568.081266590967;5587.804426845041;5597.74810133545;11734.471948762326;11909;11918.46366913491;11922.284662920252;11950.223235489839;6319.210098737658;6353.714082866282;6439.484841212692;6451.516136289703;6467.741290348427;4522.547745225451;4645.46577143626;5932.703364831741;5996.970015149896;6152.477039451696;4281.186574550826;4342.6742994275555;4362.541933097043;4401.2517871961845;4417.249067658506;1263.9557615483413;1271.1038717704182;1272.8027155790867;1273.5669872243482;1278.8529319128234;695.8712638161957;761.5963539324167;790.7627711686471;794.2970471133088;796.0328201235527;6501.934980650133;6848.828779280558;7183.748568800067;7209.567425954459;7315.085614298213;925.3476299209042;935.1209862729013;940.4874343482818;940.6472572785206;976.5019839881569;3677.7609455385314;4324.610785029299;4406.823727050946;4441.71128876622;4567.058999925085;14650.926745366205;14870.925645371704;15269.236538173269;15383.153926533923;15425.305861236271;15000.774988375184;15195.620109497351;15216.391344346324;15256.23718814077;15274.847251527564;8459.788505287417;8537.786555336166;8602.483850968962;8628.69799557025;8629.568521573896;5512.448755124456;5674.489295963218;6868.965655171693;7041;7120.643967801486;13519.594412167651;13710.38303276375;13829.930850345683;13914.304284785518;14000.229987350385;13825.654358641115;14225.644358890697;14249.857501424853;14379.568612941628;14422;7794.493357931717;9602.711918642452;9622;9661.46861922601;9678.274129440179;10900.34597924127;11007.944960275468;11020.5040773167;11025.558977640805;11030.06244469197;8988.280937525113;9256.166944974715;9345.392549484262;9362.344635875423;9439.81120377575;9129.315301352291;9189;9230.769230769016;9297.767555811159;9412.482313472825;7864.331531819859;7983.72056978003;8035.758927232192;8090.110087890445;8126.959365203136;6021.488173505126;6093.086037094471;6103.42017508336;6220.937790622036;6228.408301211378;5988.760449581968;5995.640261584319;6112.633242005316;6150.661713605795;6243.7814676486105 14:38:20 INFO - PID 1871 | |4;prototype.html;323.2984423800353;386.06572095528816;402.35823860941787;411.97734124623145;414.3598140872352;6164.7534098636;6273.309935907047;6388.872222555552;6395.776147834827;6465.709043093055;5320.893582128355;5723.245003020909;5727.455891690282;6228.564000519966;6279.968600157004;4773.212419950704;4788.329633851264;4789.640776941726;4804.44748853881;4825.951740482586;4241.215375155606;4720.315554244632;4743.691660042086;4825.7587120644;4829.637777166709;4080.1431699343175;4244.214820258262;4757.167495688258;4764.309175169598;4769.546893045155;3799.3351163546545;4367.711525100087;4522.525134860858;4686.601638860701;4739.289106633998;3923.7645741255333;4237.237297286487;4245.341971994346;4251.851185208503;4257.19113368459;6015.157877897098;6074.453299203093;6130.264368275791;6172.22847144107;6185.206290175844;5144;5657.3494048184575;5662.292213473316;5667.121596152603;5681.801136960186;5851.063829787214;6053.7892421515435;6067.939320606782;6498.902516462257;6731.663416829139;3832.597577254404;3873.283442563135;4368.541303163154;4391.824327026915;4426.513083560806;5512.586556008276;5654.575906807006;7048.00459804581;7121.181064177634;7154.816106832462;4421.579949904753;4428.867133985986;4437.467503899553;4451.532589078165;4460.8661740147845;4509.188346097669;4509.526499717548;4538.410006699108;4549.863504094882;4572.771361431982;5145.253938178943;5381.865453363697;5383.596230282745;5394.273832373681;5408.540274076748;6384.32964538726;6568.540202185896;6697.832554186087;6735.562188457735;6753.527253092237;1499.7825315329217;1544.6833399153372;1551.6353656890622;1551.689662067591;1556.237443652602;986.2997271936862;1014.5181039006413;1021.1524434719165;1029.8867124616286;1031.5770534080991;5629;5830.008898487267;6351.555391122577;6423.164988551458;6444.45222156122;6493.448056915121;6801.1158549388265;6914.9308506914285;6954.860902781916;6973.407260382823;6132.50939924805;6310.87611632245;6364.809055728336;6389.897647746391;6416.582922110048;3041.5893854329825;3587.121155316964;3653.488511608377;3660.698679052103;3670.944935825965;7048.647567621599;7163.853783394632;7176.390006849372;7189.388901943289;7197.856042879218;5956.255468066492;6390.712417941204;7066.92933070673;7132.821679458055;7147.428205743528;6867.416269617139;7044.577325360495;7122.685127333533;7130.25132361105;7213.134423869064;6923.93076069253;6950.270221626859;6958.652067396509;6965.268646792014;6974.197967233833;6597.340265973365;6769.882969310205;6832.248452670302;6882.5870447773295;6918.827029324307;5748.425157484218;6300.306966233619;6970.919507476349;7151.499395042505;7154.2488038757265;4356.912861742684;4445.666575006822;4477.417935668343;4478.171538265431;4514.300283456069;5701.344345400332;6026.548008899263;6115.850337076498;6924.991752391951;7006.986995591945;5903.734331954986;6610.669466526559;6674.6662666865495;6715.731370745311;6745.865082698417;6620.635865027277;6629.900551491635;6691.230508491586;6700.095487109178;6708.295628959084;5440.809571664972;5960.195373624505;6123.112148738497;6476.190476190476;6513.348665133638;6363.649999250086;6630.569013014138;6700.798976030727;6717.462602991846;6754.797356079325;6132.62016046386;6232.968835155795;6524.739010439529;6616.676664666989;6632.7678531253105 14:38:20 INFO - PID 1871 | |5;yui.html;719.5718547464259;801.515083374558;815.6003558256452;829.8423698939979;840.865461526156;16818.66362672746;17324.307027718893;17331.740023899634;19568.217271309168;20301.187952481883;18369.54076148097;18529.536761580966;18742.718859217122;18795.62408751824;18826.811731882677;1005.989940100597;1038.8026275007724;1040.8230600797883;1043.2227990147312;1045.503385891699;1003.6085926488676;1013.7110923386837;1015.6546774096805;1016.4612755239734;1019.5768755966302;1073.6617965340931;1078.234453537985;1079.8218293981522;1082.815921293382;1085.4952447112091;1075.2150929821196;1075.4192735922593;1077.3374374759467;1079.7084787107476;1080.9351438913652;783.5024759277843;798.0383637716537;800.071916576774;800.4956877167406;802.6067227058758;17279.395221167324;17911;18155.183016764277;18288;18331;17037.659246814994;17508.91245543777;17658.11709414524;17681.204345804468;17977.460676179737;14362.210078445682;14560.126392416489;14712.632184195374;14723.705525889529;14755.409783608642;3512.666296701809;3584.8028358440274;3585.515955346021;3591.371509985768;3607.1703508193264;111564;119966.60066798702;145492;146518.5348146526;148725.79459718967;13483.932580337036;13789.655258618615;13943.930280348533;13959.022868399317;14044.157350558999;23477.88261058684;25303.873480632847;25695.100671476783;26877.731222687522;26884.462310753675;6812.250652428229;7994.920050799534;8110.756677299691;8116.959415203004;8151.5109093454585;3912.256671232457;3966.167104907944;3967.206558688274;3968.6825053995613;3975.7216994810665;3680.650338217865;4288.335308027261;4383.671224658164;4436.534163912807;4441.777911104432;936.941256380286;944.3106532231509;949;954.5561313989028;955.3694561589317;629.7418058595954;661.3650895140622;665.6838001949133;672.2907332763883;681.8874885643912;11422;12235.938820306019;12410.56563020308;12512.186707863959;12664.620061398173;630.4357599948021;651.8859199640044;653.2781276689285;684.4387602166275;686.5503095472474;5259.789608415697;5306.230596563477;5366.892662146814;5406.378266499403;5503.532199762985;139776.3011184958;148716.35910947475;148989.51010489758;149602.7559586607;151238;125321;126353;128279;129104;129413.70586294205;107350;108589;108926.45536772265;109599.45200274108;111192.88807111987;8593;9322.254219662547;10417.166626669698;10420.68737937863;10891.128709703364;43878.12243755171;44036;44345.33481997837;45167.32249016333;45294.32058519059;40034.799826000686;58797.70601147138;59165.81668366695;59173.70413148079;60325.09512357406;14053.718925621635;14190.148591084568;14496.85503144955;14732;14770.48303309379;13775.931120344334;15817.68364632678;16330.67338653244;16398;16410.015399075615;14696.779548306999;14907.62730931692;15004.6999060016;15227.314770835561;15237.542873713806;12790.360481975678;14199;14796.408143674133;14907.552773416815;15032.24838758036;14409.495667651581;14688.339024743698;14723.484678036217;14830.925845370704;14866.702665946837;10597.205209609465;10599.099076578577;10782.946085269523;10899;10928.726781830199;10603.561180928275;11677.708057298636;11827;11919.284842909454;11997.460114295052 14:38:20 INFO - PID 1871 | __end_tp_report 14:38:20 INFO - PID 1871 | __start_cc_report 14:38:20 INFO - PID 1871 | _x_x_mozilla_cycle_collect,408 14:38:20 INFO - PID 1871 | __end_cc_report 14:38:20 INFO - PID 1871 | __startTimestamp1495834700224__endTimestamp 14:38:20 INFO - PID 1871 | 14:38:20 INFO - PID 1871 | ------- Summary: start ------- 14:38:20 INFO - PID 1871 | Number of tests: 6 14:38:20 INFO - PID 1871 | 14:38:20 INFO - PID 1871 | [#0] dojo.html Cycles:165 Average:6063.60 Median:6288.91 stddev:738.04 (11.7%) stddev-sans-first:709.93 14:38:20 INFO - PID 1871 | Values: 3392.2 3554.2 3598.5 3606.9 3624.0 6111.9 6130.0 6174.7 6178.2 6181.2 5137.6 6127.6 6132.6 6166.0 6207.7 5331.6 5368.3 5377.3 5420.0 5503.6 5278.1 5284.0 5458.1 5485.5 5503.4 5328.8 5432.9 5477.1 5494.7 5523.0 4459.6 5351.5 5412.7 5456.9 5488.3 4485.4 5300.8 5435.4 5453.6 5455.0 6045.8 6211.4 6224.2 6224.4 6231.0 6427.8 6514.9 6547.2 6636.1 6681.4 6732.6 6743.7 6762.8 6766.6 6776.1 6222.6 6245.1 6246.3 6252.4 6266.0 6680.2 6767.0 6807.9 6871.2 6885.9 5181.8 6254.8 6326.4 6386.4 6580.1 6605.7 6687.0 6700.5 6701.0 6724.8 5464.8 6546.4 6611.5 6623.2 6663.2 5553.8 6385.6 6480.5 6559.6 6663.1 5330.1 5362.1 5377.3 5378.3 5380.8 4092.4 4709.7 4740.5 4757.0 4797.5 5783.0 6229.5 6283.7 6288.9 6308.2 5694.9 6144.5 6649.9 6747.8 6765.4 6286.7 6293.7 6296.8 6300.5 6303.2 5975.4 5980.3 5983.9 5995.9 6005.3 6582.2 6709.6 6819.6 6823.7 6967.5 5340.5 5360.0 6274.8 6586.4 6748.1 6824.7 6865.5 6915.0 6929.2 6949.3 6355.4 6456.4 6521.1 6581.5 6596.3 6589.4 6648.2 6698.4 6714.7 6737.4 6311.0 6533.1 6539.3 6540.1 6563.6 6278.6 6445.3 6526.5 6533.6 6564.4 6185.6 6379.7 6385.8 6388.5 6388.8 5623.9 5902.2 6348.4 6505.5 6515.7 6346.3 6352.5 6367.5 6371.4 6398.3 14:38:20 INFO - PID 1871 | 14:38:20 INFO - PID 1871 | [#1] ext.html Cycles:180 Average:43546.89 Median:34943.33 stddev:75398.20 (215.8%) stddev-sans-first:75541.35 14:38:20 INFO - PID 1871 | Values: 786.4 807.6 827.5 1197.4 1277.4 37111.4 38253.8 38306.6 38323.0 38405.0 38194.2 38538.4 38606.2 38713.2 38770.6 7782.7 7900.4 8139.5 8361.8 8364.3 8221.5 8234.8 8268.8 8341.3 8365.5 8244.1 8303.5 8338.2 8359.5 8379.8 8312.4 8318.9 8329.7 8355.7 8365.7 8084.0 8165.6 8171.8 8179.5 8190.0 34886.8 35090.8 35193.8 35205.0 35298.8 3542.9 3550.4 3569.2 3590.2 3593.1 29105.9 29204.0 29325.6 29359.4 29514.1 3035.1 3116.7 3126.2 3139.2 3151.2 235567.0 238956.0 278802.6 283330.0 285962.0 45480.0 45960.0 46168.1 46228.6 46229.3 48379.8 48751.8 48824.5 48933.5 49041.5 14297.7 14344.3 14444.9 14473.5 14500.0 11447.3 11629.2 11723.9 11746.2 11786.6 11266.9 13937.9 13975.9 13999.4 14087.5 5985.4 6097.5 6120.6 6125.0 6173.8 845.9 984.7 1009.5 1017.9 1042.2 19967.6 23246.2 24247.8 24304.9 24405.0 989.9 1092.8 1099.8 1116.4 1137.3 11829.5 12314.7 12443.3 12491.7 12772.9 323268.0 323682.0 324887.0 325011.0 325630.0 41896.0 51717.7 52283.2 52380.5 52390.2 1465.0 1465.3 1501.8 1508.3 1513.2 23850.9 23955.8 24059.0 24062.6 24097.5 35692.6 35858.5 35975.6 36235.5 36273.8 268774.0 269393.0 269591.0 270085.0 270230.0 33791.3 34999.8 35055.5 35143.0 35482.0 41888.8 43453.0 44061.8 44175.0 44255.8 25602.5 28592.3 29552.3 30183.7 30677.2 30228.8 30283.5 30744.0 30754.5 30838.4 36177.6 36898.7 37077.4 37384.6 37401.3 28610.0 30290.8 31040.4 31353.5 31621.7 23677.8 23704.9 23788.9 23895.5 24084.5 14:38:20 INFO - PID 1871 | 14:38:20 INFO - PID 1871 | [#2] jquery.html Cycles:180 Average:34381.10 Median:12296.96 stddev:88269.99 (717.8%) stddev-sans-first:88480.77 14:38:20 INFO - PID 1871 | Values: 412.7 415.3 425.1 434.7 454.7 21150.5 21348.1 22001.8 22850.3 23101.7 19520.8 19743.4 22349.6 22475.3 22638.0 5963.6 6239.3 6288.5 6291.4 6292.0 5927.5 6099.9 6126.2 6233.8 6247.6 6105.9 6123.5 6124.8 6133.0 6138.7 5893.1 6062.9 6103.3 6105.3 6113.8 4635.9 5378.3 5423.4 5427.3 5451.0 26882.3 27336.9 27405.6 27589.0 27595.6 2152.1 2234.5 2244.4 2255.5 2255.8 17809.0 18006.8 18124.4 18333.4 18446.0 2137.9 2144.7 2190.0 2194.4 2198.3 117562.0 147862.0 148831.3 149305.3 150943.0 12645.0 12671.2 12730.7 12803.6 12969.4 16310.3 16368.8 16410.3 16414.8 16416.8 8801.7 8837.3 8964.1 9105.2 9180.3 7030.7 8547.9 8572.2 8649.3 8716.5 7733.6 7869.1 7870.4 7883.0 7897.7 1903.9 2189.6 2216.4 2221.5 2248.5 921.6 958.6 984.1 996.2 1032.3 19671.0 19930.9 20329.3 20965.2 21145.3 903.9 1008.5 1010.8 1048.6 1055.1 10722.5 10736.4 10776.0 10788.1 10804.9 503634.0 519939.0 522037.8 525223.0 527371.0 126601.5 127989.4 130299.3 137920.0 138322.0 1077.2 1178.8 1217.5 1246.1 1250.5 21690.8 21755.0 21925.0 22163.6 22185.0 53616.5 53659.0 53881.5 54304.5 54767.2 74228.6 74637.6 74909.0 75422.0 75925.2 8738.4 8867.4 9013.4 9075.3 9144.9 11801.4 13221.9 13553.9 13980.5 14222.4 11434.3 11641.1 11723.9 11742.2 11861.6 10925.9 11030.3 11245.6 11309.7 11388.1 11499.1 11866.0 12110.2 12129.3 12180.9 8742.5 8755.0 8827.3 9020.5 9094.3 9813.3 11929.5 11989.3 12012.0 12068.3 14:38:20 INFO - PID 1871 | 14:38:20 INFO - PID 1871 | [#3] mootools.html Cycles:180 Average:7175.02 Median:3485.66 stddev:4633.65 (132.9%) stddev-sans-first:4625.36 14:38:20 INFO - PID 1871 | Values: 1263.1 1410.3 1415.9 1425.9 1430.2 11635.3 11922.0 12102.5 12149.6 12189.5 12040.5 12087.6 12233.5 12263.7 12279.3 2178.7 2353.9 2434.3 2449.6 2480.9 2410.6 2441.7 2446.9 2450.6 2453.1 2398.9 2425.7 2434.9 2439.1 2443.6 2287.0 2365.9 2380.9 2390.5 2399.3 2137.7 2232.3 2368.6 2369.3 2498.2 12006.3 12073.8 12100.8 12102.5 12144.7 6441.4 6704.0 6706.5 6711.7 6724.5 3382.8 3588.5 4256.0 4336.8 4338.2 1214.5 1265.0 1271.4 1274.7 1305.7 15288.0 15829.8 17027.4 17040.7 17180.0 5523.8 5524.3 5568.1 5587.8 5597.7 11734.5 11909.0 11918.5 11922.3 11950.2 6319.2 6353.7 6439.5 6451.5 6467.7 4522.5 4645.5 5932.7 5997.0 6152.5 4281.2 4342.7 4362.5 4401.3 4417.2 1264.0 1271.1 1272.8 1273.6 1278.9 695.9 761.6 790.8 794.3 796.0 6501.9 6848.8 7183.7 7209.6 7315.1 925.3 935.1 940.5 940.6 976.5 3677.8 4324.6 4406.8 4441.7 4567.1 14650.9 14870.9 15269.2 15383.2 15425.3 15000.8 15195.6 15216.4 15256.2 15274.8 8459.8 8537.8 8602.5 8628.7 8629.6 5512.4 5674.5 6869.0 7041.0 7120.6 13519.6 13710.4 13829.9 13914.3 14000.2 13825.7 14225.6 14249.9 14379.6 14422.0 7794.5 9602.7 9622.0 9661.5 9678.3 10900.3 11007.9 11020.5 11025.6 11030.1 8988.3 9256.2 9345.4 9362.3 9439.8 9129.3 9189.0 9230.8 9297.8 9412.5 7864.3 7983.7 8035.8 8090.1 8127.0 6021.5 6093.1 6103.4 6220.9 6228.4 5988.8 5995.6 6112.6 6150.7 6243.8 14:38:20 INFO - PID 1871 | 14:38:20 INFO - PID 1871 | [#4] prototype.html Cycles:180 Average:5406.97 Median:6060.86 stddev:1692.23 (27.9%) stddev-sans-first:1653.40 14:38:20 INFO - PID 1871 | Values: 323.3 386.1 402.4 412.0 414.4 6164.8 6273.3 6388.9 6395.8 6465.7 5320.9 5723.2 5727.5 6228.6 6280.0 4773.2 4788.3 4789.6 4804.4 4826.0 4241.2 4720.3 4743.7 4825.8 4829.6 4080.1 4244.2 4757.2 4764.3 4769.5 3799.3 4367.7 4522.5 4686.6 4739.3 3923.8 4237.2 4245.3 4251.9 4257.2 6015.2 6074.5 6130.3 6172.2 6185.2 5144.0 5657.3 5662.3 5667.1 5681.8 5851.1 6053.8 6067.9 6498.9 6731.7 3832.6 3873.3 4368.5 4391.8 4426.5 5512.6 5654.6 7048.0 7121.2 7154.8 4421.6 4428.9 4437.5 4451.5 4460.9 4509.2 4509.5 4538.4 4549.9 4572.8 5145.3 5381.9 5383.6 5394.3 5408.5 6384.3 6568.5 6697.8 6735.6 6753.5 1499.8 1544.7 1551.6 1551.7 1556.2 986.3 1014.5 1021.2 1029.9 1031.6 5629.0 5830.0 6351.6 6423.2 6444.5 6493.4 6801.1 6914.9 6954.9 6973.4 6132.5 6310.9 6364.8 6389.9 6416.6 3041.6 3587.1 3653.5 3660.7 3670.9 7048.6 7163.9 7176.4 7189.4 7197.9 5956.3 6390.7 7066.9 7132.8 7147.4 6867.4 7044.6 7122.7 7130.3 7213.1 6923.9 6950.3 6958.7 6965.3 6974.2 6597.3 6769.9 6832.2 6882.6 6918.8 5748.4 6300.3 6970.9 7151.5 7154.2 4356.9 4445.7 4477.4 4478.2 4514.3 5701.3 6026.5 6115.9 6925.0 7007.0 5903.7 6610.7 6674.7 6715.7 6745.9 6620.6 6629.9 6691.2 6700.1 6708.3 5440.8 5960.2 6123.1 6476.2 6513.3 6363.6 6630.6 6700.8 6717.5 6754.8 6132.6 6233.0 6524.7 6616.7 6632.8 14:38:20 INFO - PID 1871 | 14:38:20 INFO - PID 1871 | [#5] yui.html Cycles:180 Average:24929.31 Median:16364.34 stddev:39282.51 (240.0%) stddev-sans-first:39350.65 14:38:20 INFO - PID 1871 | Values: 719.6 801.5 815.6 829.8 840.9 16818.7 17324.3 17331.7 19568.2 20301.2 18369.5 18529.5 18742.7 18795.6 18826.8 1006.0 1038.8 1040.8 1043.2 1045.5 1003.6 1013.7 1015.7 1016.5 1019.6 1073.7 1078.2 1079.8 1082.8 1085.5 1075.2 1075.4 1077.3 1079.7 1080.9 783.5 798.0 800.1 800.5 802.6 17279.4 17911.0 18155.2 18288.0 18331.0 17037.7 17508.9 17658.1 17681.2 17977.5 14362.2 14560.1 14712.6 14723.7 14755.4 3512.7 3584.8 3585.5 3591.4 3607.2 111564.0 119966.6 145492.0 146518.5 148725.8 13483.9 13789.7 13943.9 13959.0 14044.2 23477.9 25303.9 25695.1 26877.7 26884.5 6812.3 7994.9 8110.8 8117.0 8151.5 3912.3 3966.2 3967.2 3968.7 3975.7 3680.7 4288.3 4383.7 4436.5 4441.8 936.9 944.3 949.0 954.6 955.4 629.7 661.4 665.7 672.3 681.9 11422.0 12235.9 12410.6 12512.2 12664.6 630.4 651.9 653.3 684.4 686.6 5259.8 5306.2 5366.9 5406.4 5503.5 139776.3 148716.4 148989.5 149602.8 151238.0 125321.0 126353.0 128279.0 129104.0 129413.7 107350.0 108589.0 108926.5 109599.5 111192.9 8593.0 9322.3 10417.2 10420.7 10891.1 43878.1 44036.0 44345.3 45167.3 45294.3 40034.8 58797.7 59165.8 59173.7 60325.1 14053.7 14190.1 14496.9 14732.0 14770.5 13775.9 15817.7 16330.7 16398.0 16410.0 14696.8 14907.6 15004.7 15227.3 15237.5 12790.4 14199.0 14796.4 14907.6 15032.2 14409.5 14688.3 14723.5 14830.9 14866.7 10597.2 10599.1 10782.9 10899.0 10928.7 10603.6 11677.7 11827.0 11919.3 11997.5 14:38:20 INFO - PID 1871 | -------- Summary: end -------- 14:38:20 INFO - PID 1871 | 14:38:20 INFO - TEST-INFO | 1871: exit 0 14:38:20 INFO - TEST-OK | dromaeo_css | took 1142906ms 14:38:20 INFO - TEST-START | kraken 14:38:20 INFO - operating with platform_type : mac_ 14:38:20 INFO - Initialising browser for kraken test... 14:38:20 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49239/getInfo.html -foreground -profile /var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/tmpX9EeW6/profile 14:38:20 INFO - TEST-INFO | started process 1883 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49239/getInfo.html -foreground) 14:38:54 INFO - TEST-INFO | 1883: killed by SIGTERM 14:38:54 INFO - Browser initialized. 14:38:54 INFO - Running cycle 1/1 for kraken test... 14:38:54 INFO - TEST-INFO | started process 1887 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/tmpX9EeW6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:38:56 INFO - PID 1887 | 2017-05-26 14:38:56.194 plugin-container[1888:11449] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 14:38:56 INFO - PID 1887 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:38:56 INFO - PID 1887 | 2017-05-26 14:38:56.195 plugin-container[1888:11449] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a1f, name = 'com.apple.CFPasteboardClient' 14:38:56 INFO - PID 1887 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:38:56 INFO - PID 1887 | 2017-05-26 14:38:56.195 plugin-container[1888:11449] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:38:56 INFO - PID 1887 | 2017-05-26 14:38:56.714 plugin-container[1889:11491] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4543, name = 'com.apple.tsm.portname' 14:38:56 INFO - PID 1887 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:38:56 INFO - PID 1887 | 2017-05-26 14:38:56.715 plugin-container[1889:11491] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c03, name = 'com.apple.CFPasteboardClient' 14:38:56 INFO - PID 1887 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:38:56 INFO - PID 1887 | 2017-05-26 14:38:56.715 plugin-container[1889:11491] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:39:06 INFO - PID 1887 | RSS: Main: 242786304 14:39:06 INFO - PID 1887 | 14:39:08 INFO - PID 1887 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-beat-detection) 14:39:09 INFO - PID 1887 | RSS: Main: 257028096 14:39:09 INFO - PID 1887 | 14:39:12 INFO - PID 1887 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-dft) 14:39:12 INFO - PID 1887 | RSS: Main: 265867264 14:39:12 INFO - PID 1887 | 14:39:16 INFO - PID 1887 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-fft) 14:39:17 INFO - PID 1887 | RSS: Main: 268926976 14:39:17 INFO - PID 1887 | 14:39:18 INFO - PID 1887 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-oscillator) 14:39:19 INFO - PID 1887 | RSS: Main: 270843904 14:39:19 INFO - PID 1887 | 14:39:21 INFO - PID 1887 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49239/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:39:22 INFO - PID 1887 | RSS: Main: 271695872 14:39:22 INFO - PID 1887 | 14:39:24 INFO - PID 1887 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49239/tests/kraken/driver.html?testName=imaging-darkroom) 14:39:25 INFO - PID 1887 | RSS: Main: 276320256 14:39:25 INFO - PID 1887 | 14:39:28 INFO - PID 1887 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49239/tests/kraken/driver.html?testName=imaging-desaturate) 14:39:29 INFO - PID 1887 | RSS: Main: 279060480 14:39:29 INFO - PID 1887 | 14:39:31 INFO - PID 1887 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49239/tests/kraken/driver.html?testName=json-parse-financial) 14:39:32 INFO - PID 1887 | RSS: Main: 278315008 14:39:32 INFO - PID 1887 | 14:39:33 INFO - PID 1887 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49239/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:39:34 INFO - PID 1887 | RSS: Main: 278216704 14:39:34 INFO - PID 1887 | 14:39:36 INFO - PID 1887 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:39:37 INFO - PID 1887 | RSS: Main: 277700608 14:39:37 INFO - PID 1887 | 14:39:39 INFO - PID 1887 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:39:40 INFO - PID 1887 | RSS: Main: 277696512 14:39:40 INFO - PID 1887 | 14:39:43 INFO - PID 1887 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:39:43 INFO - PID 1887 | RSS: Main: 277680128 14:39:43 INFO - PID 1887 | 14:39:46 INFO - PID 1887 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:39:47 INFO - PID 1887 | RSS: Main: 277680128 14:39:47 INFO - PID 1887 | 14:39:49 INFO - PID 1887 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49239/tests/kraken/driver.html?testName=ai-astar) 14:39:49 INFO - PID 1887 | RSS: Main: 279257088 14:39:49 INFO - PID 1887 | 14:39:49 INFO - PID 1887 | __start_tp_report 14:39:49 INFO - PID 1887 | _x_x_mozilla_page_load 14:39:49 INFO - PID 1887 | _x_x_mozilla_page_load_details 14:39:49 INFO - PID 1887 | |i|pagename|runs| 14:39:49 INFO - PID 1887 | |0;ai-astar;102;115;112;114;113;113;113;114;112;113 14:39:49 INFO - PID 1887 | |1;audio-beat-detection;148;107;112;148;124;151;144;116;135;111 14:39:49 INFO - PID 1887 | |2;audio-dft;199;197;198;199;258;275;269;281;223;203 14:39:49 INFO - PID 1887 | |3;audio-fft;73;58;60;80;62;57;58;59;76;63 14:39:49 INFO - PID 1887 | |4;audio-oscillator;82;70;68;89;93;106;106;105;83;87 14:39:49 INFO - PID 1887 | |5;imaging-gaussian-blur;88;91;88;88;90;88;87;89;89;96 14:39:49 INFO - PID 1887 | |6;imaging-darkroom;95;96;95;95;98;94;96;96;96;96 14:39:49 INFO - PID 1887 | |7;imaging-desaturate;90;91;90;93;104;90;91;90;92;92 14:39:49 INFO - PID 1887 | |8;json-parse-financial;51;49;50;48;51;50;54;52;50;50 14:39:49 INFO - PID 1887 | |9;json-stringify-tinderbox;57;60;59;56;72;62;59;70;82;86 14:39:49 INFO - PID 1887 | |10;stanford-crypto-aes;89;89;89;86;86;86;89;91;132;108 14:39:49 INFO - PID 1887 | |11;stanford-crypto-ccm;163;159;151;141;146;158;161;148;161;140 14:39:49 INFO - PID 1887 | |12;stanford-crypto-pbkdf2;187;189;195;177;183;186;187;187;186;187 14:39:49 INFO - PID 1887 | |13;stanford-crypto-sha256-iterative;68;66;68;67;66;70;68;67;69;72 14:39:49 INFO - PID 1887 | __end_tp_report 14:39:49 INFO - PID 1887 | __start_cc_report 14:39:49 INFO - PID 1887 | _x_x_mozilla_cycle_collect,1068 14:39:49 INFO - PID 1887 | __end_cc_report 14:39:49 INFO - PID 1887 | __startTimestamp1495834789252__endTimestamp 14:39:49 INFO - PID 1887 | 14:39:49 INFO - PID 1887 | ------- Summary: start ------- 14:39:49 INFO - PID 1887 | Number of tests: 14 14:39:49 INFO - PID 1887 | 14:39:49 INFO - PID 1887 | [#0] ai-astar Cycles:10 Average:112.10 Median:113.00 stddev:3.67 (3.2%) stddev-sans-first:0.97 14:39:49 INFO - PID 1887 | Values: 102.0 115.0 112.0 114.0 113.0 113.0 113.0 114.0 112.0 113.0 14:39:49 INFO - PID 1887 | 14:39:49 INFO - PID 1887 | [#1] audio-beat-detection Cycles:10 Average:129.60 Median:139.50 stddev:17.49 (12.5%) stddev-sans-first:17.24 14:39:49 INFO - PID 1887 | Values: 148.0 107.0 112.0 148.0 124.0 151.0 144.0 116.0 135.0 111.0 14:39:49 INFO - PID 1887 | 14:39:49 INFO - PID 1887 | [#2] audio-dft Cycles:10 Average:230.20 Median:240.50 stddev:36.12 (15.0%) stddev-sans-first:36.51 14:39:49 INFO - PID 1887 | Values: 199.0 197.0 198.0 199.0 258.0 275.0 269.0 281.0 223.0 203.0 14:39:49 INFO - PID 1887 | 14:39:49 INFO - PID 1887 | [#3] audio-fft Cycles:10 Average:64.60 Median:62.50 stddev:8.46 (13.5%) stddev-sans-first:8.41 14:39:49 INFO - PID 1887 | Values: 73.0 58.0 60.0 80.0 62.0 57.0 58.0 59.0 76.0 63.0 14:39:49 INFO - PID 1887 | 14:39:49 INFO - PID 1887 | [#4] audio-oscillator Cycles:10 Average:88.90 Median:82.50 stddev:13.91 (16.9%) stddev-sans-first:14.53 14:39:49 INFO - PID 1887 | Values: 82.0 70.0 68.0 89.0 93.0 106.0 106.0 105.0 83.0 87.0 14:39:49 INFO - PID 1887 | 14:39:49 INFO - PID 1887 | [#5] imaging-gaussian-blur Cycles:10 Average:89.40 Median:89.00 stddev:2.59 (2.9%) stddev-sans-first:2.70 14:39:49 INFO - PID 1887 | Values: 88.0 91.0 88.0 88.0 90.0 88.0 87.0 89.0 89.0 96.0 14:39:49 INFO - PID 1887 | 14:39:49 INFO - PID 1887 | [#6] imaging-darkroom Cycles:10 Average:95.70 Median:96.00 stddev:1.06 (1.1%) stddev-sans-first:1.09 14:39:49 INFO - PID 1887 | Values: 95.0 96.0 95.0 95.0 98.0 94.0 96.0 96.0 96.0 96.0 14:39:49 INFO - PID 1887 | 14:39:49 INFO - PID 1887 | [#7] imaging-desaturate Cycles:10 Average:92.30 Median:91.00 stddev:4.24 (4.7%) stddev-sans-first:4.42 14:39:49 INFO - PID 1887 | Values: 90.0 91.0 90.0 93.0 104.0 90.0 91.0 90.0 92.0 92.0 14:39:49 INFO - PID 1887 | 14:39:49 INFO - PID 1887 | [#8] json-parse-financial Cycles:10 Average:50.50 Median:50.50 stddev:1.65 (3.3%) stddev-sans-first:1.74 14:39:49 INFO - PID 1887 | Values: 51.0 49.0 50.0 48.0 51.0 50.0 54.0 52.0 50.0 50.0 14:39:49 INFO - PID 1887 | 14:39:49 INFO - PID 1887 | [#9] json-stringify-tinderbox Cycles:10 Average:66.30 Median:66.00 stddev:10.74 (16.3%) stddev-sans-first:10.85 14:39:49 INFO - PID 1887 | Values: 57.0 60.0 59.0 56.0 72.0 62.0 59.0 70.0 82.0 86.0 14:39:49 INFO - PID 1887 | 14:39:49 INFO - PID 1887 | [#10] stanford-crypto-aes Cycles:10 Average:94.50 Median:89.00 stddev:14.68 (16.5%) stddev-sans-first:15.43 14:39:49 INFO - PID 1887 | Values: 89.0 89.0 89.0 86.0 86.0 86.0 89.0 91.0 132.0 108.0 14:39:49 INFO - PID 1887 | 14:39:49 INFO - PID 1887 | [#11] stanford-crypto-ccm Cycles:10 Average:152.80 Median:158.50 stddev:8.69 (5.5%) stddev-sans-first:8.40 14:39:49 INFO - PID 1887 | Values: 163.0 159.0 151.0 141.0 146.0 158.0 161.0 148.0 161.0 140.0 14:39:49 INFO - PID 1887 | 14:39:49 INFO - PID 1887 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.40 Median:187.00 stddev:4.50 (2.4%) stddev-sans-first:4.77 14:39:49 INFO - PID 1887 | Values: 187.0 189.0 195.0 177.0 183.0 186.0 187.0 187.0 186.0 187.0 14:39:49 INFO - PID 1887 | 14:39:49 INFO - PID 1887 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:68.00 stddev:1.85 (2.7%) stddev-sans-first:1.96 14:39:49 INFO - PID 1887 | Values: 68.0 66.0 68.0 67.0 66.0 70.0 68.0 67.0 69.0 72.0 14:39:49 INFO - PID 1887 | -------- Summary: end -------- 14:39:49 INFO - PID 1887 | 14:39:49 INFO - TEST-INFO | 1887: exit 0 14:39:50 INFO - TEST-OK | kraken | took 89272ms 14:39:50 INFO - SUITE-END | took 1232s 14:39:50 INFO - Completed test suite (00:20:32) 14:39:50 INFO - javascript benchmark 14:39:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7888.900601459236, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3392.1858753899064, 3554.1825380162545, 3598.5142005829207, 3606.9098272543183, 3623.9637603623955, 6111.908321375183, 6129.969350153248, 6174.660393678351, 6178.227721534809, 6181.1964444622245, 5137.6403651744395, 6127.556056270624, 6132.600268413519, 6166.013437849945, 6207.720652570634, 5331.57347412207, 5368.32895888014, 5377.3009508764, 5420.0, 5503.559715222793, 5278.102722537178, 5284.0, 5458.072127738294, 5485.533729632986, 5503.394626591072, 5328.813491527778, 5432.864178395532, 5477.123660214346, 5494.725263736817, 5522.972385138069, 4459.643228541741, 5351.464853514656, 5412.675239485604, 5456.890862182774, 5488.259085023511, 4485.394471746337, 5300.814471493518, 5435.402105768402, 5453.618246722731, 5455.0453670607485, 6045.848853778647, 6211.440970312694, 6224.159738435299, 6224.408681175281, 6230.968845155745, 6427.775027874002, 6514.93485065148, 6547.214334279917, 6636.104125942984, 6681.398674119304, 6732.596044237362, 6743.696533655948, 6762.8309292267595, 6766.627835469048, 6776.085228494188, 6222.595531290452, 6245.125682404467, 6246.312905580383, 6252.3747625237565, 6266.0, 6680.231773346128, 6766.966165169241, 6807.931920680829, 6871.175458944958, 6885.896711549331, 5181.844544663666, 6254.841305140874, 6326.367363263729, 6386.393292637192, 6580.078788969548, 6605.735770569219, 6686.96656516724, 6700.463962882957, 6701.0, 6724.831879202961, 5464.836054918359, 6546.378094081055, 6611.471082313404, 6623.2383275923885, 6663.200415950117, 5553.8333849984565, 6385.584936979081, 6480.546361754727, 6559.639219842955, 6663.13379260693, 5330.093884039723, 5362.115250983544, 5377.300950876439, 5378.327709036371, 5380.811671591476, 4092.4475195848777, 4709.740964246931, 4740.45484769249, 4757.0, 4797.496262892416, 5783.0, 6229.470495007975, 6283.6858157091965, 6288.9056664150075, 6308.179936608211, 5694.9430505695245, 6144.539159562962, 6649.933500665028, 6747.831304217336, 6765.35729105744, 6286.685665716695, 6293.716782744787, 6296.811095667045, 6300.527460440394, 6303.243610766737, 5975.37258587842, 5980.252468441445, 5983.9215666778155, 5995.850103747354, 6005.309389420273, 6582.17722784652, 6709.563878347989, 6819.624920629413, 6823.727050917908, 6967.477439191979, 5340.49265319794, 5359.973200133974, 6274.780382686584, 6586.4401525870835, 6748.089007984056, 6824.727010919508, 6865.519413640997, 6915.031895534528, 6929.237783843874, 6949.30506949301, 6355.396237357629, 6456.386643268882, 6521.054447105238, 6581.506387020897, 6596.340365963365, 6589.439897608565, 6648.20221573395, 6698.430633396021, 6714.731410743516, 6737.3599508046655, 6310.968445157745, 6533.0853680485725, 6539.346065393423, 6540.149780528691, 6563.573367731082, 6278.623282603058, 6445.291017988111, 6526.543141980016, 6533.575317604341, 6564.37638424368, 6185.628862268279, 6379.7129129190225, 6385.776497822554, 6388.4569811566535, 6388.840278993062, 5623.915641265303, 5902.2327097477055, 6348.396902294275, 6505.479561635152, 6515.674216289071, 6346.270178929482, 6352.523560732871, 6367.490600752021, 6371.426571608577, 6398.328175541687], "value": 6016.6238252025705, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [786.4062632712302, 807.5840941914914, 827.4786884262913, 1197.4431889171533, 1277.3868543099318, 37111.44332835006, 38253.808730956305, 38306.61693383072, 38323.0, 38405.03987400313, 38194.236115277745, 38538.4219236711, 38606.22787544254, 38713.22573548527, 38770.61229387705, 7782.688692452295, 7900.407469439786, 8139.4709343892755, 8361.83276334473, 8364.330853531703, 8221.506709597414, 8234.752957411287, 8268.751937441857, 8341.332693384515, 8365.498070115813, 8244.093149753553, 8303.543305118215, 8338.207870252316, 8359.456635318686, 8379.83240335193, 8312.376571757082, 8318.916810831905, 8329.66681332746, 8355.665773369119, 8365.707200247993, 8084.0, 8165.550894700789, 8171.795705107361, 8179.527685016695, 8189.959050204711, 34886.82556587201, 35090.824545877105, 35193.824030879936, 35205.0, 35298.76454324086, 3542.9114272143147, 3550.431930891045, 3569.161247106928, 3590.1563132663805, 3593.0658028912408, 29105.85447072751, 29204.0, 29325.56011659827, 29359.41281174386, 29514.114576562737, 3035.074302337786, 3116.7350775184136, 3126.1715645353997, 3139.230888432326, 3151.2437015116207, 235567.0, 238956.0, 278802.60598697077, 283330.0, 285962.0, 45480.0, 45960.0, 46168.07663846704, 46228.613141605805, 46229.30656040162, 48379.75810120927, 48751.756241218565, 48824.511754882704, 48933.51066489289, 49041.50958490441, 14297.714045719027, 14344.282785860874, 14444.927775361057, 14473.493427729978, 14500.0, 11447.25592836463, 11629.1859569831, 11723.944844963995, 11746.236494627821, 11786.58746943853, 11266.887331126747, 13937.930310348585, 13975.930120349332, 13999.44002239899, 14087.506937257147, 5985.371535988746, 6097.542684298606, 6120.60216085953, 6125.0, 6173.81478555644, 845.9425717852685, 984.675057231112, 1009.4851625670814, 1017.8880323164447, 1042.2235434601118, 19967.60064798725, 23246.186383476495, 24247.75752242455, 24304.878475607507, 24405.023799048195, 989.851522271665, 1092.7978324010085, 1099.7745462180235, 1116.3748300951493, 1137.3289759042204, 11829.52681892732, 12314.692132696755, 12443.253404795742, 12491.687707807196, 12772.936135319263, 323268.0, 323682.0, 324887.0, 325011.0, 325630.0, 41896.0, 51717.7414112927, 52283.21575176375, 52380.47619523832, 52390.21414678783, 1464.9633759156106, 1465.3186268385039, 1501.8498150184894, 1508.2911031815029, 1513.1904431129292, 23850.88074559616, 23955.76044239605, 24059.037638494265, 24062.639060414458, 24097.51804963926, 35692.643073568936, 35858.46212306766, 35975.64024359723, 36235.45646815247, 36273.81863090667, 268774.0, 269393.0, 269591.0, 270085.0, 270230.0, 33791.324173516885, 34999.82500087483, 35055.47416788699, 35143.0, 35482.0, 41888.79055604825, 43453.0, 44061.77969110134, 44175.0, 44255.77872110619, 25602.498291404052, 28592.285192870346, 29552.261193470335, 30183.69816301809, 30677.23306917345, 30228.788141685574, 30283.545746814256, 30744.0, 30754.538681920236, 30838.383232334778, 36177.638223617425, 36898.70854520079, 37077.44383834299, 37384.626153738114, 37401.25197495981, 28610.0, 30290.84854575713, 31040.379192416476, 31353.529697054106, 31621.683783161872, 23677.763222367557, 23704.8814755932, 23788.88105559461, 23895.522089557762, 24084.51830963406], "value": 17233.099065028135, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [412.72966207134317, 415.32509671783345, 425.10091157202527, 434.65662126919733, 454.72943598558845, 21150.471238219052, 21348.146074157034, 22001.779982200194, 22850.31449056531, 23101.653475197887, 19520.804791952076, 19743.44617968765, 22349.553008939853, 22475.325740227778, 22638.0, 5963.642181469126, 6239.2824825145235, 6288.465480434145, 6291.4337709606125, 6291.993281075029, 5927.466528012478, 6099.939000609981, 6126.203593532835, 6233.812985610439, 6247.562670613059, 6105.938940610604, 6123.540734444934, 6124.846878828021, 6132.969335153341, 6138.662373569452, 5893.086571581412, 6062.939370606282, 6103.328633850318, 6105.297890742577, 6113.7860174893885, 4635.884102897421, 5378.300820893259, 5423.376311724123, 5427.267318911936, 5450.972745136249, 26882.327941801417, 27336.863315683495, 27405.629718514196, 27589.034383796472, 27595.586066209024, 2152.1068756466047, 2234.5084081502046, 2244.3603572981624, 2255.458689914425, 2255.7744225577476, 17809.0, 18006.819931800645, 18124.365647202285, 18333.449996500127, 18445.9854707991, 2137.871727696343, 2144.667576525641, 2189.9562008759895, 2194.3965409512352, 2198.296545105567, 117562.0, 147862.0, 148831.25584372008, 149305.25347373192, 150943.0, 12644.988400927903, 12671.17637353569, 12730.745385092247, 12803.61589152327, 12969.351532423341, 16310.266038028318, 16368.83631163697, 16410.343586256655, 16414.83585164157, 16416.835831641532, 8801.691940782042, 8837.293016558788, 8964.14840590143, 9105.180533751995, 9180.31147663928, 7030.718771249093, 8547.871781923277, 8572.228499435081, 8649.264812490883, 8716.520591367536, 7733.613319334011, 7869.134395216522, 7870.370370370357, 7883.0, 7897.68409263623, 1903.8952857592687, 2189.6496560550236, 2216.3683350245424, 2221.544583360407, 2248.527809160095, 921.594637994833, 958.5590628310915, 984.084801134937, 996.242855429864, 1032.3031953431405, 19671.0, 19930.90034549818, 20329.288474903307, 20965.161393544393, 21145.259915903178, 903.9017593623779, 1008.5159123620704, 1010.8324884758116, 1048.6120135549743, 1055.145332280855, 10722.463876806129, 10736.409497477558, 10776.0, 10788.083012943987, 10804.891951080388, 503634.0, 519939.0, 522037.7796221989, 525223.0, 527371.0, 126601.46797064008, 127989.36005319914, 130299.34850325687, 137920.0, 138322.0, 1077.2297807067985, 1178.8231765235284, 1217.549506682533, 1246.0903540415547, 1250.4623012104882, 21690.783092168876, 21755.0, 21925.0, 22163.556728865657, 22185.00167492499, 53616.463835361144, 53659.0, 53881.46118538764, 54304.456955431524, 54767.17849232185, 74228.62885685537, 74637.62681186559, 74909.0, 75422.0, 75925.24074759182, 8738.432001919855, 8867.387365864197, 9013.369064165445, 9075.273978081606, 9144.862827057466, 11801.35092569917, 13221.933890330487, 13553.915686745235, 13980.510682126076, 14222.359993800097, 11434.256773309708, 11641.126915481203, 11723.941380293385, 11742.17804753659, 11861.5848445304, 10925.890741092488, 11030.28303160292, 11245.606403775828, 11309.660710178709, 11388.088952883583, 11499.137564682514, 11866.0, 12110.152289340014, 12129.272243665408, 12180.939095304468, 8742.475451472932, 8754.956225219088, 8827.293816494537, 9020.458772473672, 9094.317926155698, 9813.304242183707, 11929.522819087138, 11989.34058626784, 12012.0, 12068.275903445821], "value": 10403.376231261149, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1263.147375521523, 1410.3441899516722, 1415.9150450972943, 1425.8593125499604, 1430.1633544376537, 11635.301881887082, 11922.0, 12102.515899364014, 12149.635510934675, 12189.451474683638, 12040.458179381927, 12087.576934807283, 12233.510659573629, 12263.693407664812, 12279.324637144953, 2178.6514157734764, 2353.9176128835493, 2434.2940547241324, 2449.5835707929696, 2480.900763969439, 2410.554047501209, 2441.6703744994466, 2446.889889954947, 2450.583400821856, 2453.1046168148587, 2398.9280321590295, 2425.6604075429364, 2434.8782560871973, 2439.121916110199, 2443.609022556391, 2286.965695514569, 2365.8817059146972, 2380.880955952204, 2390.509945461185, 2399.3281881073326, 2137.700721898935, 2232.2968264996634, 2368.609179485383, 2369.312899259213, 2498.2005758157397, 12006.279623222546, 12073.758524829454, 12100.757984840342, 12102.455389507493, 12144.696382590417, 6441.355864413568, 6703.966480167616, 6706.463482921355, 6711.66441677919, 6724.462043036546, 3382.813945233011, 3588.4976103345552, 4255.978720106411, 4336.7831608419765, 4338.240807858612, 1214.5324050240633, 1264.9620511384674, 1271.4087949103712, 1274.655842922415, 1305.660528262649, 15288.0, 15829.812764042743, 17027.40404085851, 17040.65918681632, 17180.0, 5523.834284971377, 5524.281843360337, 5568.081266590967, 5587.804426845041, 5597.74810133545, 11734.471948762326, 11909.0, 11918.46366913491, 11922.284662920252, 11950.223235489839, 6319.210098737658, 6353.714082866282, 6439.484841212692, 6451.516136289703, 6467.741290348427, 4522.547745225451, 4645.46577143626, 5932.703364831741, 5996.970015149896, 6152.477039451696, 4281.186574550826, 4342.6742994275555, 4362.541933097043, 4401.2517871961845, 4417.249067658506, 1263.9557615483413, 1271.1038717704182, 1272.8027155790867, 1273.5669872243482, 1278.8529319128234, 695.8712638161957, 761.5963539324167, 790.7627711686471, 794.2970471133088, 796.0328201235527, 6501.934980650133, 6848.828779280558, 7183.748568800067, 7209.567425954459, 7315.085614298213, 925.3476299209042, 935.1209862729013, 940.4874343482818, 940.6472572785206, 976.5019839881569, 3677.7609455385314, 4324.610785029299, 4406.823727050946, 4441.71128876622, 4567.058999925085, 14650.926745366205, 14870.925645371704, 15269.236538173269, 15383.153926533923, 15425.305861236271, 15000.774988375184, 15195.620109497351, 15216.391344346324, 15256.23718814077, 15274.847251527564, 8459.788505287417, 8537.786555336166, 8602.483850968962, 8628.69799557025, 8629.568521573896, 5512.448755124456, 5674.489295963218, 6868.965655171693, 7041.0, 7120.643967801486, 13519.594412167651, 13710.38303276375, 13829.930850345683, 13914.304284785518, 14000.229987350385, 13825.654358641115, 14225.644358890697, 14249.857501424853, 14379.568612941628, 14422.0, 7794.493357931717, 9602.711918642452, 9622.0, 9661.46861922601, 9678.274129440179, 10900.34597924127, 11007.944960275468, 11020.5040773167, 11025.558977640805, 11030.06244469197, 8988.280937525113, 9256.166944974715, 9345.392549484262, 9362.344635875423, 9439.81120377575, 9129.315301352291, 9189.0, 9230.769230769016, 9297.767555811159, 9412.482313472825, 7864.331531819859, 7983.72056978003, 8035.758927232192, 8090.110087890445, 8126.959365203136, 6021.488173505126, 6093.086037094471, 6103.42017508336, 6220.937790622036, 6228.408301211378, 5988.760449581968, 5995.640261584319, 6112.633242005316, 6150.661713605795, 6243.7814676486105], "value": 5371.241773285923, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [323.2984423800353, 386.06572095528816, 402.35823860941787, 411.97734124623145, 414.3598140872352, 6164.7534098636, 6273.309935907047, 6388.872222555552, 6395.776147834827, 6465.709043093055, 5320.893582128355, 5723.245003020909, 5727.455891690282, 6228.564000519966, 6279.968600157004, 4773.212419950704, 4788.329633851264, 4789.640776941726, 4804.44748853881, 4825.951740482586, 4241.215375155606, 4720.315554244632, 4743.691660042086, 4825.7587120644, 4829.637777166709, 4080.1431699343175, 4244.214820258262, 4757.167495688258, 4764.309175169598, 4769.546893045155, 3799.3351163546545, 4367.711525100087, 4522.525134860858, 4686.601638860701, 4739.289106633998, 3923.7645741255333, 4237.237297286487, 4245.341971994346, 4251.851185208503, 4257.19113368459, 6015.157877897098, 6074.453299203093, 6130.264368275791, 6172.22847144107, 6185.206290175844, 5144.0, 5657.3494048184575, 5662.292213473316, 5667.121596152603, 5681.801136960186, 5851.063829787214, 6053.7892421515435, 6067.939320606782, 6498.902516462257, 6731.663416829139, 3832.597577254404, 3873.283442563135, 4368.541303163154, 4391.824327026915, 4426.513083560806, 5512.586556008276, 5654.575906807006, 7048.00459804581, 7121.181064177634, 7154.816106832462, 4421.579949904753, 4428.867133985986, 4437.467503899553, 4451.532589078165, 4460.8661740147845, 4509.188346097669, 4509.526499717548, 4538.410006699108, 4549.863504094882, 4572.771361431982, 5145.253938178943, 5381.865453363697, 5383.596230282745, 5394.273832373681, 5408.540274076748, 6384.32964538726, 6568.540202185896, 6697.832554186087, 6735.562188457735, 6753.527253092237, 1499.7825315329217, 1544.6833399153372, 1551.6353656890622, 1551.689662067591, 1556.237443652602, 986.2997271936862, 1014.5181039006413, 1021.1524434719165, 1029.8867124616286, 1031.5770534080991, 5629.0, 5830.008898487267, 6351.555391122577, 6423.164988551458, 6444.45222156122, 6493.448056915121, 6801.1158549388265, 6914.9308506914285, 6954.860902781916, 6973.407260382823, 6132.50939924805, 6310.87611632245, 6364.809055728336, 6389.897647746391, 6416.582922110048, 3041.5893854329825, 3587.121155316964, 3653.488511608377, 3660.698679052103, 3670.944935825965, 7048.647567621599, 7163.853783394632, 7176.390006849372, 7189.388901943289, 7197.856042879218, 5956.255468066492, 6390.712417941204, 7066.92933070673, 7132.821679458055, 7147.428205743528, 6867.416269617139, 7044.577325360495, 7122.685127333533, 7130.25132361105, 7213.134423869064, 6923.93076069253, 6950.270221626859, 6958.652067396509, 6965.268646792014, 6974.197967233833, 6597.340265973365, 6769.882969310205, 6832.248452670302, 6882.5870447773295, 6918.827029324307, 5748.425157484218, 6300.306966233619, 6970.919507476349, 7151.499395042505, 7154.2488038757265, 4356.912861742684, 4445.666575006822, 4477.417935668343, 4478.171538265431, 4514.300283456069, 5701.344345400332, 6026.548008899263, 6115.850337076498, 6924.991752391951, 7006.986995591945, 5903.734331954986, 6610.669466526559, 6674.6662666865495, 6715.731370745311, 6745.865082698417, 6620.635865027277, 6629.900551491635, 6691.230508491586, 6700.095487109178, 6708.295628959084, 5440.809571664972, 5960.195373624505, 6123.112148738497, 6476.190476190476, 6513.348665133638, 6363.649999250086, 6630.569013014138, 6700.798976030727, 6717.462602991846, 6754.797356079325, 6132.62016046386, 6232.968835155795, 6524.739010439529, 6616.676664666989, 6632.7678531253105], "value": 4869.323711282297, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [719.5718547464259, 801.515083374558, 815.6003558256452, 829.8423698939979, 840.865461526156, 16818.66362672746, 17324.307027718893, 17331.740023899634, 19568.217271309168, 20301.187952481883, 18369.54076148097, 18529.536761580966, 18742.718859217122, 18795.62408751824, 18826.811731882677, 1005.989940100597, 1038.8026275007724, 1040.8230600797883, 1043.2227990147312, 1045.503385891699, 1003.6085926488676, 1013.7110923386837, 1015.6546774096805, 1016.4612755239734, 1019.5768755966302, 1073.6617965340931, 1078.234453537985, 1079.8218293981522, 1082.815921293382, 1085.4952447112091, 1075.2150929821196, 1075.4192735922593, 1077.3374374759467, 1079.7084787107476, 1080.9351438913652, 783.5024759277843, 798.0383637716537, 800.071916576774, 800.4956877167406, 802.6067227058758, 17279.395221167324, 17911.0, 18155.183016764277, 18288.0, 18331.0, 17037.659246814994, 17508.91245543777, 17658.11709414524, 17681.204345804468, 17977.460676179737, 14362.210078445682, 14560.126392416489, 14712.632184195374, 14723.705525889529, 14755.409783608642, 3512.666296701809, 3584.8028358440274, 3585.515955346021, 3591.371509985768, 3607.1703508193264, 111564.0, 119966.60066798702, 145492.0, 146518.5348146526, 148725.79459718967, 13483.932580337036, 13789.655258618615, 13943.930280348533, 13959.022868399317, 14044.157350558999, 23477.88261058684, 25303.873480632847, 25695.100671476783, 26877.731222687522, 26884.462310753675, 6812.250652428229, 7994.920050799534, 8110.756677299691, 8116.959415203004, 8151.5109093454585, 3912.256671232457, 3966.167104907944, 3967.206558688274, 3968.6825053995613, 3975.7216994810665, 3680.650338217865, 4288.335308027261, 4383.671224658164, 4436.534163912807, 4441.777911104432, 936.941256380286, 944.3106532231509, 949.0, 954.5561313989028, 955.3694561589317, 629.7418058595954, 661.3650895140622, 665.6838001949133, 672.2907332763883, 681.8874885643912, 11422.0, 12235.938820306019, 12410.56563020308, 12512.186707863959, 12664.620061398173, 630.4357599948021, 651.8859199640044, 653.2781276689285, 684.4387602166275, 686.5503095472474, 5259.789608415697, 5306.230596563477, 5366.892662146814, 5406.378266499403, 5503.532199762985, 139776.3011184958, 148716.35910947475, 148989.51010489758, 149602.7559586607, 151238.0, 125321.0, 126353.0, 128279.0, 129104.0, 129413.70586294205, 107350.0, 108589.0, 108926.45536772265, 109599.45200274108, 111192.88807111987, 8593.0, 9322.254219662547, 10417.166626669698, 10420.68737937863, 10891.128709703364, 43878.12243755171, 44036.0, 44345.33481997837, 45167.32249016333, 45294.32058519059, 40034.799826000686, 58797.70601147138, 59165.81668366695, 59173.70413148079, 60325.09512357406, 14053.718925621635, 14190.148591084568, 14496.85503144955, 14732.0, 14770.48303309379, 13775.931120344334, 15817.68364632678, 16330.67338653244, 16398.0, 16410.015399075615, 14696.779548306999, 14907.62730931692, 15004.6999060016, 15227.314770835561, 15237.542873713806, 12790.360481975678, 14199.0, 14796.408143674133, 14907.552773416815, 15032.24838758036, 14409.495667651581, 14688.339024743698, 14723.484678036217, 14830.925845370704, 14866.702665946837, 10597.205209609465, 10599.099076578577, 10782.946085269523, 10899.0, 10928.726781830199, 10603.561180928275, 11677.708057298636, 11827.0, 11919.284842909454, 11997.460114295052], "value": 8543.489985711765, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1521.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 115.0, 112.0, 114.0, 113.0, 113.0, 113.0, 114.0, 112.0, 113.0], "value": 112.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 107.0, 112.0, 148.0, 124.0, 151.0, 144.0, 116.0, 135.0, 111.0], "value": 129.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 197.0, 198.0, 199.0, 258.0, 275.0, 269.0, 281.0, 223.0, 203.0], "value": 230.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 58.0, 60.0, 80.0, 62.0, 57.0, 58.0, 59.0, 76.0, 63.0], "value": 64.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 70.0, 68.0, 89.0, 93.0, 106.0, 106.0, 105.0, 83.0, 87.0], "value": 88.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 91.0, 88.0, 88.0, 90.0, 88.0, 87.0, 89.0, 89.0, 96.0], "value": 89.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 98.0, 94.0, 96.0, 96.0, 96.0, 96.0], "value": 95.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 91.0, 90.0, 93.0, 104.0, 90.0, 91.0, 90.0, 92.0, 92.0], "value": 92.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 49.0, 50.0, 48.0, 51.0, 50.0, 54.0, 52.0, 50.0, 50.0], "value": 50.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 60.0, 59.0, 56.0, 72.0, 62.0, 59.0, 70.0, 82.0, 86.0], "value": 66.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 89.0, 86.0, 86.0, 86.0, 89.0, 91.0, 132.0, 108.0], "value": 94.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 159.0, 151.0, 141.0, 146.0, 158.0, 161.0, 148.0, 161.0, 140.0], "value": 152.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 189.0, 195.0, 177.0, 183.0, 186.0, 187.0, 187.0, 186.0, 187.0], "value": 186.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 68.0, 67.0, 66.0, 70.0, 68.0, 67.0, 69.0, 72.0], "value": 68.1, "unit": "score"}]}]} 14:39:50 INFO - Return code: 0 14:39:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:39:50 INFO - # TBPL SUCCESS # 14:39:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:39:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:39:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:39:50 INFO - 2017-05-26 14:39:50.570 screenresolution[1895:11887] starting screenresolution argv=screenresolution get 14:39:50 INFO - 2017-05-26 14:39:50.572 screenresolution[1895:11887] Display 0: 1600x1200x32@60 14:39:50 INFO - 2017-05-26 14:39:50.578 screenresolution[1896:11890] starting screenresolution argv=screenresolution list 14:39:50 INFO - Available Modes on Display 0 14:39:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:39:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:39:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:39:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:39:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:39:50 INFO - Intel Iris: 14:39:50 INFO - Chipset Model: Intel Iris 14:39:50 INFO - Type: GPU 14:39:50 INFO - Bus: Built-In 14:39:50 INFO - VRAM (Dynamic, Max): 1536 MB 14:39:50 INFO - Vendor: Intel (0x8086) 14:39:50 INFO - Device ID: 0x0a2e 14:39:50 INFO - Revision ID: 0x0009 14:39:50 INFO - Displays: 14:39:50 INFO - Display: 14:39:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:39:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:39:50 INFO - Display Serial Number: 1600x1200 60 14:39:50 INFO - Main Display: Yes 14:39:50 INFO - Mirror: Off 14:39:50 INFO - Online: Yes 14:39:50 INFO - Rotation: Supported 14:39:50 INFO - Return code: 0 14:39:50 INFO - Running post-action listener: _resource_record_post_action 14:39:50 INFO - [mozharness: 2017-05-26 21:39:50.812169Z] Finished run-tests step (success) 14:39:50 INFO - Running post-run listener: _resource_record_post_run 14:39:50 INFO - Total resource usage - Wall time: 1254s; CPU: 53.0%; Read bytes: 51837952; Write bytes: 564379648; Read time: 728; Write time: 1797 14:39:50 INFO - TinderboxPrint: CPU usage
53.4% 14:39:50 INFO - TinderboxPrint: I/O read bytes / time
51,837,952 / 728 14:39:50 INFO - TinderboxPrint: I/O write bytes / time
564,379,648 / 1,797 14:39:50 INFO - TinderboxPrint: CPU idle
2,337.3 (46.6%) 14:39:50 INFO - TinderboxPrint: CPU system
1,015.5 (20.2%) 14:39:50 INFO - TinderboxPrint: CPU user
1,664.9 (33.2%) 14:39:50 INFO - TinderboxPrint: Swap in / out
290,353,152 / 0 14:39:50 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 239565312; Write bytes: 231792640; Read time: 16980; Write time: 419 14:39:50 INFO - run-tests - Wall time: 1234s; CPU: 53.0%; Read bytes: 49585152; Write bytes: 318226432; Read time: 701; Write time: 1332 14:39:51 INFO - Running post-run listener: _upload_blobber_files 14:39:51 INFO - Blob upload gear active. 14:39:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:39:51 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:39:51 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:39:51 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:39:51 INFO - (blobuploader) - INFO - Open directory for files ... 14:39:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:39:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:39:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:39:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:39:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:39:52 INFO - (blobuploader) - INFO - Done attempting. 14:39:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:39:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:39:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:39:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:39:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:39:53 INFO - (blobuploader) - INFO - Done attempting. 14:39:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:39:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:39:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:39:54 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:39:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:39:54 INFO - (blobuploader) - INFO - Done attempting. 14:39:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:39:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:39:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:39:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:39:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:39:56 INFO - (blobuploader) - INFO - Done attempting. 14:39:56 INFO - (blobuploader) - INFO - Iteration through files over. 14:39:56 INFO - Return code: 0 14:39:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:39:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:39:56 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c88876d98ffa64b46fafd07c1faf47d80e9ad2da6e6e9971db0830ff93ffc0db10a27b59b7e89e941ffb27cbd42ad73ac0f2d20cd2ef836bdab962128fd24082", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cdbf75f817ee93acd2eb9e179015037e7333af2f8aa5c92ea33ba6e146d037159e2d2fe662eb8da9845759224d6c58d87ef8070b39e48a9065f6b321ced959cd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/48d123d139ac6fbdf3eeec575a352b948d10507ebf73c10573b5839936091a4e245ccf1a4bc74f9f2d3c64923e57fe3743d74abbc9495b15a30cc436adf2ef6e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/508563982e90a0f55cae42ac6439ea5ecb17ba1aca25ad532aff50f6b4182a7e176b10512bd3a65b78f406663e68a065c0f52a54925c05743bb6b2b4a1f88c4d"} 14:39:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:39:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:39:56 INFO - Contents: 14:39:56 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c88876d98ffa64b46fafd07c1faf47d80e9ad2da6e6e9971db0830ff93ffc0db10a27b59b7e89e941ffb27cbd42ad73ac0f2d20cd2ef836bdab962128fd24082", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cdbf75f817ee93acd2eb9e179015037e7333af2f8aa5c92ea33ba6e146d037159e2d2fe662eb8da9845759224d6c58d87ef8070b39e48a9065f6b321ced959cd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/48d123d139ac6fbdf3eeec575a352b948d10507ebf73c10573b5839936091a4e245ccf1a4bc74f9f2d3c64923e57fe3743d74abbc9495b15a30cc436adf2ef6e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/508563982e90a0f55cae42ac6439ea5ecb17ba1aca25ad532aff50f6b4182a7e176b10512bd3a65b78f406663e68a065c0f52a54925c05743bb6b2b4a1f88c4d"} 14:39:56 INFO - Running post-run listener: copy_logs_to_upload_dir 14:39:56 INFO - Copying logs to upload dir... 14:39:56 INFO - mkdir: /builds/slave/test/build/upload/logs 14:39:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1337.055860 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2017-05-26 14:39:56.865784) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-26 14:39:56.873314) ========= 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.PP22TxG5Wk/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.mqu14ounF3/Listeners TMPDIR=/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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/c88876d98ffa64b46fafd07c1faf47d80e9ad2da6e6e9971db0830ff93ffc0db10a27b59b7e89e941ffb27cbd42ad73ac0f2d20cd2ef836bdab962128fd24082", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cdbf75f817ee93acd2eb9e179015037e7333af2f8aa5c92ea33ba6e146d037159e2d2fe662eb8da9845759224d6c58d87ef8070b39e48a9065f6b321ced959cd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/48d123d139ac6fbdf3eeec575a352b948d10507ebf73c10573b5839936091a4e245ccf1a4bc74f9f2d3c64923e57fe3743d74abbc9495b15a30cc436adf2ef6e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/508563982e90a0f55cae42ac6439ea5ecb17ba1aca25ad532aff50f6b4182a7e176b10512bd3a65b78f406663e68a065c0f52a54925c05743bb6b2b4a1f88c4d"} build_url:https://queue.taskcluster.net/v1/task/LHz1mKwlT_qbpV2mdtWiyw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011098 build_url: 'https://queue.taskcluster.net/v1/task/LHz1mKwlT_qbpV2mdtWiyw/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/c88876d98ffa64b46fafd07c1faf47d80e9ad2da6e6e9971db0830ff93ffc0db10a27b59b7e89e941ffb27cbd42ad73ac0f2d20cd2ef836bdab962128fd24082", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cdbf75f817ee93acd2eb9e179015037e7333af2f8aa5c92ea33ba6e146d037159e2d2fe662eb8da9845759224d6c58d87ef8070b39e48a9065f6b321ced959cd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/48d123d139ac6fbdf3eeec575a352b948d10507ebf73c10573b5839936091a4e245ccf1a4bc74f9f2d3c64923e57fe3743d74abbc9495b15a30cc436adf2ef6e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/508563982e90a0f55cae42ac6439ea5ecb17ba1aca25ad532aff50f6b4182a7e176b10512bd3a65b78f406663e68a065c0f52a54925c05743bb6b2b4a1f88c4d"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-26 14:39:56.912299) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 14:39:56.912699) ========= 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.PP22TxG5Wk/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.mqu14ounF3/Listeners TMPDIR=/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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.005166 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 14:39:56.965473) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-26 14:39:56.965842) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-26 14:39:56.966684) ========= ========= Total master_lag: 0.19 =========