builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0258 starttime: 1496605704.63 results: success (0) revision: 78450dd743b92638cdcf70b16c0850214782eff4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-04 12:48:24.628415) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-04 12:48:24.628891) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-04 12:48:24.629759) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-04 12:48:24.814094) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-04 12:48:24.814374) ========= 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.It0ZpoPIES/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.ADWUNn96wd/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-04 12:48:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.2M=0.001s 2017-06-04 12:48:25 (18.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.493665 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-04 12:48:25.362642) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-04 12:48:25.363004) ========= 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.It0ZpoPIES/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.ADWUNn96wd/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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.026581 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-04 12:48:25.420418) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-04 12:48:25.420765) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 78450dd743b92638cdcf70b16c0850214782eff4 --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 78450dd743b92638cdcf70b16c0850214782eff4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.It0ZpoPIES/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.ADWUNn96wd/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-04 12:48:25,519 truncating revision to first 12 chars 2017-06-04 12:48:25,519 Setting DEBUG logging. 2017-06-04 12:48:25,520 attempt 1/10 2017-06-04 12:48:25,520 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/78450dd743b9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-04 12:48:26,677 unpacking tar archive at: mozilla-central-78450dd743b9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.456466 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-04 12:48:26.903748) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-04 12:48:26.907169) ========= script_repo_revision: 78450dd743b92638cdcf70b16c0850214782eff4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-04 12:48:26.907748) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-04 12:48:26.908124) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-04 12:48:27.003848) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2017-06-04 12:48:27.004186) ========= /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.It0ZpoPIES/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.ADWUNn96wd/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 12:48:27 INFO - MultiFileLogger online at 20170604 12:48:27 in /builds/slave/test 12:48:27 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 12:48:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:48:27 INFO - {'append_to_log': False, 12:48:27 INFO - 'base_work_dir': '/builds/slave/test', 12:48:27 INFO - 'blob_upload_branch': 'Firefox', 12:48:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:48:27 INFO - 'branch': 'Firefox', 12:48:27 INFO - 'buildbot_json_path': 'buildprops.json', 12:48:27 INFO - 'config_files': ('talos/mac_config.py',), 12:48:27 INFO - 'default_actions': ('clobber', 12:48:27 INFO - 'read-buildbot-config', 12:48:27 INFO - 'download-and-extract', 12:48:27 INFO - 'populate-webroot', 12:48:27 INFO - 'create-virtualenv', 12:48:27 INFO - 'install', 12:48:27 INFO - 'setup-mitmproxy', 12:48:27 INFO - 'run-tests'), 12:48:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:48:27 INFO - 'download_minidump_stackwalk': True, 12:48:27 INFO - 'download_symbols': 'ondemand', 12:48:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:48:27 INFO - 'tooltool.py': '/tools/tooltool.py', 12:48:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:48:27 INFO - '/tools/misc-python/virtualenv.py')}, 12:48:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:48:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:48:27 INFO - 'gecko_profile': False, 12:48:27 INFO - 'gecko_profile_interval': 0, 12:48:27 INFO - 'installer_path': 'installer.exe', 12:48:27 INFO - 'log_level': 'info', 12:48:27 INFO - 'log_name': 'talos', 12:48:27 INFO - 'log_to_console': True, 12:48:27 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:48:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:48:27 INFO - 'opt_config_files': (), 12:48:27 INFO - 'pip_index': False, 12:48:27 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:48:27 INFO - 'cmd': ('bash', 12:48:27 INFO - '-c', 12:48:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:48:27 INFO - 'enabled': True, 12:48:27 INFO - 'halt_on_failure': False, 12:48:27 INFO - 'name': 'check_screen_resolution'},), 12:48:27 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:48:27 INFO - 'cmd': ('bash', 12:48:27 INFO - '-c', 12:48:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:48:27 INFO - 'enabled': True, 12:48:27 INFO - 'halt_on_failure': False, 12:48:27 INFO - 'name': 'check_screen_resolution'},), 12:48:27 INFO - 'run_cmd_checks_enabled': True, 12:48:27 INFO - 'suite': 'dromaeojs-e10s', 12:48:27 INFO - 'system_bits': '32', 12:48:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:48:27 INFO - 'title': 't-yosemite-r7-0258', 12:48:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:48:27 INFO - 'use_talos_json': True, 12:48:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:48:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:48:27 INFO - 'work_dir': 'build'} 12:48:27 INFO - [mozharness: 2017-06-04 19:48:27.226906Z] Running clobber step. 12:48:27 INFO - Running pre-action listener: _resource_record_pre_action 12:48:27 INFO - Running main action method: clobber 12:48:27 INFO - rmtree: /builds/slave/test/build 12:48:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:48:29 INFO - Running post-action listener: _resource_record_post_action 12:48:29 INFO - [mozharness: 2017-06-04 19:48:29.622319Z] Finished clobber step (success) 12:48:29 INFO - [mozharness: 2017-06-04 19:48:29.622396Z] Running read-buildbot-config step. 12:48:29 INFO - Running pre-action listener: _resource_record_pre_action 12:48:29 INFO - Running main action method: read_buildbot_config 12:48:29 INFO - Using buildbot properties: 12:48:29 INFO - { 12:48:29 INFO - "project": "", 12:48:29 INFO - "product": "firefox", 12:48:29 INFO - "who": "archaeopteryx@coole-files.de", 12:48:29 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 12:48:29 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 12:48:29 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 12:48:29 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 12:48:29 INFO - "stage_platform": "macosx64", 12:48:29 INFO - "basedir": "/builds/slave/test", 12:48:29 INFO - "buildnumber": 86, 12:48:29 INFO - "platform": "yosemite_r7", 12:48:29 INFO - "parent_task_id": "OCdICIMyRsC2TCJejClHTQ", 12:48:29 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 12:48:29 INFO - "slavebuilddir": "test", 12:48:29 INFO - "taskId": "ZbDVG3lkS92EiOsbeSBzog", 12:48:29 INFO - "branch": "mozilla-central", 12:48:29 INFO - "script_repo_revision": "production", 12:48:29 INFO - "revision": "78450dd743b92638cdcf70b16c0850214782eff4", 12:48:29 INFO - "slavename": "t-yosemite-r7-0258", 12:48:29 INFO - "repo_path": "mozilla-central" 12:48:29 INFO - } 12:48:29 INFO - Finding installer, test and symbols from parent task. 12:48:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZbDVG3lkS92EiOsbeSBzog'}, attempt #1 12:48:30 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZbDVG3lkS92EiOsbeSBzog'}, attempt #1 12:48:30 INFO - Task dependencies: DtOEWA9ETACAn0nYOa2LjA OCdICIMyRsC2TCJejClHTQ 12:48:30 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OCdICIMyRsC2TCJejClHTQ'}, attempt #1 12:48:31 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/OCdICIMyRsC2TCJejClHTQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 12:48:31 INFO - Set test_url: https://queue.taskcluster.net/v1/task/OCdICIMyRsC2TCJejClHTQ/artifacts/public/build/test_packages.json 12:48:31 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/OCdICIMyRsC2TCJejClHTQ/artifacts/public/build/target.crashreporter-symbols.zip 12:48:31 INFO - Running post-action listener: _resource_record_post_action 12:48:31 INFO - [mozharness: 2017-06-04 19:48:31.408322Z] Finished read-buildbot-config step (success) 12:48:31 INFO - [mozharness: 2017-06-04 19:48:31.408447Z] Running download-and-extract step. 12:48:31 INFO - Running pre-action listener: _resource_record_pre_action 12:48:31 INFO - Running main action method: download_and_extract 12:48:31 INFO - mkdir: /builds/slave/test/build/tests 12:48:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:48:31 INFO - trying https://queue.taskcluster.net/v1/task/OCdICIMyRsC2TCJejClHTQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 12:48:31 INFO - Downloading https://queue.taskcluster.net/v1/task/OCdICIMyRsC2TCJejClHTQ/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 12:48:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OCdICIMyRsC2TCJejClHTQ/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 12:48:31 INFO - Downloaded 1328 bytes. 12:48:31 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 12:48:31 INFO - Using the following test package requirements: 12:48:31 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:48:31 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 12:48:31 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 12:48:31 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:48:31 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 12:48:31 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:48:31 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 12:48:31 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:48:31 INFO - u'jsshell-mac.zip'], 12:48:31 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:48:31 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 12:48:31 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 12:48:31 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:48:31 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 12:48:31 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:48:31 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 12:48:31 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:48:31 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.tar.gz'], 12:48:31 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:48:31 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 12:48:31 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:48:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OCdICIMyRsC2TCJejClHTQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 12:48:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OCdICIMyRsC2TCJejClHTQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:48:31 INFO - Fetch https://queue.taskcluster.net/v1/task/OCdICIMyRsC2TCJejClHTQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 12:48:34 INFO - Content-Length response header: 57631202 12:48:34 INFO - Bytes received: 57631202 12:48:52 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 12:48:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OCdICIMyRsC2TCJejClHTQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 12:48:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OCdICIMyRsC2TCJejClHTQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:48:52 INFO - Fetch https://queue.taskcluster.net/v1/task/OCdICIMyRsC2TCJejClHTQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 12:48:54 INFO - Content-Length response header: 57631202 12:48:54 INFO - Bytes received: 57631202 12:49:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OCdICIMyRsC2TCJejClHTQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 12:49:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OCdICIMyRsC2TCJejClHTQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 12:49:11 INFO - Fetch https://queue.taskcluster.net/v1/task/OCdICIMyRsC2TCJejClHTQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 12:49:12 INFO - Content-Length response header: 14447425 12:49:12 INFO - Bytes received: 14447425 12:49:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:49:13 INFO - trying https://queue.taskcluster.net/v1/task/OCdICIMyRsC2TCJejClHTQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 12:49:13 INFO - Downloading https://queue.taskcluster.net/v1/task/OCdICIMyRsC2TCJejClHTQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 12:49:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OCdICIMyRsC2TCJejClHTQ/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 12:49:14 INFO - Downloaded 67785100 bytes. 12:49:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OCdICIMyRsC2TCJejClHTQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 12:49:14 INFO - mkdir: /builds/slave/test/properties 12:49:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:49:14 INFO - Writing to file /builds/slave/test/properties/build_url 12:49:14 INFO - Contents: 12:49:14 INFO - build_url:https://queue.taskcluster.net/v1/task/OCdICIMyRsC2TCJejClHTQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 12:49:14 INFO - Running post-action listener: _resource_record_post_action 12:49:14 INFO - Running post-action listener: set_extra_try_arguments 12:49:14 INFO - [mozharness: 2017-06-04 19:49:14.917173Z] Finished download-and-extract step (success) 12:49:14 INFO - [mozharness: 2017-06-04 19:49:14.917309Z] Running populate-webroot step. 12:49:14 INFO - Running pre-action listener: _resource_record_pre_action 12:49:14 INFO - Running main action method: populate_webroot 12:49:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:49:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:49:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:49:14 INFO - '--tppagecycles', 12:49:14 INFO - '7'], 12:49:14 INFO - 'tests': ['tsvgm']}}, 12:49:14 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:49:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:49:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:49:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:49:14 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:49:14 INFO - 'talos_options': ['--disable-e10s'], 12:49:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:49:14 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:49:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:49:14 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:49:14 INFO - 'talos_options': ['--disable-e10s'], 12:49:14 INFO - 'tests': ['damp', 'tps']}, 12:49:14 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:49:14 INFO - 'tests': ['damp', 'tps']}, 12:49:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:49:14 INFO - 'tests': ['dromaeo_dom']}, 12:49:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:49:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:49:14 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 12:49:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 12:49:14 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 12:49:14 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 12:49:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:49:14 INFO - 'tests': ['a11yr', 12:49:14 INFO - 'ts_paint', 12:49:14 INFO - 'tpaint', 12:49:14 INFO - 'sessionrestore', 12:49:14 INFO - 'sessionrestore_no_auto_restore', 12:49:14 INFO - 'tabpaint']}, 12:49:14 INFO - 'other-e10s': {'tests': ['a11yr', 12:49:14 INFO - 'ts_paint', 12:49:14 INFO - 'tpaint', 12:49:14 INFO - 'sessionrestore', 12:49:14 INFO - 'sessionrestore_no_auto_restore', 12:49:14 INFO - 'tabpaint']}, 12:49:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:49:14 INFO - 'ts_paint', 12:49:14 INFO - 'tpaint', 12:49:14 INFO - 'sessionrestore', 12:49:14 INFO - 'sessionrestore_no_auto_restore', 12:49:14 INFO - 'tabpaint']}, 12:49:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:49:14 INFO - 'ts_paint', 12:49:14 INFO - 'tpaint', 12:49:14 INFO - 'sessionrestore', 12:49:14 INFO - 'sessionrestore_no_auto_restore', 12:49:14 INFO - 'tabpaint']}, 12:49:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:49:14 INFO - 'tests': ['a11yr', 12:49:14 INFO - 'ts_paint', 12:49:14 INFO - 'tpaint', 12:49:14 INFO - 'sessionrestore', 12:49:14 INFO - 'sessionrestore_no_auto_restore', 12:49:14 INFO - 'tabpaint']}, 12:49:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:49:14 INFO - 'tests': ['a11yr', 12:49:14 INFO - 'ts_paint', 12:49:14 INFO - 'tpaint', 12:49:14 INFO - 'sessionrestore', 12:49:14 INFO - 'sessionrestore_no_auto_restore', 12:49:14 INFO - 'tabpaint']}, 12:49:14 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 12:49:14 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 12:49:14 INFO - 'bloom_basic_ref']}, 12:49:14 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 12:49:14 INFO - 'talos_options': ['--mitmproxy', 12:49:14 INFO - 'mitmproxy-recording-1.mp', 12:49:14 INFO - '--firstNonBlankPaint'], 12:49:14 INFO - 'tests': ['Quantum_1']}, 12:49:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:49:14 INFO - 'tests': ['tsvgx', 12:49:14 INFO - 'tsvgr_opacity', 12:49:14 INFO - 'tart', 12:49:14 INFO - 'tscrollx', 12:49:14 INFO - 'cart', 12:49:14 INFO - 'tsvg_static']}, 12:49:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:49:14 INFO - 'tsvgr_opacity', 12:49:14 INFO - 'tart', 12:49:14 INFO - 'tscrollx', 12:49:14 INFO - 'cart', 12:49:14 INFO - 'tsvg_static']}, 12:49:14 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:49:14 INFO - 'talos_options': ['--disable-e10s'], 12:49:14 INFO - 'tests': ['tp5o']}, 12:49:14 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:49:14 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:49:14 INFO - 'talos_options': ['--disable-e10s', 12:49:14 INFO - '--xperf_path', 12:49:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:49:14 INFO - 'tests': ['tp5n']}, 12:49:14 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:49:14 INFO - 'talos_options': ['--xperf_path', 12:49:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:49:14 INFO - 'tests': ['tp5n']}}, 12:49:14 INFO - 'talos.zip': {'path': '', 12:49:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:49:14 INFO - Running post-action listener: _resource_record_post_action 12:49:14 INFO - [mozharness: 2017-06-04 19:49:14.938823Z] Finished populate-webroot step (success) 12:49:14 INFO - [mozharness: 2017-06-04 19:49:14.938963Z] Running create-virtualenv step. 12:49:14 INFO - Running pre-action listener: _resource_record_pre_action 12:49:14 INFO - Running main action method: create_virtualenv 12:49:14 INFO - Creating virtualenv /builds/slave/test/build/venv 12:49:14 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 12:49:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:49:14 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 12:49:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:49:15 INFO - Using real prefix '/tools/python27' 12:49:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:49:15 INFO - Installing distribute.............................................................................................................................................................................................done. 12:49:18 INFO - Installing pip.................done. 12:49:18 INFO - Return code: 0 12:49:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:49:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:49:18 INFO - Reading from file tmpfile_stdout 12:49:18 INFO - Output received: 12:49:18 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) 12:49:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:49:18 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')]} 12:49:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:49:18 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')]} 12:49:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:49:18 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 0x10c5a6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9a2451530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c52fc60>, '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 0x10c2c4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c2edbe8>, '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.It0ZpoPIES/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.ADWUNn96wd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 12:49:18 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 12:49:18 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 12:49:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.It0ZpoPIES/Render', 12:49:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:49:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:49:18 INFO - 'HOME': '/Users/cltbld', 12:49:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:49:18 INFO - 'LOGNAME': 'cltbld', 12:49:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:49:18 INFO - 'MOZ_NO_REMOTE': '1', 12:49:18 INFO - 'NO_EM_RESTART': '1', 12:49:18 INFO - 'PAGER': '/bin/cat', 12:49:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:49:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:49:18 INFO - 'PWD': '/builds/slave/test', 12:49:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:49:18 INFO - 'SHELL': '/bin/bash', 12:49:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ADWUNn96wd/Listeners', 12:49:18 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 12:49:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:49:18 INFO - 'USER': 'cltbld', 12:49:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:49:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:49:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:49:18 INFO - 'XPC_FLAGS': '0x0', 12:49:18 INFO - 'XPC_SERVICE_NAME': '0', 12:49:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:49:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:49:19 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 12:49:19 INFO - Cleaning up... 12:49:19 INFO - Return code: 0 12:49:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:49:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:49:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:49:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:49:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:49:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c5a6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9a2451530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c52fc60>, '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 0x10c2c4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c2edbe8>, '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.It0ZpoPIES/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.ADWUNn96wd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 12:49:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:49:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:49:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.It0ZpoPIES/Render', 12:49:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:49:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:49:19 INFO - 'HOME': '/Users/cltbld', 12:49:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:49:19 INFO - 'LOGNAME': 'cltbld', 12:49:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:49:19 INFO - 'MOZ_NO_REMOTE': '1', 12:49:19 INFO - 'NO_EM_RESTART': '1', 12:49:19 INFO - 'PAGER': '/bin/cat', 12:49:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:49:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:49:19 INFO - 'PWD': '/builds/slave/test', 12:49:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:49:19 INFO - 'SHELL': '/bin/bash', 12:49:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ADWUNn96wd/Listeners', 12:49:19 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 12:49:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:49:19 INFO - 'USER': 'cltbld', 12:49:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:49:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:49:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:49:19 INFO - 'XPC_FLAGS': '0x0', 12:49:19 INFO - 'XPC_SERVICE_NAME': '0', 12:49:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:49:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:49:19 INFO - Downloading/unpacking psutil>=3.1.1 12:49:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:49:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:49:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:49:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:49:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:49:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:49:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:49:21 INFO - Installing collected packages: psutil 12:49:21 INFO - Running setup.py install for psutil 12:49:21 INFO - building 'psutil._psutil_osx' extension 12:49:21 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 12:49:22 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 12:49:22 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 12:49:22 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 12:49:22 INFO - building 'psutil._psutil_posix' extension 12:49:22 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 12:49:22 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:49:22 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:49:22 INFO - ^ 12:49:22 INFO - 1 warning generated. 12:49:22 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 12:49:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:49:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:49:22 INFO - Successfully installed psutil 12:49:22 INFO - Cleaning up... 12:49:22 INFO - Return code: 0 12:49:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:49:22 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')]} 12:49:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:49:22 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')]} 12:49:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:49:22 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 0x10c5a6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9a2451530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c52fc60>, '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 0x10c2c4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c2edbe8>, '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.It0ZpoPIES/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.ADWUNn96wd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 12:49:22 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 12:49:22 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 12:49:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.It0ZpoPIES/Render', 12:49:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:49:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:49:22 INFO - 'HOME': '/Users/cltbld', 12:49:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:49:22 INFO - 'LOGNAME': 'cltbld', 12:49:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:49:22 INFO - 'MOZ_NO_REMOTE': '1', 12:49:22 INFO - 'NO_EM_RESTART': '1', 12:49:22 INFO - 'PAGER': '/bin/cat', 12:49:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:49:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:49:22 INFO - 'PWD': '/builds/slave/test', 12:49:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:49:22 INFO - 'SHELL': '/bin/bash', 12:49:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ADWUNn96wd/Listeners', 12:49:22 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 12:49:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:49:22 INFO - 'USER': 'cltbld', 12:49:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:49:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:49:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:49:22 INFO - 'XPC_FLAGS': '0x0', 12:49:22 INFO - 'XPC_SERVICE_NAME': '0', 12:49:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:49:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:49:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:49:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:49:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:49:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:49:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:49:24 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:49:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:49:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:49:24 INFO - Installing collected packages: mozsystemmonitor 12:49:24 INFO - Running setup.py install for mozsystemmonitor 12:49:24 INFO - Successfully installed mozsystemmonitor 12:49:24 INFO - Cleaning up... 12:49:24 INFO - Return code: 0 12:49:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:49:24 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')]} 12:49:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:49:24 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')]} 12:49:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:49:24 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 0x10c5a6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9a2451530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c52fc60>, '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 0x10c2c4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c2edbe8>, '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.It0ZpoPIES/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.ADWUNn96wd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 12:49:24 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 12:49:24 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 12:49:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.It0ZpoPIES/Render', 12:49:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:49:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:49:24 INFO - 'HOME': '/Users/cltbld', 12:49:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:49:24 INFO - 'LOGNAME': 'cltbld', 12:49:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:49:24 INFO - 'MOZ_NO_REMOTE': '1', 12:49:24 INFO - 'NO_EM_RESTART': '1', 12:49:24 INFO - 'PAGER': '/bin/cat', 12:49:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:49:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:49:24 INFO - 'PWD': '/builds/slave/test', 12:49:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:49:24 INFO - 'SHELL': '/bin/bash', 12:49:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ADWUNn96wd/Listeners', 12:49:24 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 12:49:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:49:24 INFO - 'USER': 'cltbld', 12:49:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:49:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:49:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:49:24 INFO - 'XPC_FLAGS': '0x0', 12:49:24 INFO - 'XPC_SERVICE_NAME': '0', 12:49:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:49:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:49:25 INFO - Downloading/unpacking jsonschema==2.5.1 12:49:25 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 12:49:25 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 12:49:25 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 12:49:25 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 12:49:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:49:26 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:49:26 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 12:49:26 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 12:49:26 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 12:49:26 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 12:49:26 INFO - Downloading functools32-3.2.3-2.tar.gz 12:49:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:49:26 INFO - warning: no files found matching '*.txt' 12:49:26 INFO - no previously-included directories found matching 'build' 12:49:26 INFO - no previously-included directories found matching 'dist' 12:49:26 INFO - no previously-included directories found matching '.git*' 12:49:26 INFO - Installing collected packages: jsonschema, functools32 12:49:26 INFO - Running setup.py install for functools32 12:49:26 INFO - warning: no files found matching '*.txt' 12:49:26 INFO - no previously-included directories found matching 'build' 12:49:26 INFO - no previously-included directories found matching 'dist' 12:49:26 INFO - no previously-included directories found matching '.git*' 12:49:26 INFO - Successfully installed jsonschema functools32 12:49:26 INFO - Cleaning up... 12:49:27 INFO - Return code: 0 12:49:27 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:49:27 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')]} 12:49:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:49:27 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')]} 12:49:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:49:27 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 0x10c5a6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9a2451530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c52fc60>, '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 0x10c2c4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c2edbe8>, '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.It0ZpoPIES/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.ADWUNn96wd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 12:49:27 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 12:49:27 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 12:49:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.It0ZpoPIES/Render', 12:49:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:49:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:49:27 INFO - 'HOME': '/Users/cltbld', 12:49:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:49:27 INFO - 'LOGNAME': 'cltbld', 12:49:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:49:27 INFO - 'MOZ_NO_REMOTE': '1', 12:49:27 INFO - 'NO_EM_RESTART': '1', 12:49:27 INFO - 'PAGER': '/bin/cat', 12:49:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:49:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:49:27 INFO - 'PWD': '/builds/slave/test', 12:49:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:49:27 INFO - 'SHELL': '/bin/bash', 12:49:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ADWUNn96wd/Listeners', 12:49:27 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 12:49:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:49:27 INFO - 'USER': 'cltbld', 12:49:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:49:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:49:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:49:27 INFO - 'XPC_FLAGS': '0x0', 12:49:27 INFO - 'XPC_SERVICE_NAME': '0', 12:49:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:49:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:49:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:49:27 INFO - Cleaning up... 12:49:27 INFO - Return code: 0 12:49:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:49:27 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')]} 12:49:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:49:27 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')]} 12:49:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:49:27 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 0x10c5a6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9a2451530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c52fc60>, '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 0x10c2c4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c2edbe8>, '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.It0ZpoPIES/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.ADWUNn96wd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 12:49:27 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 12:49:27 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 12:49:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.It0ZpoPIES/Render', 12:49:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:49:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:49:27 INFO - 'HOME': '/Users/cltbld', 12:49:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:49:27 INFO - 'LOGNAME': 'cltbld', 12:49:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:49:27 INFO - 'MOZ_NO_REMOTE': '1', 12:49:27 INFO - 'NO_EM_RESTART': '1', 12:49:27 INFO - 'PAGER': '/bin/cat', 12:49:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:49:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:49:27 INFO - 'PWD': '/builds/slave/test', 12:49:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:49:27 INFO - 'SHELL': '/bin/bash', 12:49:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ADWUNn96wd/Listeners', 12:49:27 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 12:49:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:49:27 INFO - 'USER': 'cltbld', 12:49:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:49:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:49:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:49:27 INFO - 'XPC_FLAGS': '0x0', 12:49:27 INFO - 'XPC_SERVICE_NAME': '0', 12:49:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:49:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:49:27 INFO - Downloading/unpacking blobuploader==1.2.4 12:49:27 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 12:49:27 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 12:49:27 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 12:49:27 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 12:49:28 INFO - Downloading blobuploader-1.2.4.tar.gz 12:49:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:49:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:49:29 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 12:49:29 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 12:49:29 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 12:49:29 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 12:49:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:49:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:49:29 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 12:49:29 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 12:49:29 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 12:49:29 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 12:49:29 INFO - Downloading docopt-0.6.1.tar.gz 12:49:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:49:29 INFO - Installing collected packages: blobuploader, requests, docopt 12:49:29 INFO - Running setup.py install for blobuploader 12:49:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:49:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:49:30 INFO - Running setup.py install for requests 12:49:30 INFO - Running setup.py install for docopt 12:49:30 INFO - Successfully installed blobuploader requests docopt 12:49:30 INFO - Cleaning up... 12:49:30 INFO - Return code: 0 12:49:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:49:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:49:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:49:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:49:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:49:30 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 0x10c5a6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9a2451530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c52fc60>, '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 0x10c2c4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c2edbe8>, '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.It0ZpoPIES/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.ADWUNn96wd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 12:49:30 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 12:49:30 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 12:49:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.It0ZpoPIES/Render', 12:49:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:49:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:49:30 INFO - 'HOME': '/Users/cltbld', 12:49:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:49:30 INFO - 'LOGNAME': 'cltbld', 12:49:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:49:30 INFO - 'MOZ_NO_REMOTE': '1', 12:49:30 INFO - 'NO_EM_RESTART': '1', 12:49:30 INFO - 'PAGER': '/bin/cat', 12:49:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:49:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:49:30 INFO - 'PWD': '/builds/slave/test', 12:49:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:49:30 INFO - 'SHELL': '/bin/bash', 12:49:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ADWUNn96wd/Listeners', 12:49:30 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 12:49:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:49:30 INFO - 'USER': 'cltbld', 12:49:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:49:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:49:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:49:30 INFO - 'XPC_FLAGS': '0x0', 12:49:30 INFO - 'XPC_SERVICE_NAME': '0', 12:49:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:49:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:49:31 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-gmGK7e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:49:31 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-gjjqlM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:49:31 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-gHwNZ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:49:31 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-xu0IQB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:49:31 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-2aOD52-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:49:31 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-0hnKRy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:49:31 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-ANCFMP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:49:31 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-kxzS8m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:49:31 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-iwKpLA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:49:31 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-eYN4Ye-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:49:32 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-E_Vfri-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:49:32 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-5_aQY3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:49:32 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-IQ6utK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:49:32 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-VuZfFJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:49:32 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-pg7ZRR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:49:32 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-1hnmyY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:49:32 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-qFpARK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:49:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:49:32 INFO - Running setup.py install for manifestparser 12:49:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:49:32 INFO - Running setup.py install for mozcrash 12:49:33 INFO - Running setup.py install for mozdebug 12:49:33 INFO - Running setup.py install for mozdevice 12:49:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:49:33 INFO - Running setup.py install for mozfile 12:49:33 INFO - Running setup.py install for mozhttpd 12:49:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:49:33 INFO - Running setup.py install for mozinfo 12:49:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:49:33 INFO - Running setup.py install for mozInstall 12:49:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:49:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:49:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:49:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:49:33 INFO - Running setup.py install for mozleak 12:49:34 INFO - Running setup.py install for mozlog 12:49:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:49:34 INFO - Running setup.py install for moznetwork 12:49:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:49:34 INFO - Running setup.py install for mozprocess 12:49:34 INFO - Running setup.py install for mozprofile 12:49:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:49:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:49:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:49:34 INFO - Running setup.py install for mozrunner 12:49:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:49:34 INFO - Running setup.py install for mozscreenshot 12:49:35 INFO - Running setup.py install for moztest 12:49:35 INFO - Running setup.py install for mozversion 12:49:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:49:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:49:35 INFO - Cleaning up... 12:49:35 INFO - Return code: 0 12:49:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:49:35 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')]} 12:49:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:49:35 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')]} 12:49:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:49:35 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 0x10c5a6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9a2451530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c52fc60>, '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 0x10c2c4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c2edbe8>, '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.It0ZpoPIES/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.ADWUNn96wd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 12:49:35 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 12:49:35 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 12:49:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.It0ZpoPIES/Render', 12:49:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:49:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:49:35 INFO - 'HOME': '/Users/cltbld', 12:49:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:49:35 INFO - 'LOGNAME': 'cltbld', 12:49:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:49:35 INFO - 'MOZ_NO_REMOTE': '1', 12:49:35 INFO - 'NO_EM_RESTART': '1', 12:49:35 INFO - 'PAGER': '/bin/cat', 12:49:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:49:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:49:35 INFO - 'PWD': '/builds/slave/test', 12:49:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:49:35 INFO - 'SHELL': '/bin/bash', 12:49:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ADWUNn96wd/Listeners', 12:49:35 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 12:49:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:49:35 INFO - 'USER': 'cltbld', 12:49:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:49:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:49:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:49:35 INFO - 'XPC_FLAGS': '0x0', 12:49:35 INFO - 'XPC_SERVICE_NAME': '0', 12:49:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:49:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:49:35 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-UntxzG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:49:35 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)) 12:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:49:35 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-vlzMP3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:49:35 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)) 12:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:49:35 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-23Jmna-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:49:35 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)) 12:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:49:35 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-pygzUt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:49:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:49:36 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-r1LDDm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:49:36 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)) 12:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:49:36 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-ZfNJ1m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:49:36 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)) 12:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:49:36 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-n8gYCu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:49:36 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)) 12:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:49:36 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-ftq22k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:49:36 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)) 12:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:49:36 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-4GMtYP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:49:36 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)) 12:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:49:36 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-aTVKBy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:49:36 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)) 12:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:49:36 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-sxEfm8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:49:36 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)) 12:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:49:36 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-gzwsVx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:49:36 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)) 12:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:49:36 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-DQUNrI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:49:36 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)) 12:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:49:36 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-wWch3e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:49:37 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)) 12:49:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:49:37 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-HZGLoU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:49:37 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)) 12:49:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:49:37 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-bVET7O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:49:37 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)) 12:49:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:49:37 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-tb5ZMq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:49:37 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)) 12:49:37 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)) 12:49:37 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)) 12:49:37 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)) 12:49:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:49:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:49:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:49:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:49:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:49:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:49:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:49:38 INFO - Downloading blessings-1.6.tar.gz 12:49:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:49:38 INFO - Installing collected packages: blessings 12:49:38 INFO - Running setup.py install for blessings 12:49:39 INFO - Successfully installed blessings 12:49:39 INFO - Cleaning up... 12:49:39 INFO - Return code: 0 12:49:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:49:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:49:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:49:39 INFO - Reading from file tmpfile_stdout 12:49:39 INFO - Current package versions: 12:49:39 INFO - blessings == 1.6 12:49:39 INFO - blobuploader == 1.2.4 12:49:39 INFO - docopt == 0.6.1 12:49:39 INFO - functools32 == 3.2.3-2 12:49:39 INFO - jsonschema == 2.5.1 12:49:39 INFO - manifestparser == 1.1 12:49:39 INFO - mozInstall == 1.12 12:49:39 INFO - mozcrash == 1.0 12:49:39 INFO - mozdebug == 0.1 12:49:39 INFO - mozdevice == 0.51 12:49:39 INFO - mozfile == 1.2 12:49:39 INFO - mozhttpd == 0.7 12:49:39 INFO - mozinfo == 0.9 12:49:39 INFO - mozleak == 0.1 12:49:39 INFO - mozlog == 3.4 12:49:39 INFO - moznetwork == 0.27 12:49:39 INFO - mozprocess == 0.25 12:49:39 INFO - mozprofile == 0.28 12:49:39 INFO - mozrunner == 6.13 12:49:39 INFO - mozscreenshot == 0.1 12:49:39 INFO - mozsystemmonitor == 0.3 12:49:39 INFO - moztest == 0.8 12:49:39 INFO - mozversion == 1.4 12:49:39 INFO - psutil == 3.1.1 12:49:39 INFO - requests == 1.2.3 12:49:39 INFO - wsgiref == 0.1.2 12:49:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:49: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')]} 12:49:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:49: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')]} 12:49:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:49:39 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 0x10c5a6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9a2451530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c52fc60>, '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 0x10c2c4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c2edbe8>, '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.It0ZpoPIES/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.ADWUNn96wd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 12:49:39 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 12:49:39 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 12:49:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.It0ZpoPIES/Render', 12:49:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:49:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:49:39 INFO - 'HOME': '/Users/cltbld', 12:49:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:49:39 INFO - 'LOGNAME': 'cltbld', 12:49:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:49:39 INFO - 'MOZ_NO_REMOTE': '1', 12:49:39 INFO - 'NO_EM_RESTART': '1', 12:49:39 INFO - 'PAGER': '/bin/cat', 12:49:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:49:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:49:39 INFO - 'PWD': '/builds/slave/test', 12:49:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:49:39 INFO - 'SHELL': '/bin/bash', 12:49:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ADWUNn96wd/Listeners', 12:49:39 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 12:49:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:49:39 INFO - 'USER': 'cltbld', 12:49:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:49:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:49:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:49:39 INFO - 'XPC_FLAGS': '0x0', 12:49:39 INFO - 'XPC_SERVICE_NAME': '0', 12:49:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:49:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:49:39 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)) 12:49:39 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)) 12:49:39 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)) 12:49:39 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)) 12:49:39 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)) 12:49:39 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)) 12:49:39 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)) 12:49:39 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)) 12:49:39 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)) 12:49:39 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 12:49:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:49:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:49:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:49:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:49:41 INFO - Downloading marionette_driver-1.4.0.tar.gz 12:49:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 12:49:41 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)) 12:49:41 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)) 12:49:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 12:49:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 12:49:41 INFO - Installing collected packages: marionette-driver 12:49:41 INFO - Running setup.py install for marionette-driver 12:49:41 INFO - Successfully installed marionette-driver 12:49:41 INFO - Cleaning up... 12:49:41 INFO - Return code: 0 12:49:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:49:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:49:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:49:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:49:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:49:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c5a6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9a2451530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c52fc60>, '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 0x10c2c4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c2edbe8>, '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.It0ZpoPIES/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.ADWUNn96wd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 12:49:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:49:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:49:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.It0ZpoPIES/Render', 12:49:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:49:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:49:41 INFO - 'HOME': '/Users/cltbld', 12:49:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:49:41 INFO - 'LOGNAME': 'cltbld', 12:49:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:49:41 INFO - 'MOZ_NO_REMOTE': '1', 12:49:41 INFO - 'NO_EM_RESTART': '1', 12:49:41 INFO - 'PAGER': '/bin/cat', 12:49:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:49:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:49:41 INFO - 'PWD': '/builds/slave/test', 12:49:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:49:41 INFO - 'SHELL': '/bin/bash', 12:49:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ADWUNn96wd/Listeners', 12:49:41 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 12:49:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:49:41 INFO - 'USER': 'cltbld', 12:49:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:49:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:49:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:49:41 INFO - 'XPC_FLAGS': '0x0', 12:49:41 INFO - 'XPC_SERVICE_NAME': '0', 12:49:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:49:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:49:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:49:41 INFO - Cleaning up... 12:49:41 INFO - Return code: 0 12:49:41 INFO - Running post-action listener: _resource_record_post_action 12:49:41 INFO - Running post-action listener: _start_resource_monitoring 12:49:41 INFO - Starting resource monitoring. 12:49:41 INFO - [mozharness: 2017-06-04 19:49:41.728441Z] Finished create-virtualenv step (success) 12:49:41 INFO - [mozharness: 2017-06-04 19:49:41.729424Z] Running install step. 12:49:41 INFO - Running pre-action listener: _resource_record_pre_action 12:49:41 INFO - Running main action method: install 12:49:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:49:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:49:41 INFO - Reading from file tmpfile_stdout 12:49:41 INFO - Detecting whether we're running mozinstall >=1.0... 12:49:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:49:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:49:42 INFO - Reading from file tmpfile_stdout 12:49:42 INFO - Output received: 12:49:42 INFO - Usage: mozinstall [options] installer 12:49:42 INFO - Options: 12:49:42 INFO - -h, --help show this help message and exit 12:49:42 INFO - -d DEST, --destination=DEST 12:49:42 INFO - Directory to install application into. [default: 12:49:42 INFO - "/builds/slave/test"] 12:49:42 INFO - --app=APP Application being installed. [default: firefox] 12:49:42 INFO - mkdir: /builds/slave/test/build/application 12:49:42 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'] 12:49:42 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 12:50:03 INFO - Reading from file tmpfile_stdout 12:50:03 INFO - Output received: 12:50:03 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:50:03 INFO - Running post-action listener: _resource_record_post_action 12:50:03 INFO - [mozharness: 2017-06-04 19:50:03.960149Z] Finished install step (success) 12:50:03 INFO - [mozharness: 2017-06-04 19:50:03.960290Z] Running setup-mitmproxy step. 12:50:03 INFO - Running pre-action listener: _resource_record_pre_action 12:50:03 INFO - Running main action method: setup_mitmproxy 12:50:03 INFO - Skipping: mitmproxy is not required 12:50:03 INFO - Running post-action listener: _resource_record_post_action 12:50:03 INFO - [mozharness: 2017-06-04 19:50:03.960953Z] Finished setup-mitmproxy step (success) 12:50:03 INFO - [mozharness: 2017-06-04 19:50:03.961092Z] Running run-tests step. 12:50:03 INFO - Running pre-action listener: _resource_record_pre_action 12:50:03 INFO - Running main action method: run_tests 12:50:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:50:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:50:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:50:03 INFO - 2017-06-04 12:50:03.975 screenresolution[1804:9121] starting screenresolution argv=screenresolution get 12:50:03 INFO - 2017-06-04 12:50:03.978 screenresolution[1804:9121] Display 0: 1600x1200x32@60 12:50:03 INFO - 2017-06-04 12:50:03.984 screenresolution[1805:9123] starting screenresolution argv=screenresolution list 12:50:03 INFO - Available Modes on Display 0 12:50:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:50:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:50:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:50:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:50:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:50:04 INFO - Intel Iris: 12:50:04 INFO - Chipset Model: Intel Iris 12:50:04 INFO - Type: GPU 12:50:04 INFO - Bus: Built-In 12:50:04 INFO - VRAM (Dynamic, Max): 1536 MB 12:50:04 INFO - Vendor: Intel (0x8086) 12:50:04 INFO - Device ID: 0x0a2e 12:50:04 INFO - Revision ID: 0x0009 12:50:04 INFO - Displays: 12:50:04 INFO - Display: 12:50:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:50:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:50:04 INFO - Display Serial Number: 1600x1200 60 12:50:04 INFO - Main Display: Yes 12:50:04 INFO - Mirror: Off 12:50:04 INFO - Online: Yes 12:50:04 INFO - Rotation: Supported 12:50:04 INFO - Return code: 0 12:50:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:50:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:50:04 INFO - Python 2.7.3 12:50:04 INFO - Return code: 0 12:50:04 INFO - grabbing minidump binary from tooltool 12:50:04 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')]} 12:50:04 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 0x10c52fc60>, '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 0x10c2c4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c2edbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:50:04 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 12:50:04 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 12:50:04 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 12:50:04 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:50:04 INFO - Return code: 0 12:50:04 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:50:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:50:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:50:04 INFO - ENV: RUST_BACKTRACE is now 1 12:50:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:50:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:50:04 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/OCdICIMyRsC2TCJejClHTQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0258', '--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 12:50:04 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/OCdICIMyRsC2TCJejClHTQ/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0258 --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 12:50:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.It0ZpoPIES/Render', 12:50:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:50:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:50:04 INFO - 'HOME': '/Users/cltbld', 12:50:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:50:04 INFO - 'LOGNAME': 'cltbld', 12:50:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:50:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:50:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:50:04 INFO - 'MOZ_NO_REMOTE': '1', 12:50:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:50:04 INFO - 'NO_EM_RESTART': '1', 12:50:04 INFO - 'PAGER': '/bin/cat', 12:50:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:50:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:50:04 INFO - 'PWD': '/builds/slave/test', 12:50:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:50:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:50:04 INFO - 'RUST_BACKTRACE': '1', 12:50:04 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:50:04 INFO - 'SHELL': '/bin/bash', 12:50:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ADWUNn96wd/Listeners', 12:50:04 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 12:50:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:50:04 INFO - 'USER': 'cltbld', 12:50:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:50:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:50:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:50:04 INFO - 'XPC_FLAGS': '0x0', 12:50:04 INFO - 'XPC_SERVICE_NAME': '0', 12:50:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:50:04 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/OCdICIMyRsC2TCJejClHTQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0258', '--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 12:50:04 INFO - mozversion application_buildid: 20170604111033 12:50:04 INFO - mozversion application_changeset: 78450dd743b92638cdcf70b16c0850214782eff4 12:50:04 INFO - mozversion application_display_name: Nightly 12:50:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:50:04 INFO - mozversion application_name: Firefox 12:50:04 INFO - mozversion application_remotingname: firefox 12:50:04 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:50:04 INFO - mozversion application_vendor: Mozilla 12:50:04 INFO - mozversion application_version: 55.0a1 12:50:04 INFO - mozversion platform_buildid: 20170604111033 12:50:04 INFO - mozversion platform_changeset: 78450dd743b92638cdcf70b16c0850214782eff4 12:50:04 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:50:04 INFO - mozversion platform_version: 55.0a1 12:50:04 INFO - using testdate: 1496605804 12:50:04 INFO - actual date: 1496605804 12:50:04 INFO - starting webserver on 'localhost:49223' 12:50:04 INFO - SUITE-START | Running 2 tests 12:50:04 INFO - TEST-START | dromaeo_css 12:50:04 INFO - operating with platform_type : mac_ 12:50:04 INFO - Initialising browser for dromaeo_css test... 12:50:04 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49223/getInfo.html -foreground -profile /var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/tmpe0L9p7/profile 12:50:04 INFO - TEST-INFO | started process 1815 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49223/getInfo.html -foreground) 12:50:38 INFO - TEST-INFO | 1815: killed by SIGTERM 12:50:38 INFO - Browser initialized. 12:50:38 INFO - Running cycle 1/1 for dromaeo_css test... 12:50:38 INFO - TEST-INFO | started process 1826 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/tmpe0L9p7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:50:40 INFO - PID 1826 | 2017-06-04 12:50:40.837 plugin-container[1828:9630] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9943, name = 'com.apple.tsm.portname' 12:50:40 INFO - PID 1826 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:50:40 INFO - PID 1826 | 2017-06-04 12:50:40.838 plugin-container[1828:9630] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f1b, name = 'com.apple.CFPasteboardClient' 12:50:40 INFO - PID 1826 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:50:40 INFO - PID 1826 | 2017-06-04 12:50:40.838 plugin-container[1828:9630] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:50:41 INFO - PID 1826 | 2017-06-04 12:50:41.282 plugin-container[1829:9677] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9743, name = 'com.apple.tsm.portname' 12:50:41 INFO - PID 1826 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:50:41 INFO - PID 1826 | 2017-06-04 12:50:41.282 plugin-container[1829:9677] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4817, name = 'com.apple.CFPasteboardClient' 12:50:41 INFO - PID 1826 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:50:41 INFO - PID 1826 | 2017-06-04 12:50:41.282 plugin-container[1829:9677] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:50:50 INFO - PID 1826 | RSS: Main: 268996608 12:50:50 INFO - PID 1826 | 12:53:52 INFO - PID 1826 | Cycle 1(1): loaded http://localhost:49223/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49223/tests/dromaeo/cssquery-ext.html) 12:53:54 INFO - PID 1826 | RSS: Main: 269348864 12:53:54 INFO - PID 1826 | 12:56:56 INFO - PID 1826 | Cycle 1(1): loaded http://localhost:49223/tests/dromaeo/cssquery-ext.html (next: http://localhost:49223/tests/dromaeo/cssquery-jquery.html) 12:56:57 INFO - PID 1826 | RSS: Main: 282951680 12:56:57 INFO - PID 1826 | 12:59:58 INFO - PID 1826 | Cycle 1(1): loaded http://localhost:49223/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49223/tests/dromaeo/cssquery-mootools.html) 12:59:59 INFO - PID 1826 | RSS: Main: 282718208 12:59:59 INFO - PID 1826 | 13:03:01 INFO - PID 1826 | Cycle 1(1): loaded http://localhost:49223/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49223/tests/dromaeo/cssquery-prototype.html) 13:03:01 INFO - PID 1826 | RSS: Main: 287178752 13:03:01 INFO - PID 1826 | 13:06:04 INFO - PID 1826 | Cycle 1(1): loaded http://localhost:49223/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49223/tests/dromaeo/cssquery-yui.html) 13:06:05 INFO - PID 1826 | RSS: Main: 289316864 13:06:05 INFO - PID 1826 | 13:09:07 INFO - PID 1826 | Cycle 1(1): loaded http://localhost:49223/tests/dromaeo/cssquery-yui.html (next: http://localhost:49223/tests/dromaeo/cssquery-dojo.html) 13:09:07 INFO - PID 1826 | RSS: Main: 289263616 13:09:07 INFO - PID 1826 | 13:09:07 INFO - PID 1826 | __start_tp_report 13:09:07 INFO - PID 1826 | _x_x_mozilla_page_load 13:09:07 INFO - PID 1826 | _x_x_mozilla_page_load_details 13:09:07 INFO - PID 1826 | |i|pagename|runs| 13:09:07 INFO - PID 1826 | |0;dojo.html;3075.5694202811596;3614.72889533285;3643.143861192619;3659.6889264412525;3686.631336866312;6196.473299769525;6223.222097237845;6265.780697675576;6288.276848162463;6325.557210995232;4703.929441058387;5032.597392208624;5172.896542069157;6251.656158911258;6338;5457.072297709379;5497.147942068966;5498.670079795205;5513.448655134474;5531.751071201806;4657.3722479373855;4988.551030407262;5602.299712535933;5664.150377443375;5701.885962280752;5421.891562168774;5478.04134276504;5561.110222364443;5578.609497335188;5622.662640241599;5499.202615620753;5517.889642207133;5528.9723551382385;5538.141588053858;5542.140968149944;5418.133098704227;5480.835574932758;5488.53347465466;5502.8349149525575;5541.889162216733;6160.5071594272795;6245.687715614246;6316.74733010679;6329.651869147195;6342.556021078526;6624.105745724314;6695.832604184886;6778.356056174655;6811.965940170317;6822.965885170543;6726.3609957054005;6747.156605424322;6773.525853190278;6796.694148763318;6798.830029249259;6306.558540902093;6327.5570710050315;6328.272248691367;6331.588446750947;6340.302566717657;6783.389494945479;6873.2783057778215;6943.930560694429;6998.370146686721;7035.577865328097;6335.588186767845;6504.252011018793;6635.800925972132;6667.366600172976;6676.899846502306;5710.371859095496;6727.360900714424;6746.190457145174;6854.691538880762;6860.245373008965;6714.328567143247;6788.321167883172;6796.286389929085;6805.319468053155;6823.93176068233;6572.474202063823;6592.703328350236;6645.534812563171;6813.1142951415995;6822.8635427292165;5368.651037682538;5408.1887716842;5424.1321388577635;5436;5460.31746031746;4801.831935882227;4823.059503396874;4839.322494850724;4844.079624871263;4862.41651001882;6199.070139479114;6209.751609935552;6216.191895053614;6371.713272902731;6394.872102558016;6830.658467076626;6884.242733299333;6898.793036208922;6927.445804335641;6930.44556435494;5278.604104692086;6370.235571731329;6370.77702280418;6381.744730210832;6394.968025159845;4722.574968252874;5013.573846223039;5640.5487560995025;6031.24609423822;6073.1497590337385;5813.389594092644;6924.688389022405;7002.159740830929;7010;7018.052562903943;6788.185417749902;6885.758998435031;6910.308969103049;6915.585064896122;6982.546134501241;6995.965020174866;7013.544119632207;7023.438124949889;7033.718651253893;7047.7180912762915;6529.934700652932;6647.268800432045;6717.361850624183;6737.831554211184;6800.965995169992;6936.583804971718;6942.028116063654;6944.618046007518;6946.16646002483;6977.092977913042;6480.837979050561;6560.8359791005605;6607.240167380813;6710.932890671226;6722.798316050526;6588;6637.900431493435;6672.399484046459;6672.432843208382;6687.76592819249;6448.516361272829;6531.608103513804;6557.9344206557325;6558.081868538313;6561.671916404256;6488.286288508166;6687.097241872285;6695.196576410862;6697.430718388991;6720.663966801738;6395.264544577433;6445;6521.543491955517;6539.869202615826;6565.343465653396 13:09:07 INFO - PID 1826 | |1;ext.html;694.4479139084427;783.4085265624452;842.2882664148794;1285.1903300920412;1337.8394592648883;38965.80517097414;39930.60069399305;39946.60053399465;39977.60022399775;39997.20005599886;40712.38931416024;40913.77258682237;41007.58992410075;41020.384694229535;41155.79422102885;7950.801229969254;8088.716894908679;8271.54506502142;8445.788855278606;8469.830603387929;8381.07808141104;8436.831263374728;8538.615762290712;8558.87161692572;8580.313574914024;8464.703735369263;8495.787605309855;8541.444806087615;8552.315814734806;8556.229939305462;8429.283510901581;8505.957470212672;8537.57312134397;8538.743837684879;8539.487630742175;8348.791280217982;8373.371997100241;8380.8742868857;8395.328373730086;8404.705835295796;37679.81160094182;37961.24077518462;37980.43029354562;37987.240255195014;38033.619663803285;3475.7740746851628;3507.91230219244;3518.648135186461;3519.04985653872;3522.3131489359585;30888;31013.8449307752;31156.688433115607;31174.22064448407;31233.8438307807;3031.575579418883;3078.291992841637;3090.6600273969843;3099.5970523831984;3100.5039193728894;234223;238097;277581.61209194025;290482;290749;47929.520704793205;48319.75840120777;48325.5167448321;48374.75812620985;48559.75720121377;46921;50656.746716266185;50695.74652126716;50859;54580.90448241783;14681.853181468263;14817.925910370379;14819.259037048105;14827.92586037063;14834.035787673767;11932.463039163258;11980.221285616408;12026.338551379758;12047.819282710767;12065;14187.14877107377;14240.003199776123;14378.35297411619;14426.06230595008;14524.709505810037;5365.785368585291;6215.192025036717;6252.937470625327;6285.717142728588;6334.5430550972615;963.5904740485406;983.5868935047297;998.7153661206667;1034.891336409666;1036.2797855490512;18418.723719144222;21808.89095554512;28840.134795956154;30778.69221307803;31463.05610831679;1019.4953498018529;1087.2389327470803;1091.792559413709;1142.251825054574;1144.690933447981;11734.882651173379;12593.181443206162;12599.93700031494;12629.684257893627;12750.681232969064;319313;322707.38646306616;322990;323229;324442;53966.730166349706;53984;54135.18797218045;54155.64610884759;54252.72873635607;1318.4264844792547;1448.1455940994654;1488.4767823066136;1525.9694806103817;1544.6138465383654;22704.65943010823;22889.198878039853;23727.406814829767;24029.51940961206;24144.275671729876;37992;38292.85121446361;38370.42444363281;38608.42087368748;38751.224975500896;261681;262383.3761662359;263214;263524;263724;34472;34656.65343346534;34895.12762180966;35131;35178.296434071686;34963.25183740752;42542.574574253864;43521.34717979296;44314.556854431044;44330;29261.85369073141;29290.97481588134;29934.25164370908;30271.697283026886;30824.53763193509;24979;26268.73731262663;26997.59503607487;30717;30875;36097;36304.81847590745;36431.90704278876;36763.89708308755;37224.81387593045;29230.70769292365;29686.257843554085;30485.390292193588;30707.040498442366;30893.53659695151;22393.88412821372;23130.76869231286;24009.759902400754;24111.03555857746;24144 13:09:07 INFO - PID 1826 | |2;jquery.html;424.984287552749;433.1661551513336;442.0716495359744;447.80520473593987;455.86551967169686;14838.406463741465;15570.844291557081;21723.610402855695;22056.33830985071;22466.213682521095;23038.539229215407;23919.521609567797;23979.801009949475;23989.520209595797;24018.03927842884;6053.87892242156;6207.751689932397;6221.129041934133;6226.813195604139;6257.780977665782;6078.240219972504;6091.512678985692;6100.969495152518;6174.567780255384;6184.8453788655415;6148.077788331763;6611.801645950629;6958.060661810667;7026.402755765766;7031.332023457763;6851.794446166623;6869.931300686979;6922.342377474157;6936.895946560806;6948.305169483062;6746.426553742888;6768.593884366905;6776.728930842761;6778.288279730656;6789.1174147360525;29645.851770741006;30030;30296;30358.696413035806;30771.538426923613;2309.307207837659;2339.239747082188;2341.0167729553627;2347.2371479269304;2348.3659411958674;19088.33190838328;19452.027398630013;19673;19712.901435492873;19773.60452790936;2276.681264622954;2312.167619656922;2360.811135109187;2369.6563998220336;2382.285314405671;194706;195397;196341;198522.0147798518;199069;24738.381540461272;25071;25900.611490827654;26222.47555048888;26336.341591459983;36323.4551481728;38234.85295441141;41326.173476530304;41750;41870.58129418728;9655.662051828293;11291.435428228555;11353.659390218307;11517.884821151682;11547;7486.663100160506;9125.45247285165;9175.632974681072;9190.63237470507;9202.125798049176;7824.92175078242;8292.668293268202;11769.764604708029;12002.21985570935;12026.579069732692;2138.155428605692;2219.4895174110047;2276.5902137615058;2293.2776175504664;2472.418981539337;930.3905941608258;930.4510338900081;942.528735632184;988.5304480371909;992.806402751471;18124.45626631203;18447.800892941916;20173.899130504255;20233.392998210078;20831.37505874826;858.3305022082785;968.5205823117602;1007.9546420410952;1009.8535712321673;1041.682286902502;10285.691429257135;10426.947865260625;10681.83977240342;10785.40680262593;10997.12023038155;393964;473964;540454;552368;554195;146784;148001.25999369935;148078;149751.25124374309;153962;997.9650712225038;1174.0783484964263;1225.093430861174;1226.1171956191706;1230.796918508436;20328.390148295573;21389.67915481238;22358.664620030388;22648.980795864554;22868.31395058151;67673.64652707017;68611.65694171497;68871.31128688648;69092.30907690858;69299;94675;98910;99481.50259248658;99600.50199749245;99937;8625.008124065813;9948.900510995087;10266.948665256627;10280.691579252634;10388.532516036948;13660.112092713942;14549.781753273497;17044.74432883483;18284;18474.261029558667;14811;14892.0320179188;15023.849761502244;15040.774388384401;15132.697346053237;12905.741885162432;13134.737305254033;13184.802227966396;13406.731865362834;13494.32528373597;14937.477188298357;15072.849271507583;15167;15252.084874907541;15349.846501534841;10194.745131371776;10233.13018393415;10235;10243.795124097625;10261.384316941007;12084.577039803564;13678;14751.92624036873;14868.182249976355;14891.776623350443 13:09:07 INFO - PID 1826 | |3;mootools.html;1239.6343078791756;1349.5681381957772;1395.8344782106947;1424.7177540213806;1435.3612642374142;11714.707132321697;11757.529698812059;12225.938870305657;12267.325297108666;12561.309127997956;12147.75704485912;12236;12368.443420046096;12416.192947458409;12422.751544969095;2440.829141960068;2507.661465702125;2510.410053637394;2511.5353659572947;2512.208654273907;2131.328631481086;2216.7339919209717;2499.262717498342;2499.300195945129;2561.679790026247;2554.3103362092224;2557.2839604910564;2578.033237535924;2578.445634188649;2578.716341202466;2461;2462.1505580574762;2469.259222233323;2473.3692908308444;2478.7273399926084;2494.9376265593323;2541.2884392370165;2542.834715743489;2543.1480454047914;2545.592705167183;9088.545572721338;11104.44477776116;11965.700857478545;12123.151379403445;12142;6492.09110724499;6698.732050717966;6734.259231484532;6763.188417389895;6769.05233267343;4123.752574845489;4248.192843359751;4315.115401342718;4347.130573885204;4348.21732088224;1219.3659297165514;1243.8445194350707;1273.7834568123349;1275.3623188405797;1276;16757;16928.153592320334;16942.983420994657;16961.236744346534;16977.830221697874;5463.69949652765;5539.529140023144;5693.943060569424;5725.08398656213;5778.306603207642;12439.564615238423;12462.75074498505;12477.75044499105;12485.25088494675;12505.749885002431;6382.712777925005;6550.2794692583775;6556.147700798961;6556.344365563501;6559.147310849559;6114.205153330127;6167.938320616737;6173.290071641819;6177.660228687465;6228.283747369111;4331.7834108294455;4421.9336709949375;4525.570070843265;4531.274996000624;4567.566081222279;1165.347405452949;1199.484221784641;1260.615512268767;1272.6054922973908;1304.2370213425063;704.7674267491716;805.295366554265;822.8601137806587;844.531285136755;855.1320409784074;6981.89527157093;7099.36105750485;7347.926520734831;7475.289847464591;7634.465587408939;951.3578334624101;970.9563069661882;972.7373609125497;976.7899901521207;999.3703966501058;4574.862754117382;4627.9074418511445;4689.484156742756;4753.096911586787;4875.561199492063;14812.555623331318;14914;15239.314230859638;15239.390424383122;15422;14364.497242596668;14433.134011959106;14471.565853024425;14526.927365363106;14548.272586370638;8262.462939909006;8407.369447291356;8413.537255450887;8414.032386275656;8432.325413966868;6638.336166383516;6642.368974947372;6749.73001079971;7605.429592780453;7645;13420.060595758203;13783.310834458438;13837;13943.930280348533;14032.579022628928;11198.44007799623;11465;14023.859761402255;14363.712725745218;14692.338844752225;9423.38747981379;9746.658866939622;9785.80428391414;9801.215902727907;9946.502674866373;9497.525123743648;11012.39431831225;11276.492557835081;11300.773984520429;11320.433978300887;9226.907730922874;9320.254379649747;9347.971723110588;9563;9596.32825702222;9105.954470227607;9210.94074181451;9246.907530924873;9259.398139120936;9395.812083758423;7958.045034595885;8288.088310285742;8302.16978302189;8344.66621335164;8418.242358187792;5351.571874249973;6131;6360.713767880549;6628.53600247978;6660.234073081658;6334.968325158345;6586.868262634816;6678.632675202911;6716.529842910949;6836.589804611739 13:09:07 INFO - PID 1826 | |4;prototype.html;370.73307218802466;390.62695126154523;395.1721144202896;396.3104198694271;407.39095052895925;5247.86880327991;6247.875042499159;6391.7948307833785;6408.455281301095;6416.262129855068;5381.973090134554;5533.3083364579425;6227.937720622793;6236.912728199548;6238.688065596713;3758.68051215647;3818.5799562048223;4603.240465323218;4629.884252893671;4647.395838540985;4329.567043295677;4630.8147674093;4651.418572678415;4660.440747110351;4667.97304592989;3945.546262179843;4553.408056952591;4569.702969307001;4589.495155532889;4603.8158473661015;3636.709063274932;4560.179167749804;4600.837606325022;4614.630829533646;4631.9536804632025;3983.322835118037;3990.920181596352;3993.820278087493;4007.2185923744883;4025.4766880305665;6244.250689917194;6245.812625621239;6267.874642507124;6276.148536181838;6317.020861766434;4820.349252850855;5327.227552004938;5541.750621222014;5557.694326812024;5565.972170139164;6807.183138023419;6811.25076241613;6814.454843612499;6814.965925170392;6844.623545704984;4507.504174540798;4524.773761311922;4528.965593052662;4545.386372839657;4547;5622.662640241558;5651.519620832234;5983.324669092617;7121.003059571664;7252.238514955907;3988.581198974124;4026.9395959060053;4027.0536423940353;4469.843555475542;4554.453465584151;4235;4354.281543545343;4653.557911998355;4658.534146585383;4683.531646835358;4481.901934026119;5286.444923283077;5346.385165705916;5505.6696598204235;5537.280153580009;6225.439710426083;6878.071460352889;6879.793606191723;6880.449564034965;6892.931070689229;1336.85294617592;1546.1264385622087;1591.761235814637;1599.2963096237618;1601.1913983438521;797.5055465611242;898.2006014646969;1009.8333774927202;1041.1415623112707;1052.921030922684;6163.84590385235;6440.227172739301;6440.259370172396;6442.452391546677;6472.320406357265;6081.878362432726;6463.739570783746;6993.2657071007825;7070;7097.92902070983;5829.446202610745;6300.621962682162;6389.393007664265;6405.8718825624155;6418.679066046772;3676.6507181817683;3692.48305237267;3717.553893532793;3725;3737.719671024684;6000.429959153874;7175.820604484929;7307.19620841707;7327.63361831917;7432.145303290083;6066.66633335162;7175.964120179366;7270.018547496126;7270.745523906638;7280.635968201675;5976.710611109286;6130.417610327012;6708.2956289589865;7117.71529138839;7148.749793757193;5915.704214789157;6728.461723062242;7133.750318738819;7137.321954414322;7147.425647585445;5738.713064346849;5751.597388182787;5767.731099158178;7035.6833942471685;7073.559621930426;6760.966195168992;6853.417459515797;7263.201047884834;7299.708011679473;7316.853662926818;3852.998220462756;4217.557156498524;4563.178627847019;4594.402727645384;4595.494495605414;6146.376065899652;6530.771423000172;7089.468289878177;7120.572765634079;7125.5368401053765;6261.154744109487;6675.599464032173;6904.344087311697;6907.861842763016;6912.688928998106;6859.3140685933;6860;6897;6901.413379862768;6920.273371295942;6629.569078010107;6744.22441419243;6754.898676519758;6769.93230067693;6771.729130834711;5699.316082070012;5718.513926316144;6936.687849046704;6941.409980151744;6949.366898778681;5397;6260.780872669435;6653.205653625392;6739.865202696017;6765.221999470124 13:09:07 INFO - PID 1826 | |5;yui.html;791.7505985614532;836.8619177835658;846.2975730143985;871.7907702151485;872.0973792125152;14576.125432474058;15116.546503604883;16795.664086718258;16974.5756356091;17304.567385815346;17243.31026758928;18047.007414592194;18158.72761908569;18207.453776386697;18212.908935455303;979.9167070798973;984.6849008317341;984.8867380251255;994.174834887042;1003.5484032185509;839.8446287436814;1006.5219020965056;1010.1363334349136;1014.2697257974247;1035.829088200446;941.2517048946104;1041.0682439216935;1051.2536099369456;1052.5894900988621;1057.4501259345136;1051.2588625019343;1051.8790339110947;1053.1259054984382;1054.7204990677476;1055.292953721005;758.1243663568587;771.8842173673994;772.1506343022686;773.1302284929454;774.9806254843618;17111.144442777935;17150.399736009178;17221.827781722146;17305;17457.563560910952;17403.825961740346;17428.738568921475;17515.824841751673;17887.1950762216;17922;14650.413983440756;14882.404703811835;14895.851041489555;14925.850741492555;14937.327820248114;3509.508668786372;3586.3006713690843;3599.2621512590117;3616.258666973264;3625.546806649169;149284.2535787325;152185.9999999989;152826;152999.2350038254;153540.32298385035;13648.112872663245;13756.587302380945;13899;13941.954353423534;14112.153270803785;21318.147274108862;26484.205473835817;26881.596776048376;26921.596176057374;27161.45677086487;5640.210370548127;7001.544899581511;7681.1934746850775;7845.529268243924;7944.761657150179;3402.285520040819;3932.6067393260446;3976.1451287973223;4009.69927255457;4017.5580686124504;3715.6623615498397;4123.195976784498;4240.49114106303;4289.270823959871;4342.435483387139;804.3364224514681;945.9952700236455;946.4415994563241;947.0718695678136;956.4787190980932;629.7953165221321;644.2944975252092;647.5564238496645;654.2084078265257;682.3585829320423;11268.588286887454;11604.825927611093;11627.418629068512;12122.39388030595;12398.442070106867;626.7774939896271;630.6562923206864;637.8851806674751;658.8287045368239;667.2360147631032;5471.5896307777075;5597.916031259534;5677.829665110054;5749.453801888814;5917.082852157922;141369.29315353357;148548.257258713;151827;152201.0817944587;153993;108665;125319;128121.35939320244;131921;135714;78490;88759;110052.4497377508;117608;120070.39964800295;10018.198544116307;10875.619353322913;11410.82883756761;11463.197576169589;12022.819657705317;47833.89079709808;47986;48040;48748.75625621849;49676.50323496719;42829.78585107055;51972.220416693024;52595.73702131465;65797;69292.65353673199;14568.198749068903;14739.484118056245;14763.92618036903;15112.01771884824;15119.470818521298;16426.507204783877;16592.83407165913;16612.00327980325;16671.166441677626;16859.325626975275;14701;15209.847901520843;15412.691746165237;15459.536213913601;15500.767488487456;15172;15181.392744290546;15347.155906425254;15484.845151548341;15520.456784012957;14789.334479948213;15082.924585377003;15103.018303810217;15205.923970380078;15277.236138192824;9350.345475816628;10044.246681498771;10090.646827361006;10602.575896964036;11932;10070.647527336509;12185.634430966731;12611.810822837848;12818.974482041227;13002.479900803863 13:09:07 INFO - PID 1826 | __end_tp_report 13:09:07 INFO - PID 1826 | __start_cc_report 13:09:07 INFO - PID 1826 | _x_x_mozilla_cycle_collect,454 13:09:07 INFO - PID 1826 | __end_cc_report 13:09:07 INFO - PID 1826 | __startTimestamp1496606947384__endTimestamp 13:09:07 INFO - PID 1826 | 13:09:07 INFO - PID 1826 | ------- Summary: start ------- 13:09:07 INFO - PID 1826 | Number of tests: 6 13:09:07 INFO - PID 1826 | 13:09:07 INFO - PID 1826 | [#0] dojo.html Cycles:165 Average:6202.14 Median:6488.29 stddev:790.33 (12.2%) stddev-sans-first:753.73 13:09:07 INFO - PID 1826 | Values: 3075.6 3614.7 3643.1 3659.7 3686.6 6196.5 6223.2 6265.8 6288.3 6325.6 4703.9 5032.6 5172.9 6251.7 6338.0 5457.1 5497.1 5498.7 5513.4 5531.8 4657.4 4988.6 5602.3 5664.2 5701.9 5421.9 5478.0 5561.1 5578.6 5622.7 5499.2 5517.9 5529.0 5538.1 5542.1 5418.1 5480.8 5488.5 5502.8 5541.9 6160.5 6245.7 6316.7 6329.7 6342.6 6624.1 6695.8 6778.4 6812.0 6823.0 6726.4 6747.2 6773.5 6796.7 6798.8 6306.6 6327.6 6328.3 6331.6 6340.3 6783.4 6873.3 6943.9 6998.4 7035.6 6335.6 6504.3 6635.8 6667.4 6676.9 5710.4 6727.4 6746.2 6854.7 6860.2 6714.3 6788.3 6796.3 6805.3 6823.9 6572.5 6592.7 6645.5 6813.1 6822.9 5368.7 5408.2 5424.1 5436.0 5460.3 4801.8 4823.1 4839.3 4844.1 4862.4 6199.1 6209.8 6216.2 6371.7 6394.9 6830.7 6884.2 6898.8 6927.4 6930.4 5278.6 6370.2 6370.8 6381.7 6395.0 4722.6 5013.6 5640.5 6031.2 6073.1 5813.4 6924.7 7002.2 7010.0 7018.1 6788.2 6885.8 6910.3 6915.6 6982.5 6996.0 7013.5 7023.4 7033.7 7047.7 6529.9 6647.3 6717.4 6737.8 6801.0 6936.6 6942.0 6944.6 6946.2 6977.1 6480.8 6560.8 6607.2 6710.9 6722.8 6588.0 6637.9 6672.4 6672.4 6687.8 6448.5 6531.6 6557.9 6558.1 6561.7 6488.3 6687.1 6695.2 6697.4 6720.7 6395.3 6445.0 6521.5 6539.9 6565.3 13:09:07 INFO - PID 1826 | 13:09:07 INFO - PID 1826 | [#1] ext.html Cycles:180 Average:43909.49 Median:19066.21 stddev:74765.04 (392.1%) stddev-sans-first:74904.37 13:09:07 INFO - PID 1826 | Values: 694.4 783.4 842.3 1285.2 1337.8 38965.8 39930.6 39946.6 39977.6 39997.2 40712.4 40913.8 41007.6 41020.4 41155.8 7950.8 8088.7 8271.5 8445.8 8469.8 8381.1 8436.8 8538.6 8558.9 8580.3 8464.7 8495.8 8541.4 8552.3 8556.2 8429.3 8506.0 8537.6 8538.7 8539.5 8348.8 8373.4 8380.9 8395.3 8404.7 37679.8 37961.2 37980.4 37987.2 38033.6 3475.8 3507.9 3518.6 3519.0 3522.3 30888.0 31013.8 31156.7 31174.2 31233.8 3031.6 3078.3 3090.7 3099.6 3100.5 234223.0 238097.0 277581.6 290482.0 290749.0 47929.5 48319.8 48325.5 48374.8 48559.8 46921.0 50656.7 50695.7 50859.0 54580.9 14681.9 14817.9 14819.3 14827.9 14834.0 11932.5 11980.2 12026.3 12047.8 12065.0 14187.1 14240.0 14378.4 14426.1 14524.7 5365.8 6215.2 6252.9 6285.7 6334.5 963.6 983.6 998.7 1034.9 1036.3 18418.7 21808.9 28840.1 30778.7 31463.1 1019.5 1087.2 1091.8 1142.3 1144.7 11734.9 12593.2 12599.9 12629.7 12750.7 319313.0 322707.4 322990.0 323229.0 324442.0 53966.7 53984.0 54135.2 54155.6 54252.7 1318.4 1448.1 1488.5 1526.0 1544.6 22704.7 22889.2 23727.4 24029.5 24144.3 37992.0 38292.9 38370.4 38608.4 38751.2 261681.0 262383.4 263214.0 263524.0 263724.0 34472.0 34656.7 34895.1 35131.0 35178.3 34963.3 42542.6 43521.3 44314.6 44330.0 29261.9 29291.0 29934.3 30271.7 30824.5 24979.0 26268.7 26997.6 30717.0 30875.0 36097.0 36304.8 36431.9 36763.9 37224.8 29230.7 29686.3 30485.4 30707.0 30893.5 22393.9 23130.8 24009.8 24111.0 24144.0 13:09:07 INFO - PID 1826 | 13:09:07 INFO - PID 1826 | [#2] jquery.html Cycles:180 Average:38733.21 Median:2340.13 stddev:89322.10 (3817.0%) stddev-sans-first:89526.36 13:09:07 INFO - PID 1826 | Values: 425.0 433.2 442.1 447.8 455.9 14838.4 15570.8 21723.6 22056.3 22466.2 23038.5 23919.5 23979.8 23989.5 24018.0 6053.9 6207.8 6221.1 6226.8 6257.8 6078.2 6091.5 6101.0 6174.6 6184.8 6148.1 6611.8 6958.1 7026.4 7031.3 6851.8 6869.9 6922.3 6936.9 6948.3 6746.4 6768.6 6776.7 6778.3 6789.1 29645.9 30030.0 30296.0 30358.7 30771.5 2309.3 2339.2 2341.0 2347.2 2348.4 19088.3 19452.0 19673.0 19712.9 19773.6 2276.7 2312.2 2360.8 2369.7 2382.3 194706.0 195397.0 196341.0 198522.0 199069.0 24738.4 25071.0 25900.6 26222.5 26336.3 36323.5 38234.9 41326.2 41750.0 41870.6 9655.7 11291.4 11353.7 11517.9 11547.0 7486.7 9125.5 9175.6 9190.6 9202.1 7824.9 8292.7 11769.8 12002.2 12026.6 2138.2 2219.5 2276.6 2293.3 2472.4 930.4 930.5 942.5 988.5 992.8 18124.5 18447.8 20173.9 20233.4 20831.4 858.3 968.5 1008.0 1009.9 1041.7 10285.7 10426.9 10681.8 10785.4 10997.1 393964.0 473964.0 540454.0 552368.0 554195.0 146784.0 148001.3 148078.0 149751.3 153962.0 998.0 1174.1 1225.1 1226.1 1230.8 20328.4 21389.7 22358.7 22649.0 22868.3 67673.6 68611.7 68871.3 69092.3 69299.0 94675.0 98910.0 99481.5 99600.5 99937.0 8625.0 9948.9 10266.9 10280.7 10388.5 13660.1 14549.8 17044.7 18284.0 18474.3 14811.0 14892.0 15023.8 15040.8 15132.7 12905.7 13134.7 13184.8 13406.7 13494.3 14937.5 15072.8 15167.0 15252.1 15349.8 10194.7 10233.1 10235.0 10243.8 10261.4 12084.6 13678.0 14751.9 14868.2 14891.8 13:09:07 INFO - PID 1826 | 13:09:07 INFO - PID 1826 | [#3] mootools.html Cycles:180 Average:7260.21 Median:4323.45 stddev:4556.86 (105.4%) stddev-sans-first:4547.18 13:09:07 INFO - PID 1826 | Values: 1239.6 1349.6 1395.8 1424.7 1435.4 11714.7 11757.5 12225.9 12267.3 12561.3 12147.8 12236.0 12368.4 12416.2 12422.8 2440.8 2507.7 2510.4 2511.5 2512.2 2131.3 2216.7 2499.3 2499.3 2561.7 2554.3 2557.3 2578.0 2578.4 2578.7 2461.0 2462.2 2469.3 2473.4 2478.7 2494.9 2541.3 2542.8 2543.1 2545.6 9088.5 11104.4 11965.7 12123.2 12142.0 6492.1 6698.7 6734.3 6763.2 6769.1 4123.8 4248.2 4315.1 4347.1 4348.2 1219.4 1243.8 1273.8 1275.4 1276.0 16757.0 16928.2 16943.0 16961.2 16977.8 5463.7 5539.5 5693.9 5725.1 5778.3 12439.6 12462.8 12477.8 12485.3 12505.7 6382.7 6550.3 6556.1 6556.3 6559.1 6114.2 6167.9 6173.3 6177.7 6228.3 4331.8 4421.9 4525.6 4531.3 4567.6 1165.3 1199.5 1260.6 1272.6 1304.2 704.8 805.3 822.9 844.5 855.1 6981.9 7099.4 7347.9 7475.3 7634.5 951.4 971.0 972.7 976.8 999.4 4574.9 4627.9 4689.5 4753.1 4875.6 14812.6 14914.0 15239.3 15239.4 15422.0 14364.5 14433.1 14471.6 14526.9 14548.3 8262.5 8407.4 8413.5 8414.0 8432.3 6638.3 6642.4 6749.7 7605.4 7645.0 13420.1 13783.3 13837.0 13943.9 14032.6 11198.4 11465.0 14023.9 14363.7 14692.3 9423.4 9746.7 9785.8 9801.2 9946.5 9497.5 11012.4 11276.5 11300.8 11320.4 9226.9 9320.3 9348.0 9563.0 9596.3 9106.0 9210.9 9246.9 9259.4 9395.8 7958.0 8288.1 8302.2 8344.7 8418.2 5351.6 6131.0 6360.7 6628.5 6660.2 6335.0 6586.9 6678.6 6716.5 6836.6 13:09:07 INFO - PID 1826 | 13:09:07 INFO - PID 1826 | [#4] prototype.html Cycles:180 Average:5417.33 Median:6106.15 stddev:1742.15 (28.5%) stddev-sans-first:1705.36 13:09:07 INFO - PID 1826 | Values: 370.7 390.6 395.2 396.3 407.4 5247.9 6247.9 6391.8 6408.5 6416.3 5382.0 5533.3 6227.9 6236.9 6238.7 3758.7 3818.6 4603.2 4629.9 4647.4 4329.6 4630.8 4651.4 4660.4 4668.0 3945.5 4553.4 4569.7 4589.5 4603.8 3636.7 4560.2 4600.8 4614.6 4632.0 3983.3 3990.9 3993.8 4007.2 4025.5 6244.3 6245.8 6267.9 6276.1 6317.0 4820.3 5327.2 5541.8 5557.7 5566.0 6807.2 6811.3 6814.5 6815.0 6844.6 4507.5 4524.8 4529.0 4545.4 4547.0 5622.7 5651.5 5983.3 7121.0 7252.2 3988.6 4026.9 4027.1 4469.8 4554.5 4235.0 4354.3 4653.6 4658.5 4683.5 4481.9 5286.4 5346.4 5505.7 5537.3 6225.4 6878.1 6879.8 6880.4 6892.9 1336.9 1546.1 1591.8 1599.3 1601.2 797.5 898.2 1009.8 1041.1 1052.9 6163.8 6440.2 6440.3 6442.5 6472.3 6081.9 6463.7 6993.3 7070.0 7097.9 5829.4 6300.6 6389.4 6405.9 6418.7 3676.7 3692.5 3717.6 3725.0 3737.7 6000.4 7175.8 7307.2 7327.6 7432.1 6066.7 7176.0 7270.0 7270.7 7280.6 5976.7 6130.4 6708.3 7117.7 7148.7 5915.7 6728.5 7133.8 7137.3 7147.4 5738.7 5751.6 5767.7 7035.7 7073.6 6761.0 6853.4 7263.2 7299.7 7316.9 3853.0 4217.6 4563.2 4594.4 4595.5 6146.4 6530.8 7089.5 7120.6 7125.5 6261.2 6675.6 6904.3 6907.9 6912.7 6859.3 6860.0 6897.0 6901.4 6920.3 6629.6 6744.2 6754.9 6769.9 6771.7 5699.3 5718.5 6936.7 6941.4 6949.4 5397.0 6260.8 6653.2 6739.9 6765.2 13:09:07 INFO - PID 1826 | 13:09:07 INFO - PID 1826 | [#5] yui.html Cycles:180 Average:25394.92 Median:17130.77 stddev:40572.02 (236.8%) stddev-sans-first:40643.78 13:09:07 INFO - PID 1826 | Values: 791.8 836.9 846.3 871.8 872.1 14576.1 15116.5 16795.7 16974.6 17304.6 17243.3 18047.0 18158.7 18207.5 18212.9 979.9 984.7 984.9 994.2 1003.5 839.8 1006.5 1010.1 1014.3 1035.8 941.3 1041.1 1051.3 1052.6 1057.5 1051.3 1051.9 1053.1 1054.7 1055.3 758.1 771.9 772.2 773.1 775.0 17111.1 17150.4 17221.8 17305.0 17457.6 17403.8 17428.7 17515.8 17887.2 17922.0 14650.4 14882.4 14895.9 14925.9 14937.3 3509.5 3586.3 3599.3 3616.3 3625.5 149284.3 152186.0 152826.0 152999.2 153540.3 13648.1 13756.6 13899.0 13942.0 14112.2 21318.1 26484.2 26881.6 26921.6 27161.5 5640.2 7001.5 7681.2 7845.5 7944.8 3402.3 3932.6 3976.1 4009.7 4017.6 3715.7 4123.2 4240.5 4289.3 4342.4 804.3 946.0 946.4 947.1 956.5 629.8 644.3 647.6 654.2 682.4 11268.6 11604.8 11627.4 12122.4 12398.4 626.8 630.7 637.9 658.8 667.2 5471.6 5597.9 5677.8 5749.5 5917.1 141369.3 148548.3 151827.0 152201.1 153993.0 108665.0 125319.0 128121.4 131921.0 135714.0 78490.0 88759.0 110052.4 117608.0 120070.4 10018.2 10875.6 11410.8 11463.2 12022.8 47833.9 47986.0 48040.0 48748.8 49676.5 42829.8 51972.2 52595.7 65797.0 69292.7 14568.2 14739.5 14763.9 15112.0 15119.5 16426.5 16592.8 16612.0 16671.2 16859.3 14701.0 15209.8 15412.7 15459.5 15500.8 15172.0 15181.4 15347.2 15484.8 15520.5 14789.3 15082.9 15103.0 15205.9 15277.2 9350.3 10044.2 10090.6 10602.6 11932.0 10070.6 12185.6 12611.8 12819.0 13002.5 13:09:07 INFO - PID 1826 | -------- Summary: end -------- 13:09:07 INFO - PID 1826 | 13:09:08 INFO - TEST-INFO | 1826: exit 0 13:09:08 INFO - TEST-OK | dromaeo_css | took 1143439ms 13:09:08 INFO - TEST-START | kraken 13:09:08 INFO - operating with platform_type : mac_ 13:09:08 INFO - Initialising browser for kraken test... 13:09:08 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49223/getInfo.html -foreground -profile /var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/tmp8BDFss/profile 13:09:08 INFO - TEST-INFO | started process 1947 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49223/getInfo.html -foreground) 13:09:42 INFO - TEST-INFO | 1947: killed by SIGTERM 13:09:42 INFO - Browser initialized. 13:09:42 INFO - Running cycle 1/1 for kraken test... 13:09:42 INFO - TEST-INFO | started process 1955 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/tmp8BDFss/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:09:44 INFO - PID 1955 | 2017-06-04 13:09:44.369 plugin-container[1956:12437] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x983f, name = 'com.apple.tsm.portname' 13:09:44 INFO - PID 1955 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:09:44 INFO - PID 1955 | 2017-06-04 13:09:44.370 plugin-container[1956:12437] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4923, name = 'com.apple.CFPasteboardClient' 13:09:44 INFO - PID 1955 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:09:44 INFO - PID 1955 | 2017-06-04 13:09:44.370 plugin-container[1956:12437] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:09:44 INFO - PID 1955 | 2017-06-04 13:09:44.762 plugin-container[1957:12482] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4943, name = 'com.apple.tsm.portname' 13:09:44 INFO - PID 1955 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:09:44 INFO - PID 1955 | 2017-06-04 13:09:44.763 plugin-container[1957:12482] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x480b, name = 'com.apple.CFPasteboardClient' 13:09:44 INFO - PID 1955 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:09:44 INFO - PID 1955 | 2017-06-04 13:09:44.763 plugin-container[1957:12482] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:09:54 INFO - PID 1955 | RSS: Main: 268877824 13:09:54 INFO - PID 1955 | 13:09:56 INFO - PID 1955 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49223/tests/kraken/driver.html?testName=audio-beat-detection) 13:09:57 INFO - PID 1955 | RSS: Main: 276680704 13:09:57 INFO - PID 1955 | 13:10:00 INFO - PID 1955 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49223/tests/kraken/driver.html?testName=audio-dft) 13:10:01 INFO - PID 1955 | RSS: Main: 285188096 13:10:01 INFO - PID 1955 | 13:10:04 INFO - PID 1955 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49223/tests/kraken/driver.html?testName=audio-fft) 13:10:05 INFO - PID 1955 | RSS: Main: 283103232 13:10:05 INFO - PID 1955 | 13:10:07 INFO - PID 1955 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49223/tests/kraken/driver.html?testName=audio-oscillator) 13:10:07 INFO - PID 1955 | RSS: Main: 284917760 13:10:07 INFO - PID 1955 | 13:10:09 INFO - PID 1955 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49223/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:10:10 INFO - PID 1955 | RSS: Main: 286117888 13:10:10 INFO - PID 1955 | 13:10:13 INFO - PID 1955 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49223/tests/kraken/driver.html?testName=imaging-darkroom) 13:10:13 INFO - PID 1955 | RSS: Main: 290365440 13:10:13 INFO - PID 1955 | 13:10:16 INFO - PID 1955 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49223/tests/kraken/driver.html?testName=imaging-desaturate) 13:10:17 INFO - PID 1955 | RSS: Main: 293531648 13:10:17 INFO - PID 1955 | 13:10:20 INFO - PID 1955 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49223/tests/kraken/driver.html?testName=json-parse-financial) 13:10:21 INFO - PID 1955 | RSS: Main: 292503552 13:10:21 INFO - PID 1955 | 13:10:22 INFO - PID 1955 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49223/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:10:23 INFO - PID 1955 | RSS: Main: 292163584 13:10:23 INFO - PID 1955 | 13:10:24 INFO - PID 1955 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:10:25 INFO - PID 1955 | RSS: Main: 292237312 13:10:25 INFO - PID 1955 | 13:10:27 INFO - PID 1955 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:10:28 INFO - PID 1955 | RSS: Main: 292003840 13:10:28 INFO - PID 1955 | 13:10:31 INFO - PID 1955 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:10:32 INFO - PID 1955 | RSS: Main: 291520512 13:10:32 INFO - PID 1955 | 13:10:35 INFO - PID 1955 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:10:36 INFO - PID 1955 | RSS: Main: 291848192 13:10:36 INFO - PID 1955 | 13:10:38 INFO - PID 1955 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49223/tests/kraken/driver.html?testName=ai-astar) 13:10:38 INFO - PID 1955 | RSS: Main: 293629952 13:10:38 INFO - PID 1955 | 13:10:38 INFO - PID 1955 | __start_tp_report 13:10:38 INFO - PID 1955 | _x_x_mozilla_page_load 13:10:38 INFO - PID 1955 | _x_x_mozilla_page_load_details 13:10:38 INFO - PID 1955 | |i|pagename|runs| 13:10:38 INFO - PID 1955 | |0;ai-astar;101;118;115;116;115;114;115;141;172;153 13:10:38 INFO - PID 1955 | |1;audio-beat-detection;145;111;118;140;150;149;103;136;167;148 13:10:38 INFO - PID 1955 | |2;audio-dft;198;214;258;236;224;203;193;197;195;197 13:10:38 INFO - PID 1955 | |3;audio-fft;72;56;66;115;76;58;62;81;88;50 13:10:38 INFO - PID 1955 | |4;audio-oscillator;76;70;96;104;72;69;86;68;69;90 13:10:38 INFO - PID 1955 | |5;imaging-gaussian-blur;93;93;92;92;93;91;97;106;93;91 13:10:38 INFO - PID 1955 | |6;imaging-darkroom;98;99;99;98;103;129;100;99;101;98 13:10:38 INFO - PID 1955 | |7;imaging-desaturate;91;91;90;94;94;91;92;92;92;90 13:10:38 INFO - PID 1955 | |8;json-parse-financial;52;51;52;51;52;52;53;52;53;53 13:10:38 INFO - PID 1955 | |9;json-stringify-tinderbox;61;64;59;61;65;62;59;58;58;58 13:10:38 INFO - PID 1955 | |10;stanford-crypto-aes;87;87;89;89;84;88;91;97;130;122 13:10:38 INFO - PID 1955 | |11;stanford-crypto-ccm;136;136;132;142;147;130;139;153;136;139 13:10:38 INFO - PID 1955 | |12;stanford-crypto-pbkdf2;187;183;242;236;222;230;213;178;232;235 13:10:38 INFO - PID 1955 | |13;stanford-crypto-sha256-iterative;66;72;97;92;92;94;90;65;66;65 13:10:38 INFO - PID 1955 | __end_tp_report 13:10:38 INFO - PID 1955 | __start_cc_report 13:10:38 INFO - PID 1955 | _x_x_mozilla_cycle_collect,1203 13:10:38 INFO - PID 1955 | __end_cc_report 13:10:38 INFO - PID 1955 | __startTimestamp1496607038283__endTimestamp 13:10:38 INFO - PID 1955 | 13:10:38 INFO - PID 1955 | ------- Summary: start ------- 13:10:38 INFO - PID 1955 | Number of tests: 14 13:10:38 INFO - PID 1955 | 13:10:38 INFO - PID 1955 | [#0] ai-astar Cycles:10 Average:126.00 Median:117.00 stddev:22.03 (18.8%) stddev-sans-first:21.42 13:10:38 INFO - PID 1955 | Values: 101.0 118.0 115.0 116.0 115.0 114.0 115.0 141.0 172.0 153.0 13:10:38 INFO - PID 1955 | 13:10:38 INFO - PID 1955 | [#1] audio-beat-detection Cycles:10 Average:136.70 Median:146.50 stddev:20.00 (13.7%) stddev-sans-first:20.99 13:10:38 INFO - PID 1955 | Values: 145.0 111.0 118.0 140.0 150.0 149.0 103.0 136.0 167.0 148.0 13:10:38 INFO - PID 1955 | 13:10:38 INFO - PID 1955 | [#2] audio-dft Cycles:10 Average:211.50 Median:208.50 stddev:21.64 (10.4%) stddev-sans-first:22.39 13:10:38 INFO - PID 1955 | Values: 198.0 214.0 258.0 236.0 224.0 203.0 193.0 197.0 195.0 197.0 13:10:38 INFO - PID 1955 | 13:10:38 INFO - PID 1955 | [#3] audio-fft Cycles:10 Average:72.40 Median:69.00 stddev:19.07 (27.6%) stddev-sans-first:20.22 13:10:38 INFO - PID 1955 | Values: 72.0 56.0 66.0 115.0 76.0 58.0 62.0 81.0 88.0 50.0 13:10:38 INFO - PID 1955 | 13:10:38 INFO - PID 1955 | [#4] audio-oscillator Cycles:10 Average:80.00 Median:74.00 stddev:13.06 (17.6%) stddev-sans-first:13.77 13:10:38 INFO - PID 1955 | Values: 76.0 70.0 96.0 104.0 72.0 69.0 86.0 68.0 69.0 90.0 13:10:38 INFO - PID 1955 | 13:10:38 INFO - PID 1955 | [#5] imaging-gaussian-blur Cycles:10 Average:94.10 Median:93.00 stddev:4.51 (4.8%) stddev-sans-first:4.76 13:10:38 INFO - PID 1955 | Values: 93.0 93.0 92.0 92.0 93.0 91.0 97.0 106.0 93.0 91.0 13:10:38 INFO - PID 1955 | 13:10:38 INFO - PID 1955 | [#6] imaging-darkroom Cycles:10 Average:102.40 Median:98.00 stddev:9.48 (9.7%) stddev-sans-first:9.92 13:10:38 INFO - PID 1955 | Values: 98.0 99.0 99.0 98.0 103.0 129.0 100.0 99.0 101.0 98.0 13:10:38 INFO - PID 1955 | 13:10:38 INFO - PID 1955 | [#7] imaging-desaturate Cycles:10 Average:91.70 Median:92.00 stddev:1.42 (1.5%) stddev-sans-first:1.48 13:10:38 INFO - PID 1955 | Values: 91.0 91.0 90.0 94.0 94.0 91.0 92.0 92.0 92.0 90.0 13:10:38 INFO - PID 1955 | 13:10:38 INFO - PID 1955 | [#8] json-parse-financial Cycles:10 Average:52.10 Median:52.00 stddev:0.74 (1.4%) stddev-sans-first:0.78 13:10:38 INFO - PID 1955 | Values: 52.0 51.0 52.0 51.0 52.0 52.0 53.0 52.0 53.0 53.0 13:10:38 INFO - PID 1955 | 13:10:38 INFO - PID 1955 | [#9] json-stringify-tinderbox Cycles:10 Average:60.50 Median:61.00 stddev:2.55 (4.2%) stddev-sans-first:2.70 13:10:38 INFO - PID 1955 | Values: 61.0 64.0 59.0 61.0 65.0 62.0 59.0 58.0 58.0 58.0 13:10:38 INFO - PID 1955 | 13:10:38 INFO - PID 1955 | [#10] stanford-crypto-aes Cycles:10 Average:96.40 Median:88.50 stddev:16.07 (18.2%) stddev-sans-first:16.68 13:10:38 INFO - PID 1955 | Values: 87.0 87.0 89.0 89.0 84.0 88.0 91.0 97.0 130.0 122.0 13:10:38 INFO - PID 1955 | 13:10:38 INFO - PID 1955 | [#11] stanford-crypto-ccm Cycles:10 Average:139.00 Median:139.00 stddev:6.88 (4.9%) stddev-sans-first:7.21 13:10:38 INFO - PID 1955 | Values: 136.0 136.0 132.0 142.0 147.0 130.0 139.0 153.0 136.0 139.0 13:10:38 INFO - PID 1955 | 13:10:38 INFO - PID 1955 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:215.80 Median:231.00 stddev:24.28 (10.5%) stddev-sans-first:23.41 13:10:38 INFO - PID 1955 | Values: 187.0 183.0 242.0 236.0 222.0 230.0 213.0 178.0 232.0 235.0 13:10:38 INFO - PID 1955 | 13:10:38 INFO - PID 1955 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:79.90 Median:91.00 stddev:14.06 (15.4%) stddev-sans-first:13.98 13:10:38 INFO - PID 1955 | Values: 66.0 72.0 97.0 92.0 92.0 94.0 90.0 65.0 66.0 65.0 13:10:38 INFO - PID 1955 | -------- Summary: end -------- 13:10:38 INFO - PID 1955 | 13:10:39 INFO - TEST-INFO | 1955: exit 0 13:10:39 INFO - TEST-OK | kraken | took 91079ms 13:10:39 INFO - SUITE-END | took 1234s 13:10:39 INFO - Completed test suite (00:20:34) 13:10:39 INFO - javascript benchmark 13:10:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8158.156284656096, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3075.5694202811596, 3614.72889533285, 3643.143861192619, 3659.6889264412525, 3686.631336866312, 6196.473299769525, 6223.222097237845, 6265.780697675576, 6288.276848162463, 6325.557210995232, 4703.929441058387, 5032.597392208624, 5172.896542069157, 6251.656158911258, 6338.0, 5457.072297709379, 5497.147942068966, 5498.670079795205, 5513.448655134474, 5531.751071201806, 4657.3722479373855, 4988.551030407262, 5602.299712535933, 5664.150377443375, 5701.885962280752, 5421.891562168774, 5478.04134276504, 5561.110222364443, 5578.609497335188, 5622.662640241599, 5499.202615620753, 5517.889642207133, 5528.9723551382385, 5538.141588053858, 5542.140968149944, 5418.133098704227, 5480.835574932758, 5488.53347465466, 5502.8349149525575, 5541.889162216733, 6160.5071594272795, 6245.687715614246, 6316.74733010679, 6329.651869147195, 6342.556021078526, 6624.105745724314, 6695.832604184886, 6778.356056174655, 6811.965940170317, 6822.965885170543, 6726.3609957054005, 6747.156605424322, 6773.525853190278, 6796.694148763318, 6798.830029249259, 6306.558540902093, 6327.5570710050315, 6328.272248691367, 6331.588446750947, 6340.302566717657, 6783.389494945479, 6873.2783057778215, 6943.930560694429, 6998.370146686721, 7035.577865328097, 6335.588186767845, 6504.252011018793, 6635.800925972132, 6667.366600172976, 6676.899846502306, 5710.371859095496, 6727.360900714424, 6746.190457145174, 6854.691538880762, 6860.245373008965, 6714.328567143247, 6788.321167883172, 6796.286389929085, 6805.319468053155, 6823.93176068233, 6572.474202063823, 6592.703328350236, 6645.534812563171, 6813.1142951415995, 6822.8635427292165, 5368.651037682538, 5408.1887716842, 5424.1321388577635, 5436.0, 5460.31746031746, 4801.831935882227, 4823.059503396874, 4839.322494850724, 4844.079624871263, 4862.41651001882, 6199.070139479114, 6209.751609935552, 6216.191895053614, 6371.713272902731, 6394.872102558016, 6830.658467076626, 6884.242733299333, 6898.793036208922, 6927.445804335641, 6930.44556435494, 5278.604104692086, 6370.235571731329, 6370.77702280418, 6381.744730210832, 6394.968025159845, 4722.574968252874, 5013.573846223039, 5640.5487560995025, 6031.24609423822, 6073.1497590337385, 5813.389594092644, 6924.688389022405, 7002.159740830929, 7010.0, 7018.052562903943, 6788.185417749902, 6885.758998435031, 6910.308969103049, 6915.585064896122, 6982.546134501241, 6995.965020174866, 7013.544119632207, 7023.438124949889, 7033.718651253893, 7047.7180912762915, 6529.934700652932, 6647.268800432045, 6717.361850624183, 6737.831554211184, 6800.965995169992, 6936.583804971718, 6942.028116063654, 6944.618046007518, 6946.16646002483, 6977.092977913042, 6480.837979050561, 6560.8359791005605, 6607.240167380813, 6710.932890671226, 6722.798316050526, 6588.0, 6637.900431493435, 6672.399484046459, 6672.432843208382, 6687.76592819249, 6448.516361272829, 6531.608103513804, 6557.9344206557325, 6558.081868538313, 6561.671916404256, 6488.286288508166, 6687.097241872285, 6695.196576410862, 6697.430718388991, 6720.663966801738, 6395.264544577433, 6445.0, 6521.543491955517, 6539.869202615826, 6565.343465653396], "value": 6148.10040782126, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [694.4479139084427, 783.4085265624452, 842.2882664148794, 1285.1903300920412, 1337.8394592648883, 38965.80517097414, 39930.60069399305, 39946.60053399465, 39977.60022399775, 39997.20005599886, 40712.38931416024, 40913.77258682237, 41007.58992410075, 41020.384694229535, 41155.79422102885, 7950.801229969254, 8088.716894908679, 8271.54506502142, 8445.788855278606, 8469.830603387929, 8381.07808141104, 8436.831263374728, 8538.615762290712, 8558.87161692572, 8580.313574914024, 8464.703735369263, 8495.787605309855, 8541.444806087615, 8552.315814734806, 8556.229939305462, 8429.283510901581, 8505.957470212672, 8537.57312134397, 8538.743837684879, 8539.487630742175, 8348.791280217982, 8373.371997100241, 8380.8742868857, 8395.328373730086, 8404.705835295796, 37679.81160094182, 37961.24077518462, 37980.43029354562, 37987.240255195014, 38033.619663803285, 3475.7740746851628, 3507.91230219244, 3518.648135186461, 3519.04985653872, 3522.3131489359585, 30888.0, 31013.8449307752, 31156.688433115607, 31174.22064448407, 31233.8438307807, 3031.575579418883, 3078.291992841637, 3090.6600273969843, 3099.5970523831984, 3100.5039193728894, 234223.0, 238097.0, 277581.61209194025, 290482.0, 290749.0, 47929.520704793205, 48319.75840120777, 48325.5167448321, 48374.75812620985, 48559.75720121377, 46921.0, 50656.746716266185, 50695.74652126716, 50859.0, 54580.90448241783, 14681.853181468263, 14817.925910370379, 14819.259037048105, 14827.92586037063, 14834.035787673767, 11932.463039163258, 11980.221285616408, 12026.338551379758, 12047.819282710767, 12065.0, 14187.14877107377, 14240.003199776123, 14378.35297411619, 14426.06230595008, 14524.709505810037, 5365.785368585291, 6215.192025036717, 6252.937470625327, 6285.717142728588, 6334.5430550972615, 963.5904740485406, 983.5868935047297, 998.7153661206667, 1034.891336409666, 1036.2797855490512, 18418.723719144222, 21808.89095554512, 28840.134795956154, 30778.69221307803, 31463.05610831679, 1019.4953498018529, 1087.2389327470803, 1091.792559413709, 1142.251825054574, 1144.690933447981, 11734.882651173379, 12593.181443206162, 12599.93700031494, 12629.684257893627, 12750.681232969064, 319313.0, 322707.38646306616, 322990.0, 323229.0, 324442.0, 53966.730166349706, 53984.0, 54135.18797218045, 54155.64610884759, 54252.72873635607, 1318.4264844792547, 1448.1455940994654, 1488.4767823066136, 1525.9694806103817, 1544.6138465383654, 22704.65943010823, 22889.198878039853, 23727.406814829767, 24029.51940961206, 24144.275671729876, 37992.0, 38292.85121446361, 38370.42444363281, 38608.42087368748, 38751.224975500896, 261681.0, 262383.3761662359, 263214.0, 263524.0, 263724.0, 34472.0, 34656.65343346534, 34895.12762180966, 35131.0, 35178.296434071686, 34963.25183740752, 42542.574574253864, 43521.34717979296, 44314.556854431044, 44330.0, 29261.85369073141, 29290.97481588134, 29934.25164370908, 30271.697283026886, 30824.53763193509, 24979.0, 26268.73731262663, 26997.59503607487, 30717.0, 30875.0, 36097.0, 36304.81847590745, 36431.90704278876, 36763.89708308755, 37224.81387593045, 29230.70769292365, 29686.257843554085, 30485.390292193588, 30707.040498442366, 30893.53659695151, 22393.88412821372, 23130.76869231286, 24009.759902400754, 24111.03555857746, 24144.0], "value": 17547.017048746166, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [424.984287552749, 433.1661551513336, 442.0716495359744, 447.80520473593987, 455.86551967169686, 14838.406463741465, 15570.844291557081, 21723.610402855695, 22056.33830985071, 22466.213682521095, 23038.539229215407, 23919.521609567797, 23979.801009949475, 23989.520209595797, 24018.03927842884, 6053.87892242156, 6207.751689932397, 6221.129041934133, 6226.813195604139, 6257.780977665782, 6078.240219972504, 6091.512678985692, 6100.969495152518, 6174.567780255384, 6184.8453788655415, 6148.077788331763, 6611.801645950629, 6958.060661810667, 7026.402755765766, 7031.332023457763, 6851.794446166623, 6869.931300686979, 6922.342377474157, 6936.895946560806, 6948.305169483062, 6746.426553742888, 6768.593884366905, 6776.728930842761, 6778.288279730656, 6789.1174147360525, 29645.851770741006, 30030.0, 30296.0, 30358.696413035806, 30771.538426923613, 2309.307207837659, 2339.239747082188, 2341.0167729553627, 2347.2371479269304, 2348.3659411958674, 19088.33190838328, 19452.027398630013, 19673.0, 19712.901435492873, 19773.60452790936, 2276.681264622954, 2312.167619656922, 2360.811135109187, 2369.6563998220336, 2382.285314405671, 194706.0, 195397.0, 196341.0, 198522.0147798518, 199069.0, 24738.381540461272, 25071.0, 25900.611490827654, 26222.47555048888, 26336.341591459983, 36323.4551481728, 38234.85295441141, 41326.173476530304, 41750.0, 41870.58129418728, 9655.662051828293, 11291.435428228555, 11353.659390218307, 11517.884821151682, 11547.0, 7486.663100160506, 9125.45247285165, 9175.632974681072, 9190.63237470507, 9202.125798049176, 7824.92175078242, 8292.668293268202, 11769.764604708029, 12002.21985570935, 12026.579069732692, 2138.155428605692, 2219.4895174110047, 2276.5902137615058, 2293.2776175504664, 2472.418981539337, 930.3905941608258, 930.4510338900081, 942.528735632184, 988.5304480371909, 992.806402751471, 18124.45626631203, 18447.800892941916, 20173.899130504255, 20233.392998210078, 20831.37505874826, 858.3305022082785, 968.5205823117602, 1007.9546420410952, 1009.8535712321673, 1041.682286902502, 10285.691429257135, 10426.947865260625, 10681.83977240342, 10785.40680262593, 10997.12023038155, 393964.0, 473964.0, 540454.0, 552368.0, 554195.0, 146784.0, 148001.25999369935, 148078.0, 149751.25124374309, 153962.0, 997.9650712225038, 1174.0783484964263, 1225.093430861174, 1226.1171956191706, 1230.796918508436, 20328.390148295573, 21389.67915481238, 22358.664620030388, 22648.980795864554, 22868.31395058151, 67673.64652707017, 68611.65694171497, 68871.31128688648, 69092.30907690858, 69299.0, 94675.0, 98910.0, 99481.50259248658, 99600.50199749245, 99937.0, 8625.008124065813, 9948.900510995087, 10266.948665256627, 10280.691579252634, 10388.532516036948, 13660.112092713942, 14549.781753273497, 17044.74432883483, 18284.0, 18474.261029558667, 14811.0, 14892.0320179188, 15023.849761502244, 15040.774388384401, 15132.697346053237, 12905.741885162432, 13134.737305254033, 13184.802227966396, 13406.731865362834, 13494.32528373597, 14937.477188298357, 15072.849271507583, 15167.0, 15252.084874907541, 15349.846501534841, 10194.745131371776, 10233.13018393415, 10235.0, 10243.795124097625, 10261.384316941007, 12084.577039803564, 13678.0, 14751.92624036873, 14868.182249976355, 14891.776623350443], "value": 11974.023449936329, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1239.6343078791756, 1349.5681381957772, 1395.8344782106947, 1424.7177540213806, 1435.3612642374142, 11714.707132321697, 11757.529698812059, 12225.938870305657, 12267.325297108666, 12561.309127997956, 12147.75704485912, 12236.0, 12368.443420046096, 12416.192947458409, 12422.751544969095, 2440.829141960068, 2507.661465702125, 2510.410053637394, 2511.5353659572947, 2512.208654273907, 2131.328631481086, 2216.7339919209717, 2499.262717498342, 2499.300195945129, 2561.679790026247, 2554.3103362092224, 2557.2839604910564, 2578.033237535924, 2578.445634188649, 2578.716341202466, 2461.0, 2462.1505580574762, 2469.259222233323, 2473.3692908308444, 2478.7273399926084, 2494.9376265593323, 2541.2884392370165, 2542.834715743489, 2543.1480454047914, 2545.592705167183, 9088.545572721338, 11104.44477776116, 11965.700857478545, 12123.151379403445, 12142.0, 6492.09110724499, 6698.732050717966, 6734.259231484532, 6763.188417389895, 6769.05233267343, 4123.752574845489, 4248.192843359751, 4315.115401342718, 4347.130573885204, 4348.21732088224, 1219.3659297165514, 1243.8445194350707, 1273.7834568123349, 1275.3623188405797, 1276.0, 16757.0, 16928.153592320334, 16942.983420994657, 16961.236744346534, 16977.830221697874, 5463.69949652765, 5539.529140023144, 5693.943060569424, 5725.08398656213, 5778.306603207642, 12439.564615238423, 12462.75074498505, 12477.75044499105, 12485.25088494675, 12505.749885002431, 6382.712777925005, 6550.2794692583775, 6556.147700798961, 6556.344365563501, 6559.147310849559, 6114.205153330127, 6167.938320616737, 6173.290071641819, 6177.660228687465, 6228.283747369111, 4331.7834108294455, 4421.9336709949375, 4525.570070843265, 4531.274996000624, 4567.566081222279, 1165.347405452949, 1199.484221784641, 1260.615512268767, 1272.6054922973908, 1304.2370213425063, 704.7674267491716, 805.295366554265, 822.8601137806587, 844.531285136755, 855.1320409784074, 6981.89527157093, 7099.36105750485, 7347.926520734831, 7475.289847464591, 7634.465587408939, 951.3578334624101, 970.9563069661882, 972.7373609125497, 976.7899901521207, 999.3703966501058, 4574.862754117382, 4627.9074418511445, 4689.484156742756, 4753.096911586787, 4875.561199492063, 14812.555623331318, 14914.0, 15239.314230859638, 15239.390424383122, 15422.0, 14364.497242596668, 14433.134011959106, 14471.565853024425, 14526.927365363106, 14548.272586370638, 8262.462939909006, 8407.369447291356, 8413.537255450887, 8414.032386275656, 8432.325413966868, 6638.336166383516, 6642.368974947372, 6749.73001079971, 7605.429592780453, 7645.0, 13420.060595758203, 13783.310834458438, 13837.0, 13943.930280348533, 14032.579022628928, 11198.44007799623, 11465.0, 14023.859761402255, 14363.712725745218, 14692.338844752225, 9423.38747981379, 9746.658866939622, 9785.80428391414, 9801.215902727907, 9946.502674866373, 9497.525123743648, 11012.39431831225, 11276.492557835081, 11300.773984520429, 11320.433978300887, 9226.907730922874, 9320.254379649747, 9347.971723110588, 9563.0, 9596.32825702222, 9105.954470227607, 9210.94074181451, 9246.907530924873, 9259.398139120936, 9395.812083758423, 7958.045034595885, 8288.088310285742, 8302.16978302189, 8344.66621335164, 8418.242358187792, 5351.571874249973, 6131.0, 6360.713767880549, 6628.53600247978, 6660.234073081658, 6334.968325158345, 6586.868262634816, 6678.632675202911, 6716.529842910949, 6836.589804611739], "value": 5490.267708359593, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [370.73307218802466, 390.62695126154523, 395.1721144202896, 396.3104198694271, 407.39095052895925, 5247.86880327991, 6247.875042499159, 6391.7948307833785, 6408.455281301095, 6416.262129855068, 5381.973090134554, 5533.3083364579425, 6227.937720622793, 6236.912728199548, 6238.688065596713, 3758.68051215647, 3818.5799562048223, 4603.240465323218, 4629.884252893671, 4647.395838540985, 4329.567043295677, 4630.8147674093, 4651.418572678415, 4660.440747110351, 4667.97304592989, 3945.546262179843, 4553.408056952591, 4569.702969307001, 4589.495155532889, 4603.8158473661015, 3636.709063274932, 4560.179167749804, 4600.837606325022, 4614.630829533646, 4631.9536804632025, 3983.322835118037, 3990.920181596352, 3993.820278087493, 4007.2185923744883, 4025.4766880305665, 6244.250689917194, 6245.812625621239, 6267.874642507124, 6276.148536181838, 6317.020861766434, 4820.349252850855, 5327.227552004938, 5541.750621222014, 5557.694326812024, 5565.972170139164, 6807.183138023419, 6811.25076241613, 6814.454843612499, 6814.965925170392, 6844.623545704984, 4507.504174540798, 4524.773761311922, 4528.965593052662, 4545.386372839657, 4547.0, 5622.662640241558, 5651.519620832234, 5983.324669092617, 7121.003059571664, 7252.238514955907, 3988.581198974124, 4026.9395959060053, 4027.0536423940353, 4469.843555475542, 4554.453465584151, 4235.0, 4354.281543545343, 4653.557911998355, 4658.534146585383, 4683.531646835358, 4481.901934026119, 5286.444923283077, 5346.385165705916, 5505.6696598204235, 5537.280153580009, 6225.439710426083, 6878.071460352889, 6879.793606191723, 6880.449564034965, 6892.931070689229, 1336.85294617592, 1546.1264385622087, 1591.761235814637, 1599.2963096237618, 1601.1913983438521, 797.5055465611242, 898.2006014646969, 1009.8333774927202, 1041.1415623112707, 1052.921030922684, 6163.84590385235, 6440.227172739301, 6440.259370172396, 6442.452391546677, 6472.320406357265, 6081.878362432726, 6463.739570783746, 6993.2657071007825, 7070.0, 7097.92902070983, 5829.446202610745, 6300.621962682162, 6389.393007664265, 6405.8718825624155, 6418.679066046772, 3676.6507181817683, 3692.48305237267, 3717.553893532793, 3725.0, 3737.719671024684, 6000.429959153874, 7175.820604484929, 7307.19620841707, 7327.63361831917, 7432.145303290083, 6066.66633335162, 7175.964120179366, 7270.018547496126, 7270.745523906638, 7280.635968201675, 5976.710611109286, 6130.417610327012, 6708.2956289589865, 7117.71529138839, 7148.749793757193, 5915.704214789157, 6728.461723062242, 7133.750318738819, 7137.321954414322, 7147.425647585445, 5738.713064346849, 5751.597388182787, 5767.731099158178, 7035.6833942471685, 7073.559621930426, 6760.966195168992, 6853.417459515797, 7263.201047884834, 7299.708011679473, 7316.853662926818, 3852.998220462756, 4217.557156498524, 4563.178627847019, 4594.402727645384, 4595.494495605414, 6146.376065899652, 6530.771423000172, 7089.468289878177, 7120.572765634079, 7125.5368401053765, 6261.154744109487, 6675.599464032173, 6904.344087311697, 6907.861842763016, 6912.688928998106, 6859.3140685933, 6860.0, 6897.0, 6901.413379862768, 6920.273371295942, 6629.569078010107, 6744.22441419243, 6754.898676519758, 6769.93230067693, 6771.729130834711, 5699.316082070012, 5718.513926316144, 6936.687849046704, 6941.409980151744, 6949.366898778681, 5397.0, 6260.780872669435, 6653.205653625392, 6739.865202696017, 6765.221999470124], "value": 4865.336503199073, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [791.7505985614532, 836.8619177835658, 846.2975730143985, 871.7907702151485, 872.0973792125152, 14576.125432474058, 15116.546503604883, 16795.664086718258, 16974.5756356091, 17304.567385815346, 17243.31026758928, 18047.007414592194, 18158.72761908569, 18207.453776386697, 18212.908935455303, 979.9167070798973, 984.6849008317341, 984.8867380251255, 994.174834887042, 1003.5484032185509, 839.8446287436814, 1006.5219020965056, 1010.1363334349136, 1014.2697257974247, 1035.829088200446, 941.2517048946104, 1041.0682439216935, 1051.2536099369456, 1052.5894900988621, 1057.4501259345136, 1051.2588625019343, 1051.8790339110947, 1053.1259054984382, 1054.7204990677476, 1055.292953721005, 758.1243663568587, 771.8842173673994, 772.1506343022686, 773.1302284929454, 774.9806254843618, 17111.144442777935, 17150.399736009178, 17221.827781722146, 17305.0, 17457.563560910952, 17403.825961740346, 17428.738568921475, 17515.824841751673, 17887.1950762216, 17922.0, 14650.413983440756, 14882.404703811835, 14895.851041489555, 14925.850741492555, 14937.327820248114, 3509.508668786372, 3586.3006713690843, 3599.2621512590117, 3616.258666973264, 3625.546806649169, 149284.2535787325, 152185.9999999989, 152826.0, 152999.2350038254, 153540.32298385035, 13648.112872663245, 13756.587302380945, 13899.0, 13941.954353423534, 14112.153270803785, 21318.147274108862, 26484.205473835817, 26881.596776048376, 26921.596176057374, 27161.45677086487, 5640.210370548127, 7001.544899581511, 7681.1934746850775, 7845.529268243924, 7944.761657150179, 3402.285520040819, 3932.6067393260446, 3976.1451287973223, 4009.69927255457, 4017.5580686124504, 3715.6623615498397, 4123.195976784498, 4240.49114106303, 4289.270823959871, 4342.435483387139, 804.3364224514681, 945.9952700236455, 946.4415994563241, 947.0718695678136, 956.4787190980932, 629.7953165221321, 644.2944975252092, 647.5564238496645, 654.2084078265257, 682.3585829320423, 11268.588286887454, 11604.825927611093, 11627.418629068512, 12122.39388030595, 12398.442070106867, 626.7774939896271, 630.6562923206864, 637.8851806674751, 658.8287045368239, 667.2360147631032, 5471.5896307777075, 5597.916031259534, 5677.829665110054, 5749.453801888814, 5917.082852157922, 141369.29315353357, 148548.257258713, 151827.0, 152201.0817944587, 153993.0, 108665.0, 125319.0, 128121.35939320244, 131921.0, 135714.0, 78490.0, 88759.0, 110052.4497377508, 117608.0, 120070.39964800295, 10018.198544116307, 10875.619353322913, 11410.82883756761, 11463.197576169589, 12022.819657705317, 47833.89079709808, 47986.0, 48040.0, 48748.75625621849, 49676.50323496719, 42829.78585107055, 51972.220416693024, 52595.73702131465, 65797.0, 69292.65353673199, 14568.198749068903, 14739.484118056245, 14763.92618036903, 15112.01771884824, 15119.470818521298, 16426.507204783877, 16592.83407165913, 16612.00327980325, 16671.166441677626, 16859.325626975275, 14701.0, 15209.847901520843, 15412.691746165237, 15459.536213913601, 15500.767488487456, 15172.0, 15181.392744290546, 15347.155906425254, 15484.845151548341, 15520.456784012957, 14789.334479948213, 15082.924585377003, 15103.018303810217, 15205.923970380078, 15277.236138192824, 9350.345475816628, 10044.246681498771, 10090.646827361006, 10602.575896964036, 11932.0, 10070.647527336509, 12185.634430966731, 12611.810822837848, 12818.974482041227, 13002.479900803863], "value": 8543.383900848426, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1558.5000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 118.0, 115.0, 116.0, 115.0, 114.0, 115.0, 141.0, 172.0, 153.0], "value": 126.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 111.0, 118.0, 140.0, 150.0, 149.0, 103.0, 136.0, 167.0, 148.0], "value": 136.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 214.0, 258.0, 236.0, 224.0, 203.0, 193.0, 197.0, 195.0, 197.0], "value": 211.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 56.0, 66.0, 115.0, 76.0, 58.0, 62.0, 81.0, 88.0, 50.0], "value": 72.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 70.0, 96.0, 104.0, 72.0, 69.0, 86.0, 68.0, 69.0, 90.0], "value": 80.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 92.0, 92.0, 93.0, 91.0, 97.0, 106.0, 93.0, 91.0], "value": 94.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 99.0, 99.0, 98.0, 103.0, 129.0, 100.0, 99.0, 101.0, 98.0], "value": 102.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 90.0, 94.0, 94.0, 91.0, 92.0, 92.0, 92.0, 90.0], "value": 91.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 52.0, 51.0, 52.0, 52.0, 53.0, 52.0, 53.0, 53.0], "value": 52.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 64.0, 59.0, 61.0, 65.0, 62.0, 59.0, 58.0, 58.0, 58.0], "value": 60.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 89.0, 89.0, 84.0, 88.0, 91.0, 97.0, 130.0, 122.0], "value": 96.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 136.0, 132.0, 142.0, 147.0, 130.0, 139.0, 153.0, 136.0, 139.0], "value": 139.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 183.0, 242.0, 236.0, 222.0, 230.0, 213.0, 178.0, 232.0, 235.0], "value": 215.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 72.0, 97.0, 92.0, 92.0, 94.0, 90.0, 65.0, 66.0, 65.0], "value": 79.9, "unit": "score"}]}]} 13:10:39 INFO - Return code: 0 13:10:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:10:39 INFO - # TBPL SUCCESS # 13:10:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:10:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:10:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:10:39 INFO - 2017-06-04 13:10:39.660 screenresolution[1968:12873] starting screenresolution argv=screenresolution get 13:10:39 INFO - 2017-06-04 13:10:39.662 screenresolution[1968:12873] Display 0: 1600x1200x32@60 13:10:39 INFO - 2017-06-04 13:10:39.668 screenresolution[1969:12876] starting screenresolution argv=screenresolution list 13:10:39 INFO - Available Modes on Display 0 13:10:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:10:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:10:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:10:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:10:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:10:39 INFO - Intel Iris: 13:10:39 INFO - Chipset Model: Intel Iris 13:10:39 INFO - Type: GPU 13:10:39 INFO - Bus: Built-In 13:10:39 INFO - VRAM (Dynamic, Max): 1536 MB 13:10:39 INFO - Vendor: Intel (0x8086) 13:10:39 INFO - Device ID: 0x0a2e 13:10:39 INFO - Revision ID: 0x0009 13:10:39 INFO - Displays: 13:10:39 INFO - Display: 13:10:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:10:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:10:39 INFO - Display Serial Number: 1600x1200 60 13:10:39 INFO - Main Display: Yes 13:10:39 INFO - Mirror: Off 13:10:39 INFO - Online: Yes 13:10:39 INFO - Rotation: Supported 13:10:39 INFO - Return code: 0 13:10:39 INFO - Running post-action listener: _resource_record_post_action 13:10:39 INFO - [mozharness: 2017-06-04 20:10:39.905401Z] Finished run-tests step (success) 13:10:39 INFO - Running post-run listener: _resource_record_post_run 13:10:39 INFO - Total resource usage - Wall time: 1257s; CPU: 53.0%; Read bytes: 40068096; Write bytes: 579405824; Read time: 328; Write time: 1816 13:10:39 INFO - TinderboxPrint: CPU usage
53.2% 13:10:39 INFO - TinderboxPrint: I/O read bytes / time
40,068,096 / 328 13:10:39 INFO - TinderboxPrint: I/O write bytes / time
579,405,824 / 1,816 13:10:39 INFO - TinderboxPrint: CPU idle
2,355.5 (46.8%) 13:10:39 INFO - TinderboxPrint: CPU system
1,018.1 (20.2%) 13:10:39 INFO - TinderboxPrint: CPU user
1,655.9 (32.9%) 13:10:39 INFO - TinderboxPrint: Swap in / out
282,742,784 / 0 13:10:39 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 243677696; Write bytes: 240510976; Read time: 18198; Write time: 534 13:10:39 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 13:10:39 INFO - run-tests - Wall time: 1236s; CPU: 53.0%; Read bytes: 37905408; Write bytes: 326467584; Read time: 307; Write time: 1246 13:10:40 INFO - Running post-run listener: _upload_blobber_files 13:10:40 INFO - Blob upload gear active. 13:10:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:10:40 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 13:10:40 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'] 13:10:40 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 13:10:40 INFO - (blobuploader) - INFO - Open directory for files ... 13:10:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 13:10:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:10:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:10:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 13:10:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:10:41 INFO - (blobuploader) - INFO - Done attempting. 13:10:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 13:10:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:10:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:10:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 13:10:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:10:42 INFO - (blobuploader) - INFO - Done attempting. 13:10:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 13:10:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:10:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:10:43 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 13:10:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:10:43 INFO - (blobuploader) - INFO - Done attempting. 13:10:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:10:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:10:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:10:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:10:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:10:45 INFO - (blobuploader) - INFO - Done attempting. 13:10:45 INFO - (blobuploader) - INFO - Iteration through files over. 13:10:45 INFO - Return code: 0 13:10:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:10:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:10:45 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a8ba789766546865f5e521f7835dd2054696dcf192416decff658270071dcfd34f64dc4b495b8bc401a91203af8b97ac2980a8af0c6e9adf63d13c32e013d6ca", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/65f1f7b5d5f6c9aa7c6efda1a4359838842c096cbd18768c4d4dfd0cbbb3bc36fc36f7db49088089af4c044cbff8d82320efc0c7d4bce3eea641d666ee5cbc49", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e90990f7141cd312ecb1ed072d5e12bf7c974b4d52ec0528d2f500ccff5946e2e3aaf6f8e83d73b1b2835ca745f4e5982e243c15148650732170b66069012e4a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5cc154aee7d292d604f31641a9117ba31449e4e7ffd5b606e9f8a322857a435c949fc51372deb6a742a716d750cab7f26e0a8ded8eb626e5c98eb4142d37da5c"} 13:10:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:10:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:10:45 INFO - Contents: 13:10:45 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a8ba789766546865f5e521f7835dd2054696dcf192416decff658270071dcfd34f64dc4b495b8bc401a91203af8b97ac2980a8af0c6e9adf63d13c32e013d6ca", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/65f1f7b5d5f6c9aa7c6efda1a4359838842c096cbd18768c4d4dfd0cbbb3bc36fc36f7db49088089af4c044cbff8d82320efc0c7d4bce3eea641d666ee5cbc49", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e90990f7141cd312ecb1ed072d5e12bf7c974b4d52ec0528d2f500ccff5946e2e3aaf6f8e83d73b1b2835ca745f4e5982e243c15148650732170b66069012e4a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5cc154aee7d292d604f31641a9117ba31449e4e7ffd5b606e9f8a322857a435c949fc51372deb6a742a716d750cab7f26e0a8ded8eb626e5c98eb4142d37da5c"} 13:10:45 INFO - Running post-run listener: copy_logs_to_upload_dir 13:10:45 INFO - Copying logs to upload dir... 13:10:45 INFO - mkdir: /builds/slave/test/build/upload/logs 13:10:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1338.855415 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2017-06-04 13:10:45.913363) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-04 13:10:45.916711) ========= 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.It0ZpoPIES/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.ADWUNn96wd/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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/a8ba789766546865f5e521f7835dd2054696dcf192416decff658270071dcfd34f64dc4b495b8bc401a91203af8b97ac2980a8af0c6e9adf63d13c32e013d6ca", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/65f1f7b5d5f6c9aa7c6efda1a4359838842c096cbd18768c4d4dfd0cbbb3bc36fc36f7db49088089af4c044cbff8d82320efc0c7d4bce3eea641d666ee5cbc49", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e90990f7141cd312ecb1ed072d5e12bf7c974b4d52ec0528d2f500ccff5946e2e3aaf6f8e83d73b1b2835ca745f4e5982e243c15148650732170b66069012e4a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5cc154aee7d292d604f31641a9117ba31449e4e7ffd5b606e9f8a322857a435c949fc51372deb6a742a716d750cab7f26e0a8ded8eb626e5c98eb4142d37da5c"} build_url:https://queue.taskcluster.net/v1/task/OCdICIMyRsC2TCJejClHTQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011283 build_url: 'https://queue.taskcluster.net/v1/task/OCdICIMyRsC2TCJejClHTQ/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/a8ba789766546865f5e521f7835dd2054696dcf192416decff658270071dcfd34f64dc4b495b8bc401a91203af8b97ac2980a8af0c6e9adf63d13c32e013d6ca", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/65f1f7b5d5f6c9aa7c6efda1a4359838842c096cbd18768c4d4dfd0cbbb3bc36fc36f7db49088089af4c044cbff8d82320efc0c7d4bce3eea641d666ee5cbc49", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e90990f7141cd312ecb1ed072d5e12bf7c974b4d52ec0528d2f500ccff5946e2e3aaf6f8e83d73b1b2835ca745f4e5982e243c15148650732170b66069012e4a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5cc154aee7d292d604f31641a9117ba31449e4e7ffd5b606e9f8a322857a435c949fc51372deb6a742a716d750cab7f26e0a8ded8eb626e5c98eb4142d37da5c"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-04 13:10:45.955730) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-04 13:10:45.956091) ========= 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.It0ZpoPIES/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.ADWUNn96wd/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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.005192 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-04 13:10:46.006222) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-04 13:10:46.006723) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-04 13:10:46.007245) ========= ========= Total master_lag: 0.24 =========