builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0284 starttime: 1494983869.56 results: success (0) revision: 41958333867b0f537271dbd4cb4ba9e8a67a85a8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-16 18:17:49.563350) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-16 18:17:49.564091) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-16 18:17:49.564636) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-16 18:17:49.590085) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 18:17:49.590477) ========= 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.qtDUph9IMa/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.ZP0nZCrROJ/Listeners TMPDIR=/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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-16 18:17:49-- 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% 10.7M=0.001s 2017-05-16 18:17:49 (10.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.130346 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 18:17:49.743545) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 18:17:49.744107) ========= 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.qtDUph9IMa/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.ZP0nZCrROJ/Listeners TMPDIR=/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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.021898 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 18:17:49.794570) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-16 18:17:49.795197) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 41958333867b0f537271dbd4cb4ba9e8a67a85a8 --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 41958333867b0f537271dbd4cb4ba9e8a67a85a8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qtDUph9IMa/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.ZP0nZCrROJ/Listeners TMPDIR=/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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-16 18:17:49,876 truncating revision to first 12 chars 2017-05-16 18:17:49,876 Setting DEBUG logging. 2017-05-16 18:17:49,876 attempt 1/10 2017-05-16 18:17:49,876 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/41958333867b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-16 18:17:51,252 unpacking tar archive at: mozilla-central-41958333867b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.630872 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-16 18:17:51.447941) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-16 18:17:51.448279) ========= script_repo_revision: 41958333867b0f537271dbd4cb4ba9e8a67a85a8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-16 18:17:51.448668) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-16 18:17:51.448954) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-16 18:17:51.468084) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-05-16 18:17:51.468506) ========= /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.qtDUph9IMa/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.ZP0nZCrROJ/Listeners TMPDIR=/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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:17:51 INFO - MultiFileLogger online at 20170516 18:17:51 in /builds/slave/test 18:17:51 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:17:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:17:51 INFO - {'append_to_log': False, 18:17:51 INFO - 'base_work_dir': '/builds/slave/test', 18:17:51 INFO - 'blob_upload_branch': 'Firefox', 18:17:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:17:51 INFO - 'branch': 'Firefox', 18:17:51 INFO - 'buildbot_json_path': 'buildprops.json', 18:17:51 INFO - 'config_files': ('talos/mac_config.py',), 18:17:51 INFO - 'default_actions': ('clobber', 18:17:51 INFO - 'read-buildbot-config', 18:17:51 INFO - 'download-and-extract', 18:17:51 INFO - 'populate-webroot', 18:17:51 INFO - 'create-virtualenv', 18:17:51 INFO - 'install', 18:17:51 INFO - 'run-tests'), 18:17:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:17:51 INFO - 'download_minidump_stackwalk': True, 18:17:51 INFO - 'download_symbols': 'ondemand', 18:17:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:17:51 INFO - 'tooltool.py': '/tools/tooltool.py', 18:17:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:17:51 INFO - '/tools/misc-python/virtualenv.py')}, 18:17:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:17:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:17:51 INFO - 'gecko_profile': False, 18:17:51 INFO - 'gecko_profile_interval': 0, 18:17:51 INFO - 'installer_path': 'installer.exe', 18:17:51 INFO - 'log_level': 'info', 18:17:51 INFO - 'log_name': 'talos', 18:17:51 INFO - 'log_to_console': True, 18:17:51 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:17:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:17:51 INFO - 'opt_config_files': (), 18:17:51 INFO - 'pip_index': False, 18:17:51 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:17:51 INFO - 'cmd': ('bash', 18:17:51 INFO - '-c', 18:17:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:17:51 INFO - 'enabled': True, 18:17:51 INFO - 'halt_on_failure': False, 18:17:51 INFO - 'name': 'check_screen_resolution'},), 18:17:51 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:17:51 INFO - 'cmd': ('bash', 18:17:51 INFO - '-c', 18:17:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:17:51 INFO - 'enabled': True, 18:17:51 INFO - 'halt_on_failure': False, 18:17:51 INFO - 'name': 'check_screen_resolution'},), 18:17:51 INFO - 'run_cmd_checks_enabled': True, 18:17:51 INFO - 'suite': 'dromaeojs-e10s', 18:17:51 INFO - 'system_bits': '32', 18:17:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:17:51 INFO - 'title': 't-yosemite-r7-0284', 18:17:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:17:51 INFO - 'use_talos_json': True, 18:17:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:17:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:17:51 INFO - 'work_dir': 'build'} 18:17:51 INFO - [mozharness: 2017-05-17 01:17:51.673120Z] Running clobber step. 18:17:51 INFO - Running pre-action listener: _resource_record_pre_action 18:17:51 INFO - Running main action method: clobber 18:17:51 INFO - rmtree: /builds/slave/test/build 18:17:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:17:52 INFO - Running post-action listener: _resource_record_post_action 18:17:52 INFO - [mozharness: 2017-05-17 01:17:52.038892Z] Finished clobber step (success) 18:17:52 INFO - [mozharness: 2017-05-17 01:17:52.039089Z] Running read-buildbot-config step. 18:17:52 INFO - Running pre-action listener: _resource_record_pre_action 18:17:52 INFO - Running main action method: read_buildbot_config 18:17:52 INFO - Using buildbot properties: 18:17:52 INFO - { 18:17:52 INFO - "project": "", 18:17:52 INFO - "product": "firefox", 18:17:52 INFO - "who": "ryanvm@gmail.com", 18:17:52 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 18:17:52 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 18:17:52 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 18:17:52 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 18:17:52 INFO - "stage_platform": "macosx64", 18:17:52 INFO - "basedir": "/builds/slave/test", 18:17:52 INFO - "buildnumber": 74, 18:17:52 INFO - "platform": "yosemite_r7", 18:17:52 INFO - "parent_task_id": "Yv-zrmFaTEyagzyZpkfM_w", 18:17:52 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 18:17:52 INFO - "slavebuilddir": "test", 18:17:52 INFO - "taskId": "AJQ75utTRRm7a2AlEsDrHQ", 18:17:52 INFO - "branch": "mozilla-central", 18:17:52 INFO - "script_repo_revision": "production", 18:17:52 INFO - "revision": "41958333867b0f537271dbd4cb4ba9e8a67a85a8", 18:17:52 INFO - "slavename": "t-yosemite-r7-0284", 18:17:52 INFO - "repo_path": "mozilla-central" 18:17:52 INFO - } 18:17:52 INFO - Finding installer, test and symbols from parent task. 18:17:52 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AJQ75utTRRm7a2AlEsDrHQ'}, attempt #1 18:17:52 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AJQ75utTRRm7a2AlEsDrHQ'}, attempt #1 18:17:52 INFO - Task dependencies: b310qUcoTCGdaRlfZjjmMw 18:17:52 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Yv-zrmFaTEyagzyZpkfM_w'}, attempt #1 18:17:53 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Yv-zrmFaTEyagzyZpkfM_w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:17:53 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Yv-zrmFaTEyagzyZpkfM_w/artifacts/public/build/test_packages.json 18:17:53 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Yv-zrmFaTEyagzyZpkfM_w/artifacts/public/build/target.crashreporter-symbols.zip 18:17:53 INFO - Running post-action listener: _resource_record_post_action 18:17:53 INFO - [mozharness: 2017-05-17 01:17:53.411653Z] Finished read-buildbot-config step (success) 18:17:53 INFO - [mozharness: 2017-05-17 01:17:53.411749Z] Running download-and-extract step. 18:17:53 INFO - Running pre-action listener: _resource_record_pre_action 18:17:53 INFO - Running main action method: download_and_extract 18:17:53 INFO - mkdir: /builds/slave/test/build/tests 18:17:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:17:53 INFO - trying https://queue.taskcluster.net/v1/task/Yv-zrmFaTEyagzyZpkfM_w/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 18:17:53 INFO - Downloading https://queue.taskcluster.net/v1/task/Yv-zrmFaTEyagzyZpkfM_w/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:17:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Yv-zrmFaTEyagzyZpkfM_w/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:17:53 INFO - Downloaded 1325 bytes. 18:17:53 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 18:17:53 INFO - Using the following test package requirements: 18:17:53 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:17:53 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 18:17:53 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:17:53 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:17:53 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 18:17:53 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:17:53 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 18:17:53 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:17:53 INFO - u'jsshell-mac.zip'], 18:17:53 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:17:53 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 18:17:53 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:17:53 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:17:53 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 18:17:53 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:17:53 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 18:17:53 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:17:53 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 18:17:53 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:17:53 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 18:17:53 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:17:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Yv-zrmFaTEyagzyZpkfM_w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:17:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Yv-zrmFaTEyagzyZpkfM_w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:17:53 INFO - Fetch https://queue.taskcluster.net/v1/task/Yv-zrmFaTEyagzyZpkfM_w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:17:55 INFO - Content-Length response header: 56566192 18:17:55 INFO - Bytes received: 56566192 18:18:13 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:18:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Yv-zrmFaTEyagzyZpkfM_w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:18:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Yv-zrmFaTEyagzyZpkfM_w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:18:13 INFO - Fetch https://queue.taskcluster.net/v1/task/Yv-zrmFaTEyagzyZpkfM_w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:18:15 INFO - Content-Length response header: 56566192 18:18:15 INFO - Bytes received: 56566192 18:18:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Yv-zrmFaTEyagzyZpkfM_w/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 18:18:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Yv-zrmFaTEyagzyZpkfM_w/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:18:32 INFO - Fetch https://queue.taskcluster.net/v1/task/Yv-zrmFaTEyagzyZpkfM_w/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 18:18:32 INFO - Content-Length response header: 14410069 18:18:32 INFO - Bytes received: 14410069 18:18:33 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:18:33 INFO - trying https://queue.taskcluster.net/v1/task/Yv-zrmFaTEyagzyZpkfM_w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:18:33 INFO - Downloading https://queue.taskcluster.net/v1/task/Yv-zrmFaTEyagzyZpkfM_w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 18:18:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Yv-zrmFaTEyagzyZpkfM_w/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:18:35 INFO - Downloaded 75953154 bytes. 18:18:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Yv-zrmFaTEyagzyZpkfM_w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:18:35 INFO - mkdir: /builds/slave/test/properties 18:18:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:18:35 INFO - Writing to file /builds/slave/test/properties/build_url 18:18:35 INFO - Contents: 18:18:35 INFO - build_url:https://queue.taskcluster.net/v1/task/Yv-zrmFaTEyagzyZpkfM_w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:18:35 INFO - Running post-action listener: _resource_record_post_action 18:18:35 INFO - Running post-action listener: set_extra_try_arguments 18:18:35 INFO - [mozharness: 2017-05-17 01:18:35.944511Z] Finished download-and-extract step (success) 18:18:35 INFO - [mozharness: 2017-05-17 01:18:35.944679Z] Running populate-webroot step. 18:18:35 INFO - Running pre-action listener: _resource_record_pre_action 18:18:35 INFO - Running main action method: populate_webroot 18:18:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:18:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:18:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:18:35 INFO - '--tppagecycles', 18:18:35 INFO - '7'], 18:18:35 INFO - 'tests': ['tsvgm']}}, 18:18:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:18:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:18:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:18:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:18:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:18:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:18:35 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:18:35 INFO - 'talos_options': ['--disable-e10s'], 18:18:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:18:35 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:18:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:18:35 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:18:35 INFO - 'talos_options': ['--disable-e10s'], 18:18:35 INFO - 'tests': ['damp', 'tps']}, 18:18:35 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:18:35 INFO - 'tests': ['damp', 'tps']}, 18:18:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:18:35 INFO - 'tests': ['dromaeo_dom']}, 18:18:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:18:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:18:35 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 18:18:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:18:35 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:18:35 INFO - 'tests': ['ts_paint', 'tp5o']}, 18:18:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:18:35 INFO - 'tests': ['a11yr', 18:18:35 INFO - 'ts_paint', 18:18:35 INFO - 'tpaint', 18:18:35 INFO - 'sessionrestore', 18:18:35 INFO - 'sessionrestore_no_auto_restore', 18:18:35 INFO - 'tabpaint']}, 18:18:35 INFO - 'other-e10s': {'tests': ['a11yr', 18:18:35 INFO - 'ts_paint', 18:18:35 INFO - 'tpaint', 18:18:35 INFO - 'sessionrestore', 18:18:35 INFO - 'sessionrestore_no_auto_restore', 18:18:35 INFO - 'tabpaint']}, 18:18:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:18:35 INFO - 'ts_paint', 18:18:35 INFO - 'tpaint', 18:18:35 INFO - 'sessionrestore', 18:18:35 INFO - 'sessionrestore_no_auto_restore', 18:18:35 INFO - 'tabpaint']}, 18:18:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:18:35 INFO - 'ts_paint', 18:18:35 INFO - 'tpaint', 18:18:35 INFO - 'sessionrestore', 18:18:35 INFO - 'sessionrestore_no_auto_restore', 18:18:35 INFO - 'tabpaint']}, 18:18:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:18:35 INFO - 'tests': ['a11yr', 18:18:35 INFO - 'ts_paint', 18:18:35 INFO - 'tpaint', 18:18:35 INFO - 'sessionrestore', 18:18:35 INFO - 'sessionrestore_no_auto_restore', 18:18:35 INFO - 'tabpaint']}, 18:18:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:18:35 INFO - 'tests': ['a11yr', 18:18:35 INFO - 'ts_paint', 18:18:35 INFO - 'tpaint', 18:18:35 INFO - 'sessionrestore', 18:18:35 INFO - 'sessionrestore_no_auto_restore', 18:18:35 INFO - 'tabpaint']}, 18:18:35 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 18:18:35 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 18:18:35 INFO - 'bloom_basic_ref']}, 18:18:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:18:35 INFO - 'tests': ['tsvgx', 18:18:35 INFO - 'tsvgr_opacity', 18:18:35 INFO - 'tart', 18:18:35 INFO - 'tscrollx', 18:18:35 INFO - 'cart', 18:18:35 INFO - 'tsvg_static']}, 18:18:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:18:35 INFO - 'tsvgr_opacity', 18:18:35 INFO - 'tart', 18:18:35 INFO - 'tscrollx', 18:18:35 INFO - 'cart', 18:18:35 INFO - 'tsvg_static']}, 18:18:35 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:18:35 INFO - 'talos_options': ['--disable-e10s'], 18:18:35 INFO - 'tests': ['tp5o']}, 18:18:35 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:18:35 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:18:35 INFO - 'talos_options': ['--disable-e10s', 18:18:35 INFO - '--xperf_path', 18:18:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:18:35 INFO - 'tests': ['tp5n']}, 18:18:35 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:18:35 INFO - 'talos_options': ['--xperf_path', 18:18:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:18:35 INFO - 'tests': ['tp5n']}}, 18:18:35 INFO - 'talos.zip': {'path': '', 18:18:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:18:35 INFO - Running post-action listener: _resource_record_post_action 18:18:35 INFO - [mozharness: 2017-05-17 01:18:35.966301Z] Finished populate-webroot step (success) 18:18:35 INFO - [mozharness: 2017-05-17 01:18:35.966438Z] Running create-virtualenv step. 18:18:35 INFO - Running pre-action listener: _resource_record_pre_action 18:18:35 INFO - Running main action method: create_virtualenv 18:18:35 INFO - Creating virtualenv /builds/slave/test/build/venv 18:18:35 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:18:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:18:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:18:36 INFO - Using real prefix '/tools/python27' 18:18:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:18:36 INFO - Installing distribute.............................................................................................................................................................................................done. 18:18:39 INFO - Installing pip.................done. 18:18:39 INFO - Return code: 0 18:18:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:18:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:18:39 INFO - Reading from file tmpfile_stdout 18:18:39 INFO - Output received: 18:18:39 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:18:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:18:39 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:18:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:39 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:18:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:39 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 0x10d1aab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1ae768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd750563c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cebad30>, '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 0x10ce6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ceafbe8>, '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.qtDUph9IMa/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.ZP0nZCrROJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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:18:39 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:18:39 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:18:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qtDUph9IMa/Render', 18:18:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:18:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:18:39 INFO - 'HOME': '/Users/cltbld', 18:18:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:18:39 INFO - 'LOGNAME': 'cltbld', 18:18:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:39 INFO - 'MOZ_NO_REMOTE': '1', 18:18:39 INFO - 'NO_EM_RESTART': '1', 18:18:39 INFO - 'PAGER': '/bin/cat', 18:18:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:18:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:39 INFO - 'PWD': '/builds/slave/test', 18:18:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:18:39 INFO - 'SHELL': '/bin/bash', 18:18:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZP0nZCrROJ/Listeners', 18:18:39 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 18:18:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:18:39 INFO - 'USER': 'cltbld', 18:18:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:18:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:18:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:39 INFO - 'XPC_FLAGS': '0x0', 18:18:39 INFO - 'XPC_SERVICE_NAME': '0', 18:18:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:18:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:40 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:18:40 INFO - Cleaning up... 18:18:40 INFO - Return code: 0 18:18:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:18:40 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:18:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:40 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:18:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:40 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 0x10d1aab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1ae768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd750563c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cebad30>, '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 0x10ce6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ceafbe8>, '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.qtDUph9IMa/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.ZP0nZCrROJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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:18:40 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:18:40 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:18:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qtDUph9IMa/Render', 18:18:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:18:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:18:40 INFO - 'HOME': '/Users/cltbld', 18:18:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:18:40 INFO - 'LOGNAME': 'cltbld', 18:18:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:40 INFO - 'MOZ_NO_REMOTE': '1', 18:18:40 INFO - 'NO_EM_RESTART': '1', 18:18:40 INFO - 'PAGER': '/bin/cat', 18:18:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:18:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:40 INFO - 'PWD': '/builds/slave/test', 18:18:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:18:40 INFO - 'SHELL': '/bin/bash', 18:18:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZP0nZCrROJ/Listeners', 18:18:40 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 18:18:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:18:40 INFO - 'USER': 'cltbld', 18:18:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:18:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:18:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:40 INFO - 'XPC_FLAGS': '0x0', 18:18:40 INFO - 'XPC_SERVICE_NAME': '0', 18:18:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:18:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:40 INFO - Downloading/unpacking psutil>=3.1.1 18:18:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:18:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:18:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:18:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:18:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:18:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:18:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:18:42 INFO - Installing collected packages: psutil 18:18:42 INFO - Running setup.py install for psutil 18:18:42 INFO - building 'psutil._psutil_osx' extension 18:18:42 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:18:42 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:18:42 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:18:42 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:18:42 INFO - building 'psutil._psutil_posix' extension 18:18:42 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:18:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:18:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:18:43 INFO - ^ 18:18:43 INFO - 1 warning generated. 18:18:43 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:18:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:18:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:18:43 INFO - Successfully installed psutil 18:18:43 INFO - Cleaning up... 18:18:43 INFO - Return code: 0 18:18:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:18:43 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:18:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:43 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:18:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:43 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 0x10d1aab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1ae768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd750563c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cebad30>, '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 0x10ce6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ceafbe8>, '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.qtDUph9IMa/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.ZP0nZCrROJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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:18:43 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:18:43 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:18:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qtDUph9IMa/Render', 18:18:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:18:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:18:43 INFO - 'HOME': '/Users/cltbld', 18:18:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:18:43 INFO - 'LOGNAME': 'cltbld', 18:18:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:43 INFO - 'MOZ_NO_REMOTE': '1', 18:18:43 INFO - 'NO_EM_RESTART': '1', 18:18:43 INFO - 'PAGER': '/bin/cat', 18:18:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:18:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:43 INFO - 'PWD': '/builds/slave/test', 18:18:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:18:43 INFO - 'SHELL': '/bin/bash', 18:18:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZP0nZCrROJ/Listeners', 18:18:43 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 18:18:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:18:43 INFO - 'USER': 'cltbld', 18:18:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:18:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:18:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:43 INFO - 'XPC_FLAGS': '0x0', 18:18:43 INFO - 'XPC_SERVICE_NAME': '0', 18:18:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:18:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:18:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:18:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:18:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:18:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:18:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:18:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:18:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:18:45 INFO - Installing collected packages: mozsystemmonitor 18:18:45 INFO - Running setup.py install for mozsystemmonitor 18:18:45 INFO - Successfully installed mozsystemmonitor 18:18:45 INFO - Cleaning up... 18:18:45 INFO - Return code: 0 18:18:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:18:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:18:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:18:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:45 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 0x10d1aab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1ae768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd750563c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cebad30>, '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 0x10ce6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ceafbe8>, '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.qtDUph9IMa/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.ZP0nZCrROJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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:18:45 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:18:45 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:18:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qtDUph9IMa/Render', 18:18:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:18:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:18:45 INFO - 'HOME': '/Users/cltbld', 18:18:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:18:45 INFO - 'LOGNAME': 'cltbld', 18:18:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:45 INFO - 'MOZ_NO_REMOTE': '1', 18:18:45 INFO - 'NO_EM_RESTART': '1', 18:18:45 INFO - 'PAGER': '/bin/cat', 18:18:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:18:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:45 INFO - 'PWD': '/builds/slave/test', 18:18:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:18:45 INFO - 'SHELL': '/bin/bash', 18:18:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZP0nZCrROJ/Listeners', 18:18:45 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 18:18:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:18:45 INFO - 'USER': 'cltbld', 18:18:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:18:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:18:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:45 INFO - 'XPC_FLAGS': '0x0', 18:18:45 INFO - 'XPC_SERVICE_NAME': '0', 18:18:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:18:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:45 INFO - Downloading/unpacking jsonschema==2.5.1 18:18:45 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:18:45 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:18:45 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:18:45 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:18:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:18:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:18:46 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:18:46 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:18:46 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:18:46 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:18:47 INFO - Downloading functools32-3.2.3-2.tar.gz 18:18:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:18:47 INFO - warning: no files found matching '*.txt' 18:18:47 INFO - no previously-included directories found matching 'build' 18:18:47 INFO - no previously-included directories found matching 'dist' 18:18:47 INFO - no previously-included directories found matching '.git*' 18:18:47 INFO - Installing collected packages: jsonschema, functools32 18:18:47 INFO - Running setup.py install for functools32 18:18:47 INFO - warning: no files found matching '*.txt' 18:18:47 INFO - no previously-included directories found matching 'build' 18:18:47 INFO - no previously-included directories found matching 'dist' 18:18:47 INFO - no previously-included directories found matching '.git*' 18:18:47 INFO - Successfully installed jsonschema functools32 18:18:47 INFO - Cleaning up... 18:18:47 INFO - Return code: 0 18:18:47 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:18:47 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:18:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:47 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:18:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:48 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 0x10d1aab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1ae768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd750563c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cebad30>, '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 0x10ce6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ceafbe8>, '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.qtDUph9IMa/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.ZP0nZCrROJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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:18:48 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:18:48 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:18:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qtDUph9IMa/Render', 18:18:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:18:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:18:48 INFO - 'HOME': '/Users/cltbld', 18:18:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:18:48 INFO - 'LOGNAME': 'cltbld', 18:18:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:48 INFO - 'MOZ_NO_REMOTE': '1', 18:18:48 INFO - 'NO_EM_RESTART': '1', 18:18:48 INFO - 'PAGER': '/bin/cat', 18:18:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:18:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:48 INFO - 'PWD': '/builds/slave/test', 18:18:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:18:48 INFO - 'SHELL': '/bin/bash', 18:18:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZP0nZCrROJ/Listeners', 18:18:48 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 18:18:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:18:48 INFO - 'USER': 'cltbld', 18:18:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:18:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:18:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:48 INFO - 'XPC_FLAGS': '0x0', 18:18:48 INFO - 'XPC_SERVICE_NAME': '0', 18:18:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:18:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:48 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:18:48 INFO - Cleaning up... 18:18:48 INFO - Return code: 0 18:18:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:18:48 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:18:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:48 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:18:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:48 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 0x10d1aab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1ae768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd750563c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cebad30>, '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 0x10ce6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ceafbe8>, '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.qtDUph9IMa/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.ZP0nZCrROJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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:18:48 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:18:48 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:18:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qtDUph9IMa/Render', 18:18:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:18:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:18:48 INFO - 'HOME': '/Users/cltbld', 18:18:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:18:48 INFO - 'LOGNAME': 'cltbld', 18:18:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:48 INFO - 'MOZ_NO_REMOTE': '1', 18:18:48 INFO - 'NO_EM_RESTART': '1', 18:18:48 INFO - 'PAGER': '/bin/cat', 18:18:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:18:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:48 INFO - 'PWD': '/builds/slave/test', 18:18:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:18:48 INFO - 'SHELL': '/bin/bash', 18:18:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZP0nZCrROJ/Listeners', 18:18:48 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 18:18:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:18:48 INFO - 'USER': 'cltbld', 18:18:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:18:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:18:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:48 INFO - 'XPC_FLAGS': '0x0', 18:18:48 INFO - 'XPC_SERVICE_NAME': '0', 18:18:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:18:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:49 INFO - Downloading/unpacking blobuploader==1.2.4 18:18:49 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:18:49 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:18:49 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:18:49 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:18:50 INFO - Downloading blobuploader-1.2.4.tar.gz 18:18:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:18:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:18:50 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:18:50 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:18:50 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:18:50 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:18:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:18:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:18:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:18:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:18:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:18:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:18:51 INFO - Downloading docopt-0.6.1.tar.gz 18:18:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:18:51 INFO - Installing collected packages: blobuploader, requests, docopt 18:18:51 INFO - Running setup.py install for blobuploader 18:18:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:18:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:18:51 INFO - Running setup.py install for requests 18:18:52 INFO - Running setup.py install for docopt 18:18:52 INFO - Successfully installed blobuploader requests docopt 18:18:52 INFO - Cleaning up... 18:18:52 INFO - Return code: 0 18:18:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:18:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:18:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:18:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:52 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 0x10d1aab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1ae768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd750563c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cebad30>, '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 0x10ce6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ceafbe8>, '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.qtDUph9IMa/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.ZP0nZCrROJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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:18:52 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:18:52 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:18:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qtDUph9IMa/Render', 18:18:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:18:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:18:52 INFO - 'HOME': '/Users/cltbld', 18:18:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:18:52 INFO - 'LOGNAME': 'cltbld', 18:18:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:52 INFO - 'MOZ_NO_REMOTE': '1', 18:18:52 INFO - 'NO_EM_RESTART': '1', 18:18:52 INFO - 'PAGER': '/bin/cat', 18:18:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:18:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:52 INFO - 'PWD': '/builds/slave/test', 18:18:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:18:52 INFO - 'SHELL': '/bin/bash', 18:18:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZP0nZCrROJ/Listeners', 18:18:52 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 18:18:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:18:52 INFO - 'USER': 'cltbld', 18:18:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:18:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:18:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:52 INFO - 'XPC_FLAGS': '0x0', 18:18:52 INFO - 'XPC_SERVICE_NAME': '0', 18:18:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:18:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:18:52 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-VrOHWA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:18:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:18:52 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-OQbU7Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:18:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:18:52 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-SjbMsB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:18:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:18:52 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-35MNkl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:18:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:18:53 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-JKQoGK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:18:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:18:53 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-RfJS28-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:18:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:18:53 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-Q0zVL3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:18:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:18:53 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-o3DIw3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:18:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:18:53 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-Drfos1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:18:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:18:53 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-hBZdJ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:18:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:18:53 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-x16i5C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:18:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:18:53 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-tS3t2X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:18:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:18:53 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-txgnyU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:18:54 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-LOejdB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:18:54 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-UufzLh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:18:54 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-MmrNUy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:18:54 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-Xs2JgU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:18:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:18:54 INFO - Running setup.py install for manifestparser 18:18:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:18:54 INFO - Running setup.py install for mozcrash 18:18:54 INFO - Running setup.py install for mozdebug 18:18:54 INFO - Running setup.py install for mozdevice 18:18:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:18:55 INFO - Running setup.py install for mozfile 18:18:55 INFO - Running setup.py install for mozhttpd 18:18:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:18:55 INFO - Running setup.py install for mozinfo 18:18:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:18:55 INFO - Running setup.py install for mozInstall 18:18:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:18:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:18:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:18:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:18:55 INFO - Running setup.py install for mozleak 18:18:55 INFO - Running setup.py install for mozlog 18:18:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:18:55 INFO - Running setup.py install for moznetwork 18:18:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:18:56 INFO - Running setup.py install for mozprocess 18:18:56 INFO - Running setup.py install for mozprofile 18:18:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:18:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:18:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:18:56 INFO - Running setup.py install for mozrunner 18:18:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:18:56 INFO - Running setup.py install for mozscreenshot 18:18:56 INFO - Running setup.py install for moztest 18:18:56 INFO - Running setup.py install for mozversion 18:18:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:18:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:18:56 INFO - Cleaning up... 18:18:57 INFO - Return code: 0 18:18:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:18: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:18:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18: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:18:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:57 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 0x10d1aab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1ae768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd750563c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cebad30>, '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 0x10ce6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ceafbe8>, '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.qtDUph9IMa/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.ZP0nZCrROJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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:18:57 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:18:57 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:18:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qtDUph9IMa/Render', 18:18:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:18:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:18:57 INFO - 'HOME': '/Users/cltbld', 18:18:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:18:57 INFO - 'LOGNAME': 'cltbld', 18:18:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:57 INFO - 'MOZ_NO_REMOTE': '1', 18:18:57 INFO - 'NO_EM_RESTART': '1', 18:18:57 INFO - 'PAGER': '/bin/cat', 18:18:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:18:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:57 INFO - 'PWD': '/builds/slave/test', 18:18:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:18:57 INFO - 'SHELL': '/bin/bash', 18:18:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZP0nZCrROJ/Listeners', 18:18:57 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 18:18:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:18:57 INFO - 'USER': 'cltbld', 18:18:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:18:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:18:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:57 INFO - 'XPC_FLAGS': '0x0', 18:18:57 INFO - 'XPC_SERVICE_NAME': '0', 18:18:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:18:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:18:57 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-jhwrzi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:18:57 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:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:18:57 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-9_BrwX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:18:57 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:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:18:57 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-WduvW5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:18:57 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:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:18:57 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-Qzmbf7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:18:57 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:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:18:57 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-XpEei5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:18:57 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:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:18:57 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-qjeS30-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:18:57 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:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:18:57 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-OnHO9D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:18:57 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:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:18:57 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-dsVRm9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:18:58 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:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:18:58 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-gefvqw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:18:58 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:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:18:58 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-XkcPnB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:18:58 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:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:18:58 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-bUDbbW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:18:58 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:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:18:58 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-_CFwFY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:18:58 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:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:18:58 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-uOuQ_y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:18:58 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:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:18:58 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-touFb2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:18:58 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:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:18:58 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-yu3OCH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:18:58 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:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:18:58 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-7b9cHb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:18:58 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:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:18:58 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-wZQX1C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:18:59 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:18:59 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:18:59 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:18:59 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:18:59 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:18:59 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:18:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:18:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:18:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:18:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:18:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:19:00 INFO - Downloading blessings-1.6.tar.gz 18:19:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:19:00 INFO - Installing collected packages: blessings 18:19:00 INFO - Running setup.py install for blessings 18:19:00 INFO - Successfully installed blessings 18:19:00 INFO - Cleaning up... 18:19:00 INFO - Return code: 0 18:19:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:19:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:19:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:19:01 INFO - Reading from file tmpfile_stdout 18:19:01 INFO - Current package versions: 18:19:01 INFO - blessings == 1.6 18:19:01 INFO - blobuploader == 1.2.4 18:19:01 INFO - docopt == 0.6.1 18:19:01 INFO - functools32 == 3.2.3-2 18:19:01 INFO - jsonschema == 2.5.1 18:19:01 INFO - manifestparser == 1.1 18:19:01 INFO - mozInstall == 1.12 18:19:01 INFO - mozcrash == 1.0 18:19:01 INFO - mozdebug == 0.1 18:19:01 INFO - mozdevice == 0.50 18:19:01 INFO - mozfile == 1.2 18:19:01 INFO - mozhttpd == 0.7 18:19:01 INFO - mozinfo == 0.9 18:19:01 INFO - mozleak == 0.1 18:19:01 INFO - mozlog == 3.4 18:19:01 INFO - moznetwork == 0.27 18:19:01 INFO - mozprocess == 0.25 18:19:01 INFO - mozprofile == 0.28 18:19:01 INFO - mozrunner == 6.13 18:19:01 INFO - mozscreenshot == 0.1 18:19:01 INFO - mozsystemmonitor == 0.3 18:19:01 INFO - moztest == 0.8 18:19:01 INFO - mozversion == 1.4 18:19:01 INFO - psutil == 3.1.1 18:19:01 INFO - requests == 1.2.3 18:19:01 INFO - wsgiref == 0.1.2 18:19:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:19:01 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:19:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:19:01 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:19:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:19:01 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 0x10d1aab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1ae768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd750563c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cebad30>, '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 0x10ce6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ceafbe8>, '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.qtDUph9IMa/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.ZP0nZCrROJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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:19:01 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:19:01 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:19:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qtDUph9IMa/Render', 18:19:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:19:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:19:01 INFO - 'HOME': '/Users/cltbld', 18:19:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:19:01 INFO - 'LOGNAME': 'cltbld', 18:19:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:19:01 INFO - 'MOZ_NO_REMOTE': '1', 18:19:01 INFO - 'NO_EM_RESTART': '1', 18:19:01 INFO - 'PAGER': '/bin/cat', 18:19:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:19:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:19:01 INFO - 'PWD': '/builds/slave/test', 18:19:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:19:01 INFO - 'SHELL': '/bin/bash', 18:19:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZP0nZCrROJ/Listeners', 18:19:01 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 18:19:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:19:01 INFO - 'USER': 'cltbld', 18:19:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:19:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:19:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:19:01 INFO - 'XPC_FLAGS': '0x0', 18:19:01 INFO - 'XPC_SERVICE_NAME': '0', 18:19:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:19:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:19:01 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:19:01 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:19:01 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:19:01 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:19:01 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:19:01 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:19:01 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:19:01 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:19:01 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:19:01 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:19:01 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:19:01 INFO - Cleaning up... 18:19:01 INFO - Return code: 0 18:19:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:19:01 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:19:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:19:01 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:19:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:19:01 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 0x10d1aab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1ae768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd750563c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cebad30>, '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 0x10ce6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ceafbe8>, '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.qtDUph9IMa/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.ZP0nZCrROJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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:19:01 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:19:01 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:19:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qtDUph9IMa/Render', 18:19:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:19:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:19:01 INFO - 'HOME': '/Users/cltbld', 18:19:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:19:01 INFO - 'LOGNAME': 'cltbld', 18:19:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:19:01 INFO - 'MOZ_NO_REMOTE': '1', 18:19:01 INFO - 'NO_EM_RESTART': '1', 18:19:01 INFO - 'PAGER': '/bin/cat', 18:19:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:19:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:19:01 INFO - 'PWD': '/builds/slave/test', 18:19:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:19:01 INFO - 'SHELL': '/bin/bash', 18:19:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZP0nZCrROJ/Listeners', 18:19:01 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 18:19:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:19:01 INFO - 'USER': 'cltbld', 18:19:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:19:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:19:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:19:01 INFO - 'XPC_FLAGS': '0x0', 18:19:01 INFO - 'XPC_SERVICE_NAME': '0', 18:19:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:19:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:19:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:19:01 INFO - Cleaning up... 18:19:01 INFO - Return code: 0 18:19:01 INFO - Running post-action listener: _resource_record_post_action 18:19:01 INFO - Running post-action listener: _start_resource_monitoring 18:19:01 INFO - Starting resource monitoring. 18:19:01 INFO - [mozharness: 2017-05-17 01:19:01.605129Z] Finished create-virtualenv step (success) 18:19:01 INFO - [mozharness: 2017-05-17 01:19:01.606119Z] Running install step. 18:19:01 INFO - Running pre-action listener: _resource_record_pre_action 18:19:01 INFO - Running main action method: install 18:19:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:19:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:19:01 INFO - Reading from file tmpfile_stdout 18:19:01 INFO - Detecting whether we're running mozinstall >=1.0... 18:19:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:19:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:19:01 INFO - Reading from file tmpfile_stdout 18:19:01 INFO - Output received: 18:19:01 INFO - Usage: mozinstall [options] installer 18:19:01 INFO - Options: 18:19:01 INFO - -h, --help show this help message and exit 18:19:01 INFO - -d DEST, --destination=DEST 18:19:01 INFO - Directory to install application into. [default: 18:19:01 INFO - "/builds/slave/test"] 18:19:01 INFO - --app=APP Application being installed. [default: firefox] 18:19:01 INFO - mkdir: /builds/slave/test/build/application 18:19:01 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:19:01 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:19:23 INFO - Reading from file tmpfile_stdout 18:19:23 INFO - Output received: 18:19:23 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:19:23 INFO - Running post-action listener: _resource_record_post_action 18:19:23 INFO - [mozharness: 2017-05-17 01:19:23.216772Z] Finished install step (success) 18:19:23 INFO - [mozharness: 2017-05-17 01:19:23.216914Z] Running run-tests step. 18:19:23 INFO - Running pre-action listener: _resource_record_pre_action 18:19:23 INFO - Running main action method: run_tests 18:19:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:19:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:19:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:19:23 INFO - 2017-05-16 18:19:23.232 screenresolution[1996:20077] starting screenresolution argv=screenresolution get 18:19:23 INFO - 2017-05-16 18:19:23.235 screenresolution[1996:20077] Display 0: 1600x1200x32@60 18:19:23 INFO - 2017-05-16 18:19:23.241 screenresolution[1997:20079] starting screenresolution argv=screenresolution list 18:19:23 INFO - Available Modes on Display 0 18:19:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:19:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:19:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:19:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:19:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:19:23 INFO - Intel Iris: 18:19:23 INFO - Chipset Model: Intel Iris 18:19:23 INFO - Type: GPU 18:19:23 INFO - Bus: Built-In 18:19:23 INFO - VRAM (Dynamic, Max): 1536 MB 18:19:23 INFO - Vendor: Intel (0x8086) 18:19:23 INFO - Device ID: 0x0a2e 18:19:23 INFO - Revision ID: 0x0009 18:19:23 INFO - Displays: 18:19:23 INFO - Display: 18:19:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:19:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:19:23 INFO - Display Serial Number: 1600x1200 60 18:19:23 INFO - Main Display: Yes 18:19:23 INFO - Mirror: Off 18:19:23 INFO - Online: Yes 18:19:23 INFO - Rotation: Supported 18:19:23 INFO - Return code: 0 18:19:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:19:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:19:23 INFO - Python 2.7.3 18:19:23 INFO - Return code: 0 18:19:23 INFO - grabbing minidump binary from tooltool 18:19:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:19:23 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 0x10cebad30>, '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 0x10ce6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ceafbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:19:23 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:19:23 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:19:23 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:19:23 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:19:23 INFO - Return code: 0 18:19:23 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:19:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:19:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:19:23 INFO - ENV: RUST_BACKTRACE is now 1 18:19:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:19:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:19:23 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/Yv-zrmFaTEyagzyZpkfM_w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0284', '--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:19:23 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/Yv-zrmFaTEyagzyZpkfM_w/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0284 --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:19:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qtDUph9IMa/Render', 18:19:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:19:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:19:23 INFO - 'HOME': '/Users/cltbld', 18:19:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:19:23 INFO - 'LOGNAME': 'cltbld', 18:19:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:19:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:19:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:19:23 INFO - 'MOZ_NO_REMOTE': '1', 18:19:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:19:23 INFO - 'NO_EM_RESTART': '1', 18:19:23 INFO - 'PAGER': '/bin/cat', 18:19:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:19:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:19:23 INFO - 'PWD': '/builds/slave/test', 18:19:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:19:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:19:23 INFO - 'RUST_BACKTRACE': '1', 18:19:23 INFO - 'SHELL': '/bin/bash', 18:19:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZP0nZCrROJ/Listeners', 18:19:23 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 18:19:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:19:23 INFO - 'USER': 'cltbld', 18:19:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:19:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:19:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:19:23 INFO - 'XPC_FLAGS': '0x0', 18:19:23 INFO - 'XPC_SERVICE_NAME': '0', 18:19:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:19:23 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/Yv-zrmFaTEyagzyZpkfM_w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0284', '--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:19:23 INFO - mozversion application_buildid: 20170516171325 18:19:23 INFO - mozversion application_changeset: 41958333867b0f537271dbd4cb4ba9e8a67a85a8 18:19:23 INFO - mozversion application_display_name: Nightly 18:19:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:19:23 INFO - mozversion application_name: Firefox 18:19:23 INFO - mozversion application_remotingname: firefox 18:19:23 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:19:23 INFO - mozversion application_vendor: Mozilla 18:19:23 INFO - mozversion application_version: 55.0a1 18:19:23 INFO - mozversion platform_buildid: 20170516171325 18:19:23 INFO - mozversion platform_changeset: 41958333867b0f537271dbd4cb4ba9e8a67a85a8 18:19:23 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:19:23 INFO - mozversion platform_version: 55.0a1 18:19:23 INFO - using testdate: 1494983963 18:19:23 INFO - actual date: 1494983963 18:19:23 INFO - starting webserver on 'localhost:49274' 18:19:23 INFO - SUITE-START | Running 2 tests 18:19:23 INFO - TEST-START | dromaeo_css 18:19:23 INFO - operating with platform_type : mac_ 18:19:23 INFO - Initialising browser for dromaeo_css test... 18:19:23 INFO - TEST-INFO | started process 2007 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/tmp9eqV3u/profile http://localhost:49274/getInfo.html) 18:19:24 INFO - PID 2007 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2930: String.localeCompare is deprecated; use String.prototype.localeCompare instead 18:19:26 INFO - PID 2007 | 2017-05-16 18:19:26.262 plugin-container[2009:20260] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x973f, name = 'com.apple.tsm.portname' 18:19:26 INFO - PID 2007 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:19:26 INFO - PID 2007 | 2017-05-16 18:19:26.265 plugin-container[2009:20260] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a2b, name = 'com.apple.CFPasteboardClient' 18:19:26 INFO - PID 2007 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:19:26 INFO - PID 2007 | 2017-05-16 18:19:26.266 plugin-container[2009:20260] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:19:26 INFO - PID 2007 | __metrics Screen width/height:1600/1200 18:19:26 INFO - PID 2007 | colorDepth:24 18:19:26 INFO - PID 2007 | Browser inner width/height: 1024/691 18:19:26 INFO - PID 2007 | __metrics 18:19:26 INFO - PID 2007 | 2017-05-16 18:19:26.938 plugin-container[2011:20326] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 18:19:26 INFO - PID 2007 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:19:26 INFO - PID 2007 | 2017-05-16 18:19:26.938 plugin-container[2011:20326] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9803, name = 'com.apple.CFPasteboardClient' 18:19:26 INFO - PID 2007 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:19:26 INFO - PID 2007 | 2017-05-16 18:19:26.938 plugin-container[2011:20326] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:19:27 INFO - TEST-INFO | 2007: exit 0 18:19:27 INFO - Browser initialized. 18:19:27 INFO - Running cycle 1/1 for dromaeo_css test... 18:19:27 INFO - TEST-INFO | started process 2012 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/tmp9eqV3u/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:19:29 INFO - PID 2012 | 2017-05-16 18:19:29.286 plugin-container[2013:20419] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434f, name = 'com.apple.tsm.portname' 18:19:29 INFO - PID 2012 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:19:29 INFO - PID 2012 | 2017-05-16 18:19:29.286 plugin-container[2013:20419] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980f, name = 'com.apple.CFPasteboardClient' 18:19:29 INFO - PID 2012 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:19:29 INFO - PID 2012 | 2017-05-16 18:19:29.286 plugin-container[2013:20419] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:19:29 INFO - PID 2012 | 2017-05-16 18:19:29.992 plugin-container[2014:20468] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4443, name = 'com.apple.tsm.portname' 18:19:29 INFO - PID 2012 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:19:29 INFO - PID 2012 | 2017-05-16 18:19:29.992 plugin-container[2014:20468] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x990b, name = 'com.apple.CFPasteboardClient' 18:19:29 INFO - PID 2012 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:19:29 INFO - PID 2012 | 2017-05-16 18:19:29.992 plugin-container[2014:20468] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:19:39 INFO - PID 2012 | RSS: Main: 234213376 18:19:39 INFO - PID 2012 | 18:22:43 INFO - PID 2012 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49274/tests/dromaeo/cssquery-ext.html) 18:22:44 INFO - PID 2012 | RSS: Main: 256679936 18:22:44 INFO - PID 2012 | 18:25:46 INFO - PID 2012 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-ext.html (next: http://localhost:49274/tests/dromaeo/cssquery-jquery.html) 18:25:47 INFO - PID 2012 | RSS: Main: 263749632 18:25:47 INFO - PID 2012 | 18:28:49 INFO - PID 2012 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49274/tests/dromaeo/cssquery-mootools.html) 18:28:49 INFO - PID 2012 | RSS: Main: 269066240 18:28:49 INFO - PID 2012 | 18:31:51 INFO - PID 2012 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49274/tests/dromaeo/cssquery-prototype.html) 18:31:52 INFO - PID 2012 | RSS: Main: 266715136 18:31:52 INFO - PID 2012 | 18:34:54 INFO - PID 2012 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49274/tests/dromaeo/cssquery-yui.html) 18:34:55 INFO - PID 2012 | RSS: Main: 268292096 18:34:55 INFO - PID 2012 | 18:37:57 INFO - PID 2012 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-yui.html (next: http://localhost:49274/tests/dromaeo/cssquery-dojo.html) 18:37:57 INFO - PID 2012 | RSS: Main: 269545472 18:37:57 INFO - PID 2012 | 18:37:57 INFO - PID 2012 | __start_tp_report 18:37:57 INFO - PID 2012 | _x_x_mozilla_page_load 18:37:57 INFO - PID 2012 | _x_x_mozilla_page_load_details 18:37:57 INFO - PID 2012 | |i|pagename|runs| 18:37:57 INFO - PID 2012 | |0;dojo.html;3557.98221008895;3594.1014746313404;3617.9688788695225;3625.7280703947213;3636.5817930937933;6139.645466941716;6174.660393678346;6193.7832175873855;6219.8134055978335;6225.221847269091;6187.876242475148;6191.226096737908;6195.163652906856;6239.34486878878;6277.2467303923595;5407.459254074601;5419.295491586088;5442.619016668835;5448.509634132927;5473.343198816127;4519.5932366087045;5607.831765047055;5608.10270356743;5654.321481422215;5715.3141623005295;5406.243125962368;5515.972420137914;5556.111022236463;5578.30271216098;5599.356074051475;4483.574060464251;5448.019356515825;5463.180522921554;5559.610827242094;5564.86087847803;5479.150731636602;5483.917741233885;5493.533049690781;5507.339119305669;5510.201020851994;5729.369769325371;6166;6257.468115210213;6288.276848162428;6295.5907865988565;6509.576877502995;6593.736250549972;6667.533272670915;6687.164104486939;6760.1549806274215;6822.351876571767;6838.589684618939;6850.588964662136;6851.109355783766;6863;6223.688815559204;6251.281102673205;6322.841928951813;6332.050192471166;6336;6747.05541224229;6879.862402751917;6908.689108990107;6996.475264355194;7016.649167541705;5204.880950595564;5693.17448969897;6140.969295153585;6585.407313341822;6619.834504137435;6544.869102618016;6755.2906944771075;6812;6817.250102488722;6846.4522838172825;6444.387783160592;6601.438877695354;6605.108310378134;6688.933110668929;6705.798826035227;5763.366029736726;6642.036904648799;6681.532292739462;6731.32686731323;6742.42689371409;5349.839504814861;5367.329083864517;5379.058664733656;5391.35303763543;5414.729263536808;4757.072370887713;4764.8332308369045;4802.303665968485;4809.687370320918;4839.564439200489;6336.58812177207;6374.362563743682;6394.392532709385;6401.55189136756;6427.068075129174;6775.559588626724;6775.830604234933;6839.897401538981;6841.589504629738;6854.2117656469145;5729.4843464088435;6230.626162430269;6319.684015799192;6335.778247761306;6369.9681501592195;5981.521478281814;6041.154238406627;6055.970485017558;6075.848103797441;6110.816675499742;6983.580985140908;7031.08595882532;7036.788896333118;7040.260772618802;7054.435645148478;6611.636360000054;6701.865962680817;6778.578733381596;6833.180018397824;6849.1096157499205;6875.621840798804;6881.036654868422;6987.161540615159;7011.684474198775;7016.964915175391;6731.663416829237;6738.966305168443;6740;6750.729970801113;6752.662366881735;6781.38967492928;6785.050092986887;6810.489213309121;6827.52207345481;6830.897536536857;6347.270063942521;6457.7094030767785;6503.154589903283;6601.33986601336;6627.039079333566;5405.297311349499;6543;6544.574602650812;6552.705128269144;6605.735770569316;5586;6268.091126786499;6402.327755585597;6409.262934762562;6472.838179045561;6541.0188471729625;6619.238787539494;6633.900491492535;6634.5024123191915;6644.568103073285;6429.132067170872;6471.741130354733;6474.935250647622;6489.448396886318;6492.5455218134275 18:37:57 INFO - PID 2012 | |1;ext.html;634.4670476799489;777.5023984649825;827.7930517370655;1081.3511892864287;1214.5445457953263;37186.62813371869;38208.99999999996;38214.235715285744;38245.42631860524;38381;32745.690172393137;37767.86696399106;38110;38457.61542384575;38541;7594.4683872128835;7965.561894095822;8170.959145204266;8192.30365418937;8209.507429554216;8141.348692104647;8228.177182281754;8229.094799572042;8240.958795206016;8249.752507424757;8195.59022048898;8266.83466330673;8267.669293228264;8271.917280827205;8304.920360353146;8202.425830191889;8235;8235.917640823574;8237;8257.83484330313;8026.799330016796;8040.59797010153;8080.191980801932;8084.757457276232;8090.312323452514;35600.7539736108;36032.63967360319;36218;36272.81863590665;36314.45528317051;3614.114541937216;3628.4738712886483;3644.2529281497227;3652.2512884858807;3654.3056819204267;29686;29728.85135574308;29750.851245743634;29771.5534266984;29851.552226716616;2986.596809430721;3110.1758034120976;3126.405982863271;3130.9530357044664;3136.7961082529564;153793.23103384522;206927.8960815589;280026.5998670014;304179;329185;38674.80662596669;47229;47389;47460.5253947463;48118.75940620345;47651.285230721565;48228.27657585139;48287.75856120767;48438;48475;13643.90848732099;13707.45170193201;13830.23933683657;13840.961927855249;13850.515231966832;11131.165162612837;11200.935911088434;11286.548538058385;11293.209475336811;11330.433478326215;12864.356782160854;12956.481740730454;13003.219806811621;13030.957523398105;13131.080824342205;5849.970750146222;5855.238818953509;5873.853153671107;5908.940910590925;5956.434138756811;994.4629899854142;996.1234113979651;1005.1556692378438;1022.7187523430968;1039.158281791751;21178.682319765216;22864;22927.599938200645;24028.759712403004;24152;1085.9619913302997;1096.0628662493612;1101.801675698366;1126.9042131418921;1133.1274918313018;11009.449527523591;11799.587014454453;11971.52113915451;12288.1398302118;12299.93850030762;322271;323487;323594;325554;325669;51390;51570.99999999925;51688.74155629198;51692.74153629208;51786.48213517892;1463.341496326636;1477.6453651123652;1525.5880912153657;1529.717002354568;1536.270271620971;23523.76476235216;23657.645135323328;23698.407539811644;23742.406439839142;23863.642045368986;36882.26235475329;36976.52093916213;37089.62910370862;37159.442608360354;37209.813950930074;277345.61327193235;277717;278348;278873;278948;31557;32231.516527252577;32506.67493325131;33353.499697505045;33380.666193337755;41598.584014159474;43463.56536434722;43609.12781744411;43726.78136609297;43741.56258437375;29136.125916222547;29425.558616621194;29825.5526167112;30716.53925191079;30870.69129308768;30108.84945575258;30341.39317213599;30664.693353066184;30671.079867604007;30948.53577196389;35186.824065879504;35968;36600.26799464049;36610.901672949854;36809.63190368062;29549.556756649097;29931.401371972875;30875.073747787603;30951.535726964565;31021.68978310188;22061.669074963567;22664.433389165402;24062.51874962456;24241.878790605933;24701.876490617433 18:37:57 INFO - PID 2012 | |2;jquery.html;373.3988278871018;407.0860917240795;422.93233082706774;424.8449315999661;430.30290928695496;17105.743413848802;20101.497462563442;20961.16155353786;21629.78370216301;21875.343739687814;18222.362217322396;19350.903245483787;21028.158873645076;23043;23803.047878084857;5849.473547380746;6071.301800292957;6112.816615501542;6134;6141.723622436979;5939.67331796751;6040.758369665208;6042.516598672118;6560.147180866481;6894.207166175879;6926.653667316639;6945.409640180591;6960.373566379025;6964.582125072487;6973.372396484316;6637.568558043736;6766.898496522556;6837.794866154023;6847.828804279933;6853.999999999975;6530.444912182423;6543.541952063358;6634.203895532567;6686.030525573765;6694;29377.412451751057;29732.851335743184;30030.84984575085;30094.54858177129;30115.397692046037;2209.635410157322;2354.682117914077;2410.0721222329353;2411.0958390603523;2414.215380001489;16161.353545858152;16185.271662775203;18906.81093189078;18967;19224.134913928905;2286.8284878634167;2294.4493321602863;2328.976710232893;2364.3261670424017;2367.337145599235;141246;157596.8480630381;162209;164372;166533.16733416377;23214.65178022331;24817.379565511008;24843.5031299373;24969.750302497105;25248.747512525006;41033.58966410357;41714.58285417107;41823.37264941028;41906;42011;11141;11188.272762270426;11317.320960742381;11390.860913908542;11737;7431.739889103964;8832.64669413229;8877.511736854534;9095.590698418455;9170.037146099698;11373.090152787758;11441.885581144083;11512.481938312796;11607.76784464306;11669.981907779737;3938.9212215756097;4304.655627549789;4883.169861123618;4900.803967841246;4911.312416261726;864.9135086491301;939.5865819039601;960.9093678674755;979.637534112383;1012.8025035118228;16025.439109631106;18913.621727565373;19330.226790928205;21062.368128956157;21236.25673101434;993.6820217530466;1010.6664800615927;1010.8231059564547;1010.9090181883665;1024.702836177515;9832.885929525186;10490.947545262225;10823.512941917632;10904.291221070764;10936.726581835359;527334;546171;551463.242683792;552955.8817647249;563565;138707;139684;141555;142590;147293;1199.1126566340845;1209.2563073709605;1241.9068569857297;1245.4333278358324;1252.0546987024738;16346.509604711877;17933.65497587712;19633.018349082773;21962.01170947343;22126.557468850853;55719;55930.720346398004;56459;56513.15230271631;57086.42913570811;55733.721331393084;72923;75156;76330.23669763231;77988;8760.78098047554;8907.955460222658;9009.324300677607;9111.271098311987;9158.908410916072;13405.262710551013;13960.30198490051;14092.647683807576;14178;14509.78235326492;9976.351537150062;11690.298582085103;11710.355930423568;12132.332721700039;12234.632961011184;10782.73043173927;11075;11247.437628118398;11343.602973895873;11810.291382517076;11968.341741204318;12032.458539365925;12322.383880805744;12444.813327800272;12604.747905042032;8930.86603700932;8979.730608081769;9061.592228349871;9136.177744003071;9154.862677059717;10840.186985975926;11052.613158539412;11973;12151.270923744603;12185.756284874076 18:37:57 INFO - PID 2012 | |3;mootools.html;1288.4137717338608;1463.3926920328065;1471.212901097913;1474.5871156076296;1476.7415702252104;11547.076233901289;11840.467178976945;11916.7616647667;12067.637970860877;12262.509499620004;11924.344161071138;11973.760524789499;12144.574939877106;12167.452464639091;12178.51285948561;2568.3579105223694;2591.7926565874686;2624.685037795467;2626.1727555819953;2628.5005848888745;2540.3903063264775;2586.159498163095;2598.142612937726;2599.7660210581043;2601.1546247469555;2540.745925407463;2555.029088946207;2556.4503631719176;2583.6253743207317;2591.4169311904766;2455.852648841066;2529.911453099142;2543.3133054075297;2580.316216202708;2582.6384306197147;2491.3397949543387;2559.6800399950007;2574.8197626166175;2611.955217912831;2612.4644447888136;11855.94072029643;11931;11939.641810745692;11951.223170493977;11970.461329240205;5430.70131142787;6806.897896531556;6808.829779255509;6812.48906332027;6824.4199243064395;4040.656544193747;4360.345948107777;4363.825446982117;4386.780660966971;4420.425344705168;1199.4227590718265;1227.864934857165;1275.923444593318;1277.9680507987375;1279.3283526148846;16196.352145914148;16433.506994790176;16466.259018344204;16531.83468165315;16655.41706040295;4755.809767609257;5520.033994051025;5608.579356548275;5657.6039677223025;5808.88382232353;9533.666321678847;11468.31190128595;11504.42477876103;11548.133889958286;11559.653210403701;5347.2781174541715;5547.890421915552;6389.840253993594;6437.485001199892;6493.155889734304;6002.459778619946;6080.847978800477;6199.473044791243;6243.687815609202;6253;4291.270484017725;4380.080183161508;4398.296272596369;4409.030013397048;4486.170058539181;1108.6895669212633;1184;1258.2953546014262;1281.1224311346757;1300.3498250874563;724.7390939261861;789.6012513680555;804.8430556041633;819.7950512371907;823.7858156879255;6550.312217217219;6742.595444273359;7052.5894821036;7064.1876184238445;7130.6808240475675;845.450457202823;920.5305294299956;955.3933252384786;958.5399008475831;970.4807927758616;3685.5208822852856;3918.490596222473;4327.54431567027;4339.457567804025;4400.801963911632;12716.17344872599;14261.144331340152;14481.551844815645;15141.545753627408;15200.239988000556;14807;15016.099034057992;15018.849811501963;15041.699166016619;15047.924760376129;7534.284242996907;8253.587320633944;8509.276711479593;8569.228769410784;8785.385023048453;6635.900461493178;7191.09435999471;7261.564306141649;7285.744998925013;7322.780316590511;10979.725506862393;12541.996640268575;13335.599932002055;13451.26935353951;13567.86432135666;13675.247861367323;13702.65743356424;13765.862341376858;13903.165810051429;14086.22525761093;8171.836563268583;8379.790505237417;8405.537695426565;8406.201410865959;8408.915910841059;10355.689329320132;10473.31923424975;10516.211284153873;10534.525946332587;10595.629152979916;7705.576193309197;9131.178193962574;9147.451152930846;9160.312976526653;9257.074292570689;9004.86492702623;9013.369064165445;9070.727878163665;9092.272618190662;9226.35415520907;8149.511029338259;8168.305694016075;8282.50304981703;8283.08886022525;8285.171482851907;5969.3732158122775;6076.3012253591405;6079.969600151971;6098.390160983866;6134.539909506716;6081.087836824512;6160.815175544741;6261.342559031236;6311.053341998553;6418.550701450854 18:37:57 INFO - PID 2012 | |4;prototype.html;319.83208815371944;358.8869506105578;393.66735108833035;401.662463994897;410.685825343612;5541.307336582927;5740.6268592541455;6286.245650521938;6314.305426403092;6365.077063825743;5881.764729410818;6010.669413182273;6121.877562448748;6139.461473530687;6197.628142311464;3632.6185750496215;4255.5957184067465;4454.37638730578;4671.392718946532;4711.104890070876;4707.717536947777;4722.17361961657;4722.291656251556;4722.98455831996;4765.618750499969;4507.323901414798;4631.861044168681;4661.114388266235;4688.718676879381;4696.765161741916;3959.98020009901;4533.050788161089;4607.608353289975;4630.143423466652;4632.1662100822;4110.630043296118;4116.6329360617765;4136.234796562646;4136.855210067663;4142.689298302639;4981.45945567763;5859.267591551056;5991;6090.33006369299;6095.348523392338;5322.973385133049;5596.440355964371;5604.719764011824;5606.074997625387;5622.522085622727;5837.620554663933;6160.353162917874;6198.132261483396;6585.695006747648;6737.831554211135;4132.462779838602;4535.909281814378;4542.159700455426;4725.078609671115;4754.453237877654;5937.910931336033;6800.965995170042;6872.896906546406;7055;7109.0758201434;4407.826365017182;4413.514513403523;4435.645148388121;4442.977785111054;4444.1333939881415;3885.669718073995;4464.348191169223;4517.977410112929;4529.867533116721;4544.727316361029;5230.97384513075;5339.439358867341;5371.946280537223;5407.270018547524;5463.262459567987;6651.600903945682;6694.564853284618;6697.631630260285;6710.966445167743;6712.630805305755;1346.6902612399008;1519.5517322389921;1537.2851623994673;1539.5227479481396;1544.266473425114;959.9136077753036;990.7225976726528;1005.9748506287401;1009.5356136177294;1013.6857574152036;5691.203231547586;6031.336552979169;6313.337099604567;6340.809775706736;6368.949123394681;5381.973090134525;6584.670766461658;6744.898826517606;6878.796210663114;6879.759208427781;5548.69482178484;5574.386817450078;6162.26052873649;6309.936900630935;6356.300806911236;3469.011331770433;3513.94729079064;3576.302620988934;3594.5866225384416;3611.2235869287706;5699.943000570024;5919.408059194132;7071.868501039912;7113.466490013269;7135.322144396274;6957.8956315655305;7104.076470058859;7128.788106021885;7153.889602567255;7171.605561694157;5514.393416724157;6203.286622038523;7157.498975071695;7161;7203.1356237249765;5836.737346819488;6273.149420920748;6572.441342485942;6982.6508674567085;6990.951357296446;5032.3961124665275;5644.109223410203;6220.849142908576;6729.332513780099;6814.727410903508;5944.554158438221;7174.748883789042;7193.856122877618;7214.521023190338;7236.312550307713;3721.21854410566;4437;4450.354698568754;4452.1763473757455;4456.866294011184;6648.235452922782;6829.590224586541;6887.418542357846;6947.652617369213;6959.7216111357;5919.556033297434;6470.029495575702;6748.7300507979135;6754.628495432798;6802.523823332319;6456.612603243821;6579;6685.364890335411;6722.361375669304;6729.192496900403;6544.181977252843;6572.375624315682;6580.638064906476;6585;6591.769288074895;6445.967770161119;6693.330666933268;6706.362895524917;6716.966415167893;6753.662316884038;6556.573822701509;6564.310747371457;6576.868462630816;6578.184652901401;6590.7693230736695 18:37:57 INFO - PID 2012 | |5;yui.html;742.6769355330432;812.6546217857409;847.1528471528471;851.6550796927247;857.6312185760124;16207;16794.412195573157;16803.579910502227;18375.44873653789;19474.31839885604;17993.100344982762;18242.81757182428;18439;18564.72152917704;18652.720209196872;954.8949615542285;1004.3521928356242;1004.4124187350409;1006.4213077480441;1008.8638033865408;968.4867020479157;1003.1222680154865;1005.8038682456923;1007.8538611901306;1008.8587597736323;1055.8152323343384;1059.8463222832722;1066.1950227578168;1066.642674703975;1068.2895874243618;1042.280826229899;1061.7876424715012;1063.388551582839;1063.6808957312815;1064.6114168328504;641.9454346380563;661.6162625677096;694.9783817787844;769.5998080997849;791.2325044706682;16877.240524176443;16992.980421174645;17084.743728844078;17407.564810879703;17684.292628294854;17291.481255562227;17330;17463.738043929352;17537.736933946002;17722.91138544299;12639.810402843965;14488.420463181461;14668.193249371281;14732.484363047242;14826.629334266621;3607.9098022549383;3611.205534782344;3644.835982380799;3653.1232504199065;3656.8537258509627;103437;129311.35344323312;130866;131885;134054;13332.600021999355;13360.131591446525;13423.731525369438;13469.730605387836;13600.843928266011;24838.875805620857;26108.477830443284;26194.476110478066;26215.475690486084;26729;6764.5603035802515;7068.681909314093;7724.266194711493;7728.265814747702;7951.483153594998;3191.0426871938325;3586.1393265616066;3599.5680518337444;3680.1167719747605;3832.9233415332096;4094.488188976378;4190.622843943998;4229.2598795210715;4233.005243767712;4240.787960601958;912.9543522823832;935.1443429262149;942.3309450290234;943.556528431636;947.8815148106487;654.2345455816705;656.1797752808989;657.0932113683086;660.745612939022;689.648279377521;10005.499725013866;11586.71033224176;11798.351090690094;12068.336241506802;12366.75266494665;641.7176442365437;652.9249136349285;662.9933700663028;668.8194187569426;670.936261055199;4427.535108813592;4525.773711314487;4574;5033.062271025681;5683.403242659461;102724;103575.48212259042;103778.48110759398;104912;105707;93723;93932.06067939234;94110.58834117494;94278;95888;84439;86146.13853861507;86166.56916715502;86484.70272945911;86784;9994.400335979864;10989.560417583207;11251.268667536584;11308.773824523629;11384.14618903569;40987;41158;41725.79137104295;41788;41856.58143418649;52115;52911.94176116533;52996.735016324674;53007.469925300255;53272.20091698705;14144.78782818279;15876.364945402054;16608.750868736737;16989.49031529056;17264.395746148824;17598;18462.44612661622;18591.070446477894;18660.720089198945;18954.43136705901;16390.67218655644;16969.575760606083;16983.320667173175;17198.398056067977;17523.474295771146;16885.91557042207;17159.056251905764;17300.394486192923;17722.291108355523;17751.64496710033;16204.432844850373;16773.32906683719;17055.829441705424;17101.401450949157;17137.657246855244;11307.773844522899;11493.141398835685;11667.708307292385;11906.880931190924;11932.403379831147;12385.513738351456;12388.62834114978;13027.869721303045;13182.209067455984;13192.406341714837 18:37:57 INFO - PID 2012 | __end_tp_report 18:37:57 INFO - PID 2012 | __start_cc_report 18:37:57 INFO - PID 2012 | _x_x_mozilla_cycle_collect,344 18:37:57 INFO - PID 2012 | __end_cc_report 18:37:57 INFO - PID 2012 | __startTimestamp1494985077398__endTimestamp 18:37:57 INFO - PID 2012 | 18:37:57 INFO - PID 2012 | ------- Summary: start ------- 18:37:57 INFO - PID 2012 | Number of tests: 6 18:37:57 INFO - PID 2012 | 18:37:57 INFO - PID 2012 | [#0] dojo.html Cycles:165 Average:6182.98 Median:6402.33 stddev:748.12 (11.7%) stddev-sans-first:721.51 18:37:57 INFO - PID 2012 | Values: 3558.0 3594.1 3618.0 3625.7 3636.6 6139.6 6174.7 6193.8 6219.8 6225.2 6187.9 6191.2 6195.2 6239.3 6277.2 5407.5 5419.3 5442.6 5448.5 5473.3 4519.6 5607.8 5608.1 5654.3 5715.3 5406.2 5516.0 5556.1 5578.3 5599.4 4483.6 5448.0 5463.2 5559.6 5564.9 5479.2 5483.9 5493.5 5507.3 5510.2 5729.4 6166.0 6257.5 6288.3 6295.6 6509.6 6593.7 6667.5 6687.2 6760.2 6822.4 6838.6 6850.6 6851.1 6863.0 6223.7 6251.3 6322.8 6332.1 6336.0 6747.1 6879.9 6908.7 6996.5 7016.6 5204.9 5693.2 6141.0 6585.4 6619.8 6544.9 6755.3 6812.0 6817.3 6846.5 6444.4 6601.4 6605.1 6688.9 6705.8 5763.4 6642.0 6681.5 6731.3 6742.4 5349.8 5367.3 5379.1 5391.4 5414.7 4757.1 4764.8 4802.3 4809.7 4839.6 6336.6 6374.4 6394.4 6401.6 6427.1 6775.6 6775.8 6839.9 6841.6 6854.2 5729.5 6230.6 6319.7 6335.8 6370.0 5981.5 6041.2 6056.0 6075.8 6110.8 6983.6 7031.1 7036.8 7040.3 7054.4 6611.6 6701.9 6778.6 6833.2 6849.1 6875.6 6881.0 6987.2 7011.7 7017.0 6731.7 6739.0 6740.0 6750.7 6752.7 6781.4 6785.1 6810.5 6827.5 6830.9 6347.3 6457.7 6503.2 6601.3 6627.0 5405.3 6543.0 6544.6 6552.7 6605.7 5586.0 6268.1 6402.3 6409.3 6472.8 6541.0 6619.2 6633.9 6634.5 6644.6 6429.1 6471.7 6474.9 6489.4 6492.5 18:37:57 INFO - PID 2012 | 18:37:57 INFO - PID 2012 | [#1] ext.html Cycles:180 Average:43391.36 Median:325611.50 stddev:76043.42 (23.4%) stddev-sans-first:76188.97 18:37:57 INFO - PID 2012 | Values: 634.5 777.5 827.8 1081.4 1214.5 37186.6 38209.0 38214.2 38245.4 38381.0 32745.7 37767.9 38110.0 38457.6 38541.0 7594.5 7965.6 8171.0 8192.3 8209.5 8141.3 8228.2 8229.1 8241.0 8249.8 8195.6 8266.8 8267.7 8271.9 8304.9 8202.4 8235.0 8235.9 8237.0 8257.8 8026.8 8040.6 8080.2 8084.8 8090.3 35600.8 36032.6 36218.0 36272.8 36314.5 3614.1 3628.5 3644.3 3652.3 3654.3 29686.0 29728.9 29750.9 29771.6 29851.6 2986.6 3110.2 3126.4 3131.0 3136.8 153793.2 206927.9 280026.6 304179.0 329185.0 38674.8 47229.0 47389.0 47460.5 48118.8 47651.3 48228.3 48287.8 48438.0 48475.0 13643.9 13707.5 13830.2 13841.0 13850.5 11131.2 11200.9 11286.5 11293.2 11330.4 12864.4 12956.5 13003.2 13031.0 13131.1 5850.0 5855.2 5873.9 5908.9 5956.4 994.5 996.1 1005.2 1022.7 1039.2 21178.7 22864.0 22927.6 24028.8 24152.0 1086.0 1096.1 1101.8 1126.9 1133.1 11009.4 11799.6 11971.5 12288.1 12299.9 322271.0 323487.0 323594.0 325554.0 325669.0 51390.0 51571.0 51688.7 51692.7 51786.5 1463.3 1477.6 1525.6 1529.7 1536.3 23523.8 23657.6 23698.4 23742.4 23863.6 36882.3 36976.5 37089.6 37159.4 37209.8 277345.6 277717.0 278348.0 278873.0 278948.0 31557.0 32231.5 32506.7 33353.5 33380.7 41598.6 43463.6 43609.1 43726.8 43741.6 29136.1 29425.6 29825.6 30716.5 30870.7 30108.8 30341.4 30664.7 30671.1 30948.5 35186.8 35968.0 36600.3 36610.9 36809.6 29549.6 29931.4 30875.1 30951.5 31021.7 22061.7 22664.4 24062.5 24241.9 24701.9 18:37:57 INFO - PID 2012 | 18:37:57 INFO - PID 2012 | [#2] jquery.html Cycles:180 Average:37154.89 Median:13106.56 stddev:93291.42 (711.8%) stddev-sans-first:93512.26 18:37:57 INFO - PID 2012 | Values: 373.4 407.1 422.9 424.8 430.3 17105.7 20101.5 20961.2 21629.8 21875.3 18222.4 19350.9 21028.2 23043.0 23803.0 5849.5 6071.3 6112.8 6134.0 6141.7 5939.7 6040.8 6042.5 6560.1 6894.2 6926.7 6945.4 6960.4 6964.6 6973.4 6637.6 6766.9 6837.8 6847.8 6854.0 6530.4 6543.5 6634.2 6686.0 6694.0 29377.4 29732.9 30030.8 30094.5 30115.4 2209.6 2354.7 2410.1 2411.1 2414.2 16161.4 16185.3 18906.8 18967.0 19224.1 2286.8 2294.4 2329.0 2364.3 2367.3 141246.0 157596.8 162209.0 164372.0 166533.2 23214.7 24817.4 24843.5 24969.8 25248.7 41033.6 41714.6 41823.4 41906.0 42011.0 11141.0 11188.3 11317.3 11390.9 11737.0 7431.7 8832.6 8877.5 9095.6 9170.0 11373.1 11441.9 11512.5 11607.8 11670.0 3938.9 4304.7 4883.2 4900.8 4911.3 864.9 939.6 960.9 979.6 1012.8 16025.4 18913.6 19330.2 21062.4 21236.3 993.7 1010.7 1010.8 1010.9 1024.7 9832.9 10490.9 10823.5 10904.3 10936.7 527334.0 546171.0 551463.2 552955.9 563565.0 138707.0 139684.0 141555.0 142590.0 147293.0 1199.1 1209.3 1241.9 1245.4 1252.1 16346.5 17933.7 19633.0 21962.0 22126.6 55719.0 55930.7 56459.0 56513.2 57086.4 55733.7 72923.0 75156.0 76330.2 77988.0 8760.8 8908.0 9009.3 9111.3 9158.9 13405.3 13960.3 14092.6 14178.0 14509.8 9976.4 11690.3 11710.4 12132.3 12234.6 10782.7 11075.0 11247.4 11343.6 11810.3 11968.3 12032.5 12322.4 12444.8 12604.7 8930.9 8979.7 9061.6 9136.2 9154.9 10840.2 11052.6 11973.0 12151.3 12185.8 18:37:57 INFO - PID 2012 | 18:37:57 INFO - PID 2012 | [#3] mootools.html Cycles:180 Average:7076.50 Median:3979.57 stddev:4441.61 (111.6%) stddev-sans-first:4432.78 18:37:57 INFO - PID 2012 | Values: 1288.4 1463.4 1471.2 1474.6 1476.7 11547.1 11840.5 11916.8 12067.6 12262.5 11924.3 11973.8 12144.6 12167.5 12178.5 2568.4 2591.8 2624.7 2626.2 2628.5 2540.4 2586.2 2598.1 2599.8 2601.2 2540.7 2555.0 2556.5 2583.6 2591.4 2455.9 2529.9 2543.3 2580.3 2582.6 2491.3 2559.7 2574.8 2612.0 2612.5 11855.9 11931.0 11939.6 11951.2 11970.5 5430.7 6806.9 6808.8 6812.5 6824.4 4040.7 4360.3 4363.8 4386.8 4420.4 1199.4 1227.9 1275.9 1278.0 1279.3 16196.4 16433.5 16466.3 16531.8 16655.4 4755.8 5520.0 5608.6 5657.6 5808.9 9533.7 11468.3 11504.4 11548.1 11559.7 5347.3 5547.9 6389.8 6437.5 6493.2 6002.5 6080.8 6199.5 6243.7 6253.0 4291.3 4380.1 4398.3 4409.0 4486.2 1108.7 1184.0 1258.3 1281.1 1300.3 724.7 789.6 804.8 819.8 823.8 6550.3 6742.6 7052.6 7064.2 7130.7 845.5 920.5 955.4 958.5 970.5 3685.5 3918.5 4327.5 4339.5 4400.8 12716.2 14261.1 14481.6 15141.5 15200.2 14807.0 15016.1 15018.8 15041.7 15047.9 7534.3 8253.6 8509.3 8569.2 8785.4 6635.9 7191.1 7261.6 7285.7 7322.8 10979.7 12542.0 13335.6 13451.3 13567.9 13675.2 13702.7 13765.9 13903.2 14086.2 8171.8 8379.8 8405.5 8406.2 8408.9 10355.7 10473.3 10516.2 10534.5 10595.6 7705.6 9131.2 9147.5 9160.3 9257.1 9004.9 9013.4 9070.7 9092.3 9226.4 8149.5 8168.3 8282.5 8283.1 8285.2 5969.4 6076.3 6080.0 6098.4 6134.5 6081.1 6160.8 6261.3 6311.1 6418.6 18:37:57 INFO - PID 2012 | 18:37:57 INFO - PID 2012 | [#4] prototype.html Cycles:180 Average:5371.31 Median:5941.23 stddev:1688.52 (28.4%) stddev-sans-first:1650.14 18:37:57 INFO - PID 2012 | Values: 319.8 358.9 393.7 401.7 410.7 5541.3 5740.6 6286.2 6314.3 6365.1 5881.8 6010.7 6121.9 6139.5 6197.6 3632.6 4255.6 4454.4 4671.4 4711.1 4707.7 4722.2 4722.3 4723.0 4765.6 4507.3 4631.9 4661.1 4688.7 4696.8 3960.0 4533.1 4607.6 4630.1 4632.2 4110.6 4116.6 4136.2 4136.9 4142.7 4981.5 5859.3 5991.0 6090.3 6095.3 5323.0 5596.4 5604.7 5606.1 5622.5 5837.6 6160.4 6198.1 6585.7 6737.8 4132.5 4535.9 4542.2 4725.1 4754.5 5937.9 6801.0 6872.9 7055.0 7109.1 4407.8 4413.5 4435.6 4443.0 4444.1 3885.7 4464.3 4518.0 4529.9 4544.7 5231.0 5339.4 5371.9 5407.3 5463.3 6651.6 6694.6 6697.6 6711.0 6712.6 1346.7 1519.6 1537.3 1539.5 1544.3 959.9 990.7 1006.0 1009.5 1013.7 5691.2 6031.3 6313.3 6340.8 6368.9 5382.0 6584.7 6744.9 6878.8 6879.8 5548.7 5574.4 6162.3 6309.9 6356.3 3469.0 3513.9 3576.3 3594.6 3611.2 5699.9 5919.4 7071.9 7113.5 7135.3 6957.9 7104.1 7128.8 7153.9 7171.6 5514.4 6203.3 7157.5 7161.0 7203.1 5836.7 6273.1 6572.4 6982.7 6991.0 5032.4 5644.1 6220.8 6729.3 6814.7 5944.6 7174.7 7193.9 7214.5 7236.3 3721.2 4437.0 4450.4 4452.2 4456.9 6648.2 6829.6 6887.4 6947.7 6959.7 5919.6 6470.0 6748.7 6754.6 6802.5 6456.6 6579.0 6685.4 6722.4 6729.2 6544.2 6572.4 6580.6 6585.0 6591.8 6446.0 6693.3 6706.4 6717.0 6753.7 6556.6 6564.3 6576.9 6578.2 6590.8 18:37:57 INFO - PID 2012 | 18:37:57 INFO - PID 2012 | [#5] yui.html Cycles:180 Average:22045.68 Median:17872.37 stddev:31102.42 (174.0%) stddev-sans-first:31148.54 18:37:57 INFO - PID 2012 | Values: 742.7 812.7 847.2 851.7 857.6 16207.0 16794.4 16803.6 18375.4 19474.3 17993.1 18242.8 18439.0 18564.7 18652.7 954.9 1004.4 1004.4 1006.4 1008.9 968.5 1003.1 1005.8 1007.9 1008.9 1055.8 1059.8 1066.2 1066.6 1068.3 1042.3 1061.8 1063.4 1063.7 1064.6 641.9 661.6 695.0 769.6 791.2 16877.2 16993.0 17084.7 17407.6 17684.3 17291.5 17330.0 17463.7 17537.7 17722.9 12639.8 14488.4 14668.2 14732.5 14826.6 3607.9 3611.2 3644.8 3653.1 3656.9 103437.0 129311.4 130866.0 131885.0 134054.0 13332.6 13360.1 13423.7 13469.7 13600.8 24838.9 26108.5 26194.5 26215.5 26729.0 6764.6 7068.7 7724.3 7728.3 7951.5 3191.0 3586.1 3599.6 3680.1 3832.9 4094.5 4190.6 4229.3 4233.0 4240.8 913.0 935.1 942.3 943.6 947.9 654.2 656.2 657.1 660.7 689.6 10005.5 11586.7 11798.4 12068.3 12366.8 641.7 652.9 663.0 668.8 670.9 4427.5 4525.8 4574.0 5033.1 5683.4 102724.0 103575.5 103778.5 104912.0 105707.0 93723.0 93932.1 94110.6 94278.0 95888.0 84439.0 86146.1 86166.6 86484.7 86784.0 9994.4 10989.6 11251.3 11308.8 11384.1 40987.0 41158.0 41725.8 41788.0 41856.6 52115.0 52911.9 52996.7 53007.5 53272.2 14144.8 15876.4 16608.8 16989.5 17264.4 17598.0 18462.4 18591.1 18660.7 18954.4 16390.7 16969.6 16983.3 17198.4 17523.5 16885.9 17159.1 17300.4 17722.3 17751.6 16204.4 16773.3 17055.8 17101.4 17137.7 11307.8 11493.1 11667.7 11906.9 11932.4 12385.5 12388.6 13027.9 13182.2 13192.4 18:37:57 INFO - PID 2012 | -------- Summary: end -------- 18:37:57 INFO - PID 2012 | 18:37:58 INFO - TEST-INFO | 2012: exit 0 18:37:58 INFO - TEST-OK | dromaeo_css | took 1114212ms 18:37:58 INFO - TEST-START | kraken 18:37:58 INFO - operating with platform_type : mac_ 18:37:58 INFO - Initialising browser for kraken test... 18:37:58 INFO - TEST-INFO | started process 2023 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/tmpmq0cHw/profile http://localhost:49274/getInfo.html) 18:37:58 INFO - PID 2023 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2930: String.localeCompare is deprecated; use String.prototype.localeCompare instead 18:38:00 INFO - PID 2023 | 2017-05-16 18:38:00.459 plugin-container[2024:22637] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x913f, name = 'com.apple.tsm.portname' 18:38:00 INFO - PID 2023 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:38:00 INFO - PID 2023 | 2017-05-16 18:38:00.462 plugin-container[2024:22637] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x971f, name = 'com.apple.CFPasteboardClient' 18:38:00 INFO - PID 2023 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:38:00 INFO - PID 2023 | 2017-05-16 18:38:00.462 plugin-container[2024:22637] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:38:00 INFO - PID 2023 | __metrics Screen width/height:1600/1200 18:38:00 INFO - PID 2023 | colorDepth:24 18:38:00 INFO - PID 2023 | Browser inner width/height: 1024/691 18:38:00 INFO - PID 2023 | __metrics 18:38:01 INFO - TEST-INFO | 2023: exit 0 18:38:01 INFO - Browser initialized. 18:38:01 INFO - Running cycle 1/1 for kraken test... 18:38:01 INFO - TEST-INFO | started process 2026 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/tmpmq0cHw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:38:03 INFO - PID 2026 | 2017-05-16 18:38:03.681 plugin-container[2027:22784] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x923b, name = 'com.apple.tsm.portname' 18:38:03 INFO - PID 2026 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:38:03 INFO - PID 2026 | 2017-05-16 18:38:03.681 plugin-container[2027:22784] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980f, name = 'com.apple.CFPasteboardClient' 18:38:03 INFO - PID 2026 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:38:03 INFO - PID 2026 | 2017-05-16 18:38:03.681 plugin-container[2027:22784] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:38:04 INFO - PID 2026 | 2017-05-16 18:38:04.367 plugin-container[2028:22833] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 18:38:04 INFO - PID 2026 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:38:04 INFO - PID 2026 | 2017-05-16 18:38:04.367 plugin-container[2028:22833] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980b, name = 'com.apple.CFPasteboardClient' 18:38:04 INFO - PID 2026 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:38:04 INFO - PID 2026 | 2017-05-16 18:38:04.368 plugin-container[2028:22833] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:38:13 INFO - PID 2026 | RSS: Main: 235646976 18:38:13 INFO - PID 2026 | 18:38:16 INFO - PID 2026 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection) 18:38:17 INFO - PID 2026 | RSS: Main: 248074240 18:38:17 INFO - PID 2026 | 18:38:20 INFO - PID 2026 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-dft) 18:38:20 INFO - PID 2026 | RSS: Main: 256843776 18:38:20 INFO - PID 2026 | 18:38:24 INFO - PID 2026 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-fft) 18:38:24 INFO - PID 2026 | RSS: Main: 258859008 18:38:24 INFO - PID 2026 | 18:38:26 INFO - PID 2026 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator) 18:38:27 INFO - PID 2026 | RSS: Main: 259653632 18:38:27 INFO - PID 2026 | 18:38:29 INFO - PID 2026 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:38:29 INFO - PID 2026 | RSS: Main: 260153344 18:38:29 INFO - PID 2026 | 18:38:32 INFO - PID 2026 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom) 18:38:33 INFO - PID 2026 | RSS: Main: 265637888 18:38:33 INFO - PID 2026 | 18:38:36 INFO - PID 2026 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate) 18:38:36 INFO - PID 2026 | RSS: Main: 266043392 18:38:36 INFO - PID 2026 | 18:38:40 INFO - PID 2026 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial) 18:38:40 INFO - PID 2026 | RSS: Main: 265613312 18:38:40 INFO - PID 2026 | 18:38:42 INFO - PID 2026 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:38:42 INFO - PID 2026 | RSS: Main: 266067968 18:38:42 INFO - PID 2026 | 18:38:44 INFO - PID 2026 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:38:45 INFO - PID 2026 | RSS: Main: 265777152 18:38:45 INFO - PID 2026 | 18:38:47 INFO - PID 2026 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:38:47 INFO - PID 2026 | RSS: Main: 265527296 18:38:47 INFO - PID 2026 | 18:38:50 INFO - PID 2026 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:38:51 INFO - PID 2026 | RSS: Main: 265568256 18:38:51 INFO - PID 2026 | 18:38:54 INFO - PID 2026 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:38:55 INFO - PID 2026 | RSS: Main: 265846784 18:38:55 INFO - PID 2026 | 18:38:56 INFO - PID 2026 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49274/tests/kraken/driver.html?testName=ai-astar) 18:38:57 INFO - PID 2026 | RSS: Main: 267685888 18:38:57 INFO - PID 2026 | 18:38:57 INFO - PID 2026 | __start_tp_report 18:38:57 INFO - PID 2026 | _x_x_mozilla_page_load 18:38:57 INFO - PID 2026 | _x_x_mozilla_page_load_details 18:38:57 INFO - PID 2026 | |i|pagename|runs| 18:38:57 INFO - PID 2026 | |0;ai-astar;104;132;156;116;145;106;160;98;161;171 18:38:57 INFO - PID 2026 | |1;audio-beat-detection;130;110;114;142;140;137;135;152;155;112 18:38:57 INFO - PID 2026 | |2;audio-dft;196;195;196;209;221;200;199;200;198;198 18:38:57 INFO - PID 2026 | |3;audio-fft;82;58;81;115;71;59;61;58;80;58 18:38:57 INFO - PID 2026 | |4;audio-oscillator;79;69;83;69;69;88;107;54;81;111 18:38:57 INFO - PID 2026 | |5;imaging-gaussian-blur;90;91;89;90;89;89;88;89;92;108 18:38:57 INFO - PID 2026 | |6;imaging-darkroom;97;106;155;99;95;97;98;98;98;98 18:38:57 INFO - PID 2026 | |7;imaging-desaturate;105;96;90;89;92;136;144;100;91;128 18:38:57 INFO - PID 2026 | |8;json-parse-financial;48;47;48;49;50;50;50;50;48;51 18:38:57 INFO - PID 2026 | |9;json-stringify-tinderbox;58;63;61;71;62;59;57;58;60;56 18:38:57 INFO - PID 2026 | |10;stanford-crypto-aes;85;83;83;83;84;83;85;82;86;84 18:38:57 INFO - PID 2026 | |11;stanford-crypto-ccm;139;140;137;136;146;138;137;141;187;145 18:38:57 INFO - PID 2026 | |12;stanford-crypto-pbkdf2;187;187;186;190;186;188;185;190;185;192 18:38:57 INFO - PID 2026 | |13;stanford-crypto-sha256-iterative;69;66;71;72;71;85;67;70;68;71 18:38:57 INFO - PID 2026 | __end_tp_report 18:38:57 INFO - PID 2026 | __start_cc_report 18:38:57 INFO - PID 2026 | _x_x_mozilla_cycle_collect,905 18:38:57 INFO - PID 2026 | __end_cc_report 18:38:57 INFO - PID 2026 | __startTimestamp1494985137048__endTimestamp 18:38:57 INFO - PID 2026 | 18:38:57 INFO - PID 2026 | ------- Summary: start ------- 18:38:57 INFO - PID 2026 | Number of tests: 14 18:38:57 INFO - PID 2026 | 18:38:57 INFO - PID 2026 | [#0] ai-astar Cycles:10 Average:134.90 Median:158.00 stddev:27.24 (17.2%) stddev-sans-first:26.50 18:38:57 INFO - PID 2026 | Values: 104.0 132.0 156.0 116.0 145.0 106.0 160.0 98.0 161.0 171.0 18:38:57 INFO - PID 2026 | 18:38:57 INFO - PID 2026 | [#1] audio-beat-detection Cycles:10 Average:132.70 Median:138.50 stddev:16.10 (11.6%) stddev-sans-first:17.05 18:38:57 INFO - PID 2026 | Values: 130.0 110.0 114.0 142.0 140.0 137.0 135.0 152.0 155.0 112.0 18:38:57 INFO - PID 2026 | 18:38:57 INFO - PID 2026 | [#2] audio-dft Cycles:10 Average:201.20 Median:199.50 stddev:7.98 (4.0%) stddev-sans-first:8.24 18:38:57 INFO - PID 2026 | Values: 196.0 195.0 196.0 209.0 221.0 200.0 199.0 200.0 198.0 198.0 18:38:57 INFO - PID 2026 | 18:38:57 INFO - PID 2026 | [#3] audio-fft Cycles:10 Average:72.30 Median:66.00 stddev:18.17 (27.5%) stddev-sans-first:18.93 18:38:57 INFO - PID 2026 | Values: 82.0 58.0 81.0 115.0 71.0 59.0 61.0 58.0 80.0 58.0 18:38:57 INFO - PID 2026 | 18:38:57 INFO - PID 2026 | [#4] audio-oscillator Cycles:10 Average:81.00 Median:74.00 stddev:17.62 (23.8%) stddev-sans-first:18.67 18:38:57 INFO - PID 2026 | Values: 79.0 69.0 83.0 69.0 69.0 88.0 107.0 54.0 81.0 111.0 18:38:57 INFO - PID 2026 | 18:38:57 INFO - PID 2026 | [#5] imaging-gaussian-blur Cycles:10 Average:91.50 Median:89.50 stddev:5.91 (6.6%) stddev-sans-first:6.24 18:38:57 INFO - PID 2026 | Values: 90.0 91.0 89.0 90.0 89.0 89.0 88.0 89.0 92.0 108.0 18:38:57 INFO - PID 2026 | 18:38:57 INFO - PID 2026 | [#6] imaging-darkroom Cycles:10 Average:104.10 Median:98.00 stddev:18.11 (18.5%) stddev-sans-first:19.03 18:38:57 INFO - PID 2026 | Values: 97.0 106.0 155.0 99.0 95.0 97.0 98.0 98.0 98.0 98.0 18:38:57 INFO - PID 2026 | 18:38:57 INFO - PID 2026 | [#7] imaging-desaturate Cycles:10 Average:107.10 Median:89.50 stddev:20.87 (23.3%) stddev-sans-first:22.12 18:38:57 INFO - PID 2026 | Values: 105.0 96.0 90.0 89.0 92.0 136.0 144.0 100.0 91.0 128.0 18:38:57 INFO - PID 2026 | 18:38:57 INFO - PID 2026 | [#8] json-parse-financial Cycles:10 Average:49.10 Median:50.00 stddev:1.29 (2.6%) stddev-sans-first:1.30 18:38:57 INFO - PID 2026 | Values: 48.0 47.0 48.0 49.0 50.0 50.0 50.0 50.0 48.0 51.0 18:38:57 INFO - PID 2026 | 18:38:57 INFO - PID 2026 | [#9] json-stringify-tinderbox Cycles:10 Average:60.50 Median:60.50 stddev:4.30 (7.1%) stddev-sans-first:4.47 18:38:57 INFO - PID 2026 | Values: 58.0 63.0 61.0 71.0 62.0 59.0 57.0 58.0 60.0 56.0 18:38:57 INFO - PID 2026 | 18:38:57 INFO - PID 2026 | [#10] stanford-crypto-aes Cycles:10 Average:83.80 Median:84.00 stddev:1.23 (1.5%) stddev-sans-first:1.22 18:38:57 INFO - PID 2026 | Values: 85.0 83.0 83.0 83.0 84.0 83.0 85.0 82.0 86.0 84.0 18:38:57 INFO - PID 2026 | 18:38:57 INFO - PID 2026 | [#11] stanford-crypto-ccm Cycles:10 Average:144.60 Median:140.50 stddev:15.27 (10.9%) stddev-sans-first:16.06 18:38:57 INFO - PID 2026 | Values: 139.0 140.0 137.0 136.0 146.0 138.0 137.0 141.0 187.0 145.0 18:38:57 INFO - PID 2026 | 18:38:57 INFO - PID 2026 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.60 Median:187.50 stddev:2.37 (1.3%) stddev-sans-first:2.50 18:38:57 INFO - PID 2026 | Values: 187.0 187.0 186.0 190.0 186.0 188.0 185.0 190.0 185.0 192.0 18:38:57 INFO - PID 2026 | 18:38:57 INFO - PID 2026 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.00 Median:71.00 stddev:5.29 (7.5%) stddev-sans-first:5.56 18:38:57 INFO - PID 2026 | Values: 69.0 66.0 71.0 72.0 71.0 85.0 67.0 70.0 68.0 71.0 18:38:57 INFO - PID 2026 | -------- Summary: end -------- 18:38:57 INFO - PID 2026 | 18:38:57 INFO - TEST-INFO | 2026: exit 0 18:38:57 INFO - TEST-OK | kraken | took 59812ms 18:38:57 INFO - SUITE-END | took 1174s 18:38:58 INFO - Completed test suite (00:19:34) 18:38:58 INFO - javascript benchmark 18:38:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8005.881016663514, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3557.98221008895, 3594.1014746313404, 3617.9688788695225, 3625.7280703947213, 3636.5817930937933, 6139.645466941716, 6174.660393678346, 6193.7832175873855, 6219.8134055978335, 6225.221847269091, 6187.876242475148, 6191.226096737908, 6195.163652906856, 6239.34486878878, 6277.2467303923595, 5407.459254074601, 5419.295491586088, 5442.619016668835, 5448.509634132927, 5473.343198816127, 4519.5932366087045, 5607.831765047055, 5608.10270356743, 5654.321481422215, 5715.3141623005295, 5406.243125962368, 5515.972420137914, 5556.111022236463, 5578.30271216098, 5599.356074051475, 4483.574060464251, 5448.019356515825, 5463.180522921554, 5559.610827242094, 5564.86087847803, 5479.150731636602, 5483.917741233885, 5493.533049690781, 5507.339119305669, 5510.201020851994, 5729.369769325371, 6166.0, 6257.468115210213, 6288.276848162428, 6295.5907865988565, 6509.576877502995, 6593.736250549972, 6667.533272670915, 6687.164104486939, 6760.1549806274215, 6822.351876571767, 6838.589684618939, 6850.588964662136, 6851.109355783766, 6863.0, 6223.688815559204, 6251.281102673205, 6322.841928951813, 6332.050192471166, 6336.0, 6747.05541224229, 6879.862402751917, 6908.689108990107, 6996.475264355194, 7016.649167541705, 5204.880950595564, 5693.17448969897, 6140.969295153585, 6585.407313341822, 6619.834504137435, 6544.869102618016, 6755.2906944771075, 6812.0, 6817.250102488722, 6846.4522838172825, 6444.387783160592, 6601.438877695354, 6605.108310378134, 6688.933110668929, 6705.798826035227, 5763.366029736726, 6642.036904648799, 6681.532292739462, 6731.32686731323, 6742.42689371409, 5349.839504814861, 5367.329083864517, 5379.058664733656, 5391.35303763543, 5414.729263536808, 4757.072370887713, 4764.8332308369045, 4802.303665968485, 4809.687370320918, 4839.564439200489, 6336.58812177207, 6374.362563743682, 6394.392532709385, 6401.55189136756, 6427.068075129174, 6775.559588626724, 6775.830604234933, 6839.897401538981, 6841.589504629738, 6854.2117656469145, 5729.4843464088435, 6230.626162430269, 6319.684015799192, 6335.778247761306, 6369.9681501592195, 5981.521478281814, 6041.154238406627, 6055.970485017558, 6075.848103797441, 6110.816675499742, 6983.580985140908, 7031.08595882532, 7036.788896333118, 7040.260772618802, 7054.435645148478, 6611.636360000054, 6701.865962680817, 6778.578733381596, 6833.180018397824, 6849.1096157499205, 6875.621840798804, 6881.036654868422, 6987.161540615159, 7011.684474198775, 7016.964915175391, 6731.663416829237, 6738.966305168443, 6740.0, 6750.729970801113, 6752.662366881735, 6781.38967492928, 6785.050092986887, 6810.489213309121, 6827.52207345481, 6830.897536536857, 6347.270063942521, 6457.7094030767785, 6503.154589903283, 6601.33986601336, 6627.039079333566, 5405.297311349499, 6543.0, 6544.574602650812, 6552.705128269144, 6605.735770569316, 5586.0, 6268.091126786499, 6402.327755585597, 6409.262934762562, 6472.838179045561, 6541.0188471729625, 6619.238787539494, 6633.900491492535, 6634.5024123191915, 6644.568103073285, 6429.132067170872, 6471.741130354733, 6474.935250647622, 6489.448396886318, 6492.5455218134275], "value": 6133.893792680039, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [634.4670476799489, 777.5023984649825, 827.7930517370655, 1081.3511892864287, 1214.5445457953263, 37186.62813371869, 38208.99999999996, 38214.235715285744, 38245.42631860524, 38381.0, 32745.690172393137, 37767.86696399106, 38110.0, 38457.61542384575, 38541.0, 7594.4683872128835, 7965.561894095822, 8170.959145204266, 8192.30365418937, 8209.507429554216, 8141.348692104647, 8228.177182281754, 8229.094799572042, 8240.958795206016, 8249.752507424757, 8195.59022048898, 8266.83466330673, 8267.669293228264, 8271.917280827205, 8304.920360353146, 8202.425830191889, 8235.0, 8235.917640823574, 8237.0, 8257.83484330313, 8026.799330016796, 8040.59797010153, 8080.191980801932, 8084.757457276232, 8090.312323452514, 35600.7539736108, 36032.63967360319, 36218.0, 36272.81863590665, 36314.45528317051, 3614.114541937216, 3628.4738712886483, 3644.2529281497227, 3652.2512884858807, 3654.3056819204267, 29686.0, 29728.85135574308, 29750.851245743634, 29771.5534266984, 29851.552226716616, 2986.596809430721, 3110.1758034120976, 3126.405982863271, 3130.9530357044664, 3136.7961082529564, 153793.23103384522, 206927.8960815589, 280026.5998670014, 304179.0, 329185.0, 38674.80662596669, 47229.0, 47389.0, 47460.5253947463, 48118.75940620345, 47651.285230721565, 48228.27657585139, 48287.75856120767, 48438.0, 48475.0, 13643.90848732099, 13707.45170193201, 13830.23933683657, 13840.961927855249, 13850.515231966832, 11131.165162612837, 11200.935911088434, 11286.548538058385, 11293.209475336811, 11330.433478326215, 12864.356782160854, 12956.481740730454, 13003.219806811621, 13030.957523398105, 13131.080824342205, 5849.970750146222, 5855.238818953509, 5873.853153671107, 5908.940910590925, 5956.434138756811, 994.4629899854142, 996.1234113979651, 1005.1556692378438, 1022.7187523430968, 1039.158281791751, 21178.682319765216, 22864.0, 22927.599938200645, 24028.759712403004, 24152.0, 1085.9619913302997, 1096.0628662493612, 1101.801675698366, 1126.9042131418921, 1133.1274918313018, 11009.449527523591, 11799.587014454453, 11971.52113915451, 12288.1398302118, 12299.93850030762, 322271.0, 323487.0, 323594.0, 325554.0, 325669.0, 51390.0, 51570.99999999925, 51688.74155629198, 51692.74153629208, 51786.48213517892, 1463.341496326636, 1477.6453651123652, 1525.5880912153657, 1529.717002354568, 1536.270271620971, 23523.76476235216, 23657.645135323328, 23698.407539811644, 23742.406439839142, 23863.642045368986, 36882.26235475329, 36976.52093916213, 37089.62910370862, 37159.442608360354, 37209.813950930074, 277345.61327193235, 277717.0, 278348.0, 278873.0, 278948.0, 31557.0, 32231.516527252577, 32506.67493325131, 33353.499697505045, 33380.666193337755, 41598.584014159474, 43463.56536434722, 43609.12781744411, 43726.78136609297, 43741.56258437375, 29136.125916222547, 29425.558616621194, 29825.5526167112, 30716.53925191079, 30870.69129308768, 30108.84945575258, 30341.39317213599, 30664.693353066184, 30671.079867604007, 30948.53577196389, 35186.824065879504, 35968.0, 36600.26799464049, 36610.901672949854, 36809.63190368062, 29549.556756649097, 29931.401371972875, 30875.073747787603, 30951.535726964565, 31021.68978310188, 22061.669074963567, 22664.433389165402, 24062.51874962456, 24241.878790605933, 24701.876490617433], "value": 17088.325578250762, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [373.3988278871018, 407.0860917240795, 422.93233082706774, 424.8449315999661, 430.30290928695496, 17105.743413848802, 20101.497462563442, 20961.16155353786, 21629.78370216301, 21875.343739687814, 18222.362217322396, 19350.903245483787, 21028.158873645076, 23043.0, 23803.047878084857, 5849.473547380746, 6071.301800292957, 6112.816615501542, 6134.0, 6141.723622436979, 5939.67331796751, 6040.758369665208, 6042.516598672118, 6560.147180866481, 6894.207166175879, 6926.653667316639, 6945.409640180591, 6960.373566379025, 6964.582125072487, 6973.372396484316, 6637.568558043736, 6766.898496522556, 6837.794866154023, 6847.828804279933, 6853.999999999975, 6530.444912182423, 6543.541952063358, 6634.203895532567, 6686.030525573765, 6694.0, 29377.412451751057, 29732.851335743184, 30030.84984575085, 30094.54858177129, 30115.397692046037, 2209.635410157322, 2354.682117914077, 2410.0721222329353, 2411.0958390603523, 2414.215380001489, 16161.353545858152, 16185.271662775203, 18906.81093189078, 18967.0, 19224.134913928905, 2286.8284878634167, 2294.4493321602863, 2328.976710232893, 2364.3261670424017, 2367.337145599235, 141246.0, 157596.8480630381, 162209.0, 164372.0, 166533.16733416377, 23214.65178022331, 24817.379565511008, 24843.5031299373, 24969.750302497105, 25248.747512525006, 41033.58966410357, 41714.58285417107, 41823.37264941028, 41906.0, 42011.0, 11141.0, 11188.272762270426, 11317.320960742381, 11390.860913908542, 11737.0, 7431.739889103964, 8832.64669413229, 8877.511736854534, 9095.590698418455, 9170.037146099698, 11373.090152787758, 11441.885581144083, 11512.481938312796, 11607.76784464306, 11669.981907779737, 3938.9212215756097, 4304.655627549789, 4883.169861123618, 4900.803967841246, 4911.312416261726, 864.9135086491301, 939.5865819039601, 960.9093678674755, 979.637534112383, 1012.8025035118228, 16025.439109631106, 18913.621727565373, 19330.226790928205, 21062.368128956157, 21236.25673101434, 993.6820217530466, 1010.6664800615927, 1010.8231059564547, 1010.9090181883665, 1024.702836177515, 9832.885929525186, 10490.947545262225, 10823.512941917632, 10904.291221070764, 10936.726581835359, 527334.0, 546171.0, 551463.242683792, 552955.8817647249, 563565.0, 138707.0, 139684.0, 141555.0, 142590.0, 147293.0, 1199.1126566340845, 1209.2563073709605, 1241.9068569857297, 1245.4333278358324, 1252.0546987024738, 16346.509604711877, 17933.65497587712, 19633.018349082773, 21962.01170947343, 22126.557468850853, 55719.0, 55930.720346398004, 56459.0, 56513.15230271631, 57086.42913570811, 55733.721331393084, 72923.0, 75156.0, 76330.23669763231, 77988.0, 8760.78098047554, 8907.955460222658, 9009.324300677607, 9111.271098311987, 9158.908410916072, 13405.262710551013, 13960.30198490051, 14092.647683807576, 14178.0, 14509.78235326492, 9976.351537150062, 11690.298582085103, 11710.355930423568, 12132.332721700039, 12234.632961011184, 10782.73043173927, 11075.0, 11247.437628118398, 11343.602973895873, 11810.291382517076, 11968.341741204318, 12032.458539365925, 12322.383880805744, 12444.813327800272, 12604.747905042032, 8930.86603700932, 8979.730608081769, 9061.592228349871, 9136.177744003071, 9154.862677059717, 10840.186985975926, 11052.613158539412, 11973.0, 12151.270923744603, 12185.756284874076], "value": 11526.188538183422, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1288.4137717338608, 1463.3926920328065, 1471.212901097913, 1474.5871156076296, 1476.7415702252104, 11547.076233901289, 11840.467178976945, 11916.7616647667, 12067.637970860877, 12262.509499620004, 11924.344161071138, 11973.760524789499, 12144.574939877106, 12167.452464639091, 12178.51285948561, 2568.3579105223694, 2591.7926565874686, 2624.685037795467, 2626.1727555819953, 2628.5005848888745, 2540.3903063264775, 2586.159498163095, 2598.142612937726, 2599.7660210581043, 2601.1546247469555, 2540.745925407463, 2555.029088946207, 2556.4503631719176, 2583.6253743207317, 2591.4169311904766, 2455.852648841066, 2529.911453099142, 2543.3133054075297, 2580.316216202708, 2582.6384306197147, 2491.3397949543387, 2559.6800399950007, 2574.8197626166175, 2611.955217912831, 2612.4644447888136, 11855.94072029643, 11931.0, 11939.641810745692, 11951.223170493977, 11970.461329240205, 5430.70131142787, 6806.897896531556, 6808.829779255509, 6812.48906332027, 6824.4199243064395, 4040.656544193747, 4360.345948107777, 4363.825446982117, 4386.780660966971, 4420.425344705168, 1199.4227590718265, 1227.864934857165, 1275.923444593318, 1277.9680507987375, 1279.3283526148846, 16196.352145914148, 16433.506994790176, 16466.259018344204, 16531.83468165315, 16655.41706040295, 4755.809767609257, 5520.033994051025, 5608.579356548275, 5657.6039677223025, 5808.88382232353, 9533.666321678847, 11468.31190128595, 11504.42477876103, 11548.133889958286, 11559.653210403701, 5347.2781174541715, 5547.890421915552, 6389.840253993594, 6437.485001199892, 6493.155889734304, 6002.459778619946, 6080.847978800477, 6199.473044791243, 6243.687815609202, 6253.0, 4291.270484017725, 4380.080183161508, 4398.296272596369, 4409.030013397048, 4486.170058539181, 1108.6895669212633, 1184.0, 1258.2953546014262, 1281.1224311346757, 1300.3498250874563, 724.7390939261861, 789.6012513680555, 804.8430556041633, 819.7950512371907, 823.7858156879255, 6550.312217217219, 6742.595444273359, 7052.5894821036, 7064.1876184238445, 7130.6808240475675, 845.450457202823, 920.5305294299956, 955.3933252384786, 958.5399008475831, 970.4807927758616, 3685.5208822852856, 3918.490596222473, 4327.54431567027, 4339.457567804025, 4400.801963911632, 12716.17344872599, 14261.144331340152, 14481.551844815645, 15141.545753627408, 15200.239988000556, 14807.0, 15016.099034057992, 15018.849811501963, 15041.699166016619, 15047.924760376129, 7534.284242996907, 8253.587320633944, 8509.276711479593, 8569.228769410784, 8785.385023048453, 6635.900461493178, 7191.09435999471, 7261.564306141649, 7285.744998925013, 7322.780316590511, 10979.725506862393, 12541.996640268575, 13335.599932002055, 13451.26935353951, 13567.86432135666, 13675.247861367323, 13702.65743356424, 13765.862341376858, 13903.165810051429, 14086.22525761093, 8171.836563268583, 8379.790505237417, 8405.537695426565, 8406.201410865959, 8408.915910841059, 10355.689329320132, 10473.31923424975, 10516.211284153873, 10534.525946332587, 10595.629152979916, 7705.576193309197, 9131.178193962574, 9147.451152930846, 9160.312976526653, 9257.074292570689, 9004.86492702623, 9013.369064165445, 9070.727878163665, 9092.272618190662, 9226.35415520907, 8149.511029338259, 8168.305694016075, 8282.50304981703, 8283.08886022525, 8285.171482851907, 5969.3732158122775, 6076.3012253591405, 6079.969600151971, 6098.390160983866, 6134.539909506716, 6081.087836824512, 6160.815175544741, 6261.342559031236, 6311.053341998553, 6418.550701450854], "value": 5383.34266904018, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [319.83208815371944, 358.8869506105578, 393.66735108833035, 401.662463994897, 410.685825343612, 5541.307336582927, 5740.6268592541455, 6286.245650521938, 6314.305426403092, 6365.077063825743, 5881.764729410818, 6010.669413182273, 6121.877562448748, 6139.461473530687, 6197.628142311464, 3632.6185750496215, 4255.5957184067465, 4454.37638730578, 4671.392718946532, 4711.104890070876, 4707.717536947777, 4722.17361961657, 4722.291656251556, 4722.98455831996, 4765.618750499969, 4507.323901414798, 4631.861044168681, 4661.114388266235, 4688.718676879381, 4696.765161741916, 3959.98020009901, 4533.050788161089, 4607.608353289975, 4630.143423466652, 4632.1662100822, 4110.630043296118, 4116.6329360617765, 4136.234796562646, 4136.855210067663, 4142.689298302639, 4981.45945567763, 5859.267591551056, 5991.0, 6090.33006369299, 6095.348523392338, 5322.973385133049, 5596.440355964371, 5604.719764011824, 5606.074997625387, 5622.522085622727, 5837.620554663933, 6160.353162917874, 6198.132261483396, 6585.695006747648, 6737.831554211135, 4132.462779838602, 4535.909281814378, 4542.159700455426, 4725.078609671115, 4754.453237877654, 5937.910931336033, 6800.965995170042, 6872.896906546406, 7055.0, 7109.0758201434, 4407.826365017182, 4413.514513403523, 4435.645148388121, 4442.977785111054, 4444.1333939881415, 3885.669718073995, 4464.348191169223, 4517.977410112929, 4529.867533116721, 4544.727316361029, 5230.97384513075, 5339.439358867341, 5371.946280537223, 5407.270018547524, 5463.262459567987, 6651.600903945682, 6694.564853284618, 6697.631630260285, 6710.966445167743, 6712.630805305755, 1346.6902612399008, 1519.5517322389921, 1537.2851623994673, 1539.5227479481396, 1544.266473425114, 959.9136077753036, 990.7225976726528, 1005.9748506287401, 1009.5356136177294, 1013.6857574152036, 5691.203231547586, 6031.336552979169, 6313.337099604567, 6340.809775706736, 6368.949123394681, 5381.973090134525, 6584.670766461658, 6744.898826517606, 6878.796210663114, 6879.759208427781, 5548.69482178484, 5574.386817450078, 6162.26052873649, 6309.936900630935, 6356.300806911236, 3469.011331770433, 3513.94729079064, 3576.302620988934, 3594.5866225384416, 3611.2235869287706, 5699.943000570024, 5919.408059194132, 7071.868501039912, 7113.466490013269, 7135.322144396274, 6957.8956315655305, 7104.076470058859, 7128.788106021885, 7153.889602567255, 7171.605561694157, 5514.393416724157, 6203.286622038523, 7157.498975071695, 7161.0, 7203.1356237249765, 5836.737346819488, 6273.149420920748, 6572.441342485942, 6982.6508674567085, 6990.951357296446, 5032.3961124665275, 5644.109223410203, 6220.849142908576, 6729.332513780099, 6814.727410903508, 5944.554158438221, 7174.748883789042, 7193.856122877618, 7214.521023190338, 7236.312550307713, 3721.21854410566, 4437.0, 4450.354698568754, 4452.1763473757455, 4456.866294011184, 6648.235452922782, 6829.590224586541, 6887.418542357846, 6947.652617369213, 6959.7216111357, 5919.556033297434, 6470.029495575702, 6748.7300507979135, 6754.628495432798, 6802.523823332319, 6456.612603243821, 6579.0, 6685.364890335411, 6722.361375669304, 6729.192496900403, 6544.181977252843, 6572.375624315682, 6580.638064906476, 6585.0, 6591.769288074895, 6445.967770161119, 6693.330666933268, 6706.362895524917, 6716.966415167893, 6753.662316884038, 6556.573822701509, 6564.310747371457, 6576.868462630816, 6578.184652901401, 6590.7693230736695], "value": 4832.283959558012, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [742.6769355330432, 812.6546217857409, 847.1528471528471, 851.6550796927247, 857.6312185760124, 16207.0, 16794.412195573157, 16803.579910502227, 18375.44873653789, 19474.31839885604, 17993.100344982762, 18242.81757182428, 18439.0, 18564.72152917704, 18652.720209196872, 954.8949615542285, 1004.3521928356242, 1004.4124187350409, 1006.4213077480441, 1008.8638033865408, 968.4867020479157, 1003.1222680154865, 1005.8038682456923, 1007.8538611901306, 1008.8587597736323, 1055.8152323343384, 1059.8463222832722, 1066.1950227578168, 1066.642674703975, 1068.2895874243618, 1042.280826229899, 1061.7876424715012, 1063.388551582839, 1063.6808957312815, 1064.6114168328504, 641.9454346380563, 661.6162625677096, 694.9783817787844, 769.5998080997849, 791.2325044706682, 16877.240524176443, 16992.980421174645, 17084.743728844078, 17407.564810879703, 17684.292628294854, 17291.481255562227, 17330.0, 17463.738043929352, 17537.736933946002, 17722.91138544299, 12639.810402843965, 14488.420463181461, 14668.193249371281, 14732.484363047242, 14826.629334266621, 3607.9098022549383, 3611.205534782344, 3644.835982380799, 3653.1232504199065, 3656.8537258509627, 103437.0, 129311.35344323312, 130866.0, 131885.0, 134054.0, 13332.600021999355, 13360.131591446525, 13423.731525369438, 13469.730605387836, 13600.843928266011, 24838.875805620857, 26108.477830443284, 26194.476110478066, 26215.475690486084, 26729.0, 6764.5603035802515, 7068.681909314093, 7724.266194711493, 7728.265814747702, 7951.483153594998, 3191.0426871938325, 3586.1393265616066, 3599.5680518337444, 3680.1167719747605, 3832.9233415332096, 4094.488188976378, 4190.622843943998, 4229.2598795210715, 4233.005243767712, 4240.787960601958, 912.9543522823832, 935.1443429262149, 942.3309450290234, 943.556528431636, 947.8815148106487, 654.2345455816705, 656.1797752808989, 657.0932113683086, 660.745612939022, 689.648279377521, 10005.499725013866, 11586.71033224176, 11798.351090690094, 12068.336241506802, 12366.75266494665, 641.7176442365437, 652.9249136349285, 662.9933700663028, 668.8194187569426, 670.936261055199, 4427.535108813592, 4525.773711314487, 4574.0, 5033.062271025681, 5683.403242659461, 102724.0, 103575.48212259042, 103778.48110759398, 104912.0, 105707.0, 93723.0, 93932.06067939234, 94110.58834117494, 94278.0, 95888.0, 84439.0, 86146.13853861507, 86166.56916715502, 86484.70272945911, 86784.0, 9994.400335979864, 10989.560417583207, 11251.268667536584, 11308.773824523629, 11384.14618903569, 40987.0, 41158.0, 41725.79137104295, 41788.0, 41856.58143418649, 52115.0, 52911.94176116533, 52996.735016324674, 53007.469925300255, 53272.20091698705, 14144.78782818279, 15876.364945402054, 16608.750868736737, 16989.49031529056, 17264.395746148824, 17598.0, 18462.44612661622, 18591.070446477894, 18660.720089198945, 18954.43136705901, 16390.67218655644, 16969.575760606083, 16983.320667173175, 17198.398056067977, 17523.474295771146, 16885.91557042207, 17159.056251905764, 17300.394486192923, 17722.291108355523, 17751.64496710033, 16204.432844850373, 16773.32906683719, 17055.829441705424, 17101.401450949157, 17137.657246855244, 11307.773844522899, 11493.141398835685, 11667.708307292385, 11906.880931190924, 11932.403379831147, 12385.513738351456, 12388.62834114978, 13027.869721303045, 13182.209067455984, 13192.406341714837], "value": 8377.183688668465, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1521.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 132.0, 156.0, 116.0, 145.0, 106.0, 160.0, 98.0, 161.0, 171.0], "value": 134.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 110.0, 114.0, 142.0, 140.0, 137.0, 135.0, 152.0, 155.0, 112.0], "value": 132.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 195.0, 196.0, 209.0, 221.0, 200.0, 199.0, 200.0, 198.0, 198.0], "value": 201.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 58.0, 81.0, 115.0, 71.0, 59.0, 61.0, 58.0, 80.0, 58.0], "value": 72.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 69.0, 83.0, 69.0, 69.0, 88.0, 107.0, 54.0, 81.0, 111.0], "value": 81.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 91.0, 89.0, 90.0, 89.0, 89.0, 88.0, 89.0, 92.0, 108.0], "value": 91.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 106.0, 155.0, 99.0, 95.0, 97.0, 98.0, 98.0, 98.0, 98.0], "value": 104.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 96.0, 90.0, 89.0, 92.0, 136.0, 144.0, 100.0, 91.0, 128.0], "value": 107.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [48.0, 47.0, 48.0, 49.0, 50.0, 50.0, 50.0, 50.0, 48.0, 51.0], "value": 49.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 63.0, 61.0, 71.0, 62.0, 59.0, 57.0, 58.0, 60.0, 56.0], "value": 60.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 83.0, 83.0, 84.0, 83.0, 85.0, 82.0, 86.0, 84.0], "value": 83.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 140.0, 137.0, 136.0, 146.0, 138.0, 137.0, 141.0, 187.0, 145.0], "value": 144.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 187.0, 186.0, 190.0, 186.0, 188.0, 185.0, 190.0, 185.0, 192.0], "value": 187.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 66.0, 71.0, 72.0, 71.0, 85.0, 67.0, 70.0, 68.0, 71.0], "value": 71.0, "unit": "score"}]}]} 18:38:58 INFO - Return code: 0 18:38:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:38:58 INFO - # TBPL SUCCESS # 18:38:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:38:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:38:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:38:58 INFO - 2017-05-16 18:38:58.366 screenresolution[2033:23211] starting screenresolution argv=screenresolution get 18:38:58 INFO - 2017-05-16 18:38:58.369 screenresolution[2033:23211] Display 0: 1600x1200x32@60 18:38:58 INFO - 2017-05-16 18:38:58.375 screenresolution[2034:23214] starting screenresolution argv=screenresolution list 18:38:58 INFO - Available Modes on Display 0 18:38:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:38:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:38:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:38:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:38:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:38:58 INFO - Intel Iris: 18:38:58 INFO - Chipset Model: Intel Iris 18:38:58 INFO - Type: GPU 18:38:58 INFO - Bus: Built-In 18:38:58 INFO - VRAM (Dynamic, Max): 1536 MB 18:38:58 INFO - Vendor: Intel (0x8086) 18:38:58 INFO - Device ID: 0x0a2e 18:38:58 INFO - Revision ID: 0x0009 18:38:58 INFO - Displays: 18:38:58 INFO - Display: 18:38:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:38:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:38:58 INFO - Display Serial Number: 1600x1200 60 18:38:58 INFO - Main Display: Yes 18:38:58 INFO - Mirror: Off 18:38:58 INFO - Online: Yes 18:38:58 INFO - Rotation: Supported 18:38:58 INFO - Return code: 0 18:38:58 INFO - Running post-action listener: _resource_record_post_action 18:38:58 INFO - [mozharness: 2017-05-17 01:38:58.609874Z] Finished run-tests step (success) 18:38:58 INFO - Running post-run listener: _resource_record_post_run 18:38:58 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 31499264; Write bytes: 533229056; Read time: 191; Write time: 1680 18:38:58 INFO - TinderboxPrint: CPU usage
54.0% 18:38:58 INFO - TinderboxPrint: I/O read bytes / time
31,499,264 / 191 18:38:58 INFO - TinderboxPrint: I/O write bytes / time
533,229,056 / 1,680 18:38:58 INFO - TinderboxPrint: CPU idle
2,203.5 (46.0%) 18:38:58 INFO - TinderboxPrint: CPU system
958.9 (20.0%) 18:38:58 INFO - TinderboxPrint: CPU user
1,623.2 (33.9%) 18:38:58 INFO - TinderboxPrint: Swap in / out
241,119,232 / 0 18:38:58 INFO - install - Wall time: 22s; CPU: 55.0%; Read bytes: 208046592; Write bytes: 205938688; Read time: 16291; Write time: 456 18:38:58 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 31499264; Write bytes: 316583424; Read time: 191; Write time: 1186 18:38:59 INFO - Running post-run listener: _upload_blobber_files 18:38:59 INFO - Blob upload gear active. 18:38:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:38:59 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:38:59 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:38:59 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:38:59 INFO - (blobuploader) - INFO - Open directory for files ... 18:38:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:38:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:38:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:39:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:39:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:39:00 INFO - (blobuploader) - INFO - Done attempting. 18:39:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:39:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:39:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:39:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:39:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:39:01 INFO - (blobuploader) - INFO - Done attempting. 18:39:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:39:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:39:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:39:02 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:39:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:39:02 INFO - (blobuploader) - INFO - Done attempting. 18:39:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:39:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:39:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:39:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:39:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:39:04 INFO - (blobuploader) - INFO - Done attempting. 18:39:04 INFO - (blobuploader) - INFO - Iteration through files over. 18:39:04 INFO - Return code: 0 18:39:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:39:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:39:04 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e85da2e375e4a95f8b7d5fe883fde07292085a4c738178454fdc9c24bfad613474aec87a3c23a9c6b40cd18f7c7721a0e18408e5d6a6058ad8e7f2dbcc20612", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0f86b20669defcbdcba5e1ff7f116846f2526ef9b33954c1aca91d54499d1792f799841ca87226a21fdb670482def46383f0ae6317d3bfef702e9d80d45bfc70", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dce58b0afd4a123e6d23b3d3e60789d42d4dc1f37dbde257af25922e63d10d8da25ccad3bd8e7c8edd7c6d1adeef258fdaa60cbdc7b50844b6a319b93feac242", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0bdf6aeec4cf5c0a5eec9313ff434e45882d011749e3ab8611f9508fca9e2b4ed5a39440fa64571daaef0ee31c831d80e0569d6870dd2176d8f780d35a15cbc5"} 18:39:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:39:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:39:04 INFO - Contents: 18:39:04 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e85da2e375e4a95f8b7d5fe883fde07292085a4c738178454fdc9c24bfad613474aec87a3c23a9c6b40cd18f7c7721a0e18408e5d6a6058ad8e7f2dbcc20612", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0f86b20669defcbdcba5e1ff7f116846f2526ef9b33954c1aca91d54499d1792f799841ca87226a21fdb670482def46383f0ae6317d3bfef702e9d80d45bfc70", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dce58b0afd4a123e6d23b3d3e60789d42d4dc1f37dbde257af25922e63d10d8da25ccad3bd8e7c8edd7c6d1adeef258fdaa60cbdc7b50844b6a319b93feac242", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0bdf6aeec4cf5c0a5eec9313ff434e45882d011749e3ab8611f9508fca9e2b4ed5a39440fa64571daaef0ee31c831d80e0569d6870dd2176d8f780d35a15cbc5"} 18:39:04 INFO - Running post-run listener: copy_logs_to_upload_dir 18:39:04 INFO - Copying logs to upload dir... 18:39:04 INFO - mkdir: /builds/slave/test/build/upload/logs 18:39:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.295352 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-05-16 18:39:04.809473) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-16 18:39:04.813501) ========= 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.qtDUph9IMa/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.ZP0nZCrROJ/Listeners TMPDIR=/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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/4e85da2e375e4a95f8b7d5fe883fde07292085a4c738178454fdc9c24bfad613474aec87a3c23a9c6b40cd18f7c7721a0e18408e5d6a6058ad8e7f2dbcc20612", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0f86b20669defcbdcba5e1ff7f116846f2526ef9b33954c1aca91d54499d1792f799841ca87226a21fdb670482def46383f0ae6317d3bfef702e9d80d45bfc70", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dce58b0afd4a123e6d23b3d3e60789d42d4dc1f37dbde257af25922e63d10d8da25ccad3bd8e7c8edd7c6d1adeef258fdaa60cbdc7b50844b6a319b93feac242", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0bdf6aeec4cf5c0a5eec9313ff434e45882d011749e3ab8611f9508fca9e2b4ed5a39440fa64571daaef0ee31c831d80e0569d6870dd2176d8f780d35a15cbc5"} build_url:https://queue.taskcluster.net/v1/task/Yv-zrmFaTEyagzyZpkfM_w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011052 build_url: 'https://queue.taskcluster.net/v1/task/Yv-zrmFaTEyagzyZpkfM_w/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/4e85da2e375e4a95f8b7d5fe883fde07292085a4c738178454fdc9c24bfad613474aec87a3c23a9c6b40cd18f7c7721a0e18408e5d6a6058ad8e7f2dbcc20612", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0f86b20669defcbdcba5e1ff7f116846f2526ef9b33954c1aca91d54499d1792f799841ca87226a21fdb670482def46383f0ae6317d3bfef702e9d80d45bfc70", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dce58b0afd4a123e6d23b3d3e60789d42d4dc1f37dbde257af25922e63d10d8da25ccad3bd8e7c8edd7c6d1adeef258fdaa60cbdc7b50844b6a319b93feac242", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0bdf6aeec4cf5c0a5eec9313ff434e45882d011749e3ab8611f9508fca9e2b4ed5a39440fa64571daaef0ee31c831d80e0569d6870dd2176d8f780d35a15cbc5"}' ========= master_lag: 0.08 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-16 18:39:04.905461) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 18:39:04.905814) ========= 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.qtDUph9IMa/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.ZP0nZCrROJ/Listeners TMPDIR=/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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.005265 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 18:39:04.933463) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-16 18:39:04.933923) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-16 18:39:04.934418) ========= ========= Total master_lag: 0.22 =========