builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0027 starttime: 1497270461.7 results: success (0) revision: 8c7211cc49637cec922b1d4b141a44c7007f9bb6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-12 05:27:41.699727) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-12 05:27:41.700160) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-12 05:27:41.700428) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-12 05:27:41.726001) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 05:27:41.726287) ========= 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.2uoXBe3KjX/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.0YF1uuoazl/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-12 05:27:41-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 12.9M=0.001s 2017-06-12 05:27:42 (12.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.352030 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 05:27:42.101789) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 05:27:42.102348) ========= 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.2uoXBe3KjX/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.0YF1uuoazl/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.035946 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 05:27:42.159633) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-12 05:27:42.159999) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8c7211cc49637cec922b1d4b141a44c7007f9bb6 --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 8c7211cc49637cec922b1d4b141a44c7007f9bb6 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.2uoXBe3KjX/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.0YF1uuoazl/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-12 05:27:42,257 truncating revision to first 12 chars 2017-06-12 05:27:42,257 Setting DEBUG logging. 2017-06-12 05:27:42,257 attempt 1/10 2017-06-12 05:27:42,257 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8c7211cc4963?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-12 05:27:43,328 unpacking tar archive at: mozilla-central-8c7211cc4963/testing/mozharness/ program finished with exit code 0 elapsedTime=1.347565 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-12 05:27:43.529602) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-12 05:27:43.530098) ========= script_repo_revision: 8c7211cc49637cec922b1d4b141a44c7007f9bb6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-12 05:27:43.530470) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-12 05:27:43.530745) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-12 05:27:43.549439) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-06-12 05:27:43.549875) ========= /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.2uoXBe3KjX/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.0YF1uuoazl/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 05:27:43 INFO - MultiFileLogger online at 20170612 05:27:43 in /builds/slave/test 05:27:43 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 05:27:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:27:43 INFO - {'append_to_log': False, 05:27:43 INFO - 'base_work_dir': '/builds/slave/test', 05:27:43 INFO - 'blob_upload_branch': 'Firefox', 05:27:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:27:43 INFO - 'branch': 'Firefox', 05:27:43 INFO - 'buildbot_json_path': 'buildprops.json', 05:27:43 INFO - 'config_files': ('talos/mac_config.py',), 05:27:43 INFO - 'default_actions': ('clobber', 05:27:43 INFO - 'read-buildbot-config', 05:27:43 INFO - 'download-and-extract', 05:27:43 INFO - 'populate-webroot', 05:27:43 INFO - 'create-virtualenv', 05:27:43 INFO - 'install', 05:27:43 INFO - 'setup-mitmproxy', 05:27:43 INFO - 'run-tests'), 05:27:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:27:43 INFO - 'download_minidump_stackwalk': True, 05:27:43 INFO - 'download_symbols': 'ondemand', 05:27:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:27:43 INFO - 'tooltool.py': '/tools/tooltool.py', 05:27:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:27:43 INFO - '/tools/misc-python/virtualenv.py')}, 05:27:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:27:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:27:43 INFO - 'gecko_profile': False, 05:27:43 INFO - 'gecko_profile_interval': 0, 05:27:43 INFO - 'installer_path': 'installer.exe', 05:27:43 INFO - 'log_level': 'info', 05:27:43 INFO - 'log_name': 'talos', 05:27:43 INFO - 'log_to_console': True, 05:27:43 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:27:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:27:43 INFO - 'opt_config_files': (), 05:27:43 INFO - 'pip_index': False, 05:27:43 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:27:43 INFO - 'cmd': ('bash', 05:27:43 INFO - '-c', 05:27:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:27:43 INFO - 'enabled': True, 05:27:43 INFO - 'halt_on_failure': False, 05:27:43 INFO - 'name': 'check_screen_resolution'},), 05:27:43 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:27:43 INFO - 'cmd': ('bash', 05:27:43 INFO - '-c', 05:27:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:27:43 INFO - 'enabled': True, 05:27:43 INFO - 'halt_on_failure': False, 05:27:43 INFO - 'name': 'check_screen_resolution'},), 05:27:43 INFO - 'run_cmd_checks_enabled': True, 05:27:43 INFO - 'suite': 'dromaeojs-e10s', 05:27:43 INFO - 'system_bits': '32', 05:27:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:27:43 INFO - 'title': 't-yosemite-r7-0027', 05:27:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:27:43 INFO - 'use_talos_json': True, 05:27:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:27:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:27:43 INFO - 'work_dir': 'build'} 05:27:43 INFO - [mozharness: 2017-06-12 12:27:43.764313Z] Running clobber step. 05:27:43 INFO - Running pre-action listener: _resource_record_pre_action 05:27:43 INFO - Running main action method: clobber 05:27:43 INFO - rmtree: /builds/slave/test/build 05:27:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:27:46 INFO - Running post-action listener: _resource_record_post_action 05:27:46 INFO - [mozharness: 2017-06-12 12:27:46.174867Z] Finished clobber step (success) 05:27:46 INFO - [mozharness: 2017-06-12 12:27:46.175040Z] Running read-buildbot-config step. 05:27:46 INFO - Running pre-action listener: _resource_record_pre_action 05:27:46 INFO - Running main action method: read_buildbot_config 05:27:46 INFO - Using buildbot properties: 05:27:46 INFO - { 05:27:46 INFO - "project": "", 05:27:46 INFO - "product": "firefox", 05:27:46 INFO - "who": "cbook@mozilla.com", 05:27:46 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 05:27:46 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:27:46 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 05:27:46 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:27:46 INFO - "stage_platform": "macosx64", 05:27:46 INFO - "basedir": "/builds/slave/test", 05:27:46 INFO - "buildnumber": 24, 05:27:46 INFO - "platform": "yosemite_r7", 05:27:46 INFO - "parent_task_id": "K-k7BBcFRvmbJubPOj5Mzg", 05:27:46 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 05:27:46 INFO - "slavebuilddir": "test", 05:27:46 INFO - "taskId": "XZfH-TFYRGyv1Va1upFfVA", 05:27:46 INFO - "branch": "mozilla-central", 05:27:46 INFO - "script_repo_revision": "production", 05:27:46 INFO - "revision": "8c7211cc49637cec922b1d4b141a44c7007f9bb6", 05:27:46 INFO - "slavename": "t-yosemite-r7-0027", 05:27:46 INFO - "repo_path": "mozilla-central" 05:27:46 INFO - } 05:27:46 INFO - Finding installer, test and symbols from parent task. 05:27:46 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XZfH-TFYRGyv1Va1upFfVA'}, attempt #1 05:27:46 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XZfH-TFYRGyv1Va1upFfVA'}, attempt #1 05:27:47 INFO - Task dependencies: bbx_jB-gQRe9appjlMZVOw K-k7BBcFRvmbJubPOj5Mzg 05:27:47 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K-k7BBcFRvmbJubPOj5Mzg'}, attempt #1 05:27:47 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/K-k7BBcFRvmbJubPOj5Mzg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:27:47 INFO - Set test_url: https://queue.taskcluster.net/v1/task/K-k7BBcFRvmbJubPOj5Mzg/artifacts/public/build/test_packages.json 05:27:47 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/K-k7BBcFRvmbJubPOj5Mzg/artifacts/public/build/target.crashreporter-symbols.zip 05:27:47 INFO - Running post-action listener: _resource_record_post_action 05:27:47 INFO - [mozharness: 2017-06-12 12:27:47.528127Z] Finished read-buildbot-config step (success) 05:27:47 INFO - [mozharness: 2017-06-12 12:27:47.528250Z] Running download-and-extract step. 05:27:47 INFO - Running pre-action listener: _resource_record_pre_action 05:27:47 INFO - Running main action method: download_and_extract 05:27:47 INFO - mkdir: /builds/slave/test/build/tests 05:27: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')]} 05:27:47 INFO - trying https://queue.taskcluster.net/v1/task/K-k7BBcFRvmbJubPOj5Mzg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 05:27:47 INFO - Downloading https://queue.taskcluster.net/v1/task/K-k7BBcFRvmbJubPOj5Mzg/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 05:27:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K-k7BBcFRvmbJubPOj5Mzg/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 05:27:48 INFO - Downloaded 1328 bytes. 05:27:48 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 05:27:48 INFO - Using the following test package requirements: 05:27:48 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:27:48 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 05:27:48 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 05:27:48 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:27:48 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 05:27:48 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:27:48 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 05:27:48 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:27:48 INFO - u'jsshell-mac.zip'], 05:27:48 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:27:48 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 05:27:48 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 05:27:48 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:27:48 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 05:27:48 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:27:48 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 05:27:48 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:27:48 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.tar.gz'], 05:27:48 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:27:48 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 05:27:48 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:27:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/K-k7BBcFRvmbJubPOj5Mzg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 05:27:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K-k7BBcFRvmbJubPOj5Mzg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:27:48 INFO - Fetch https://queue.taskcluster.net/v1/task/K-k7BBcFRvmbJubPOj5Mzg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 05:27:50 INFO - Content-Length response header: 59540632 05:27:50 INFO - Bytes received: 59540632 05:28:08 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 05:28:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/K-k7BBcFRvmbJubPOj5Mzg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 05:28:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K-k7BBcFRvmbJubPOj5Mzg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:28:08 INFO - Fetch https://queue.taskcluster.net/v1/task/K-k7BBcFRvmbJubPOj5Mzg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 05:28:10 INFO - Content-Length response header: 59540632 05:28:10 INFO - Bytes received: 59540632 05:28:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/K-k7BBcFRvmbJubPOj5Mzg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 05:28:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K-k7BBcFRvmbJubPOj5Mzg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:28:27 INFO - Fetch https://queue.taskcluster.net/v1/task/K-k7BBcFRvmbJubPOj5Mzg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 05:28:28 INFO - Content-Length response header: 14450010 05:28:28 INFO - Bytes received: 14450010 05:28:28 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')]} 05:28:28 INFO - trying https://queue.taskcluster.net/v1/task/K-k7BBcFRvmbJubPOj5Mzg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:28:28 INFO - Downloading https://queue.taskcluster.net/v1/task/K-k7BBcFRvmbJubPOj5Mzg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 05:28:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K-k7BBcFRvmbJubPOj5Mzg/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 05:28:30 INFO - Downloaded 69310773 bytes. 05:28:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/K-k7BBcFRvmbJubPOj5Mzg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:28:30 INFO - mkdir: /builds/slave/test/properties 05:28:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:28:30 INFO - Writing to file /builds/slave/test/properties/build_url 05:28:30 INFO - Contents: 05:28:30 INFO - build_url:https://queue.taskcluster.net/v1/task/K-k7BBcFRvmbJubPOj5Mzg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:28:30 INFO - Running post-action listener: _resource_record_post_action 05:28:30 INFO - Running post-action listener: set_extra_try_arguments 05:28:30 INFO - [mozharness: 2017-06-12 12:28:30.887107Z] Finished download-and-extract step (success) 05:28:30 INFO - [mozharness: 2017-06-12 12:28:30.887255Z] Running populate-webroot step. 05:28:30 INFO - Running pre-action listener: _resource_record_pre_action 05:28:30 INFO - Running main action method: populate_webroot 05:28:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:28:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:28:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:28:30 INFO - '--tppagecycles', 05:28:30 INFO - '7'], 05:28:30 INFO - 'tests': ['tsvgm']}}, 05:28:30 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:28:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:28:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:28:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:28:30 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:28:30 INFO - 'talos_options': ['--disable-e10s'], 05:28:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:28:30 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:28:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:28:30 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:28:30 INFO - 'talos_options': ['--disable-e10s'], 05:28:30 INFO - 'tests': ['damp', 'tps']}, 05:28:30 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:28:30 INFO - 'tests': ['damp', 'tps']}, 05:28:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:28:30 INFO - 'tests': ['dromaeo_dom']}, 05:28:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:28:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:28:30 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 05:28:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 05:28:30 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 05:28:30 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 05:28:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:28:30 INFO - 'tests': ['a11yr', 05:28:30 INFO - 'ts_paint', 05:28:30 INFO - 'tpaint', 05:28:30 INFO - 'sessionrestore', 05:28:30 INFO - 'sessionrestore_no_auto_restore', 05:28:30 INFO - 'tabpaint']}, 05:28:30 INFO - 'other-e10s': {'tests': ['a11yr', 05:28:30 INFO - 'ts_paint', 05:28:30 INFO - 'tpaint', 05:28:30 INFO - 'sessionrestore', 05:28:30 INFO - 'sessionrestore_no_auto_restore', 05:28:30 INFO - 'tabpaint']}, 05:28:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:28:30 INFO - 'ts_paint', 05:28:30 INFO - 'tpaint', 05:28:30 INFO - 'sessionrestore', 05:28:30 INFO - 'sessionrestore_no_auto_restore', 05:28:30 INFO - 'tabpaint']}, 05:28:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:28:30 INFO - 'ts_paint', 05:28:30 INFO - 'tpaint', 05:28:30 INFO - 'sessionrestore', 05:28:30 INFO - 'sessionrestore_no_auto_restore', 05:28:30 INFO - 'tabpaint']}, 05:28:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:28:30 INFO - 'tests': ['a11yr', 05:28:30 INFO - 'ts_paint', 05:28:30 INFO - 'tpaint', 05:28:30 INFO - 'sessionrestore', 05:28:30 INFO - 'sessionrestore_no_auto_restore', 05:28:30 INFO - 'tabpaint']}, 05:28:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:28:30 INFO - 'tests': ['a11yr', 05:28:30 INFO - 'ts_paint', 05:28:30 INFO - 'tpaint', 05:28:30 INFO - 'sessionrestore', 05:28:30 INFO - 'sessionrestore_no_auto_restore', 05:28:30 INFO - 'tabpaint']}, 05:28:30 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 05:28:30 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 05:28:30 INFO - 'bloom_basic_ref']}, 05:28:30 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:28:30 INFO - 'talos_options': ['--mitmproxy', 05:28:30 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:28:30 INFO - '--firstNonBlankPaint'], 05:28:30 INFO - 'tests': ['quantum_pageload_google', 05:28:30 INFO - 'quantum_pageload_youtube', 05:28:30 INFO - 'quantum_pageload_amazon', 05:28:30 INFO - 'quantum_pageload_facebook']}, 05:28:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:28:30 INFO - 'tests': ['tsvgx', 05:28:30 INFO - 'tsvgr_opacity', 05:28:30 INFO - 'tart', 05:28:30 INFO - 'tscrollx', 05:28:30 INFO - 'cart', 05:28:30 INFO - 'tsvg_static']}, 05:28:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:28:30 INFO - 'tsvgr_opacity', 05:28:30 INFO - 'tart', 05:28:30 INFO - 'tscrollx', 05:28:30 INFO - 'cart', 05:28:30 INFO - 'tsvg_static']}, 05:28:30 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:28:30 INFO - 'talos_options': ['--disable-e10s'], 05:28:30 INFO - 'tests': ['tp5o']}, 05:28:30 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:28:30 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:28:30 INFO - 'talos_options': ['--disable-e10s', 05:28:30 INFO - '--xperf_path', 05:28:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:28:30 INFO - 'tests': ['tp5n']}, 05:28:30 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:28:30 INFO - 'talos_options': ['--xperf_path', 05:28:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:28:30 INFO - 'tests': ['tp5n']}}, 05:28:30 INFO - 'talos.zip': {'path': '', 05:28:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:28:30 INFO - Running post-action listener: _resource_record_post_action 05:28:30 INFO - [mozharness: 2017-06-12 12:28:30.909997Z] Finished populate-webroot step (success) 05:28:30 INFO - [mozharness: 2017-06-12 12:28:30.910140Z] Running create-virtualenv step. 05:28:30 INFO - Running pre-action listener: _resource_record_pre_action 05:28:30 INFO - Running main action method: create_virtualenv 05:28:30 INFO - Creating virtualenv /builds/slave/test/build/venv 05:28:30 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 05:28:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:28:30 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 05:28:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:28:31 INFO - Using real prefix '/tools/python27' 05:28:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:28:31 INFO - Installing distribute.............................................................................................................................................................................................done. 05:28:34 INFO - Installing pip.................done. 05:28:34 INFO - Return code: 0 05:28:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:28:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:28:34 INFO - Reading from file tmpfile_stdout 05:28:34 INFO - Output received: 05:28:34 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) 05:28:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:28:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:28:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:28:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101bc5c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101bc9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff61ad9a880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10191bd30>, '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 0x1018e5cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10190cbe8>, '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.2uoXBe3KjX/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.0YF1uuoazl/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 05:28:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:28:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:28:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2uoXBe3KjX/Render', 05:28:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:28:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:28:34 INFO - 'HOME': '/Users/cltbld', 05:28:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:28:34 INFO - 'LOGNAME': 'cltbld', 05:28:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:34 INFO - 'MOZ_NO_REMOTE': '1', 05:28:34 INFO - 'NO_EM_RESTART': '1', 05:28:34 INFO - 'PAGER': '/bin/cat', 05:28:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:28:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:34 INFO - 'PWD': '/builds/slave/test', 05:28:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:28:34 INFO - 'SHELL': '/bin/bash', 05:28:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0YF1uuoazl/Listeners', 05:28:34 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 05:28:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:28:34 INFO - 'USER': 'cltbld', 05:28:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:28:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:28:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:34 INFO - 'XPC_FLAGS': '0x0', 05:28:34 INFO - 'XPC_SERVICE_NAME': '0', 05:28:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:28:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:35 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 05:28:35 INFO - Cleaning up... 05:28:35 INFO - Return code: 0 05:28:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:28: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')]} 05:28:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28: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')]} 05:28:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28: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', '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 0x101bc5c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101bc9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff61ad9a880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10191bd30>, '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 0x1018e5cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10190cbe8>, '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.2uoXBe3KjX/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.0YF1uuoazl/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 05:28: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', 'psutil>=3.1.1'] in /builds/slave/test/build 05:28: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 psutil>=3.1.1 05:28:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2uoXBe3KjX/Render', 05:28:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:28:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:28:35 INFO - 'HOME': '/Users/cltbld', 05:28:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:28:35 INFO - 'LOGNAME': 'cltbld', 05:28:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:35 INFO - 'MOZ_NO_REMOTE': '1', 05:28:35 INFO - 'NO_EM_RESTART': '1', 05:28:35 INFO - 'PAGER': '/bin/cat', 05:28:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:28:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:35 INFO - 'PWD': '/builds/slave/test', 05:28:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:28:35 INFO - 'SHELL': '/bin/bash', 05:28:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0YF1uuoazl/Listeners', 05:28:35 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 05:28:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:28:35 INFO - 'USER': 'cltbld', 05:28:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:28:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:28:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:35 INFO - 'XPC_FLAGS': '0x0', 05:28:35 INFO - 'XPC_SERVICE_NAME': '0', 05:28:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:28:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:35 INFO - Downloading/unpacking psutil>=3.1.1 05:28:35 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 05:28:35 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 05:28:35 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 05:28:35 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 05:28:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:28:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:28:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:28:37 INFO - Installing collected packages: psutil 05:28:37 INFO - Running setup.py install for psutil 05:28:37 INFO - building 'psutil._psutil_osx' extension 05:28:37 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 05:28: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_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:28: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/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:28:38 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 05:28:38 INFO - building 'psutil._psutil_posix' extension 05:28:38 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 05:28:38 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:28:38 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:28:38 INFO - ^ 05:28:38 INFO - 1 warning generated. 05:28:38 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 05:28:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:28:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:28:38 INFO - Successfully installed psutil 05:28:38 INFO - Cleaning up... 05:28:38 INFO - Return code: 0 05:28:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:28:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:28:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:28:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101bc5c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101bc9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff61ad9a880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10191bd30>, '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 0x1018e5cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10190cbe8>, '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.2uoXBe3KjX/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.0YF1uuoazl/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 05:28:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:28:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:28:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2uoXBe3KjX/Render', 05:28:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:28:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:28:38 INFO - 'HOME': '/Users/cltbld', 05:28:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:28:38 INFO - 'LOGNAME': 'cltbld', 05:28:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:38 INFO - 'MOZ_NO_REMOTE': '1', 05:28:38 INFO - 'NO_EM_RESTART': '1', 05:28:38 INFO - 'PAGER': '/bin/cat', 05:28:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:28:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:38 INFO - 'PWD': '/builds/slave/test', 05:28:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:28:38 INFO - 'SHELL': '/bin/bash', 05:28:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0YF1uuoazl/Listeners', 05:28:38 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 05:28:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:28:38 INFO - 'USER': 'cltbld', 05:28:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:28:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:28:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:38 INFO - 'XPC_FLAGS': '0x0', 05:28:38 INFO - 'XPC_SERVICE_NAME': '0', 05:28:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:28:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:28:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:28:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:28:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:28:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:28:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:28:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:28:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:28:40 INFO - Installing collected packages: mozsystemmonitor 05:28:40 INFO - Running setup.py install for mozsystemmonitor 05:28:40 INFO - Successfully installed mozsystemmonitor 05:28:40 INFO - Cleaning up... 05:28:40 INFO - Return code: 0 05:28:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:28: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')]} 05:28:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28: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')]} 05:28:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28: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', '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 0x101bc5c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101bc9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff61ad9a880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10191bd30>, '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 0x1018e5cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10190cbe8>, '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.2uoXBe3KjX/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.0YF1uuoazl/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 05:28: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:28: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 jsonschema==2.5.1 05:28:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2uoXBe3KjX/Render', 05:28:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:28:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:28:40 INFO - 'HOME': '/Users/cltbld', 05:28:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:28:40 INFO - 'LOGNAME': 'cltbld', 05:28:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:40 INFO - 'MOZ_NO_REMOTE': '1', 05:28:40 INFO - 'NO_EM_RESTART': '1', 05:28:40 INFO - 'PAGER': '/bin/cat', 05:28:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:28:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:40 INFO - 'PWD': '/builds/slave/test', 05:28:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:28:40 INFO - 'SHELL': '/bin/bash', 05:28:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0YF1uuoazl/Listeners', 05:28:40 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 05:28:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:28:40 INFO - 'USER': 'cltbld', 05:28:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:28:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:28:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:40 INFO - 'XPC_FLAGS': '0x0', 05:28:40 INFO - 'XPC_SERVICE_NAME': '0', 05:28:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:28:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:41 INFO - Downloading/unpacking jsonschema==2.5.1 05:28:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:28:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:28:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:28:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:28:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:28:42 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:28: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 05:28: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 05:28: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 05:28: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 05:28:42 INFO - Downloading functools32-3.2.3-2.tar.gz 05:28:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:28:42 INFO - warning: no files found matching '*.txt' 05:28:42 INFO - no previously-included directories found matching 'build' 05:28:42 INFO - no previously-included directories found matching 'dist' 05:28:42 INFO - no previously-included directories found matching '.git*' 05:28:42 INFO - Installing collected packages: jsonschema, functools32 05:28:42 INFO - Running setup.py install for functools32 05:28:43 INFO - warning: no files found matching '*.txt' 05:28:43 INFO - no previously-included directories found matching 'build' 05:28:43 INFO - no previously-included directories found matching 'dist' 05:28:43 INFO - no previously-included directories found matching '.git*' 05:28:43 INFO - Successfully installed jsonschema functools32 05:28:43 INFO - Cleaning up... 05:28:43 INFO - Return code: 0 05:28:43 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:28:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:28:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:28:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101bc5c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101bc9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff61ad9a880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10191bd30>, '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 0x1018e5cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10190cbe8>, '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.2uoXBe3KjX/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.0YF1uuoazl/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 05:28:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:28:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:28:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2uoXBe3KjX/Render', 05:28:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:28:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:28:43 INFO - 'HOME': '/Users/cltbld', 05:28:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:28:43 INFO - 'LOGNAME': 'cltbld', 05:28:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:43 INFO - 'MOZ_NO_REMOTE': '1', 05:28:43 INFO - 'NO_EM_RESTART': '1', 05:28:43 INFO - 'PAGER': '/bin/cat', 05:28:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:28:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:43 INFO - 'PWD': '/builds/slave/test', 05:28:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:28:43 INFO - 'SHELL': '/bin/bash', 05:28:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0YF1uuoazl/Listeners', 05:28:43 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 05:28:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:28:43 INFO - 'USER': 'cltbld', 05:28:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:28:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:28:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:43 INFO - 'XPC_FLAGS': '0x0', 05:28:43 INFO - 'XPC_SERVICE_NAME': '0', 05:28:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:28:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:43 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:28:43 INFO - Cleaning up... 05:28:43 INFO - Return code: 0 05:28:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:28:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:28:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:28:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101bc5c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101bc9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff61ad9a880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10191bd30>, '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 0x1018e5cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10190cbe8>, '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.2uoXBe3KjX/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.0YF1uuoazl/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 05:28:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:28:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:28:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2uoXBe3KjX/Render', 05:28:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:28:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:28:43 INFO - 'HOME': '/Users/cltbld', 05:28:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:28:43 INFO - 'LOGNAME': 'cltbld', 05:28:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:43 INFO - 'MOZ_NO_REMOTE': '1', 05:28:43 INFO - 'NO_EM_RESTART': '1', 05:28:43 INFO - 'PAGER': '/bin/cat', 05:28:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:28:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:43 INFO - 'PWD': '/builds/slave/test', 05:28:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:28:43 INFO - 'SHELL': '/bin/bash', 05:28:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0YF1uuoazl/Listeners', 05:28:43 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 05:28:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:28:43 INFO - 'USER': 'cltbld', 05:28:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:28:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:28:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:43 INFO - 'XPC_FLAGS': '0x0', 05:28:43 INFO - 'XPC_SERVICE_NAME': '0', 05:28:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:28:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:43 INFO - Downloading/unpacking blobuploader==1.2.4 05:28: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 05:28: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 05:28: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 05:28: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 05:28:45 INFO - Downloading blobuploader-1.2.4.tar.gz 05:28:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:28:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:28:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:28:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:28:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:28:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:28:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:28:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:28:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:28:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:28:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:28:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:28:45 INFO - Downloading docopt-0.6.1.tar.gz 05:28:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:28:46 INFO - Installing collected packages: blobuploader, requests, docopt 05:28:46 INFO - Running setup.py install for blobuploader 05:28:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:28:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:28:46 INFO - Running setup.py install for requests 05:28:46 INFO - Running setup.py install for docopt 05:28:46 INFO - Successfully installed blobuploader requests docopt 05:28:46 INFO - Cleaning up... 05:28:46 INFO - Return code: 0 05:28:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:28:46 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')]} 05:28:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:46 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')]} 05:28:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:46 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 0x101bc5c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101bc9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff61ad9a880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10191bd30>, '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 0x1018e5cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10190cbe8>, '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.2uoXBe3KjX/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.0YF1uuoazl/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 05:28:46 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 05:28:46 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 05:28:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2uoXBe3KjX/Render', 05:28:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:28:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:28:46 INFO - 'HOME': '/Users/cltbld', 05:28:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:28:46 INFO - 'LOGNAME': 'cltbld', 05:28:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:46 INFO - 'MOZ_NO_REMOTE': '1', 05:28:46 INFO - 'NO_EM_RESTART': '1', 05:28:46 INFO - 'PAGER': '/bin/cat', 05:28:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:28:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:46 INFO - 'PWD': '/builds/slave/test', 05:28:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:28:46 INFO - 'SHELL': '/bin/bash', 05:28:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0YF1uuoazl/Listeners', 05:28:46 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 05:28:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:28:46 INFO - 'USER': 'cltbld', 05:28:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:28:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:28:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:46 INFO - 'XPC_FLAGS': '0x0', 05:28:46 INFO - 'XPC_SERVICE_NAME': '0', 05:28:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:28:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:28:47 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-_bSexd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:28:47 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-Jfthpb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:28:47 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-t_seUA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:28:47 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-lpmOaj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:28:47 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-fB3jCA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:28:47 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-EJHTey-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:28:47 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-zM5q6_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:28:47 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-RNKiIc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:28:47 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-SsKI74-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:28:48 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-rRNkUo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:28:48 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-B6yds0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:28:48 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-3lKTTn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:28:48 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-0a77Ro-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:28:48 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-kMROZQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:28:48 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-yCkLSw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:28:48 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-CzCY_p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:28:48 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-Hg9wnx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:28:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:28:48 INFO - Running setup.py install for manifestparser 05:28:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:28:49 INFO - Running setup.py install for mozcrash 05:28:49 INFO - Running setup.py install for mozdebug 05:28:49 INFO - Running setup.py install for mozdevice 05:28:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:28:49 INFO - Running setup.py install for mozfile 05:28:49 INFO - Running setup.py install for mozhttpd 05:28:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:28:49 INFO - Running setup.py install for mozinfo 05:28:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:28:49 INFO - Running setup.py install for mozInstall 05:28:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:28:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:28:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:28:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:28:50 INFO - Running setup.py install for mozleak 05:28:50 INFO - Running setup.py install for mozlog 05:28:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:28:50 INFO - Running setup.py install for moznetwork 05:28:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:28:50 INFO - Running setup.py install for mozprocess 05:28:50 INFO - Running setup.py install for mozprofile 05:28:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:28:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:28:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:28:50 INFO - Running setup.py install for mozrunner 05:28:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:28:51 INFO - Running setup.py install for mozscreenshot 05:28:51 INFO - Running setup.py install for moztest 05:28:51 INFO - Running setup.py install for mozversion 05:28:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:28:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:28:51 INFO - Cleaning up... 05:28:51 INFO - Return code: 0 05:28:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:28:51 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')]} 05:28:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:51 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')]} 05:28:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:51 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 0x101bc5c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101bc9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff61ad9a880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10191bd30>, '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 0x1018e5cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10190cbe8>, '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.2uoXBe3KjX/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.0YF1uuoazl/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 05:28:51 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 05:28:51 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 05:28:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2uoXBe3KjX/Render', 05:28:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:28:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:28:51 INFO - 'HOME': '/Users/cltbld', 05:28:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:28:51 INFO - 'LOGNAME': 'cltbld', 05:28:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:51 INFO - 'MOZ_NO_REMOTE': '1', 05:28:51 INFO - 'NO_EM_RESTART': '1', 05:28:51 INFO - 'PAGER': '/bin/cat', 05:28:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:28:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:51 INFO - 'PWD': '/builds/slave/test', 05:28:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:28:51 INFO - 'SHELL': '/bin/bash', 05:28:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0YF1uuoazl/Listeners', 05:28:51 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 05:28:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:28:51 INFO - 'USER': 'cltbld', 05:28:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:28:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:28:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:51 INFO - 'XPC_FLAGS': '0x0', 05:28:51 INFO - 'XPC_SERVICE_NAME': '0', 05:28:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:28:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:28:51 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-_OSgco-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:28:51 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)) 05:28:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:28:51 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-Jps8mq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:28:51 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)) 05:28:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:28:51 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-fr0wLN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:28:52 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)) 05:28:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:28:52 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-SuKGr7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:28:52 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)) 05:28:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:28:52 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-UIzkEr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:28:52 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)) 05:28:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:28:52 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-apCpnE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:28:52 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)) 05:28:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:28:52 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-x5__Sc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:28:52 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)) 05:28:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:28:52 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-ui4aWh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:28:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 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)) 05:28:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:28:52 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-Fy3ldN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:28:52 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)) 05:28:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:28:52 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-iwsiZp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:28:52 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)) 05:28:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:28:52 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-z0ZY5H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:28:52 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)) 05:28:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:28:52 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-Gves1a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:28:53 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)) 05:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:28:53 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-1bcmLF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:28:53 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)) 05:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:28:53 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-f3Mtm_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:28:53 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)) 05:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:28:53 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-olnHE1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:28:53 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)) 05:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:28:53 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-V36Ltn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:28:53 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)) 05:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:28:53 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-D2JUYn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:28:53 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)) 05:28:53 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)) 05:28:53 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)) 05:28:53 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)) 05:28:53 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)) 05:28:53 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)) 05:28:53 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:28:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:28:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:28:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:28:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:28:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:28:54 INFO - Downloading blessings-1.6.tar.gz 05:28:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:28:55 INFO - Installing collected packages: blessings 05:28:55 INFO - Running setup.py install for blessings 05:28:55 INFO - Successfully installed blessings 05:28:55 INFO - Cleaning up... 05:28:55 INFO - Return code: 0 05:28:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:28:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:28:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:28:55 INFO - Reading from file tmpfile_stdout 05:28:55 INFO - Current package versions: 05:28:55 INFO - blessings == 1.6 05:28:55 INFO - blobuploader == 1.2.4 05:28:55 INFO - docopt == 0.6.1 05:28:55 INFO - functools32 == 3.2.3-2 05:28:55 INFO - jsonschema == 2.5.1 05:28:55 INFO - manifestparser == 1.1 05:28:55 INFO - mozInstall == 1.13 05:28:55 INFO - mozcrash == 1.0 05:28:55 INFO - mozdebug == 0.1 05:28:55 INFO - mozdevice == 0.51 05:28:55 INFO - mozfile == 1.2 05:28:55 INFO - mozhttpd == 0.7 05:28:55 INFO - mozinfo == 0.10 05:28:55 INFO - mozleak == 0.1 05:28:55 INFO - mozlog == 3.4 05:28:55 INFO - moznetwork == 0.27 05:28:55 INFO - mozprocess == 0.25 05:28:55 INFO - mozprofile == 0.28 05:28:55 INFO - mozrunner == 6.13 05:28:55 INFO - mozscreenshot == 0.1 05:28:55 INFO - mozsystemmonitor == 0.3 05:28:55 INFO - moztest == 0.8 05:28:55 INFO - mozversion == 1.4 05:28:55 INFO - psutil == 3.1.1 05:28:55 INFO - requests == 1.2.3 05:28:55 INFO - wsgiref == 0.1.2 05:28:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:28:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:28:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:28:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:55 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 0x101bc5c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101bc9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff61ad9a880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10191bd30>, '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 0x1018e5cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10190cbe8>, '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.2uoXBe3KjX/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.0YF1uuoazl/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 05:28:55 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 05:28:55 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 05:28:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2uoXBe3KjX/Render', 05:28:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:28:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:28:55 INFO - 'HOME': '/Users/cltbld', 05:28:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:28:55 INFO - 'LOGNAME': 'cltbld', 05:28:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:55 INFO - 'MOZ_NO_REMOTE': '1', 05:28:55 INFO - 'NO_EM_RESTART': '1', 05:28:55 INFO - 'PAGER': '/bin/cat', 05:28:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:28:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:55 INFO - 'PWD': '/builds/slave/test', 05:28:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:28:55 INFO - 'SHELL': '/bin/bash', 05:28:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0YF1uuoazl/Listeners', 05:28:55 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 05:28:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:28:55 INFO - 'USER': 'cltbld', 05:28:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:28:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:28:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:55 INFO - 'XPC_FLAGS': '0x0', 05:28:55 INFO - 'XPC_SERVICE_NAME': '0', 05:28:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:28:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:55 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)) 05:28:55 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)) 05:28:55 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)) 05:28:55 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)) 05:28:55 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)) 05:28:55 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)) 05:28:55 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)) 05:28:55 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)) 05:28:55 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)) 05:28:55 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 05:28:55 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 05:28:55 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 05:28:55 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 05:28:55 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 05:28:57 INFO - Downloading marionette_driver-1.4.0.tar.gz 05:28:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 05:28:57 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)) 05:28:57 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)) 05:28:57 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)) 05:28:57 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)) 05:28:57 INFO - Installing collected packages: marionette-driver 05:28:57 INFO - Running setup.py install for marionette-driver 05:28:57 INFO - Successfully installed marionette-driver 05:28:57 INFO - Cleaning up... 05:28:57 INFO - Return code: 0 05:28:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:28:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:28:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:28:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:57 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 0x101bc5c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101bc9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff61ad9a880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10191bd30>, '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 0x1018e5cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10190cbe8>, '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.2uoXBe3KjX/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.0YF1uuoazl/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 05:28:57 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 05:28:57 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 05:28:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2uoXBe3KjX/Render', 05:28:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:28:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:28:57 INFO - 'HOME': '/Users/cltbld', 05:28:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:28:57 INFO - 'LOGNAME': 'cltbld', 05:28:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:57 INFO - 'MOZ_NO_REMOTE': '1', 05:28:57 INFO - 'NO_EM_RESTART': '1', 05:28:57 INFO - 'PAGER': '/bin/cat', 05:28:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:28:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:57 INFO - 'PWD': '/builds/slave/test', 05:28:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:28:57 INFO - 'SHELL': '/bin/bash', 05:28:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0YF1uuoazl/Listeners', 05:28:57 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 05:28:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:28:57 INFO - 'USER': 'cltbld', 05:28:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:28:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:28:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:57 INFO - 'XPC_FLAGS': '0x0', 05:28:57 INFO - 'XPC_SERVICE_NAME': '0', 05:28:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:28:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:28:57 INFO - Cleaning up... 05:28:57 INFO - Return code: 0 05:28:57 INFO - Running post-action listener: _resource_record_post_action 05:28:57 INFO - Running post-action listener: _start_resource_monitoring 05:28:57 INFO - Starting resource monitoring. 05:28:57 INFO - [mozharness: 2017-06-12 12:28:57.917809Z] Finished create-virtualenv step (success) 05:28:57 INFO - [mozharness: 2017-06-12 12:28:57.918622Z] Running install step. 05:28:57 INFO - Running pre-action listener: _resource_record_pre_action 05:28:57 INFO - Running main action method: install 05:28:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:28:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:28:58 INFO - Reading from file tmpfile_stdout 05:28:58 INFO - Detecting whether we're running mozinstall >=1.0... 05:28:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:28:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:28:58 INFO - Reading from file tmpfile_stdout 05:28:58 INFO - Output received: 05:28:58 INFO - Usage: mozinstall [options] installer 05:28:58 INFO - Options: 05:28:58 INFO - -h, --help show this help message and exit 05:28:58 INFO - -d DEST, --destination=DEST 05:28:58 INFO - Directory to install application into. [default: 05:28:58 INFO - "/builds/slave/test"] 05:28:58 INFO - --app=APP Application being installed. [default: firefox] 05:28:58 INFO - mkdir: /builds/slave/test/build/application 05:28:58 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'] 05:28:58 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 05:29:20 INFO - Reading from file tmpfile_stdout 05:29:20 INFO - Output received: 05:29:20 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:29:20 INFO - Running post-action listener: _resource_record_post_action 05:29:20 INFO - [mozharness: 2017-06-12 12:29:20.029084Z] Finished install step (success) 05:29:20 INFO - [mozharness: 2017-06-12 12:29:20.029245Z] Running setup-mitmproxy step. 05:29:20 INFO - Running pre-action listener: _resource_record_pre_action 05:29:20 INFO - Running main action method: setup_mitmproxy 05:29:20 INFO - Skipping: mitmproxy is not required 05:29:20 INFO - Running post-action listener: _resource_record_post_action 05:29:20 INFO - [mozharness: 2017-06-12 12:29:20.030014Z] Finished setup-mitmproxy step (success) 05:29:20 INFO - [mozharness: 2017-06-12 12:29:20.030159Z] Running run-tests step. 05:29:20 INFO - Running pre-action listener: _resource_record_pre_action 05:29:20 INFO - Running main action method: run_tests 05:29:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:29:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:29:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:29:20 INFO - 2017-06-12 05:29:20.044 screenresolution[1633:8554] starting screenresolution argv=screenresolution get 05:29:20 INFO - 2017-06-12 05:29:20.047 screenresolution[1633:8554] Display 0: 1600x1200x32@60 05:29:20 INFO - 2017-06-12 05:29:20.053 screenresolution[1634:8557] starting screenresolution argv=screenresolution list 05:29:20 INFO - Available Modes on Display 0 05:29:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:29:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:29:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:29:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:29:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:29:20 INFO - Intel Iris: 05:29:20 INFO - Chipset Model: Intel Iris 05:29:20 INFO - Type: GPU 05:29:20 INFO - Bus: Built-In 05:29:20 INFO - VRAM (Dynamic, Max): 1536 MB 05:29:20 INFO - Vendor: Intel (0x8086) 05:29:20 INFO - Device ID: 0x0a2e 05:29:20 INFO - Revision ID: 0x0009 05:29:20 INFO - Displays: 05:29:20 INFO - Display: 05:29:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:29:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:29:20 INFO - Display Serial Number: 1600x1200 60 05:29:20 INFO - Main Display: Yes 05:29:20 INFO - Mirror: Off 05:29:20 INFO - Online: Yes 05:29:20 INFO - Rotation: Supported 05:29:20 INFO - Return code: 0 05:29:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:29:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:29:20 INFO - Python 2.7.3 05:29:20 INFO - Return code: 0 05:29:20 INFO - grabbing minidump binary from tooltool 05:29:20 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')]} 05:29:20 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 0x10191bd30>, '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 0x1018e5cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10190cbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:29:20 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 05:29:20 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 05:29:20 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 05:29:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:29:20 INFO - Return code: 0 05:29:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:29:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:29:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:29:20 INFO - ENV: RUST_BACKTRACE is now 1 05:29:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:29:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:29:20 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/K-k7BBcFRvmbJubPOj5Mzg/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 05:29:20 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/K-k7BBcFRvmbJubPOj5Mzg/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 05:29:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2uoXBe3KjX/Render', 05:29:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:29:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:29:20 INFO - 'HOME': '/Users/cltbld', 05:29:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:29:20 INFO - 'LOGNAME': 'cltbld', 05:29:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:29:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:29:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:20 INFO - 'MOZ_NO_REMOTE': '1', 05:29:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:29:20 INFO - 'NO_EM_RESTART': '1', 05:29:20 INFO - 'PAGER': '/bin/cat', 05:29:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:29:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:29:20 INFO - 'PWD': '/builds/slave/test', 05:29:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:29:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:29:20 INFO - 'RUST_BACKTRACE': '1', 05:29:20 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 05:29:20 INFO - 'SHELL': '/bin/bash', 05:29:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0YF1uuoazl/Listeners', 05:29:20 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 05:29:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:29:20 INFO - 'USER': 'cltbld', 05:29:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:29:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:29:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:20 INFO - 'XPC_FLAGS': '0x0', 05:29:20 INFO - 'XPC_SERVICE_NAME': '0', 05:29:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:29:20 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/K-k7BBcFRvmbJubPOj5Mzg/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 05:29:20 INFO - mozversion application_buildid: 20170612035250 05:29:20 INFO - mozversion application_changeset: 8c7211cc49637cec922b1d4b141a44c7007f9bb6 05:29:20 INFO - mozversion application_display_name: Nightly 05:29:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:29:20 INFO - mozversion application_name: Firefox 05:29:20 INFO - mozversion application_remotingname: firefox 05:29:20 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:29:20 INFO - mozversion application_vendor: Mozilla 05:29:20 INFO - mozversion application_version: 55.0a1 05:29:20 INFO - mozversion platform_buildid: 20170612035250 05:29:20 INFO - mozversion platform_changeset: 8c7211cc49637cec922b1d4b141a44c7007f9bb6 05:29:20 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:29:20 INFO - mozversion platform_version: 55.0a1 05:29:20 INFO - using testdate: 1497270560 05:29:20 INFO - actual date: 1497270560 05:29:20 INFO - starting webserver on 'localhost:49229' 05:29:20 INFO - SUITE-START | Running 2 tests 05:29:20 INFO - TEST-START | dromaeo_css 05:29:20 INFO - operating with platform_type : mac_ 05:29:20 INFO - Initialising browser for dromaeo_css test... 05:29:20 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground -profile /var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/tmpBmBT7c/profile 05:29:20 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground) 05:29:54 INFO - TEST-INFO | 1644: killed by SIGTERM 05:29:54 INFO - Browser initialized. 05:29:54 INFO - Running cycle 1/1 for dromaeo_css test... 05:29:54 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/tmpBmBT7c/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:29:56 INFO - PID 1652 | 2017-06-12 05:29:56.557 plugin-container[1653:9063] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 05:29:56 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:29:56 INFO - PID 1652 | 2017-06-12 05:29:56.558 plugin-container[1653:9063] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 05:29:56 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:29:56 INFO - PID 1652 | 2017-06-12 05:29:56.558 plugin-container[1653:9063] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:29:57 INFO - PID 1652 | 2017-06-12 05:29:57.338 plugin-container[1654:9111] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464b, name = 'com.apple.tsm.portname' 05:29:57 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:29:57 INFO - PID 1652 | 2017-06-12 05:29:57.339 plugin-container[1654:9111] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0b, name = 'com.apple.CFPasteboardClient' 05:29:57 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:29:57 INFO - PID 1652 | 2017-06-12 05:29:57.339 plugin-container[1654:9111] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:30:06 INFO - PID 1652 | RSS: Main: 252747776 05:30:06 INFO - PID 1652 | 05:33:10 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49229/tests/dromaeo/cssquery-ext.html) 05:33:11 INFO - PID 1652 | RSS: Main: 269508608 05:33:11 INFO - PID 1652 | 05:36:13 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-ext.html (next: http://localhost:49229/tests/dromaeo/cssquery-jquery.html) 05:36:14 INFO - PID 1652 | RSS: Main: 281325568 05:36:14 INFO - PID 1652 | 05:39:15 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49229/tests/dromaeo/cssquery-mootools.html) 05:39:16 INFO - PID 1652 | RSS: Main: 280309760 05:39:16 INFO - PID 1652 | 05:42:18 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49229/tests/dromaeo/cssquery-prototype.html) 05:42:18 INFO - PID 1652 | RSS: Main: 287956992 05:42:18 INFO - PID 1652 | 05:45:21 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49229/tests/dromaeo/cssquery-yui.html) 05:45:22 INFO - PID 1652 | RSS: Main: 285929472 05:45:22 INFO - PID 1652 | 05:48:24 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-yui.html (next: http://localhost:49229/tests/dromaeo/cssquery-dojo.html) 05:48:24 INFO - PID 1652 | RSS: Main: 284971008 05:48:24 INFO - PID 1652 | 05:48:24 INFO - PID 1652 | __start_tp_report 05:48:24 INFO - PID 1652 | _x_x_mozilla_page_load 05:48:24 INFO - PID 1652 | _x_x_mozilla_page_load_details 05:48:24 INFO - PID 1652 | |i|pagename|runs| 05:48:24 INFO - PID 1652 | |0;dojo.html;3046.5887105240786;3513.069036705272;3515.4902539131813;3521.1197200699826;3632.164602141509;6166.9999999999945;6231.50147988161;6265.404786545282;6289;6301.873962520758;6244.594101383407;6248.406401391871;6310.085037669547;6327.335629758866;6331.715072821723;5433.809816656418;5455.563554915617;5456.863578410552;5520.558355331584;5546.029444847156;5480.479354461319;5489.725513724298;5491.341039075317;5498.3127109111365;5556.86107847303;5305.761240744157;5401.459854014606;5456.890862182774;5461.15352120418;5483.75323110461;5431.646942948735;5436.945630543683;5492.642978206404;5519.834404967857;5520.806771763009;5424.186372044225;5442.210879422501;5475.890482190373;5489.204065410493;5498.3127109111365;6275.435210831001;6276;6311.495080393557;6314;6331.176946996861;6457.741690332381;6515.967420162916;6539.607623542603;6631;6725.293844146343;6727.764528241537;6786.423154031913;6787.694553745044;6827.761028363983;6830.214525329601;6291.748330066837;6345.143405640272;6351.96824015877;6359.586626869239;6392.296847346788;6720.294369091175;6920.446364290845;6935.445164386837;6939.410150137295;6946.826329341807;6437.678116094176;6458.870822583522;6466.094746735461;6466.741330346827;6475.4819614430735;6587.308332625101;6590.044443555657;6658.1344425224415;6740.29226931175;6745.426638735762;6562.606243625302;6690.431313338323;6700.530962832555;6705.865882682319;6757.763478278237;6690.297518760557;6704.597724136567;6715.966420167965;6716.932830671631;6723.462123030145;5354.866128346823;5362.276092727432;5367.75845086972;5376.220448035013;5401.459854014645;4612.285095810154;4692.343071969927;4709.7174169549935;4759.50025247351;4805.879853003703;5397.839464515148;6139.43080903429;6283.968580157161;6308.116863639094;6330.208723909512;6611.801645950629;6627.966860165669;6646.9032609619835;6668.166479190101;6686.598804071674;5462.918056229159;6142.170806941006;6301.779437719658;6311.715972781236;6337.841553961188;5921.259842519685;5964.940350596438;5970.253718285215;5989.610675306179;6033.5173186145;6816.250212476524;6857.657117144223;6916.861662766616;6930.861382772317;6936.93063069373;6551.115599394211;6645.833854153685;6818.454523638196;6823.249442561413;6835.350641689031;5824.359320474659;6544.083828264142;6766.323367663194;6790.72837086511;6801.251862295045;6198.81403557894;6659.933400665931;6704.4636429084585;6712.530122891546;6754.662266886734;6804.047433359433;6825.590464572141;6834.8633027340165;6835.89746153818;6855;6053.606515576474;6379.106925030592;6567.343265673395;6567.9014814778775;6575.704093315908;6385.77649782274;6435.420812126931;6444.4844412445955;6510.283868774336;6528.281888992302;5149.356330458692;5487.533559647315;6215.689215539295;6383;6391.424771770563;6481.287058423664;6489.1888513935755;6499.122618446449;6499.4800415965665;6521;6352.777652782131;6369.681515924278;6372.267189273108;6373.490120790419;6376.968115159394 05:48:24 INFO - PID 1652 | |1;ext.html;786.7797016835289;808.3856269235381;811.853866304065;836.7155167243136;852.9616167272476;36169.63830361696;36394;36545;36572;36641.63358366415;36217.73237936673;36281.637183628154;36608;36628;36642.45036324457;7265.673044712988;7345.669444874967;7375.446841486883;7386.187519372892;7403.148637906629;7258.673359698826;7810.531368117931;7956.2441568050945;8053.59732013397;8055.51666899985;8046.436749427542;8072.354211663082;8117.431779775418;8136.186381361846;8139.226773456512;7005.369516743466;8018.278354948025;8047.356211503065;8069.959650201741;8098.838023239502;6649.700763465607;7964.601769911539;7980.4413691041655;7999.920000800034;8002.799930001738;33620.663793362;33682.831585841916;33692.6630733692;33693.6630633693;33787.83106084479;3489.6335884732243;3495.24852156785;3498.9825050874583;3530.9117272068147;3542.3977923753023;28641.28396790076;28656;28715.856420717973;28739.425211495654;28781.712182878113;2961.8667159977854;2975.1966968918487;3003.3843062172423;3008.638963324393;3029.59100521429;233071;249397;250139;310881;314552;35934.1016474585;37007.814960925025;45009;45305.09389812185;45321.54678453173;39572.80213598971;48068;48549.51450485521;48699.75650121798;48916.7554162234;13473.86526134726;13494.595162145151;13592.66018349549;13621.931890340486;13767.724645507034;11092.556297748;11099.44502774858;11117.27737697047;11122.666320010412;11157.66527004191;12989.935050324877;13074.215547067206;13085;13091.214527128403;13175.14361566495;5071.87320316995;5502.972485137629;5599.768051028767;5696.316442026983;5829.766809327664;920.7836158502746;928.215657769184;931.7577429868284;975.4586204656483;988.367444835306;22201.333959981228;22353.776462235495;22528.32415027552;23218.767812322;23285.835708214523;922.9215516681023;1027.912627426677;1062.7396287909512;1071.6731396924013;1104.7679987202778;11197.841187347089;11461.025812805838;11530.250533715449;11804.586839460577;11853.348065856288;326137;332347;332462;334016;334397;48373.03253934901;48462.5153748465;48576;48634;48912;1398.2030242761732;1416.4971435140583;1421.665908511499;1437.4681367893945;1440.517426662059;21808.018639160957;22208.333749987527;22632.547349053257;22661.093556258224;22709;35908.102297442776;36291.45562816613;36386;36431.45352819763;36458.45312320264;196806;197909;199140;199362;199473.0026349859;25432.364190895376;25953.610695839954;25975.48049038971;31759.84120079385;32172.034838954867;31688.841555792074;31811.68188318087;32758.34483310368;37714.811425942695;38187.80906095452;26339.604905926808;27380.317092997968;27876.72123278741;28881.56677649879;29090.7090929088;27689.08739810456;28444.85777571099;28794.28014299659;29970.70029299679;30379;32486.51270230901;35308.29383412266;35696.28607427889;36048.91853244407;36810.8159459201;28004.719952800213;28397;30570.847145764128;30883.69116308898;30902.227444313172;23160.420989474726;23894.522109557365;24148.222954360474;24410;24519.63220551729 05:48:24 INFO - PID 1652 | |2;jquery.html;478.3709422109925;492.38451935081173;499.49301459019125;504.03728877843315;517.8032347707871;21861.125554977803;21880.671789923123;22519.09923603058;22829.08683652652;22889.19887803927;19158.616827663438;23764.405889852762;24389;25042.248732538006;25073.623895641533;6339.683015849212;6488.837779055514;6493.318201588812;6505.707243174045;6513.153290072308;6418.326075762047;6431.356864313578;6484.351564843525;6491.188601424822;6496.707648155821;6225.972714502102;6328.525360597951;6341.651209183493;6361.300256971729;6395.20059992501;5309;6252.218472690914;6275.435210831024;6322.272938612073;6324.683765811691;5768.221290125821;5777.306723193202;5778.797742079049;5780.364159942403;5782.595218334718;29491.70508294911;29849.25376865574;30325.24186895323;30346;30371.544426833614;2164.588728141648;2214.7010153629217;2215.28003398896;2216.9889150554145;2224.277109939276;18726.97001664908;18849;18997.62004759911;19045;19085.236590536362;2156.5686862627535;2163.12393480639;2191.7589065202815;2205.547862688145;2245.5733410652088;152013;171696;182041;189758;191228.0877191238;13883.097598656055;14120.85879141216;15216.467423640119;15307.46423875159;15417.768733468783;20350.389488315373;20369.49076273105;20396.184152633727;20426.285080022128;20438.386848394573;9547.2839537035;9628.037196280316;9870.60517579303;10090.09189172978;10380.948095259475;9078.092190781;9089.181973622406;9100.317476189311;9143.679971201109;9163.954180229057;7838.725644602411;9083.273338132933;9122.86315705265;9134.543272836332;9160.267178625694;2373.8842743910336;2422.0675040109686;2623.790096792252;2628.579427291624;2744.40995186036;961.3078583419926;973.7176218896583;976.829054915387;1001.5861773126729;1002.9210324304315;21103.261385851423;21206.575868482545;21248.575028499345;21602.675959860615;21772.782272177075;977.1645243317004;982.9505481072044;1001.4742260313249;1006.436395618456;1036.2073014144187;10879.12966962641;10899.67300980956;10918.399488028075;11159.609413670483;11295;544327;555456;573913;576038;576783;136392;136489.27021459516;137345;139146.304268478;154121.22939385232;1149.8792626774236;1188.827619995113;1223.687959570304;1224.406163010939;1224.6509744722712;22037.33887983363;22072.668909966043;23255.953482093686;23290.184843530395;23472.765272347056;70704;73351.63324183345;74053.62973185099;74123.62938185275;74243.88634170599;94176;95874.52062739641;97954.51022744841;99302;99761.50119249357;11148.163887708291;11409.771804563696;11924.880751192377;11929.985951194245;11939.044876409693;18333.908330458264;18513.44459666212;18674.626507470046;18781.96699181558;18914.337998170548;14466.349014294172;15015.399384024517;15058.698826023638;15280.770788438405;15336.079835209923;9994.800103998024;12337.827906348883;13010.609681709564;13189.538366157138;13576.049676522549;14745.92627036858;15794.7630785536;15941.282642281358;16136.515904522883;16186.838131619004;11905.761884762429;11966;12035.819462707892;12078.456469458719;12086.516539338329;14123.435062597791;15185.16481593523;15210.695786083996;15227.619309517351;15345.232738362814 05:48:24 INFO - PID 1652 | |3;mootools.html;1342.9059965802392;1403.550863723609;1410.1034109123239;1418.9716205675886;1420.3679362683604;13041.869581304185;13612.659683507918;13613;13619.659508512279;13638.18170909745;12683.492660293578;13135.474581016748;13137.014723895722;13144.737105257913;13190.60428187156;2674.2111077232216;2720;2739.58906164076;2739.6986331503617;2740.9588856167175;2671.639328690631;2705.296622878056;2711.715269896662;2716.660417447819;2726.209399274218;2652;2702.8513431761244;2706.9187924362204;2708.377073273148;2709.444563864403;2184.5194057307367;2311.844077961028;2542.466082122738;2672.385351369186;2677.143314139476;2545.2618740565213;2554.591265397546;2568.8972441102333;2575.652286941258;2622.344413896526;10699.251052426333;12987.41556629954;13088.280144592043;13106.475740970349;13111.934440327832;7225.89161162583;7477.102747670315;7483.588402637853;7506.699732010713;7512.32389084985;4506.391637128979;4668.43978722552;4670.462896766848;4757.833475828364;4764.499727528594;1116.8771435142128;1375.5391943698874;1378.9517366892212;1384.5707830572555;1402.1306789790294;18445.63108737831;18907.716384254247;19367.322143725043;19398.80601193984;19477.02614869265;5473.863153421196;5985.521158307325;6142.324344322121;6384.904226436607;6536.869262614721;13637.795433068512;13859.307034648227;13870;13874.791878121836;13877.791833122512;6262.373762623702;7290.927090729025;7333.1566869809585;7363.521371110861;7424.480286379901;6688.097106890605;6782.898256526156;6785.626790526569;6801.65991700413;6854.937484689881;4968.850934471971;5060.696358218519;5070.594352451869;5120.4111527174855;5144.459831717691;1404.8735613794809;1441.509886638527;1465.2453986197118;1465.7654775236124;1476.1364601707905;801.4229754576695;814.6293436486385;822.7946059023559;824.2169938558394;840.6217202258886;6939.548929319578;7133.92866071343;7172.784816455514;7184.281571842774;7220.097487814023;652.8955367141234;675.1459403854128;702.610051421466;707.929207079288;711.5161690050816;4024.577419370924;4301.526832048472;4895.534924182197;4937.0372777308085;5034.697918124924;17423.73864392035;18324.267029318944;18366;18374;18379.72430413545;17910.462686119437;18104.728429073573;18144.818551814576;18186.18162182705;18218.180181891847;6949.861002779916;8727.607257673293;9167.312451566158;9301.34890557668;9439.52802359893;5762.423757624204;7563.357114645317;7576.62116894142;7904.328132108835;7911.802204944923;14613.634659133606;15447.2276386178;15684;16104.03375797409;16204.756928645844;15743.606409839846;16149.273282702072;16184.867059305736;16362;16621.252043657823;10587;10619;10747.731306717395;10787.029167374974;10799.460026998775;12010.819837702267;12036.398180090786;12096.939515302367;12103.697407564881;12206.267623942593;9418;9648.131668149901;9711.174550163316;9733.756656083371;9971.900280997097;9369;9430.858537122085;9790.216782657228;9853.359531630422;9990.500474976077;8926.776830579287;9039.638414463348;9109.635614575343;9115.453072815651;9118.498482583522;6473.967630161819;6541.378569035934;6621.503387246072;6644.468442524683;6894.413974812197;6812.65936703153;6939.028536004862;6977.371766681399;7040.964795175992;7123.536970096927 05:48:24 INFO - PID 1652 | |4;prototype.html;440.3636744903616;457.5973143633601;459.6116281741928;467.4997752404928;479.60911856836674;6078.300995385532;6377.553571250014;6420.293767685551;6479.481641468683;6489.578177418476;6011.669358185298;6123.448889599935;6201.643375517308;6212.974859148247;6226.283977342607;4649.953500465002;4831.855044348676;4881.560659540641;4898.926516102261;4934.925976110361;4348.369486424466;4752.382190315254;4873.269009648546;4923.163062279421;4932.901341973158;4028.81870315835;4895.0454661341055;4906.926396104043;4908.582770464497;4921.680090794105;4147;4361.302191649353;4817.51824817519;4834.033193361307;4945.35710357653;4007.479027726406;4260.382244574519;4347.239233134189;4429.379886815848;4450.35469856869;5307.3896501902;6133.815985520441;6139.201903752528;6141.109539116847;6144.910730351702;5854.151148083504;5892.793752218695;5907.232059832238;5914;5937.821865344046;6203.007518797016;6773.153355830521;6786.8303292417595;6809.557378770364;6836.38472537474;4227.217964676544;4630.652701047435;4986.252062190664;5093.5415812576675;5127.3847138343635;6248.843778905518;6988.965055174743;7006.789796306119;7032.367086962198;7157.284271572801;4340.153670034313;4596.770161491912;4819.807207711722;5281.287026251483;5288.444713305047;4441.888952776142;5378.1663842104535;5393.6224464287125;5421.918671219935;5425.9186112208345;4524.5701658342405;5489.7922457059385;5888.911666325009;5899;5911.822645320647;6803.2856550062525;6825.180978282638;6826.733291692692;6840.760573380007;6887.276835932255;1440.9279536023157;1595.2741502616282;1822.489702883195;1836.6143109946793;1843.1889968413855;963.3978763272954;1124.7806677697934;1137.5222406589253;1146.4955419615342;1148.1618418554335;4846.61227101831;5282.735863206901;5776.422357764274;6247.969085100907;6331.335209802996;6112.816615501453;6692;6882.7591034313555;6884.965575172092;6947.270536593584;5351.464853514617;6337.239531256187;6350.237971443456;6356.269029061717;6381.160383629333;3327.9500807487907;3607.85568577254;3666.560012798428;3671.6695497405362;3677.595464498903;5885.499732522783;7130.786076417716;7245.094363204599;7249.202587715347;7269.709211631582;6826.726930922708;7181.271116975516;7191.496595238283;7194.532355396883;7228.975861776015;7132.215456299803;7133.643317834191;7145.961662395268;7147.714091436388;7152.249013853574;6384.361563843764;7009.492959013836;7037.049998250171;7037.109209099626;7040.190378106378;5616.66300022;6247;6561.489603062339;6852.862942741217;6862.764702353625;7097.9290207097265;7116.857662846611;7133.785986420416;7168.247334029852;7184.3000854558895;3836.8081595919534;4029.899252518593;4136.482939632546;4806.519348065165;4817.723303324692;6043.576949613484;6157.32269450369;6868.484863635147;7011.684474198775;7078.433725302067;6102.694865256808;6671.199456065109;6690.83272918181;6718.294579069127;6740.966295168493;6068.848278792889;6112.327643959072;6674.966625166843;6695.163104611924;6722.495812813961;5710.914336285042;6595;6599.406053455212;6602.801915942529;6612.8346791330605;6265.026516647242;6688.163979502562;6709.966450167718;6723.159605049369;6727.966360168168;5984.670843103497;6516.120323756422;6525.477961763143;6588.670566471753;6589.4728421725185 05:48:24 INFO - PID 1652 | |5;yui.html;827.3381294964029;832.7335252719132;843.2326582809644;865.0614083719164;865.9437136586122;16965.15174241289;17193.65612687747;17238.138093095342;17286.654266914655;17531.94808311502;11987.741287164852;12300.876991230085;13571.185728856275;13588.728225435485;13998.860011399884;988.0909563201835;1000.8848982367011;1013.5489707080352;1018.4296793795461;1019.1591936652254;989.6338354808731;993.8707967964154;1004.6433516101788;1009.2481101579334;1010.8028934357803;852.6333676519095;1046.074224311486;1046.7697106636529;1055.5091882274742;1058.343826827368;930.6417029443679;963.3545524498603;1029.130384824822;1031.4945676618404;1044.3994703045742;752.6199269368933;770.2374649097353;771.2364758888716;772.926571975667;776.4526009163583;16943.57641058971;17368.392106276347;17567.385141520113;17580.824191758176;17592.824071759245;17580.824191758045;18198.72515935508;18296.810707303983;18342.63314733698;18399.264029438807;14176.29118544079;14750.70498590033;14763.114213147139;14945.02857314271;14963.476278330207;3649.379605467077;3657.414813629833;3662.505561749156;3680.6319368062977;3682.4844521766972;92737;112514;128724.3563782175;162266;164039.17980410022;13887.44450222;14072.507462238773;14195.148291102567;14227.35976881043;14238.928805355907;26546.867265663546;26801;27151.592726109124;27515.31211719683;27517.174484765488;7963.1638677939145;7998.520088794691;8028.438009339407;8035.156308587514;8037.437379383504;3915.8629447969756;3994.5406278277787;3999.9000024999027;4003.919921601552;4006.238814625212;4661.696989695727;4682.1103990241745;4686.929696054562;4709.058188362273;4751.453582838018;901.4501154295874;907.4011152639226;922.4050487435701;943.5753910740194;957.0286159548065;594.8126340202908;642.7204166187723;653.3009679642737;673.9932600674028;680.792358330704;9783.315167938175;12264.38678066093;12340.629781106582;12619.179753316004;12823.551175708806;628.2241431831684;640.5740182778493;650.5381179362611;674.3863084592997;681.3152781454705;5073.315102461121;5486.149646804752;6033.396660333931;6096;6110.480609148183;122349;127905.3604731989;128020;129406;129720;102097.97902020885;102485;109227;110039.44980275207;110772.44613777041;81061;90181.54909225412;92386;94431.0556894436;100314.4984275074;11667.416629168338;11975.401229938294;12005.63983080509;12040;12473.625791226277;39194.02014949649;39902.37889806483;41087.589124108374;42309.73070807881;43032;55937.44062559485;58635;58939.705301473216;58964.346604402046;59005.40994590171;15979.680406392039;16357.591060223589;16554.503364899072;16562.66874662476;16788.496345109666;17470.563235918697;17861.464156075337;18355.632887342446;18657.72013419827;18677.719834202228;16472.588185295463;16674;16697.332106715596;16937.576560586083;16962.32150714007;16348.509544713677;16512.174391280147;16522.83477165213;16562.751558726868;16669.91665041667;15025.924870376015;15392.538223853302;16087.195640218177;16151.27319270659;16367.099809510115;10756.085732712807;11004.119670426187;11133.443327833738;11309.377984210947;11684.88315116872;11815.350155741517;12054;12193.146479746332;12227.87772122303;12587.937060315006 05:48:24 INFO - PID 1652 | __end_tp_report 05:48:24 INFO - PID 1652 | __start_cc_report 05:48:24 INFO - PID 1652 | _x_x_mozilla_cycle_collect,479 05:48:24 INFO - PID 1652 | __end_cc_report 05:48:24 INFO - PID 1652 | __startTimestamp1497271704380__endTimestamp 05:48:24 INFO - PID 1652 | 05:48:24 INFO - PID 1652 | ------- Summary: start ------- 05:48:24 INFO - PID 1652 | Number of tests: 6 05:48:24 INFO - PID 1652 | 05:48:24 INFO - PID 1652 | [#0] dojo.html Cycles:165 Average:6150.82 Median:6372.27 stddev:734.67 (11.5%) stddev-sans-first:695.40 05:48:24 INFO - PID 1652 | Values: 3046.6 3513.1 3515.5 3521.1 3632.2 6167.0 6231.5 6265.4 6289.0 6301.9 6244.6 6248.4 6310.1 6327.3 6331.7 5433.8 5455.6 5456.9 5520.6 5546.0 5480.5 5489.7 5491.3 5498.3 5556.9 5305.8 5401.5 5456.9 5461.2 5483.8 5431.6 5436.9 5492.6 5519.8 5520.8 5424.2 5442.2 5475.9 5489.2 5498.3 6275.4 6276.0 6311.5 6314.0 6331.2 6457.7 6516.0 6539.6 6631.0 6725.3 6727.8 6786.4 6787.7 6827.8 6830.2 6291.7 6345.1 6352.0 6359.6 6392.3 6720.3 6920.4 6935.4 6939.4 6946.8 6437.7 6458.9 6466.1 6466.7 6475.5 6587.3 6590.0 6658.1 6740.3 6745.4 6562.6 6690.4 6700.5 6705.9 6757.8 6690.3 6704.6 6716.0 6716.9 6723.5 5354.9 5362.3 5367.8 5376.2 5401.5 4612.3 4692.3 4709.7 4759.5 4805.9 5397.8 6139.4 6284.0 6308.1 6330.2 6611.8 6628.0 6646.9 6668.2 6686.6 5462.9 6142.2 6301.8 6311.7 6337.8 5921.3 5964.9 5970.3 5989.6 6033.5 6816.3 6857.7 6916.9 6930.9 6936.9 6551.1 6645.8 6818.5 6823.2 6835.4 5824.4 6544.1 6766.3 6790.7 6801.3 6198.8 6659.9 6704.5 6712.5 6754.7 6804.0 6825.6 6834.9 6835.9 6855.0 6053.6 6379.1 6567.3 6567.9 6575.7 6385.8 6435.4 6444.5 6510.3 6528.3 5149.4 5487.5 6215.7 6383.0 6391.4 6481.3 6489.2 6499.1 6499.5 6521.0 6352.8 6369.7 6372.3 6373.5 6377.0 05:48:24 INFO - PID 1652 | 05:48:24 INFO - PID 1652 | [#1] ext.html Cycles:180 Average:40806.74 Median:333239.00 stddev:71913.35 (21.6%) stddev-sans-first:72052.31 05:48:24 INFO - PID 1652 | Values: 786.8 808.4 811.9 836.7 853.0 36169.6 36394.0 36545.0 36572.0 36641.6 36217.7 36281.6 36608.0 36628.0 36642.5 7265.7 7345.7 7375.4 7386.2 7403.1 7258.7 7810.5 7956.2 8053.6 8055.5 8046.4 8072.4 8117.4 8136.2 8139.2 7005.4 8018.3 8047.4 8070.0 8098.8 6649.7 7964.6 7980.4 7999.9 8002.8 33620.7 33682.8 33692.7 33693.7 33787.8 3489.6 3495.2 3499.0 3530.9 3542.4 28641.3 28656.0 28715.9 28739.4 28781.7 2961.9 2975.2 3003.4 3008.6 3029.6 233071.0 249397.0 250139.0 310881.0 314552.0 35934.1 37007.8 45009.0 45305.1 45321.5 39572.8 48068.0 48549.5 48699.8 48916.8 13473.9 13494.6 13592.7 13621.9 13767.7 11092.6 11099.4 11117.3 11122.7 11157.7 12989.9 13074.2 13085.0 13091.2 13175.1 5071.9 5503.0 5599.8 5696.3 5829.8 920.8 928.2 931.8 975.5 988.4 22201.3 22353.8 22528.3 23218.8 23285.8 922.9 1027.9 1062.7 1071.7 1104.8 11197.8 11461.0 11530.3 11804.6 11853.3 326137.0 332347.0 332462.0 334016.0 334397.0 48373.0 48462.5 48576.0 48634.0 48912.0 1398.2 1416.5 1421.7 1437.5 1440.5 21808.0 22208.3 22632.5 22661.1 22709.0 35908.1 36291.5 36386.0 36431.5 36458.5 196806.0 197909.0 199140.0 199362.0 199473.0 25432.4 25953.6 25975.5 31759.8 32172.0 31688.8 31811.7 32758.3 37714.8 38187.8 26339.6 27380.3 27876.7 28881.6 29090.7 27689.1 28444.9 28794.3 29970.7 30379.0 32486.5 35308.3 35696.3 36048.9 36810.8 28004.7 28397.0 30570.8 30883.7 30902.2 23160.4 23894.5 24148.2 24410.0 24519.6 05:48:24 INFO - PID 1652 | 05:48:24 INFO - PID 1652 | [#2] jquery.html Cycles:180 Average:39155.93 Median:12143.68 stddev:97012.94 (798.9%) stddev-sans-first:97241.62 05:48:24 INFO - PID 1652 | Values: 478.4 492.4 499.5 504.0 517.8 21861.1 21880.7 22519.1 22829.1 22889.2 19158.6 23764.4 24389.0 25042.2 25073.6 6339.7 6488.8 6493.3 6505.7 6513.2 6418.3 6431.4 6484.4 6491.2 6496.7 6226.0 6328.5 6341.7 6361.3 6395.2 5309.0 6252.2 6275.4 6322.3 6324.7 5768.2 5777.3 5778.8 5780.4 5782.6 29491.7 29849.3 30325.2 30346.0 30371.5 2164.6 2214.7 2215.3 2217.0 2224.3 18727.0 18849.0 18997.6 19045.0 19085.2 2156.6 2163.1 2191.8 2205.5 2245.6 152013.0 171696.0 182041.0 189758.0 191228.1 13883.1 14120.9 15216.5 15307.5 15417.8 20350.4 20369.5 20396.2 20426.3 20438.4 9547.3 9628.0 9870.6 10090.1 10380.9 9078.1 9089.2 9100.3 9143.7 9164.0 7838.7 9083.3 9122.9 9134.5 9160.3 2373.9 2422.1 2623.8 2628.6 2744.4 961.3 973.7 976.8 1001.6 1002.9 21103.3 21206.6 21248.6 21602.7 21772.8 977.2 983.0 1001.5 1006.4 1036.2 10879.1 10899.7 10918.4 11159.6 11295.0 544327.0 555456.0 573913.0 576038.0 576783.0 136392.0 136489.3 137345.0 139146.3 154121.2 1149.9 1188.8 1223.7 1224.4 1224.7 22037.3 22072.7 23256.0 23290.2 23472.8 70704.0 73351.6 74053.6 74123.6 74243.9 94176.0 95874.5 97954.5 99302.0 99761.5 11148.2 11409.8 11924.9 11930.0 11939.0 18333.9 18513.4 18674.6 18782.0 18914.3 14466.3 15015.4 15058.7 15280.8 15336.1 9994.8 12337.8 13010.6 13189.5 13576.0 14745.9 15794.8 15941.3 16136.5 16186.8 11905.8 11966.0 12035.8 12078.5 12086.5 14123.4 15185.2 15210.7 15227.6 15345.2 05:48:24 INFO - PID 1652 | 05:48:24 INFO - PID 1652 | [#3] mootools.html Cycles:180 Average:8027.24 Median:2723.10 stddev:5343.19 (196.2%) stddev-sans-first:5334.57 05:48:24 INFO - PID 1652 | Values: 1342.9 1403.6 1410.1 1419.0 1420.4 13041.9 13612.7 13613.0 13619.7 13638.2 12683.5 13135.5 13137.0 13144.7 13190.6 2674.2 2720.0 2739.6 2739.7 2741.0 2671.6 2705.3 2711.7 2716.7 2726.2 2652.0 2702.9 2706.9 2708.4 2709.4 2184.5 2311.8 2542.5 2672.4 2677.1 2545.3 2554.6 2568.9 2575.7 2622.3 10699.3 12987.4 13088.3 13106.5 13111.9 7225.9 7477.1 7483.6 7506.7 7512.3 4506.4 4668.4 4670.5 4757.8 4764.5 1116.9 1375.5 1379.0 1384.6 1402.1 18445.6 18907.7 19367.3 19398.8 19477.0 5473.9 5985.5 6142.3 6384.9 6536.9 13637.8 13859.3 13870.0 13874.8 13877.8 6262.4 7290.9 7333.2 7363.5 7424.5 6688.1 6782.9 6785.6 6801.7 6854.9 4968.9 5060.7 5070.6 5120.4 5144.5 1404.9 1441.5 1465.2 1465.8 1476.1 801.4 814.6 822.8 824.2 840.6 6939.5 7133.9 7172.8 7184.3 7220.1 652.9 675.1 702.6 707.9 711.5 4024.6 4301.5 4895.5 4937.0 5034.7 17423.7 18324.3 18366.0 18374.0 18379.7 17910.5 18104.7 18144.8 18186.2 18218.2 6949.9 8727.6 9167.3 9301.3 9439.5 5762.4 7563.4 7576.6 7904.3 7911.8 14613.6 15447.2 15684.0 16104.0 16204.8 15743.6 16149.3 16184.9 16362.0 16621.3 10587.0 10619.0 10747.7 10787.0 10799.5 12010.8 12036.4 12096.9 12103.7 12206.3 9418.0 9648.1 9711.2 9733.8 9971.9 9369.0 9430.9 9790.2 9853.4 9990.5 8926.8 9039.6 9109.6 9115.5 9118.5 6474.0 6541.4 6621.5 6644.5 6894.4 6812.7 6939.0 6977.4 7041.0 7123.5 05:48:24 INFO - PID 1652 | 05:48:24 INFO - PID 1652 | [#4] prototype.html Cycles:180 Average:5530.34 Median:6128.63 stddev:1671.39 (27.3%) stddev-sans-first:1631.83 05:48:24 INFO - PID 1652 | Values: 440.4 457.6 459.6 467.5 479.6 6078.3 6377.6 6420.3 6479.5 6489.6 6011.7 6123.4 6201.6 6213.0 6226.3 4650.0 4831.9 4881.6 4898.9 4934.9 4348.4 4752.4 4873.3 4923.2 4932.9 4028.8 4895.0 4906.9 4908.6 4921.7 4147.0 4361.3 4817.5 4834.0 4945.4 4007.5 4260.4 4347.2 4429.4 4450.4 5307.4 6133.8 6139.2 6141.1 6144.9 5854.2 5892.8 5907.2 5914.0 5937.8 6203.0 6773.2 6786.8 6809.6 6836.4 4227.2 4630.7 4986.3 5093.5 5127.4 6248.8 6989.0 7006.8 7032.4 7157.3 4340.2 4596.8 4819.8 5281.3 5288.4 4441.9 5378.2 5393.6 5421.9 5425.9 4524.6 5489.8 5888.9 5899.0 5911.8 6803.3 6825.2 6826.7 6840.8 6887.3 1440.9 1595.3 1822.5 1836.6 1843.2 963.4 1124.8 1137.5 1146.5 1148.2 4846.6 5282.7 5776.4 6248.0 6331.3 6112.8 6692.0 6882.8 6885.0 6947.3 5351.5 6337.2 6350.2 6356.3 6381.2 3328.0 3607.9 3666.6 3671.7 3677.6 5885.5 7130.8 7245.1 7249.2 7269.7 6826.7 7181.3 7191.5 7194.5 7229.0 7132.2 7133.6 7146.0 7147.7 7152.2 6384.4 7009.5 7037.0 7037.1 7040.2 5616.7 6247.0 6561.5 6852.9 6862.8 7097.9 7116.9 7133.8 7168.2 7184.3 3836.8 4029.9 4136.5 4806.5 4817.7 6043.6 6157.3 6868.5 7011.7 7078.4 6102.7 6671.2 6690.8 6718.3 6741.0 6068.8 6112.3 6675.0 6695.2 6722.5 5710.9 6595.0 6599.4 6602.8 6612.8 6265.0 6688.2 6710.0 6723.2 6728.0 5984.7 6516.1 6525.5 6588.7 6589.5 05:48:24 INFO - PID 1652 | 05:48:24 INFO - PID 1652 | [#5] yui.html Cycles:180 Average:23457.35 Median:17549.67 stddev:35067.39 (199.8%) stddev-sans-first:35124.60 05:48:24 INFO - PID 1652 | Values: 827.3 832.7 843.2 865.1 865.9 16965.2 17193.7 17238.1 17286.7 17531.9 11987.7 12300.9 13571.2 13588.7 13998.9 988.1 1000.9 1013.5 1018.4 1019.2 989.6 993.9 1004.6 1009.2 1010.8 852.6 1046.1 1046.8 1055.5 1058.3 930.6 963.4 1029.1 1031.5 1044.4 752.6 770.2 771.2 772.9 776.5 16943.6 17368.4 17567.4 17580.8 17592.8 17580.8 18198.7 18296.8 18342.6 18399.3 14176.3 14750.7 14763.1 14945.0 14963.5 3649.4 3657.4 3662.5 3680.6 3682.5 92737.0 112514.0 128724.4 162266.0 164039.2 13887.4 14072.5 14195.1 14227.4 14238.9 26546.9 26801.0 27151.6 27515.3 27517.2 7963.2 7998.5 8028.4 8035.2 8037.4 3915.9 3994.5 3999.9 4003.9 4006.2 4661.7 4682.1 4686.9 4709.1 4751.5 901.5 907.4 922.4 943.6 957.0 594.8 642.7 653.3 674.0 680.8 9783.3 12264.4 12340.6 12619.2 12823.6 628.2 640.6 650.5 674.4 681.3 5073.3 5486.1 6033.4 6096.0 6110.5 122349.0 127905.4 128020.0 129406.0 129720.0 102098.0 102485.0 109227.0 110039.4 110772.4 81061.0 90181.5 92386.0 94431.1 100314.5 11667.4 11975.4 12005.6 12040.0 12473.6 39194.0 39902.4 41087.6 42309.7 43032.0 55937.4 58635.0 58939.7 58964.3 59005.4 15979.7 16357.6 16554.5 16562.7 16788.5 17470.6 17861.5 18355.6 18657.7 18677.7 16472.6 16674.0 16697.3 16937.6 16962.3 16348.5 16512.2 16522.8 16562.8 16669.9 15025.9 15392.5 16087.2 16151.3 16367.1 10756.1 11004.1 11133.4 11309.4 11684.9 11815.4 12054.0 12193.1 12227.9 12587.9 05:48:24 INFO - PID 1652 | -------- Summary: end -------- 05:48:24 INFO - PID 1652 | 05:48:24 INFO - TEST-INFO | 1652: exit 0 05:48:25 INFO - TEST-OK | dromaeo_css | took 1144317ms 05:48:25 INFO - TEST-START | kraken 05:48:25 INFO - operating with platform_type : mac_ 05:48:25 INFO - Initialising browser for kraken test... 05:48:25 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground -profile /var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/tmpBeMMMA/profile 05:48:25 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground) 05:48:58 INFO - TEST-INFO | 1663: killed by SIGTERM 05:48:58 INFO - Browser initialized. 05:48:58 INFO - Running cycle 1/1 for kraken test... 05:48:58 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/tmpBeMMMA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:49:00 INFO - PID 1667 | 2017-06-12 05:49:00.518 plugin-container[1668:11635] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x973f, name = 'com.apple.tsm.portname' 05:49:00 INFO - PID 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:49:00 INFO - PID 1667 | 2017-06-12 05:49:00.519 plugin-container[1668:11635] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x491f, name = 'com.apple.CFPasteboardClient' 05:49:00 INFO - PID 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:49:00 INFO - PID 1667 | 2017-06-12 05:49:00.519 plugin-container[1668:11635] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:49:01 INFO - PID 1667 | 2017-06-12 05:49:01.323 plugin-container[1669:11680] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.tsm.portname' 05:49:01 INFO - PID 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:49:01 INFO - PID 1667 | 2017-06-12 05:49:01.324 plugin-container[1669:11680] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a13, name = 'com.apple.CFPasteboardClient' 05:49:01 INFO - PID 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:49:01 INFO - PID 1667 | 2017-06-12 05:49:01.324 plugin-container[1669:11680] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:49:10 INFO - PID 1667 | RSS: Main: 252526592 05:49:10 INFO - PID 1667 | 05:49:13 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-beat-detection) 05:49:14 INFO - PID 1667 | RSS: Main: 265285632 05:49:14 INFO - PID 1667 | 05:49:17 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-dft) 05:49:17 INFO - PID 1667 | RSS: Main: 276082688 05:49:17 INFO - PID 1667 | 05:49:21 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-fft) 05:49:21 INFO - PID 1667 | RSS: Main: 280870912 05:49:21 INFO - PID 1667 | 05:49:23 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-oscillator) 05:49:24 INFO - PID 1667 | RSS: Main: 282976256 05:49:24 INFO - PID 1667 | 05:49:26 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:49:26 INFO - PID 1667 | RSS: Main: 283164672 05:49:26 INFO - PID 1667 | 05:49:29 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-darkroom) 05:49:30 INFO - PID 1667 | RSS: Main: 287350784 05:49:30 INFO - PID 1667 | 05:49:33 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-desaturate) 05:49:34 INFO - PID 1667 | RSS: Main: 289587200 05:49:34 INFO - PID 1667 | 05:49:37 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49229/tests/kraken/driver.html?testName=json-parse-financial) 05:49:38 INFO - PID 1667 | RSS: Main: 288415744 05:49:38 INFO - PID 1667 | 05:49:39 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49229/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:49:40 INFO - PID 1667 | RSS: Main: 288440320 05:49:40 INFO - PID 1667 | 05:49:41 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:49:42 INFO - PID 1667 | RSS: Main: 288673792 05:49:42 INFO - PID 1667 | 05:49:44 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:49:45 INFO - PID 1667 | RSS: Main: 288759808 05:49:45 INFO - PID 1667 | 05:49:48 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:49:48 INFO - PID 1667 | RSS: Main: 288899072 05:49:48 INFO - PID 1667 | 05:49:51 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:49:52 INFO - PID 1667 | RSS: Main: 288833536 05:49:52 INFO - PID 1667 | 05:49:54 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49229/tests/kraken/driver.html?testName=ai-astar) 05:49:54 INFO - PID 1667 | RSS: Main: 290615296 05:49:54 INFO - PID 1667 | 05:49:54 INFO - PID 1667 | __start_tp_report 05:49:54 INFO - PID 1667 | _x_x_mozilla_page_load 05:49:54 INFO - PID 1667 | _x_x_mozilla_page_load_details 05:49:54 INFO - PID 1667 | |i|pagename|runs| 05:49:54 INFO - PID 1667 | |0;ai-astar;104;134;172;136;165;93;95;141;168;173 05:49:54 INFO - PID 1667 | |1;audio-beat-detection;168;140;141;141;111;131;112;143;149;148 05:49:54 INFO - PID 1667 | |2;audio-dft;201;199;221;254;254;200;198;194;195;197 05:49:54 INFO - PID 1667 | |3;audio-fft;73;79;76;127;62;61;67;73;108;57 05:49:54 INFO - PID 1667 | |4;audio-oscillator;71;69;110;72;71;92;111;60;96;91 05:49:54 INFO - PID 1667 | |5;imaging-gaussian-blur;88;90;88;93;90;88;90;97;102;138 05:49:54 INFO - PID 1667 | |6;imaging-darkroom;125;92;121;158;95;125;96;95;98;99 05:49:54 INFO - PID 1667 | |7;imaging-desaturate;93;95;91;93;93;91;92;76;140;132 05:49:54 INFO - PID 1667 | |8;json-parse-financial;52;53;50;53;53;54;53;54;53;52 05:49:54 INFO - PID 1667 | |9;json-stringify-tinderbox;59;56;66;57;68;65;60;57;58;81 05:49:54 INFO - PID 1667 | |10;stanford-crypto-aes;78;79;79;80;81;81;83;82;82;80 05:49:54 INFO - PID 1667 | |11;stanford-crypto-ccm;143;137;143;132;177;138;139;136;133;190 05:49:54 INFO - PID 1667 | |12;stanford-crypto-pbkdf2;185;177;181;181;179;180;189;180;180;196 05:49:54 INFO - PID 1667 | |13;stanford-crypto-sha256-iterative;68;69;67;67;70;72;94;92;57;81 05:49:54 INFO - PID 1667 | __end_tp_report 05:49:54 INFO - PID 1667 | __start_cc_report 05:49:54 INFO - PID 1667 | _x_x_mozilla_cycle_collect,1171 05:49:54 INFO - PID 1667 | __end_cc_report 05:49:54 INFO - PID 1667 | __startTimestamp1497271794408__endTimestamp 05:49:54 INFO - PID 1667 | 05:49:54 INFO - PID 1667 | ------- Summary: start ------- 05:49:54 INFO - PID 1667 | Number of tests: 14 05:49:54 INFO - PID 1667 | 05:49:54 INFO - PID 1667 | [#0] ai-astar Cycles:10 Average:138.10 Median:170.00 stddev:31.74 (18.7%) stddev-sans-first:31.18 05:49:54 INFO - PID 1667 | Values: 104.0 134.0 172.0 136.0 165.0 93.0 95.0 141.0 168.0 173.0 05:49:54 INFO - PID 1667 | 05:49:54 INFO - PID 1667 | [#1] audio-beat-detection Cycles:10 Average:138.40 Median:142.00 stddev:17.06 (12.0%) stddev-sans-first:14.35 05:49:54 INFO - PID 1667 | Values: 168.0 140.0 141.0 141.0 111.0 131.0 112.0 143.0 149.0 148.0 05:49:54 INFO - PID 1667 | 05:49:54 INFO - PID 1667 | [#2] audio-dft Cycles:10 Average:211.30 Median:200.50 stddev:23.74 (11.8%) stddev-sans-first:24.89 05:49:54 INFO - PID 1667 | Values: 201.0 199.0 221.0 254.0 254.0 200.0 198.0 194.0 195.0 197.0 05:49:54 INFO - PID 1667 | 05:49:54 INFO - PID 1667 | [#3] audio-fft Cycles:10 Average:78.30 Median:70.00 stddev:22.27 (31.8%) stddev-sans-first:23.53 05:49:54 INFO - PID 1667 | Values: 73.0 79.0 76.0 127.0 62.0 61.0 67.0 73.0 108.0 57.0 05:49:54 INFO - PID 1667 | 05:49:54 INFO - PID 1667 | [#4] audio-oscillator Cycles:10 Average:84.30 Median:71.50 stddev:18.09 (25.3%) stddev-sans-first:18.53 05:49:54 INFO - PID 1667 | Values: 71.0 69.0 110.0 72.0 71.0 92.0 111.0 60.0 96.0 91.0 05:49:54 INFO - PID 1667 | 05:49:54 INFO - PID 1667 | [#5] imaging-gaussian-blur Cycles:10 Average:96.40 Median:90.00 stddev:15.31 (17.0%) stddev-sans-first:15.93 05:49:54 INFO - PID 1667 | Values: 88.0 90.0 88.0 93.0 90.0 88.0 90.0 97.0 102.0 138.0 05:49:54 INFO - PID 1667 | 05:49:54 INFO - PID 1667 | [#6] imaging-darkroom Cycles:10 Average:110.40 Median:95.00 stddev:21.37 (22.5%) stddev-sans-first:22.00 05:49:54 INFO - PID 1667 | Values: 125.0 92.0 121.0 158.0 95.0 125.0 96.0 95.0 98.0 99.0 05:49:54 INFO - PID 1667 | 05:49:54 INFO - PID 1667 | [#7] imaging-desaturate Cycles:10 Average:99.60 Median:92.50 stddev:19.99 (21.6%) stddev-sans-first:21.06 05:49:54 INFO - PID 1667 | Values: 93.0 95.0 91.0 93.0 93.0 91.0 92.0 76.0 140.0 132.0 05:49:54 INFO - PID 1667 | 05:49:54 INFO - PID 1667 | [#8] json-parse-financial Cycles:10 Average:52.70 Median:53.00 stddev:1.16 (2.2%) stddev-sans-first:1.20 05:49:54 INFO - PID 1667 | Values: 52.0 53.0 50.0 53.0 53.0 54.0 53.0 54.0 53.0 52.0 05:49:54 INFO - PID 1667 | 05:49:54 INFO - PID 1667 | [#9] json-stringify-tinderbox Cycles:10 Average:62.70 Median:62.50 stddev:7.69 (12.3%) stddev-sans-first:8.04 05:49:54 INFO - PID 1667 | Values: 59.0 56.0 66.0 57.0 68.0 65.0 60.0 57.0 58.0 81.0 05:49:54 INFO - PID 1667 | 05:49:54 INFO - PID 1667 | [#10] stanford-crypto-aes Cycles:10 Average:80.50 Median:81.00 stddev:1.58 (2.0%) stddev-sans-first:1.39 05:49:54 INFO - PID 1667 | Values: 78.0 79.0 79.0 80.0 81.0 81.0 83.0 82.0 82.0 80.0 05:49:54 INFO - PID 1667 | 05:49:54 INFO - PID 1667 | [#11] stanford-crypto-ccm Cycles:10 Average:146.80 Median:141.00 stddev:19.91 (14.1%) stddev-sans-first:21.07 05:49:54 INFO - PID 1667 | Values: 143.0 137.0 143.0 132.0 177.0 138.0 139.0 136.0 133.0 190.0 05:49:54 INFO - PID 1667 | 05:49:54 INFO - PID 1667 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.80 Median:181.00 stddev:5.73 (3.2%) stddev-sans-first:6.02 05:49:54 INFO - PID 1667 | Values: 185.0 177.0 181.0 181.0 179.0 180.0 189.0 180.0 180.0 196.0 05:49:54 INFO - PID 1667 | 05:49:54 INFO - PID 1667 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.70 Median:71.00 stddev:11.74 (16.5%) stddev-sans-first:12.27 05:49:54 INFO - PID 1667 | Values: 68.0 69.0 67.0 67.0 70.0 72.0 94.0 92.0 57.0 81.0 05:49:54 INFO - PID 1667 | -------- Summary: end -------- 05:49:54 INFO - PID 1667 | 05:49:55 INFO - TEST-INFO | 1667: exit 0 05:49:55 INFO - TEST-OK | kraken | took 90269ms 05:49:55 INFO - SUITE-END | took 1234s 05:49:55 INFO - Completed test suite (00:20:34) 05:49:55 INFO - javascript benchmark 05:49:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8150.441267637543, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3046.5887105240786, 3513.069036705272, 3515.4902539131813, 3521.1197200699826, 3632.164602141509, 6166.9999999999945, 6231.50147988161, 6265.404786545282, 6289.0, 6301.873962520758, 6244.594101383407, 6248.406401391871, 6310.085037669547, 6327.335629758866, 6331.715072821723, 5433.809816656418, 5455.563554915617, 5456.863578410552, 5520.558355331584, 5546.029444847156, 5480.479354461319, 5489.725513724298, 5491.341039075317, 5498.3127109111365, 5556.86107847303, 5305.761240744157, 5401.459854014606, 5456.890862182774, 5461.15352120418, 5483.75323110461, 5431.646942948735, 5436.945630543683, 5492.642978206404, 5519.834404967857, 5520.806771763009, 5424.186372044225, 5442.210879422501, 5475.890482190373, 5489.204065410493, 5498.3127109111365, 6275.435210831001, 6276.0, 6311.495080393557, 6314.0, 6331.176946996861, 6457.741690332381, 6515.967420162916, 6539.607623542603, 6631.0, 6725.293844146343, 6727.764528241537, 6786.423154031913, 6787.694553745044, 6827.761028363983, 6830.214525329601, 6291.748330066837, 6345.143405640272, 6351.96824015877, 6359.586626869239, 6392.296847346788, 6720.294369091175, 6920.446364290845, 6935.445164386837, 6939.410150137295, 6946.826329341807, 6437.678116094176, 6458.870822583522, 6466.094746735461, 6466.741330346827, 6475.4819614430735, 6587.308332625101, 6590.044443555657, 6658.1344425224415, 6740.29226931175, 6745.426638735762, 6562.606243625302, 6690.431313338323, 6700.530962832555, 6705.865882682319, 6757.763478278237, 6690.297518760557, 6704.597724136567, 6715.966420167965, 6716.932830671631, 6723.462123030145, 5354.866128346823, 5362.276092727432, 5367.75845086972, 5376.220448035013, 5401.459854014645, 4612.285095810154, 4692.343071969927, 4709.7174169549935, 4759.50025247351, 4805.879853003703, 5397.839464515148, 6139.43080903429, 6283.968580157161, 6308.116863639094, 6330.208723909512, 6611.801645950629, 6627.966860165669, 6646.9032609619835, 6668.166479190101, 6686.598804071674, 5462.918056229159, 6142.170806941006, 6301.779437719658, 6311.715972781236, 6337.841553961188, 5921.259842519685, 5964.940350596438, 5970.253718285215, 5989.610675306179, 6033.5173186145, 6816.250212476524, 6857.657117144223, 6916.861662766616, 6930.861382772317, 6936.93063069373, 6551.115599394211, 6645.833854153685, 6818.454523638196, 6823.249442561413, 6835.350641689031, 5824.359320474659, 6544.083828264142, 6766.323367663194, 6790.72837086511, 6801.251862295045, 6198.81403557894, 6659.933400665931, 6704.4636429084585, 6712.530122891546, 6754.662266886734, 6804.047433359433, 6825.590464572141, 6834.8633027340165, 6835.89746153818, 6855.0, 6053.606515576474, 6379.106925030592, 6567.343265673395, 6567.9014814778775, 6575.704093315908, 6385.77649782274, 6435.420812126931, 6444.4844412445955, 6510.283868774336, 6528.281888992302, 5149.356330458692, 5487.533559647315, 6215.689215539295, 6383.0, 6391.424771770563, 6481.287058423664, 6489.1888513935755, 6499.122618446449, 6499.4800415965665, 6521.0, 6352.777652782131, 6369.681515924278, 6372.267189273108, 6373.490120790419, 6376.968115159394], "value": 6100.530509006189, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [786.7797016835289, 808.3856269235381, 811.853866304065, 836.7155167243136, 852.9616167272476, 36169.63830361696, 36394.0, 36545.0, 36572.0, 36641.63358366415, 36217.73237936673, 36281.637183628154, 36608.0, 36628.0, 36642.45036324457, 7265.673044712988, 7345.669444874967, 7375.446841486883, 7386.187519372892, 7403.148637906629, 7258.673359698826, 7810.531368117931, 7956.2441568050945, 8053.59732013397, 8055.51666899985, 8046.436749427542, 8072.354211663082, 8117.431779775418, 8136.186381361846, 8139.226773456512, 7005.369516743466, 8018.278354948025, 8047.356211503065, 8069.959650201741, 8098.838023239502, 6649.700763465607, 7964.601769911539, 7980.4413691041655, 7999.920000800034, 8002.799930001738, 33620.663793362, 33682.831585841916, 33692.6630733692, 33693.6630633693, 33787.83106084479, 3489.6335884732243, 3495.24852156785, 3498.9825050874583, 3530.9117272068147, 3542.3977923753023, 28641.28396790076, 28656.0, 28715.856420717973, 28739.425211495654, 28781.712182878113, 2961.8667159977854, 2975.1966968918487, 3003.3843062172423, 3008.638963324393, 3029.59100521429, 233071.0, 249397.0, 250139.0, 310881.0, 314552.0, 35934.1016474585, 37007.814960925025, 45009.0, 45305.09389812185, 45321.54678453173, 39572.80213598971, 48068.0, 48549.51450485521, 48699.75650121798, 48916.7554162234, 13473.86526134726, 13494.595162145151, 13592.66018349549, 13621.931890340486, 13767.724645507034, 11092.556297748, 11099.44502774858, 11117.27737697047, 11122.666320010412, 11157.66527004191, 12989.935050324877, 13074.215547067206, 13085.0, 13091.214527128403, 13175.14361566495, 5071.87320316995, 5502.972485137629, 5599.768051028767, 5696.316442026983, 5829.766809327664, 920.7836158502746, 928.215657769184, 931.7577429868284, 975.4586204656483, 988.367444835306, 22201.333959981228, 22353.776462235495, 22528.32415027552, 23218.767812322, 23285.835708214523, 922.9215516681023, 1027.912627426677, 1062.7396287909512, 1071.6731396924013, 1104.7679987202778, 11197.841187347089, 11461.025812805838, 11530.250533715449, 11804.586839460577, 11853.348065856288, 326137.0, 332347.0, 332462.0, 334016.0, 334397.0, 48373.03253934901, 48462.5153748465, 48576.0, 48634.0, 48912.0, 1398.2030242761732, 1416.4971435140583, 1421.665908511499, 1437.4681367893945, 1440.517426662059, 21808.018639160957, 22208.333749987527, 22632.547349053257, 22661.093556258224, 22709.0, 35908.102297442776, 36291.45562816613, 36386.0, 36431.45352819763, 36458.45312320264, 196806.0, 197909.0, 199140.0, 199362.0, 199473.0026349859, 25432.364190895376, 25953.610695839954, 25975.48049038971, 31759.84120079385, 32172.034838954867, 31688.841555792074, 31811.68188318087, 32758.34483310368, 37714.811425942695, 38187.80906095452, 26339.604905926808, 27380.317092997968, 27876.72123278741, 28881.56677649879, 29090.7090929088, 27689.08739810456, 28444.85777571099, 28794.28014299659, 29970.70029299679, 30379.0, 32486.51270230901, 35308.29383412266, 35696.28607427889, 36048.91853244407, 36810.8159459201, 28004.719952800213, 28397.0, 30570.847145764128, 30883.69116308898, 30902.227444313172, 23160.420989474726, 23894.522109557365, 24148.222954360474, 24410.0, 24519.63220551729], "value": 16199.408854463249, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [478.3709422109925, 492.38451935081173, 499.49301459019125, 504.03728877843315, 517.8032347707871, 21861.125554977803, 21880.671789923123, 22519.09923603058, 22829.08683652652, 22889.19887803927, 19158.616827663438, 23764.405889852762, 24389.0, 25042.248732538006, 25073.623895641533, 6339.683015849212, 6488.837779055514, 6493.318201588812, 6505.707243174045, 6513.153290072308, 6418.326075762047, 6431.356864313578, 6484.351564843525, 6491.188601424822, 6496.707648155821, 6225.972714502102, 6328.525360597951, 6341.651209183493, 6361.300256971729, 6395.20059992501, 5309.0, 6252.218472690914, 6275.435210831024, 6322.272938612073, 6324.683765811691, 5768.221290125821, 5777.306723193202, 5778.797742079049, 5780.364159942403, 5782.595218334718, 29491.70508294911, 29849.25376865574, 30325.24186895323, 30346.0, 30371.544426833614, 2164.588728141648, 2214.7010153629217, 2215.28003398896, 2216.9889150554145, 2224.277109939276, 18726.97001664908, 18849.0, 18997.62004759911, 19045.0, 19085.236590536362, 2156.5686862627535, 2163.12393480639, 2191.7589065202815, 2205.547862688145, 2245.5733410652088, 152013.0, 171696.0, 182041.0, 189758.0, 191228.0877191238, 13883.097598656055, 14120.85879141216, 15216.467423640119, 15307.46423875159, 15417.768733468783, 20350.389488315373, 20369.49076273105, 20396.184152633727, 20426.285080022128, 20438.386848394573, 9547.2839537035, 9628.037196280316, 9870.60517579303, 10090.09189172978, 10380.948095259475, 9078.092190781, 9089.181973622406, 9100.317476189311, 9143.679971201109, 9163.954180229057, 7838.725644602411, 9083.273338132933, 9122.86315705265, 9134.543272836332, 9160.267178625694, 2373.8842743910336, 2422.0675040109686, 2623.790096792252, 2628.579427291624, 2744.40995186036, 961.3078583419926, 973.7176218896583, 976.829054915387, 1001.5861773126729, 1002.9210324304315, 21103.261385851423, 21206.575868482545, 21248.575028499345, 21602.675959860615, 21772.782272177075, 977.1645243317004, 982.9505481072044, 1001.4742260313249, 1006.436395618456, 1036.2073014144187, 10879.12966962641, 10899.67300980956, 10918.399488028075, 11159.609413670483, 11295.0, 544327.0, 555456.0, 573913.0, 576038.0, 576783.0, 136392.0, 136489.27021459516, 137345.0, 139146.304268478, 154121.22939385232, 1149.8792626774236, 1188.827619995113, 1223.687959570304, 1224.406163010939, 1224.6509744722712, 22037.33887983363, 22072.668909966043, 23255.953482093686, 23290.184843530395, 23472.765272347056, 70704.0, 73351.63324183345, 74053.62973185099, 74123.62938185275, 74243.88634170599, 94176.0, 95874.52062739641, 97954.51022744841, 99302.0, 99761.50119249357, 11148.163887708291, 11409.771804563696, 11924.880751192377, 11929.985951194245, 11939.044876409693, 18333.908330458264, 18513.44459666212, 18674.626507470046, 18781.96699181558, 18914.337998170548, 14466.349014294172, 15015.399384024517, 15058.698826023638, 15280.770788438405, 15336.079835209923, 9994.800103998024, 12337.827906348883, 13010.609681709564, 13189.538366157138, 13576.049676522549, 14745.92627036858, 15794.7630785536, 15941.282642281358, 16136.515904522883, 16186.838131619004, 11905.761884762429, 11966.0, 12035.819462707892, 12078.456469458719, 12086.516539338329, 14123.435062597791, 15185.16481593523, 15210.695786083996, 15227.619309517351, 15345.232738362814], "value": 11727.7162982473, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1342.9059965802392, 1403.550863723609, 1410.1034109123239, 1418.9716205675886, 1420.3679362683604, 13041.869581304185, 13612.659683507918, 13613.0, 13619.659508512279, 13638.18170909745, 12683.492660293578, 13135.474581016748, 13137.014723895722, 13144.737105257913, 13190.60428187156, 2674.2111077232216, 2720.0, 2739.58906164076, 2739.6986331503617, 2740.9588856167175, 2671.639328690631, 2705.296622878056, 2711.715269896662, 2716.660417447819, 2726.209399274218, 2652.0, 2702.8513431761244, 2706.9187924362204, 2708.377073273148, 2709.444563864403, 2184.5194057307367, 2311.844077961028, 2542.466082122738, 2672.385351369186, 2677.143314139476, 2545.2618740565213, 2554.591265397546, 2568.8972441102333, 2575.652286941258, 2622.344413896526, 10699.251052426333, 12987.41556629954, 13088.280144592043, 13106.475740970349, 13111.934440327832, 7225.89161162583, 7477.102747670315, 7483.588402637853, 7506.699732010713, 7512.32389084985, 4506.391637128979, 4668.43978722552, 4670.462896766848, 4757.833475828364, 4764.499727528594, 1116.8771435142128, 1375.5391943698874, 1378.9517366892212, 1384.5707830572555, 1402.1306789790294, 18445.63108737831, 18907.716384254247, 19367.322143725043, 19398.80601193984, 19477.02614869265, 5473.863153421196, 5985.521158307325, 6142.324344322121, 6384.904226436607, 6536.869262614721, 13637.795433068512, 13859.307034648227, 13870.0, 13874.791878121836, 13877.791833122512, 6262.373762623702, 7290.927090729025, 7333.1566869809585, 7363.521371110861, 7424.480286379901, 6688.097106890605, 6782.898256526156, 6785.626790526569, 6801.65991700413, 6854.937484689881, 4968.850934471971, 5060.696358218519, 5070.594352451869, 5120.4111527174855, 5144.459831717691, 1404.8735613794809, 1441.509886638527, 1465.2453986197118, 1465.7654775236124, 1476.1364601707905, 801.4229754576695, 814.6293436486385, 822.7946059023559, 824.2169938558394, 840.6217202258886, 6939.548929319578, 7133.92866071343, 7172.784816455514, 7184.281571842774, 7220.097487814023, 652.8955367141234, 675.1459403854128, 702.610051421466, 707.929207079288, 711.5161690050816, 4024.577419370924, 4301.526832048472, 4895.534924182197, 4937.0372777308085, 5034.697918124924, 17423.73864392035, 18324.267029318944, 18366.0, 18374.0, 18379.72430413545, 17910.462686119437, 18104.728429073573, 18144.818551814576, 18186.18162182705, 18218.180181891847, 6949.861002779916, 8727.607257673293, 9167.312451566158, 9301.34890557668, 9439.52802359893, 5762.423757624204, 7563.357114645317, 7576.62116894142, 7904.328132108835, 7911.802204944923, 14613.634659133606, 15447.2276386178, 15684.0, 16104.03375797409, 16204.756928645844, 15743.606409839846, 16149.273282702072, 16184.867059305736, 16362.0, 16621.252043657823, 10587.0, 10619.0, 10747.731306717395, 10787.029167374974, 10799.460026998775, 12010.819837702267, 12036.398180090786, 12096.939515302367, 12103.697407564881, 12206.267623942593, 9418.0, 9648.131668149901, 9711.174550163316, 9733.756656083371, 9971.900280997097, 9369.0, 9430.858537122085, 9790.216782657228, 9853.359531630422, 9990.500474976077, 8926.776830579287, 9039.638414463348, 9109.635614575343, 9115.453072815651, 9118.498482583522, 6473.967630161819, 6541.378569035934, 6621.503387246072, 6644.468442524683, 6894.413974812197, 6812.65936703153, 6939.028536004862, 6977.371766681399, 7040.964795175992, 7123.536970096927], "value": 5891.906019158972, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [440.3636744903616, 457.5973143633601, 459.6116281741928, 467.4997752404928, 479.60911856836674, 6078.300995385532, 6377.553571250014, 6420.293767685551, 6479.481641468683, 6489.578177418476, 6011.669358185298, 6123.448889599935, 6201.643375517308, 6212.974859148247, 6226.283977342607, 4649.953500465002, 4831.855044348676, 4881.560659540641, 4898.926516102261, 4934.925976110361, 4348.369486424466, 4752.382190315254, 4873.269009648546, 4923.163062279421, 4932.901341973158, 4028.81870315835, 4895.0454661341055, 4906.926396104043, 4908.582770464497, 4921.680090794105, 4147.0, 4361.302191649353, 4817.51824817519, 4834.033193361307, 4945.35710357653, 4007.479027726406, 4260.382244574519, 4347.239233134189, 4429.379886815848, 4450.35469856869, 5307.3896501902, 6133.815985520441, 6139.201903752528, 6141.109539116847, 6144.910730351702, 5854.151148083504, 5892.793752218695, 5907.232059832238, 5914.0, 5937.821865344046, 6203.007518797016, 6773.153355830521, 6786.8303292417595, 6809.557378770364, 6836.38472537474, 4227.217964676544, 4630.652701047435, 4986.252062190664, 5093.5415812576675, 5127.3847138343635, 6248.843778905518, 6988.965055174743, 7006.789796306119, 7032.367086962198, 7157.284271572801, 4340.153670034313, 4596.770161491912, 4819.807207711722, 5281.287026251483, 5288.444713305047, 4441.888952776142, 5378.1663842104535, 5393.6224464287125, 5421.918671219935, 5425.9186112208345, 4524.5701658342405, 5489.7922457059385, 5888.911666325009, 5899.0, 5911.822645320647, 6803.2856550062525, 6825.180978282638, 6826.733291692692, 6840.760573380007, 6887.276835932255, 1440.9279536023157, 1595.2741502616282, 1822.489702883195, 1836.6143109946793, 1843.1889968413855, 963.3978763272954, 1124.7806677697934, 1137.5222406589253, 1146.4955419615342, 1148.1618418554335, 4846.61227101831, 5282.735863206901, 5776.422357764274, 6247.969085100907, 6331.335209802996, 6112.816615501453, 6692.0, 6882.7591034313555, 6884.965575172092, 6947.270536593584, 5351.464853514617, 6337.239531256187, 6350.237971443456, 6356.269029061717, 6381.160383629333, 3327.9500807487907, 3607.85568577254, 3666.560012798428, 3671.6695497405362, 3677.595464498903, 5885.499732522783, 7130.786076417716, 7245.094363204599, 7249.202587715347, 7269.709211631582, 6826.726930922708, 7181.271116975516, 7191.496595238283, 7194.532355396883, 7228.975861776015, 7132.215456299803, 7133.643317834191, 7145.961662395268, 7147.714091436388, 7152.249013853574, 6384.361563843764, 7009.492959013836, 7037.049998250171, 7037.109209099626, 7040.190378106378, 5616.66300022, 6247.0, 6561.489603062339, 6852.862942741217, 6862.764702353625, 7097.9290207097265, 7116.857662846611, 7133.785986420416, 7168.247334029852, 7184.3000854558895, 3836.8081595919534, 4029.899252518593, 4136.482939632546, 4806.519348065165, 4817.723303324692, 6043.576949613484, 6157.32269450369, 6868.484863635147, 7011.684474198775, 7078.433725302067, 6102.694865256808, 6671.199456065109, 6690.83272918181, 6718.294579069127, 6740.966295168493, 6068.848278792889, 6112.327643959072, 6674.966625166843, 6695.163104611924, 6722.495812813961, 5710.914336285042, 6595.0, 6599.406053455212, 6602.801915942529, 6612.8346791330605, 6265.026516647242, 6688.163979502562, 6709.966450167718, 6723.159605049369, 6727.966360168168, 5984.670843103497, 6516.120323756422, 6525.477961763143, 6588.670566471753, 6589.4728421725185], "value": 5026.20060912181, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [827.3381294964029, 832.7335252719132, 843.2326582809644, 865.0614083719164, 865.9437136586122, 16965.15174241289, 17193.65612687747, 17238.138093095342, 17286.654266914655, 17531.94808311502, 11987.741287164852, 12300.876991230085, 13571.185728856275, 13588.728225435485, 13998.860011399884, 988.0909563201835, 1000.8848982367011, 1013.5489707080352, 1018.4296793795461, 1019.1591936652254, 989.6338354808731, 993.8707967964154, 1004.6433516101788, 1009.2481101579334, 1010.8028934357803, 852.6333676519095, 1046.074224311486, 1046.7697106636529, 1055.5091882274742, 1058.343826827368, 930.6417029443679, 963.3545524498603, 1029.130384824822, 1031.4945676618404, 1044.3994703045742, 752.6199269368933, 770.2374649097353, 771.2364758888716, 772.926571975667, 776.4526009163583, 16943.57641058971, 17368.392106276347, 17567.385141520113, 17580.824191758176, 17592.824071759245, 17580.824191758045, 18198.72515935508, 18296.810707303983, 18342.63314733698, 18399.264029438807, 14176.29118544079, 14750.70498590033, 14763.114213147139, 14945.02857314271, 14963.476278330207, 3649.379605467077, 3657.414813629833, 3662.505561749156, 3680.6319368062977, 3682.4844521766972, 92737.0, 112514.0, 128724.3563782175, 162266.0, 164039.17980410022, 13887.44450222, 14072.507462238773, 14195.148291102567, 14227.35976881043, 14238.928805355907, 26546.867265663546, 26801.0, 27151.592726109124, 27515.31211719683, 27517.174484765488, 7963.1638677939145, 7998.520088794691, 8028.438009339407, 8035.156308587514, 8037.437379383504, 3915.8629447969756, 3994.5406278277787, 3999.9000024999027, 4003.919921601552, 4006.238814625212, 4661.696989695727, 4682.1103990241745, 4686.929696054562, 4709.058188362273, 4751.453582838018, 901.4501154295874, 907.4011152639226, 922.4050487435701, 943.5753910740194, 957.0286159548065, 594.8126340202908, 642.7204166187723, 653.3009679642737, 673.9932600674028, 680.792358330704, 9783.315167938175, 12264.38678066093, 12340.629781106582, 12619.179753316004, 12823.551175708806, 628.2241431831684, 640.5740182778493, 650.5381179362611, 674.3863084592997, 681.3152781454705, 5073.315102461121, 5486.149646804752, 6033.396660333931, 6096.0, 6110.480609148183, 122349.0, 127905.3604731989, 128020.0, 129406.0, 129720.0, 102097.97902020885, 102485.0, 109227.0, 110039.44980275207, 110772.44613777041, 81061.0, 90181.54909225412, 92386.0, 94431.0556894436, 100314.4984275074, 11667.416629168338, 11975.401229938294, 12005.63983080509, 12040.0, 12473.625791226277, 39194.02014949649, 39902.37889806483, 41087.589124108374, 42309.73070807881, 43032.0, 55937.44062559485, 58635.0, 58939.705301473216, 58964.346604402046, 59005.40994590171, 15979.680406392039, 16357.591060223589, 16554.503364899072, 16562.66874662476, 16788.496345109666, 17470.563235918697, 17861.464156075337, 18355.632887342446, 18657.72013419827, 18677.719834202228, 16472.588185295463, 16674.0, 16697.332106715596, 16937.576560586083, 16962.32150714007, 16348.509544713677, 16512.174391280147, 16522.83477165213, 16562.751558726868, 16669.91665041667, 15025.924870376015, 15392.538223853302, 16087.195640218177, 16151.27319270659, 16367.099809510115, 10756.085732712807, 11004.119670426187, 11133.443327833738, 11309.377984210947, 11684.88315116872, 11815.350155741517, 12054.0, 12193.146479746332, 12227.87772122303, 12587.937060315006], "value": 8540.525618351367, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1556.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 134.0, 172.0, 136.0, 165.0, 93.0, 95.0, 141.0, 168.0, 173.0], "value": 138.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 140.0, 141.0, 141.0, 111.0, 131.0, 112.0, 143.0, 149.0, 148.0], "value": 138.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 199.0, 221.0, 254.0, 254.0, 200.0, 198.0, 194.0, 195.0, 197.0], "value": 211.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 79.0, 76.0, 127.0, 62.0, 61.0, 67.0, 73.0, 108.0, 57.0], "value": 78.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 69.0, 110.0, 72.0, 71.0, 92.0, 111.0, 60.0, 96.0, 91.0], "value": 84.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 90.0, 88.0, 93.0, 90.0, 88.0, 90.0, 97.0, 102.0, 138.0], "value": 96.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 92.0, 121.0, 158.0, 95.0, 125.0, 96.0, 95.0, 98.0, 99.0], "value": 110.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 95.0, 91.0, 93.0, 93.0, 91.0, 92.0, 76.0, 140.0, 132.0], "value": 99.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 53.0, 50.0, 53.0, 53.0, 54.0, 53.0, 54.0, 53.0, 52.0], "value": 52.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 56.0, 66.0, 57.0, 68.0, 65.0, 60.0, 57.0, 58.0, 81.0], "value": 62.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 79.0, 79.0, 80.0, 81.0, 81.0, 83.0, 82.0, 82.0, 80.0], "value": 80.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 137.0, 143.0, 132.0, 177.0, 138.0, 139.0, 136.0, 133.0, 190.0], "value": 146.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 177.0, 181.0, 181.0, 179.0, 180.0, 189.0, 180.0, 180.0, 196.0], "value": 182.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 67.0, 67.0, 70.0, 72.0, 94.0, 92.0, 57.0, 81.0], "value": 73.7, "unit": "score"}]}]} 05:49:55 INFO - Return code: 0 05:49:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:49:55 INFO - # TBPL SUCCESS # 05:49:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:49:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:49:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:49:55 INFO - 2017-06-12 05:49:55.812 screenresolution[1675:12073] starting screenresolution argv=screenresolution get 05:49:55 INFO - 2017-06-12 05:49:55.815 screenresolution[1675:12073] Display 0: 1600x1200x32@60 05:49:55 INFO - 2017-06-12 05:49:55.821 screenresolution[1676:12076] starting screenresolution argv=screenresolution list 05:49:55 INFO - Available Modes on Display 0 05:49:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:49:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:49:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:49:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:49:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:49:56 INFO - Intel Iris: 05:49:56 INFO - Chipset Model: Intel Iris 05:49:56 INFO - Type: GPU 05:49:56 INFO - Bus: Built-In 05:49:56 INFO - VRAM (Dynamic, Max): 1536 MB 05:49:56 INFO - Vendor: Intel (0x8086) 05:49:56 INFO - Device ID: 0x0a2e 05:49:56 INFO - Revision ID: 0x0009 05:49:56 INFO - Displays: 05:49:56 INFO - Display: 05:49:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:49:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:49:56 INFO - Display Serial Number: 1600x1200 60 05:49:56 INFO - Main Display: Yes 05:49:56 INFO - Mirror: Off 05:49:56 INFO - Online: Yes 05:49:56 INFO - Rotation: Supported 05:49:56 INFO - Return code: 0 05:49:56 INFO - Running post-action listener: _resource_record_post_action 05:49:56 INFO - [mozharness: 2017-06-12 12:49:56.054462Z] Finished run-tests step (success) 05:49:56 INFO - Running post-run listener: _resource_record_post_run 05:49:56 INFO - Total resource usage - Wall time: 1257s; CPU: 53.0%; Read bytes: 35861504; Write bytes: 578750464; Read time: 343; Write time: 1922 05:49:56 INFO - TinderboxPrint: CPU usage
53.2% 05:49:56 INFO - TinderboxPrint: I/O read bytes / time
35,861,504 / 343 05:49:56 INFO - TinderboxPrint: I/O write bytes / time
578,750,464 / 1,922 05:49:56 INFO - TinderboxPrint: CPU idle
2,355.4 (46.8%) 05:49:56 INFO - TinderboxPrint: CPU system
1,013.0 (20.1%) 05:49:56 INFO - TinderboxPrint: CPU user
1,661.3 (33.0%) 05:49:56 INFO - TinderboxPrint: Swap in / out
285,032,448 / 0 05:49:56 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 253749760; Write bytes: 256636928; Read time: 18138; Write time: 569 05:49:56 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 05:49:56 INFO - run-tests - Wall time: 1236s; CPU: 53.0%; Read bytes: 30528512; Write bytes: 320753664; Read time: 301; Write time: 1347 05:49:56 INFO - Running post-run listener: _upload_blobber_files 05:49:56 INFO - Blob upload gear active. 05:49:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:49:56 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 05:49:56 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'] 05:49:56 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 05:49:56 INFO - (blobuploader) - INFO - Open directory for files ... 05:49:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:49:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:49:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:49:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:49:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:49:58 INFO - (blobuploader) - INFO - Done attempting. 05:49:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:49:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:49:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:49:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:49:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:49:59 INFO - (blobuploader) - INFO - Done attempting. 05:49:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:49:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:49:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:50:00 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:50:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:50:00 INFO - (blobuploader) - INFO - Done attempting. 05:50:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:50:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:50:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:50:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:50:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:50:03 INFO - (blobuploader) - INFO - Done attempting. 05:50:03 INFO - (blobuploader) - INFO - Iteration through files over. 05:50:03 INFO - Return code: 0 05:50:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:50:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:50:03 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a671ab53866622002ae13a870312b58f333d077ac58651a9964c7de6c6f547761eb9aa04703e8d366f7fc11a3669b5472cc9e4781af762eac53b691ffe2b883b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/09586e4102c215d94d1eca9eb68c095f326b10d68a10c5a25698455cddc2346b2372816003550caa1506e969c7ec5a8610365dccc2ad6090fc53eaecdf788225", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cf00da40b58a346581f313d0511572e6ea420c44835df8e2b19c07bf6c0b181786899398f04f3f5f13615f0a6f1748ea4d4cc7341d11c8c100a4bdbd77243d80", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d09ae6e29b97cf2d9c468e00dd1bed09f7b089e70f51ac0ccbc5d8db81f3e906e661f65572936b18943eae4668cb02bcfc596e1f8de81233ce04e7de0d115918"} 05:50:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:50:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:50:03 INFO - Contents: 05:50:03 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a671ab53866622002ae13a870312b58f333d077ac58651a9964c7de6c6f547761eb9aa04703e8d366f7fc11a3669b5472cc9e4781af762eac53b691ffe2b883b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/09586e4102c215d94d1eca9eb68c095f326b10d68a10c5a25698455cddc2346b2372816003550caa1506e969c7ec5a8610365dccc2ad6090fc53eaecdf788225", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cf00da40b58a346581f313d0511572e6ea420c44835df8e2b19c07bf6c0b181786899398f04f3f5f13615f0a6f1748ea4d4cc7341d11c8c100a4bdbd77243d80", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d09ae6e29b97cf2d9c468e00dd1bed09f7b089e70f51ac0ccbc5d8db81f3e906e661f65572936b18943eae4668cb02bcfc596e1f8de81233ce04e7de0d115918"} 05:50:03 INFO - Running post-run listener: copy_logs_to_upload_dir 05:50:03 INFO - Copying logs to upload dir... 05:50:03 INFO - mkdir: /builds/slave/test/build/upload/logs 05:50:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1339.566330 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-06-12 05:50:03.155044) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-12 05:50:03.158157) ========= 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.2uoXBe3KjX/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.0YF1uuoazl/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/a671ab53866622002ae13a870312b58f333d077ac58651a9964c7de6c6f547761eb9aa04703e8d366f7fc11a3669b5472cc9e4781af762eac53b691ffe2b883b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/09586e4102c215d94d1eca9eb68c095f326b10d68a10c5a25698455cddc2346b2372816003550caa1506e969c7ec5a8610365dccc2ad6090fc53eaecdf788225", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cf00da40b58a346581f313d0511572e6ea420c44835df8e2b19c07bf6c0b181786899398f04f3f5f13615f0a6f1748ea4d4cc7341d11c8c100a4bdbd77243d80", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d09ae6e29b97cf2d9c468e00dd1bed09f7b089e70f51ac0ccbc5d8db81f3e906e661f65572936b18943eae4668cb02bcfc596e1f8de81233ce04e7de0d115918"} build_url:https://queue.taskcluster.net/v1/task/K-k7BBcFRvmbJubPOj5Mzg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011275 build_url: 'https://queue.taskcluster.net/v1/task/K-k7BBcFRvmbJubPOj5Mzg/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/a671ab53866622002ae13a870312b58f333d077ac58651a9964c7de6c6f547761eb9aa04703e8d366f7fc11a3669b5472cc9e4781af762eac53b691ffe2b883b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/09586e4102c215d94d1eca9eb68c095f326b10d68a10c5a25698455cddc2346b2372816003550caa1506e969c7ec5a8610365dccc2ad6090fc53eaecdf788225", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cf00da40b58a346581f313d0511572e6ea420c44835df8e2b19c07bf6c0b181786899398f04f3f5f13615f0a6f1748ea4d4cc7341d11c8c100a4bdbd77243d80", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d09ae6e29b97cf2d9c468e00dd1bed09f7b089e70f51ac0ccbc5d8db81f3e906e661f65572936b18943eae4668cb02bcfc596e1f8de81233ce04e7de0d115918"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-12 05:50:03.191751) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 05:50:03.192072) ========= 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.2uoXBe3KjX/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.0YF1uuoazl/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.005046 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 05:50:03.240592) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-12 05:50:03.240907) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-12 05:50:03.241228) ========= ========= Total master_lag: 0.17 =========