builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0196 starttime: 1495243279.99 results: success (0) revision: 8d60d0f825110cfb646ac31dc16dc011708bcf34 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-19 18:21:19.992582) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-19 18:21:19.993050) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-19 18:21:19.993330) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-19 18:21:20.024050) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 18:21:20.024423) ========= 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.MKG4TKaMkh/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.zpkjQim7Qk/Listeners TMPDIR=/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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-19 18:21:20-- 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% 9.47M=0.001s 2017-05-19 18:21:20 (9.47 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.110127 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 18:21:20.154667) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 18:21:20.158111) ========= 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.MKG4TKaMkh/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.zpkjQim7Qk/Listeners TMPDIR=/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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.028250 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 18:21:20.207033) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-19 18:21:20.207362) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8d60d0f825110cfb646ac31dc16dc011708bcf34 --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 8d60d0f825110cfb646ac31dc16dc011708bcf34 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.MKG4TKaMkh/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.zpkjQim7Qk/Listeners TMPDIR=/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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-19 18:21:20,327 truncating revision to first 12 chars 2017-05-19 18:21:20,327 Setting DEBUG logging. 2017-05-19 18:21:20,327 attempt 1/10 2017-05-19 18:21:20,327 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8d60d0f82511?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-19 18:21:21,212 unpacking tar archive at: mozilla-central-8d60d0f82511/testing/mozharness/ program finished with exit code 0 elapsedTime=1.157499 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-19 18:21:21.391981) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-19 18:21:21.392308) ========= script_repo_revision: 8d60d0f825110cfb646ac31dc16dc011708bcf34 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-19 18:21:21.392677) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-19 18:21:21.392951) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-19 18:21:21.411276) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2017-05-19 18:21:21.411661) ========= /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.MKG4TKaMkh/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.zpkjQim7Qk/Listeners TMPDIR=/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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 18:21:21 INFO - MultiFileLogger online at 20170519 18:21:21 in /builds/slave/test 18:21:21 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 18:21:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:21:21 INFO - {'append_to_log': False, 18:21:21 INFO - 'base_work_dir': '/builds/slave/test', 18:21:21 INFO - 'blob_upload_branch': 'Firefox', 18:21:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:21:21 INFO - 'branch': 'Firefox', 18:21:21 INFO - 'buildbot_json_path': 'buildprops.json', 18:21:21 INFO - 'config_files': ('talos/mac_config.py',), 18:21:21 INFO - 'default_actions': ('clobber', 18:21:21 INFO - 'read-buildbot-config', 18:21:21 INFO - 'download-and-extract', 18:21:21 INFO - 'populate-webroot', 18:21:21 INFO - 'create-virtualenv', 18:21:21 INFO - 'install', 18:21:21 INFO - 'run-tests'), 18:21:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:21:21 INFO - 'download_minidump_stackwalk': True, 18:21:21 INFO - 'download_symbols': 'ondemand', 18:21:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:21:21 INFO - 'tooltool.py': '/tools/tooltool.py', 18:21:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:21:21 INFO - '/tools/misc-python/virtualenv.py')}, 18:21:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:21:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:21:21 INFO - 'gecko_profile': False, 18:21:21 INFO - 'gecko_profile_interval': 0, 18:21:21 INFO - 'installer_path': 'installer.exe', 18:21:21 INFO - 'log_level': 'info', 18:21:21 INFO - 'log_name': 'talos', 18:21:21 INFO - 'log_to_console': True, 18:21:21 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:21:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:21:21 INFO - 'opt_config_files': (), 18:21:21 INFO - 'pip_index': False, 18:21:21 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:21:21 INFO - 'cmd': ('bash', 18:21:21 INFO - '-c', 18:21:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:21:21 INFO - 'enabled': True, 18:21:21 INFO - 'halt_on_failure': False, 18:21:21 INFO - 'name': 'check_screen_resolution'},), 18:21:21 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:21:21 INFO - 'cmd': ('bash', 18:21:21 INFO - '-c', 18:21:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:21:21 INFO - 'enabled': True, 18:21:21 INFO - 'halt_on_failure': False, 18:21:21 INFO - 'name': 'check_screen_resolution'},), 18:21:21 INFO - 'run_cmd_checks_enabled': True, 18:21:21 INFO - 'suite': 'dromaeojs-e10s', 18:21:21 INFO - 'system_bits': '32', 18:21:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:21:21 INFO - 'title': 't-yosemite-r7-0196', 18:21:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:21:21 INFO - 'use_talos_json': True, 18:21:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:21:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:21:21 INFO - 'work_dir': 'build'} 18:21:21 INFO - [mozharness: 2017-05-20 01:21:21.643581Z] Running clobber step. 18:21:21 INFO - Running pre-action listener: _resource_record_pre_action 18:21:21 INFO - Running main action method: clobber 18:21:21 INFO - rmtree: /builds/slave/test/build 18:21:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:21:24 INFO - Running post-action listener: _resource_record_post_action 18:21:24 INFO - [mozharness: 2017-05-20 01:21:24.260746Z] Finished clobber step (success) 18:21:24 INFO - [mozharness: 2017-05-20 01:21:24.260823Z] Running read-buildbot-config step. 18:21:24 INFO - Running pre-action listener: _resource_record_pre_action 18:21:24 INFO - Running main action method: read_buildbot_config 18:21:24 INFO - Using buildbot properties: 18:21:24 INFO - { 18:21:24 INFO - "project": "", 18:21:24 INFO - "product": "firefox", 18:21:24 INFO - "who": "kwierso@gmail.com", 18:21:24 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 18:21:24 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 18:21:24 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 18:21:24 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 18:21:24 INFO - "stage_platform": "macosx64", 18:21:24 INFO - "basedir": "/builds/slave/test", 18:21:24 INFO - "buildnumber": 220, 18:21:24 INFO - "platform": "yosemite_r7", 18:21:24 INFO - "parent_task_id": "GCKSwIhdSfe5r7nzaY-zGg", 18:21:24 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 18:21:24 INFO - "slavebuilddir": "test", 18:21:24 INFO - "taskId": "fmmQQAUDRBmVM0TxyVjpXA", 18:21:24 INFO - "branch": "mozilla-central", 18:21:24 INFO - "script_repo_revision": "production", 18:21:24 INFO - "revision": "8d60d0f825110cfb646ac31dc16dc011708bcf34", 18:21:24 INFO - "slavename": "t-yosemite-r7-0196", 18:21:24 INFO - "repo_path": "mozilla-central" 18:21:24 INFO - } 18:21:24 INFO - Finding installer, test and symbols from parent task. 18:21:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fmmQQAUDRBmVM0TxyVjpXA'}, attempt #1 18:21:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fmmQQAUDRBmVM0TxyVjpXA'}, attempt #1 18:21:25 INFO - Task dependencies: AwiOW-18RAWRn-_SxECLOQ GCKSwIhdSfe5r7nzaY-zGg 18:21:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GCKSwIhdSfe5r7nzaY-zGg'}, attempt #1 18:21:25 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/GCKSwIhdSfe5r7nzaY-zGg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:21:25 INFO - Set test_url: https://queue.taskcluster.net/v1/task/GCKSwIhdSfe5r7nzaY-zGg/artifacts/public/build/test_packages.json 18:21:25 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/GCKSwIhdSfe5r7nzaY-zGg/artifacts/public/build/target.crashreporter-symbols.zip 18:21:25 INFO - Running post-action listener: _resource_record_post_action 18:21:25 INFO - [mozharness: 2017-05-20 01:21:25.614179Z] Finished read-buildbot-config step (success) 18:21:25 INFO - [mozharness: 2017-05-20 01:21:25.614328Z] Running download-and-extract step. 18:21:25 INFO - Running pre-action listener: _resource_record_pre_action 18:21:25 INFO - Running main action method: download_and_extract 18:21:25 INFO - mkdir: /builds/slave/test/build/tests 18:21: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')]} 18:21:25 INFO - trying https://queue.taskcluster.net/v1/task/GCKSwIhdSfe5r7nzaY-zGg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 18:21:25 INFO - Downloading https://queue.taskcluster.net/v1/task/GCKSwIhdSfe5r7nzaY-zGg/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 18:21:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GCKSwIhdSfe5r7nzaY-zGg/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 18:21:26 INFO - Downloaded 1325 bytes. 18:21:26 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 18:21:26 INFO - Using the following test package requirements: 18:21:26 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:21:26 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 18:21:26 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:21:26 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:21:26 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 18:21:26 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:21:26 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 18:21:26 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:21:26 INFO - u'jsshell-mac.zip'], 18:21:26 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:21:26 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 18:21:26 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:21:26 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:21:26 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 18:21:26 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:21:26 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 18:21:26 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:21:26 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 18:21:26 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:21:26 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 18:21:26 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:21:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GCKSwIhdSfe5r7nzaY-zGg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:21:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GCKSwIhdSfe5r7nzaY-zGg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:21:26 INFO - Fetch https://queue.taskcluster.net/v1/task/GCKSwIhdSfe5r7nzaY-zGg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:21:28 INFO - Content-Length response header: 57622714 18:21:28 INFO - Bytes received: 57622714 18:21:46 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 18:21:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GCKSwIhdSfe5r7nzaY-zGg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:21:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GCKSwIhdSfe5r7nzaY-zGg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:21:46 INFO - Fetch https://queue.taskcluster.net/v1/task/GCKSwIhdSfe5r7nzaY-zGg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:21:48 INFO - Content-Length response header: 57622714 18:21:48 INFO - Bytes received: 57622714 18:22:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GCKSwIhdSfe5r7nzaY-zGg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 18:22:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GCKSwIhdSfe5r7nzaY-zGg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:22:05 INFO - Fetch https://queue.taskcluster.net/v1/task/GCKSwIhdSfe5r7nzaY-zGg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 18:22:06 INFO - Content-Length response header: 14410000 18:22:06 INFO - Bytes received: 14410000 18:22:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:06 INFO - trying https://queue.taskcluster.net/v1/task/GCKSwIhdSfe5r7nzaY-zGg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:22:06 INFO - Downloading https://queue.taskcluster.net/v1/task/GCKSwIhdSfe5r7nzaY-zGg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 18:22:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GCKSwIhdSfe5r7nzaY-zGg/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 18:22:08 INFO - Downloaded 68065903 bytes. 18:22:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GCKSwIhdSfe5r7nzaY-zGg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:22:08 INFO - mkdir: /builds/slave/test/properties 18:22:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:22:08 INFO - Writing to file /builds/slave/test/properties/build_url 18:22:08 INFO - Contents: 18:22:08 INFO - build_url:https://queue.taskcluster.net/v1/task/GCKSwIhdSfe5r7nzaY-zGg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:22:08 INFO - Running post-action listener: _resource_record_post_action 18:22:08 INFO - Running post-action listener: set_extra_try_arguments 18:22:08 INFO - [mozharness: 2017-05-20 01:22:08.591513Z] Finished download-and-extract step (success) 18:22:08 INFO - [mozharness: 2017-05-20 01:22:08.591686Z] Running populate-webroot step. 18:22:08 INFO - Running pre-action listener: _resource_record_pre_action 18:22:08 INFO - Running main action method: populate_webroot 18:22:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:22:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:22:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:22:08 INFO - '--tppagecycles', 18:22:08 INFO - '7'], 18:22:08 INFO - 'tests': ['tsvgm']}}, 18:22:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:22:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:22:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:22:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:22:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:22:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:22:08 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:22:08 INFO - 'talos_options': ['--disable-e10s'], 18:22:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:22:08 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:22:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:22:08 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:22:08 INFO - 'talos_options': ['--disable-e10s'], 18:22:08 INFO - 'tests': ['damp', 'tps']}, 18:22:08 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:22:08 INFO - 'tests': ['damp', 'tps']}, 18:22:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:22:08 INFO - 'tests': ['dromaeo_dom']}, 18:22:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:22:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:22:08 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 18:22:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:22:08 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:22:08 INFO - 'tests': ['ts_paint', 'tp5o']}, 18:22:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:22:08 INFO - 'tests': ['a11yr', 18:22:08 INFO - 'ts_paint', 18:22:08 INFO - 'tpaint', 18:22:08 INFO - 'sessionrestore', 18:22:08 INFO - 'sessionrestore_no_auto_restore', 18:22:08 INFO - 'tabpaint']}, 18:22:08 INFO - 'other-e10s': {'tests': ['a11yr', 18:22:08 INFO - 'ts_paint', 18:22:08 INFO - 'tpaint', 18:22:08 INFO - 'sessionrestore', 18:22:08 INFO - 'sessionrestore_no_auto_restore', 18:22:08 INFO - 'tabpaint']}, 18:22:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:22:08 INFO - 'ts_paint', 18:22:08 INFO - 'tpaint', 18:22:08 INFO - 'sessionrestore', 18:22:08 INFO - 'sessionrestore_no_auto_restore', 18:22:08 INFO - 'tabpaint']}, 18:22:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:22:08 INFO - 'ts_paint', 18:22:08 INFO - 'tpaint', 18:22:08 INFO - 'sessionrestore', 18:22:08 INFO - 'sessionrestore_no_auto_restore', 18:22:08 INFO - 'tabpaint']}, 18:22:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:22:08 INFO - 'tests': ['a11yr', 18:22:08 INFO - 'ts_paint', 18:22:08 INFO - 'tpaint', 18:22:08 INFO - 'sessionrestore', 18:22:08 INFO - 'sessionrestore_no_auto_restore', 18:22:08 INFO - 'tabpaint']}, 18:22:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:22:08 INFO - 'tests': ['a11yr', 18:22:08 INFO - 'ts_paint', 18:22:08 INFO - 'tpaint', 18:22:08 INFO - 'sessionrestore', 18:22:08 INFO - 'sessionrestore_no_auto_restore', 18:22:08 INFO - 'tabpaint']}, 18:22:08 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 18:22:08 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 18:22:08 INFO - 'bloom_basic_ref']}, 18:22:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:22:08 INFO - 'tests': ['tsvgx', 18:22:08 INFO - 'tsvgr_opacity', 18:22:08 INFO - 'tart', 18:22:08 INFO - 'tscrollx', 18:22:08 INFO - 'cart', 18:22:08 INFO - 'tsvg_static']}, 18:22:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:22:08 INFO - 'tsvgr_opacity', 18:22:08 INFO - 'tart', 18:22:08 INFO - 'tscrollx', 18:22:08 INFO - 'cart', 18:22:08 INFO - 'tsvg_static']}, 18:22:08 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:22:08 INFO - 'talos_options': ['--disable-e10s'], 18:22:08 INFO - 'tests': ['tp5o']}, 18:22:08 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:22:08 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:22:08 INFO - 'talos_options': ['--disable-e10s', 18:22:08 INFO - '--xperf_path', 18:22:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:22:08 INFO - 'tests': ['tp5n']}, 18:22:08 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:22:08 INFO - 'talos_options': ['--xperf_path', 18:22:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:22:08 INFO - 'tests': ['tp5n']}}, 18:22:08 INFO - 'talos.zip': {'path': '', 18:22:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:22:08 INFO - Running post-action listener: _resource_record_post_action 18:22:08 INFO - [mozharness: 2017-05-20 01:22:08.613398Z] Finished populate-webroot step (success) 18:22:08 INFO - [mozharness: 2017-05-20 01:22:08.613542Z] Running create-virtualenv step. 18:22:08 INFO - Running pre-action listener: _resource_record_pre_action 18:22:08 INFO - Running main action method: create_virtualenv 18:22:08 INFO - Creating virtualenv /builds/slave/test/build/venv 18:22:08 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 18:22:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:22:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:22:08 INFO - Using real prefix '/tools/python27' 18:22:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:22:09 INFO - Installing distribute.............................................................................................................................................................................................done. 18:22:12 INFO - Installing pip.................done. 18:22:12 INFO - Return code: 0 18:22:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:22:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:22:12 INFO - Reading from file tmpfile_stdout 18:22:12 INFO - Output received: 18:22:12 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) 18:22:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:22:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102497b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10249b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb773466c10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021a7d30>, '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 0x10215bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10219cbe8>, '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.MKG4TKaMkh/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.zpkjQim7Qk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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 18:22:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:22:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:22:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MKG4TKaMkh/Render', 18:22:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:22:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:22:12 INFO - 'HOME': '/Users/cltbld', 18:22:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:22:12 INFO - 'LOGNAME': 'cltbld', 18:22:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:12 INFO - 'MOZ_NO_REMOTE': '1', 18:22:12 INFO - 'NO_EM_RESTART': '1', 18:22:12 INFO - 'PAGER': '/bin/cat', 18:22:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:22:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:12 INFO - 'PWD': '/builds/slave/test', 18:22:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:22:12 INFO - 'SHELL': '/bin/bash', 18:22:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zpkjQim7Qk/Listeners', 18:22:12 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 18:22:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:22:12 INFO - 'USER': 'cltbld', 18:22:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:22:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:22:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:12 INFO - 'XPC_FLAGS': '0x0', 18:22:12 INFO - 'XPC_SERVICE_NAME': '0', 18:22:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:22:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:12 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 18:22:12 INFO - Cleaning up... 18:22:12 INFO - Return code: 0 18:22:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:22:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x102497b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10249b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb773466c10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021a7d30>, '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 0x10215bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10219cbe8>, '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.MKG4TKaMkh/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.zpkjQim7Qk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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 18:22:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:22:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:22:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MKG4TKaMkh/Render', 18:22:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:22:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:22:12 INFO - 'HOME': '/Users/cltbld', 18:22:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:22:12 INFO - 'LOGNAME': 'cltbld', 18:22:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:12 INFO - 'MOZ_NO_REMOTE': '1', 18:22:12 INFO - 'NO_EM_RESTART': '1', 18:22:12 INFO - 'PAGER': '/bin/cat', 18:22:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:22:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:12 INFO - 'PWD': '/builds/slave/test', 18:22:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:22:12 INFO - 'SHELL': '/bin/bash', 18:22:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zpkjQim7Qk/Listeners', 18:22:12 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 18:22:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:22:12 INFO - 'USER': 'cltbld', 18:22:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:22:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:22:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:12 INFO - 'XPC_FLAGS': '0x0', 18:22:12 INFO - 'XPC_SERVICE_NAME': '0', 18:22:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:22:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:13 INFO - Downloading/unpacking psutil>=3.1.1 18:22:13 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 18:22:13 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 18:22:13 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 18:22:13 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 18:22:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:22:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:22:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:22:15 INFO - Installing collected packages: psutil 18:22:15 INFO - Running setup.py install for psutil 18:22:15 INFO - building 'psutil._psutil_osx' extension 18:22:15 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 18:22:15 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 18:22:15 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 18:22:16 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 18:22:16 INFO - building 'psutil._psutil_posix' extension 18:22:16 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 18:22:16 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:22:16 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:22:16 INFO - ^ 18:22:16 INFO - 1 warning generated. 18:22:16 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 18:22:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:22:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:22:16 INFO - Successfully installed psutil 18:22:16 INFO - Cleaning up... 18:22:16 INFO - Return code: 0 18:22:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:22:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102497b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10249b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb773466c10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021a7d30>, '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 0x10215bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10219cbe8>, '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.MKG4TKaMkh/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.zpkjQim7Qk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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 18:22:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:22:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:22:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MKG4TKaMkh/Render', 18:22:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:22:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:22:17 INFO - 'HOME': '/Users/cltbld', 18:22:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:22:17 INFO - 'LOGNAME': 'cltbld', 18:22:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:17 INFO - 'MOZ_NO_REMOTE': '1', 18:22:17 INFO - 'NO_EM_RESTART': '1', 18:22:17 INFO - 'PAGER': '/bin/cat', 18:22:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:22:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:17 INFO - 'PWD': '/builds/slave/test', 18:22:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:22:17 INFO - 'SHELL': '/bin/bash', 18:22:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zpkjQim7Qk/Listeners', 18:22:17 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 18:22:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:22:17 INFO - 'USER': 'cltbld', 18:22:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:22:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:22:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:17 INFO - 'XPC_FLAGS': '0x0', 18:22:17 INFO - 'XPC_SERVICE_NAME': '0', 18:22:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:22:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:22:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:22:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:22:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:22:19 INFO - Installing collected packages: mozsystemmonitor 18:22:19 INFO - Running setup.py install for mozsystemmonitor 18:22:19 INFO - Successfully installed mozsystemmonitor 18:22:19 INFO - Cleaning up... 18:22:19 INFO - Return code: 0 18:22:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:22:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102497b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10249b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb773466c10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021a7d30>, '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 0x10215bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10219cbe8>, '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.MKG4TKaMkh/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.zpkjQim7Qk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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 18:22:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:22:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:22:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MKG4TKaMkh/Render', 18:22:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:22:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:22:19 INFO - 'HOME': '/Users/cltbld', 18:22:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:22:19 INFO - 'LOGNAME': 'cltbld', 18:22:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:19 INFO - 'MOZ_NO_REMOTE': '1', 18:22:19 INFO - 'NO_EM_RESTART': '1', 18:22:19 INFO - 'PAGER': '/bin/cat', 18:22:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:22:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:19 INFO - 'PWD': '/builds/slave/test', 18:22:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:22:19 INFO - 'SHELL': '/bin/bash', 18:22:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zpkjQim7Qk/Listeners', 18:22:19 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 18:22:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:22:19 INFO - 'USER': 'cltbld', 18:22:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:22:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:22:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:19 INFO - 'XPC_FLAGS': '0x0', 18:22:19 INFO - 'XPC_SERVICE_NAME': '0', 18:22:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:22:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:19 INFO - Downloading/unpacking jsonschema==2.5.1 18:22:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:22:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:22:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:21 INFO - Downloading functools32-3.2.3-2.tar.gz 18:22:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:22:21 INFO - warning: no files found matching '*.txt' 18:22:21 INFO - no previously-included directories found matching 'build' 18:22:21 INFO - no previously-included directories found matching 'dist' 18:22:21 INFO - no previously-included directories found matching '.git*' 18:22:21 INFO - Installing collected packages: jsonschema, functools32 18:22:21 INFO - Running setup.py install for functools32 18:22:21 INFO - warning: no files found matching '*.txt' 18:22:21 INFO - no previously-included directories found matching 'build' 18:22:21 INFO - no previously-included directories found matching 'dist' 18:22:21 INFO - no previously-included directories found matching '.git*' 18:22:21 INFO - Successfully installed jsonschema functools32 18:22:21 INFO - Cleaning up... 18:22:21 INFO - Return code: 0 18:22:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:22:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:21 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 0x102497b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10249b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb773466c10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021a7d30>, '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 0x10215bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10219cbe8>, '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.MKG4TKaMkh/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.zpkjQim7Qk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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 18:22:21 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 18:22:21 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 18:22:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MKG4TKaMkh/Render', 18:22:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:22:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:22:21 INFO - 'HOME': '/Users/cltbld', 18:22:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:22:21 INFO - 'LOGNAME': 'cltbld', 18:22:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:21 INFO - 'MOZ_NO_REMOTE': '1', 18:22:21 INFO - 'NO_EM_RESTART': '1', 18:22:21 INFO - 'PAGER': '/bin/cat', 18:22:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:22:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:21 INFO - 'PWD': '/builds/slave/test', 18:22:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:22:21 INFO - 'SHELL': '/bin/bash', 18:22:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zpkjQim7Qk/Listeners', 18:22:21 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 18:22:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:22:21 INFO - 'USER': 'cltbld', 18:22:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:22:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:22:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:21 INFO - 'XPC_FLAGS': '0x0', 18:22:21 INFO - 'XPC_SERVICE_NAME': '0', 18:22:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:22:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:22:21 INFO - Cleaning up... 18:22:21 INFO - Return code: 0 18:22:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:22:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:21 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 0x102497b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10249b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb773466c10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021a7d30>, '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 0x10215bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10219cbe8>, '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.MKG4TKaMkh/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.zpkjQim7Qk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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 18:22:21 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 18:22:21 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 18:22:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MKG4TKaMkh/Render', 18:22:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:22:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:22:21 INFO - 'HOME': '/Users/cltbld', 18:22:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:22:21 INFO - 'LOGNAME': 'cltbld', 18:22:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:21 INFO - 'MOZ_NO_REMOTE': '1', 18:22:21 INFO - 'NO_EM_RESTART': '1', 18:22:21 INFO - 'PAGER': '/bin/cat', 18:22:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:22:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:21 INFO - 'PWD': '/builds/slave/test', 18:22:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:22:21 INFO - 'SHELL': '/bin/bash', 18:22:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zpkjQim7Qk/Listeners', 18:22:21 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 18:22:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:22:21 INFO - 'USER': 'cltbld', 18:22:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:22:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:22:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:21 INFO - 'XPC_FLAGS': '0x0', 18:22:21 INFO - 'XPC_SERVICE_NAME': '0', 18:22:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:22:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:22 INFO - Downloading/unpacking blobuploader==1.2.4 18:22:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:23 INFO - Downloading blobuploader-1.2.4.tar.gz 18:22:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:22:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:22:23 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 18:22:23 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 18:22:23 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 18:22:23 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 18:22:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:22:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:22:24 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 18:22:24 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 18:22:24 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 18:22:24 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 18:22:24 INFO - Downloading docopt-0.6.1.tar.gz 18:22:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:22:24 INFO - Installing collected packages: blobuploader, requests, docopt 18:22:24 INFO - Running setup.py install for blobuploader 18:22:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:22:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:22:24 INFO - Running setup.py install for requests 18:22:25 INFO - Running setup.py install for docopt 18:22:25 INFO - Successfully installed blobuploader requests docopt 18:22:25 INFO - Cleaning up... 18:22:25 INFO - Return code: 0 18:22:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:22: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')]} 18:22:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22: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')]} 18:22:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:25 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 0x102497b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10249b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb773466c10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021a7d30>, '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 0x10215bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10219cbe8>, '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.MKG4TKaMkh/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.zpkjQim7Qk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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 18:22:25 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 18:22:25 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 18:22:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MKG4TKaMkh/Render', 18:22:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:22:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:22:25 INFO - 'HOME': '/Users/cltbld', 18:22:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:22:25 INFO - 'LOGNAME': 'cltbld', 18:22:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:25 INFO - 'MOZ_NO_REMOTE': '1', 18:22:25 INFO - 'NO_EM_RESTART': '1', 18:22:25 INFO - 'PAGER': '/bin/cat', 18:22:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:22:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:25 INFO - 'PWD': '/builds/slave/test', 18:22:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:22:25 INFO - 'SHELL': '/bin/bash', 18:22:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zpkjQim7Qk/Listeners', 18:22:25 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 18:22:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:22:25 INFO - 'USER': 'cltbld', 18:22:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:22:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:22:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:25 INFO - 'XPC_FLAGS': '0x0', 18:22:25 INFO - 'XPC_SERVICE_NAME': '0', 18:22:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:22:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:22:25 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-Hu2_Do-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:22:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:22:25 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-KUsEad-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:22:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:22:25 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-qwSJYE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:22:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:22:26 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-55l_wZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:22:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:22:26 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-MbdZOv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:22:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:22:26 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-bcTFxC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:22:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:22:26 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-JPNq4V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:22:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:22:26 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-kvwRSS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:22:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:22:26 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-gwnqSg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:22:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:22:26 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-ZcOZ6o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:22:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:22:26 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-DtYJM4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:22:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:22:26 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-rHYzko-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:22:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:22:26 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-CtEYPo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:22:27 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-WfaCIU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:22:27 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-OFwqN8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:22:27 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-on01DJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:22:27 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-ZeTN80-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:22:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:22:27 INFO - Running setup.py install for manifestparser 18:22:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:22:27 INFO - Running setup.py install for mozcrash 18:22:27 INFO - Running setup.py install for mozdebug 18:22:27 INFO - Running setup.py install for mozdevice 18:22:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:22:28 INFO - Running setup.py install for mozfile 18:22:28 INFO - Running setup.py install for mozhttpd 18:22:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:22:28 INFO - Running setup.py install for mozinfo 18:22:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:22:28 INFO - Running setup.py install for mozInstall 18:22:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:22:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:22:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:22:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:22:28 INFO - Running setup.py install for mozleak 18:22:28 INFO - Running setup.py install for mozlog 18:22:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:22:28 INFO - Running setup.py install for moznetwork 18:22:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:22:29 INFO - Running setup.py install for mozprocess 18:22:29 INFO - Running setup.py install for mozprofile 18:22:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:22:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:22:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:22:29 INFO - Running setup.py install for mozrunner 18:22:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:22:29 INFO - Running setup.py install for mozscreenshot 18:22:29 INFO - Running setup.py install for moztest 18:22:29 INFO - Running setup.py install for mozversion 18:22:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:22:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:22:30 INFO - Cleaning up... 18:22:30 INFO - Return code: 0 18:22:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:22:30 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')]} 18:22:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:30 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')]} 18:22:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:30 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 0x102497b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10249b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb773466c10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021a7d30>, '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 0x10215bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10219cbe8>, '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.MKG4TKaMkh/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.zpkjQim7Qk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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 18:22:30 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 18:22:30 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 18:22:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MKG4TKaMkh/Render', 18:22:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:22:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:22:30 INFO - 'HOME': '/Users/cltbld', 18:22:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:22:30 INFO - 'LOGNAME': 'cltbld', 18:22:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:30 INFO - 'MOZ_NO_REMOTE': '1', 18:22:30 INFO - 'NO_EM_RESTART': '1', 18:22:30 INFO - 'PAGER': '/bin/cat', 18:22:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:22:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:30 INFO - 'PWD': '/builds/slave/test', 18:22:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:22:30 INFO - 'SHELL': '/bin/bash', 18:22:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zpkjQim7Qk/Listeners', 18:22:30 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 18:22:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:22:30 INFO - 'USER': 'cltbld', 18:22:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:22:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:22:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:30 INFO - 'XPC_FLAGS': '0x0', 18:22:30 INFO - 'XPC_SERVICE_NAME': '0', 18:22:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:22:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:22:30 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-NFgdvt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:22:30 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)) 18:22:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:22:30 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-JlwchX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:22:30 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)) 18:22:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:22:30 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-E90lfs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:22:30 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)) 18:22:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:22:30 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-toYlJ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:22:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:22:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:22:30 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-bVwvGQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:22:30 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)) 18:22:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:22:30 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-pIO7S9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:22:30 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)) 18:22:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:22:30 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-dEXJum-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:22:31 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)) 18:22:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:22:31 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-oJYg2G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:22:31 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)) 18:22:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:22:31 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-KJAhB1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:22:31 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)) 18:22:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:22:31 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-LRhBQe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:22:31 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)) 18:22:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:22:31 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-EaepFg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:22:31 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)) 18:22:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:22:31 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-dsXUWb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:22:31 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)) 18:22:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:22:31 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-X17HOQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:22:31 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)) 18:22:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:22:31 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-RnotYq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:22:31 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)) 18:22:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:22:31 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-lky6Qm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:22:31 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)) 18:22:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:22:31 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-dGTenn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:22:31 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)) 18:22:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:22:32 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-RrYcF2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:22:32 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)) 18:22:32 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)) 18:22:32 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)) 18:22:32 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)) 18:22:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:22:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:22:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:22: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 18:22: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 18:22: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 18:22: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 18:22:33 INFO - Downloading blessings-1.6.tar.gz 18:22:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:22:33 INFO - Installing collected packages: blessings 18:22:33 INFO - Running setup.py install for blessings 18:22:33 INFO - Successfully installed blessings 18:22:33 INFO - Cleaning up... 18:22:33 INFO - Return code: 0 18:22:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:22:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:22:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:22:34 INFO - Reading from file tmpfile_stdout 18:22:34 INFO - Current package versions: 18:22:34 INFO - blessings == 1.6 18:22:34 INFO - blobuploader == 1.2.4 18:22:34 INFO - docopt == 0.6.1 18:22:34 INFO - functools32 == 3.2.3-2 18:22:34 INFO - jsonschema == 2.5.1 18:22:34 INFO - manifestparser == 1.1 18:22:34 INFO - mozInstall == 1.12 18:22:34 INFO - mozcrash == 1.0 18:22:34 INFO - mozdebug == 0.1 18:22:34 INFO - mozdevice == 0.50 18:22:34 INFO - mozfile == 1.2 18:22:34 INFO - mozhttpd == 0.7 18:22:34 INFO - mozinfo == 0.9 18:22:34 INFO - mozleak == 0.1 18:22:34 INFO - mozlog == 3.4 18:22:34 INFO - moznetwork == 0.27 18:22:34 INFO - mozprocess == 0.25 18:22:34 INFO - mozprofile == 0.28 18:22:34 INFO - mozrunner == 6.13 18:22:34 INFO - mozscreenshot == 0.1 18:22:34 INFO - mozsystemmonitor == 0.3 18:22:34 INFO - moztest == 0.8 18:22:34 INFO - mozversion == 1.4 18:22:34 INFO - psutil == 3.1.1 18:22:34 INFO - requests == 1.2.3 18:22:34 INFO - wsgiref == 0.1.2 18:22:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:22:34 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')]} 18:22:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:34 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')]} 18:22:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:34 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 0x102497b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10249b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb773466c10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021a7d30>, '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 0x10215bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10219cbe8>, '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.MKG4TKaMkh/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.zpkjQim7Qk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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 18:22:34 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 18:22:34 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 18:22:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MKG4TKaMkh/Render', 18:22:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:22:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:22:34 INFO - 'HOME': '/Users/cltbld', 18:22:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:22:34 INFO - 'LOGNAME': 'cltbld', 18:22:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:34 INFO - 'MOZ_NO_REMOTE': '1', 18:22:34 INFO - 'NO_EM_RESTART': '1', 18:22:34 INFO - 'PAGER': '/bin/cat', 18:22:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:22:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:34 INFO - 'PWD': '/builds/slave/test', 18:22:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:22:34 INFO - 'SHELL': '/bin/bash', 18:22:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zpkjQim7Qk/Listeners', 18:22:34 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 18:22:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:22:34 INFO - 'USER': 'cltbld', 18:22:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:22:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:22:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:34 INFO - 'XPC_FLAGS': '0x0', 18:22:34 INFO - 'XPC_SERVICE_NAME': '0', 18:22:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:22:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:34 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)) 18:22:34 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)) 18:22:34 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)) 18:22:34 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)) 18:22:34 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)) 18:22:34 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)) 18:22:34 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)) 18:22:34 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)) 18:22:34 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)) 18:22:34 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)) 18:22:34 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)) 18:22:34 INFO - Cleaning up... 18:22:34 INFO - Return code: 0 18:22:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:22:34 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')]} 18:22:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:34 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')]} 18:22:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:34 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 0x102497b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10249b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb773466c10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021a7d30>, '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 0x10215bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10219cbe8>, '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.MKG4TKaMkh/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.zpkjQim7Qk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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 18:22:34 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 18:22:34 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 18:22:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MKG4TKaMkh/Render', 18:22:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:22:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:22:34 INFO - 'HOME': '/Users/cltbld', 18:22:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:22:34 INFO - 'LOGNAME': 'cltbld', 18:22:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:34 INFO - 'MOZ_NO_REMOTE': '1', 18:22:34 INFO - 'NO_EM_RESTART': '1', 18:22:34 INFO - 'PAGER': '/bin/cat', 18:22:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:22:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:34 INFO - 'PWD': '/builds/slave/test', 18:22:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:22:34 INFO - 'SHELL': '/bin/bash', 18:22:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zpkjQim7Qk/Listeners', 18:22:34 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 18:22:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:22:34 INFO - 'USER': 'cltbld', 18:22:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:22:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:22:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:34 INFO - 'XPC_FLAGS': '0x0', 18:22:34 INFO - 'XPC_SERVICE_NAME': '0', 18:22:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:22:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:22:34 INFO - Cleaning up... 18:22:34 INFO - Return code: 0 18:22:34 INFO - Running post-action listener: _resource_record_post_action 18:22:34 INFO - Running post-action listener: _start_resource_monitoring 18:22:34 INFO - Starting resource monitoring. 18:22:34 INFO - [mozharness: 2017-05-20 01:22:34.740625Z] Finished create-virtualenv step (success) 18:22:34 INFO - [mozharness: 2017-05-20 01:22:34.741566Z] Running install step. 18:22:34 INFO - Running pre-action listener: _resource_record_pre_action 18:22:34 INFO - Running main action method: install 18:22:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:22:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:22:34 INFO - Reading from file tmpfile_stdout 18:22:35 INFO - Detecting whether we're running mozinstall >=1.0... 18:22:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:22:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:22:35 INFO - Reading from file tmpfile_stdout 18:22:35 INFO - Output received: 18:22:35 INFO - Usage: mozinstall [options] installer 18:22:35 INFO - Options: 18:22:35 INFO - -h, --help show this help message and exit 18:22:35 INFO - -d DEST, --destination=DEST 18:22:35 INFO - Directory to install application into. [default: 18:22:35 INFO - "/builds/slave/test"] 18:22:35 INFO - --app=APP Application being installed. [default: firefox] 18:22:35 INFO - mkdir: /builds/slave/test/build/application 18:22:35 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'] 18:22:35 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 18:22:56 INFO - Reading from file tmpfile_stdout 18:22:56 INFO - Output received: 18:22:56 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:22:56 INFO - Running post-action listener: _resource_record_post_action 18:22:56 INFO - [mozharness: 2017-05-20 01:22:56.898783Z] Finished install step (success) 18:22:56 INFO - [mozharness: 2017-05-20 01:22:56.898948Z] Running run-tests step. 18:22:56 INFO - Running pre-action listener: _resource_record_pre_action 18:22:56 INFO - Running main action method: run_tests 18:22:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:22:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:22:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:22:56 INFO - 2017-05-19 18:22:56.913 screenresolution[1620:7303] starting screenresolution argv=screenresolution get 18:22:56 INFO - 2017-05-19 18:22:56.916 screenresolution[1620:7303] Display 0: 1600x1200x32@60 18:22:56 INFO - 2017-05-19 18:22:56.922 screenresolution[1621:7305] starting screenresolution argv=screenresolution list 18:22:56 INFO - Available Modes on Display 0 18:22:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:22:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:22:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:22:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:22:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:22:57 INFO - Intel Iris: 18:22:57 INFO - Chipset Model: Intel Iris 18:22:57 INFO - Type: GPU 18:22:57 INFO - Bus: Built-In 18:22:57 INFO - VRAM (Dynamic, Max): 1536 MB 18:22:57 INFO - Vendor: Intel (0x8086) 18:22:57 INFO - Device ID: 0x0a2e 18:22:57 INFO - Revision ID: 0x0009 18:22:57 INFO - Displays: 18:22:57 INFO - Display: 18:22:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:22:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:22:57 INFO - Display Serial Number: 1600x1200 60 18:22:57 INFO - Main Display: Yes 18:22:57 INFO - Mirror: Off 18:22:57 INFO - Online: Yes 18:22:57 INFO - Rotation: Supported 18:22:57 INFO - Return code: 0 18:22:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:22:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:22:57 INFO - Python 2.7.3 18:22:57 INFO - Return code: 0 18:22:57 INFO - grabbing minidump binary from tooltool 18:22:57 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')]} 18:22:57 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 0x1021a7d30>, '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 0x10215bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10219cbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:22:57 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 18:22:57 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 18:22:57 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 18:22:57 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:22:57 INFO - Return code: 0 18:22:57 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:22:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:22:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:22:57 INFO - ENV: RUST_BACKTRACE is now 1 18:22:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:22:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:22:57 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/GCKSwIhdSfe5r7nzaY-zGg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0196', '--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 18:22:57 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/GCKSwIhdSfe5r7nzaY-zGg/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0196 --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 18:22:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MKG4TKaMkh/Render', 18:22:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:22:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:22:57 INFO - 'HOME': '/Users/cltbld', 18:22:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:22:57 INFO - 'LOGNAME': 'cltbld', 18:22:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:22:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:22:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:57 INFO - 'MOZ_NO_REMOTE': '1', 18:22:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:22:57 INFO - 'NO_EM_RESTART': '1', 18:22:57 INFO - 'PAGER': '/bin/cat', 18:22:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:22:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:57 INFO - 'PWD': '/builds/slave/test', 18:22:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:22:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:22:57 INFO - 'RUST_BACKTRACE': '1', 18:22:57 INFO - 'SHELL': '/bin/bash', 18:22:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zpkjQim7Qk/Listeners', 18:22:57 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 18:22:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:22:57 INFO - 'USER': 'cltbld', 18:22:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:22:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:22:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:57 INFO - 'XPC_FLAGS': '0x0', 18:22:57 INFO - 'XPC_SERVICE_NAME': '0', 18:22:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:22:57 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/GCKSwIhdSfe5r7nzaY-zGg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0196', '--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 18:22:57 INFO - mozversion application_buildid: 20170519152243 18:22:57 INFO - mozversion application_changeset: 8d60d0f825110cfb646ac31dc16dc011708bcf34 18:22:57 INFO - mozversion application_display_name: Nightly 18:22:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:22:57 INFO - mozversion application_name: Firefox 18:22:57 INFO - mozversion application_remotingname: firefox 18:22:57 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:22:57 INFO - mozversion application_vendor: Mozilla 18:22:57 INFO - mozversion application_version: 55.0a1 18:22:57 INFO - mozversion platform_buildid: 20170519152243 18:22:57 INFO - mozversion platform_changeset: 8d60d0f825110cfb646ac31dc16dc011708bcf34 18:22:57 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:22:57 INFO - mozversion platform_version: 55.0a1 18:22:57 INFO - using testdate: 1495243377 18:22:57 INFO - actual date: 1495243377 18:22:57 INFO - starting webserver on 'localhost:49226' 18:22:57 INFO - SUITE-START | Running 2 tests 18:22:57 INFO - TEST-START | dromaeo_css 18:22:57 INFO - operating with platform_type : mac_ 18:22:57 INFO - Initialising browser for dromaeo_css test... 18:22:57 INFO - TEST-INFO | started process 1631 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/tmpazfDkC/profile http://localhost:49226/getInfo.html) 18:22:58 INFO - PID 1631 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2963: String.localeCompare is deprecated; use String.prototype.localeCompare instead 18:23:00 INFO - PID 1631 | 2017-05-19 18:23:00.117 plugin-container[1633:7480] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b3f, name = 'com.apple.tsm.portname' 18:23:00 INFO - PID 1631 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:23:00 INFO - PID 1631 | 2017-05-19 18:23:00.120 plugin-container[1633:7480] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0xa10b, name = 'com.apple.CFPasteboardClient' 18:23:00 INFO - PID 1631 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:23:00 INFO - PID 1631 | 2017-05-19 18:23:00.120 plugin-container[1633:7480] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:23:00 INFO - PID 1631 | __metrics Screen width/height:1600/1200 18:23:00 INFO - PID 1631 | colorDepth:24 18:23:00 INFO - PID 1631 | Browser inner width/height: 1024/691 18:23:00 INFO - PID 1631 | __metrics 18:23:00 INFO - PID 1631 | 2017-05-19 18:23:00.617 plugin-container[1634:7533] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 18:23:00 INFO - PID 1631 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:23:00 INFO - PID 1631 | 2017-05-19 18:23:00.618 plugin-container[1634:7533] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.CFPasteboardClient' 18:23:00 INFO - PID 1631 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:23:00 INFO - PID 1631 | 2017-05-19 18:23:00.618 plugin-container[1634:7533] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:23:01 INFO - TEST-INFO | 1631: exit 0 18:23:01 INFO - Browser initialized. 18:23:01 INFO - Running cycle 1/1 for dromaeo_css test... 18:23:01 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/tmpazfDkC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:23:03 INFO - PID 1635 | 2017-05-19 18:23:03.121 plugin-container[1636:7635] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.tsm.portname' 18:23:03 INFO - PID 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:23:03 INFO - PID 1635 | 2017-05-19 18:23:03.122 plugin-container[1636:7635] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4823, name = 'com.apple.CFPasteboardClient' 18:23:03 INFO - PID 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:23:03 INFO - PID 1635 | 2017-05-19 18:23:03.122 plugin-container[1636:7635] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:23:03 INFO - PID 1635 | 2017-05-19 18:23:03.821 plugin-container[1637:7678] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4643, name = 'com.apple.tsm.portname' 18:23:03 INFO - PID 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:23:03 INFO - PID 1635 | 2017-05-19 18:23:03.822 plugin-container[1637:7678] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x971b, name = 'com.apple.CFPasteboardClient' 18:23:03 INFO - PID 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:23:03 INFO - PID 1635 | 2017-05-19 18:23:03.822 plugin-container[1637:7678] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:23:13 INFO - PID 1635 | RSS: Main: 239882240 18:23:13 INFO - PID 1635 | 18:26:17 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49226/tests/dromaeo/cssquery-ext.html) 18:26:18 INFO - PID 1635 | RSS: Main: 258572288 18:26:18 INFO - PID 1635 | 18:29:20 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-ext.html (next: http://localhost:49226/tests/dromaeo/cssquery-jquery.html) 18:29:20 INFO - PID 1635 | RSS: Main: 266805248 18:29:20 INFO - PID 1635 | 18:32:22 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49226/tests/dromaeo/cssquery-mootools.html) 18:32:23 INFO - PID 1635 | RSS: Main: 270397440 18:32:23 INFO - PID 1635 | 18:35:24 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49226/tests/dromaeo/cssquery-prototype.html) 18:35:25 INFO - PID 1635 | RSS: Main: 268271616 18:35:25 INFO - PID 1635 | 18:38:28 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49226/tests/dromaeo/cssquery-yui.html) 18:38:28 INFO - PID 1635 | RSS: Main: 269742080 18:38:28 INFO - PID 1635 | 18:41:30 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-yui.html (next: http://localhost:49226/tests/dromaeo/cssquery-dojo.html) 18:41:30 INFO - PID 1635 | RSS: Main: 271052800 18:41:30 INFO - PID 1635 | 18:41:30 INFO - PID 1635 | __start_tp_report 18:41:30 INFO - PID 1635 | _x_x_mozilla_page_load 18:41:30 INFO - PID 1635 | _x_x_mozilla_page_load_details 18:41:30 INFO - PID 1635 | |i|pagename|runs| 18:41:30 INFO - PID 1635 | |0;dojo.html;3537.2218112015357;3581.856725730971;3593.4609808528703;3609.855605775769;3618.077390265482;4997.500249975001;6154.476869466101;6180.13478113064;6210.19267495225;6236.0645903114555;6120.9347721082095;6142.805741014681;6180.598261113025;6219.937800621992;6304.117423560705;5338.145896656536;5387.488188622075;5394.352677678684;5417.377001644822;5474.91787623186;4436.4676238851225;4981;5453.890922181554;5534.197541356501;5592.133219351007;5285.418603953562;5335.653182543122;5416.756245968941;5445.319335083114;5490.972545137269;4517.254652982254;5360.758765855507;5383.923215356945;5465.562754979592;5484.122540393558;5374.677519348813;5421.268128802601;5421.972890135564;5424.864378390533;5456.399796022434;5984.581079324449;6232.844178895519;6234.0960560718895;6258.4680302174365;6259.530535209832;6427.8393040173905;6473.708683109271;6585.868282634368;6595.901061484082;6608.074869518271;6682.164729408824;6687.765928192539;6691.531592788507;6711.127553418024;6715.429188518981;6274.874502509924;6295.779647712354;6296.527760417987;6305.527085468517;6328.335524769925;5639.492445679908;6575.506836987245;6625.138731964814;6750.3924646782025;6803.931960680429;5644.294463192101;6478.611283323015;6478.773242936474;6486.740530378826;6531.510136739668;6482.092507049017;6550.639714815636;6617.338266173344;6711.496637752188;6729.932700673028;6515.250746164252;6586.835329116715;6589.7364105435245;6597.076409302702;6616.8345791355605;5244.6328756987405;6636.2368327642935;6636.5354425190735;6643.368879956396;6649.567778094505;5251.422343542207;5355.678659280456;5362.892742145135;5367;5426.62013659048;4707.929381059287;4755.809767609257;4806.327114204014;4819.734914579662;4830.541098595628;6305.432511073934;6308.873822523524;6312.905306420407;6323.20959880015;6327.240731112296;6657.101291325609;6659.367360100783;6744.629045402452;6770.8645827084165;6803.557768745016;5242.292290540729;6187.041008643668;6215.036669316263;6218.378162183745;6247.843803904848;5958.553108516879;5980.73086711099;5984.611000284967;5996.2504686914135;6032.728527216286;6866;6909.170899491993;6921.411680007256;6928.307169283032;6949.270326615632;6489.351064893662;6583.572067815577;6639.634820084749;6762.154730658668;6790.117284753148;6860.313968603299;6883.827904302432;6889.965550172217;6924.480663950325;6931.133608298963;6654.800355989328;6662.866742665216;6665.966670166812;6671.8999215010845;6689.297623749436;6475.838104047437;6715.731370745116;6731.360520750521;6788.76239331621;6800.421964132906;6400.135981642418;6450.0969864218105;6476.352364763486;6499.252585952676;6550.377714117151;5324.494173053554;6570.11303474044;6574.901376479453;6575.50683698715;6624.56940298879;6132.386102851662;6168.444839964424;6283.057541368831;6366.554341196072;6390.808275751735;6367.9044814328745;6378.266499352634;6383.9361606383345;6389.041643753474;6413.230412350548;5579.776808927598;6098.0852872069545;6276.780312689034;6300.086487459201;6327.462165715781 18:41:30 INFO - PID 1635 | |1;ext.html;759.8632246195687;789.238384958515;806.4516129032257;1034.648219605335;1210.6126039667304;38373.61626383735;39313.62402315916;39384.606153938454;39446.40830387543;39524;40100.79949600248;40399.59600403995;40468.98827529306;40473.78578642638;40492.59507404925;7784.377249820015;8144.4706094103985;8274;8288.046874609438;8296.58517074147;8351.95824020879;8436.873446898302;8449.619767110495;8456.957715211416;8462.111478294783;7036.683349249296;7553.169151393342;8315.958420207891;8413.495190288602;8482.066972633005;8407.70573029944;8453.365997550209;8454.61954212062;8454.830903381959;8461.66153353865;8142.877856832152;8216.507009579444;8220.136885627042;8242;8245.95877020617;36449;36728.449073263924;36730.63269367299;36737.81631091854;36742.26515469702;3678.282734866702;3706.3328600851837;3718.721095917817;3724;3731.458938453909;23582.93876775549;30016.54975175374;30303.393932121453;30414;30449.695503045128;2999;3033.514637657964;3043.634763828333;3048.253177971389;3049.8170109793264;238693;239730.8013459939;239790;285107;285782;45558.08883822305;45588;45936.5406345939;46017.539824602;46120.769396152806;48441.75779121082;48488;48551.27173092406;48620.27069593959;48668.02663946701;13874.791878121836;13883.236421996888;13966.650833729238;14002.36989335462;14074;11357.886421135683;11389;11395.316281023164;11412.258203216765;11428.0286175676;13095.083344165816;13337.799933001013;13347.399367028507;13369.131006484547;13437;6023.578349515579;6026;6061.787837425669;6064.241969753781;6121.693915304306;999.5801763259303;1022.601185537641;1028.8990779865621;1055.350959160111;1055.5461151704687;17294.578758973752;20558;21095;24346.63480047801;24717.752822471906;936.3679516326589;1069.540097757956;1090.2477290669412;1106.269861891148;1139.4530625299903;10582.841257381146;11719.941400292944;12132.090093242865;12382.81425778614;12391.75216495665;315805;315840;315963;316282;318138.409307952;41473.79263103665;52411.21383179256;52569.737151313995;52588.21117683238;52690;1499.8800095992294;1505.4580351073605;1541.5760665816765;1543.845615438447;1553.3475940105184;17345.21946512435;22666.66000009968;23797.762022380248;23825.761742382354;23968.640470393308;37838.810805946894;38080.42879356757;38117.618823811405;38157.61842381541;38220.80889595534;212726;276673;277513;277632;278241.21758782153;33610.99167024993;34925.30149397049;35516.467252991744;36167.638323616426;36519;43313.35029974616;44324.22703091969;44633.33050004317;45312.773436133924;45431.54568454273;30342;30547.389052218386;31010.224744381572;31333.843330783202;31583.684163158076;24834.379140521633;30497.695023049484;31510.52734208943;31552.368952621277;31669.841650791597;35060.649393506756;37047;37061.44407833939;37066.07334816651;37176.81411593033;29398.55902161512;29684.257893552833;31532.52701209438;31577.69563881745;31697.25665088442;21372.931353431955;23259;24908.750912490643;25080.49839003173;25335 18:41:30 INFO - PID 1635 | |2;jquery.html;407.20188430675876;423.73301842792074;435.6318910520611;446.5534465534465;451.46952331011073;19235.711464328047;20840;21507.81707006114;21665.241716539924;21711.3486595402;18845.24619015238;20894.89552552239;22342.88828555859;23066.88466557669;23068.846557672132;5982.222311099551;6173.475254603364;6225.097360882669;6226.875462490748;6245.56281060326;4551.476580193287;5764.423557644244;6108.877822443548;6118.1434599156155;6134.754609815602;6012.158297838306;6078.392160783908;6088.238970128734;6097.420745029215;6108.328083910788;5066.74666266687;5382.761964748111;5693.4875861172695;6025.3731492497;6063.636181829104;4929.482404347527;5366.651167674088;5374.220737992969;5396.406395296514;5404.6486978346675;27422;27688.169354919384;27837.721622783716;28280.717192828015;28372.432551349062;2151.1610471915965;2224.922127725522;2225.299030805307;2228.253535065755;2233.084435381502;16097.678046439123;17738.822611773845;18251.361202357853;18293.268269269214;18329.450116496526;2160.5894879972757;2164.8592841465256;2165.967510487344;2174.402039439151;2181.83636227282;148957;149359.50640493564;150614.7407788873;151723;155934;12249.816252756038;12767.361631918367;12829.93585032069;12906.290154041619;12952.935235323952;16204.83795162057;16529.66940661204;16581.922175058582;16598.502044938672;16628.83371166273;8852.557372131369;8983.191512763751;9115.088491150831;9160.908390916138;9302.53487325631;7198.352148306678;8655.523946182895;8664.95667521671;8678.95660521706;8843.955780221058;8013.719519816778;8015.919840801634;8171.264586187272;8186.672533098609;8201.30288925436;2175.271284119806;2175.912963481443;2178.455386153462;2180.6401943679116;2322.8606283623035;944.3012170993416;953.4327075390132;957.5212393803098;974.965876194344;991.9256055795844;16338.673226535402;19879.30422435208;20609.969501524865;20889.059992300383;21182.894085529475;990.1089019882164;1014.898510148994;1017.3133135133755;1022.754538910656;1023.1967905194387;9077.45535267886;9580.754501914846;10814.729631759112;10860.565577376816;10913.963173498505;495460;522231;523651;533612;537107;109649.67977721983;116810;125712.1143182853;129371;136080;1189.316143217636;1211.8848709372419;1214.732758793064;1230.6308107567693;1233.543588872123;21606;22661.773382265965;22689.63862168209;22840.428989275402;22897.969591368095;54448.63878402913;57844.71077644753;58237;58469.122963156435;59523.70238148781;81686.18313817024;82427.17572824196;83704;83797;84253;8454.070052294366;8638.56807159627;8818.250448711675;8867.866981995146;9042.864357034781;12689;13729.308666017241;13779.173249605055;13915;13956.302184890917;11598.304101753922;11915.702107447383;12033.278003319829;12044.75910481768;12075.637730868088;9567.473788941676;9668.371555848847;10515;10736.785264294827;11096.556137754722;11700.239484433485;11874.76250475003;11989.460474278503;12063.276203427822;12079.57721479744;7049.7885063448175;7845.921540784748;8463.619137138967;8688.348373872112;8843;11280;11743.295402275891;11906.583269585522;11996;12090.697732556757 18:41:30 INFO - PID 1635 | |3;mootools.html;1410.8095407120038;1455.8689717925397;1467.2736995187377;1483.184248663235;1496.7006598680264;10908.945455272722;11204.551817927273;11212.775744485105;11252.943735281322;11627.360495172763;11245.269057511256;11416.828747568772;11416.942915285432;11418.828717569222;11438.428078596078;2493.2644869763417;2535.556277651413;2550.9489810203786;2551.285640020797;2553.5786595211766;2480.1443501991785;2528.115159694111;2534.556452620793;2539.6190571414345;2539.8222124451295;2476.1704828882257;2501.4246723253573;2507.912223072193;2527.4692314613953;2533.949321013579;2455.6316552517087;2477.0587176872855;2499.275210189043;2505.5615267328144;2507.9749202507924;2477.1206221791185;2477.6407420924047;2493.4015836199183;2494.4138127540014;2506.8495890246645;11070.501827417786;11127.054200392977;11131.220814542905;11140.053095486894;11142.387168705718;5228.79084836606;6446.709898054552;6448.484121270287;6449.12936753537;6480.02799580062;3798.2593394288124;4091.243120022805;4142.2543942090415;4157.168566286755;4166.395872598457;1113.3097479562641;1150.9136814738845;1187.1511869013696;1207.4445754952733;1217.0324591949422;12770.744585108247;14030.228337441436;14339.067960582528;15246;15384.769228461582;5092.694438333638;5169.612279079009;5326.573874090141;5330.626856120112;5342.412334643186;11130.220884538005;11165.16261280391;11225.550977960791;11225.943870280596;11291.943540282246;6178.783742568989;6256.7810126645345;6266.624002559861;6269.7492100316385;6274.090256912722;5811.82564523065;5907.911381329283;5958.910616340759;5974.641521508724;6020.939790602126;3734.215814678948;4206.843118142535;4285.807138678767;4315.266404711207;4387.341898715218;1253.921627400435;1254.523281153156;1255.0712472770085;1267.0180610027119;1269.0545543569915;783.9216078392116;794.4875555266821;803.8553060449175;805.3476683886071;822.1326500541985;6076.422739839708;6139.4781443577795;6275.937240627535;6598.6472773081405;6979.092717946634;761.8438220164963;933.7385532051037;941.3786900645678;946.2288235088381;950.4534892436876;3427.588689357293;4313.762743049162;4357.542458041923;4378.255696531597;4477.888052798706;13389.933050334881;13491.325433728274;13701.725965480635;13813.861861381458;13941.721165576631;13858.307084645727;13863.376148073361;13870.722585548434;13877.306134693225;13913.791293130611;8125.75622731319;8206.548639824867;8330.833383332421;8345.123762004901;8364.456310339809;5796.420357964338;6190.690465476799;6632.137822083098;6939.132608423947;6957.095577574883;12041.638750837488;12419.441125149571;12558.554982135696;12648.43082061328;12674;12519.374031298217;12657.240565566095;12692.36538173106;12733.108682392143;12982.935085324512;8677;8883.200511953955;8921.598528066383;8973.775655608662;9052.275817934418;8422.6209820557;9928.851067234142;10177.338472999232;10190.490475476345;10193;8448.502469332425;8679.696210632597;8825.602847871733;8851.69019084329;8889.4666320021;8538.316934645089;8615.483071015758;8658.826823463369;8674.262687671619;8702.82594348122;7755.961220193863;7864.410169237444;7929.841403172019;7996.280334769898;7997.7600671979935;5886.146508756121;5911.733971971185;5945.732442040032;6146.846328841814;6149;5460.453954604666;5606.579506537108;5981.790637327673;6101.115338276014;6142.201513803177 18:41:30 INFO - PID 1635 | |4;prototype.html;367.72971865678727;378.71217874415436;383.07869573675305;386.6809881847473;390.122224993764;5318.213607753024;6255.906161407577;6301.2438507379175;6323.525735569828;6339.746410143594;6039.758409663608;6122.877542449149;6152.446279834814;6159.722812473438;6175.07373893915;4629.768511574424;4645.256758918574;4654.9767251163785;4664.836730714425;4677.555632214951;4667.649926255527;4670.719756814585;4679.181143299926;4689.765511724418;4694.3662605548325;4579.3131030345385;4618.053299073682;4623.8844028899375;4647.512011238822;4655.255159174532;4476.81364438424;4611.371518234608;4622.445306563201;4636.304554316879;4636.860894173164;3879.515060617423;4098.364753463218;4101.892489027946;4118.91762164755;4125.66994640428;4743.715377077387;4938.580220681247;5282.735863206824;5978.252718410199;6037.9396206038255;4056.2901492239034;4796.736179510125;5085.084684756743;5274.393444753826;5501.587380946445;5562.666240025612;6423.678816059225;6552.803415897531;6559.8192325381415;6690;3935.409688546741;4626.028534007829;4656.441227052742;4689.320048592935;4706.7646617669325;5714.857128571777;6894.724211031603;6906.205786334585;7024.719011239545;7121.430285577141;4081.346984482528;4385.934210986838;4408.206522825923;4449.7997590108525;4488.259437192892;3882.475865758143;4373.840932152982;4512.187806194851;4520.960179158812;4525.230710779176;5251.868703282372;5395.78416863321;5407.297051383373;5411.134218525017;5446.4477623877;6490.967545162244;6717.227518835396;6731.663416829237;6733.956236783209;6766.864662706718;1381.854905234956;1498.1535432480614;1502.676924461246;1506.3070987345943;1508.9698206035819;958;988.8763904511936;998.8152191844387;1000.5447521377756;1009.914157296638;5324.733763311819;6111.097228816277;6266.216722909637;6339.7781077662985;6356.650384228912;6277.027060805583;6733.989901514714;6931.445484361239;6996.335348141918;7016.122984626922;5075.847724568269;5304.602154838402;6156.938430615636;6296.79612135758;6335.144755458046;3409.6931276184764;3550.4555518349603;3599.208174201671;3634.2186429917697;3667.2848794485353;6239.469645080128;6602.570832895846;7160.785176444715;7212.4590655699985;7324.059124332056;6573.901391479036;6646.634435106019;7225.494215404976;7238.059052323164;7245.7826265212125;5899.46904778572;6612.768553100618;6627.569208001464;7184.892226616605;7257.818554536178;6998.62046904055;7006.229314775473;7017.7192912282935;7040.471964602573;7041.753538626123;5852.912206316823;6171.1360409543595;6271.717772700148;6701.698423571049;6798.354156355138;6354.650494222862;6583.913654246996;6897.48268879826;7083.539569927938;7098.858022839618;4100.6262901928185;4395.538468460765;4971.726555039508;4988.575971042357;4994.225894986283;4818.95181048185;6761.526693131434;6820.874555698254;6978.197507286524;7133.678984445609;6038.215032045806;6472.708728107153;6851.109355783916;6851.862962740817;6900.447964162755;5944.554158438221;6587.934120658923;6687.565308254948;6832.111825462658;6875.278095799869;6016.488598469179;6668.366505182;6683.498737594603;6714.160729908762;6734.6632668367365;6624.541072454318;6681.198256209286;6839.879637312449;6844.93155068443;6855.143107111611;5461.945380546303;5571.60998730085;6602.438792702674;6641.833954151185;6687.531872768859 18:41:30 INFO - PID 1635 | |5;yui.html;697.4281089506605;709.7338498063226;811.431997601679;834.0450184538707;841.7558907916705;12903.806442903353;16610.667786644273;16720.665586688257;18208.271669133253;19307.517312067204;18066.458006259832;18360.265589376442;18367.816321836777;18518.814811851877;18642.627147457042;818.7380038387719;846.6740304982565;973.255459573427;991.7619771254919;995.4922989275485;946.1437399153776;984.0602224875227;1006.9043440873105;1008.9344192627492;1009.470028235175;1051.9737006574821;1058.5236643510414;1059.6185373265619;1059.89930956559;1065.7921705267438;1056.2764506313124;1059.7403636109163;1061.3950048472375;1061.8938106189396;1063.6489958313778;764.7896828372186;767.098659075584;776.2858170483169;778.528989961071;781.3007358414244;16679.583010424714;16852.325906963706;17107.657846843118;17300.39448619305;17471.825281747148;17024.574385640335;17095;17211.05339206343;17215.053172075655;17376.957382557088;14157.787633185511;14256.002079854414;14338.283085845665;14516.346764395628;14539.273036348246;3522.5068490411363;3556.78659280444;3588.3899737044767;3594.4608308753636;3598.118460977051;92310;121736;121981.39009304896;123829;129563;13245.668858278621;13303.667408314675;13389.129706569041;13410.396532156077;13421.731565368638;24914.875425622755;25602.61596076023;25632.743672563407;25767.226983190536;25836.899572051476;7450.552966822008;7465.104187497535;7534.886976695354;7542.396608271325;7564.773056808304;3461.5153878457036;3465.1510379956567;3468.826558672107;3471.0628130405153;3527.030066731669;4185.309423945015;4240.6395456385935;4271.124419493996;4274.4870615525715;4291.120320334324;817.6974519427731;847.859628799264;875.1729615513278;938.3619138985555;965.16513216069;615.0497481391261;635.3995474276765;646.6314200905533;646.7574659502686;674.835908058595;11047.447627618747;11753.26769017099;11854.348010859485;11967.341796201292;12005.09961752872;624.4130517313802;625.6902833097646;626.395528315178;635.5646382228189;653.7515744017243;4611.077784443125;5041.117804384291;5351.170568561879;5432.402435732066;5556.972215138899;71901;104530.14525005838;104709.42935855969;105899;106492;92315.53842230747;92328.53835730779;92509.53745231366;92722;93927.12145757047;81391;83250.16749832545;83685;84559;84936.99999999876;7808.921910780819;10993.505292261707;11121.944390277997;11133.777324453304;11262;40960.1807963845;41491.58508414877;41675.37486937638;41779.58220417878;41828.790856045525;53035.40893773193;53081.46918530765;53191;53458.73270633622;53940.46059539511;12680.175788573713;13803.723925521634;14070.788938165731;14355.784663229852;14501.129932204101;15225.086494810346;15510.612234694223;15599.376024958874;15633.452829150925;15785.68428631444;14018.509352172625;14330.068545544505;14473.85526144725;14560.563183104523;14652.853471465576;13023.283719395524;13340.866591333963;13513.256770877697;13577.59267221985;13611.11527750693;12004.699882502659;12851.550195743104;13483.797743033667;13736.65658358549;13929.791053134008;10654.467276636293;10701.983311585387;10718.571257149939;10839.837402439127;10867.021968022917;11061.502232399722;11623.6512904613;11751.47118379692;11833.230839995373;11957.162998590014 18:41:30 INFO - PID 1635 | __end_tp_report 18:41:30 INFO - PID 1635 | __start_cc_report 18:41:30 INFO - PID 1635 | _x_x_mozilla_cycle_collect,335 18:41:30 INFO - PID 1635 | __end_cc_report 18:41:30 INFO - PID 1635 | __startTimestamp1495244490745__endTimestamp 18:41:30 INFO - PID 1635 | 18:41:30 INFO - PID 1635 | ------- Summary: start ------- 18:41:30 INFO - PID 1635 | Number of tests: 6 18:41:30 INFO - PID 1635 | 18:41:30 INFO - PID 1635 | [#0] dojo.html Cycles:165 Average:6093.95 Median:6312.91 stddev:736.31 (11.7%) stddev-sans-first:710.73 18:41:30 INFO - PID 1635 | Values: 3537.2 3581.9 3593.5 3609.9 3618.1 4997.5 6154.5 6180.1 6210.2 6236.1 6120.9 6142.8 6180.6 6219.9 6304.1 5338.1 5387.5 5394.4 5417.4 5474.9 4436.5 4981.0 5453.9 5534.2 5592.1 5285.4 5335.7 5416.8 5445.3 5491.0 4517.3 5360.8 5383.9 5465.6 5484.1 5374.7 5421.3 5422.0 5424.9 5456.4 5984.6 6232.8 6234.1 6258.5 6259.5 6427.8 6473.7 6585.9 6595.9 6608.1 6682.2 6687.8 6691.5 6711.1 6715.4 6274.9 6295.8 6296.5 6305.5 6328.3 5639.5 6575.5 6625.1 6750.4 6803.9 5644.3 6478.6 6478.8 6486.7 6531.5 6482.1 6550.6 6617.3 6711.5 6729.9 6515.3 6586.8 6589.7 6597.1 6616.8 5244.6 6636.2 6636.5 6643.4 6649.6 5251.4 5355.7 5362.9 5367.0 5426.6 4707.9 4755.8 4806.3 4819.7 4830.5 6305.4 6308.9 6312.9 6323.2 6327.2 6657.1 6659.4 6744.6 6770.9 6803.6 5242.3 6187.0 6215.0 6218.4 6247.8 5958.6 5980.7 5984.6 5996.3 6032.7 6866.0 6909.2 6921.4 6928.3 6949.3 6489.4 6583.6 6639.6 6762.2 6790.1 6860.3 6883.8 6890.0 6924.5 6931.1 6654.8 6662.9 6666.0 6671.9 6689.3 6475.8 6715.7 6731.4 6788.8 6800.4 6400.1 6450.1 6476.4 6499.3 6550.4 5324.5 6570.1 6574.9 6575.5 6624.6 6132.4 6168.4 6283.1 6366.6 6390.8 6367.9 6378.3 6383.9 6389.0 6413.2 5579.8 6098.1 6276.8 6300.1 6327.5 18:41:30 INFO - PID 1635 | 18:41:30 INFO - PID 1635 | [#1] ext.html Cycles:180 Average:43117.24 Median:34268.15 stddev:73738.62 (215.2%) stddev-sans-first:73876.90 18:41:30 INFO - PID 1635 | Values: 759.9 789.2 806.5 1034.6 1210.6 38373.6 39313.6 39384.6 39446.4 39524.0 40100.8 40399.6 40469.0 40473.8 40492.6 7784.4 8144.5 8274.0 8288.0 8296.6 8352.0 8436.9 8449.6 8457.0 8462.1 7036.7 7553.2 8316.0 8413.5 8482.1 8407.7 8453.4 8454.6 8454.8 8461.7 8142.9 8216.5 8220.1 8242.0 8246.0 36449.0 36728.4 36730.6 36737.8 36742.3 3678.3 3706.3 3718.7 3724.0 3731.5 23582.9 30016.5 30303.4 30414.0 30449.7 2999.0 3033.5 3043.6 3048.3 3049.8 238693.0 239730.8 239790.0 285107.0 285782.0 45558.1 45588.0 45936.5 46017.5 46120.8 48441.8 48488.0 48551.3 48620.3 48668.0 13874.8 13883.2 13966.7 14002.4 14074.0 11357.9 11389.0 11395.3 11412.3 11428.0 13095.1 13337.8 13347.4 13369.1 13437.0 6023.6 6026.0 6061.8 6064.2 6121.7 999.6 1022.6 1028.9 1055.4 1055.5 17294.6 20558.0 21095.0 24346.6 24717.8 936.4 1069.5 1090.2 1106.3 1139.5 10582.8 11719.9 12132.1 12382.8 12391.8 315805.0 315840.0 315963.0 316282.0 318138.4 41473.8 52411.2 52569.7 52588.2 52690.0 1499.9 1505.5 1541.6 1543.8 1553.3 17345.2 22666.7 23797.8 23825.8 23968.6 37838.8 38080.4 38117.6 38157.6 38220.8 212726.0 276673.0 277513.0 277632.0 278241.2 33611.0 34925.3 35516.5 36167.6 36519.0 43313.4 44324.2 44633.3 45312.8 45431.5 30342.0 30547.4 31010.2 31333.8 31583.7 24834.4 30497.7 31510.5 31552.4 31669.8 35060.6 37047.0 37061.4 37066.1 37176.8 29398.6 29684.3 31532.5 31577.7 31697.3 21372.9 23259.0 24908.8 25080.5 25335.0 18:41:30 INFO - PID 1635 | 18:41:30 INFO - PID 1635 | [#2] jquery.html Cycles:180 Average:34709.70 Median:22869.20 stddev:88906.27 (388.8%) stddev-sans-first:89118.37 18:41:30 INFO - PID 1635 | Values: 407.2 423.7 435.6 446.6 451.5 19235.7 20840.0 21507.8 21665.2 21711.3 18845.2 20894.9 22342.9 23066.9 23068.8 5982.2 6173.5 6225.1 6226.9 6245.6 4551.5 5764.4 6108.9 6118.1 6134.8 6012.2 6078.4 6088.2 6097.4 6108.3 5066.7 5382.8 5693.5 6025.4 6063.6 4929.5 5366.7 5374.2 5396.4 5404.6 27422.0 27688.2 27837.7 28280.7 28372.4 2151.2 2224.9 2225.3 2228.3 2233.1 16097.7 17738.8 18251.4 18293.3 18329.5 2160.6 2164.9 2166.0 2174.4 2181.8 148957.0 149359.5 150614.7 151723.0 155934.0 12249.8 12767.4 12829.9 12906.3 12952.9 16204.8 16529.7 16581.9 16598.5 16628.8 8852.6 8983.2 9115.1 9160.9 9302.5 7198.4 8655.5 8665.0 8679.0 8844.0 8013.7 8015.9 8171.3 8186.7 8201.3 2175.3 2175.9 2178.5 2180.6 2322.9 944.3 953.4 957.5 975.0 991.9 16338.7 19879.3 20610.0 20889.1 21182.9 990.1 1014.9 1017.3 1022.8 1023.2 9077.5 9580.8 10814.7 10860.6 10914.0 495460.0 522231.0 523651.0 533612.0 537107.0 109649.7 116810.0 125712.1 129371.0 136080.0 1189.3 1211.9 1214.7 1230.6 1233.5 21606.0 22661.8 22689.6 22840.4 22898.0 54448.6 57844.7 58237.0 58469.1 59523.7 81686.2 82427.2 83704.0 83797.0 84253.0 8454.1 8638.6 8818.3 8867.9 9042.9 12689.0 13729.3 13779.2 13915.0 13956.3 11598.3 11915.7 12033.3 12044.8 12075.6 9567.5 9668.4 10515.0 10736.8 11096.6 11700.2 11874.8 11989.5 12063.3 12079.6 7049.8 7845.9 8463.6 8688.3 8843.0 11280.0 11743.3 11906.6 11996.0 12090.7 18:41:30 INFO - PID 1635 | 18:41:30 INFO - PID 1635 | [#3] mootools.html Cycles:180 Average:6758.93 Median:3944.75 stddev:4117.02 (104.4%) stddev-sans-first:4108.96 18:41:30 INFO - PID 1635 | Values: 1410.8 1455.9 1467.3 1483.2 1496.7 10908.9 11204.6 11212.8 11252.9 11627.4 11245.3 11416.8 11416.9 11418.8 11438.4 2493.3 2535.6 2550.9 2551.3 2553.6 2480.1 2528.1 2534.6 2539.6 2539.8 2476.2 2501.4 2507.9 2527.5 2533.9 2455.6 2477.1 2499.3 2505.6 2508.0 2477.1 2477.6 2493.4 2494.4 2506.8 11070.5 11127.1 11131.2 11140.1 11142.4 5228.8 6446.7 6448.5 6449.1 6480.0 3798.3 4091.2 4142.3 4157.2 4166.4 1113.3 1150.9 1187.2 1207.4 1217.0 12770.7 14030.2 14339.1 15246.0 15384.8 5092.7 5169.6 5326.6 5330.6 5342.4 11130.2 11165.2 11225.6 11225.9 11291.9 6178.8 6256.8 6266.6 6269.7 6274.1 5811.8 5907.9 5958.9 5974.6 6020.9 3734.2 4206.8 4285.8 4315.3 4387.3 1253.9 1254.5 1255.1 1267.0 1269.1 783.9 794.5 803.9 805.3 822.1 6076.4 6139.5 6275.9 6598.6 6979.1 761.8 933.7 941.4 946.2 950.5 3427.6 4313.8 4357.5 4378.3 4477.9 13389.9 13491.3 13701.7 13813.9 13941.7 13858.3 13863.4 13870.7 13877.3 13913.8 8125.8 8206.5 8330.8 8345.1 8364.5 5796.4 6190.7 6632.1 6939.1 6957.1 12041.6 12419.4 12558.6 12648.4 12674.0 12519.4 12657.2 12692.4 12733.1 12982.9 8677.0 8883.2 8921.6 8973.8 9052.3 8422.6 9928.9 10177.3 10190.5 10193.0 8448.5 8679.7 8825.6 8851.7 8889.5 8538.3 8615.5 8658.8 8674.3 8702.8 7756.0 7864.4 7929.8 7996.3 7997.8 5886.1 5911.7 5945.7 6146.8 6149.0 5460.5 5606.6 5981.8 6101.1 6142.2 18:41:30 INFO - PID 1635 | 18:41:30 INFO - PID 1635 | [#4] prototype.html Cycles:180 Average:5373.81 Median:6038.99 stddev:1696.73 (28.1%) stddev-sans-first:1659.36 18:41:30 INFO - PID 1635 | Values: 367.7 378.7 383.1 386.7 390.1 5318.2 6255.9 6301.2 6323.5 6339.7 6039.8 6122.9 6152.4 6159.7 6175.1 4629.8 4645.3 4655.0 4664.8 4677.6 4667.6 4670.7 4679.2 4689.8 4694.4 4579.3 4618.1 4623.9 4647.5 4655.3 4476.8 4611.4 4622.4 4636.3 4636.9 3879.5 4098.4 4101.9 4118.9 4125.7 4743.7 4938.6 5282.7 5978.3 6037.9 4056.3 4796.7 5085.1 5274.4 5501.6 5562.7 6423.7 6552.8 6559.8 6690.0 3935.4 4626.0 4656.4 4689.3 4706.8 5714.9 6894.7 6906.2 7024.7 7121.4 4081.3 4385.9 4408.2 4449.8 4488.3 3882.5 4373.8 4512.2 4521.0 4525.2 5251.9 5395.8 5407.3 5411.1 5446.4 6491.0 6717.2 6731.7 6734.0 6766.9 1381.9 1498.2 1502.7 1506.3 1509.0 958.0 988.9 998.8 1000.5 1009.9 5324.7 6111.1 6266.2 6339.8 6356.7 6277.0 6734.0 6931.4 6996.3 7016.1 5075.8 5304.6 6156.9 6296.8 6335.1 3409.7 3550.5 3599.2 3634.2 3667.3 6239.5 6602.6 7160.8 7212.5 7324.1 6573.9 6646.6 7225.5 7238.1 7245.8 5899.5 6612.8 6627.6 7184.9 7257.8 6998.6 7006.2 7017.7 7040.5 7041.8 5852.9 6171.1 6271.7 6701.7 6798.4 6354.7 6583.9 6897.5 7083.5 7098.9 4100.6 4395.5 4971.7 4988.6 4994.2 4819.0 6761.5 6820.9 6978.2 7133.7 6038.2 6472.7 6851.1 6851.9 6900.4 5944.6 6587.9 6687.6 6832.1 6875.3 6016.5 6668.4 6683.5 6714.2 6734.7 6624.5 6681.2 6839.9 6844.9 6855.1 5461.9 5571.6 6602.4 6641.8 6687.5 18:41:30 INFO - PID 1635 | 18:41:30 INFO - PID 1635 | [#5] yui.html Cycles:180 Average:21035.90 Median:18364.04 stddev:29944.72 (163.1%) stddev-sans-first:29989.78 18:41:30 INFO - PID 1635 | Values: 697.4 709.7 811.4 834.0 841.8 12903.8 16610.7 16720.7 18208.3 19307.5 18066.5 18360.3 18367.8 18518.8 18642.6 818.7 846.7 973.3 991.8 995.5 946.1 984.1 1006.9 1008.9 1009.5 1052.0 1058.5 1059.6 1059.9 1065.8 1056.3 1059.7 1061.4 1061.9 1063.6 764.8 767.1 776.3 778.5 781.3 16679.6 16852.3 17107.7 17300.4 17471.8 17024.6 17095.0 17211.1 17215.1 17377.0 14157.8 14256.0 14338.3 14516.3 14539.3 3522.5 3556.8 3588.4 3594.5 3598.1 92310.0 121736.0 121981.4 123829.0 129563.0 13245.7 13303.7 13389.1 13410.4 13421.7 24914.9 25602.6 25632.7 25767.2 25836.9 7450.6 7465.1 7534.9 7542.4 7564.8 3461.5 3465.2 3468.8 3471.1 3527.0 4185.3 4240.6 4271.1 4274.5 4291.1 817.7 847.9 875.2 938.4 965.2 615.0 635.4 646.6 646.8 674.8 11047.4 11753.3 11854.3 11967.3 12005.1 624.4 625.7 626.4 635.6 653.8 4611.1 5041.1 5351.2 5432.4 5557.0 71901.0 104530.1 104709.4 105899.0 106492.0 92315.5 92328.5 92509.5 92722.0 93927.1 81391.0 83250.2 83685.0 84559.0 84937.0 7808.9 10993.5 11121.9 11133.8 11262.0 40960.2 41491.6 41675.4 41779.6 41828.8 53035.4 53081.5 53191.0 53458.7 53940.5 12680.2 13803.7 14070.8 14355.8 14501.1 15225.1 15510.6 15599.4 15633.5 15785.7 14018.5 14330.1 14473.9 14560.6 14652.9 13023.3 13340.9 13513.3 13577.6 13611.1 12004.7 12851.6 13483.8 13736.7 13929.8 10654.5 10702.0 10718.6 10839.8 10867.0 11061.5 11623.7 11751.5 11833.2 11957.2 18:41:30 INFO - PID 1635 | -------- Summary: end -------- 18:41:30 INFO - PID 1635 | 18:41:31 INFO - TEST-INFO | 1635: exit 0 18:41:31 INFO - TEST-OK | dromaeo_css | took 1113891ms 18:41:31 INFO - TEST-START | kraken 18:41:31 INFO - operating with platform_type : mac_ 18:41:31 INFO - Initialising browser for kraken test... 18:41:31 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/tmp3gWZyr/profile http://localhost:49226/getInfo.html) 18:41:31 INFO - PID 1650 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2963: String.localeCompare is deprecated; use String.prototype.localeCompare instead 18:41:34 INFO - PID 1650 | 2017-05-19 18:41:34.204 plugin-container[1651:9974] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 18:41:34 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:41:34 INFO - PID 1650 | 2017-05-19 18:41:34.206 plugin-container[1651:9974] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 18:41:34 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:41:34 INFO - PID 1650 | 2017-05-19 18:41:34.207 plugin-container[1651:9974] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:41:34 INFO - PID 1650 | __metrics Screen width/height:1600/1200 18:41:34 INFO - PID 1650 | colorDepth:24 18:41:34 INFO - PID 1650 | Browser inner width/height: 1024/691 18:41:34 INFO - PID 1650 | __metrics 18:41:34 INFO - PID 1650 | 2017-05-19 18:41:34.758 plugin-container[1652:10026] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x933f, name = 'com.apple.tsm.portname' 18:41:34 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:41:34 INFO - PID 1650 | 2017-05-19 18:41:34.759 plugin-container[1652:10026] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980b, name = 'com.apple.CFPasteboardClient' 18:41:34 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:41:34 INFO - PID 1650 | 2017-05-19 18:41:34.759 plugin-container[1652:10026] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:41:35 INFO - TEST-INFO | 1650: exit 0 18:41:35 INFO - Browser initialized. 18:41:35 INFO - Running cycle 1/1 for kraken test... 18:41:35 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/tmp3gWZyr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:41:37 INFO - PID 1653 | 2017-05-19 18:41:37.289 plugin-container[1654:10123] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.tsm.portname' 18:41:37 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:41:37 INFO - PID 1653 | 2017-05-19 18:41:37.289 plugin-container[1654:10123] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 18:41:37 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:41:37 INFO - PID 1653 | 2017-05-19 18:41:37.289 plugin-container[1654:10123] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:41:38 INFO - PID 1653 | 2017-05-19 18:41:38.034 plugin-container[1655:10168] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x984f, name = 'com.apple.tsm.portname' 18:41:38 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:41:38 INFO - PID 1653 | 2017-05-19 18:41:38.034 plugin-container[1655:10168] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x450f, name = 'com.apple.CFPasteboardClient' 18:41:38 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:41:38 INFO - PID 1653 | 2017-05-19 18:41:38.034 plugin-container[1655:10168] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:41:47 INFO - PID 1653 | RSS: Main: 242311168 18:41:47 INFO - PID 1653 | 18:41:50 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection) 18:41:51 INFO - PID 1653 | RSS: Main: 253902848 18:41:51 INFO - PID 1653 | 18:41:53 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-dft) 18:41:54 INFO - PID 1653 | RSS: Main: 262082560 18:41:54 INFO - PID 1653 | 18:41:57 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-fft) 18:41:58 INFO - PID 1653 | RSS: Main: 265392128 18:41:58 INFO - PID 1653 | 18:42:00 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator) 18:42:00 INFO - PID 1653 | RSS: Main: 266063872 18:42:00 INFO - PID 1653 | 18:42:02 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:42:03 INFO - PID 1653 | RSS: Main: 267894784 18:42:03 INFO - PID 1653 | 18:42:06 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom) 18:42:07 INFO - PID 1653 | RSS: Main: 277626880 18:42:07 INFO - PID 1653 | 18:42:10 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate) 18:42:10 INFO - PID 1653 | RSS: Main: 277606400 18:42:10 INFO - PID 1653 | 18:42:13 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial) 18:42:14 INFO - PID 1653 | RSS: Main: 276492288 18:42:14 INFO - PID 1653 | 18:42:16 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:42:16 INFO - PID 1653 | RSS: Main: 276336640 18:42:16 INFO - PID 1653 | 18:42:18 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:42:19 INFO - PID 1653 | RSS: Main: 276377600 18:42:19 INFO - PID 1653 | 18:42:21 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:42:22 INFO - PID 1653 | RSS: Main: 276021248 18:42:22 INFO - PID 1653 | 18:42:24 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:42:25 INFO - PID 1653 | RSS: Main: 276230144 18:42:25 INFO - PID 1653 | 18:42:29 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:42:29 INFO - PID 1653 | RSS: Main: 273760256 18:42:29 INFO - PID 1653 | 18:42:31 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49226/tests/kraken/driver.html?testName=ai-astar) 18:42:31 INFO - PID 1653 | RSS: Main: 275804160 18:42:31 INFO - PID 1653 | 18:42:31 INFO - PID 1653 | __start_tp_report 18:42:31 INFO - PID 1653 | _x_x_mozilla_page_load 18:42:31 INFO - PID 1653 | _x_x_mozilla_page_load_details 18:42:31 INFO - PID 1653 | |i|pagename|runs| 18:42:31 INFO - PID 1653 | |0;ai-astar;102;118;116;116;114;116;134;173;151;115 18:42:31 INFO - PID 1653 | |1;audio-beat-detection;145;117;121;155;109;119;145;134;143;111 18:42:31 INFO - PID 1653 | |2;audio-dft;198;197;230;276;234;197;198;199;209;202 18:42:31 INFO - PID 1653 | |3;audio-fft;83;57;61;83;58;62;57;63;83;85 18:42:31 INFO - PID 1653 | |4;audio-oscillator;87;70;83;76;101;76;108;89;70;90 18:42:31 INFO - PID 1653 | |5;imaging-gaussian-blur;92;91;92;92;92;90;91;91;104;92 18:42:31 INFO - PID 1653 | |6;imaging-darkroom;129;98;98;98;105;151;118;98;111;148 18:42:31 INFO - PID 1653 | |7;imaging-desaturate;93;94;93;127;134;90;92;91;92;94 18:42:31 INFO - PID 1653 | |8;json-parse-financial;52;51;53;52;53;53;53;54;51;51 18:42:31 INFO - PID 1653 | |9;json-stringify-tinderbox;56;68;70;78;90;95;87;57;61;60 18:42:31 INFO - PID 1653 | |10;stanford-crypto-aes;86;83;84;87;85;85;80;87;87;86 18:42:31 INFO - PID 1653 | |11;stanford-crypto-ccm;137;140;139;138;143;149;154;140;202;151 18:42:31 INFO - PID 1653 | |12;stanford-crypto-pbkdf2;187;238;170;183;217;208;215;229;235;237 18:42:31 INFO - PID 1653 | |13;stanford-crypto-sha256-iterative;65;83;70;70;67;68;69;68;66;69 18:42:31 INFO - PID 1653 | __end_tp_report 18:42:31 INFO - PID 1653 | __start_cc_report 18:42:31 INFO - PID 1653 | _x_x_mozilla_cycle_collect,917 18:42:31 INFO - PID 1653 | __end_cc_report 18:42:31 INFO - PID 1653 | __startTimestamp1495244551649__endTimestamp 18:42:31 INFO - PID 1653 | 18:42:31 INFO - PID 1653 | ------- Summary: start ------- 18:42:31 INFO - PID 1653 | Number of tests: 14 18:42:31 INFO - PID 1653 | 18:42:31 INFO - PID 1653 | [#0] ai-astar Cycles:10 Average:125.50 Median:117.00 stddev:21.35 (18.2%) stddev-sans-first:20.88 18:42:31 INFO - PID 1653 | Values: 102.0 118.0 116.0 116.0 114.0 116.0 134.0 173.0 151.0 115.0 18:42:31 INFO - PID 1653 | 18:42:31 INFO - PID 1653 | [#1] audio-beat-detection Cycles:10 Average:129.90 Median:138.50 stddev:16.44 (11.9%) stddev-sans-first:16.51 18:42:31 INFO - PID 1653 | Values: 145.0 117.0 121.0 155.0 109.0 119.0 145.0 134.0 143.0 111.0 18:42:31 INFO - PID 1653 | 18:42:31 INFO - PID 1653 | [#2] audio-dft Cycles:10 Average:214.00 Median:205.50 stddev:25.79 (12.5%) stddev-sans-first:26.69 18:42:31 INFO - PID 1653 | Values: 198.0 197.0 230.0 276.0 234.0 197.0 198.0 199.0 209.0 202.0 18:42:31 INFO - PID 1653 | 18:42:31 INFO - PID 1653 | [#3] audio-fft Cycles:10 Average:69.20 Median:73.00 stddev:12.48 (17.1%) stddev-sans-first:12.20 18:42:31 INFO - PID 1653 | Values: 83.0 57.0 61.0 83.0 58.0 62.0 57.0 63.0 83.0 85.0 18:42:31 INFO - PID 1653 | 18:42:31 INFO - PID 1653 | [#4] audio-oscillator Cycles:10 Average:85.00 Median:79.50 stddev:12.68 (15.9%) stddev-sans-first:13.42 18:42:31 INFO - PID 1653 | Values: 87.0 70.0 83.0 76.0 101.0 76.0 108.0 89.0 70.0 90.0 18:42:31 INFO - PID 1653 | 18:42:31 INFO - PID 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:92.70 Median:92.00 stddev:4.03 (4.4%) stddev-sans-first:4.27 18:42:31 INFO - PID 1653 | Values: 92.0 91.0 92.0 92.0 92.0 90.0 91.0 91.0 104.0 92.0 18:42:31 INFO - PID 1653 | 18:42:31 INFO - PID 1653 | [#6] imaging-darkroom Cycles:10 Average:115.40 Median:124.50 stddev:20.71 (16.6%) stddev-sans-first:21.37 18:42:31 INFO - PID 1653 | Values: 129.0 98.0 98.0 98.0 105.0 151.0 118.0 98.0 111.0 148.0 18:42:31 INFO - PID 1653 | 18:42:31 INFO - PID 1653 | [#7] imaging-desaturate Cycles:10 Average:100.00 Median:92.50 stddev:16.21 (17.5%) stddev-sans-first:16.99 18:42:31 INFO - PID 1653 | Values: 93.0 94.0 93.0 127.0 134.0 90.0 92.0 91.0 92.0 94.0 18:42:31 INFO - PID 1653 | 18:42:31 INFO - PID 1653 | [#8] json-parse-financial Cycles:10 Average:52.30 Median:53.00 stddev:1.06 (2.0%) stddev-sans-first:1.12 18:42:31 INFO - PID 1653 | Values: 52.0 51.0 53.0 52.0 53.0 53.0 53.0 54.0 51.0 51.0 18:42:31 INFO - PID 1653 | 18:42:31 INFO - PID 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:72.20 Median:74.00 stddev:14.45 (19.5%) stddev-sans-first:14.09 18:42:31 INFO - PID 1653 | Values: 56.0 68.0 70.0 78.0 90.0 95.0 87.0 57.0 61.0 60.0 18:42:31 INFO - PID 1653 | 18:42:31 INFO - PID 1653 | [#10] stanford-crypto-aes Cycles:10 Average:85.00 Median:86.00 stddev:2.21 (2.6%) stddev-sans-first:2.32 18:42:31 INFO - PID 1653 | Values: 86.0 83.0 84.0 87.0 85.0 85.0 80.0 87.0 87.0 86.0 18:42:31 INFO - PID 1653 | 18:42:31 INFO - PID 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:149.30 Median:146.00 stddev:19.44 (13.3%) stddev-sans-first:20.10 18:42:31 INFO - PID 1653 | Values: 137.0 140.0 139.0 138.0 143.0 149.0 154.0 140.0 202.0 151.0 18:42:31 INFO - PID 1653 | 18:42:31 INFO - PID 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:211.90 Median:223.00 stddev:24.49 (11.0%) stddev-sans-first:24.26 18:42:31 INFO - PID 1653 | Values: 187.0 238.0 170.0 183.0 217.0 208.0 215.0 229.0 235.0 237.0 18:42:31 INFO - PID 1653 | 18:42:31 INFO - PID 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.50 Median:69.00 stddev:5.02 (7.3%) stddev-sans-first:5.05 18:42:31 INFO - PID 1653 | Values: 65.0 83.0 70.0 70.0 67.0 68.0 69.0 68.0 66.0 69.0 18:42:31 INFO - PID 1653 | -------- Summary: end -------- 18:42:31 INFO - PID 1653 | 18:42:32 INFO - TEST-INFO | 1653: exit 0 18:42:32 INFO - TEST-OK | kraken | took 61197ms 18:42:32 INFO - SUITE-END | took 1175s 18:42:32 INFO - Completed test suite (00:19:35) 18:42:32 INFO - javascript benchmark 18:42:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7754.418951527231, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3537.2218112015357, 3581.856725730971, 3593.4609808528703, 3609.855605775769, 3618.077390265482, 4997.500249975001, 6154.476869466101, 6180.13478113064, 6210.19267495225, 6236.0645903114555, 6120.9347721082095, 6142.805741014681, 6180.598261113025, 6219.937800621992, 6304.117423560705, 5338.145896656536, 5387.488188622075, 5394.352677678684, 5417.377001644822, 5474.91787623186, 4436.4676238851225, 4981.0, 5453.890922181554, 5534.197541356501, 5592.133219351007, 5285.418603953562, 5335.653182543122, 5416.756245968941, 5445.319335083114, 5490.972545137269, 4517.254652982254, 5360.758765855507, 5383.923215356945, 5465.562754979592, 5484.122540393558, 5374.677519348813, 5421.268128802601, 5421.972890135564, 5424.864378390533, 5456.399796022434, 5984.581079324449, 6232.844178895519, 6234.0960560718895, 6258.4680302174365, 6259.530535209832, 6427.8393040173905, 6473.708683109271, 6585.868282634368, 6595.901061484082, 6608.074869518271, 6682.164729408824, 6687.765928192539, 6691.531592788507, 6711.127553418024, 6715.429188518981, 6274.874502509924, 6295.779647712354, 6296.527760417987, 6305.527085468517, 6328.335524769925, 5639.492445679908, 6575.506836987245, 6625.138731964814, 6750.3924646782025, 6803.931960680429, 5644.294463192101, 6478.611283323015, 6478.773242936474, 6486.740530378826, 6531.510136739668, 6482.092507049017, 6550.639714815636, 6617.338266173344, 6711.496637752188, 6729.932700673028, 6515.250746164252, 6586.835329116715, 6589.7364105435245, 6597.076409302702, 6616.8345791355605, 5244.6328756987405, 6636.2368327642935, 6636.5354425190735, 6643.368879956396, 6649.567778094505, 5251.422343542207, 5355.678659280456, 5362.892742145135, 5367.0, 5426.62013659048, 4707.929381059287, 4755.809767609257, 4806.327114204014, 4819.734914579662, 4830.541098595628, 6305.432511073934, 6308.873822523524, 6312.905306420407, 6323.20959880015, 6327.240731112296, 6657.101291325609, 6659.367360100783, 6744.629045402452, 6770.8645827084165, 6803.557768745016, 5242.292290540729, 6187.041008643668, 6215.036669316263, 6218.378162183745, 6247.843803904848, 5958.553108516879, 5980.73086711099, 5984.611000284967, 5996.2504686914135, 6032.728527216286, 6866.0, 6909.170899491993, 6921.411680007256, 6928.307169283032, 6949.270326615632, 6489.351064893662, 6583.572067815577, 6639.634820084749, 6762.154730658668, 6790.117284753148, 6860.313968603299, 6883.827904302432, 6889.965550172217, 6924.480663950325, 6931.133608298963, 6654.800355989328, 6662.866742665216, 6665.966670166812, 6671.8999215010845, 6689.297623749436, 6475.838104047437, 6715.731370745116, 6731.360520750521, 6788.76239331621, 6800.421964132906, 6400.135981642418, 6450.0969864218105, 6476.352364763486, 6499.252585952676, 6550.377714117151, 5324.494173053554, 6570.11303474044, 6574.901376479453, 6575.50683698715, 6624.56940298879, 6132.386102851662, 6168.444839964424, 6283.057541368831, 6366.554341196072, 6390.808275751735, 6367.9044814328745, 6378.266499352634, 6383.9361606383345, 6389.041643753474, 6413.230412350548, 5579.776808927598, 6098.0852872069545, 6276.780312689034, 6300.086487459201, 6327.462165715781], "value": 6047.126535959199, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [759.8632246195687, 789.238384958515, 806.4516129032257, 1034.648219605335, 1210.6126039667304, 38373.61626383735, 39313.62402315916, 39384.606153938454, 39446.40830387543, 39524.0, 40100.79949600248, 40399.59600403995, 40468.98827529306, 40473.78578642638, 40492.59507404925, 7784.377249820015, 8144.4706094103985, 8274.0, 8288.046874609438, 8296.58517074147, 8351.95824020879, 8436.873446898302, 8449.619767110495, 8456.957715211416, 8462.111478294783, 7036.683349249296, 7553.169151393342, 8315.958420207891, 8413.495190288602, 8482.066972633005, 8407.70573029944, 8453.365997550209, 8454.61954212062, 8454.830903381959, 8461.66153353865, 8142.877856832152, 8216.507009579444, 8220.136885627042, 8242.0, 8245.95877020617, 36449.0, 36728.449073263924, 36730.63269367299, 36737.81631091854, 36742.26515469702, 3678.282734866702, 3706.3328600851837, 3718.721095917817, 3724.0, 3731.458938453909, 23582.93876775549, 30016.54975175374, 30303.393932121453, 30414.0, 30449.695503045128, 2999.0, 3033.514637657964, 3043.634763828333, 3048.253177971389, 3049.8170109793264, 238693.0, 239730.8013459939, 239790.0, 285107.0, 285782.0, 45558.08883822305, 45588.0, 45936.5406345939, 46017.539824602, 46120.769396152806, 48441.75779121082, 48488.0, 48551.27173092406, 48620.27069593959, 48668.02663946701, 13874.791878121836, 13883.236421996888, 13966.650833729238, 14002.36989335462, 14074.0, 11357.886421135683, 11389.0, 11395.316281023164, 11412.258203216765, 11428.0286175676, 13095.083344165816, 13337.799933001013, 13347.399367028507, 13369.131006484547, 13437.0, 6023.578349515579, 6026.0, 6061.787837425669, 6064.241969753781, 6121.693915304306, 999.5801763259303, 1022.601185537641, 1028.8990779865621, 1055.350959160111, 1055.5461151704687, 17294.578758973752, 20558.0, 21095.0, 24346.63480047801, 24717.752822471906, 936.3679516326589, 1069.540097757956, 1090.2477290669412, 1106.269861891148, 1139.4530625299903, 10582.841257381146, 11719.941400292944, 12132.090093242865, 12382.81425778614, 12391.75216495665, 315805.0, 315840.0, 315963.0, 316282.0, 318138.409307952, 41473.79263103665, 52411.21383179256, 52569.737151313995, 52588.21117683238, 52690.0, 1499.8800095992294, 1505.4580351073605, 1541.5760665816765, 1543.845615438447, 1553.3475940105184, 17345.21946512435, 22666.66000009968, 23797.762022380248, 23825.761742382354, 23968.640470393308, 37838.810805946894, 38080.42879356757, 38117.618823811405, 38157.61842381541, 38220.80889595534, 212726.0, 276673.0, 277513.0, 277632.0, 278241.21758782153, 33610.99167024993, 34925.30149397049, 35516.467252991744, 36167.638323616426, 36519.0, 43313.35029974616, 44324.22703091969, 44633.33050004317, 45312.773436133924, 45431.54568454273, 30342.0, 30547.389052218386, 31010.224744381572, 31333.843330783202, 31583.684163158076, 24834.379140521633, 30497.695023049484, 31510.52734208943, 31552.368952621277, 31669.841650791597, 35060.649393506756, 37047.0, 37061.44407833939, 37066.07334816651, 37176.81411593033, 29398.55902161512, 29684.257893552833, 31532.52701209438, 31577.69563881745, 31697.25665088442, 21372.931353431955, 23259.0, 24908.750912490643, 25080.49839003173, 25335.0], "value": 17198.355418293377, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [407.20188430675876, 423.73301842792074, 435.6318910520611, 446.5534465534465, 451.46952331011073, 19235.711464328047, 20840.0, 21507.81707006114, 21665.241716539924, 21711.3486595402, 18845.24619015238, 20894.89552552239, 22342.88828555859, 23066.88466557669, 23068.846557672132, 5982.222311099551, 6173.475254603364, 6225.097360882669, 6226.875462490748, 6245.56281060326, 4551.476580193287, 5764.423557644244, 6108.877822443548, 6118.1434599156155, 6134.754609815602, 6012.158297838306, 6078.392160783908, 6088.238970128734, 6097.420745029215, 6108.328083910788, 5066.74666266687, 5382.761964748111, 5693.4875861172695, 6025.3731492497, 6063.636181829104, 4929.482404347527, 5366.651167674088, 5374.220737992969, 5396.406395296514, 5404.6486978346675, 27422.0, 27688.169354919384, 27837.721622783716, 28280.717192828015, 28372.432551349062, 2151.1610471915965, 2224.922127725522, 2225.299030805307, 2228.253535065755, 2233.084435381502, 16097.678046439123, 17738.822611773845, 18251.361202357853, 18293.268269269214, 18329.450116496526, 2160.5894879972757, 2164.8592841465256, 2165.967510487344, 2174.402039439151, 2181.83636227282, 148957.0, 149359.50640493564, 150614.7407788873, 151723.0, 155934.0, 12249.816252756038, 12767.361631918367, 12829.93585032069, 12906.290154041619, 12952.935235323952, 16204.83795162057, 16529.66940661204, 16581.922175058582, 16598.502044938672, 16628.83371166273, 8852.557372131369, 8983.191512763751, 9115.088491150831, 9160.908390916138, 9302.53487325631, 7198.352148306678, 8655.523946182895, 8664.95667521671, 8678.95660521706, 8843.955780221058, 8013.719519816778, 8015.919840801634, 8171.264586187272, 8186.672533098609, 8201.30288925436, 2175.271284119806, 2175.912963481443, 2178.455386153462, 2180.6401943679116, 2322.8606283623035, 944.3012170993416, 953.4327075390132, 957.5212393803098, 974.965876194344, 991.9256055795844, 16338.673226535402, 19879.30422435208, 20609.969501524865, 20889.059992300383, 21182.894085529475, 990.1089019882164, 1014.898510148994, 1017.3133135133755, 1022.754538910656, 1023.1967905194387, 9077.45535267886, 9580.754501914846, 10814.729631759112, 10860.565577376816, 10913.963173498505, 495460.0, 522231.0, 523651.0, 533612.0, 537107.0, 109649.67977721983, 116810.0, 125712.1143182853, 129371.0, 136080.0, 1189.316143217636, 1211.8848709372419, 1214.732758793064, 1230.6308107567693, 1233.543588872123, 21606.0, 22661.773382265965, 22689.63862168209, 22840.428989275402, 22897.969591368095, 54448.63878402913, 57844.71077644753, 58237.0, 58469.122963156435, 59523.70238148781, 81686.18313817024, 82427.17572824196, 83704.0, 83797.0, 84253.0, 8454.070052294366, 8638.56807159627, 8818.250448711675, 8867.866981995146, 9042.864357034781, 12689.0, 13729.308666017241, 13779.173249605055, 13915.0, 13956.302184890917, 11598.304101753922, 11915.702107447383, 12033.278003319829, 12044.75910481768, 12075.637730868088, 9567.473788941676, 9668.371555848847, 10515.0, 10736.785264294827, 11096.556137754722, 11700.239484433485, 11874.76250475003, 11989.460474278503, 12063.276203427822, 12079.57721479744, 7049.7885063448175, 7845.921540784748, 8463.619137138967, 8688.348373872112, 8843.0, 11280.0, 11743.295402275891, 11906.583269585522, 11996.0, 12090.697732556757], "value": 10374.021948669395, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1410.8095407120038, 1455.8689717925397, 1467.2736995187377, 1483.184248663235, 1496.7006598680264, 10908.945455272722, 11204.551817927273, 11212.775744485105, 11252.943735281322, 11627.360495172763, 11245.269057511256, 11416.828747568772, 11416.942915285432, 11418.828717569222, 11438.428078596078, 2493.2644869763417, 2535.556277651413, 2550.9489810203786, 2551.285640020797, 2553.5786595211766, 2480.1443501991785, 2528.115159694111, 2534.556452620793, 2539.6190571414345, 2539.8222124451295, 2476.1704828882257, 2501.4246723253573, 2507.912223072193, 2527.4692314613953, 2533.949321013579, 2455.6316552517087, 2477.0587176872855, 2499.275210189043, 2505.5615267328144, 2507.9749202507924, 2477.1206221791185, 2477.6407420924047, 2493.4015836199183, 2494.4138127540014, 2506.8495890246645, 11070.501827417786, 11127.054200392977, 11131.220814542905, 11140.053095486894, 11142.387168705718, 5228.79084836606, 6446.709898054552, 6448.484121270287, 6449.12936753537, 6480.02799580062, 3798.2593394288124, 4091.243120022805, 4142.2543942090415, 4157.168566286755, 4166.395872598457, 1113.3097479562641, 1150.9136814738845, 1187.1511869013696, 1207.4445754952733, 1217.0324591949422, 12770.744585108247, 14030.228337441436, 14339.067960582528, 15246.0, 15384.769228461582, 5092.694438333638, 5169.612279079009, 5326.573874090141, 5330.626856120112, 5342.412334643186, 11130.220884538005, 11165.16261280391, 11225.550977960791, 11225.943870280596, 11291.943540282246, 6178.783742568989, 6256.7810126645345, 6266.624002559861, 6269.7492100316385, 6274.090256912722, 5811.82564523065, 5907.911381329283, 5958.910616340759, 5974.641521508724, 6020.939790602126, 3734.215814678948, 4206.843118142535, 4285.807138678767, 4315.266404711207, 4387.341898715218, 1253.921627400435, 1254.523281153156, 1255.0712472770085, 1267.0180610027119, 1269.0545543569915, 783.9216078392116, 794.4875555266821, 803.8553060449175, 805.3476683886071, 822.1326500541985, 6076.422739839708, 6139.4781443577795, 6275.937240627535, 6598.6472773081405, 6979.092717946634, 761.8438220164963, 933.7385532051037, 941.3786900645678, 946.2288235088381, 950.4534892436876, 3427.588689357293, 4313.762743049162, 4357.542458041923, 4378.255696531597, 4477.888052798706, 13389.933050334881, 13491.325433728274, 13701.725965480635, 13813.861861381458, 13941.721165576631, 13858.307084645727, 13863.376148073361, 13870.722585548434, 13877.306134693225, 13913.791293130611, 8125.75622731319, 8206.548639824867, 8330.833383332421, 8345.123762004901, 8364.456310339809, 5796.420357964338, 6190.690465476799, 6632.137822083098, 6939.132608423947, 6957.095577574883, 12041.638750837488, 12419.441125149571, 12558.554982135696, 12648.43082061328, 12674.0, 12519.374031298217, 12657.240565566095, 12692.36538173106, 12733.108682392143, 12982.935085324512, 8677.0, 8883.200511953955, 8921.598528066383, 8973.775655608662, 9052.275817934418, 8422.6209820557, 9928.851067234142, 10177.338472999232, 10190.490475476345, 10193.0, 8448.502469332425, 8679.696210632597, 8825.602847871733, 8851.69019084329, 8889.4666320021, 8538.316934645089, 8615.483071015758, 8658.826823463369, 8674.262687671619, 8702.82594348122, 7755.961220193863, 7864.410169237444, 7929.841403172019, 7996.280334769898, 7997.7600671979935, 5886.146508756121, 5911.733971971185, 5945.732442040032, 6146.846328841814, 6149.0, 5460.453954604666, 5606.579506537108, 5981.790637327673, 6101.115338276014, 6142.201513803177], "value": 5198.609004831637, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [367.72971865678727, 378.71217874415436, 383.07869573675305, 386.6809881847473, 390.122224993764, 5318.213607753024, 6255.906161407577, 6301.2438507379175, 6323.525735569828, 6339.746410143594, 6039.758409663608, 6122.877542449149, 6152.446279834814, 6159.722812473438, 6175.07373893915, 4629.768511574424, 4645.256758918574, 4654.9767251163785, 4664.836730714425, 4677.555632214951, 4667.649926255527, 4670.719756814585, 4679.181143299926, 4689.765511724418, 4694.3662605548325, 4579.3131030345385, 4618.053299073682, 4623.8844028899375, 4647.512011238822, 4655.255159174532, 4476.81364438424, 4611.371518234608, 4622.445306563201, 4636.304554316879, 4636.860894173164, 3879.515060617423, 4098.364753463218, 4101.892489027946, 4118.91762164755, 4125.66994640428, 4743.715377077387, 4938.580220681247, 5282.735863206824, 5978.252718410199, 6037.9396206038255, 4056.2901492239034, 4796.736179510125, 5085.084684756743, 5274.393444753826, 5501.587380946445, 5562.666240025612, 6423.678816059225, 6552.803415897531, 6559.8192325381415, 6690.0, 3935.409688546741, 4626.028534007829, 4656.441227052742, 4689.320048592935, 4706.7646617669325, 5714.857128571777, 6894.724211031603, 6906.205786334585, 7024.719011239545, 7121.430285577141, 4081.346984482528, 4385.934210986838, 4408.206522825923, 4449.7997590108525, 4488.259437192892, 3882.475865758143, 4373.840932152982, 4512.187806194851, 4520.960179158812, 4525.230710779176, 5251.868703282372, 5395.78416863321, 5407.297051383373, 5411.134218525017, 5446.4477623877, 6490.967545162244, 6717.227518835396, 6731.663416829237, 6733.956236783209, 6766.864662706718, 1381.854905234956, 1498.1535432480614, 1502.676924461246, 1506.3070987345943, 1508.9698206035819, 958.0, 988.8763904511936, 998.8152191844387, 1000.5447521377756, 1009.914157296638, 5324.733763311819, 6111.097228816277, 6266.216722909637, 6339.7781077662985, 6356.650384228912, 6277.027060805583, 6733.989901514714, 6931.445484361239, 6996.335348141918, 7016.122984626922, 5075.847724568269, 5304.602154838402, 6156.938430615636, 6296.79612135758, 6335.144755458046, 3409.6931276184764, 3550.4555518349603, 3599.208174201671, 3634.2186429917697, 3667.2848794485353, 6239.469645080128, 6602.570832895846, 7160.785176444715, 7212.4590655699985, 7324.059124332056, 6573.901391479036, 6646.634435106019, 7225.494215404976, 7238.059052323164, 7245.7826265212125, 5899.46904778572, 6612.768553100618, 6627.569208001464, 7184.892226616605, 7257.818554536178, 6998.62046904055, 7006.229314775473, 7017.7192912282935, 7040.471964602573, 7041.753538626123, 5852.912206316823, 6171.1360409543595, 6271.717772700148, 6701.698423571049, 6798.354156355138, 6354.650494222862, 6583.913654246996, 6897.48268879826, 7083.539569927938, 7098.858022839618, 4100.6262901928185, 4395.538468460765, 4971.726555039508, 4988.575971042357, 4994.225894986283, 4818.95181048185, 6761.526693131434, 6820.874555698254, 6978.197507286524, 7133.678984445609, 6038.215032045806, 6472.708728107153, 6851.109355783916, 6851.862962740817, 6900.447964162755, 5944.554158438221, 6587.934120658923, 6687.565308254948, 6832.111825462658, 6875.278095799869, 6016.488598469179, 6668.366505182, 6683.498737594603, 6714.160729908762, 6734.6632668367365, 6624.541072454318, 6681.198256209286, 6839.879637312449, 6844.93155068443, 6855.143107111611, 5461.945380546303, 5571.60998730085, 6602.438792702674, 6641.833954151185, 6687.531872768859], "value": 4834.619040749483, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [697.4281089506605, 709.7338498063226, 811.431997601679, 834.0450184538707, 841.7558907916705, 12903.806442903353, 16610.667786644273, 16720.665586688257, 18208.271669133253, 19307.517312067204, 18066.458006259832, 18360.265589376442, 18367.816321836777, 18518.814811851877, 18642.627147457042, 818.7380038387719, 846.6740304982565, 973.255459573427, 991.7619771254919, 995.4922989275485, 946.1437399153776, 984.0602224875227, 1006.9043440873105, 1008.9344192627492, 1009.470028235175, 1051.9737006574821, 1058.5236643510414, 1059.6185373265619, 1059.89930956559, 1065.7921705267438, 1056.2764506313124, 1059.7403636109163, 1061.3950048472375, 1061.8938106189396, 1063.6489958313778, 764.7896828372186, 767.098659075584, 776.2858170483169, 778.528989961071, 781.3007358414244, 16679.583010424714, 16852.325906963706, 17107.657846843118, 17300.39448619305, 17471.825281747148, 17024.574385640335, 17095.0, 17211.05339206343, 17215.053172075655, 17376.957382557088, 14157.787633185511, 14256.002079854414, 14338.283085845665, 14516.346764395628, 14539.273036348246, 3522.5068490411363, 3556.78659280444, 3588.3899737044767, 3594.4608308753636, 3598.118460977051, 92310.0, 121736.0, 121981.39009304896, 123829.0, 129563.0, 13245.668858278621, 13303.667408314675, 13389.129706569041, 13410.396532156077, 13421.731565368638, 24914.875425622755, 25602.61596076023, 25632.743672563407, 25767.226983190536, 25836.899572051476, 7450.552966822008, 7465.104187497535, 7534.886976695354, 7542.396608271325, 7564.773056808304, 3461.5153878457036, 3465.1510379956567, 3468.826558672107, 3471.0628130405153, 3527.030066731669, 4185.309423945015, 4240.6395456385935, 4271.124419493996, 4274.4870615525715, 4291.120320334324, 817.6974519427731, 847.859628799264, 875.1729615513278, 938.3619138985555, 965.16513216069, 615.0497481391261, 635.3995474276765, 646.6314200905533, 646.7574659502686, 674.835908058595, 11047.447627618747, 11753.26769017099, 11854.348010859485, 11967.341796201292, 12005.09961752872, 624.4130517313802, 625.6902833097646, 626.395528315178, 635.5646382228189, 653.7515744017243, 4611.077784443125, 5041.117804384291, 5351.170568561879, 5432.402435732066, 5556.972215138899, 71901.0, 104530.14525005838, 104709.42935855969, 105899.0, 106492.0, 92315.53842230747, 92328.53835730779, 92509.53745231366, 92722.0, 93927.12145757047, 81391.0, 83250.16749832545, 83685.0, 84559.0, 84936.99999999876, 7808.921910780819, 10993.505292261707, 11121.944390277997, 11133.777324453304, 11262.0, 40960.1807963845, 41491.58508414877, 41675.37486937638, 41779.58220417878, 41828.790856045525, 53035.40893773193, 53081.46918530765, 53191.0, 53458.73270633622, 53940.46059539511, 12680.175788573713, 13803.723925521634, 14070.788938165731, 14355.784663229852, 14501.129932204101, 15225.086494810346, 15510.612234694223, 15599.376024958874, 15633.452829150925, 15785.68428631444, 14018.509352172625, 14330.068545544505, 14473.85526144725, 14560.563183104523, 14652.853471465576, 13023.283719395524, 13340.866591333963, 13513.256770877697, 13577.59267221985, 13611.11527750693, 12004.699882502659, 12851.550195743104, 13483.797743033667, 13736.65658358549, 13929.791053134008, 10654.467276636293, 10701.983311585387, 10718.571257149939, 10839.837402439127, 10867.021968022917, 11061.502232399722, 11623.6512904613, 11751.47118379692, 11833.230839995373, 11957.162998590014], "value": 8017.382164696419, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1571.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 118.0, 116.0, 116.0, 114.0, 116.0, 134.0, 173.0, 151.0, 115.0], "value": 125.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 117.0, 121.0, 155.0, 109.0, 119.0, 145.0, 134.0, 143.0, 111.0], "value": 129.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 197.0, 230.0, 276.0, 234.0, 197.0, 198.0, 199.0, 209.0, 202.0], "value": 214.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 57.0, 61.0, 83.0, 58.0, 62.0, 57.0, 63.0, 83.0, 85.0], "value": 69.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 70.0, 83.0, 76.0, 101.0, 76.0, 108.0, 89.0, 70.0, 90.0], "value": 85.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 91.0, 92.0, 92.0, 92.0, 90.0, 91.0, 91.0, 104.0, 92.0], "value": 92.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 98.0, 98.0, 98.0, 105.0, 151.0, 118.0, 98.0, 111.0, 148.0], "value": 115.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 93.0, 127.0, 134.0, 90.0, 92.0, 91.0, 92.0, 94.0], "value": 100.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 53.0, 52.0, 53.0, 53.0, 53.0, 54.0, 51.0, 51.0], "value": 52.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 68.0, 70.0, 78.0, 90.0, 95.0, 87.0, 57.0, 61.0, 60.0], "value": 72.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 84.0, 87.0, 85.0, 85.0, 80.0, 87.0, 87.0, 86.0], "value": 85.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 140.0, 139.0, 138.0, 143.0, 149.0, 154.0, 140.0, 202.0, 151.0], "value": 149.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 238.0, 170.0, 183.0, 217.0, 208.0, 215.0, 229.0, 235.0, 237.0], "value": 211.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 83.0, 70.0, 70.0, 67.0, 68.0, 69.0, 68.0, 66.0, 69.0], "value": 69.5, "unit": "score"}]}]} 18:42:32 INFO - Return code: 0 18:42:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:42:32 INFO - # TBPL SUCCESS # 18:42:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:42:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:42:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:42:32 INFO - 2017-05-19 18:42:32.956 screenresolution[1660:10543] starting screenresolution argv=screenresolution get 18:42:32 INFO - 2017-05-19 18:42:32.959 screenresolution[1660:10543] Display 0: 1600x1200x32@60 18:42:32 INFO - 2017-05-19 18:42:32.966 screenresolution[1661:10546] starting screenresolution argv=screenresolution list 18:42:32 INFO - Available Modes on Display 0 18:42:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:42:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:42:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:42:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:42:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:42:33 INFO - Intel Iris: 18:42:33 INFO - Chipset Model: Intel Iris 18:42:33 INFO - Type: GPU 18:42:33 INFO - Bus: Built-In 18:42:33 INFO - VRAM (Dynamic, Max): 1536 MB 18:42:33 INFO - Vendor: Intel (0x8086) 18:42:33 INFO - Device ID: 0x0a2e 18:42:33 INFO - Revision ID: 0x0009 18:42:33 INFO - Displays: 18:42:33 INFO - Display: 18:42:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:42:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:42:33 INFO - Display Serial Number: 1600x1200 60 18:42:33 INFO - Main Display: Yes 18:42:33 INFO - Mirror: Off 18:42:33 INFO - Online: Yes 18:42:33 INFO - Rotation: Supported 18:42:33 INFO - Return code: 0 18:42:33 INFO - Running post-action listener: _resource_record_post_action 18:42:33 INFO - [mozharness: 2017-05-20 01:42:33.210455Z] Finished run-tests step (success) 18:42:33 INFO - Running post-run listener: _resource_record_post_run 18:42:33 INFO - Total resource usage - Wall time: 1198s; CPU: 54.0%; Read bytes: 40250368; Write bytes: 566728704; Read time: 395; Write time: 1695 18:42:33 INFO - TinderboxPrint: CPU usage
54.1% 18:42:33 INFO - TinderboxPrint: I/O read bytes / time
40,250,368 / 395 18:42:33 INFO - TinderboxPrint: I/O write bytes / time
566,728,704 / 1,695 18:42:33 INFO - TinderboxPrint: CPU idle
2,201.8 (45.9%) 18:42:33 INFO - TinderboxPrint: CPU system
965.5 (20.1%) 18:42:33 INFO - TinderboxPrint: CPU user
1,626.2 (33.9%) 18:42:33 INFO - TinderboxPrint: Swap in / out
285,163,520 / 0 18:42:33 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 244742656; Write bytes: 240830464; Read time: 18236; Write time: 513 18:42:33 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 35490816; Write bytes: 315580416; Read time: 362; Write time: 1146 18:42:33 INFO - Running post-run listener: _upload_blobber_files 18:42:33 INFO - Blob upload gear active. 18:42:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:42:33 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 18:42:33 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'] 18:42:33 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 18:42:34 INFO - (blobuploader) - INFO - Open directory for files ... 18:42:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:42:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:42:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:42:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:42:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:42:35 INFO - (blobuploader) - INFO - Done attempting. 18:42:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:42:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:42:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:42:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:42:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:42:36 INFO - (blobuploader) - INFO - Done attempting. 18:42:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:42:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:42:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:42:37 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:42:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:42:37 INFO - (blobuploader) - INFO - Done attempting. 18:42:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:42:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:42:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:42:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:42:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:42:39 INFO - (blobuploader) - INFO - Done attempting. 18:42:39 INFO - (blobuploader) - INFO - Iteration through files over. 18:42:39 INFO - Return code: 0 18:42:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:42:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:42:39 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/08d59674b6ccb67d4f7a22d9dbb8ebb79eab75a0578d7a6a54c0c7cd9f82156427d4229fe51db5a767b638c2bd28923d292edb48f4dc0b2d12110a730da2b2ce", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/29a59e91d7f64258a6cc6ae188fabf6b708ffa83a1595fa6805b7d125aad8a843d195042385d8bd262c44cf854384b95b6b362010ca8e77eb65b4552d155fcfd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d8881d46aa5adfc0a1bf54a224f9c4acf96e8780e7ac11cb1d94d906649023950c23dea41ec39b224df64cd94aec8b75f62e831425ba986787a80b661d65b2b9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/acebf92cbbe49db9b0976dcab604aa0ec72abb8bca9cef253662a63e0629f537d71e5ea6b3c4dbe2f926c5cf616d28a782e2e95ae0c2fd8b6196019e6aa0b6c9"} 18:42:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:42:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:42:39 INFO - Contents: 18:42:39 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/08d59674b6ccb67d4f7a22d9dbb8ebb79eab75a0578d7a6a54c0c7cd9f82156427d4229fe51db5a767b638c2bd28923d292edb48f4dc0b2d12110a730da2b2ce", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/29a59e91d7f64258a6cc6ae188fabf6b708ffa83a1595fa6805b7d125aad8a843d195042385d8bd262c44cf854384b95b6b362010ca8e77eb65b4552d155fcfd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d8881d46aa5adfc0a1bf54a224f9c4acf96e8780e7ac11cb1d94d906649023950c23dea41ec39b224df64cd94aec8b75f62e831425ba986787a80b661d65b2b9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/acebf92cbbe49db9b0976dcab604aa0ec72abb8bca9cef253662a63e0629f537d71e5ea6b3c4dbe2f926c5cf616d28a782e2e95ae0c2fd8b6196019e6aa0b6c9"} 18:42:39 INFO - Running post-run listener: copy_logs_to_upload_dir 18:42:39 INFO - Copying logs to upload dir... 18:42:39 INFO - mkdir: /builds/slave/test/build/upload/logs 18:42:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1278.082791 ========= master_lag: 2.28 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2017-05-19 18:42:41.779219) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-19 18:42:41.782307) ========= 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.MKG4TKaMkh/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.zpkjQim7Qk/Listeners TMPDIR=/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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/08d59674b6ccb67d4f7a22d9dbb8ebb79eab75a0578d7a6a54c0c7cd9f82156427d4229fe51db5a767b638c2bd28923d292edb48f4dc0b2d12110a730da2b2ce", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/29a59e91d7f64258a6cc6ae188fabf6b708ffa83a1595fa6805b7d125aad8a843d195042385d8bd262c44cf854384b95b6b362010ca8e77eb65b4552d155fcfd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d8881d46aa5adfc0a1bf54a224f9c4acf96e8780e7ac11cb1d94d906649023950c23dea41ec39b224df64cd94aec8b75f62e831425ba986787a80b661d65b2b9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/acebf92cbbe49db9b0976dcab604aa0ec72abb8bca9cef253662a63e0629f537d71e5ea6b3c4dbe2f926c5cf616d28a782e2e95ae0c2fd8b6196019e6aa0b6c9"} build_url:https://queue.taskcluster.net/v1/task/GCKSwIhdSfe5r7nzaY-zGg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011044 build_url: 'https://queue.taskcluster.net/v1/task/GCKSwIhdSfe5r7nzaY-zGg/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/08d59674b6ccb67d4f7a22d9dbb8ebb79eab75a0578d7a6a54c0c7cd9f82156427d4229fe51db5a767b638c2bd28923d292edb48f4dc0b2d12110a730da2b2ce", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/29a59e91d7f64258a6cc6ae188fabf6b708ffa83a1595fa6805b7d125aad8a843d195042385d8bd262c44cf854384b95b6b362010ca8e77eb65b4552d155fcfd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d8881d46aa5adfc0a1bf54a224f9c4acf96e8780e7ac11cb1d94d906649023950c23dea41ec39b224df64cd94aec8b75f62e831425ba986787a80b661d65b2b9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/acebf92cbbe49db9b0976dcab604aa0ec72abb8bca9cef253662a63e0629f537d71e5ea6b3c4dbe2f926c5cf616d28a782e2e95ae0c2fd8b6196019e6aa0b6c9"}' ========= master_lag: 0.08 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-19 18:42:41.877721) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 18:42:41.878041) ========= 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.MKG4TKaMkh/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.zpkjQim7Qk/Listeners TMPDIR=/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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.005196 ========= master_lag: 0.51 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 18:42:42.395559) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-19 18:42:42.395861) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-19 18:42:42.396742) ========= ========= Total master_lag: 2.95 =========