builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0027 starttime: 1496745402.79 results: success (0) revision: d3b8e8571020ffad87bb140b190799fc6fae06d5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-06 03:36:42.787477) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-06 03:36:42.787879) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-06 03:36:42.788153) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-06 03:36:42.814039) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 03:36:42.814454) ========= 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.X85nbCil83/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.MzG6ZkgXkS/Listeners TMPDIR=/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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-06 03:36:42-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.09M=0.001s 2017-06-06 03:36:43 (8.09 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.214650 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 03:36:43.052011) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 03:36:43.052363) ========= 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.X85nbCil83/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.MzG6ZkgXkS/Listeners TMPDIR=/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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.029109 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 03:36:43.101779) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 03:36:43.102117) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d3b8e8571020ffad87bb140b190799fc6fae06d5 --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 d3b8e8571020ffad87bb140b190799fc6fae06d5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.X85nbCil83/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.MzG6ZkgXkS/Listeners TMPDIR=/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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-06 03:36:43,200 truncating revision to first 12 chars 2017-06-06 03:36:43,200 Setting DEBUG logging. 2017-06-06 03:36:43,200 attempt 1/10 2017-06-06 03:36:43,200 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d3b8e8571020?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-06 03:36:43,844 unpacking tar archive at: mozilla-central-d3b8e8571020/testing/mozharness/ program finished with exit code 0 elapsedTime=0.926610 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 03:36:44.050875) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-06 03:36:44.051158) ========= script_repo_revision: d3b8e8571020ffad87bb140b190799fc6fae06d5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-06 03:36:44.051576) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-06 03:36:44.051894) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-06 03:36:44.072604) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-06-06 03:36:44.072927) ========= /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.X85nbCil83/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.MzG6ZkgXkS/Listeners TMPDIR=/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 03:36:44 INFO - MultiFileLogger online at 20170606 03:36:44 in /builds/slave/test 03:36:44 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 03:36:44 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:36:44 INFO - {'append_to_log': False, 03:36:44 INFO - 'base_work_dir': '/builds/slave/test', 03:36:44 INFO - 'blob_upload_branch': 'Firefox', 03:36:44 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:36:44 INFO - 'branch': 'Firefox', 03:36:44 INFO - 'buildbot_json_path': 'buildprops.json', 03:36:44 INFO - 'config_files': ('talos/mac_config.py',), 03:36:44 INFO - 'default_actions': ('clobber', 03:36:44 INFO - 'read-buildbot-config', 03:36:44 INFO - 'download-and-extract', 03:36:44 INFO - 'populate-webroot', 03:36:44 INFO - 'create-virtualenv', 03:36:44 INFO - 'install', 03:36:44 INFO - 'setup-mitmproxy', 03:36:44 INFO - 'run-tests'), 03:36:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:36:44 INFO - 'download_minidump_stackwalk': True, 03:36:44 INFO - 'download_symbols': 'ondemand', 03:36:44 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:36:44 INFO - 'tooltool.py': '/tools/tooltool.py', 03:36:44 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:36:44 INFO - '/tools/misc-python/virtualenv.py')}, 03:36:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:36:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:36:44 INFO - 'gecko_profile': False, 03:36:44 INFO - 'gecko_profile_interval': 0, 03:36:44 INFO - 'installer_path': 'installer.exe', 03:36:44 INFO - 'log_level': 'info', 03:36:44 INFO - 'log_name': 'talos', 03:36:44 INFO - 'log_to_console': True, 03:36:44 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:36:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:36:44 INFO - 'opt_config_files': (), 03:36:44 INFO - 'pip_index': False, 03:36:44 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:36:44 INFO - 'cmd': ('bash', 03:36:44 INFO - '-c', 03:36:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:36:44 INFO - 'enabled': True, 03:36:44 INFO - 'halt_on_failure': False, 03:36:44 INFO - 'name': 'check_screen_resolution'},), 03:36:44 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:36:44 INFO - 'cmd': ('bash', 03:36:44 INFO - '-c', 03:36:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:36:44 INFO - 'enabled': True, 03:36:44 INFO - 'halt_on_failure': False, 03:36:44 INFO - 'name': 'check_screen_resolution'},), 03:36:44 INFO - 'run_cmd_checks_enabled': True, 03:36:44 INFO - 'suite': 'dromaeojs-e10s', 03:36:44 INFO - 'system_bits': '32', 03:36:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:36:44 INFO - 'title': 't-yosemite-r7-0027', 03:36:44 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:36:44 INFO - 'use_talos_json': True, 03:36:44 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:36:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:36:44 INFO - 'work_dir': 'build'} 03:36:44 INFO - [mozharness: 2017-06-06 10:36:44.287724Z] Running clobber step. 03:36:44 INFO - Running pre-action listener: _resource_record_pre_action 03:36:44 INFO - Running main action method: clobber 03:36:44 INFO - rmtree: /builds/slave/test/build 03:36:44 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:36:46 INFO - Running post-action listener: _resource_record_post_action 03:36:46 INFO - [mozharness: 2017-06-06 10:36:46.772243Z] Finished clobber step (success) 03:36:46 INFO - [mozharness: 2017-06-06 10:36:46.772374Z] Running read-buildbot-config step. 03:36:46 INFO - Running pre-action listener: _resource_record_pre_action 03:36:46 INFO - Running main action method: read_buildbot_config 03:36:46 INFO - Using buildbot properties: 03:36:46 INFO - { 03:36:46 INFO - "project": "", 03:36:46 INFO - "product": "firefox", 03:36:46 INFO - "who": "archaeopteryx@coole-files.de", 03:36:46 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 03:36:46 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 03:36:46 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 03:36:46 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 03:36:46 INFO - "stage_platform": "macosx64", 03:36:46 INFO - "basedir": "/builds/slave/test", 03:36:46 INFO - "buildnumber": 18, 03:36:46 INFO - "platform": "yosemite_r7", 03:36:46 INFO - "parent_task_id": "FoFtF0FURE6OXzrhzdmtUQ", 03:36:46 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 03:36:46 INFO - "slavebuilddir": "test", 03:36:46 INFO - "taskId": "WeDmcF8yRzi80ksw7wBt2w", 03:36:46 INFO - "branch": "mozilla-central", 03:36:46 INFO - "script_repo_revision": "production", 03:36:46 INFO - "revision": "d3b8e8571020ffad87bb140b190799fc6fae06d5", 03:36:46 INFO - "slavename": "t-yosemite-r7-0027", 03:36:46 INFO - "repo_path": "mozilla-central" 03:36:46 INFO - } 03:36:46 INFO - Finding installer, test and symbols from parent task. 03:36:46 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WeDmcF8yRzi80ksw7wBt2w'}, attempt #1 03:36:47 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WeDmcF8yRzi80ksw7wBt2w'}, attempt #1 03:36:48 INFO - Task dependencies: FFdJ_SerRGm_0p8b6wGXHA FoFtF0FURE6OXzrhzdmtUQ 03:36:48 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FoFtF0FURE6OXzrhzdmtUQ'}, attempt #1 03:36:48 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/FoFtF0FURE6OXzrhzdmtUQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:36:48 INFO - Set test_url: https://queue.taskcluster.net/v1/task/FoFtF0FURE6OXzrhzdmtUQ/artifacts/public/build/test_packages.json 03:36:48 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/FoFtF0FURE6OXzrhzdmtUQ/artifacts/public/build/target.crashreporter-symbols.zip 03:36:48 INFO - Running post-action listener: _resource_record_post_action 03:36:48 INFO - [mozharness: 2017-06-06 10:36:48.622986Z] Finished read-buildbot-config step (success) 03:36:48 INFO - [mozharness: 2017-06-06 10:36:48.623128Z] Running download-and-extract step. 03:36:48 INFO - Running pre-action listener: _resource_record_pre_action 03:36:48 INFO - Running main action method: download_and_extract 03:36:48 INFO - mkdir: /builds/slave/test/build/tests 03:36:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:36:48 INFO - trying https://queue.taskcluster.net/v1/task/FoFtF0FURE6OXzrhzdmtUQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 03:36:48 INFO - Downloading https://queue.taskcluster.net/v1/task/FoFtF0FURE6OXzrhzdmtUQ/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 03:36:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FoFtF0FURE6OXzrhzdmtUQ/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 03:36:49 INFO - Downloaded 1328 bytes. 03:36:49 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 03:36:49 INFO - Using the following test package requirements: 03:36:49 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:36:49 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 03:36:49 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 03:36:49 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:36:49 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 03:36:49 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:36:49 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 03:36:49 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:36:49 INFO - u'jsshell-mac.zip'], 03:36:49 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:36:49 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 03:36:49 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 03:36:49 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:36:49 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 03:36:49 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:36:49 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 03:36:49 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:36:49 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.tar.gz'], 03:36:49 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:36:49 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 03:36:49 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:36:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FoFtF0FURE6OXzrhzdmtUQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 03:36:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FoFtF0FURE6OXzrhzdmtUQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:36:49 INFO - Fetch https://queue.taskcluster.net/v1/task/FoFtF0FURE6OXzrhzdmtUQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 03:36:51 INFO - Content-Length response header: 57631684 03:36:51 INFO - Bytes received: 57631684 03:37:09 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 03:37:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FoFtF0FURE6OXzrhzdmtUQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 03:37:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FoFtF0FURE6OXzrhzdmtUQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:37:09 INFO - Fetch https://queue.taskcluster.net/v1/task/FoFtF0FURE6OXzrhzdmtUQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 03:37:11 INFO - Content-Length response header: 57631684 03:37:11 INFO - Bytes received: 57631684 03:37:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FoFtF0FURE6OXzrhzdmtUQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 03:37:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FoFtF0FURE6OXzrhzdmtUQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 03:37:28 INFO - Fetch https://queue.taskcluster.net/v1/task/FoFtF0FURE6OXzrhzdmtUQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 03:37:29 INFO - Content-Length response header: 14447425 03:37:29 INFO - Bytes received: 14447425 03:37:29 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')]} 03:37:29 INFO - trying https://queue.taskcluster.net/v1/task/FoFtF0FURE6OXzrhzdmtUQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:37:29 INFO - Downloading https://queue.taskcluster.net/v1/task/FoFtF0FURE6OXzrhzdmtUQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 03:37:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FoFtF0FURE6OXzrhzdmtUQ/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 03:37:31 INFO - Downloaded 67988664 bytes. 03:37:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FoFtF0FURE6OXzrhzdmtUQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:37:31 INFO - mkdir: /builds/slave/test/properties 03:37:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:37:31 INFO - Writing to file /builds/slave/test/properties/build_url 03:37:31 INFO - Contents: 03:37:31 INFO - build_url:https://queue.taskcluster.net/v1/task/FoFtF0FURE6OXzrhzdmtUQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:37:31 INFO - Running post-action listener: _resource_record_post_action 03:37:31 INFO - Running post-action listener: set_extra_try_arguments 03:37:31 INFO - [mozharness: 2017-06-06 10:37:31.716361Z] Finished download-and-extract step (success) 03:37:31 INFO - [mozharness: 2017-06-06 10:37:31.716500Z] Running populate-webroot step. 03:37:31 INFO - Running pre-action listener: _resource_record_pre_action 03:37:31 INFO - Running main action method: populate_webroot 03:37:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:37:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:37:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:37:31 INFO - '--tppagecycles', 03:37:31 INFO - '7'], 03:37:31 INFO - 'tests': ['tsvgm']}}, 03:37:31 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:37:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:37:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:37:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:37:31 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 03:37:31 INFO - 'talos_options': ['--disable-e10s'], 03:37:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:37:31 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:37:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:37:31 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 03:37:31 INFO - 'talos_options': ['--disable-e10s'], 03:37:31 INFO - 'tests': ['damp', 'tps']}, 03:37:31 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:37:31 INFO - 'tests': ['damp', 'tps']}, 03:37:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:37:31 INFO - 'tests': ['dromaeo_dom']}, 03:37:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:37:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:37:31 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 03:37:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:37:31 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:37:31 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:37:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:37:31 INFO - 'tests': ['a11yr', 03:37:31 INFO - 'ts_paint', 03:37:31 INFO - 'tpaint', 03:37:31 INFO - 'sessionrestore', 03:37:31 INFO - 'sessionrestore_no_auto_restore', 03:37:31 INFO - 'tabpaint']}, 03:37:31 INFO - 'other-e10s': {'tests': ['a11yr', 03:37:31 INFO - 'ts_paint', 03:37:31 INFO - 'tpaint', 03:37:31 INFO - 'sessionrestore', 03:37:31 INFO - 'sessionrestore_no_auto_restore', 03:37:31 INFO - 'tabpaint']}, 03:37:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:37:31 INFO - 'ts_paint', 03:37:31 INFO - 'tpaint', 03:37:31 INFO - 'sessionrestore', 03:37:31 INFO - 'sessionrestore_no_auto_restore', 03:37:31 INFO - 'tabpaint']}, 03:37:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:37:31 INFO - 'ts_paint', 03:37:31 INFO - 'tpaint', 03:37:31 INFO - 'sessionrestore', 03:37:31 INFO - 'sessionrestore_no_auto_restore', 03:37:31 INFO - 'tabpaint']}, 03:37:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:37:31 INFO - 'tests': ['a11yr', 03:37:31 INFO - 'ts_paint', 03:37:31 INFO - 'tpaint', 03:37:31 INFO - 'sessionrestore', 03:37:31 INFO - 'sessionrestore_no_auto_restore', 03:37:31 INFO - 'tabpaint']}, 03:37:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:37:31 INFO - 'tests': ['a11yr', 03:37:31 INFO - 'ts_paint', 03:37:31 INFO - 'tpaint', 03:37:31 INFO - 'sessionrestore', 03:37:31 INFO - 'sessionrestore_no_auto_restore', 03:37:31 INFO - 'tabpaint']}, 03:37:31 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 03:37:31 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 03:37:31 INFO - 'bloom_basic_ref']}, 03:37:31 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 03:37:31 INFO - 'talos_options': ['--mitmproxy', 03:37:31 INFO - 'mitmproxy-recording-1.mp', 03:37:31 INFO - '--firstNonBlankPaint'], 03:37:31 INFO - 'tests': ['Quantum_1']}, 03:37:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:37:31 INFO - 'tests': ['tsvgx', 03:37:31 INFO - 'tsvgr_opacity', 03:37:31 INFO - 'tart', 03:37:31 INFO - 'tscrollx', 03:37:31 INFO - 'cart', 03:37:31 INFO - 'tsvg_static']}, 03:37:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:37:31 INFO - 'tsvgr_opacity', 03:37:31 INFO - 'tart', 03:37:31 INFO - 'tscrollx', 03:37:31 INFO - 'cart', 03:37:31 INFO - 'tsvg_static']}, 03:37:31 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 03:37:31 INFO - 'talos_options': ['--disable-e10s'], 03:37:31 INFO - 'tests': ['tp5o']}, 03:37:31 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:37:31 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 03:37:31 INFO - 'talos_options': ['--disable-e10s', 03:37:31 INFO - '--xperf_path', 03:37:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:37:31 INFO - 'tests': ['tp5n']}, 03:37:31 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:37:31 INFO - 'talos_options': ['--xperf_path', 03:37:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:37:31 INFO - 'tests': ['tp5n']}}, 03:37:31 INFO - 'talos.zip': {'path': '', 03:37:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:37:31 INFO - Running post-action listener: _resource_record_post_action 03:37:31 INFO - [mozharness: 2017-06-06 10:37:31.738694Z] Finished populate-webroot step (success) 03:37:31 INFO - [mozharness: 2017-06-06 10:37:31.738845Z] Running create-virtualenv step. 03:37:31 INFO - Running pre-action listener: _resource_record_pre_action 03:37:31 INFO - Running main action method: create_virtualenv 03:37:31 INFO - Creating virtualenv /builds/slave/test/build/venv 03:37:31 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 03:37:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:37:31 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 03:37:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:37:31 INFO - Using real prefix '/tools/python27' 03:37:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:37:32 INFO - Installing distribute.............................................................................................................................................................................................done. 03:37:35 INFO - Installing pip.................done. 03:37:35 INFO - Return code: 0 03:37:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:37:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:37:35 INFO - Reading from file tmpfile_stdout 03:37:35 INFO - Output received: 03:37:35 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) 03:37:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:37: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')]} 03:37:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:37: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')]} 03:37:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:37:35 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 0x10916cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109170768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97595c03c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090f5c60>, '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 0x108e8acd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108eb3be8>, '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.X85nbCil83/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.MzG6ZkgXkS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 03:37:35 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 03:37:35 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 03:37:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X85nbCil83/Render', 03:37:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:37:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:37:35 INFO - 'HOME': '/Users/cltbld', 03:37:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:37:35 INFO - 'LOGNAME': 'cltbld', 03:37:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:35 INFO - 'MOZ_NO_REMOTE': '1', 03:37:35 INFO - 'NO_EM_RESTART': '1', 03:37:35 INFO - 'PAGER': '/bin/cat', 03:37:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:37:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:35 INFO - 'PWD': '/builds/slave/test', 03:37:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:37:35 INFO - 'SHELL': '/bin/bash', 03:37:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MzG6ZkgXkS/Listeners', 03:37:35 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 03:37:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:37:35 INFO - 'USER': 'cltbld', 03:37:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:37:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:37:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:35 INFO - 'XPC_FLAGS': '0x0', 03:37:35 INFO - 'XPC_SERVICE_NAME': '0', 03:37:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:37:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:37:36 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 03:37:36 INFO - Cleaning up... 03:37:36 INFO - Return code: 0 03:37:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:37:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:37:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:37:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:37:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:37:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10916cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109170768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97595c03c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090f5c60>, '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 0x108e8acd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108eb3be8>, '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.X85nbCil83/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.MzG6ZkgXkS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 03:37:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:37:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:37:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X85nbCil83/Render', 03:37:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:37:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:37:36 INFO - 'HOME': '/Users/cltbld', 03:37:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:37:36 INFO - 'LOGNAME': 'cltbld', 03:37:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:36 INFO - 'MOZ_NO_REMOTE': '1', 03:37:36 INFO - 'NO_EM_RESTART': '1', 03:37:36 INFO - 'PAGER': '/bin/cat', 03:37:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:37:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:36 INFO - 'PWD': '/builds/slave/test', 03:37:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:37:36 INFO - 'SHELL': '/bin/bash', 03:37:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MzG6ZkgXkS/Listeners', 03:37:36 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 03:37:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:37:36 INFO - 'USER': 'cltbld', 03:37:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:37:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:37:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:36 INFO - 'XPC_FLAGS': '0x0', 03:37:36 INFO - 'XPC_SERVICE_NAME': '0', 03:37:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:37:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:37:36 INFO - Downloading/unpacking psutil>=3.1.1 03:37:36 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 03:37:36 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 03:37:36 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 03:37:36 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 03:37:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:37:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:37:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:37:38 INFO - Installing collected packages: psutil 03:37:38 INFO - Running setup.py install for psutil 03:37:38 INFO - building 'psutil._psutil_osx' extension 03:37:38 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 03:37:39 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 03:37:39 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 03:37:39 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 03:37:39 INFO - building 'psutil._psutil_posix' extension 03:37:39 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 03:37:39 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:37:39 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:37:39 INFO - ^ 03:37:39 INFO - 1 warning generated. 03:37:39 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 03:37:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:37:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:37:39 INFO - Successfully installed psutil 03:37:39 INFO - Cleaning up... 03:37:40 INFO - Return code: 0 03:37:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:37:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:37:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:37:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:37:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:37:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10916cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109170768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97595c03c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090f5c60>, '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 0x108e8acd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108eb3be8>, '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.X85nbCil83/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.MzG6ZkgXkS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 03:37:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:37:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:37:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X85nbCil83/Render', 03:37:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:37:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:37:40 INFO - 'HOME': '/Users/cltbld', 03:37:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:37:40 INFO - 'LOGNAME': 'cltbld', 03:37:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:40 INFO - 'MOZ_NO_REMOTE': '1', 03:37:40 INFO - 'NO_EM_RESTART': '1', 03:37:40 INFO - 'PAGER': '/bin/cat', 03:37:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:37:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:40 INFO - 'PWD': '/builds/slave/test', 03:37:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:37:40 INFO - 'SHELL': '/bin/bash', 03:37:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MzG6ZkgXkS/Listeners', 03:37:40 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 03:37:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:37:40 INFO - 'USER': 'cltbld', 03:37:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:37:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:37:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:40 INFO - 'XPC_FLAGS': '0x0', 03:37:40 INFO - 'XPC_SERVICE_NAME': '0', 03:37:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:37:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:37:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:37:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:37:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:37:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:37:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:37:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:37:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:37:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:37:41 INFO - Installing collected packages: mozsystemmonitor 03:37:41 INFO - Running setup.py install for mozsystemmonitor 03:37:41 INFO - Successfully installed mozsystemmonitor 03:37:41 INFO - Cleaning up... 03:37:41 INFO - Return code: 0 03:37:41 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:37: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')]} 03:37:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:37: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')]} 03:37:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:37: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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10916cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109170768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97595c03c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090f5c60>, '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 0x108e8acd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108eb3be8>, '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.X85nbCil83/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.MzG6ZkgXkS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 03:37: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==2.5.1'] in /builds/slave/test/build 03:37: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==2.5.1 03:37:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X85nbCil83/Render', 03:37:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:37:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:37:41 INFO - 'HOME': '/Users/cltbld', 03:37:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:37:41 INFO - 'LOGNAME': 'cltbld', 03:37:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:41 INFO - 'MOZ_NO_REMOTE': '1', 03:37:41 INFO - 'NO_EM_RESTART': '1', 03:37:41 INFO - 'PAGER': '/bin/cat', 03:37:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:37:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:41 INFO - 'PWD': '/builds/slave/test', 03:37:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:37:41 INFO - 'SHELL': '/bin/bash', 03:37:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MzG6ZkgXkS/Listeners', 03:37:41 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 03:37:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:37:41 INFO - 'USER': 'cltbld', 03:37:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:37:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:37:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:41 INFO - 'XPC_FLAGS': '0x0', 03:37:41 INFO - 'XPC_SERVICE_NAME': '0', 03:37:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:37:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:37:42 INFO - Downloading/unpacking jsonschema==2.5.1 03:37:42 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 03:37:42 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 03:37:42 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 03:37:42 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 03:37:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:37:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:37:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:37:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:37:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:37:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:37:43 INFO - Downloading functools32-3.2.3-2.tar.gz 03:37:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:37:44 INFO - warning: no files found matching '*.txt' 03:37:44 INFO - no previously-included directories found matching 'build' 03:37:44 INFO - no previously-included directories found matching 'dist' 03:37:44 INFO - no previously-included directories found matching '.git*' 03:37:44 INFO - Installing collected packages: jsonschema, functools32 03:37:44 INFO - Running setup.py install for functools32 03:37:44 INFO - warning: no files found matching '*.txt' 03:37:44 INFO - no previously-included directories found matching 'build' 03:37:44 INFO - no previously-included directories found matching 'dist' 03:37:44 INFO - no previously-included directories found matching '.git*' 03:37:44 INFO - Successfully installed jsonschema functools32 03:37:44 INFO - Cleaning up... 03:37:44 INFO - Return code: 0 03:37:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:37:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:37:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:37:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:37:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:37:44 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 0x10916cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109170768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97595c03c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090f5c60>, '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 0x108e8acd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108eb3be8>, '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.X85nbCil83/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.MzG6ZkgXkS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 03:37:44 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 03:37:44 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 03:37:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X85nbCil83/Render', 03:37:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:37:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:37:44 INFO - 'HOME': '/Users/cltbld', 03:37:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:37:44 INFO - 'LOGNAME': 'cltbld', 03:37:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:44 INFO - 'MOZ_NO_REMOTE': '1', 03:37:44 INFO - 'NO_EM_RESTART': '1', 03:37:44 INFO - 'PAGER': '/bin/cat', 03:37:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:37:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:44 INFO - 'PWD': '/builds/slave/test', 03:37:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:37:44 INFO - 'SHELL': '/bin/bash', 03:37:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MzG6ZkgXkS/Listeners', 03:37:44 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 03:37:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:37:44 INFO - 'USER': 'cltbld', 03:37:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:37:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:37:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:44 INFO - 'XPC_FLAGS': '0x0', 03:37:44 INFO - 'XPC_SERVICE_NAME': '0', 03:37:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:37:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:37:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:37:44 INFO - Cleaning up... 03:37:44 INFO - Return code: 0 03:37:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:37:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:37:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:37:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:37:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:37:44 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 0x10916cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109170768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97595c03c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090f5c60>, '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 0x108e8acd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108eb3be8>, '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.X85nbCil83/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.MzG6ZkgXkS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 03:37:44 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 03:37:44 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 03:37:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X85nbCil83/Render', 03:37:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:37:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:37:44 INFO - 'HOME': '/Users/cltbld', 03:37:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:37:44 INFO - 'LOGNAME': 'cltbld', 03:37:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:44 INFO - 'MOZ_NO_REMOTE': '1', 03:37:44 INFO - 'NO_EM_RESTART': '1', 03:37:44 INFO - 'PAGER': '/bin/cat', 03:37:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:37:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:44 INFO - 'PWD': '/builds/slave/test', 03:37:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:37:44 INFO - 'SHELL': '/bin/bash', 03:37:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MzG6ZkgXkS/Listeners', 03:37:44 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 03:37:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:37:44 INFO - 'USER': 'cltbld', 03:37:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:37:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:37:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:44 INFO - 'XPC_FLAGS': '0x0', 03:37:44 INFO - 'XPC_SERVICE_NAME': '0', 03:37:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:37:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:37:44 INFO - Downloading/unpacking blobuploader==1.2.4 03:37:44 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 03:37:44 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 03:37:44 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 03:37:44 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 03:37:46 INFO - Downloading blobuploader-1.2.4.tar.gz 03:37:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:37:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:37:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:37:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:37:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:37:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:37:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:37:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:37:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:37:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:37:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:37:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:37:46 INFO - Downloading docopt-0.6.1.tar.gz 03:37:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:37:47 INFO - Installing collected packages: blobuploader, requests, docopt 03:37:47 INFO - Running setup.py install for blobuploader 03:37:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:37:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:37:47 INFO - Running setup.py install for requests 03:37:47 INFO - Running setup.py install for docopt 03:37:47 INFO - Successfully installed blobuploader requests docopt 03:37:47 INFO - Cleaning up... 03:37:47 INFO - Return code: 0 03:37:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:37:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:37:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:37:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:37:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:37:47 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 0x10916cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109170768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97595c03c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090f5c60>, '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 0x108e8acd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108eb3be8>, '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.X85nbCil83/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.MzG6ZkgXkS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 03:37:47 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 03:37:47 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 03:37:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X85nbCil83/Render', 03:37:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:37:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:37:47 INFO - 'HOME': '/Users/cltbld', 03:37:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:37:47 INFO - 'LOGNAME': 'cltbld', 03:37:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:47 INFO - 'MOZ_NO_REMOTE': '1', 03:37:47 INFO - 'NO_EM_RESTART': '1', 03:37:47 INFO - 'PAGER': '/bin/cat', 03:37:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:37:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:47 INFO - 'PWD': '/builds/slave/test', 03:37:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:37:47 INFO - 'SHELL': '/bin/bash', 03:37:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MzG6ZkgXkS/Listeners', 03:37:47 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 03:37:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:37:47 INFO - 'USER': 'cltbld', 03:37:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:37:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:37:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:47 INFO - 'XPC_FLAGS': '0x0', 03:37:47 INFO - 'XPC_SERVICE_NAME': '0', 03:37:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:37:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:37:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:37:48 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-Ginoo0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:37:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:37:48 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-6U5N9w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:37:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:37:48 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-V7aRG6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:37:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:37:48 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-GdGK5P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:37:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:37:48 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-V39NSZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:37:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:37:48 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-nxG9xj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:37:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:37:48 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-aA6JAd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:37:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:37:48 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-d_QC2N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:37:49 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-V1gHOQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:37:49 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-fw9v1s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:37:49 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-mxVZLU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:37:49 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-vqUz35-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:37:49 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-yRATPd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:37:49 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-hZGA8c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:37:49 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-BluRM6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:37:49 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-JvnqMS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:37:49 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-x6c2Kq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:37:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:37:49 INFO - Running setup.py install for manifestparser 03:37:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:37:50 INFO - Running setup.py install for mozcrash 03:37:50 INFO - Running setup.py install for mozdebug 03:37:50 INFO - Running setup.py install for mozdevice 03:37:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:37:50 INFO - Running setup.py install for mozfile 03:37:50 INFO - Running setup.py install for mozhttpd 03:37:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:37:50 INFO - Running setup.py install for mozinfo 03:37:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:37:50 INFO - Running setup.py install for mozInstall 03:37:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:37:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:37:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:37:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:37:51 INFO - Running setup.py install for mozleak 03:37:51 INFO - Running setup.py install for mozlog 03:37:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:37:51 INFO - Running setup.py install for moznetwork 03:37:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:37:51 INFO - Running setup.py install for mozprocess 03:37:51 INFO - Running setup.py install for mozprofile 03:37:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:37:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:37:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:37:51 INFO - Running setup.py install for mozrunner 03:37:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:37:52 INFO - Running setup.py install for mozscreenshot 03:37:52 INFO - Running setup.py install for moztest 03:37:52 INFO - Running setup.py install for mozversion 03:37:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:37:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:37:52 INFO - Cleaning up... 03:37:52 INFO - Return code: 0 03:37:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:37:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:37:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:37:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:37:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:37:52 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 0x10916cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109170768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97595c03c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090f5c60>, '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 0x108e8acd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108eb3be8>, '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.X85nbCil83/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.MzG6ZkgXkS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 03:37:52 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 03:37:52 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 03:37:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X85nbCil83/Render', 03:37:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:37:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:37:52 INFO - 'HOME': '/Users/cltbld', 03:37:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:37:52 INFO - 'LOGNAME': 'cltbld', 03:37:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:52 INFO - 'MOZ_NO_REMOTE': '1', 03:37:52 INFO - 'NO_EM_RESTART': '1', 03:37:52 INFO - 'PAGER': '/bin/cat', 03:37:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:37:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:52 INFO - 'PWD': '/builds/slave/test', 03:37:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:37:52 INFO - 'SHELL': '/bin/bash', 03:37:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MzG6ZkgXkS/Listeners', 03:37:52 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 03:37:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:37:52 INFO - 'USER': 'cltbld', 03:37:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:37:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:37:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:52 INFO - 'XPC_FLAGS': '0x0', 03:37:52 INFO - 'XPC_SERVICE_NAME': '0', 03:37:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:37:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:37:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:37:52 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-gqmZuQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:37:52 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)) 03:37:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:37:52 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-l3Izqq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:37:53 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)) 03:37:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:37:53 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-XjSb_x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:37:53 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)) 03:37:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:37:53 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-scbkSf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:37:53 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)) 03:37:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:37:53 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-NdsePu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:37:53 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)) 03:37:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:37:53 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-3YrGNI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:37:53 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)) 03:37:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:37:53 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-_9NpZY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:37:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 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)) 03:37:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:37:53 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-5jmJRK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:37:53 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)) 03:37:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:37:53 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-O91gAc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:37:53 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)) 03:37:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:37:53 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-qUy0q7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:37:53 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)) 03:37:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:37:53 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-UzjSai-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:37:54 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)) 03:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:37:54 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-B8O3H5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:37:54 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)) 03:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:37:54 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-1e40qm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:37:54 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)) 03:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:37:54 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-geUC__-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:37:54 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)) 03:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:37:54 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-gzEmNM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:37:54 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)) 03:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:37:54 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-PtRej5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:37:54 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)) 03:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:37:54 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-5n3WTv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:37:54 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)) 03:37:54 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)) 03:37:54 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)) 03:37:54 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)) 03:37:54 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)) 03:37:54 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)) 03:37:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:37:54 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 03:37:54 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 03:37:54 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 03:37:54 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 03:37:56 INFO - Downloading blessings-1.6.tar.gz 03:37:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:37:56 INFO - Installing collected packages: blessings 03:37:56 INFO - Running setup.py install for blessings 03:37:56 INFO - Successfully installed blessings 03:37:56 INFO - Cleaning up... 03:37:56 INFO - Return code: 0 03:37:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:37:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:37:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:37:56 INFO - Reading from file tmpfile_stdout 03:37:56 INFO - Current package versions: 03:37:56 INFO - blessings == 1.6 03:37:56 INFO - blobuploader == 1.2.4 03:37:56 INFO - docopt == 0.6.1 03:37:56 INFO - functools32 == 3.2.3-2 03:37:56 INFO - jsonschema == 2.5.1 03:37:56 INFO - manifestparser == 1.1 03:37:56 INFO - mozInstall == 1.12 03:37:56 INFO - mozcrash == 1.0 03:37:56 INFO - mozdebug == 0.1 03:37:56 INFO - mozdevice == 0.51 03:37:56 INFO - mozfile == 1.2 03:37:56 INFO - mozhttpd == 0.7 03:37:56 INFO - mozinfo == 0.10 03:37:56 INFO - mozleak == 0.1 03:37:56 INFO - mozlog == 3.4 03:37:56 INFO - moznetwork == 0.27 03:37:56 INFO - mozprocess == 0.25 03:37:56 INFO - mozprofile == 0.28 03:37:56 INFO - mozrunner == 6.13 03:37:56 INFO - mozscreenshot == 0.1 03:37:56 INFO - mozsystemmonitor == 0.3 03:37:56 INFO - moztest == 0.8 03:37:56 INFO - mozversion == 1.4 03:37:56 INFO - psutil == 3.1.1 03:37:56 INFO - requests == 1.2.3 03:37:56 INFO - wsgiref == 0.1.2 03:37:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:37:56 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')]} 03:37:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:37:56 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')]} 03:37:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:37:56 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 0x10916cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109170768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97595c03c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090f5c60>, '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 0x108e8acd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108eb3be8>, '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.X85nbCil83/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.MzG6ZkgXkS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 03:37:56 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 03:37:56 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 03:37:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X85nbCil83/Render', 03:37:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:37:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:37:56 INFO - 'HOME': '/Users/cltbld', 03:37:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:37:56 INFO - 'LOGNAME': 'cltbld', 03:37:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:56 INFO - 'MOZ_NO_REMOTE': '1', 03:37:56 INFO - 'NO_EM_RESTART': '1', 03:37:56 INFO - 'PAGER': '/bin/cat', 03:37:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:37:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:56 INFO - 'PWD': '/builds/slave/test', 03:37:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:37:56 INFO - 'SHELL': '/bin/bash', 03:37:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MzG6ZkgXkS/Listeners', 03:37:56 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 03:37:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:37:56 INFO - 'USER': 'cltbld', 03:37:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:37:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:37:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:56 INFO - 'XPC_FLAGS': '0x0', 03:37:56 INFO - 'XPC_SERVICE_NAME': '0', 03:37:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:37:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:37:56 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)) 03:37:56 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)) 03:37:56 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)) 03:37:56 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)) 03:37:56 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)) 03:37:56 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)) 03:37:56 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)) 03:37:56 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)) 03:37:56 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)) 03:37:56 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:37:56 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 03:37:56 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 03:37:57 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 03:37:57 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 03:37:58 INFO - Downloading marionette_driver-1.4.0.tar.gz 03:37:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 03:37:58 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)) 03:37:58 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)) 03:37:58 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)) 03:37:58 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)) 03:37:58 INFO - Installing collected packages: marionette-driver 03:37:58 INFO - Running setup.py install for marionette-driver 03:37:58 INFO - Successfully installed marionette-driver 03:37:58 INFO - Cleaning up... 03:37:58 INFO - Return code: 0 03:37:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:37:58 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')]} 03:37:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:37:58 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')]} 03:37:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:37:58 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 0x10916cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109170768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97595c03c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090f5c60>, '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 0x108e8acd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108eb3be8>, '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.X85nbCil83/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.MzG6ZkgXkS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 03:37:58 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 03:37:58 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 03:37:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X85nbCil83/Render', 03:37:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:37:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:37:58 INFO - 'HOME': '/Users/cltbld', 03:37:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:37:58 INFO - 'LOGNAME': 'cltbld', 03:37:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:58 INFO - 'MOZ_NO_REMOTE': '1', 03:37:58 INFO - 'NO_EM_RESTART': '1', 03:37:58 INFO - 'PAGER': '/bin/cat', 03:37:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:37:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:58 INFO - 'PWD': '/builds/slave/test', 03:37:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:37:58 INFO - 'SHELL': '/bin/bash', 03:37:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MzG6ZkgXkS/Listeners', 03:37:58 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 03:37:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:37:58 INFO - 'USER': 'cltbld', 03:37:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:37:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:37:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:58 INFO - 'XPC_FLAGS': '0x0', 03:37:58 INFO - 'XPC_SERVICE_NAME': '0', 03:37:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:37:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:37:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:37:58 INFO - Cleaning up... 03:37:59 INFO - Return code: 0 03:37:59 INFO - Running post-action listener: _resource_record_post_action 03:37:59 INFO - Running post-action listener: _start_resource_monitoring 03:37:59 INFO - Starting resource monitoring. 03:37:59 INFO - [mozharness: 2017-06-06 10:37:59.029304Z] Finished create-virtualenv step (success) 03:37:59 INFO - [mozharness: 2017-06-06 10:37:59.030270Z] Running install step. 03:37:59 INFO - Running pre-action listener: _resource_record_pre_action 03:37:59 INFO - Running main action method: install 03:37:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:37:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:37:59 INFO - Reading from file tmpfile_stdout 03:37:59 INFO - Detecting whether we're running mozinstall >=1.0... 03:37:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:37:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:37:59 INFO - Reading from file tmpfile_stdout 03:37:59 INFO - Output received: 03:37:59 INFO - Usage: mozinstall [options] installer 03:37:59 INFO - Options: 03:37:59 INFO - -h, --help show this help message and exit 03:37:59 INFO - -d DEST, --destination=DEST 03:37:59 INFO - Directory to install application into. [default: 03:37:59 INFO - "/builds/slave/test"] 03:37:59 INFO - --app=APP Application being installed. [default: firefox] 03:37:59 INFO - mkdir: /builds/slave/test/build/application 03:37:59 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'] 03:37:59 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 03:38:20 INFO - Reading from file tmpfile_stdout 03:38:20 INFO - Output received: 03:38:20 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:38:20 INFO - Running post-action listener: _resource_record_post_action 03:38:20 INFO - [mozharness: 2017-06-06 10:38:20.894164Z] Finished install step (success) 03:38:20 INFO - [mozharness: 2017-06-06 10:38:20.894330Z] Running setup-mitmproxy step. 03:38:20 INFO - Running pre-action listener: _resource_record_pre_action 03:38:20 INFO - Running main action method: setup_mitmproxy 03:38:20 INFO - Skipping: mitmproxy is not required 03:38:20 INFO - Running post-action listener: _resource_record_post_action 03:38:20 INFO - [mozharness: 2017-06-06 10:38:20.894983Z] Finished setup-mitmproxy step (success) 03:38:20 INFO - [mozharness: 2017-06-06 10:38:20.895096Z] Running run-tests step. 03:38:20 INFO - Running pre-action listener: _resource_record_pre_action 03:38:20 INFO - Running main action method: run_tests 03:38:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:38:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:38:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:38:20 INFO - 2017-06-06 03:38:20.909 screenresolution[1648:8313] starting screenresolution argv=screenresolution get 03:38:20 INFO - 2017-06-06 03:38:20.912 screenresolution[1648:8313] Display 0: 1600x1200x32@60 03:38:20 INFO - 2017-06-06 03:38:20.918 screenresolution[1649:8315] starting screenresolution argv=screenresolution list 03:38:20 INFO - Available Modes on Display 0 03:38:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:38:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:38:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:38:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:38:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:38:21 INFO - Intel Iris: 03:38:21 INFO - Chipset Model: Intel Iris 03:38:21 INFO - Type: GPU 03:38:21 INFO - Bus: Built-In 03:38:21 INFO - VRAM (Dynamic, Max): 1536 MB 03:38:21 INFO - Vendor: Intel (0x8086) 03:38:21 INFO - Device ID: 0x0a2e 03:38:21 INFO - Revision ID: 0x0009 03:38:21 INFO - Displays: 03:38:21 INFO - Display: 03:38:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:38:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:38:21 INFO - Display Serial Number: 1600x1200 60 03:38:21 INFO - Main Display: Yes 03:38:21 INFO - Mirror: Off 03:38:21 INFO - Online: Yes 03:38:21 INFO - Rotation: Supported 03:38:21 INFO - Return code: 0 03:38:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:38:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:38:21 INFO - Python 2.7.3 03:38:21 INFO - Return code: 0 03:38:21 INFO - grabbing minidump binary from tooltool 03:38:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:38:21 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 0x1090f5c60>, '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 0x108e8acd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108eb3be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:38:21 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 03:38:21 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 03:38:21 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 03:38:21 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:38:21 INFO - Return code: 0 03:38:21 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:38:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:38:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:38:21 INFO - ENV: RUST_BACKTRACE is now 1 03:38:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:38:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:38:21 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/FoFtF0FURE6OXzrhzdmtUQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0027', '--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 03:38:21 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/FoFtF0FURE6OXzrhzdmtUQ/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0027 --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 03:38:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X85nbCil83/Render', 03:38:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:38:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:38:21 INFO - 'HOME': '/Users/cltbld', 03:38:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:38:21 INFO - 'LOGNAME': 'cltbld', 03:38:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:38:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:38:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:38:21 INFO - 'MOZ_NO_REMOTE': '1', 03:38:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:38:21 INFO - 'NO_EM_RESTART': '1', 03:38:21 INFO - 'PAGER': '/bin/cat', 03:38:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:38:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:38:21 INFO - 'PWD': '/builds/slave/test', 03:38:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:38:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:38:21 INFO - 'RUST_BACKTRACE': '1', 03:38:21 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:38:21 INFO - 'SHELL': '/bin/bash', 03:38:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MzG6ZkgXkS/Listeners', 03:38:21 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 03:38:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:38:21 INFO - 'USER': 'cltbld', 03:38:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:38:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:38:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:38:21 INFO - 'XPC_FLAGS': '0x0', 03:38:21 INFO - 'XPC_SERVICE_NAME': '0', 03:38:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:38:21 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/FoFtF0FURE6OXzrhzdmtUQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0027', '--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 03:38:21 INFO - mozversion application_buildid: 20170606022023 03:38:21 INFO - mozversion application_changeset: d3b8e8571020ffad87bb140b190799fc6fae06d5 03:38:21 INFO - mozversion application_display_name: Nightly 03:38:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:38:21 INFO - mozversion application_name: Firefox 03:38:21 INFO - mozversion application_remotingname: firefox 03:38:21 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:38:21 INFO - mozversion application_vendor: Mozilla 03:38:21 INFO - mozversion application_version: 55.0a1 03:38:21 INFO - mozversion platform_buildid: 20170606022023 03:38:21 INFO - mozversion platform_changeset: d3b8e8571020ffad87bb140b190799fc6fae06d5 03:38:21 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:38:21 INFO - mozversion platform_version: 55.0a1 03:38:21 INFO - using testdate: 1496745501 03:38:21 INFO - actual date: 1496745501 03:38:21 INFO - starting webserver on 'localhost:49226' 03:38:21 INFO - SUITE-START | Running 2 tests 03:38:21 INFO - TEST-START | dromaeo_css 03:38:21 INFO - operating with platform_type : mac_ 03:38:21 INFO - Initialising browser for dromaeo_css test... 03:38:21 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground -profile /var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/tmpgBKnS3/profile 03:38:21 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground) 03:38:55 INFO - TEST-INFO | 1659: killed by SIGTERM 03:38:55 INFO - Browser initialized. 03:38:55 INFO - Running cycle 1/1 for dromaeo_css test... 03:38:55 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/tmpgBKnS3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:38:57 INFO - PID 1667 | 2017-06-06 03:38:57.670 plugin-container[1668:8793] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x994b, name = 'com.apple.tsm.portname' 03:38:57 INFO - PID 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:38:57 INFO - PID 1667 | 2017-06-06 03:38:57.671 plugin-container[1668:8793] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0xa003, name = 'com.apple.CFPasteboardClient' 03:38:57 INFO - PID 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:38:57 INFO - PID 1667 | 2017-06-06 03:38:57.671 plugin-container[1668:8793] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:38:58 INFO - PID 1667 | 2017-06-06 03:38:58.188 plugin-container[1669:8843] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 03:38:58 INFO - PID 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:38:58 INFO - PID 1667 | 2017-06-06 03:38:58.189 plugin-container[1669:8843] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4913, name = 'com.apple.CFPasteboardClient' 03:38:58 INFO - PID 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:38:58 INFO - PID 1667 | 2017-06-06 03:38:58.189 plugin-container[1669:8843] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:39:07 INFO - PID 1667 | RSS: Main: 268107776 03:39:07 INFO - PID 1667 | 03:42:09 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49226/tests/dromaeo/cssquery-ext.html) 03:42:11 INFO - PID 1667 | RSS: Main: 267345920 03:42:11 INFO - PID 1667 | 03:45:13 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-ext.html (next: http://localhost:49226/tests/dromaeo/cssquery-jquery.html) 03:45:14 INFO - PID 1667 | RSS: Main: 282505216 03:45:14 INFO - PID 1667 | 03:48:16 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49226/tests/dromaeo/cssquery-mootools.html) 03:48:16 INFO - PID 1667 | RSS: Main: 283209728 03:48:16 INFO - PID 1667 | 03:51:18 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49226/tests/dromaeo/cssquery-prototype.html) 03:51:19 INFO - PID 1667 | RSS: Main: 286928896 03:51:19 INFO - PID 1667 | 03:54:21 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49226/tests/dromaeo/cssquery-yui.html) 03:54:22 INFO - PID 1667 | RSS: Main: 287899648 03:54:22 INFO - PID 1667 | 03:57:24 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-yui.html (next: http://localhost:49226/tests/dromaeo/cssquery-dojo.html) 03:57:24 INFO - PID 1667 | RSS: Main: 287981568 03:57:24 INFO - PID 1667 | 03:57:24 INFO - PID 1667 | __start_tp_report 03:57:24 INFO - PID 1667 | _x_x_mozilla_page_load 03:57:24 INFO - PID 1667 | _x_x_mozilla_page_load_details 03:57:24 INFO - PID 1667 | |i|pagename|runs| 03:57:24 INFO - PID 1667 | |0;dojo.html;3498.527698760667;3596.640335966402;3624.4744512045763;3626.492291079248;3630.8910732678014;4763.642726795491;6123.8317291405065;6251.874962500748;6288.559800813945;6296.087067375228;6127.433875357662;6195.287541932679;6229.345918678541;6303.936960630393;6327.92425287701;5301.867453313679;5423.674579525222;5432.67403955762;5472.72636368182;5564.4713752193675;5593.664380137205;5595.244641973342;5599.216109744639;5601.635893666919;5610.326760788711;5341.278927344798;5417.26866872975;5430.83707488776;5499.230107784913;5531.446855314456;5462.7268636568015;5476.205950137208;5497.47773961477;5508.14623733322;5510.669359838383;5401.7299135043095;5405.810796622139;5428.0500912340185;5452.073147564924;5473.343198816167;6126.242866754725;6161.691915404257;6223.502119830448;6251.78118765841;6274.121622972787;6620.602763834186;6722.563033402813;6728.562643428161;6729.25978142404;6737.225219099817;6824.112865327475;6828.556143850684;6833.453323734089;6841.692123854438;6845.281245469204;6265.404786545271;6352.078948552434;6364.204474440695;6366.427021568034;6373.267074286424;6803.48973826965;6874.518783685194;6931.514793964374;6955.513114081966;6975.546589471668;5214.374275086938;6384.010478375859;6546.509011824132;6613.834654133589;6617;6783.59298442095;6786.626735529593;6792.626405547742;6797.354251346114;6800.183977922681;6672.099266599044;6685.164354455693;6688.29772873851;6691.76578819739;6702.061711360413;6375.2668443128705;6446.290908000116;6591.934080659132;6646.933530664632;6722.899156512656;4572.679912406166;5289.127293996525;5367.275417818622;5377.193420986883;5386.380566234933;4017.91964160721;4227.668284490437;4753.47711751707;4791.0178413425165;4794.568488836021;5383;5923.200367950358;6306.180196574416;6306.810795676137;6368.808935731936;6781.321867813179;6843.178818541707;6843.486738494633;6851.45188384928;6851.828704282433;6312.084747711648;6338.429541341301;6383.2978372379;6388.616682999035;6394.808155755335;5797.88404231913;5866.794662186803;5952.494038006731;5961.135635332939;5996.070609055516;6887;6926.930730692729;6929.203141638776;6954.269801670752;6974.442044636316;6801.421879140328;6832.282610326043;6842.863142737217;6848.14398200225;6852.040714300102;5685.431456854281;6736;6869.107016087876;6947.270536593584;7011.6494175292055;6479.8056058318325;6584.539082264196;6600.141981542369;6648.268690444144;6681.2650608434005;6812.65936703153;6845.212800528003;6847.07564478789;6849.24658287578;6873.381395674413;6303.590266632654;6360.4911607072245;6377.075324078076;6516.218053833571;6555.082288479712;6525.967370163119;6529.934700652932;6530.412262896362;6562.868742625216;6579.4736421087155;6368.203974503187;6437.034444833313;6451.870962580816;6455.289918108911;6473.546851720334;5405.675659460445;6355.1420558224045;6378.202724659418;6419.4864410846085;6568.113304703804;5296.814611488579;5297.258383826191;6281.120643109877;6370.458511026614;6389.7763578274535 03:57:24 INFO - PID 1667 | |1;ext.html;776.5845272779064;823.7446391618598;838.6058552480335;1247.7504499100182;1314;38551.80724096379;39005.80497097511;39060.41409378858;39184.80407597965;39240.607593924055;38645;38892.80553597235;38919.41620875689;38960.61039389605;39041.60958390415;7777.22227777222;7951.403644726669;8210.917890821074;8312.667493300261;8449.873251901226;8360.83278334436;8471.449355791854;8492.320614350869;8499.107593702665;8503.829923401529;8444;8475.703350382737;8519.616617252239;8523.445976011539;8523.57382130894;8426.283765879847;8455.661773529051;8488.193621605998;8507.106753790824;8521.44610600312;7006.579605223703;8235.917640823634;8254.587270636444;8330.583470826496;8336.124706905808;36497;36628.450573241425;36639.816800916095;36672.266554669026;36685.81657091698;3601.58581763098;3621.619729928346;3642.526471558707;3649.6532829381167;3662.047867554428;29916.850415748;30345.393092138034;30410.84794576035;30417.391652166833;30446.391072178652;3060.9540856887165;3083.6145481814774;3100.534919762031;3100.612423447069;3111.2688518198206;186489;239638;281998.5900070507;286993.1300687008;305256;38204;47186.52813471821;47300;47326.76336618364;47328.290075648896;46349.07301854011;48731.75634121807;49162.508374916506;49226.753866231156;49329.75335123301;12679.809802852964;14074;14141.929290353688;14166.716665666629;14176.149431034171;11316.94341528287;11430.828537571942;11435.199536032564;11439.828402573969;11462.656120316236;13638.249896255802;13672.794908076386;13674.863251367358;13688.315584220749;13700.10949288313;4787.976060119677;6096.298925623521;6119.256012036548;6139.631622102688;6144.2319710036245;860.6643409070467;983.0071627656118;1001.3241062283075;1054.7922628590222;1063.5533076108054;24442.877785611316;24468.877655611606;24572.262832115066;25011.249662510152;25213;1078.3691540448926;1108.229780302698;1129.158776711339;1157.7106819234716;1159.8260260960756;10708.946455267675;11164.330140191614;12711.330620569599;12716;12721.745565088831;316036;318940;319693;320308;321255;53216.733916330944;53450.93098138015;53497.930041398955;53535.46464535383;53612.659683508224;1451.3178805961181;1502.3915314297726;1519.6960607878468;1529.495266562032;1543;22263.44341391478;22574.887125564266;23340.76659233386;23789.167379141647;24281.878590606935;36995.26009479741;37080.25839483249;37153.442698359;37189.6281037197;37246.62753372431;256878;257109;257517;257647.71176144;258176;34985.82507087448;35115.54191206591;35443.82278088593;35465.11337216487;35524.93425197145;42153.57846421619;42359;42591.57408425876;44546.55453445424;44632.77683611561;25774.355641109123;28536.42927141404;29916.70083299226;30580.388392231587;30825.691743083178;20408;29280;30339;30490;30843;35655.884816336875;36934.07664808401;37018.44472332971;37036.6296337044;37103.44344834776;27227.183184504498;29182.708172918847;29308.413831723672;29328.560071598517;30139.849300753354;20208.79791202128;20977.475563111046;21000.57998839984;22671.433214169778;23457.378187952494 03:57:24 INFO - PID 1667 | |2;jquery.html;427.95038873774;430.57588275548596;430.76738561176967;435.6231859441582;436.33676811246903;21917.561648767016;22792.860356982146;22824;23049.4237644059;23956;23472.061117555277;23600.5279894402;24138.637920431207;24182.75817241827;24198.274051778473;6205.255369355672;6352.205974253218;6359.55483116182;6364;6394.392532709385;6092.208012958309;6282.623042617458;6339.429451349378;6346.2067241594805;6350.809475715736;6210.223722034746;6252.124702541648;6257.5619706620555;6264.404881536247;6267.05994100884;6236.469900058501;6251.4061164189325;6257.092721555395;6262.12330273762;6266.091416744569;5517.200005999148;5564.137558678412;5566.832995010156;5577.079781835992;5579.191017302509;28444.857775711196;28500.287492812637;28739.712602874122;28983.13050608485;29004.12987610375;2124.3095993802076;2200.4168895242774;2201.801837834603;2204.4488877780555;2204.7574766775642;18787.53031174218;18832.717509237373;18867.62264754711;19047.238110475704;19056;2126.2451829600577;2198.373463562877;2221.1115553778454;2222.2222222222254;2233.8101261392803;162485;167427.16286418613;177740;183410;189699;11252.943735281271;12393.442295096742;12924.224546527239;13024.701848390008;13128.934355328352;13925.651858703412;16491.422800201934;16878.409255676237;16896;16925.069121198452;9025.909740902638;9038.276937844958;9083.18251357381;9285.860712089325;9450.33847630673;9021.413608115452;9030;9037.412568183046;9119.954400227956;9125.680601178927;8500.532470714046;8556.57217139153;8594.957025214833;8612.440191387539;8715.302775777922;2249.5163539839014;2251.5046689728233;2258.988705056497;2266.138867230442;2437.3297343230397;877.5699907045502;896.3232759266712;913.8583519554479;945.2579724916045;967.4727273635885;15815.051096934221;19454;21385.037673304738;22310.99600517948;22336.106555737915;928.3130483442204;979.294907666479;1012.3875055591409;1016.9288149829441;1044.3159730376615;10507.740835720357;10794.676159715063;11054.226204165792;11477.36874471912;11610.361430121255;461634;462114;534743;541513;557327.8404943574;139496;142448;143703;144393.8340924887;145994.2700286492;1135.4833550734395;1174.1252766688872;1189.4647408666135;1218.6892342452618;1230.4278510492663;21556.137754489646;23808.047678092684;24502.63246051275;24595.508089838462;24681.765911703984;63982.68008659927;64623.67688161529;67646;67716.6614166926;69590;92986;93076.53461732919;93331.06668933223;93609;93894;10160.695179144637;10333.638322658671;10432.739181520523;10440.582376704846;10514.94742526313;18335.266589336276;18480.16839242264;18483.90758046201;18538.814611853708;18605.883646981223;14845.554633361016;14881.553553393416;14899.47851825181;14914.850851491346;15092.320845562195;12599.68500787451;13145.408456619283;13497.460101595827;13514;13570.660733481742;14314.785278220626;14910.478133265284;15521.379144834531;15674.843251567794;15690.686186276438;10065.144462720751;10130.189584833342;10133.746656333356;10204.285700000928;10226.846597301195;12678.492860285485;14566.271686415848;14594.270286485846;14838.925805370904;14909.254537272876 03:57:24 INFO - PID 1667 | |3;mootools.html;1099.9615013474527;1359.9728005439888;1435.3971332040542;1454.9709005819884;1466.4134346261494;11688.941555292211;11762.353070581124;12223.205491643037;12284;12386.132970692055;12001.33992630405;12142.332171730552;12252.877471225285;12271.44778484968;12283.14018018739;2540.784033357162;2585.8060645451574;2587.767100960913;2589.2879458149064;2592.8703564821685;2500.437401584647;2540.974590254092;2544.0586982816385;2545.478176973725;2547.8598677072755;2486.353548077495;2527.936801579957;2530.607755797845;2530.6963164420295;2534.911278105267;2441.4384691520872;2456.1403508771964;2462.0274991378483;2469.3826543364157;2475.356407334088;2458.729539750644;2474.4679893822913;2490.2155820916355;2546.8853901574475;2564.6024866145776;11635.360055196961;11719.945204931511;11822.467988940518;11822.822657660143;11837.763244735057;5281.313429254173;5361.678299302054;6631.171103612049;6646.534742568022;6718.966405167992;3691.2248427830186;4054.5742697016976;4201.600847919473;4268.893277668052;4297.03316753731;1260.3950103950156;1263.311495235099;1267.4359909840125;1270.9428075736614;1292.5928332575209;15561.533154005398;15944.521664349972;16640.001599903924;16770;16786.580335491588;4953.6532442729385;5471.781128754885;5666.858328541737;5875.20684707559;5876.853078673067;12205.938970305091;12353.197042192409;12397.442115104843;12398.75202495945;12594.37028148589;6445;6497.642629655415;6543.836404089936;6557;6582.63795491243;6079.787207447718;6092.847678808065;6190.566660333823;6194.721237544321;6258.780942667076;4371.606555409965;4391.341298805205;4454.888627784267;4531.68278220528;4533.818647254139;1065.3501364168005;1256.9434375453127;1274.5284244829472;1285.6271681212343;1290.1936289818864;728.8943103249999;812.2933048248062;817.1787353709484;846.8306338732278;846.9407141500036;6385.553011289258;6880.518363714592;7378.852422951511;7590.848183036308;7685.654145563463;929.4748467116058;951.3720944176948;963.556763888605;969.2778879734502;978.0806042320196;4603.815847366068;4652.209124448784;4725.740084295328;4813.278008298713;4878.195097808822;11320.773584528264;12001.339926303963;14869.47956821506;14907.403703851724;15221.010634308734;14580.1980891052;14980.700385992219;15055.924720376328;15189.468368607046;15199.392024319124;8352;8553.700620478377;8556.144385561394;8563.657453701906;8569.528675922884;5057.8735531612;5315.681059136464;7515.473916825769;7580.05249343832;7665.6933722650465;12756.362181891054;13156.210627362389;13724.862751372359;13830.792538111735;13846.515371961934;13880.305984700926;14061.648458788202;14115.082519636191;14121.717565648834;14139.787903181255;8673.436226645248;8792.164744349277;8849.070847560914;8850.645974160961;8855.291576673979;10628.309159904582;10636.787264254517;10672.46637668129;10767.138628909825;10827.187960902806;9190.540472976458;9252.213561847318;9274.026227246311;9322.533873306173;9408.059194080537;8419.831603367777;8608.311335093053;8951.104889510996;8981.685641002796;9063.32025098107;8216.917830821854;8246.793830154294;8251.422400431911;8369.330453563578;8392.958035209786;5181.455947125648;6030.6080104793045;6043;6288.465480434032;6493;5321.6540924839765;5581.860453488695;6261;6511.511636627367;6606.43845273138 03:57:24 INFO - PID 1667 | |4;prototype.html;325.29086591231123;377.8507489541631;399.4886545222115;408.6199834154235;408.6363136808241;5698.458646428593;6354.364563543643;6472.223333200022;6523.706433210511;6628.237752658446;6162.582606000611;6167.4757645600175;6173.166622505972;6276.932921403372;6295.859407801347;3988.8205030773684;4595.540445955395;4609.377734005917;4609.6312295016405;4625.699329543569;4590.449146102473;4599.678022538424;4611.262198048313;4611.953880461186;4626.3523106764915;4145.523264824538;4411.691181617288;4552.203364411232;4563.361129441881;4587.472440669332;3614.457831325296;4555.817767289321;4576.199165146119;4582.908341833178;4584.404027476407;3596.29872174926;3600.3519366514015;3927.6434139516346;3937.3306537888343;3961.8019099045223;5586.966411213898;5655.688937108499;5682.829515114553;6207.658578778165;6229;5490.286262785852;5501.559875209973;5505.587080968903;5526.806561770319;5528.115501519777;5548.306461692288;5851.239338885917;6739.5282330236905;6739.865202695919;6771.762988295386;3761.5110035695457;4491.281394976821;4507.887302817423;4513.7066090704;4517.841875534374;6915.446764258847;7075.7617416951825;7195.265798996348;7264.7094116235285;7294.343509084208;3608.657177568127;4092.9972156821173;4377.890552736207;4581.862544123682;4586.97706511472;4265.549713097562;4555.863324100283;4671.672982891165;4677.415323084614;4678.3216433616935;3847.634474724897;5273.314469119066;5446.183072539151;5485.6960076767855;5495.0658388073325;5288.5769138469595;6710.966445167743;6778.457723382117;6781.796546103625;6794.76218332356;1390.7357602055577;1587.127080105937;1589.1815714906832;1589.2451085734183;1595.6250281183914;868.2402897464718;893.821235752852;968.5012218707371;1012.6455740490769;1038.8493668418037;5129.153689641242;5311.681299122065;6423.293437721847;6479.481641468671;6496.343432424702;5545.1682247663175;6394.424501794861;6396.78461092391;6972.511924165361;6986.406155476841;5281.260623512711;6106.023036314257;6375.203099612549;6423.197100362455;6473.190851143607;2982.9850850745606;3040.8783648653807;3663.816809159531;3665.17533554948;3749.850005999784;6061.757529698851;7271.527350722186;7292.927070729224;7298.343149116605;7324.523905946096;7227.385672217815;7256.274372562807;7289.380402665727;7296.927030729731;7340.265973402617;7282.3809976152625;7308.916958666754;7323.084614423197;7326.853462930711;7347.853042939005;5966.463018328372;7070.116235470567;7078.681459334445;7115.893261600977;7157.785266442015;6685.39831415175;6916.654167291716;6972.686229119803;6989.231184569795;6991.370776630128;6226.1905952227735;6562.310957349409;6907.447404207752;7045.37956270071;7312.268773122858;4257.63810076147;4270.423492828429;4399.186150562156;4608.663487588562;4633.443986721615;6089.934261504308;6328.398802113792;6402.071699603438;7037.366637002694;7101.680424380812;6742.460603151834;6765.42493888025;6767.323267673391;6772.322767723385;6795.626240556817;5235.424103348705;5617.691026993554;5903.321118071305;6306.927822270133;6779.288174741581;5519.365272993497;6053.273607167168;6620.5034622402545;6655.168103987002;6694.765683201064;6787.7237340475185;6802.285759995129;6810.455163586802;6838.281980392187;6850.862982740217;5743.856403589776;5845.678487683218;6431.260405053479;6578.309277525791;6606.339366063356 03:57:24 INFO - PID 1667 | |5;yui.html;711.9893201601974;737.9115804188822;787.3111027850631;852.3692467573998;867.7179916527131;16326.510204693863;16947.406840760577;16949;16952.321907123714;17000.489985300428;15475.45835895741;15655.13896735679;17851.732224016618;18336.174872130785;18504.72242916354;988.0267936082953;989.1641562879355;990.3711143423906;993.2897977945768;993.8757655293089;984.1978787288338;993.6323560282669;995.1938929467119;997.0727223681973;1006.0241565681278;987.5506644476745;1022.8670272864517;1026.2713473433835;1030.90206430389;1034.2760067952427;1004.6985904228702;1018.4093225928943;1022.018861892584;1022.0648106982103;1022.2231104360665;751.0724255544399;755.0486387152173;757.9204183560702;757.9242075792432;759.9468037237395;16889.577760555963;17225.39711110105;17238.482845514656;17394.826051739572;17803.554911127198;17333.826661733347;17707.645847083117;17735.37926172578;17795.46613601594;17838.821611783846;14079.648008799759;14539.854601454063;14640.707185856223;14658.853411465856;14663.560093197115;3415.64135765746;3518.5249991251367;3535.169235229719;3557.9822100889587;3594.532710747586;136375;137911.31044344814;138044;138713;139680.60319396778;13089.934550327187;13280.269585172908;13373.264470454023;13448.64648686476;13524.256165910772;25255.49489010246;26108.73891261101;26237.21288361314;26423.73576264213;26534;6299.5275354348605;6831.555948863308;6861.313868613099;6893.517453778288;6928.826779330557;3886.145048089416;3942.3495123304906;3948.0919388540797;3956.0703234739813;3958.6239307265764;4395.956040439619;4467.687261891637;4482.081173359454;4486.932696009562;4506.864794056183;881.8104107616894;889.7286327669993;916.5554705967601;953.6519170502855;962.5187406296851;633.3096924352386;638.1608185236493;638.3967151042314;657.8585604095047;669.0866966590569;11303.434828258718;11779.52881884715;12188.939055304667;12761.425735841909;13677.452901884013;645.3256347117274;645.6965226343611;659.3011407907537;661.454300202335;684.8048306232699;5286.784039670897;5432.6468779529205;5455.318085239345;5515.365732940683;5709.143628455682;118309.40845295718;123478;123652;125429.4742315464;125435;84981.72527412094;101918.49040754749;102455;105907.29112506549;106335;88119.55940220258;89425.39884109923;90044.1991160173;90278;91803;9797.608095676092;10577.259591828499;10888;11059.668209953714;11346.546138154381;40780.79609601933;41081;41274.58725412827;41281.58718412898;41492.79253603713;40670.77987660375;43217;46750.766246168554;47499.57501274967;55450.168247475514;14303.141811491343;14654;14780.260986950825;16482;17765.022923739318;18357.908210458863;18370.35703750362;19163.71254431157;19203.711944320567;19357.322493712654;17130.57173570621;17834.643307134043;17977.910110449364;18296.359627412978;18315.816841831944;15504.689906201587;17025.744613830553;17427.825721743127;17534.73697894558;18294.90852545729;17426.30294788245;17464.388746393815;17560.12199390051;17650.558736031187;17800.55498612545;11600.825987610362;11666.76666466683;12280.509546527508;12318.50725970987;12500.812487812509;12881.549145779853;12935.870641293466;14092.506762263272;14397.354225916964;14463.27683615794 03:57:24 INFO - PID 1667 | __end_tp_report 03:57:24 INFO - PID 1667 | __start_cc_report 03:57:24 INFO - PID 1667 | _x_x_mozilla_cycle_collect,459 03:57:24 INFO - PID 1667 | __end_cc_report 03:57:24 INFO - PID 1667 | __startTimestamp1496746644685__endTimestamp 03:57:24 INFO - PID 1667 | 03:57:24 INFO - PID 1667 | ------- Summary: start ------- 03:57:24 INFO - PID 1667 | Number of tests: 6 03:57:24 INFO - PID 1667 | 03:57:24 INFO - PID 1667 | [#0] dojo.html Cycles:165 Average:6145.59 Median:6373.27 stddev:768.81 (12.1%) stddev-sans-first:742.59 03:57:24 INFO - PID 1667 | Values: 3498.5 3596.6 3624.5 3626.5 3630.9 4763.6 6123.8 6251.9 6288.6 6296.1 6127.4 6195.3 6229.3 6303.9 6327.9 5301.9 5423.7 5432.7 5472.7 5564.5 5593.7 5595.2 5599.2 5601.6 5610.3 5341.3 5417.3 5430.8 5499.2 5531.4 5462.7 5476.2 5497.5 5508.1 5510.7 5401.7 5405.8 5428.1 5452.1 5473.3 6126.2 6161.7 6223.5 6251.8 6274.1 6620.6 6722.6 6728.6 6729.3 6737.2 6824.1 6828.6 6833.5 6841.7 6845.3 6265.4 6352.1 6364.2 6366.4 6373.3 6803.5 6874.5 6931.5 6955.5 6975.5 5214.4 6384.0 6546.5 6613.8 6617.0 6783.6 6786.6 6792.6 6797.4 6800.2 6672.1 6685.2 6688.3 6691.8 6702.1 6375.3 6446.3 6591.9 6646.9 6722.9 4572.7 5289.1 5367.3 5377.2 5386.4 4017.9 4227.7 4753.5 4791.0 4794.6 5383.0 5923.2 6306.2 6306.8 6368.8 6781.3 6843.2 6843.5 6851.5 6851.8 6312.1 6338.4 6383.3 6388.6 6394.8 5797.9 5866.8 5952.5 5961.1 5996.1 6887.0 6926.9 6929.2 6954.3 6974.4 6801.4 6832.3 6842.9 6848.1 6852.0 5685.4 6736.0 6869.1 6947.3 7011.6 6479.8 6584.5 6600.1 6648.3 6681.3 6812.7 6845.2 6847.1 6849.2 6873.4 6303.6 6360.5 6377.1 6516.2 6555.1 6526.0 6529.9 6530.4 6562.9 6579.5 6368.2 6437.0 6451.9 6455.3 6473.5 5405.7 6355.1 6378.2 6419.5 6568.1 5296.8 5297.3 6281.1 6370.5 6389.8 03:57:24 INFO - PID 1667 | 03:57:24 INFO - PID 1667 | [#1] ext.html Cycles:180 Average:42983.93 Median:35050.68 stddev:73763.77 (210.4%) stddev-sans-first:73902.62 03:57:24 INFO - PID 1667 | Values: 776.6 823.7 838.6 1247.8 1314.0 38551.8 39005.8 39060.4 39184.8 39240.6 38645.0 38892.8 38919.4 38960.6 39041.6 7777.2 7951.4 8210.9 8312.7 8449.9 8360.8 8471.4 8492.3 8499.1 8503.8 8444.0 8475.7 8519.6 8523.4 8523.6 8426.3 8455.7 8488.2 8507.1 8521.4 7006.6 8235.9 8254.6 8330.6 8336.1 36497.0 36628.5 36639.8 36672.3 36685.8 3601.6 3621.6 3642.5 3649.7 3662.0 29916.9 30345.4 30410.8 30417.4 30446.4 3061.0 3083.6 3100.5 3100.6 3111.3 186489.0 239638.0 281998.6 286993.1 305256.0 38204.0 47186.5 47300.0 47326.8 47328.3 46349.1 48731.8 49162.5 49226.8 49329.8 12679.8 14074.0 14141.9 14166.7 14176.1 11316.9 11430.8 11435.2 11439.8 11462.7 13638.2 13672.8 13674.9 13688.3 13700.1 4788.0 6096.3 6119.3 6139.6 6144.2 860.7 983.0 1001.3 1054.8 1063.6 24442.9 24468.9 24572.3 25011.2 25213.0 1078.4 1108.2 1129.2 1157.7 1159.8 10708.9 11164.3 12711.3 12716.0 12721.7 316036.0 318940.0 319693.0 320308.0 321255.0 53216.7 53450.9 53497.9 53535.5 53612.7 1451.3 1502.4 1519.7 1529.5 1543.0 22263.4 22574.9 23340.8 23789.2 24281.9 36995.3 37080.3 37153.4 37189.6 37246.6 256878.0 257109.0 257517.0 257647.7 258176.0 34985.8 35115.5 35443.8 35465.1 35524.9 42153.6 42359.0 42591.6 44546.6 44632.8 25774.4 28536.4 29916.7 30580.4 30825.7 20408.0 29280.0 30339.0 30490.0 30843.0 35655.9 36934.1 37018.4 37036.6 37103.4 27227.2 29182.7 29308.4 29328.6 30139.8 20208.8 20977.5 21000.6 22671.4 23457.4 03:57:24 INFO - PID 1667 | 03:57:24 INFO - PID 1667 | [#2] jquery.html Cycles:180 Average:37050.90 Median:2250.51 stddev:89120.16 (3960.0%) stddev-sans-first:89327.74 03:57:24 INFO - PID 1667 | Values: 428.0 430.6 430.8 435.6 436.3 21917.6 22792.9 22824.0 23049.4 23956.0 23472.1 23600.5 24138.6 24182.8 24198.3 6205.3 6352.2 6359.6 6364.0 6394.4 6092.2 6282.6 6339.4 6346.2 6350.8 6210.2 6252.1 6257.6 6264.4 6267.1 6236.5 6251.4 6257.1 6262.1 6266.1 5517.2 5564.1 5566.8 5577.1 5579.2 28444.9 28500.3 28739.7 28983.1 29004.1 2124.3 2200.4 2201.8 2204.4 2204.8 18787.5 18832.7 18867.6 19047.2 19056.0 2126.2 2198.4 2221.1 2222.2 2233.8 162485.0 167427.2 177740.0 183410.0 189699.0 11252.9 12393.4 12924.2 13024.7 13128.9 13925.7 16491.4 16878.4 16896.0 16925.1 9025.9 9038.3 9083.2 9285.9 9450.3 9021.4 9030.0 9037.4 9120.0 9125.7 8500.5 8556.6 8595.0 8612.4 8715.3 2249.5 2251.5 2259.0 2266.1 2437.3 877.6 896.3 913.9 945.3 967.5 15815.1 19454.0 21385.0 22311.0 22336.1 928.3 979.3 1012.4 1016.9 1044.3 10507.7 10794.7 11054.2 11477.4 11610.4 461634.0 462114.0 534743.0 541513.0 557327.8 139496.0 142448.0 143703.0 144393.8 145994.3 1135.5 1174.1 1189.5 1218.7 1230.4 21556.1 23808.0 24502.6 24595.5 24681.8 63982.7 64623.7 67646.0 67716.7 69590.0 92986.0 93076.5 93331.1 93609.0 93894.0 10160.7 10333.6 10432.7 10440.6 10514.9 18335.3 18480.2 18483.9 18538.8 18605.9 14845.6 14881.6 14899.5 14914.9 15092.3 12599.7 13145.4 13497.5 13514.0 13570.7 14314.8 14910.5 15521.4 15674.8 15690.7 10065.1 10130.2 10133.7 10204.3 10226.8 12678.5 14566.3 14594.3 14838.9 14909.3 03:57:24 INFO - PID 1667 | 03:57:24 INFO - PID 1667 | [#3] mootools.html Cycles:180 Average:7143.48 Median:3872.90 stddev:4501.43 (116.2%) stddev-sans-first:4491.14 03:57:24 INFO - PID 1667 | Values: 1100.0 1360.0 1435.4 1455.0 1466.4 11688.9 11762.4 12223.2 12284.0 12386.1 12001.3 12142.3 12252.9 12271.4 12283.1 2540.8 2585.8 2587.8 2589.3 2592.9 2500.4 2541.0 2544.1 2545.5 2547.9 2486.4 2527.9 2530.6 2530.7 2534.9 2441.4 2456.1 2462.0 2469.4 2475.4 2458.7 2474.5 2490.2 2546.9 2564.6 11635.4 11719.9 11822.5 11822.8 11837.8 5281.3 5361.7 6631.2 6646.5 6719.0 3691.2 4054.6 4201.6 4268.9 4297.0 1260.4 1263.3 1267.4 1270.9 1292.6 15561.5 15944.5 16640.0 16770.0 16786.6 4953.7 5471.8 5666.9 5875.2 5876.9 12205.9 12353.2 12397.4 12398.8 12594.4 6445.0 6497.6 6543.8 6557.0 6582.6 6079.8 6092.8 6190.6 6194.7 6258.8 4371.6 4391.3 4454.9 4531.7 4533.8 1065.4 1256.9 1274.5 1285.6 1290.2 728.9 812.3 817.2 846.8 846.9 6385.6 6880.5 7378.9 7590.8 7685.7 929.5 951.4 963.6 969.3 978.1 4603.8 4652.2 4725.7 4813.3 4878.2 11320.8 12001.3 14869.5 14907.4 15221.0 14580.2 14980.7 15055.9 15189.5 15199.4 8352.0 8553.7 8556.1 8563.7 8569.5 5057.9 5315.7 7515.5 7580.1 7665.7 12756.4 13156.2 13724.9 13830.8 13846.5 13880.3 14061.6 14115.1 14121.7 14139.8 8673.4 8792.2 8849.1 8850.6 8855.3 10628.3 10636.8 10672.5 10767.1 10827.2 9190.5 9252.2 9274.0 9322.5 9408.1 8419.8 8608.3 8951.1 8981.7 9063.3 8216.9 8246.8 8251.4 8369.3 8393.0 5181.5 6030.6 6043.0 6288.5 6493.0 5321.7 5581.9 6261.0 6511.5 6606.4 03:57:24 INFO - PID 1667 | 03:57:24 INFO - PID 1667 | [#4] prototype.html Cycles:180 Average:5393.18 Median:6057.52 stddev:1754.91 (29.0%) stddev-sans-first:1718.12 03:57:24 INFO - PID 1667 | Values: 325.3 377.9 399.5 408.6 408.6 5698.5 6354.4 6472.2 6523.7 6628.2 6162.6 6167.5 6173.2 6276.9 6295.9 3988.8 4595.5 4609.4 4609.6 4625.7 4590.4 4599.7 4611.3 4612.0 4626.4 4145.5 4411.7 4552.2 4563.4 4587.5 3614.5 4555.8 4576.2 4582.9 4584.4 3596.3 3600.4 3927.6 3937.3 3961.8 5587.0 5655.7 5682.8 6207.7 6229.0 5490.3 5501.6 5505.6 5526.8 5528.1 5548.3 5851.2 6739.5 6739.9 6771.8 3761.5 4491.3 4507.9 4513.7 4517.8 6915.4 7075.8 7195.3 7264.7 7294.3 3608.7 4093.0 4377.9 4581.9 4587.0 4265.5 4555.9 4671.7 4677.4 4678.3 3847.6 5273.3 5446.2 5485.7 5495.1 5288.6 6711.0 6778.5 6781.8 6794.8 1390.7 1587.1 1589.2 1589.2 1595.6 868.2 893.8 968.5 1012.6 1038.8 5129.2 5311.7 6423.3 6479.5 6496.3 5545.2 6394.4 6396.8 6972.5 6986.4 5281.3 6106.0 6375.2 6423.2 6473.2 2983.0 3040.9 3663.8 3665.2 3749.9 6061.8 7271.5 7292.9 7298.3 7324.5 7227.4 7256.3 7289.4 7296.9 7340.3 7282.4 7308.9 7323.1 7326.9 7347.9 5966.5 7070.1 7078.7 7115.9 7157.8 6685.4 6916.7 6972.7 6989.2 6991.4 6226.2 6562.3 6907.4 7045.4 7312.3 4257.6 4270.4 4399.2 4608.7 4633.4 6089.9 6328.4 6402.1 7037.4 7101.7 6742.5 6765.4 6767.3 6772.3 6795.6 5235.4 5617.7 5903.3 6306.9 6779.3 5519.4 6053.3 6620.5 6655.2 6694.8 6787.7 6802.3 6810.5 6838.3 6850.9 5743.9 5845.7 6431.3 6578.3 6606.3 03:57:24 INFO - PID 1667 | 03:57:24 INFO - PID 1667 | [#5] yui.html Cycles:180 Average:23070.45 Median:18326.00 stddev:34157.41 (186.4%) stddev-sans-first:34211.98 03:57:24 INFO - PID 1667 | Values: 712.0 737.9 787.3 852.4 867.7 16326.5 16947.4 16949.0 16952.3 17000.5 15475.5 15655.1 17851.7 18336.2 18504.7 988.0 989.2 990.4 993.3 993.9 984.2 993.6 995.2 997.1 1006.0 987.6 1022.9 1026.3 1030.9 1034.3 1004.7 1018.4 1022.0 1022.1 1022.2 751.1 755.0 757.9 757.9 759.9 16889.6 17225.4 17238.5 17394.8 17803.6 17333.8 17707.6 17735.4 17795.5 17838.8 14079.6 14539.9 14640.7 14658.9 14663.6 3415.6 3518.5 3535.2 3558.0 3594.5 136375.0 137911.3 138044.0 138713.0 139680.6 13089.9 13280.3 13373.3 13448.6 13524.3 25255.5 26108.7 26237.2 26423.7 26534.0 6299.5 6831.6 6861.3 6893.5 6928.8 3886.1 3942.3 3948.1 3956.1 3958.6 4396.0 4467.7 4482.1 4486.9 4506.9 881.8 889.7 916.6 953.7 962.5 633.3 638.2 638.4 657.9 669.1 11303.4 11779.5 12188.9 12761.4 13677.5 645.3 645.7 659.3 661.5 684.8 5286.8 5432.6 5455.3 5515.4 5709.1 118309.4 123478.0 123652.0 125429.5 125435.0 84981.7 101918.5 102455.0 105907.3 106335.0 88119.6 89425.4 90044.2 90278.0 91803.0 9797.6 10577.3 10888.0 11059.7 11346.5 40780.8 41081.0 41274.6 41281.6 41492.8 40670.8 43217.0 46750.8 47499.6 55450.2 14303.1 14654.0 14780.3 16482.0 17765.0 18357.9 18370.4 19163.7 19203.7 19357.3 17130.6 17834.6 17977.9 18296.4 18315.8 15504.7 17025.7 17427.8 17534.7 18294.9 17426.3 17464.4 17560.1 17650.6 17800.6 11600.8 11666.8 12280.5 12318.5 12500.8 12881.5 12935.9 14092.5 14397.4 14463.3 03:57:24 INFO - PID 1667 | -------- Summary: end -------- 03:57:24 INFO - PID 1667 | 03:57:25 INFO - TEST-INFO | 1667: exit 0 03:57:25 INFO - TEST-OK | dromaeo_css | took 1143834ms 03:57:25 INFO - TEST-START | kraken 03:57:25 INFO - operating with platform_type : mac_ 03:57:25 INFO - Initialising browser for kraken test... 03:57:25 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground -profile /var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/tmphUjDBV/profile 03:57:25 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground) 03:57:59 INFO - TEST-INFO | 1679: killed by SIGTERM 03:57:59 INFO - Browser initialized. 03:57:59 INFO - Running cycle 1/1 for kraken test... 03:57:59 INFO - TEST-INFO | started process 1683 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/tmphUjDBV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:58:01 INFO - PID 1683 | 2017-06-06 03:58:01.219 plugin-container[1684:11299] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x983f, name = 'com.apple.tsm.portname' 03:58:01 INFO - PID 1683 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:58:01 INFO - PID 1683 | 2017-06-06 03:58:01.219 plugin-container[1684:11299] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x491f, name = 'com.apple.CFPasteboardClient' 03:58:01 INFO - PID 1683 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:58:01 INFO - PID 1683 | 2017-06-06 03:58:01.219 plugin-container[1684:11299] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:58:01 INFO - PID 1683 | 2017-06-06 03:58:01.757 plugin-container[1685:11346] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.tsm.portname' 03:58:01 INFO - PID 1683 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:58:01 INFO - PID 1683 | 2017-06-06 03:58:01.757 plugin-container[1685:11346] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0f, name = 'com.apple.CFPasteboardClient' 03:58:01 INFO - PID 1683 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:58:01 INFO - PID 1683 | 2017-06-06 03:58:01.757 plugin-container[1685:11346] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:58:11 INFO - PID 1683 | RSS: Main: 268320768 03:58:11 INFO - PID 1683 | 03:58:13 INFO - PID 1683 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection) 03:58:14 INFO - PID 1683 | RSS: Main: 276156416 03:58:14 INFO - PID 1683 | 03:58:17 INFO - PID 1683 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-dft) 03:58:17 INFO - PID 1683 | RSS: Main: 284442624 03:58:17 INFO - PID 1683 | 03:58:21 INFO - PID 1683 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-fft) 03:58:21 INFO - PID 1683 | RSS: Main: 282345472 03:58:21 INFO - PID 1683 | 03:58:23 INFO - PID 1683 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator) 03:58:24 INFO - PID 1683 | RSS: Main: 284794880 03:58:24 INFO - PID 1683 | 03:58:26 INFO - PID 1683 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:58:27 INFO - PID 1683 | RSS: Main: 285028352 03:58:27 INFO - PID 1683 | 03:58:30 INFO - PID 1683 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom) 03:58:31 INFO - PID 1683 | RSS: Main: 292737024 03:58:31 INFO - PID 1683 | 03:58:34 INFO - PID 1683 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate) 03:58:34 INFO - PID 1683 | RSS: Main: 292999168 03:58:34 INFO - PID 1683 | 03:58:37 INFO - PID 1683 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial) 03:58:38 INFO - PID 1683 | RSS: Main: 291586048 03:58:38 INFO - PID 1683 | 03:58:39 INFO - PID 1683 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:58:40 INFO - PID 1683 | RSS: Main: 291733504 03:58:40 INFO - PID 1683 | 03:58:42 INFO - PID 1683 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:58:42 INFO - PID 1683 | RSS: Main: 291635200 03:58:42 INFO - PID 1683 | 03:58:45 INFO - PID 1683 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:58:45 INFO - PID 1683 | RSS: Main: 291983360 03:58:45 INFO - PID 1683 | 03:58:48 INFO - PID 1683 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:58:49 INFO - PID 1683 | RSS: Main: 291516416 03:58:49 INFO - PID 1683 | 03:58:52 INFO - PID 1683 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:58:53 INFO - PID 1683 | RSS: Main: 289435648 03:58:53 INFO - PID 1683 | 03:58:55 INFO - PID 1683 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49226/tests/kraken/driver.html?testName=ai-astar) 03:58:55 INFO - PID 1683 | RSS: Main: 291045376 03:58:55 INFO - PID 1683 | 03:58:55 INFO - PID 1683 | __start_tp_report 03:58:55 INFO - PID 1683 | _x_x_mozilla_page_load 03:58:55 INFO - PID 1683 | _x_x_mozilla_page_load_details 03:58:55 INFO - PID 1683 | |i|pagename|runs| 03:58:55 INFO - PID 1683 | |0;ai-astar;103;117;115;114;115;115;114;115;117;122 03:58:55 INFO - PID 1683 | |1;audio-beat-detection;141;128;147;154;109;113;111;115;142;94 03:58:55 INFO - PID 1683 | |2;audio-dft;197;201;197;200;202;219;244;269;205;201 03:58:55 INFO - PID 1683 | |3;audio-fft;78;61;83;110;87;67;55;58;85;47 03:58:55 INFO - PID 1683 | |4;audio-oscillator;85;69;88;56;69;86;86;69;94;113 03:58:55 INFO - PID 1683 | |5;imaging-gaussian-blur;90;133;89;124;137;89;90;113;138;89 03:58:55 INFO - PID 1683 | |6;imaging-darkroom;96;129;96;96;96;96;97;96;96;104 03:58:55 INFO - PID 1683 | |7;imaging-desaturate;90;93;129;136;93;88;92;99;93;92 03:58:55 INFO - PID 1683 | |8;json-parse-financial;49;50;49;50;51;49;53;51;52;51 03:58:55 INFO - PID 1683 | |9;json-stringify-tinderbox;56;64;58;65;60;63;57;54;55;55 03:58:55 INFO - PID 1683 | |10;stanford-crypto-aes;87;90;91;110;111;113;109;87;111;95 03:58:55 INFO - PID 1683 | |11;stanford-crypto-ccm;147;157;151;144;147;151;140;151;154;153 03:58:55 INFO - PID 1683 | |12;stanford-crypto-pbkdf2;218;227;208;246;236;198;225;238;238;185 03:58:55 INFO - PID 1683 | |13;stanford-crypto-sha256-iterative;70;68;68;70;69;66;68;62;67;68 03:58:55 INFO - PID 1683 | __end_tp_report 03:58:55 INFO - PID 1683 | __start_cc_report 03:58:55 INFO - PID 1683 | _x_x_mozilla_cycle_collect,1224 03:58:55 INFO - PID 1683 | __end_cc_report 03:58:55 INFO - PID 1683 | __startTimestamp1496746735567__endTimestamp 03:58:55 INFO - PID 1683 | 03:58:55 INFO - PID 1683 | ------- Summary: start ------- 03:58:55 INFO - PID 1683 | Number of tests: 14 03:58:55 INFO - PID 1683 | 03:58:55 INFO - PID 1683 | [#0] ai-astar Cycles:10 Average:114.70 Median:115.00 stddev:4.74 (4.1%) stddev-sans-first:2.50 03:58:55 INFO - PID 1683 | Values: 103.0 117.0 115.0 114.0 115.0 115.0 114.0 115.0 117.0 122.0 03:58:55 INFO - PID 1683 | 03:58:55 INFO - PID 1683 | [#1] audio-beat-detection Cycles:10 Average:125.40 Median:141.50 stddev:19.82 (14.0%) stddev-sans-first:20.20 03:58:55 INFO - PID 1683 | Values: 141.0 128.0 147.0 154.0 109.0 113.0 111.0 115.0 142.0 94.0 03:58:55 INFO - PID 1683 | 03:58:55 INFO - PID 1683 | [#2] audio-dft Cycles:10 Average:213.50 Median:203.50 stddev:24.23 (11.9%) stddev-sans-first:24.95 03:58:55 INFO - PID 1683 | Values: 197.0 201.0 197.0 200.0 202.0 219.0 244.0 269.0 205.0 201.0 03:58:55 INFO - PID 1683 | 03:58:55 INFO - PID 1683 | [#3] audio-fft Cycles:10 Average:73.10 Median:72.50 stddev:18.97 (26.2%) stddev-sans-first:20.04 03:58:55 INFO - PID 1683 | Values: 78.0 61.0 83.0 110.0 87.0 67.0 55.0 58.0 85.0 47.0 03:58:55 INFO - PID 1683 | 03:58:55 INFO - PID 1683 | [#4] audio-oscillator Cycles:10 Average:81.50 Median:85.50 stddev:16.20 (18.9%) stddev-sans-first:17.14 03:58:55 INFO - PID 1683 | Values: 85.0 69.0 88.0 56.0 69.0 86.0 86.0 69.0 94.0 113.0 03:58:55 INFO - PID 1683 | 03:58:55 INFO - PID 1683 | [#5] imaging-gaussian-blur Cycles:10 Average:109.20 Median:89.00 stddev:22.02 (24.7%) stddev-sans-first:22.23 03:58:55 INFO - PID 1683 | Values: 90.0 133.0 89.0 124.0 137.0 89.0 90.0 113.0 138.0 89.0 03:58:55 INFO - PID 1683 | 03:58:55 INFO - PID 1683 | [#6] imaging-darkroom Cycles:10 Average:100.20 Median:96.00 stddev:10.42 (10.9%) stddev-sans-first:10.94 03:58:55 INFO - PID 1683 | Values: 96.0 129.0 96.0 96.0 96.0 96.0 97.0 96.0 96.0 104.0 03:58:55 INFO - PID 1683 | 03:58:55 INFO - PID 1683 | [#7] imaging-desaturate Cycles:10 Average:100.50 Median:92.50 stddev:17.17 (18.6%) stddev-sans-first:17.79 03:58:55 INFO - PID 1683 | Values: 90.0 93.0 129.0 136.0 93.0 88.0 92.0 99.0 93.0 92.0 03:58:55 INFO - PID 1683 | 03:58:55 INFO - PID 1683 | [#8] json-parse-financial Cycles:10 Average:50.50 Median:51.00 stddev:1.35 (2.7%) stddev-sans-first:1.32 03:58:55 INFO - PID 1683 | Values: 49.0 50.0 49.0 50.0 51.0 49.0 53.0 51.0 52.0 51.0 03:58:55 INFO - PID 1683 | 03:58:55 INFO - PID 1683 | [#9] json-stringify-tinderbox Cycles:10 Average:58.70 Median:59.00 stddev:4.06 (6.9%) stddev-sans-first:4.18 03:58:55 INFO - PID 1683 | Values: 56.0 64.0 58.0 65.0 60.0 63.0 57.0 54.0 55.0 55.0 03:58:55 INFO - PID 1683 | 03:58:55 INFO - PID 1683 | [#10] stanford-crypto-aes Cycles:10 Average:100.40 Median:87.00 stddev:11.23 (12.9%) stddev-sans-first:10.81 03:58:55 INFO - PID 1683 | Values: 87.0 90.0 91.0 110.0 111.0 113.0 109.0 87.0 111.0 95.0 03:58:55 INFO - PID 1683 | 03:58:55 INFO - PID 1683 | [#11] stanford-crypto-ccm Cycles:10 Average:149.50 Median:151.00 stddev:5.04 (3.3%) stddev-sans-first:5.26 03:58:55 INFO - PID 1683 | Values: 147.0 157.0 151.0 144.0 147.0 151.0 140.0 151.0 154.0 153.0 03:58:55 INFO - PID 1683 | 03:58:55 INFO - PID 1683 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:221.90 Median:231.50 stddev:19.65 (8.5%) stddev-sans-first:20.79 03:58:55 INFO - PID 1683 | Values: 218.0 227.0 208.0 246.0 236.0 198.0 225.0 238.0 238.0 185.0 03:58:55 INFO - PID 1683 | 03:58:55 INFO - PID 1683 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.60 Median:68.00 stddev:2.32 (3.4%) stddev-sans-first:2.29 03:58:55 INFO - PID 1683 | Values: 70.0 68.0 68.0 70.0 69.0 66.0 68.0 62.0 67.0 68.0 03:58:55 INFO - PID 1683 | -------- Summary: end -------- 03:58:55 INFO - PID 1683 | 03:58:56 INFO - TEST-INFO | 1683: exit 0 03:58:56 INFO - TEST-OK | kraken | took 91093ms 03:58:56 INFO - SUITE-END | took 1234s 03:58:56 INFO - Completed test suite (00:20:35) 03:58:56 INFO - javascript benchmark 03:58:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7999.652960408007, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3498.527698760667, 3596.640335966402, 3624.4744512045763, 3626.492291079248, 3630.8910732678014, 4763.642726795491, 6123.8317291405065, 6251.874962500748, 6288.559800813945, 6296.087067375228, 6127.433875357662, 6195.287541932679, 6229.345918678541, 6303.936960630393, 6327.92425287701, 5301.867453313679, 5423.674579525222, 5432.67403955762, 5472.72636368182, 5564.4713752193675, 5593.664380137205, 5595.244641973342, 5599.216109744639, 5601.635893666919, 5610.326760788711, 5341.278927344798, 5417.26866872975, 5430.83707488776, 5499.230107784913, 5531.446855314456, 5462.7268636568015, 5476.205950137208, 5497.47773961477, 5508.14623733322, 5510.669359838383, 5401.7299135043095, 5405.810796622139, 5428.0500912340185, 5452.073147564924, 5473.343198816167, 6126.242866754725, 6161.691915404257, 6223.502119830448, 6251.78118765841, 6274.121622972787, 6620.602763834186, 6722.563033402813, 6728.562643428161, 6729.25978142404, 6737.225219099817, 6824.112865327475, 6828.556143850684, 6833.453323734089, 6841.692123854438, 6845.281245469204, 6265.404786545271, 6352.078948552434, 6364.204474440695, 6366.427021568034, 6373.267074286424, 6803.48973826965, 6874.518783685194, 6931.514793964374, 6955.513114081966, 6975.546589471668, 5214.374275086938, 6384.010478375859, 6546.509011824132, 6613.834654133589, 6617.0, 6783.59298442095, 6786.626735529593, 6792.626405547742, 6797.354251346114, 6800.183977922681, 6672.099266599044, 6685.164354455693, 6688.29772873851, 6691.76578819739, 6702.061711360413, 6375.2668443128705, 6446.290908000116, 6591.934080659132, 6646.933530664632, 6722.899156512656, 4572.679912406166, 5289.127293996525, 5367.275417818622, 5377.193420986883, 5386.380566234933, 4017.91964160721, 4227.668284490437, 4753.47711751707, 4791.0178413425165, 4794.568488836021, 5383.0, 5923.200367950358, 6306.180196574416, 6306.810795676137, 6368.808935731936, 6781.321867813179, 6843.178818541707, 6843.486738494633, 6851.45188384928, 6851.828704282433, 6312.084747711648, 6338.429541341301, 6383.2978372379, 6388.616682999035, 6394.808155755335, 5797.88404231913, 5866.794662186803, 5952.494038006731, 5961.135635332939, 5996.070609055516, 6887.0, 6926.930730692729, 6929.203141638776, 6954.269801670752, 6974.442044636316, 6801.421879140328, 6832.282610326043, 6842.863142737217, 6848.14398200225, 6852.040714300102, 5685.431456854281, 6736.0, 6869.107016087876, 6947.270536593584, 7011.6494175292055, 6479.8056058318325, 6584.539082264196, 6600.141981542369, 6648.268690444144, 6681.2650608434005, 6812.65936703153, 6845.212800528003, 6847.07564478789, 6849.24658287578, 6873.381395674413, 6303.590266632654, 6360.4911607072245, 6377.075324078076, 6516.218053833571, 6555.082288479712, 6525.967370163119, 6529.934700652932, 6530.412262896362, 6562.868742625216, 6579.4736421087155, 6368.203974503187, 6437.034444833313, 6451.870962580816, 6455.289918108911, 6473.546851720334, 5405.675659460445, 6355.1420558224045, 6378.202724659418, 6419.4864410846085, 6568.113304703804, 5296.814611488579, 5297.258383826191, 6281.120643109877, 6370.458511026614, 6389.7763578274535], "value": 6094.451041813056, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [776.5845272779064, 823.7446391618598, 838.6058552480335, 1247.7504499100182, 1314.0, 38551.80724096379, 39005.80497097511, 39060.41409378858, 39184.80407597965, 39240.607593924055, 38645.0, 38892.80553597235, 38919.41620875689, 38960.61039389605, 39041.60958390415, 7777.22227777222, 7951.403644726669, 8210.917890821074, 8312.667493300261, 8449.873251901226, 8360.83278334436, 8471.449355791854, 8492.320614350869, 8499.107593702665, 8503.829923401529, 8444.0, 8475.703350382737, 8519.616617252239, 8523.445976011539, 8523.57382130894, 8426.283765879847, 8455.661773529051, 8488.193621605998, 8507.106753790824, 8521.44610600312, 7006.579605223703, 8235.917640823634, 8254.587270636444, 8330.583470826496, 8336.124706905808, 36497.0, 36628.450573241425, 36639.816800916095, 36672.266554669026, 36685.81657091698, 3601.58581763098, 3621.619729928346, 3642.526471558707, 3649.6532829381167, 3662.047867554428, 29916.850415748, 30345.393092138034, 30410.84794576035, 30417.391652166833, 30446.391072178652, 3060.9540856887165, 3083.6145481814774, 3100.534919762031, 3100.612423447069, 3111.2688518198206, 186489.0, 239638.0, 281998.5900070507, 286993.1300687008, 305256.0, 38204.0, 47186.52813471821, 47300.0, 47326.76336618364, 47328.290075648896, 46349.07301854011, 48731.75634121807, 49162.508374916506, 49226.753866231156, 49329.75335123301, 12679.809802852964, 14074.0, 14141.929290353688, 14166.716665666629, 14176.149431034171, 11316.94341528287, 11430.828537571942, 11435.199536032564, 11439.828402573969, 11462.656120316236, 13638.249896255802, 13672.794908076386, 13674.863251367358, 13688.315584220749, 13700.10949288313, 4787.976060119677, 6096.298925623521, 6119.256012036548, 6139.631622102688, 6144.2319710036245, 860.6643409070467, 983.0071627656118, 1001.3241062283075, 1054.7922628590222, 1063.5533076108054, 24442.877785611316, 24468.877655611606, 24572.262832115066, 25011.249662510152, 25213.0, 1078.3691540448926, 1108.229780302698, 1129.158776711339, 1157.7106819234716, 1159.8260260960756, 10708.946455267675, 11164.330140191614, 12711.330620569599, 12716.0, 12721.745565088831, 316036.0, 318940.0, 319693.0, 320308.0, 321255.0, 53216.733916330944, 53450.93098138015, 53497.930041398955, 53535.46464535383, 53612.659683508224, 1451.3178805961181, 1502.3915314297726, 1519.6960607878468, 1529.495266562032, 1543.0, 22263.44341391478, 22574.887125564266, 23340.76659233386, 23789.167379141647, 24281.878590606935, 36995.26009479741, 37080.25839483249, 37153.442698359, 37189.6281037197, 37246.62753372431, 256878.0, 257109.0, 257517.0, 257647.71176144, 258176.0, 34985.82507087448, 35115.54191206591, 35443.82278088593, 35465.11337216487, 35524.93425197145, 42153.57846421619, 42359.0, 42591.57408425876, 44546.55453445424, 44632.77683611561, 25774.355641109123, 28536.42927141404, 29916.70083299226, 30580.388392231587, 30825.691743083178, 20408.0, 29280.0, 30339.0, 30490.0, 30843.0, 35655.884816336875, 36934.07664808401, 37018.44472332971, 37036.6296337044, 37103.44344834776, 27227.183184504498, 29182.708172918847, 29308.413831723672, 29328.560071598517, 30139.849300753354, 20208.79791202128, 20977.475563111046, 21000.57998839984, 22671.433214169778, 23457.378187952494], "value": 17205.349635854767, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [427.95038873774, 430.57588275548596, 430.76738561176967, 435.6231859441582, 436.33676811246903, 21917.561648767016, 22792.860356982146, 22824.0, 23049.4237644059, 23956.0, 23472.061117555277, 23600.5279894402, 24138.637920431207, 24182.75817241827, 24198.274051778473, 6205.255369355672, 6352.205974253218, 6359.55483116182, 6364.0, 6394.392532709385, 6092.208012958309, 6282.623042617458, 6339.429451349378, 6346.2067241594805, 6350.809475715736, 6210.223722034746, 6252.124702541648, 6257.5619706620555, 6264.404881536247, 6267.05994100884, 6236.469900058501, 6251.4061164189325, 6257.092721555395, 6262.12330273762, 6266.091416744569, 5517.200005999148, 5564.137558678412, 5566.832995010156, 5577.079781835992, 5579.191017302509, 28444.857775711196, 28500.287492812637, 28739.712602874122, 28983.13050608485, 29004.12987610375, 2124.3095993802076, 2200.4168895242774, 2201.801837834603, 2204.4488877780555, 2204.7574766775642, 18787.53031174218, 18832.717509237373, 18867.62264754711, 19047.238110475704, 19056.0, 2126.2451829600577, 2198.373463562877, 2221.1115553778454, 2222.2222222222254, 2233.8101261392803, 162485.0, 167427.16286418613, 177740.0, 183410.0, 189699.0, 11252.943735281271, 12393.442295096742, 12924.224546527239, 13024.701848390008, 13128.934355328352, 13925.651858703412, 16491.422800201934, 16878.409255676237, 16896.0, 16925.069121198452, 9025.909740902638, 9038.276937844958, 9083.18251357381, 9285.860712089325, 9450.33847630673, 9021.413608115452, 9030.0, 9037.412568183046, 9119.954400227956, 9125.680601178927, 8500.532470714046, 8556.57217139153, 8594.957025214833, 8612.440191387539, 8715.302775777922, 2249.5163539839014, 2251.5046689728233, 2258.988705056497, 2266.138867230442, 2437.3297343230397, 877.5699907045502, 896.3232759266712, 913.8583519554479, 945.2579724916045, 967.4727273635885, 15815.051096934221, 19454.0, 21385.037673304738, 22310.99600517948, 22336.106555737915, 928.3130483442204, 979.294907666479, 1012.3875055591409, 1016.9288149829441, 1044.3159730376615, 10507.740835720357, 10794.676159715063, 11054.226204165792, 11477.36874471912, 11610.361430121255, 461634.0, 462114.0, 534743.0, 541513.0, 557327.8404943574, 139496.0, 142448.0, 143703.0, 144393.8340924887, 145994.2700286492, 1135.4833550734395, 1174.1252766688872, 1189.4647408666135, 1218.6892342452618, 1230.4278510492663, 21556.137754489646, 23808.047678092684, 24502.63246051275, 24595.508089838462, 24681.765911703984, 63982.68008659927, 64623.67688161529, 67646.0, 67716.6614166926, 69590.0, 92986.0, 93076.53461732919, 93331.06668933223, 93609.0, 93894.0, 10160.695179144637, 10333.638322658671, 10432.739181520523, 10440.582376704846, 10514.94742526313, 18335.266589336276, 18480.16839242264, 18483.90758046201, 18538.814611853708, 18605.883646981223, 14845.554633361016, 14881.553553393416, 14899.47851825181, 14914.850851491346, 15092.320845562195, 12599.68500787451, 13145.408456619283, 13497.460101595827, 13514.0, 13570.660733481742, 14314.785278220626, 14910.478133265284, 15521.379144834531, 15674.843251567794, 15690.686186276438, 10065.144462720751, 10130.189584833342, 10133.746656333356, 10204.285700000928, 10226.846597301195, 12678.492860285485, 14566.271686415848, 14594.270286485846, 14838.925805370904, 14909.254537272876], "value": 11256.823892796978, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1099.9615013474527, 1359.9728005439888, 1435.3971332040542, 1454.9709005819884, 1466.4134346261494, 11688.941555292211, 11762.353070581124, 12223.205491643037, 12284.0, 12386.132970692055, 12001.33992630405, 12142.332171730552, 12252.877471225285, 12271.44778484968, 12283.14018018739, 2540.784033357162, 2585.8060645451574, 2587.767100960913, 2589.2879458149064, 2592.8703564821685, 2500.437401584647, 2540.974590254092, 2544.0586982816385, 2545.478176973725, 2547.8598677072755, 2486.353548077495, 2527.936801579957, 2530.607755797845, 2530.6963164420295, 2534.911278105267, 2441.4384691520872, 2456.1403508771964, 2462.0274991378483, 2469.3826543364157, 2475.356407334088, 2458.729539750644, 2474.4679893822913, 2490.2155820916355, 2546.8853901574475, 2564.6024866145776, 11635.360055196961, 11719.945204931511, 11822.467988940518, 11822.822657660143, 11837.763244735057, 5281.313429254173, 5361.678299302054, 6631.171103612049, 6646.534742568022, 6718.966405167992, 3691.2248427830186, 4054.5742697016976, 4201.600847919473, 4268.893277668052, 4297.03316753731, 1260.3950103950156, 1263.311495235099, 1267.4359909840125, 1270.9428075736614, 1292.5928332575209, 15561.533154005398, 15944.521664349972, 16640.001599903924, 16770.0, 16786.580335491588, 4953.6532442729385, 5471.781128754885, 5666.858328541737, 5875.20684707559, 5876.853078673067, 12205.938970305091, 12353.197042192409, 12397.442115104843, 12398.75202495945, 12594.37028148589, 6445.0, 6497.642629655415, 6543.836404089936, 6557.0, 6582.63795491243, 6079.787207447718, 6092.847678808065, 6190.566660333823, 6194.721237544321, 6258.780942667076, 4371.606555409965, 4391.341298805205, 4454.888627784267, 4531.68278220528, 4533.818647254139, 1065.3501364168005, 1256.9434375453127, 1274.5284244829472, 1285.6271681212343, 1290.1936289818864, 728.8943103249999, 812.2933048248062, 817.1787353709484, 846.8306338732278, 846.9407141500036, 6385.553011289258, 6880.518363714592, 7378.852422951511, 7590.848183036308, 7685.654145563463, 929.4748467116058, 951.3720944176948, 963.556763888605, 969.2778879734502, 978.0806042320196, 4603.815847366068, 4652.209124448784, 4725.740084295328, 4813.278008298713, 4878.195097808822, 11320.773584528264, 12001.339926303963, 14869.47956821506, 14907.403703851724, 15221.010634308734, 14580.1980891052, 14980.700385992219, 15055.924720376328, 15189.468368607046, 15199.392024319124, 8352.0, 8553.700620478377, 8556.144385561394, 8563.657453701906, 8569.528675922884, 5057.8735531612, 5315.681059136464, 7515.473916825769, 7580.05249343832, 7665.6933722650465, 12756.362181891054, 13156.210627362389, 13724.862751372359, 13830.792538111735, 13846.515371961934, 13880.305984700926, 14061.648458788202, 14115.082519636191, 14121.717565648834, 14139.787903181255, 8673.436226645248, 8792.164744349277, 8849.070847560914, 8850.645974160961, 8855.291576673979, 10628.309159904582, 10636.787264254517, 10672.46637668129, 10767.138628909825, 10827.187960902806, 9190.540472976458, 9252.213561847318, 9274.026227246311, 9322.533873306173, 9408.059194080537, 8419.831603367777, 8608.311335093053, 8951.104889510996, 8981.685641002796, 9063.32025098107, 8216.917830821854, 8246.793830154294, 8251.422400431911, 8369.330453563578, 8392.958035209786, 5181.455947125648, 6030.6080104793045, 6043.0, 6288.465480434032, 6493.0, 5321.6540924839765, 5581.860453488695, 6261.0, 6511.511636627367, 6606.43845273138], "value": 5416.15095473507, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [325.29086591231123, 377.8507489541631, 399.4886545222115, 408.6199834154235, 408.6363136808241, 5698.458646428593, 6354.364563543643, 6472.223333200022, 6523.706433210511, 6628.237752658446, 6162.582606000611, 6167.4757645600175, 6173.166622505972, 6276.932921403372, 6295.859407801347, 3988.8205030773684, 4595.540445955395, 4609.377734005917, 4609.6312295016405, 4625.699329543569, 4590.449146102473, 4599.678022538424, 4611.262198048313, 4611.953880461186, 4626.3523106764915, 4145.523264824538, 4411.691181617288, 4552.203364411232, 4563.361129441881, 4587.472440669332, 3614.457831325296, 4555.817767289321, 4576.199165146119, 4582.908341833178, 4584.404027476407, 3596.29872174926, 3600.3519366514015, 3927.6434139516346, 3937.3306537888343, 3961.8019099045223, 5586.966411213898, 5655.688937108499, 5682.829515114553, 6207.658578778165, 6229.0, 5490.286262785852, 5501.559875209973, 5505.587080968903, 5526.806561770319, 5528.115501519777, 5548.306461692288, 5851.239338885917, 6739.5282330236905, 6739.865202695919, 6771.762988295386, 3761.5110035695457, 4491.281394976821, 4507.887302817423, 4513.7066090704, 4517.841875534374, 6915.446764258847, 7075.7617416951825, 7195.265798996348, 7264.7094116235285, 7294.343509084208, 3608.657177568127, 4092.9972156821173, 4377.890552736207, 4581.862544123682, 4586.97706511472, 4265.549713097562, 4555.863324100283, 4671.672982891165, 4677.415323084614, 4678.3216433616935, 3847.634474724897, 5273.314469119066, 5446.183072539151, 5485.6960076767855, 5495.0658388073325, 5288.5769138469595, 6710.966445167743, 6778.457723382117, 6781.796546103625, 6794.76218332356, 1390.7357602055577, 1587.127080105937, 1589.1815714906832, 1589.2451085734183, 1595.6250281183914, 868.2402897464718, 893.821235752852, 968.5012218707371, 1012.6455740490769, 1038.8493668418037, 5129.153689641242, 5311.681299122065, 6423.293437721847, 6479.481641468671, 6496.343432424702, 5545.1682247663175, 6394.424501794861, 6396.78461092391, 6972.511924165361, 6986.406155476841, 5281.260623512711, 6106.023036314257, 6375.203099612549, 6423.197100362455, 6473.190851143607, 2982.9850850745606, 3040.8783648653807, 3663.816809159531, 3665.17533554948, 3749.850005999784, 6061.757529698851, 7271.527350722186, 7292.927070729224, 7298.343149116605, 7324.523905946096, 7227.385672217815, 7256.274372562807, 7289.380402665727, 7296.927030729731, 7340.265973402617, 7282.3809976152625, 7308.916958666754, 7323.084614423197, 7326.853462930711, 7347.853042939005, 5966.463018328372, 7070.116235470567, 7078.681459334445, 7115.893261600977, 7157.785266442015, 6685.39831415175, 6916.654167291716, 6972.686229119803, 6989.231184569795, 6991.370776630128, 6226.1905952227735, 6562.310957349409, 6907.447404207752, 7045.37956270071, 7312.268773122858, 4257.63810076147, 4270.423492828429, 4399.186150562156, 4608.663487588562, 4633.443986721615, 6089.934261504308, 6328.398802113792, 6402.071699603438, 7037.366637002694, 7101.680424380812, 6742.460603151834, 6765.42493888025, 6767.323267673391, 6772.322767723385, 6795.626240556817, 5235.424103348705, 5617.691026993554, 5903.321118071305, 6306.927822270133, 6779.288174741581, 5519.365272993497, 6053.273607167168, 6620.5034622402545, 6655.168103987002, 6694.765683201064, 6787.7237340475185, 6802.285759995129, 6810.455163586802, 6838.281980392187, 6850.862982740217, 5743.856403589776, 5845.678487683218, 6431.260405053479, 6578.309277525791, 6606.339366063356], "value": 4836.800960342362, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [711.9893201601974, 737.9115804188822, 787.3111027850631, 852.3692467573998, 867.7179916527131, 16326.510204693863, 16947.406840760577, 16949.0, 16952.321907123714, 17000.489985300428, 15475.45835895741, 15655.13896735679, 17851.732224016618, 18336.174872130785, 18504.72242916354, 988.0267936082953, 989.1641562879355, 990.3711143423906, 993.2897977945768, 993.8757655293089, 984.1978787288338, 993.6323560282669, 995.1938929467119, 997.0727223681973, 1006.0241565681278, 987.5506644476745, 1022.8670272864517, 1026.2713473433835, 1030.90206430389, 1034.2760067952427, 1004.6985904228702, 1018.4093225928943, 1022.018861892584, 1022.0648106982103, 1022.2231104360665, 751.0724255544399, 755.0486387152173, 757.9204183560702, 757.9242075792432, 759.9468037237395, 16889.577760555963, 17225.39711110105, 17238.482845514656, 17394.826051739572, 17803.554911127198, 17333.826661733347, 17707.645847083117, 17735.37926172578, 17795.46613601594, 17838.821611783846, 14079.648008799759, 14539.854601454063, 14640.707185856223, 14658.853411465856, 14663.560093197115, 3415.64135765746, 3518.5249991251367, 3535.169235229719, 3557.9822100889587, 3594.532710747586, 136375.0, 137911.31044344814, 138044.0, 138713.0, 139680.60319396778, 13089.934550327187, 13280.269585172908, 13373.264470454023, 13448.64648686476, 13524.256165910772, 25255.49489010246, 26108.73891261101, 26237.21288361314, 26423.73576264213, 26534.0, 6299.5275354348605, 6831.555948863308, 6861.313868613099, 6893.517453778288, 6928.826779330557, 3886.145048089416, 3942.3495123304906, 3948.0919388540797, 3956.0703234739813, 3958.6239307265764, 4395.956040439619, 4467.687261891637, 4482.081173359454, 4486.932696009562, 4506.864794056183, 881.8104107616894, 889.7286327669993, 916.5554705967601, 953.6519170502855, 962.5187406296851, 633.3096924352386, 638.1608185236493, 638.3967151042314, 657.8585604095047, 669.0866966590569, 11303.434828258718, 11779.52881884715, 12188.939055304667, 12761.425735841909, 13677.452901884013, 645.3256347117274, 645.6965226343611, 659.3011407907537, 661.454300202335, 684.8048306232699, 5286.784039670897, 5432.6468779529205, 5455.318085239345, 5515.365732940683, 5709.143628455682, 118309.40845295718, 123478.0, 123652.0, 125429.4742315464, 125435.0, 84981.72527412094, 101918.49040754749, 102455.0, 105907.29112506549, 106335.0, 88119.55940220258, 89425.39884109923, 90044.1991160173, 90278.0, 91803.0, 9797.608095676092, 10577.259591828499, 10888.0, 11059.668209953714, 11346.546138154381, 40780.79609601933, 41081.0, 41274.58725412827, 41281.58718412898, 41492.79253603713, 40670.77987660375, 43217.0, 46750.766246168554, 47499.57501274967, 55450.168247475514, 14303.141811491343, 14654.0, 14780.260986950825, 16482.0, 17765.022923739318, 18357.908210458863, 18370.35703750362, 19163.71254431157, 19203.711944320567, 19357.322493712654, 17130.57173570621, 17834.643307134043, 17977.910110449364, 18296.359627412978, 18315.816841831944, 15504.689906201587, 17025.744613830553, 17427.825721743127, 17534.73697894558, 18294.90852545729, 17426.30294788245, 17464.388746393815, 17560.12199390051, 17650.558736031187, 17800.55498612545, 11600.825987610362, 11666.76666466683, 12280.509546527508, 12318.50725970987, 12500.812487812509, 12881.549145779853, 12935.870641293466, 14092.506762263272, 14397.354225916964, 14463.27683615794], "value": 8474.876502506055, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1566.7000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 117.0, 115.0, 114.0, 115.0, 115.0, 114.0, 115.0, 117.0, 122.0], "value": 114.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 128.0, 147.0, 154.0, 109.0, 113.0, 111.0, 115.0, 142.0, 94.0], "value": 125.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 201.0, 197.0, 200.0, 202.0, 219.0, 244.0, 269.0, 205.0, 201.0], "value": 213.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 61.0, 83.0, 110.0, 87.0, 67.0, 55.0, 58.0, 85.0, 47.0], "value": 73.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 69.0, 88.0, 56.0, 69.0, 86.0, 86.0, 69.0, 94.0, 113.0], "value": 81.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 133.0, 89.0, 124.0, 137.0, 89.0, 90.0, 113.0, 138.0, 89.0], "value": 109.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 129.0, 96.0, 96.0, 96.0, 96.0, 97.0, 96.0, 96.0, 104.0], "value": 100.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 93.0, 129.0, 136.0, 93.0, 88.0, 92.0, 99.0, 93.0, 92.0], "value": 100.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 50.0, 49.0, 50.0, 51.0, 49.0, 53.0, 51.0, 52.0, 51.0], "value": 50.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 64.0, 58.0, 65.0, 60.0, 63.0, 57.0, 54.0, 55.0, 55.0], "value": 58.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 90.0, 91.0, 110.0, 111.0, 113.0, 109.0, 87.0, 111.0, 95.0], "value": 100.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 157.0, 151.0, 144.0, 147.0, 151.0, 140.0, 151.0, 154.0, 153.0], "value": 149.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [218.0, 227.0, 208.0, 246.0, 236.0, 198.0, 225.0, 238.0, 238.0, 185.0], "value": 221.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 68.0, 70.0, 69.0, 66.0, 68.0, 62.0, 67.0, 68.0], "value": 67.6, "unit": "score"}]}]} 03:58:56 INFO - Return code: 0 03:58:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:58:56 INFO - # TBPL SUCCESS # 03:58:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:58:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:58:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:58:56 INFO - 2017-06-06 03:58:56.986 screenresolution[1691:11729] starting screenresolution argv=screenresolution get 03:58:56 INFO - 2017-06-06 03:58:56.989 screenresolution[1691:11729] Display 0: 1600x1200x32@60 03:58:56 INFO - 2017-06-06 03:58:56.995 screenresolution[1692:11732] starting screenresolution argv=screenresolution list 03:58:56 INFO - Available Modes on Display 0 03:58:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:58:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:58:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:58:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:58:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:58:57 INFO - Intel Iris: 03:58:57 INFO - Chipset Model: Intel Iris 03:58:57 INFO - Type: GPU 03:58:57 INFO - Bus: Built-In 03:58:57 INFO - VRAM (Dynamic, Max): 1536 MB 03:58:57 INFO - Vendor: Intel (0x8086) 03:58:57 INFO - Device ID: 0x0a2e 03:58:57 INFO - Revision ID: 0x0009 03:58:57 INFO - Displays: 03:58:57 INFO - Display: 03:58:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:58:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:58:57 INFO - Display Serial Number: 1600x1200 60 03:58:57 INFO - Main Display: Yes 03:58:57 INFO - Mirror: Off 03:58:57 INFO - Online: Yes 03:58:57 INFO - Rotation: Supported 03:58:57 INFO - Return code: 0 03:58:57 INFO - Running post-action listener: _resource_record_post_action 03:58:57 INFO - [mozharness: 2017-06-06 10:58:57.230656Z] Finished run-tests step (success) 03:58:57 INFO - Running post-run listener: _resource_record_post_run 03:58:57 INFO - Total resource usage - Wall time: 1257s; CPU: 53.0%; Read bytes: 36066304; Write bytes: 572516352; Read time: 344; Write time: 1950 03:58:57 INFO - TinderboxPrint: CPU usage
53.4% 03:58:57 INFO - TinderboxPrint: I/O read bytes / time
36,066,304 / 344 03:58:57 INFO - TinderboxPrint: I/O write bytes / time
572,516,352 / 1,950 03:58:57 INFO - TinderboxPrint: CPU idle
2,345.7 (46.6%) 03:58:57 INFO - TinderboxPrint: CPU system
1,021.6 (20.3%) 03:58:57 INFO - TinderboxPrint: CPU user
1,662.5 (33.1%) 03:58:57 INFO - TinderboxPrint: Swap in / out
278,605,824 / 0 03:58:57 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 229876224; Write bytes: 229310464; Read time: 16789; Write time: 436 03:58:57 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:58:57 INFO - run-tests - Wall time: 1236s; CPU: 53.0%; Read bytes: 35066880; Write bytes: 324716544; Read time: 325; Write time: 1473 03:58:57 INFO - Running post-run listener: _upload_blobber_files 03:58:57 INFO - Blob upload gear active. 03:58:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:58:57 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 03:58:57 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'] 03:58:57 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 03:58:58 INFO - (blobuploader) - INFO - Open directory for files ... 03:58:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:58:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:58:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:58:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:58:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:58:58 INFO - (blobuploader) - INFO - Done attempting. 03:58:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:58:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:58:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:58:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:58:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:58:59 INFO - (blobuploader) - INFO - Done attempting. 03:58:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:58:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:58:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:59:01 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:59:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:59:01 INFO - (blobuploader) - INFO - Done attempting. 03:59:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:59:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:59:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:59:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:59:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:59:03 INFO - (blobuploader) - INFO - Done attempting. 03:59:03 INFO - (blobuploader) - INFO - Iteration through files over. 03:59:03 INFO - Return code: 0 03:59:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:59:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:59:03 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a84d778f1ae3e40655102c9576ad105b0e8343c6df0fca5d68fd3f56f8db017dc80e9f95da532418ca581d2d1874df69719a5a2abf0463ac905173e143aeb1a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e3d5af9bc1b6ce3cdd37323af2e9ea29e2900d3e9c7b5d848b957b4482b460a861824937971087400458b0b2658a2a6bb67289cc4498892edadedbd4c438c721", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fc069e0deadaa09f7089e4e9c33f7e4a7d1d8533b7b1a0621f45a99ed4879e7c8a22bff8515ff140e6faef12cc225f946ca25d936afd098a73d2116d76ce84fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2dcbfbec38486364802371713a23e6bf2e458901a062abfb1e276df691f295f98b25b84db5c0104b29d5c7ba7e272a990fa762f5b1ccc317eb330bd673787e1b"} 03:59:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:59:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:59:03 INFO - Contents: 03:59:03 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a84d778f1ae3e40655102c9576ad105b0e8343c6df0fca5d68fd3f56f8db017dc80e9f95da532418ca581d2d1874df69719a5a2abf0463ac905173e143aeb1a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e3d5af9bc1b6ce3cdd37323af2e9ea29e2900d3e9c7b5d848b957b4482b460a861824937971087400458b0b2658a2a6bb67289cc4498892edadedbd4c438c721", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fc069e0deadaa09f7089e4e9c33f7e4a7d1d8533b7b1a0621f45a99ed4879e7c8a22bff8515ff140e6faef12cc225f946ca25d936afd098a73d2116d76ce84fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2dcbfbec38486364802371713a23e6bf2e458901a062abfb1e276df691f295f98b25b84db5c0104b29d5c7ba7e272a990fa762f5b1ccc317eb330bd673787e1b"} 03:59:03 INFO - Running post-run listener: copy_logs_to_upload_dir 03:59:03 INFO - Copying logs to upload dir... 03:59:03 INFO - mkdir: /builds/slave/test/build/upload/logs 03:59:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1339.416125 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-06-06 03:59:03.528224) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-06 03:59:03.531741) ========= 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.X85nbCil83/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.MzG6ZkgXkS/Listeners TMPDIR=/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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/4a84d778f1ae3e40655102c9576ad105b0e8343c6df0fca5d68fd3f56f8db017dc80e9f95da532418ca581d2d1874df69719a5a2abf0463ac905173e143aeb1a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e3d5af9bc1b6ce3cdd37323af2e9ea29e2900d3e9c7b5d848b957b4482b460a861824937971087400458b0b2658a2a6bb67289cc4498892edadedbd4c438c721", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fc069e0deadaa09f7089e4e9c33f7e4a7d1d8533b7b1a0621f45a99ed4879e7c8a22bff8515ff140e6faef12cc225f946ca25d936afd098a73d2116d76ce84fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2dcbfbec38486364802371713a23e6bf2e458901a062abfb1e276df691f295f98b25b84db5c0104b29d5c7ba7e272a990fa762f5b1ccc317eb330bd673787e1b"} build_url:https://queue.taskcluster.net/v1/task/FoFtF0FURE6OXzrhzdmtUQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011062 build_url: 'https://queue.taskcluster.net/v1/task/FoFtF0FURE6OXzrhzdmtUQ/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/4a84d778f1ae3e40655102c9576ad105b0e8343c6df0fca5d68fd3f56f8db017dc80e9f95da532418ca581d2d1874df69719a5a2abf0463ac905173e143aeb1a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e3d5af9bc1b6ce3cdd37323af2e9ea29e2900d3e9c7b5d848b957b4482b460a861824937971087400458b0b2658a2a6bb67289cc4498892edadedbd4c438c721", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fc069e0deadaa09f7089e4e9c33f7e4a7d1d8533b7b1a0621f45a99ed4879e7c8a22bff8515ff140e6faef12cc225f946ca25d936afd098a73d2116d76ce84fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2dcbfbec38486364802371713a23e6bf2e458901a062abfb1e276df691f295f98b25b84db5c0104b29d5c7ba7e272a990fa762f5b1ccc317eb330bd673787e1b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-06 03:59:03.565232) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 03:59:03.565565) ========= 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.X85nbCil83/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.MzG6ZkgXkS/Listeners TMPDIR=/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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.005308 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 03:59:03.614262) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-06 03:59:03.614576) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-06 03:59:03.615036) ========= ========= Total master_lag: 0.17 =========