builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s slave: t-yosemite-r7-0196 starttime: 1497364433.4 results: success (0) buildid: 20170613062834 builduid: 06049b68e5fc47e09e2adf37e9855686 revision: 7421b6df2ce2f798289ea29662f08d23a0c0d18d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-13 07:33:53.400745) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-13 07:33:53.401188) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-13 07:33:53.401462) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-13 07:33:53.427180) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-13 07:33:53.427561) ========= 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.19z0cLWthw/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.rgyKTtcATO/Listeners TMPDIR=/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-13 07:33:53-- 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% 145M=0s 2017-06-13 07:33:53 (145 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.153281 ========= master_lag: 1.25 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-13 07:33:54.834256) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 07:33:54.834584) ========= 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.19z0cLWthw/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.rgyKTtcATO/Listeners TMPDIR=/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.035179 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 07:33:54.894650) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-13 07:33:54.895010) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 7421b6df2ce2f798289ea29662f08d23a0c0d18d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 7421b6df2ce2f798289ea29662f08d23a0c0d18d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.19z0cLWthw/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.rgyKTtcATO/Listeners TMPDIR=/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-13 07:33:54,979 truncating revision to first 12 chars 2017-06-13 07:33:54,979 Setting DEBUG logging. 2017-06-13 07:33:54,979 attempt 1/10 2017-06-13 07:33:54,979 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/7421b6df2ce2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-13 07:33:56,186 unpacking tar archive at: mozilla-beta-7421b6df2ce2/testing/mozharness/ program finished with exit code 0 elapsedTime=1.484565 ========= master_lag: 0.83 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-13 07:33:57.207816) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-13 07:33:57.213202) ========= script_repo_revision: 7421b6df2ce2f798289ea29662f08d23a0c0d18d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-13 07:33:57.213637) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-13 07:33:57.213923) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-13 07:33:57.233649) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 16 secs) (at 2017-06-13 07:33:57.234051) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.19z0cLWthw/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.rgyKTtcATO/Listeners TMPDIR=/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 07:33:57 INFO - MultiFileLogger online at 20170613 07:33:57 in /builds/slave/test 07:33:57 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 07:33:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:33:57 INFO - {'append_to_log': False, 07:33:57 INFO - 'base_work_dir': '/builds/slave/test', 07:33:57 INFO - 'blob_upload_branch': 'Mozilla-Beta', 07:33:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:33:57 INFO - 'branch': 'Mozilla-Beta', 07:33:57 INFO - 'buildbot_json_path': 'buildprops.json', 07:33:57 INFO - 'config_files': ('talos/mac_config.py',), 07:33:57 INFO - 'default_actions': ('clobber', 07:33:57 INFO - 'read-buildbot-config', 07:33:57 INFO - 'download-and-extract', 07:33:57 INFO - 'populate-webroot', 07:33:57 INFO - 'create-virtualenv', 07:33:57 INFO - 'install', 07:33:57 INFO - 'setup-mitmproxy', 07:33:57 INFO - 'run-tests'), 07:33:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:33:57 INFO - 'download_minidump_stackwalk': True, 07:33:57 INFO - 'download_symbols': 'ondemand', 07:33:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:33:57 INFO - 'tooltool.py': '/tools/tooltool.py', 07:33:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:33:57 INFO - '/tools/misc-python/virtualenv.py')}, 07:33:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:33:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:33:57 INFO - 'gecko_profile': False, 07:33:57 INFO - 'gecko_profile_interval': 0, 07:33:57 INFO - 'installer_path': 'installer.exe', 07:33:57 INFO - 'log_level': 'info', 07:33:57 INFO - 'log_name': 'talos', 07:33:57 INFO - 'log_to_console': True, 07:33:57 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:33:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:33:57 INFO - 'opt_config_files': (), 07:33:57 INFO - 'pip_index': False, 07:33:57 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:33:57 INFO - 'cmd': ('bash', 07:33:57 INFO - '-c', 07:33:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:33:57 INFO - 'enabled': True, 07:33:57 INFO - 'halt_on_failure': False, 07:33:57 INFO - 'name': 'check_screen_resolution'},), 07:33:57 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:33:57 INFO - 'cmd': ('bash', 07:33:57 INFO - '-c', 07:33:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:33:57 INFO - 'enabled': True, 07:33:57 INFO - 'halt_on_failure': False, 07:33:57 INFO - 'name': 'check_screen_resolution'},), 07:33:57 INFO - 'run_cmd_checks_enabled': True, 07:33:57 INFO - 'suite': 'dromaeojs-e10s', 07:33:57 INFO - 'system_bits': '32', 07:33:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:33:57 INFO - 'title': 't-yosemite-r7-0196', 07:33:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:33:57 INFO - 'use_talos_json': True, 07:33:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:33:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:33:57 INFO - 'work_dir': 'build'} 07:33:57 INFO - [mozharness: 2017-06-13 14:33:57.433872Z] Running clobber step. 07:33:57 INFO - Running pre-action listener: _resource_record_pre_action 07:33:57 INFO - Running main action method: clobber 07:33:57 INFO - rmtree: /builds/slave/test/build 07:33:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:34:00 INFO - Running post-action listener: _resource_record_post_action 07:34:00 INFO - [mozharness: 2017-06-13 14:34:00.013336Z] Finished clobber step (success) 07:34:00 INFO - [mozharness: 2017-06-13 14:34:00.013515Z] Running read-buildbot-config step. 07:34:00 INFO - Running pre-action listener: _resource_record_pre_action 07:34:00 INFO - Running main action method: read_buildbot_config 07:34:00 INFO - Using buildbot properties: 07:34:00 INFO - { 07:34:00 INFO - "project": "", 07:34:00 INFO - "product": "firefox", 07:34:00 INFO - "script_repo_revision": "production", 07:34:00 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s", 07:34:00 INFO - "repository": "", 07:34:00 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs-e10s", 07:34:00 INFO - "buildid": "20170613062834", 07:34:00 INFO - "pgo_build": "False", 07:34:00 INFO - "basedir": "/builds/slave/test", 07:34:00 INFO - "buildnumber": 0, 07:34:00 INFO - "slavename": "t-yosemite-r7-0196", 07:34:00 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 07:34:00 INFO - "platform": "yosemite_r7_devedition", 07:34:00 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 07:34:00 INFO - "branch": "mozilla-beta", 07:34:00 INFO - "repo_path": "releases/mozilla-beta", 07:34:00 INFO - "revision": "7421b6df2ce2f798289ea29662f08d23a0c0d18d", 07:34:00 INFO - "stage_platform": "macosx64-devedition", 07:34:00 INFO - "builduid": "06049b68e5fc47e09e2adf37e9855686", 07:34:00 INFO - "slavebuilddir": "test" 07:34:00 INFO - } 07:34:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/dEXmm596REujASXgiBhkdA/artifacts/public/build/firefox-55.0.en-US.mac.dmg. 07:34:00 INFO - Running post-action listener: _resource_record_post_action 07:34:00 INFO - [mozharness: 2017-06-13 14:34:00.016792Z] Finished read-buildbot-config step (success) 07:34:00 INFO - [mozharness: 2017-06-13 14:34:00.016923Z] Running download-and-extract step. 07:34:00 INFO - Running pre-action listener: _resource_record_pre_action 07:34:00 INFO - Running main action method: download_and_extract 07:34:00 INFO - mkdir: /builds/slave/test/build/tests 07:34:00 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')]} 07:34:00 INFO - trying https://queue.taskcluster.net/v1/task/dEXmm596REujASXgiBhkdA/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json 07:34:00 INFO - Downloading https://queue.taskcluster.net/v1/task/dEXmm596REujASXgiBhkdA/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 07:34:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dEXmm596REujASXgiBhkdA/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json'}, attempt #1 07:34:01 INFO - Downloaded 1290 bytes. 07:34:01 INFO - Reading from file /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 07:34:01 INFO - Using the following test package requirements: 07:34:01 INFO - {u'awsy': [u'firefox-55.0.en-US.mac.common.tests.zip', 07:34:01 INFO - u'firefox-55.0.en-US.mac.awsy.tests.zip'], 07:34:01 INFO - u'common': [u'firefox-55.0.en-US.mac.common.tests.zip'], 07:34:01 INFO - u'cppunittest': [u'firefox-55.0.en-US.mac.common.tests.zip', 07:34:01 INFO - u'firefox-55.0.en-US.mac.cppunittest.tests.zip'], 07:34:01 INFO - u'gtest': [u'firefox-55.0.en-US.mac.common.tests.zip', 07:34:01 INFO - u'firefox-55.0.en-US.mac.gtest.tests.zip'], 07:34:01 INFO - u'jittest': [u'firefox-55.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 07:34:01 INFO - u'mochitest': [u'firefox-55.0.en-US.mac.common.tests.zip', 07:34:01 INFO - u'firefox-55.0.en-US.mac.mochitest.tests.zip'], 07:34:01 INFO - u'mozbase': [u'firefox-55.0.en-US.mac.common.tests.zip'], 07:34:01 INFO - u'reftest': [u'firefox-55.0.en-US.mac.common.tests.zip', 07:34:01 INFO - u'firefox-55.0.en-US.mac.reftest.tests.zip'], 07:34:01 INFO - u'talos': [u'firefox-55.0.en-US.mac.common.tests.zip', 07:34:01 INFO - u'firefox-55.0.en-US.mac.talos.tests.zip'], 07:34:01 INFO - u'web-platform': [u'firefox-55.0.en-US.mac.common.tests.zip', 07:34:01 INFO - u'firefox-55.0.en-US.mac.web-platform.tests.tar.gz'], 07:34:01 INFO - u'xpcshell': [u'firefox-55.0.en-US.mac.common.tests.zip', 07:34:01 INFO - u'firefox-55.0.en-US.mac.xpcshell.tests.zip']} 07:34:01 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip'] for test suite category: common 07:34:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dEXmm596REujASXgiBhkdA/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 07:34:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dEXmm596REujASXgiBhkdA/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 07:34:01 INFO - Fetch https://queue.taskcluster.net/v1/task/dEXmm596REujASXgiBhkdA/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 07:34:03 INFO - Content-Length response header: 59545157 07:34:03 INFO - Bytes received: 59545157 07:34:21 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip', u'firefox-55.0.en-US.mac.talos.tests.zip'] for test suite category: talos 07:34:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dEXmm596REujASXgiBhkdA/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 07:34:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dEXmm596REujASXgiBhkdA/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 07:34:21 INFO - Fetch https://queue.taskcluster.net/v1/task/dEXmm596REujASXgiBhkdA/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 07:34:22 INFO - Content-Length response header: 59545157 07:34:22 INFO - Bytes received: 59545157 07:34:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dEXmm596REujASXgiBhkdA/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip 07:34:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dEXmm596REujASXgiBhkdA/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip'}, attempt #1 07:34:40 INFO - Fetch https://queue.taskcluster.net/v1/task/dEXmm596REujASXgiBhkdA/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip into memory 07:34:41 INFO - Content-Length response header: 14450018 07:34:41 INFO - Bytes received: 14450018 07:34:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:34:41 INFO - trying https://queue.taskcluster.net/v1/task/dEXmm596REujASXgiBhkdA/artifacts/public/build/firefox-55.0.en-US.mac.dmg 07:34:41 INFO - Downloading https://queue.taskcluster.net/v1/task/dEXmm596REujASXgiBhkdA/artifacts/public/build/firefox-55.0.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0.en-US.mac.dmg 07:34:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dEXmm596REujASXgiBhkdA/artifacts/public/build/firefox-55.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0.en-US.mac.dmg'}, attempt #1 07:34:43 INFO - Downloaded 66876798 bytes. 07:34:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dEXmm596REujASXgiBhkdA/artifacts/public/build/firefox-55.0.en-US.mac.dmg 07:34:43 INFO - mkdir: /builds/slave/test/properties 07:34:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:34:43 INFO - Writing to file /builds/slave/test/properties/build_url 07:34:43 INFO - Contents: 07:34:43 INFO - build_url:https://queue.taskcluster.net/v1/task/dEXmm596REujASXgiBhkdA/artifacts/public/build/firefox-55.0.en-US.mac.dmg 07:34:44 INFO - Running post-action listener: _resource_record_post_action 07:34:44 INFO - Running post-action listener: set_extra_try_arguments 07:34:44 INFO - [mozharness: 2017-06-13 14:34:44.434583Z] Finished download-and-extract step (success) 07:34:44 INFO - [mozharness: 2017-06-13 14:34:44.434718Z] Running populate-webroot step. 07:34:44 INFO - Running pre-action listener: _resource_record_pre_action 07:34:44 INFO - Running main action method: populate_webroot 07:34:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:34:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:34:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:34:44 INFO - '--tppagecycles', 07:34:44 INFO - '7'], 07:34:44 INFO - 'tests': ['tsvgm']}}, 07:34:44 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:34:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:34:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:34:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:34:44 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:34:44 INFO - 'talos_options': ['--disable-e10s'], 07:34:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:34:44 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:34:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:34:44 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:34:44 INFO - 'talos_options': ['--disable-e10s'], 07:34:44 INFO - 'tests': ['damp', 'tps']}, 07:34:44 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:34:44 INFO - 'tests': ['damp', 'tps']}, 07:34:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:34:44 INFO - 'tests': ['dromaeo_dom']}, 07:34:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:34:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:34:44 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 07:34:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 07:34:44 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 07:34:44 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 07:34:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:34:44 INFO - 'tests': ['a11yr', 07:34:44 INFO - 'ts_paint', 07:34:44 INFO - 'tpaint', 07:34:44 INFO - 'sessionrestore', 07:34:44 INFO - 'sessionrestore_no_auto_restore', 07:34:44 INFO - 'tabpaint']}, 07:34:44 INFO - 'other-e10s': {'tests': ['a11yr', 07:34:44 INFO - 'ts_paint', 07:34:44 INFO - 'tpaint', 07:34:44 INFO - 'sessionrestore', 07:34:44 INFO - 'sessionrestore_no_auto_restore', 07:34:44 INFO - 'tabpaint']}, 07:34:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:34:44 INFO - 'ts_paint', 07:34:44 INFO - 'tpaint', 07:34:44 INFO - 'sessionrestore', 07:34:44 INFO - 'sessionrestore_no_auto_restore', 07:34:44 INFO - 'tabpaint']}, 07:34:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:34:44 INFO - 'ts_paint', 07:34:44 INFO - 'tpaint', 07:34:44 INFO - 'sessionrestore', 07:34:44 INFO - 'sessionrestore_no_auto_restore', 07:34:44 INFO - 'tabpaint']}, 07:34:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:34:44 INFO - 'tests': ['a11yr', 07:34:44 INFO - 'ts_paint', 07:34:44 INFO - 'tpaint', 07:34:44 INFO - 'sessionrestore', 07:34:44 INFO - 'sessionrestore_no_auto_restore', 07:34:44 INFO - 'tabpaint']}, 07:34:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:34:44 INFO - 'tests': ['a11yr', 07:34:44 INFO - 'ts_paint', 07:34:44 INFO - 'tpaint', 07:34:44 INFO - 'sessionrestore', 07:34:44 INFO - 'sessionrestore_no_auto_restore', 07:34:44 INFO - 'tabpaint']}, 07:34:44 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 07:34:44 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 07:34:44 INFO - 'bloom_basic_ref']}, 07:34:44 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 07:34:44 INFO - 'talos_options': ['--mitmproxy', 07:34:44 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 07:34:44 INFO - '--firstNonBlankPaint'], 07:34:44 INFO - 'tests': ['quantum_pageload_google', 07:34:44 INFO - 'quantum_pageload_youtube', 07:34:44 INFO - 'quantum_pageload_amazon', 07:34:44 INFO - 'quantum_pageload_facebook']}, 07:34:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:34:44 INFO - 'tests': ['tsvgx', 07:34:44 INFO - 'tsvgr_opacity', 07:34:44 INFO - 'tart', 07:34:44 INFO - 'tscrollx', 07:34:44 INFO - 'cart', 07:34:44 INFO - 'tsvg_static']}, 07:34:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:34:44 INFO - 'tsvgr_opacity', 07:34:44 INFO - 'tart', 07:34:44 INFO - 'tscrollx', 07:34:44 INFO - 'cart', 07:34:44 INFO - 'tsvg_static']}, 07:34:44 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:34:44 INFO - 'talos_options': ['--disable-e10s'], 07:34:44 INFO - 'tests': ['tp5o']}, 07:34:44 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:34:44 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:34:44 INFO - 'talos_options': ['--disable-e10s', 07:34:44 INFO - '--xperf_path', 07:34:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:34:44 INFO - 'tests': ['tp5n']}, 07:34:44 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:34:44 INFO - 'talos_options': ['--xperf_path', 07:34:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:34:44 INFO - 'tests': ['tp5n']}}, 07:34:44 INFO - 'talos.zip': {'path': '', 07:34:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:34:44 INFO - Running post-action listener: _resource_record_post_action 07:34:44 INFO - [mozharness: 2017-06-13 14:34:44.455947Z] Finished populate-webroot step (success) 07:34:44 INFO - [mozharness: 2017-06-13 14:34:44.456085Z] Running create-virtualenv step. 07:34:44 INFO - Running pre-action listener: _resource_record_pre_action 07:34:44 INFO - Running main action method: create_virtualenv 07:34:44 INFO - Creating virtualenv /builds/slave/test/build/venv 07:34:44 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 07:34:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:34:44 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 07:34:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:34:44 INFO - Using real prefix '/tools/python27' 07:34:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:34:45 INFO - Installing distribute.............................................................................................................................................................................................done. 07:34:48 INFO - Installing pip.................done. 07:34:48 INFO - Return code: 0 07:34:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:34:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:34:48 INFO - Reading from file tmpfile_stdout 07:34:48 INFO - Output received: 07:34:48 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) 07:34:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:34:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:34:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:34:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:34:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:34:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106782c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106786768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce08cefa20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064d8d30>, '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 0x1064a2cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1064c9be8>, '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.19z0cLWthw/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.rgyKTtcATO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:34:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:34:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:34:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.19z0cLWthw/Render', 07:34:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:34:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:34:48 INFO - 'HOME': '/Users/cltbld', 07:34:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:34:48 INFO - 'LOGNAME': 'cltbld', 07:34:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:34:48 INFO - 'MOZ_NO_REMOTE': '1', 07:34:48 INFO - 'NO_EM_RESTART': '1', 07:34:48 INFO - 'PAGER': '/bin/cat', 07:34:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:34:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:34:48 INFO - 'PWD': '/builds/slave/test', 07:34:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:34:48 INFO - 'SHELL': '/bin/bash', 07:34:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rgyKTtcATO/Listeners', 07:34:48 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 07:34:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:34:48 INFO - 'USER': 'cltbld', 07:34:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:34:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:34:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:34:48 INFO - 'XPC_FLAGS': '0x0', 07:34:48 INFO - 'XPC_SERVICE_NAME': '0', 07:34:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:34:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:34:48 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 07:34:48 INFO - Cleaning up... 07:34:48 INFO - Return code: 0 07:34:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:34:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:34:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:34:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:34:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:34:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106782c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106786768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce08cefa20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064d8d30>, '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 0x1064a2cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1064c9be8>, '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.19z0cLWthw/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.rgyKTtcATO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:34:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:34:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:34:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.19z0cLWthw/Render', 07:34:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:34:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:34:48 INFO - 'HOME': '/Users/cltbld', 07:34:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:34:48 INFO - 'LOGNAME': 'cltbld', 07:34:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:34:48 INFO - 'MOZ_NO_REMOTE': '1', 07:34:48 INFO - 'NO_EM_RESTART': '1', 07:34:48 INFO - 'PAGER': '/bin/cat', 07:34:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:34:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:34:48 INFO - 'PWD': '/builds/slave/test', 07:34:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:34:48 INFO - 'SHELL': '/bin/bash', 07:34:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rgyKTtcATO/Listeners', 07:34:48 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 07:34:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:34:48 INFO - 'USER': 'cltbld', 07:34:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:34:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:34:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:34:48 INFO - 'XPC_FLAGS': '0x0', 07:34:48 INFO - 'XPC_SERVICE_NAME': '0', 07:34:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:34:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:34:48 INFO - Downloading/unpacking psutil>=3.1.1 07:34:48 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 07:34:48 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 07:34:48 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 07:34:48 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 07:34:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:34:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:34:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:34:50 INFO - Installing collected packages: psutil 07:34:50 INFO - Running setup.py install for psutil 07:34:50 INFO - building 'psutil._psutil_osx' extension 07:34:50 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 07:34:51 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 07:34:51 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 07:34:51 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 07:34:51 INFO - building 'psutil._psutil_posix' extension 07:34:51 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 07:34:51 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:34:51 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:34:51 INFO - ^ 07:34:51 INFO - 1 warning generated. 07:34:51 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 07:34:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:34:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:34:51 INFO - Successfully installed psutil 07:34:51 INFO - Cleaning up... 07:34:52 INFO - Return code: 0 07:34:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:34:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:34:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:34:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:34:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:34:52 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 0x106782c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106786768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce08cefa20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064d8d30>, '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 0x1064a2cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1064c9be8>, '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.19z0cLWthw/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.rgyKTtcATO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:34:52 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 07:34:52 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 07:34:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.19z0cLWthw/Render', 07:34:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:34:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:34:52 INFO - 'HOME': '/Users/cltbld', 07:34:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:34:52 INFO - 'LOGNAME': 'cltbld', 07:34:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:34:52 INFO - 'MOZ_NO_REMOTE': '1', 07:34:52 INFO - 'NO_EM_RESTART': '1', 07:34:52 INFO - 'PAGER': '/bin/cat', 07:34:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:34:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:34:52 INFO - 'PWD': '/builds/slave/test', 07:34:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:34:52 INFO - 'SHELL': '/bin/bash', 07:34:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rgyKTtcATO/Listeners', 07:34:52 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 07:34:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:34:52 INFO - 'USER': 'cltbld', 07:34:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:34:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:34:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:34:52 INFO - 'XPC_FLAGS': '0x0', 07:34:52 INFO - 'XPC_SERVICE_NAME': '0', 07:34:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:34:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:34:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:34: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 07:34: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 07:34: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 07:34: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 07:34:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:34:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:34:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:34:54 INFO - Installing collected packages: mozsystemmonitor 07:34:54 INFO - Running setup.py install for mozsystemmonitor 07:34:55 INFO - Successfully installed mozsystemmonitor 07:34:55 INFO - Cleaning up... 07:34:55 INFO - Return code: 0 07:34:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:34: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')]} 07:34:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:34: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')]} 07:34:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:34:55 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 0x106782c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106786768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce08cefa20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064d8d30>, '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 0x1064a2cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1064c9be8>, '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.19z0cLWthw/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.rgyKTtcATO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:34:55 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 07:34:55 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 07:34:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.19z0cLWthw/Render', 07:34:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:34:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:34:55 INFO - 'HOME': '/Users/cltbld', 07:34:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:34:55 INFO - 'LOGNAME': 'cltbld', 07:34:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:34:55 INFO - 'MOZ_NO_REMOTE': '1', 07:34:55 INFO - 'NO_EM_RESTART': '1', 07:34:55 INFO - 'PAGER': '/bin/cat', 07:34:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:34:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:34:55 INFO - 'PWD': '/builds/slave/test', 07:34:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:34:55 INFO - 'SHELL': '/bin/bash', 07:34:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rgyKTtcATO/Listeners', 07:34:55 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 07:34:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:34:55 INFO - 'USER': 'cltbld', 07:34:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:34:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:34:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:34:55 INFO - 'XPC_FLAGS': '0x0', 07:34:55 INFO - 'XPC_SERVICE_NAME': '0', 07:34:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:34:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:34:55 INFO - Downloading/unpacking jsonschema==2.5.1 07:34: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 07:34: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 07:34: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 07:34: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 07:34:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:34:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:34:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:34:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:34:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:34:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:34:56 INFO - Downloading functools32-3.2.3-2.tar.gz 07:34:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:34:57 INFO - warning: no files found matching '*.txt' 07:34:57 INFO - no previously-included directories found matching 'build' 07:34:57 INFO - no previously-included directories found matching 'dist' 07:34:57 INFO - no previously-included directories found matching '.git*' 07:34:57 INFO - Installing collected packages: jsonschema, functools32 07:34:57 INFO - Running setup.py install for functools32 07:34:57 INFO - warning: no files found matching '*.txt' 07:34:57 INFO - no previously-included directories found matching 'build' 07:34:57 INFO - no previously-included directories found matching 'dist' 07:34:57 INFO - no previously-included directories found matching '.git*' 07:34:57 INFO - Successfully installed jsonschema functools32 07:34:57 INFO - Cleaning up... 07:34:57 INFO - Return code: 0 07:34:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:34: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')]} 07:34:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:34: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')]} 07:34:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:34: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', '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 0x106782c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106786768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce08cefa20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064d8d30>, '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 0x1064a2cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1064c9be8>, '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.19z0cLWthw/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.rgyKTtcATO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:34: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:34: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 functools32==3.2.3-2 07:34:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.19z0cLWthw/Render', 07:34:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:34:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:34:57 INFO - 'HOME': '/Users/cltbld', 07:34:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:34:57 INFO - 'LOGNAME': 'cltbld', 07:34:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:34:57 INFO - 'MOZ_NO_REMOTE': '1', 07:34:57 INFO - 'NO_EM_RESTART': '1', 07:34:57 INFO - 'PAGER': '/bin/cat', 07:34:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:34:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:34:57 INFO - 'PWD': '/builds/slave/test', 07:34:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:34:57 INFO - 'SHELL': '/bin/bash', 07:34:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rgyKTtcATO/Listeners', 07:34:57 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 07:34:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:34:57 INFO - 'USER': 'cltbld', 07:34:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:34:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:34:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:34:57 INFO - 'XPC_FLAGS': '0x0', 07:34:57 INFO - 'XPC_SERVICE_NAME': '0', 07:34:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:34:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:34:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:34:57 INFO - Cleaning up... 07:34:57 INFO - Return code: 0 07:34:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:34: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')]} 07:34:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:34: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')]} 07:34:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:34: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', '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 0x106782c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106786768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce08cefa20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064d8d30>, '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 0x1064a2cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1064c9be8>, '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.19z0cLWthw/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.rgyKTtcATO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:34: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:34: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 blobuploader==1.2.4 07:34:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.19z0cLWthw/Render', 07:34:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:34:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:34:57 INFO - 'HOME': '/Users/cltbld', 07:34:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:34:57 INFO - 'LOGNAME': 'cltbld', 07:34:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:34:57 INFO - 'MOZ_NO_REMOTE': '1', 07:34:57 INFO - 'NO_EM_RESTART': '1', 07:34:57 INFO - 'PAGER': '/bin/cat', 07:34:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:34:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:34:57 INFO - 'PWD': '/builds/slave/test', 07:34:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:34:57 INFO - 'SHELL': '/bin/bash', 07:34:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rgyKTtcATO/Listeners', 07:34:57 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 07:34:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:34:57 INFO - 'USER': 'cltbld', 07:34:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:34:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:34:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:34:57 INFO - 'XPC_FLAGS': '0x0', 07:34:57 INFO - 'XPC_SERVICE_NAME': '0', 07:34:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:34:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:34:57 INFO - Downloading/unpacking blobuploader==1.2.4 07:34:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:34:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:34:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:34:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:34:59 INFO - Downloading blobuploader-1.2.4.tar.gz 07:34:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:34:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:34:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:34:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:34:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:34:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:34:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:34:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:34:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:34:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:34:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:34:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:35:00 INFO - Downloading docopt-0.6.1.tar.gz 07:35:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:35:00 INFO - Installing collected packages: blobuploader, requests, docopt 07:35:00 INFO - Running setup.py install for blobuploader 07:35:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:35:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:35:00 INFO - Running setup.py install for requests 07:35:00 INFO - Running setup.py install for docopt 07:35:01 INFO - Successfully installed blobuploader requests docopt 07:35:01 INFO - Cleaning up... 07:35:01 INFO - Return code: 0 07:35:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:35:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:35:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:35:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:35:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:35:01 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 0x106782c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106786768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce08cefa20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064d8d30>, '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 0x1064a2cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1064c9be8>, '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.19z0cLWthw/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.rgyKTtcATO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:35:01 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 07:35:01 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 07:35:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.19z0cLWthw/Render', 07:35:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:35:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:35:01 INFO - 'HOME': '/Users/cltbld', 07:35:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:35:01 INFO - 'LOGNAME': 'cltbld', 07:35:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:35:01 INFO - 'MOZ_NO_REMOTE': '1', 07:35:01 INFO - 'NO_EM_RESTART': '1', 07:35:01 INFO - 'PAGER': '/bin/cat', 07:35:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:35:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:35:01 INFO - 'PWD': '/builds/slave/test', 07:35:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:35:01 INFO - 'SHELL': '/bin/bash', 07:35:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rgyKTtcATO/Listeners', 07:35:01 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 07:35:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:35:01 INFO - 'USER': 'cltbld', 07:35:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:35:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:35:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:35:01 INFO - 'XPC_FLAGS': '0x0', 07:35:01 INFO - 'XPC_SERVICE_NAME': '0', 07:35:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:35:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:35:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:35:01 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-GgwA0G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:35:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:35:01 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-Raqfw4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:35:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:35:01 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-FW5H7W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:35:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:35:01 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-UarbjT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:35:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:35:01 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-I1mKWl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:35:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:35:01 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-vxRM_B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:35:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:35:01 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-NkOKYq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:35:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:35:02 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-XcbWLD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:35:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:35:02 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-9ECReD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:35:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:35:02 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-HfPeAD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:35:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:35:02 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-4ALuIt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:35:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:35:02 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-J_lIg6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:35:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:35:02 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-OCTNLa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:35:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:35:02 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-H7Idul-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:35:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:35:02 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-3yzolP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:35:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:35:02 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-MrgAuV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:35:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:35:03 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-1mLubG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:35:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:35:03 INFO - Running setup.py install for manifestparser 07:35:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:35:03 INFO - Running setup.py install for mozcrash 07:35:03 INFO - Running setup.py install for mozdebug 07:35:03 INFO - Running setup.py install for mozdevice 07:35:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:35:03 INFO - Running setup.py install for mozfile 07:35:03 INFO - Running setup.py install for mozhttpd 07:35:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:35:03 INFO - Running setup.py install for mozinfo 07:35:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:35:04 INFO - Running setup.py install for mozInstall 07:35:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:35:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:35:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:35:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:35:04 INFO - Running setup.py install for mozleak 07:35:04 INFO - Running setup.py install for mozlog 07:35:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:35:04 INFO - Running setup.py install for moznetwork 07:35:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:35:04 INFO - Running setup.py install for mozprocess 07:35:04 INFO - Running setup.py install for mozprofile 07:35:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:35:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:35:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:35:05 INFO - Running setup.py install for mozrunner 07:35:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:35:05 INFO - Running setup.py install for mozscreenshot 07:35:05 INFO - Running setup.py install for moztest 07:35:05 INFO - Running setup.py install for mozversion 07:35:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:35:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:35:05 INFO - Cleaning up... 07:35:05 INFO - Return code: 0 07:35:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:35:05 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')]} 07:35:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:35:05 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')]} 07:35:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:35:05 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 0x106782c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106786768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce08cefa20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064d8d30>, '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 0x1064a2cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1064c9be8>, '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.19z0cLWthw/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.rgyKTtcATO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:35:05 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 07:35:05 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 07:35:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.19z0cLWthw/Render', 07:35:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:35:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:35:05 INFO - 'HOME': '/Users/cltbld', 07:35:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:35:05 INFO - 'LOGNAME': 'cltbld', 07:35:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:35:05 INFO - 'MOZ_NO_REMOTE': '1', 07:35:05 INFO - 'NO_EM_RESTART': '1', 07:35:05 INFO - 'PAGER': '/bin/cat', 07:35:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:35:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:35:05 INFO - 'PWD': '/builds/slave/test', 07:35:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:35:05 INFO - 'SHELL': '/bin/bash', 07:35:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rgyKTtcATO/Listeners', 07:35:05 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 07:35:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:35:05 INFO - 'USER': 'cltbld', 07:35:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:35:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:35:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:35:05 INFO - 'XPC_FLAGS': '0x0', 07:35:05 INFO - 'XPC_SERVICE_NAME': '0', 07:35:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:35:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:35:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:35:05 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-NF8FCK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:35:06 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)) 07:35:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:35:06 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-91Xc4w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:35:06 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)) 07:35:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:35:06 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-DB9Nrm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:35:06 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)) 07:35:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:35:06 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-YfFxwA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:35:06 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)) 07:35:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:35:06 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-mof_As-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:35:06 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)) 07:35:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:35:06 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-Zsto6I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:35:06 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)) 07:35:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:35:06 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-R0bT6c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:35:06 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)) 07:35:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:35:06 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-lVuvyP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:35:06 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)) 07:35:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:35:06 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-0WauWb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:35:06 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)) 07:35:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:35:06 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-gj9sli-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:35:06 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)) 07:35:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:35:06 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-5Etcjm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:35:07 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)) 07:35:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:35:07 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-CBhvAu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:35:07 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)) 07:35:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:35:07 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-wGRwOw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:35:07 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)) 07:35:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:35:07 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-IuF8kz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:35:07 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)) 07:35:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:35:07 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-gAFRix-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:35:07 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)) 07:35:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:35:07 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-fAtYWc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:35:07 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)) 07:35:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:35:07 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-VuKrYc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:35:07 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)) 07:35:07 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)) 07:35:07 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)) 07:35:07 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)) 07:35:07 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)) 07:35:07 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)) 07:35:07 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)) 07:35:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:35:07 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 07:35:07 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 07:35:07 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 07:35:07 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 07:35:09 INFO - Downloading blessings-1.6.tar.gz 07:35:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:35:09 INFO - Installing collected packages: blessings 07:35:09 INFO - Running setup.py install for blessings 07:35:09 INFO - Successfully installed blessings 07:35:09 INFO - Cleaning up... 07:35:09 INFO - Return code: 0 07:35:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:35:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:35:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:35:09 INFO - Reading from file tmpfile_stdout 07:35:09 INFO - Current package versions: 07:35:09 INFO - blessings == 1.6 07:35:09 INFO - blobuploader == 1.2.4 07:35:09 INFO - docopt == 0.6.1 07:35:09 INFO - functools32 == 3.2.3-2 07:35:09 INFO - jsonschema == 2.5.1 07:35:09 INFO - manifestparser == 1.1 07:35:09 INFO - mozInstall == 1.13 07:35:09 INFO - mozcrash == 1.0 07:35:09 INFO - mozdebug == 0.1 07:35:09 INFO - mozdevice == 0.51 07:35:09 INFO - mozfile == 1.2 07:35:09 INFO - mozhttpd == 0.7 07:35:09 INFO - mozinfo == 0.10 07:35:09 INFO - mozleak == 0.1 07:35:09 INFO - mozlog == 3.4 07:35:09 INFO - moznetwork == 0.27 07:35:09 INFO - mozprocess == 0.25 07:35:09 INFO - mozprofile == 0.28 07:35:09 INFO - mozrunner == 6.13 07:35:09 INFO - mozscreenshot == 0.1 07:35:09 INFO - mozsystemmonitor == 0.3 07:35:09 INFO - moztest == 0.8 07:35:09 INFO - mozversion == 1.4 07:35:09 INFO - psutil == 3.1.1 07:35:09 INFO - requests == 1.2.3 07:35:09 INFO - wsgiref == 0.1.2 07:35:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:35:09 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')]} 07:35:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:35:09 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')]} 07:35:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:35:09 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 0x106782c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106786768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce08cefa20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064d8d30>, '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 0x1064a2cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1064c9be8>, '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.19z0cLWthw/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.rgyKTtcATO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:35:09 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 07:35:09 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 07:35:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.19z0cLWthw/Render', 07:35:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:35:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:35:09 INFO - 'HOME': '/Users/cltbld', 07:35:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:35:09 INFO - 'LOGNAME': 'cltbld', 07:35:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:35:09 INFO - 'MOZ_NO_REMOTE': '1', 07:35:09 INFO - 'NO_EM_RESTART': '1', 07:35:09 INFO - 'PAGER': '/bin/cat', 07:35:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:35:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:35:09 INFO - 'PWD': '/builds/slave/test', 07:35:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:35:09 INFO - 'SHELL': '/bin/bash', 07:35:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rgyKTtcATO/Listeners', 07:35:09 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 07:35:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:35:09 INFO - 'USER': 'cltbld', 07:35:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:35:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:35:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:35:09 INFO - 'XPC_FLAGS': '0x0', 07:35:09 INFO - 'XPC_SERVICE_NAME': '0', 07:35:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:35:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:35:10 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)) 07:35:10 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)) 07:35:10 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)) 07:35:10 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)) 07:35:10 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)) 07:35:10 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)) 07:35:10 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)) 07:35:10 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)) 07:35:10 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)) 07:35:10 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 07:35:10 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 07:35:10 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 07:35:10 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 07:35:10 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 07:35:11 INFO - Downloading marionette_driver-1.4.0.tar.gz 07:35:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 07:35:11 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)) 07:35:11 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)) 07:35:11 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)) 07:35:11 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)) 07:35:11 INFO - Installing collected packages: marionette-driver 07:35:11 INFO - Running setup.py install for marionette-driver 07:35:11 INFO - Successfully installed marionette-driver 07:35:11 INFO - Cleaning up... 07:35:11 INFO - Return code: 0 07:35:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:35:11 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')]} 07:35:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:35:11 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')]} 07:35:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:35:11 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 0x106782c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106786768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce08cefa20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064d8d30>, '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 0x1064a2cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1064c9be8>, '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.19z0cLWthw/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.rgyKTtcATO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:35:11 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 07:35:11 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 07:35:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.19z0cLWthw/Render', 07:35:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:35:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:35:11 INFO - 'HOME': '/Users/cltbld', 07:35:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:35:11 INFO - 'LOGNAME': 'cltbld', 07:35:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:35:11 INFO - 'MOZ_NO_REMOTE': '1', 07:35:11 INFO - 'NO_EM_RESTART': '1', 07:35:11 INFO - 'PAGER': '/bin/cat', 07:35:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:35:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:35:11 INFO - 'PWD': '/builds/slave/test', 07:35:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:35:11 INFO - 'SHELL': '/bin/bash', 07:35:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rgyKTtcATO/Listeners', 07:35:11 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 07:35:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:35:11 INFO - 'USER': 'cltbld', 07:35:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:35:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:35:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:35:11 INFO - 'XPC_FLAGS': '0x0', 07:35:11 INFO - 'XPC_SERVICE_NAME': '0', 07:35:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:35:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:35:12 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:35:12 INFO - Cleaning up... 07:35:12 INFO - Return code: 0 07:35:12 INFO - Running post-action listener: _resource_record_post_action 07:35:12 INFO - Running post-action listener: _start_resource_monitoring 07:35:12 INFO - Starting resource monitoring. 07:35:12 INFO - [mozharness: 2017-06-13 14:35:12.070358Z] Finished create-virtualenv step (success) 07:35:12 INFO - [mozharness: 2017-06-13 14:35:12.071305Z] Running install step. 07:35:12 INFO - Running pre-action listener: _resource_record_pre_action 07:35:12 INFO - Running main action method: install 07:35:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:35:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:35:12 INFO - Reading from file tmpfile_stdout 07:35:12 INFO - Detecting whether we're running mozinstall >=1.0... 07:35:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:35:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:35:12 INFO - Reading from file tmpfile_stdout 07:35:12 INFO - Output received: 07:35:12 INFO - Usage: mozinstall [options] installer 07:35:12 INFO - Options: 07:35:12 INFO - -h, --help show this help message and exit 07:35:12 INFO - -d DEST, --destination=DEST 07:35:12 INFO - Directory to install application into. [default: 07:35:12 INFO - "/builds/slave/test"] 07:35:12 INFO - --app=APP Application being installed. [default: firefox] 07:35:12 INFO - mkdir: /builds/slave/test/build/application 07:35:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:35:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0.en-US.mac.dmg --destination /builds/slave/test/build/application 07:35:33 INFO - Reading from file tmpfile_stdout 07:35:33 INFO - Output received: 07:35:33 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 07:35:33 INFO - Running post-action listener: _resource_record_post_action 07:35:33 INFO - [mozharness: 2017-06-13 14:35:33.499310Z] Finished install step (success) 07:35:33 INFO - [mozharness: 2017-06-13 14:35:33.499449Z] Running setup-mitmproxy step. 07:35:33 INFO - Running pre-action listener: _resource_record_pre_action 07:35:33 INFO - Running main action method: setup_mitmproxy 07:35:33 INFO - Skipping: mitmproxy is not required 07:35:33 INFO - Running post-action listener: _resource_record_post_action 07:35:33 INFO - [mozharness: 2017-06-13 14:35:33.500120Z] Finished setup-mitmproxy step (success) 07:35:33 INFO - [mozharness: 2017-06-13 14:35:33.500270Z] Running run-tests step. 07:35:33 INFO - Running pre-action listener: _resource_record_pre_action 07:35:33 INFO - Running main action method: run_tests 07:35:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:35:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:35:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:35:33 INFO - 2017-06-13 07:35:33.515 screenresolution[1629:7432] starting screenresolution argv=screenresolution get 07:35:33 INFO - 2017-06-13 07:35:33.517 screenresolution[1629:7432] Display 0: 1600x1200x32@60 07:35:33 INFO - 2017-06-13 07:35:33.523 screenresolution[1630:7435] starting screenresolution argv=screenresolution list 07:35:33 INFO - Available Modes on Display 0 07:35:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:35:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:35:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:35:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:35:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:35:33 INFO - Intel Iris: 07:35:33 INFO - Chipset Model: Intel Iris 07:35:33 INFO - Type: GPU 07:35:33 INFO - Bus: Built-In 07:35:33 INFO - VRAM (Dynamic, Max): 1536 MB 07:35:33 INFO - Vendor: Intel (0x8086) 07:35:33 INFO - Device ID: 0x0a2e 07:35:33 INFO - Revision ID: 0x0009 07:35:33 INFO - Displays: 07:35:33 INFO - Display: 07:35:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:35:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:35:33 INFO - Display Serial Number: 1600x1200 60 07:35:33 INFO - Main Display: Yes 07:35:33 INFO - Mirror: Off 07:35:33 INFO - Online: Yes 07:35:33 INFO - Rotation: Supported 07:35:33 INFO - Return code: 0 07:35:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:35:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:35:33 INFO - Python 2.7.3 07:35:33 INFO - Return code: 0 07:35:33 INFO - grabbing minidump binary from tooltool 07:35:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:35:33 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 0x1064d8d30>, '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 0x1064a2cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1064c9be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:35:33 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 07:35:33 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 07:35:33 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 07:35:33 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:35:33 INFO - Return code: 0 07:35:33 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:35:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:35:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:35:33 INFO - ENV: RUST_BACKTRACE is now 1 07:35:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:35:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:35:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dEXmm596REujASXgiBhkdA/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0196', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 07:35:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dEXmm596REujASXgiBhkdA/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0196 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 07:35:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.19z0cLWthw/Render', 07:35:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:35:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:35:33 INFO - 'HOME': '/Users/cltbld', 07:35:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:35:33 INFO - 'LOGNAME': 'cltbld', 07:35:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:35:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:35:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:35:33 INFO - 'MOZ_NO_REMOTE': '1', 07:35:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:35:33 INFO - 'NO_EM_RESTART': '1', 07:35:33 INFO - 'PAGER': '/bin/cat', 07:35:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:35:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:35:33 INFO - 'PWD': '/builds/slave/test', 07:35:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:35:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:35:33 INFO - 'RUST_BACKTRACE': '1', 07:35:33 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 07:35:33 INFO - 'SHELL': '/bin/bash', 07:35:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rgyKTtcATO/Listeners', 07:35:33 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 07:35:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:35:33 INFO - 'USER': 'cltbld', 07:35:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:35:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:35:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:35:33 INFO - 'XPC_FLAGS': '0x0', 07:35:33 INFO - 'XPC_SERVICE_NAME': '0', 07:35:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:35:33 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dEXmm596REujASXgiBhkdA/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0196', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 07:35:34 INFO - mozversion application_buildid: 20170613062834 07:35:34 INFO - mozversion application_changeset: 7421b6df2ce2f798289ea29662f08d23a0c0d18d 07:35:34 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:35:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:35:34 INFO - mozversion application_name: Firefox 07:35:34 INFO - mozversion application_remotingname: firefox-dev 07:35:34 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 07:35:34 INFO - mozversion application_vendor: Mozilla 07:35:34 INFO - mozversion application_version: 55.0 07:35:34 INFO - mozversion platform_buildid: 20170613062834 07:35:34 INFO - mozversion platform_changeset: 7421b6df2ce2f798289ea29662f08d23a0c0d18d 07:35:34 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 07:35:34 INFO - mozversion platform_version: 55.0 07:35:34 INFO - using testdate: 1497364534 07:35:34 INFO - actual date: 1497364534 07:35:34 INFO - starting webserver on 'localhost:49222' 07:35:34 INFO - SUITE-START | Running 2 tests 07:35:34 INFO - TEST-START | dromaeo_css 07:35:34 INFO - operating with platform_type : mac_ 07:35:34 INFO - Initialising browser for dromaeo_css test... 07:35:34 INFO - Application command: /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49222/getInfo.html -foreground -profile /var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/tmp1ZfWiL/profile 07:35:34 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49222/getInfo.html -foreground) 07:36:07 INFO - TEST-INFO | 1640: killed by SIGTERM 07:36:07 INFO - Browser initialized. 07:36:07 INFO - Running cycle 1/1 for dromaeo_css test... 07:36:07 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/tmp1ZfWiL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:36:09 INFO - PID 1648 | 2017-06-13 07:36:09.626 plugin-container[1649:7928] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 07:36:09 INFO - PID 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:36:09 INFO - PID 1648 | 2017-06-13 07:36:09.627 plugin-container[1649:7928] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x991f, name = 'com.apple.CFPasteboardClient' 07:36:09 INFO - PID 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:36:09 INFO - PID 1648 | 2017-06-13 07:36:09.627 plugin-container[1649:7928] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:36:10 INFO - PID 1648 | 2017-06-13 07:36:10.352 plugin-container[1650:7971] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464b, name = 'com.apple.tsm.portname' 07:36:10 INFO - PID 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:36:10 INFO - PID 1648 | 2017-06-13 07:36:10.353 plugin-container[1650:7971] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x480b, name = 'com.apple.CFPasteboardClient' 07:36:10 INFO - PID 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:36:10 INFO - PID 1648 | 2017-06-13 07:36:10.353 plugin-container[1650:7971] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:36:19 INFO - PID 1648 | RSS: Main: 214888448 07:36:19 INFO - PID 1648 | 07:39:22 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49222/tests/dromaeo/cssquery-ext.html) 07:39:24 INFO - PID 1648 | RSS: Main: 239316992 07:39:24 INFO - PID 1648 | 07:42:26 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-ext.html (next: http://localhost:49222/tests/dromaeo/cssquery-jquery.html) 07:42:27 INFO - PID 1648 | RSS: Main: 246497280 07:42:27 INFO - PID 1648 | 07:45:28 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49222/tests/dromaeo/cssquery-mootools.html) 07:45:29 INFO - PID 1648 | RSS: Main: 249712640 07:45:29 INFO - PID 1648 | 07:48:31 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49222/tests/dromaeo/cssquery-prototype.html) 07:48:31 INFO - PID 1648 | RSS: Main: 251359232 07:48:31 INFO - PID 1648 | 07:51:34 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49222/tests/dromaeo/cssquery-yui.html) 07:51:35 INFO - PID 1648 | RSS: Main: 252346368 07:51:35 INFO - PID 1648 | 07:54:36 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-yui.html (next: http://localhost:49222/tests/dromaeo/cssquery-dojo.html) 07:54:37 INFO - PID 1648 | RSS: Main: 252571648 07:54:37 INFO - PID 1648 | 07:54:37 INFO - PID 1648 | __start_tp_report 07:54:37 INFO - PID 1648 | _x_x_mozilla_page_load 07:54:37 INFO - PID 1648 | _x_x_mozilla_page_load_details 07:54:37 INFO - PID 1648 | |i|pagename|runs| 07:54:37 INFO - PID 1648 | |0;dojo.html;3543.7165026797857;3635.5273814404122;3638.599754027057;3693.224422871197;3700.0749812546865;6249.5939063460555;6257.530685198605;6281.968590157043;6297.748090076398;6319.083732858733;6281.623102613835;6310.652914089735;6322.810315690525;6345.23857137143;6369.840753981152;5604.355499117592;5606.719664016803;5621.578381621384;5652.406497317784;5702.686352250625;5611.046122159243;5673.432656734335;5679.432056794308;5680.602357834953;5690.971545142268;5406.83779486616;5464.754086066137;5532.3637781655225;5613.859653508654;5643.097104463266;4024.939625905614;5575.637583557056;5587.972060139714;5645.266115404971;5650.802221922213;5461.754221060022;5560.638558493685;5575.498205161514;5589.776408943638;5636.633618814764;5765.1640512125505;6043.609969706992;6308.747650093991;6317.4946004319545;6327.335629758901;6675.532712710112;6912;6931.202911665122;6932.584044957319;6956.930430695679;5573.470520300565;6441.484681225491;6887.035814985906;6905.17137943445;6987.161540615159;6419.165508483868;6459.580127291805;6471.611703297817;6529.869402611921;6531.608103513804;7160.606166660779;7169.713211471482;7176.641167941582;7181.605011724405;7185.389241914391;6584.078229047937;6668.699908504129;6698.665066746643;6801.149856267966;6817.420519255819;6848.14398200225;6874.415674667609;6928.61892595902;7002.894956575656;7017;5809.88380232393;6823.378852287407;6875.5530890491955;6910.8617827644175;6941.618210998444;5915.1718759373725;6784.846576082078;6887.655617219119;6910.239873613898;6965.721371145199;5436.945630543723;5454.127339625641;5456.672599644034;5457.099578569571;5472.5348345391085;4757.500462451462;4790.880227994328;4832.758362081952;4924.532169443897;4931.038447502774;5974.551908606872;6258.780942667076;6320.494360451153;6325.272593651697;6400.135981642512;7014.789556313319;7026.824329391806;7027.824304392431;7051.435885129177;7056.435485161175;6461.741530338733;6467.51493637979;6477.643729594917;6480.222373315232;6492.967535162388;6067.1202675611785;6181.876362472815;6206.596571222856;6229.221347331583;6250.3749625037135;6941.305869413019;7046.436285097077;7124.3944264738075;7130.607816570035;7155.39179169766;6736.831579210559;6868.587884726932;6947.23580406165;6955.930440695731;6971.755988540377;6564.5076619254705;7166.605836679033;7196.172440169447;7203.351698347174;7208.207097219407;6919.169699636076;6922.411595014481;6923.099997000358;6943.548669336477;6950.54821436605;7045.647717614202;7051.717931282691;7059.18819335783;7080.221175670569;7090.149182098181;6554.508411869224;6627.436667883091;6638.734450621921;6808.978653202059;6809.012693159563;5677.410325108976;6619.040239165391;6674.6662666865495;6760.763373281911;6769.492288078315;6593.604383736991;6648.501362397743;6664.433523150587;6694.631795251308;6707.966460167668;6812.625305608239;6819.011243369783;6820.283870193757;6822.249552549117;6824.556403833735;6434.195725534309;6463.385978332051;6502.057201705821;6504.707288172138;6555.410013098844 07:54:37 INFO - PID 1648 | |1;ext.html;789.3882241263024;819.8483280593094;825.0594322472381;843.8649816029434;855.1961156512878;37217.44173837391;37906.620933790655;37913.431298530544;37963.81018094913;38020.85937421882;37414.81292593533;37720.05699857498;37735.62264377355;37802.43296350557;37868.24263514728;7786.143524212346;8000.239977202156;8005.799855003643;8006.239407256301;8007.919920800805;7991.640376183057;8099.838003239932;8112.18878112187;8117.431779775418;8143.75568732939;8047.517148971051;8068.273855353017;8072.919270807305;8079.717209897654;8114.472559283628;7035.788926332219;8061.072976607677;8088.676452941875;8090.838183236361;8113.634886430124;7856.685732570691;7869.2130786921425;7897.960510197412;7913.485623434516;7924.564148971805;35304.76433324847;35694;35712;35765.64234357649;36064.63935360639;3645;3666.138457462494;3670.6696397324367;3679.8528058877614;3700.3154416433044;22793;29925.70074299251;29989.250268743457;30052.8497357514;30192.698073019208;3059.2351912021995;3121.719045285935;3132.733717634004;3138.466460701686;3142.732867706248;260055.6997215002;264063.6796816023;264525.67737161386;328632;328838.7116128832;46837.53162468332;47358;47378.76310618425;47595.76202118967;47619.76190119027;48206.517934820906;48448;48875.266870996966;48900.755496223006;48956.26565601519;14960.877934154982;15024.924875375771;15045.398184072734;15154.16652084124;15163.696726065638;12345.814812777815;12535.185212961129;12554.372281385895;12567.811482827765;12569.308688022247;13211.603651890458;13227.801582976263;13237;13270.469181232642;13292.73414531704;5729.88540229193;5770.047942089592;5792;5797.275340582427;5817.592768506164;862.2283056664251;863.6329559937002;965.3352382086213;990.5443495991924;1045.9477026148813;22988.65517017246;22994.7700522996;23065.308040758806;23256.06975720986;23978;1039.8232300508932;1066.3814987307344;1101.8457415961773;1116.7989761842784;1160.5763896177834;11547.942260288813;11883.70290742721;12295.016398688083;12428.440720167615;12481.375931203404;323128;323555;325458;325989;325992;53812.73093634507;53883.73058134684;53939;53957;53964.460355395946;1442.8629280218363;1536.9932694085392;1541.3372249932636;1545.1810540413599;1547.5821528187325;22474.438139046655;23052.654210187196;23216;23333.649995250424;23969.880150599136;38385.616143839325;38397.424038638885;38461;38515;38532.844014678485;288729;289932;290211;290425;291333.543332282;28351.17259170068;32378.028659140262;32577.18557036093;33772.493412599324;34266.314673705885;42232.57767422409;43323;43348.13303733971;43538;43586.56413435952;23662.81685915732;27544;27967.44065118727;29553.85223073871;30148.24629384283;28841.56737648895;29603.555946660388;30010.949616763308;30627.23431914131;30664.540031899094;34689.82655086708;35426.937191883255;35539.466907995884;36191.91424257276;36281.274374511835;29311.85344073266;29673.554896676134;31161.220969475944;31425;31644.367112657157;22276.443088922908;22443.21448749286;24063.037478500664;24479.51040979135;25018 07:54:37 INFO - PID 1648 | |2;jquery.html;507.9949200507994;517.2138349708445;517.7747679759308;527.81526465737;539.7004662412364;15255.6948861023;21105.788942110576;21600.351989440303;21792;22002.33992980211;17973.281068757267;18267.451976440694;18298.8105773125;22450.43873903153;25050.373740656494;6464.547481676284;6526.804195874108;6544.574602650812;6554.80335589933;6574.8685026299445;6390.71241794118;6491.383318584727;6502.869942601145;6517.902231466532;6518.837029074288;6086.482648974842;6108.297545782225;6382.872342553146;6458.515611329146;6461;6387.041943708435;6388.776392826252;6443.871122577522;6446.645434501101;6452.677366131698;5884.146798714205;5909.379515150933;5924;5935.376785437553;5942.405759424067;28145.71854281472;29582.70417295821;29625.555616665766;30065.69934300651;30081.699183008328;2134.3596920923815;2228.7994080532667;2315.455867871049;2341.906323747048;2345.0971376020357;18284.725729114074;18854.434366969013;18860.15129319184;18865.868047917167;18952.81047189524;2151.075037733774;2157.3204440601316;2199.9450013749783;2210.9557808843892;2213.557288542282;155481;181764.18235817604;184512;187123.06438467855;188420.05789971098;15302.234888255765;15525.534233973;15588.766168507482;15632.3747050119;15840;20374.59250815005;20375.08312125958;20378.49053773638;20401;20414.183432662823;8511.787205319917;10139.340942838691;10273.640422585173;10334.638287660046;10350.741231469123;9628.90371096294;9647.71056868281;9658.9034109658;9663.516824158763;9753.609855605839;8849.469031858109;8997.820043599091;9030.27757779376;9032.954835225913;9083.591238394289;2154.396768904709;2585.7071464267865;2671.051776619311;2671.1051797647615;2685.5837345211526;982.29274922056;997.6209040564684;1025.2310766924807;1052.4422056310168;1053.8103141434615;17907.10464476771;21119;22317.3304800853;22426.439339016655;22712.432189195402;954;972.7227740094039;1020.4795554267378;1056.4400867540216;1068.9625863094755;11627.244229125079;11793.410329483491;11843.822342664867;11853.348065856288;11917;593041;617098;620688;624406;631432;163825;166890;170051;170797;175001.1249943742;1156.472806409547;1180.504188240941;1214.9271043737403;1218.6039537150461;1222.8226907098422;22867.54264914659;23153.652695209923;24010.87994560016;24236;24357.39106522351;71557.64221178861;73279.53440931258;73770;74044;74505;95964;96114.51942740241;99082.50458747661;99909;100807;11786.292822430682;11843.703907402383;11905.82141267899;11924.880751192724;12120.212186207867;15599.454019109276;18368.724469132707;18460.723089153944;18559.44321670352;18630.906845465684;14276.928615356857;14690.926545367205;14984.951053426592;15081.622959426102;15088.622784430037;12538.87461125377;13718.93140534291;13779.793303100261;13892.30538473052;13948.511802086878;13588.932055340056;14980.101193927963;16046.117463539618;16076.19619019021;16090.195490225677;12004.75990480168;12051.734567870248;12057.51769929193;12079.912807847337;12144.75710485803;15274.770878437055;15418.845811541742;15558.844411556192;15604.063756174666;15658.765118523004 07:54:37 INFO - PID 1648 | |3;mootools.html;1416.4051098538612;1484.0798704803024;1485.982102259952;1504.5110339139776;1508.6228442889278;13864.029517933748;14416.855831441682;14428.92785536071;14441.5667529974;14459.927700361497;11756.177067605271;11955.103367247448;12090.697732556691;12102.576409825659;13820.93089534551;2796.6923638399758;2851.4297140571866;2862.969331040824;2863.9140825775157;2864.026231081432;2799.7760179185616;2834.319763256814;2843.3176037750895;2850.942981140376;2854.1722900358873;2788.5259505883946;2802.733740294679;2817.6900540940583;2818.4504021715775;2821;1938.980610193894;1992.5217947692595;2424.1394305021818;2766.585012248159;2784.651918510186;2394.8084153267696;2776.1255204610484;2844.132539575429;2869.297022229546;2870.9138725838257;13858.653533661638;13950.511732089328;13984.510542130976;14054.578362649137;14086.2956852158;7600.847983040364;7918.564478953655;7929.881051784228;7935.166807485189;7944.563049032301;4606.746628935407;4823.345347962147;4859.173940430135;4887.804487820483;4944.579710724593;1365.3173413293352;1381.4405165907824;1403.7332906747686;1410.2807568140322;1431.0698046269904;20810.959452027488;21113.5777284455;21147.577048459098;21163.04766285521;21244.468888277763;7170.964145179345;7217.566945983258;7241.493095483371;7382.409407247407;7473.215312392121;17027.744583831252;17036.82963170377;17047;17053.14734263282;17081.316747330216;7864.252895974873;7927.365810735243;7964.123946365895;7966.083900351534;7973.561454120079;6873.759418420332;7006.369426751515;7061.1173603299585;7071.92928070723;7164.749233776897;4717.773378921505;5403.621746477709;5589.972050139804;5594.440555944454;5636.97181514098;1711.5549957011017;1714.1401579286614;1716.0818961855348;1724.5516165796982;1730.0571188702188;753.2505157368454;807.6325272001226;831.9500829950223;849.6558893648082;872.7294538693026;6247.2815626202655;7207.423406127497;7233.927660723431;7442.553446793209;7511.136219334737;665.9633720145439;688.2532452289222;717.2791344698545;720.7477382915937;725.1407082607127;4330.696851220384;4851.51484851512;4902.803887844518;5307.734613269321;5460.890782184334;18739;18794.154263058193;19255.844649321087;19274.71087933682;19315.4205373839;18685.906570467334;18783.718244226075;18785.06074696287;19232;19286.71069933952;9658.565364558612;9780.266480014028;9803.509824508747;9803.509824508888;9814.312998090065;6540.345965403421;6613.371729685672;8067.112617612175;8341.082480927214;8733.432326898985;14427.42290308376;16649.583760406087;17298.394556190473;17493.38773142934;17683.469495915142;14892.553223403314;17098.145092745563;17696.93818370902;17701.46895593134;18173.545661358043;10969.23215374916;11144.275622084862;11152.052075573667;11159.665210043711;11183.664490065312;11855.466504007169;12118.878811212127;12302.81545776832;12503.187292825936;12823.294718790556;9213.677521286723;10096.394216347042;10319.94840025795;10378.221633377376;10694.304870183412;8579.442336248125;9974.650887218913;10052.84791544345;10166.593336266762;10559.472026398804;9464.621415143592;9557.044295570666;9560.713178604654;9560.952195238979;9569.760755980878;6583.275839657538;6727.293634168342;6792.354726300993;6924.792256232321;6944.340287672663;5975.880482390415;6919.515633905779;7237.1677257114;7247.746328878464;7273.709051638087 07:54:37 INFO - PID 1648 | |4;prototype.html;406.751881352375;420.17435738773304;457.75947183133707;462.3942635147956;468.5197672385804;5008.524190201925;6392.8401789955215;6404.903926441101;6559.704813283402;6577.736890524386;5123.462036486162;6224.315325314212;6295.937040629592;6348.650824204667;6475.611463312204;4973.428055773578;4978.87552811179;4988.725620090893;5002;5019;4149.917001659965;4174.415581818548;4960.479149689285;4988.925166122511;5002.1746411842005;4093.4678491796026;4573.474050484187;4768.523147685239;4799.13615549201;5003.17447621142;4761.928566072641;4764.97617511912;4772.355731976173;4776.331313616097;4802.8799280018275;3751.6998640108727;3903.4535165076913;4145.502539695226;4148.377743338493;4174.812133454002;5158.432572417031;5707.316341679215;6174.475169610589;6191.628502289832;6207.937920620781;5624.831255062355;5673.205751194795;5690.430956904318;5714.257146570961;5726.770929162829;6884.173899132086;6914.446844252448;6919.238883722786;6919.7164935571145;6926.376626103674;4220.957790422087;4806;4807.711537307773;4816.54242846929;4819.566239038469;5699.629524080921;7398.371138453238;7426.9257307427315;7434.776956691254;7435.144958329807;4878.56092951636;4885.755712214375;4913.091078150554;4923.87690307738;4932.334134891744;5014.924776128361;5027.924581131286;5030.622703297267;5044;5054.418741844662;5247.711375874287;5287.70917599528;5716.57125715573;5726.914096288559;5735.824156048;6103.938960610426;6609.041688955074;6839.84877614994;7014.859702805916;7037.472189585802;1471.0585225455716;1660.5952565493665;1707.7267637177993;1713.811480737118;1723.7672914156678;975.6633961283346;991.7223177510309;993.6124911284605;1021.6973358967376;1104.5140138339104;6175.783847565313;6359.2368915729485;6517.500974775828;6549.9345006550275;6635.767748128792;7216.819579510449;7221.31397517235;7246.420286377077;7259;7270.672819723125;6481.4166724994975;6513.0460861741285;6514.413702766774;6537.8692426152165;6550.967245163743;3699.204670995749;3748.5876553579083;3752.81235938202;3755.0987762937243;3756.887293381203;6317.620073260875;6414.5189110815945;7153.675055606948;7383.9261607384315;7415.592142432218;7151.678174482161;7372.741954031583;7392.815179620553;7397.963010184914;7406.296401841816;5884.940710672035;6542.443892269211;6935.479839012094;7366.263373662698;7399.963000184857;6592.934070659232;6670.633115178518;7181.784546463615;7225.421966242583;7251.274872512707;5790.812883358985;6872.828179295558;7060.2465038633945;7087.893681594884;7096.645167741695;5594.664320140804;6776.695048722918;7354.852902941804;7387.9261207387235;7456;4023.416604592376;4782.258749893772;4866.513348665105;4876.78054487556;4902.646729569755;5851.327097383685;6938.653067346511;7116.573005619679;7136.785896423115;7274.30894065063;6353.809385718436;6657.600543967377;6850.588964662136;6953.478489113236;6956.478264130109;5789.465791565322;6458.483321334188;6805.931940680728;6887.690053947685;6925.168979722465;6321.778737744157;6436.7448347573645;6792.388685018372;6821.8635627286185;6822.897656535255;6200.441960223601;6937;6953.374196322356;6969.16370035599;6974.895376569254;5668.461496157858;5945.494632956074;6190.721417536131;6751.898721519279;6786.626735529593 07:54:37 INFO - PID 1648 | |5;yui.html;740.6148802622635;820.1716266570769;824.5464994253159;856.6530555125173;863.7926897544589;16698;16900.746488802662;17081.40215092472;17149.056801875893;17155.228014739336;17068.82931170688;17363.91318043408;18600.069996500188;18622.348217812378;18670.253189872423;937.4515908193681;937.8827646544182;956.8660387545749;958.4009993753904;959.7696552827341;994.9502524873764;998.4209158687944;999.4153420249127;1000.6047611193574;1001.639409812467;1027.1885210683552;1046.4140081554315;1054.4306074719643;1055.8257887448563;1056.3872953686882;917.1607978699482;943.3302355327726;1014.137982714694;1016.9644062457778;1023.3655133817081;735.4116706634725;744.597917124752;746.8917007033949;748.9026426564511;751.462704166518;15390.461333853262;16906.915465422717;17238.82761172385;17385.913070434694;17522.036288004154;17969.191386387643;18050.729239061424;18200.72698909504;18290.90854545732;18442.35451759182;14804.333804978802;15103;15278;15286;15305.846941530554;3584.193556449804;3585.587657419404;3588.9641103588892;3615.6926661233565;3616.385214513539;119849;139841;142840.57159428377;159602.4039759611;166205;13761.862381376259;13880.583582492542;14011.649708757363;14048;14131.78802317966;26468.20595382103;27460.862695686395;27496.762645680992;27575;27693;7910.248526389983;7910.683572657144;7980.162082981319;7986.401019923529;8010.399220058519;3989.122393073519;4073.694472914484;4077.959220407758;4082.9591704083173;4091.9974606221667;4707.223308154184;4708.858734237979;4741.454732705783;4755.2153894607;4798.568128868418;780.9062912450543;866.7183165471247;889.5596679643618;896.0994200828126;896.5920506169678;644.6390021587926;647.8769033883547;649.1398896462207;658.8319978405476;668.4685674888475;9138.954305228564;11156.665300041012;11779.411029448493;11834.585789497498;12150.392480375946;634.2022599038261;645.2901808011244;646.0890144895675;665.9766908158191;673.5318953327488;5321.775991521971;5564.693941833157;5590.8043218487155;5689.829305120853;5910.763569457174;128061;145845;153045;153904;162930;127793;132452.3377383126;135598;135679;136741.7313862483;114748;115389.4230528842;116648;121726.39136804259;122721.38639306746;10426.061654451136;10936.45317734126;11039.613613523488;11351.37567433799;11570.421478926188;48340;51497.97004059973;51751.2237316433;51846;53503.73248133734;59822;67999;68222;68708.96936546055;70417;13891;14500.984931054725;14549.272536373352;14638.707225855636;14664.120152790867;14321.928390357982;15134.394624215349;15396.46112386061;15666.295016724047;15703.371865125268;14031.719365612835;14407.639809004859;14597.12417254968;14848.40606375733;15115.395384184509;13213.933930330288;13307.001974851732;13562.796558051834;13589.116707413987;13623.65940851447;14267.286635667968;14514.346854391366;14522.854771452572;14563.85436145625;14784;9928.404295742279;10151.238657100599;10265.948670256601;10409.375437473776;10786.460676966277;11427.485763140845;11429.199956003;11514.30914145154;11697.473613687574;11920.940395298316 07:54:37 INFO - PID 1648 | __end_tp_report 07:54:37 INFO - PID 1648 | __start_cc_report 07:54:37 INFO - PID 1648 | _x_x_mozilla_cycle_collect,408 07:54:37 INFO - PID 1648 | __end_cc_report 07:54:37 INFO - PID 1648 | __startTimestamp1497365677046__endTimestamp 07:54:37 INFO - PID 1648 | 07:54:37 INFO - PID 1648 | ------- Summary: start ------- 07:54:37 INFO - PID 1648 | Number of tests: 6 07:54:37 INFO - PID 1648 | 07:54:37 INFO - PID 1648 | [#0] dojo.html Cycles:165 Average:6323.36 Median:6529.87 stddev:782.89 (12.0%) stddev-sans-first:754.32 07:54:37 INFO - PID 1648 | Values: 3543.7 3635.5 3638.6 3693.2 3700.1 6249.6 6257.5 6282.0 6297.7 6319.1 6281.6 6310.7 6322.8 6345.2 6369.8 5604.4 5606.7 5621.6 5652.4 5702.7 5611.0 5673.4 5679.4 5680.6 5691.0 5406.8 5464.8 5532.4 5613.9 5643.1 4024.9 5575.6 5588.0 5645.3 5650.8 5461.8 5560.6 5575.5 5589.8 5636.6 5765.2 6043.6 6308.7 6317.5 6327.3 6675.5 6912.0 6931.2 6932.6 6956.9 5573.5 6441.5 6887.0 6905.2 6987.2 6419.2 6459.6 6471.6 6529.9 6531.6 7160.6 7169.7 7176.6 7181.6 7185.4 6584.1 6668.7 6698.7 6801.1 6817.4 6848.1 6874.4 6928.6 7002.9 7017.0 5809.9 6823.4 6875.6 6910.9 6941.6 5915.2 6784.8 6887.7 6910.2 6965.7 5436.9 5454.1 5456.7 5457.1 5472.5 4757.5 4790.9 4832.8 4924.5 4931.0 5974.6 6258.8 6320.5 6325.3 6400.1 7014.8 7026.8 7027.8 7051.4 7056.4 6461.7 6467.5 6477.6 6480.2 6493.0 6067.1 6181.9 6206.6 6229.2 6250.4 6941.3 7046.4 7124.4 7130.6 7155.4 6736.8 6868.6 6947.2 6955.9 6971.8 6564.5 7166.6 7196.2 7203.4 7208.2 6919.2 6922.4 6923.1 6943.5 6950.5 7045.6 7051.7 7059.2 7080.2 7090.1 6554.5 6627.4 6638.7 6809.0 6809.0 5677.4 6619.0 6674.7 6760.8 6769.5 6593.6 6648.5 6664.4 6694.6 6708.0 6812.6 6819.0 6820.3 6822.2 6824.6 6434.2 6463.4 6502.1 6504.7 6555.4 07:54:37 INFO - PID 1648 | 07:54:37 INFO - PID 1648 | [#1] ext.html Cycles:180 Average:44772.40 Median:34478.07 stddev:79398.74 (230.3%) stddev-sans-first:79552.79 07:54:37 INFO - PID 1648 | Values: 789.4 819.8 825.1 843.9 855.2 37217.4 37906.6 37913.4 37963.8 38020.9 37414.8 37720.1 37735.6 37802.4 37868.2 7786.1 8000.2 8005.8 8006.2 8007.9 7991.6 8099.8 8112.2 8117.4 8143.8 8047.5 8068.3 8072.9 8079.7 8114.5 7035.8 8061.1 8088.7 8090.8 8113.6 7856.7 7869.2 7898.0 7913.5 7924.6 35304.8 35694.0 35712.0 35765.6 36064.6 3645.0 3666.1 3670.7 3679.9 3700.3 22793.0 29925.7 29989.3 30052.8 30192.7 3059.2 3121.7 3132.7 3138.5 3142.7 260055.7 264063.7 264525.7 328632.0 328838.7 46837.5 47358.0 47378.8 47595.8 47619.8 48206.5 48448.0 48875.3 48900.8 48956.3 14960.9 15024.9 15045.4 15154.2 15163.7 12345.8 12535.2 12554.4 12567.8 12569.3 13211.6 13227.8 13237.0 13270.5 13292.7 5729.9 5770.0 5792.0 5797.3 5817.6 862.2 863.6 965.3 990.5 1045.9 22988.7 22994.8 23065.3 23256.1 23978.0 1039.8 1066.4 1101.8 1116.8 1160.6 11547.9 11883.7 12295.0 12428.4 12481.4 323128.0 323555.0 325458.0 325989.0 325992.0 53812.7 53883.7 53939.0 53957.0 53964.5 1442.9 1537.0 1541.3 1545.2 1547.6 22474.4 23052.7 23216.0 23333.6 23969.9 38385.6 38397.4 38461.0 38515.0 38532.8 288729.0 289932.0 290211.0 290425.0 291333.5 28351.2 32378.0 32577.2 33772.5 34266.3 42232.6 43323.0 43348.1 43538.0 43586.6 23662.8 27544.0 27967.4 29553.9 30148.2 28841.6 29603.6 30010.9 30627.2 30664.5 34689.8 35426.9 35539.5 36191.9 36281.3 29311.9 29673.6 31161.2 31425.0 31644.4 22276.4 22443.2 24063.0 24479.5 25018.0 07:54:37 INFO - PID 1648 | 07:54:37 INFO - PID 1648 | [#2] jquery.html Cycles:180 Average:41442.22 Median:2155.86 stddev:105891.37 (4911.8%) stddev-sans-first:106143.82 07:54:37 INFO - PID 1648 | Values: 508.0 517.2 517.8 527.8 539.7 15255.7 21105.8 21600.4 21792.0 22002.3 17973.3 18267.5 18298.8 22450.4 25050.4 6464.5 6526.8 6544.6 6554.8 6574.9 6390.7 6491.4 6502.9 6517.9 6518.8 6086.5 6108.3 6382.9 6458.5 6461.0 6387.0 6388.8 6443.9 6446.6 6452.7 5884.1 5909.4 5924.0 5935.4 5942.4 28145.7 29582.7 29625.6 30065.7 30081.7 2134.4 2228.8 2315.5 2341.9 2345.1 18284.7 18854.4 18860.2 18865.9 18952.8 2151.1 2157.3 2199.9 2211.0 2213.6 155481.0 181764.2 184512.0 187123.1 188420.1 15302.2 15525.5 15588.8 15632.4 15840.0 20374.6 20375.1 20378.5 20401.0 20414.2 8511.8 10139.3 10273.6 10334.6 10350.7 9628.9 9647.7 9658.9 9663.5 9753.6 8849.5 8997.8 9030.3 9033.0 9083.6 2154.4 2585.7 2671.1 2671.1 2685.6 982.3 997.6 1025.2 1052.4 1053.8 17907.1 21119.0 22317.3 22426.4 22712.4 954.0 972.7 1020.5 1056.4 1069.0 11627.2 11793.4 11843.8 11853.3 11917.0 593041.0 617098.0 620688.0 624406.0 631432.0 163825.0 166890.0 170051.0 170797.0 175001.1 1156.5 1180.5 1214.9 1218.6 1222.8 22867.5 23153.7 24010.9 24236.0 24357.4 71557.6 73279.5 73770.0 74044.0 74505.0 95964.0 96114.5 99082.5 99909.0 100807.0 11786.3 11843.7 11905.8 11924.9 12120.2 15599.5 18368.7 18460.7 18559.4 18630.9 14276.9 14690.9 14985.0 15081.6 15088.6 12538.9 13718.9 13779.8 13892.3 13948.5 13588.9 14980.1 16046.1 16076.2 16090.2 12004.8 12051.7 12057.5 12079.9 12144.8 15274.8 15418.8 15558.8 15604.1 15658.8 07:54:37 INFO - PID 1648 | 07:54:37 INFO - PID 1648 | [#3] mootools.html Cycles:180 Average:8527.82 Median:2827.66 stddev:5761.47 (203.8%) stddev-sans-first:5752.85 07:54:37 INFO - PID 1648 | Values: 1416.4 1484.1 1486.0 1504.5 1508.6 13864.0 14416.9 14428.9 14441.6 14459.9 11756.2 11955.1 12090.7 12102.6 13820.9 2796.7 2851.4 2863.0 2863.9 2864.0 2799.8 2834.3 2843.3 2850.9 2854.2 2788.5 2802.7 2817.7 2818.5 2821.0 1939.0 1992.5 2424.1 2766.6 2784.7 2394.8 2776.1 2844.1 2869.3 2870.9 13858.7 13950.5 13984.5 14054.6 14086.3 7600.8 7918.6 7929.9 7935.2 7944.6 4606.7 4823.3 4859.2 4887.8 4944.6 1365.3 1381.4 1403.7 1410.3 1431.1 20811.0 21113.6 21147.6 21163.0 21244.5 7171.0 7217.6 7241.5 7382.4 7473.2 17027.7 17036.8 17047.0 17053.1 17081.3 7864.3 7927.4 7964.1 7966.1 7973.6 6873.8 7006.4 7061.1 7071.9 7164.7 4717.8 5403.6 5590.0 5594.4 5637.0 1711.6 1714.1 1716.1 1724.6 1730.1 753.3 807.6 832.0 849.7 872.7 6247.3 7207.4 7233.9 7442.6 7511.1 666.0 688.3 717.3 720.7 725.1 4330.7 4851.5 4902.8 5307.7 5460.9 18739.0 18794.2 19255.8 19274.7 19315.4 18685.9 18783.7 18785.1 19232.0 19286.7 9658.6 9780.3 9803.5 9803.5 9814.3 6540.3 6613.4 8067.1 8341.1 8733.4 14427.4 16649.6 17298.4 17493.4 17683.5 14892.6 17098.1 17696.9 17701.5 18173.5 10969.2 11144.3 11152.1 11159.7 11183.7 11855.5 12118.9 12302.8 12503.2 12823.3 9213.7 10096.4 10319.9 10378.2 10694.3 8579.4 9974.7 10052.8 10166.6 10559.5 9464.6 9557.0 9560.7 9561.0 9569.8 6583.3 6727.3 6792.4 6924.8 6944.3 5975.9 6919.5 7237.2 7247.7 7273.7 07:54:37 INFO - PID 1648 | 07:54:37 INFO - PID 1648 | [#4] prototype.html Cycles:180 Average:5591.53 Median:6216.13 stddev:1727.63 (27.8%) stddev-sans-first:1688.08 07:54:37 INFO - PID 1648 | Values: 406.8 420.2 457.8 462.4 468.5 5008.5 6392.8 6404.9 6559.7 6577.7 5123.5 6224.3 6295.9 6348.7 6475.6 4973.4 4978.9 4988.7 5002.0 5019.0 4149.9 4174.4 4960.5 4988.9 5002.2 4093.5 4573.5 4768.5 4799.1 5003.2 4761.9 4765.0 4772.4 4776.3 4802.9 3751.7 3903.5 4145.5 4148.4 4174.8 5158.4 5707.3 6174.5 6191.6 6207.9 5624.8 5673.2 5690.4 5714.3 5726.8 6884.2 6914.4 6919.2 6919.7 6926.4 4221.0 4806.0 4807.7 4816.5 4819.6 5699.6 7398.4 7426.9 7434.8 7435.1 4878.6 4885.8 4913.1 4923.9 4932.3 5014.9 5027.9 5030.6 5044.0 5054.4 5247.7 5287.7 5716.6 5726.9 5735.8 6103.9 6609.0 6839.8 7014.9 7037.5 1471.1 1660.6 1707.7 1713.8 1723.8 975.7 991.7 993.6 1021.7 1104.5 6175.8 6359.2 6517.5 6549.9 6635.8 7216.8 7221.3 7246.4 7259.0 7270.7 6481.4 6513.0 6514.4 6537.9 6551.0 3699.2 3748.6 3752.8 3755.1 3756.9 6317.6 6414.5 7153.7 7383.9 7415.6 7151.7 7372.7 7392.8 7398.0 7406.3 5884.9 6542.4 6935.5 7366.3 7400.0 6592.9 6670.6 7181.8 7225.4 7251.3 5790.8 6872.8 7060.2 7087.9 7096.6 5594.7 6776.7 7354.9 7387.9 7456.0 4023.4 4782.3 4866.5 4876.8 4902.6 5851.3 6938.7 7116.6 7136.8 7274.3 6353.8 6657.6 6850.6 6953.5 6956.5 5789.5 6458.5 6805.9 6887.7 6925.2 6321.8 6436.7 6792.4 6821.9 6822.9 6200.4 6937.0 6953.4 6969.2 6974.9 5668.5 5945.5 6190.7 6751.9 6786.6 07:54:37 INFO - PID 1648 | 07:54:37 INFO - PID 1648 | [#5] yui.html Cycles:180 Average:26153.13 Median:17197.03 stddev:41695.73 (242.5%) stddev-sans-first:41769.04 07:54:37 INFO - PID 1648 | Values: 740.6 820.2 824.5 856.7 863.8 16698.0 16900.7 17081.4 17149.1 17155.2 17068.8 17363.9 18600.1 18622.3 18670.3 937.5 937.9 956.9 958.4 959.8 995.0 998.4 999.4 1000.6 1001.6 1027.2 1046.4 1054.4 1055.8 1056.4 917.2 943.3 1014.1 1017.0 1023.4 735.4 744.6 746.9 748.9 751.5 15390.5 16906.9 17238.8 17385.9 17522.0 17969.2 18050.7 18200.7 18290.9 18442.4 14804.3 15103.0 15278.0 15286.0 15305.8 3584.2 3585.6 3589.0 3615.7 3616.4 119849.0 139841.0 142840.6 159602.4 166205.0 13761.9 13880.6 14011.6 14048.0 14131.8 26468.2 27460.9 27496.8 27575.0 27693.0 7910.2 7910.7 7980.2 7986.4 8010.4 3989.1 4073.7 4078.0 4083.0 4092.0 4707.2 4708.9 4741.5 4755.2 4798.6 780.9 866.7 889.6 896.1 896.6 644.6 647.9 649.1 658.8 668.5 9139.0 11156.7 11779.4 11834.6 12150.4 634.2 645.3 646.1 666.0 673.5 5321.8 5564.7 5590.8 5689.8 5910.8 128061.0 145845.0 153045.0 153904.0 162930.0 127793.0 132452.3 135598.0 135679.0 136741.7 114748.0 115389.4 116648.0 121726.4 122721.4 10426.1 10936.5 11039.6 11351.4 11570.4 48340.0 51498.0 51751.2 51846.0 53503.7 59822.0 67999.0 68222.0 68709.0 70417.0 13891.0 14501.0 14549.3 14638.7 14664.1 14321.9 15134.4 15396.5 15666.3 15703.4 14031.7 14407.6 14597.1 14848.4 15115.4 13213.9 13307.0 13562.8 13589.1 13623.7 14267.3 14514.3 14522.9 14563.9 14784.0 9928.4 10151.2 10265.9 10409.4 10786.5 11427.5 11429.2 11514.3 11697.5 11920.9 07:54:37 INFO - PID 1648 | -------- Summary: end -------- 07:54:37 INFO - PID 1648 | 07:54:37 INFO - TEST-INFO | 1648: exit 0 07:54:37 INFO - TEST-OK | dromaeo_css | took 1143432ms 07:54:37 INFO - TEST-START | kraken 07:54:37 INFO - operating with platform_type : mac_ 07:54:37 INFO - Initialising browser for kraken test... 07:54:37 INFO - Application command: /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49222/getInfo.html -foreground -profile /var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/tmpYbEDrF/profile 07:54:37 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49222/getInfo.html -foreground) 07:55:11 INFO - TEST-INFO | 1658: killed by SIGTERM 07:55:11 INFO - Browser initialized. 07:55:11 INFO - Running cycle 1/1 for kraken test... 07:55:11 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/tmpYbEDrF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:55:12 INFO - PID 1662 | 2017-06-13 07:55:12.691 plugin-container[1663:10445] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9543, name = 'com.apple.tsm.portname' 07:55:12 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:55:12 INFO - PID 1662 | 2017-06-13 07:55:12.691 plugin-container[1663:10445] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x451b, name = 'com.apple.CFPasteboardClient' 07:55:12 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:55:12 INFO - PID 1662 | 2017-06-13 07:55:12.691 plugin-container[1663:10445] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:55:13 INFO - PID 1662 | 2017-06-13 07:55:13.494 plugin-container[1664:10488] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 07:55:13 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:55:13 INFO - PID 1662 | 2017-06-13 07:55:13.495 plugin-container[1664:10488] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9423, name = 'com.apple.CFPasteboardClient' 07:55:13 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:55:13 INFO - PID 1662 | 2017-06-13 07:55:13.495 plugin-container[1664:10488] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:55:22 INFO - PID 1662 | RSS: Main: 214192128 07:55:22 INFO - PID 1662 | 07:55:25 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49222/tests/kraken/driver.html?testName=audio-beat-detection) 07:55:26 INFO - PID 1662 | RSS: Main: 227979264 07:55:26 INFO - PID 1662 | 07:55:29 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49222/tests/kraken/driver.html?testName=audio-dft) 07:55:29 INFO - PID 1662 | RSS: Main: 237277184 07:55:29 INFO - PID 1662 | 07:55:32 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49222/tests/kraken/driver.html?testName=audio-fft) 07:55:33 INFO - PID 1662 | RSS: Main: 241283072 07:55:33 INFO - PID 1662 | 07:55:35 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49222/tests/kraken/driver.html?testName=audio-oscillator) 07:55:36 INFO - PID 1662 | RSS: Main: 243556352 07:55:36 INFO - PID 1662 | 07:55:38 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49222/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:55:38 INFO - PID 1662 | RSS: Main: 244080640 07:55:38 INFO - PID 1662 | 07:55:41 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49222/tests/kraken/driver.html?testName=imaging-darkroom) 07:55:42 INFO - PID 1662 | RSS: Main: 248520704 07:55:42 INFO - PID 1662 | 07:55:45 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49222/tests/kraken/driver.html?testName=imaging-desaturate) 07:55:45 INFO - PID 1662 | RSS: Main: 248152064 07:55:45 INFO - PID 1662 | 07:55:48 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49222/tests/kraken/driver.html?testName=json-parse-financial) 07:55:49 INFO - PID 1662 | RSS: Main: 252604416 07:55:49 INFO - PID 1662 | 07:55:50 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49222/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:55:51 INFO - PID 1662 | RSS: Main: 252137472 07:55:51 INFO - PID 1662 | 07:55:53 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:55:53 INFO - PID 1662 | RSS: Main: 252837888 07:55:53 INFO - PID 1662 | 07:55:55 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:55:56 INFO - PID 1662 | RSS: Main: 252366848 07:55:56 INFO - PID 1662 | 07:55:59 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:55:59 INFO - PID 1662 | RSS: Main: 252706816 07:55:59 INFO - PID 1662 | 07:56:02 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:56:03 INFO - PID 1662 | RSS: Main: 252870656 07:56:03 INFO - PID 1662 | 07:56:05 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49222/tests/kraken/driver.html?testName=ai-astar) 07:56:05 INFO - PID 1662 | RSS: Main: 254574592 07:56:05 INFO - PID 1662 | 07:56:05 INFO - PID 1662 | __start_tp_report 07:56:05 INFO - PID 1662 | _x_x_mozilla_page_load 07:56:05 INFO - PID 1662 | _x_x_mozilla_page_load_details 07:56:05 INFO - PID 1662 | |i|pagename|runs| 07:56:05 INFO - PID 1662 | |0;ai-astar;101;123;145;116;120;168;169;151;112;119 07:56:05 INFO - PID 1662 | |1;audio-beat-detection;138;108;139;164;142;136;135;126;131;100 07:56:05 INFO - PID 1662 | |2;audio-dft;197;197;195;195;200;204;203;197;202;195 07:56:05 INFO - PID 1662 | |3;audio-fft;70;78;82;145;57;57;61;63;126;63 07:56:05 INFO - PID 1662 | |4;audio-oscillator;69;72;119;66;70;75;109;81;95;125 07:56:05 INFO - PID 1662 | |5;imaging-gaussian-blur;95;90;89;89;92;90;90;89;89;90 07:56:05 INFO - PID 1662 | |6;imaging-darkroom;97;98;96;97;98;96;119;101;96;98 07:56:05 INFO - PID 1662 | |7;imaging-desaturate;91;94;92;91;93;94;91;93;93;123 07:56:05 INFO - PID 1662 | |8;json-parse-financial;51;49;49;49;51;52;51;52;50;42 07:56:05 INFO - PID 1662 | |9;json-stringify-tinderbox;54;54;54;57;60;60;56;54;55;55 07:56:05 INFO - PID 1662 | |10;stanford-crypto-aes;78;94;78;70;100;97;80;82;78;80 07:56:05 INFO - PID 1662 | |11;stanford-crypto-ccm;135;119;135;133;132;144;135;140;142;160 07:56:05 INFO - PID 1662 | |12;stanford-crypto-pbkdf2;206;219;214;171;175;170;179;182;174;175 07:56:05 INFO - PID 1662 | |13;stanford-crypto-sha256-iterative;70;63;66;73;92;92;86;74;68;66 07:56:05 INFO - PID 1662 | __end_tp_report 07:56:05 INFO - PID 1662 | __start_cc_report 07:56:05 INFO - PID 1662 | _x_x_mozilla_cycle_collect,1041 07:56:05 INFO - PID 1662 | __end_cc_report 07:56:05 INFO - PID 1662 | __startTimestamp1497365765553__endTimestamp 07:56:05 INFO - PID 1662 | 07:56:05 INFO - PID 1662 | ------- Summary: start ------- 07:56:05 INFO - PID 1662 | Number of tests: 14 07:56:05 INFO - PID 1662 | 07:56:05 INFO - PID 1662 | [#0] ai-astar Cycles:10 Average:132.40 Median:134.00 stddev:24.05 (17.9%) stddev-sans-first:22.66 07:56:05 INFO - PID 1662 | Values: 101.0 123.0 145.0 116.0 120.0 168.0 169.0 151.0 112.0 119.0 07:56:05 INFO - PID 1662 | 07:56:05 INFO - PID 1662 | [#1] audio-beat-detection Cycles:10 Average:131.90 Median:137.00 stddev:17.86 (13.0%) stddev-sans-first:18.81 07:56:05 INFO - PID 1662 | Values: 138.0 108.0 139.0 164.0 142.0 136.0 135.0 126.0 131.0 100.0 07:56:05 INFO - PID 1662 | 07:56:05 INFO - PID 1662 | [#2] audio-dft Cycles:10 Average:198.50 Median:198.50 stddev:3.47 (1.7%) stddev-sans-first:3.64 07:56:05 INFO - PID 1662 | Values: 197.0 197.0 195.0 195.0 200.0 204.0 203.0 197.0 202.0 195.0 07:56:05 INFO - PID 1662 | 07:56:05 INFO - PID 1662 | [#3] audio-fft Cycles:10 Average:80.20 Median:63.00 stddev:30.63 (48.6%) stddev-sans-first:32.27 07:56:05 INFO - PID 1662 | Values: 70.0 78.0 82.0 145.0 57.0 57.0 61.0 63.0 126.0 63.0 07:56:05 INFO - PID 1662 | 07:56:05 INFO - PID 1662 | [#4] audio-oscillator Cycles:10 Average:88.10 Median:71.00 stddev:22.27 (31.4%) stddev-sans-first:22.52 07:56:05 INFO - PID 1662 | Values: 69.0 72.0 119.0 66.0 70.0 75.0 109.0 81.0 95.0 125.0 07:56:05 INFO - PID 1662 | 07:56:05 INFO - PID 1662 | [#5] imaging-gaussian-blur Cycles:10 Average:90.30 Median:90.00 stddev:1.89 (2.1%) stddev-sans-first:0.97 07:56:05 INFO - PID 1662 | Values: 95.0 90.0 89.0 89.0 92.0 90.0 90.0 89.0 89.0 90.0 07:56:05 INFO - PID 1662 | 07:56:05 INFO - PID 1662 | [#6] imaging-darkroom Cycles:10 Average:99.60 Median:97.00 stddev:6.98 (7.2%) stddev-sans-first:7.34 07:56:05 INFO - PID 1662 | Values: 97.0 98.0 96.0 97.0 98.0 96.0 119.0 101.0 96.0 98.0 07:56:05 INFO - PID 1662 | 07:56:05 INFO - PID 1662 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:93.00 stddev:9.73 (10.5%) stddev-sans-first:10.19 07:56:05 INFO - PID 1662 | Values: 91.0 94.0 92.0 91.0 93.0 94.0 91.0 93.0 93.0 123.0 07:56:05 INFO - PID 1662 | 07:56:05 INFO - PID 1662 | [#8] json-parse-financial Cycles:10 Average:49.60 Median:51.00 stddev:2.91 (5.7%) stddev-sans-first:3.05 07:56:05 INFO - PID 1662 | Values: 51.0 49.0 49.0 49.0 51.0 52.0 51.0 52.0 50.0 42.0 07:56:05 INFO - PID 1662 | 07:56:05 INFO - PID 1662 | [#9] json-stringify-tinderbox Cycles:10 Average:55.90 Median:55.50 stddev:2.38 (4.3%) stddev-sans-first:2.42 07:56:05 INFO - PID 1662 | Values: 54.0 54.0 54.0 57.0 60.0 60.0 56.0 54.0 55.0 55.0 07:56:05 INFO - PID 1662 | 07:56:05 INFO - PID 1662 | [#10] stanford-crypto-aes Cycles:10 Average:83.70 Median:80.00 stddev:9.80 (12.2%) stddev-sans-first:10.17 07:56:05 INFO - PID 1662 | Values: 78.0 94.0 78.0 70.0 100.0 97.0 80.0 82.0 78.0 80.0 07:56:05 INFO - PID 1662 | 07:56:05 INFO - PID 1662 | [#11] stanford-crypto-ccm Cycles:10 Average:137.50 Median:137.50 stddev:10.47 (7.6%) stddev-sans-first:11.07 07:56:05 INFO - PID 1662 | Values: 135.0 119.0 135.0 133.0 132.0 144.0 135.0 140.0 142.0 160.0 07:56:05 INFO - PID 1662 | 07:56:05 INFO - PID 1662 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.50 Median:180.50 stddev:18.86 (10.5%) stddev-sans-first:18.64 07:56:05 INFO - PID 1662 | Values: 206.0 219.0 214.0 171.0 175.0 170.0 179.0 182.0 174.0 175.0 07:56:05 INFO - PID 1662 | 07:56:05 INFO - PID 1662 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.00 Median:73.50 stddev:10.97 (14.9%) stddev-sans-first:11.49 07:56:05 INFO - PID 1662 | Values: 70.0 63.0 66.0 73.0 92.0 92.0 86.0 74.0 68.0 66.0 07:56:05 INFO - PID 1662 | -------- Summary: end -------- 07:56:05 INFO - PID 1662 | 07:56:06 INFO - TEST-INFO | 1662: exit 0 07:56:06 INFO - TEST-OK | kraken | took 88757ms 07:56:06 INFO - SUITE-END | took 1232s 07:56:06 INFO - Completed test suite (00:20:32) 07:56:06 INFO - javascript benchmark 07:56:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8376.863112905212, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3543.7165026797857, 3635.5273814404122, 3638.599754027057, 3693.224422871197, 3700.0749812546865, 6249.5939063460555, 6257.530685198605, 6281.968590157043, 6297.748090076398, 6319.083732858733, 6281.623102613835, 6310.652914089735, 6322.810315690525, 6345.23857137143, 6369.840753981152, 5604.355499117592, 5606.719664016803, 5621.578381621384, 5652.406497317784, 5702.686352250625, 5611.046122159243, 5673.432656734335, 5679.432056794308, 5680.602357834953, 5690.971545142268, 5406.83779486616, 5464.754086066137, 5532.3637781655225, 5613.859653508654, 5643.097104463266, 4024.939625905614, 5575.637583557056, 5587.972060139714, 5645.266115404971, 5650.802221922213, 5461.754221060022, 5560.638558493685, 5575.498205161514, 5589.776408943638, 5636.633618814764, 5765.1640512125505, 6043.609969706992, 6308.747650093991, 6317.4946004319545, 6327.335629758901, 6675.532712710112, 6912.0, 6931.202911665122, 6932.584044957319, 6956.930430695679, 5573.470520300565, 6441.484681225491, 6887.035814985906, 6905.17137943445, 6987.161540615159, 6419.165508483868, 6459.580127291805, 6471.611703297817, 6529.869402611921, 6531.608103513804, 7160.606166660779, 7169.713211471482, 7176.641167941582, 7181.605011724405, 7185.389241914391, 6584.078229047937, 6668.699908504129, 6698.665066746643, 6801.149856267966, 6817.420519255819, 6848.14398200225, 6874.415674667609, 6928.61892595902, 7002.894956575656, 7017.0, 5809.88380232393, 6823.378852287407, 6875.5530890491955, 6910.8617827644175, 6941.618210998444, 5915.1718759373725, 6784.846576082078, 6887.655617219119, 6910.239873613898, 6965.721371145199, 5436.945630543723, 5454.127339625641, 5456.672599644034, 5457.099578569571, 5472.5348345391085, 4757.500462451462, 4790.880227994328, 4832.758362081952, 4924.532169443897, 4931.038447502774, 5974.551908606872, 6258.780942667076, 6320.494360451153, 6325.272593651697, 6400.135981642512, 7014.789556313319, 7026.824329391806, 7027.824304392431, 7051.435885129177, 7056.435485161175, 6461.741530338733, 6467.51493637979, 6477.643729594917, 6480.222373315232, 6492.967535162388, 6067.1202675611785, 6181.876362472815, 6206.596571222856, 6229.221347331583, 6250.3749625037135, 6941.305869413019, 7046.436285097077, 7124.3944264738075, 7130.607816570035, 7155.39179169766, 6736.831579210559, 6868.587884726932, 6947.23580406165, 6955.930440695731, 6971.755988540377, 6564.5076619254705, 7166.605836679033, 7196.172440169447, 7203.351698347174, 7208.207097219407, 6919.169699636076, 6922.411595014481, 6923.099997000358, 6943.548669336477, 6950.54821436605, 7045.647717614202, 7051.717931282691, 7059.18819335783, 7080.221175670569, 7090.149182098181, 6554.508411869224, 6627.436667883091, 6638.734450621921, 6808.978653202059, 6809.012693159563, 5677.410325108976, 6619.040239165391, 6674.6662666865495, 6760.763373281911, 6769.492288078315, 6593.604383736991, 6648.501362397743, 6664.433523150587, 6694.631795251308, 6707.966460167668, 6812.625305608239, 6819.011243369783, 6820.283870193757, 6822.249552549117, 6824.556403833735, 6434.195725534309, 6463.385978332051, 6502.057201705821, 6504.707288172138, 6555.410013098844], "value": 6270.46176255138, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [789.3882241263024, 819.8483280593094, 825.0594322472381, 843.8649816029434, 855.1961156512878, 37217.44173837391, 37906.620933790655, 37913.431298530544, 37963.81018094913, 38020.85937421882, 37414.81292593533, 37720.05699857498, 37735.62264377355, 37802.43296350557, 37868.24263514728, 7786.143524212346, 8000.239977202156, 8005.799855003643, 8006.239407256301, 8007.919920800805, 7991.640376183057, 8099.838003239932, 8112.18878112187, 8117.431779775418, 8143.75568732939, 8047.517148971051, 8068.273855353017, 8072.919270807305, 8079.717209897654, 8114.472559283628, 7035.788926332219, 8061.072976607677, 8088.676452941875, 8090.838183236361, 8113.634886430124, 7856.685732570691, 7869.2130786921425, 7897.960510197412, 7913.485623434516, 7924.564148971805, 35304.76433324847, 35694.0, 35712.0, 35765.64234357649, 36064.63935360639, 3645.0, 3666.138457462494, 3670.6696397324367, 3679.8528058877614, 3700.3154416433044, 22793.0, 29925.70074299251, 29989.250268743457, 30052.8497357514, 30192.698073019208, 3059.2351912021995, 3121.719045285935, 3132.733717634004, 3138.466460701686, 3142.732867706248, 260055.6997215002, 264063.6796816023, 264525.67737161386, 328632.0, 328838.7116128832, 46837.53162468332, 47358.0, 47378.76310618425, 47595.76202118967, 47619.76190119027, 48206.517934820906, 48448.0, 48875.266870996966, 48900.755496223006, 48956.26565601519, 14960.877934154982, 15024.924875375771, 15045.398184072734, 15154.16652084124, 15163.696726065638, 12345.814812777815, 12535.185212961129, 12554.372281385895, 12567.811482827765, 12569.308688022247, 13211.603651890458, 13227.801582976263, 13237.0, 13270.469181232642, 13292.73414531704, 5729.88540229193, 5770.047942089592, 5792.0, 5797.275340582427, 5817.592768506164, 862.2283056664251, 863.6329559937002, 965.3352382086213, 990.5443495991924, 1045.9477026148813, 22988.65517017246, 22994.7700522996, 23065.308040758806, 23256.06975720986, 23978.0, 1039.8232300508932, 1066.3814987307344, 1101.8457415961773, 1116.7989761842784, 1160.5763896177834, 11547.942260288813, 11883.70290742721, 12295.016398688083, 12428.440720167615, 12481.375931203404, 323128.0, 323555.0, 325458.0, 325989.0, 325992.0, 53812.73093634507, 53883.73058134684, 53939.0, 53957.0, 53964.460355395946, 1442.8629280218363, 1536.9932694085392, 1541.3372249932636, 1545.1810540413599, 1547.5821528187325, 22474.438139046655, 23052.654210187196, 23216.0, 23333.649995250424, 23969.880150599136, 38385.616143839325, 38397.424038638885, 38461.0, 38515.0, 38532.844014678485, 288729.0, 289932.0, 290211.0, 290425.0, 291333.543332282, 28351.17259170068, 32378.028659140262, 32577.18557036093, 33772.493412599324, 34266.314673705885, 42232.57767422409, 43323.0, 43348.13303733971, 43538.0, 43586.56413435952, 23662.81685915732, 27544.0, 27967.44065118727, 29553.85223073871, 30148.24629384283, 28841.56737648895, 29603.555946660388, 30010.949616763308, 30627.23431914131, 30664.540031899094, 34689.82655086708, 35426.937191883255, 35539.466907995884, 36191.91424257276, 36281.274374511835, 29311.85344073266, 29673.554896676134, 31161.220969475944, 31425.0, 31644.367112657157, 22276.443088922908, 22443.21448749286, 24063.037478500664, 24479.51040979135, 25018.0], "value": 17131.279953503123, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [507.9949200507994, 517.2138349708445, 517.7747679759308, 527.81526465737, 539.7004662412364, 15255.6948861023, 21105.788942110576, 21600.351989440303, 21792.0, 22002.33992980211, 17973.281068757267, 18267.451976440694, 18298.8105773125, 22450.43873903153, 25050.373740656494, 6464.547481676284, 6526.804195874108, 6544.574602650812, 6554.80335589933, 6574.8685026299445, 6390.71241794118, 6491.383318584727, 6502.869942601145, 6517.902231466532, 6518.837029074288, 6086.482648974842, 6108.297545782225, 6382.872342553146, 6458.515611329146, 6461.0, 6387.041943708435, 6388.776392826252, 6443.871122577522, 6446.645434501101, 6452.677366131698, 5884.146798714205, 5909.379515150933, 5924.0, 5935.376785437553, 5942.405759424067, 28145.71854281472, 29582.70417295821, 29625.555616665766, 30065.69934300651, 30081.699183008328, 2134.3596920923815, 2228.7994080532667, 2315.455867871049, 2341.906323747048, 2345.0971376020357, 18284.725729114074, 18854.434366969013, 18860.15129319184, 18865.868047917167, 18952.81047189524, 2151.075037733774, 2157.3204440601316, 2199.9450013749783, 2210.9557808843892, 2213.557288542282, 155481.0, 181764.18235817604, 184512.0, 187123.06438467855, 188420.05789971098, 15302.234888255765, 15525.534233973, 15588.766168507482, 15632.3747050119, 15840.0, 20374.59250815005, 20375.08312125958, 20378.49053773638, 20401.0, 20414.183432662823, 8511.787205319917, 10139.340942838691, 10273.640422585173, 10334.638287660046, 10350.741231469123, 9628.90371096294, 9647.71056868281, 9658.9034109658, 9663.516824158763, 9753.609855605839, 8849.469031858109, 8997.820043599091, 9030.27757779376, 9032.954835225913, 9083.591238394289, 2154.396768904709, 2585.7071464267865, 2671.051776619311, 2671.1051797647615, 2685.5837345211526, 982.29274922056, 997.6209040564684, 1025.2310766924807, 1052.4422056310168, 1053.8103141434615, 17907.10464476771, 21119.0, 22317.3304800853, 22426.439339016655, 22712.432189195402, 954.0, 972.7227740094039, 1020.4795554267378, 1056.4400867540216, 1068.9625863094755, 11627.244229125079, 11793.410329483491, 11843.822342664867, 11853.348065856288, 11917.0, 593041.0, 617098.0, 620688.0, 624406.0, 631432.0, 163825.0, 166890.0, 170051.0, 170797.0, 175001.1249943742, 1156.472806409547, 1180.504188240941, 1214.9271043737403, 1218.6039537150461, 1222.8226907098422, 22867.54264914659, 23153.652695209923, 24010.87994560016, 24236.0, 24357.39106522351, 71557.64221178861, 73279.53440931258, 73770.0, 74044.0, 74505.0, 95964.0, 96114.51942740241, 99082.50458747661, 99909.0, 100807.0, 11786.292822430682, 11843.703907402383, 11905.82141267899, 11924.880751192724, 12120.212186207867, 15599.454019109276, 18368.724469132707, 18460.723089153944, 18559.44321670352, 18630.906845465684, 14276.928615356857, 14690.926545367205, 14984.951053426592, 15081.622959426102, 15088.622784430037, 12538.87461125377, 13718.93140534291, 13779.793303100261, 13892.30538473052, 13948.511802086878, 13588.932055340056, 14980.101193927963, 16046.117463539618, 16076.19619019021, 16090.195490225677, 12004.75990480168, 12051.734567870248, 12057.51769929193, 12079.912807847337, 12144.75710485803, 15274.770878437055, 15418.845811541742, 15558.844411556192, 15604.063756174666, 15658.765118523004], "value": 11902.720645093501, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1416.4051098538612, 1484.0798704803024, 1485.982102259952, 1504.5110339139776, 1508.6228442889278, 13864.029517933748, 14416.855831441682, 14428.92785536071, 14441.5667529974, 14459.927700361497, 11756.177067605271, 11955.103367247448, 12090.697732556691, 12102.576409825659, 13820.93089534551, 2796.6923638399758, 2851.4297140571866, 2862.969331040824, 2863.9140825775157, 2864.026231081432, 2799.7760179185616, 2834.319763256814, 2843.3176037750895, 2850.942981140376, 2854.1722900358873, 2788.5259505883946, 2802.733740294679, 2817.6900540940583, 2818.4504021715775, 2821.0, 1938.980610193894, 1992.5217947692595, 2424.1394305021818, 2766.585012248159, 2784.651918510186, 2394.8084153267696, 2776.1255204610484, 2844.132539575429, 2869.297022229546, 2870.9138725838257, 13858.653533661638, 13950.511732089328, 13984.510542130976, 14054.578362649137, 14086.2956852158, 7600.847983040364, 7918.564478953655, 7929.881051784228, 7935.166807485189, 7944.563049032301, 4606.746628935407, 4823.345347962147, 4859.173940430135, 4887.804487820483, 4944.579710724593, 1365.3173413293352, 1381.4405165907824, 1403.7332906747686, 1410.2807568140322, 1431.0698046269904, 20810.959452027488, 21113.5777284455, 21147.577048459098, 21163.04766285521, 21244.468888277763, 7170.964145179345, 7217.566945983258, 7241.493095483371, 7382.409407247407, 7473.215312392121, 17027.744583831252, 17036.82963170377, 17047.0, 17053.14734263282, 17081.316747330216, 7864.252895974873, 7927.365810735243, 7964.123946365895, 7966.083900351534, 7973.561454120079, 6873.759418420332, 7006.369426751515, 7061.1173603299585, 7071.92928070723, 7164.749233776897, 4717.773378921505, 5403.621746477709, 5589.972050139804, 5594.440555944454, 5636.97181514098, 1711.5549957011017, 1714.1401579286614, 1716.0818961855348, 1724.5516165796982, 1730.0571188702188, 753.2505157368454, 807.6325272001226, 831.9500829950223, 849.6558893648082, 872.7294538693026, 6247.2815626202655, 7207.423406127497, 7233.927660723431, 7442.553446793209, 7511.136219334737, 665.9633720145439, 688.2532452289222, 717.2791344698545, 720.7477382915937, 725.1407082607127, 4330.696851220384, 4851.51484851512, 4902.803887844518, 5307.734613269321, 5460.890782184334, 18739.0, 18794.154263058193, 19255.844649321087, 19274.71087933682, 19315.4205373839, 18685.906570467334, 18783.718244226075, 18785.06074696287, 19232.0, 19286.71069933952, 9658.565364558612, 9780.266480014028, 9803.509824508747, 9803.509824508888, 9814.312998090065, 6540.345965403421, 6613.371729685672, 8067.112617612175, 8341.082480927214, 8733.432326898985, 14427.42290308376, 16649.583760406087, 17298.394556190473, 17493.38773142934, 17683.469495915142, 14892.553223403314, 17098.145092745563, 17696.93818370902, 17701.46895593134, 18173.545661358043, 10969.23215374916, 11144.275622084862, 11152.052075573667, 11159.665210043711, 11183.664490065312, 11855.466504007169, 12118.878811212127, 12302.81545776832, 12503.187292825936, 12823.294718790556, 9213.677521286723, 10096.394216347042, 10319.94840025795, 10378.221633377376, 10694.304870183412, 8579.442336248125, 9974.650887218913, 10052.84791544345, 10166.593336266762, 10559.472026398804, 9464.621415143592, 9557.044295570666, 9560.713178604654, 9560.952195238979, 9569.760755980878, 6583.275839657538, 6727.293634168342, 6792.354726300993, 6924.792256232321, 6944.340287672663, 5975.880482390415, 6919.515633905779, 7237.1677257114, 7247.746328878464, 7273.709051638087], "value": 6223.558256857081, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [406.751881352375, 420.17435738773304, 457.75947183133707, 462.3942635147956, 468.5197672385804, 5008.524190201925, 6392.8401789955215, 6404.903926441101, 6559.704813283402, 6577.736890524386, 5123.462036486162, 6224.315325314212, 6295.937040629592, 6348.650824204667, 6475.611463312204, 4973.428055773578, 4978.87552811179, 4988.725620090893, 5002.0, 5019.0, 4149.917001659965, 4174.415581818548, 4960.479149689285, 4988.925166122511, 5002.1746411842005, 4093.4678491796026, 4573.474050484187, 4768.523147685239, 4799.13615549201, 5003.17447621142, 4761.928566072641, 4764.97617511912, 4772.355731976173, 4776.331313616097, 4802.8799280018275, 3751.6998640108727, 3903.4535165076913, 4145.502539695226, 4148.377743338493, 4174.812133454002, 5158.432572417031, 5707.316341679215, 6174.475169610589, 6191.628502289832, 6207.937920620781, 5624.831255062355, 5673.205751194795, 5690.430956904318, 5714.257146570961, 5726.770929162829, 6884.173899132086, 6914.446844252448, 6919.238883722786, 6919.7164935571145, 6926.376626103674, 4220.957790422087, 4806.0, 4807.711537307773, 4816.54242846929, 4819.566239038469, 5699.629524080921, 7398.371138453238, 7426.9257307427315, 7434.776956691254, 7435.144958329807, 4878.56092951636, 4885.755712214375, 4913.091078150554, 4923.87690307738, 4932.334134891744, 5014.924776128361, 5027.924581131286, 5030.622703297267, 5044.0, 5054.418741844662, 5247.711375874287, 5287.70917599528, 5716.57125715573, 5726.914096288559, 5735.824156048, 6103.938960610426, 6609.041688955074, 6839.84877614994, 7014.859702805916, 7037.472189585802, 1471.0585225455716, 1660.5952565493665, 1707.7267637177993, 1713.811480737118, 1723.7672914156678, 975.6633961283346, 991.7223177510309, 993.6124911284605, 1021.6973358967376, 1104.5140138339104, 6175.783847565313, 6359.2368915729485, 6517.500974775828, 6549.9345006550275, 6635.767748128792, 7216.819579510449, 7221.31397517235, 7246.420286377077, 7259.0, 7270.672819723125, 6481.4166724994975, 6513.0460861741285, 6514.413702766774, 6537.8692426152165, 6550.967245163743, 3699.204670995749, 3748.5876553579083, 3752.81235938202, 3755.0987762937243, 3756.887293381203, 6317.620073260875, 6414.5189110815945, 7153.675055606948, 7383.9261607384315, 7415.592142432218, 7151.678174482161, 7372.741954031583, 7392.815179620553, 7397.963010184914, 7406.296401841816, 5884.940710672035, 6542.443892269211, 6935.479839012094, 7366.263373662698, 7399.963000184857, 6592.934070659232, 6670.633115178518, 7181.784546463615, 7225.421966242583, 7251.274872512707, 5790.812883358985, 6872.828179295558, 7060.2465038633945, 7087.893681594884, 7096.645167741695, 5594.664320140804, 6776.695048722918, 7354.852902941804, 7387.9261207387235, 7456.0, 4023.416604592376, 4782.258749893772, 4866.513348665105, 4876.78054487556, 4902.646729569755, 5851.327097383685, 6938.653067346511, 7116.573005619679, 7136.785896423115, 7274.30894065063, 6353.809385718436, 6657.600543967377, 6850.588964662136, 6953.478489113236, 6956.478264130109, 5789.465791565322, 6458.483321334188, 6805.931940680728, 6887.690053947685, 6925.168979722465, 6321.778737744157, 6436.7448347573645, 6792.388685018372, 6821.8635627286185, 6822.897656535255, 6200.441960223601, 6937.0, 6953.374196322356, 6969.16370035599, 6974.895376569254, 5668.461496157858, 5945.494632956074, 6190.721417536131, 6751.898721519279, 6786.626735529593], "value": 5056.3354658305125, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [740.6148802622635, 820.1716266570769, 824.5464994253159, 856.6530555125173, 863.7926897544589, 16698.0, 16900.746488802662, 17081.40215092472, 17149.056801875893, 17155.228014739336, 17068.82931170688, 17363.91318043408, 18600.069996500188, 18622.348217812378, 18670.253189872423, 937.4515908193681, 937.8827646544182, 956.8660387545749, 958.4009993753904, 959.7696552827341, 994.9502524873764, 998.4209158687944, 999.4153420249127, 1000.6047611193574, 1001.639409812467, 1027.1885210683552, 1046.4140081554315, 1054.4306074719643, 1055.8257887448563, 1056.3872953686882, 917.1607978699482, 943.3302355327726, 1014.137982714694, 1016.9644062457778, 1023.3655133817081, 735.4116706634725, 744.597917124752, 746.8917007033949, 748.9026426564511, 751.462704166518, 15390.461333853262, 16906.915465422717, 17238.82761172385, 17385.913070434694, 17522.036288004154, 17969.191386387643, 18050.729239061424, 18200.72698909504, 18290.90854545732, 18442.35451759182, 14804.333804978802, 15103.0, 15278.0, 15286.0, 15305.846941530554, 3584.193556449804, 3585.587657419404, 3588.9641103588892, 3615.6926661233565, 3616.385214513539, 119849.0, 139841.0, 142840.57159428377, 159602.4039759611, 166205.0, 13761.862381376259, 13880.583582492542, 14011.649708757363, 14048.0, 14131.78802317966, 26468.20595382103, 27460.862695686395, 27496.762645680992, 27575.0, 27693.0, 7910.248526389983, 7910.683572657144, 7980.162082981319, 7986.401019923529, 8010.399220058519, 3989.122393073519, 4073.694472914484, 4077.959220407758, 4082.9591704083173, 4091.9974606221667, 4707.223308154184, 4708.858734237979, 4741.454732705783, 4755.2153894607, 4798.568128868418, 780.9062912450543, 866.7183165471247, 889.5596679643618, 896.0994200828126, 896.5920506169678, 644.6390021587926, 647.8769033883547, 649.1398896462207, 658.8319978405476, 668.4685674888475, 9138.954305228564, 11156.665300041012, 11779.411029448493, 11834.585789497498, 12150.392480375946, 634.2022599038261, 645.2901808011244, 646.0890144895675, 665.9766908158191, 673.5318953327488, 5321.775991521971, 5564.693941833157, 5590.8043218487155, 5689.829305120853, 5910.763569457174, 128061.0, 145845.0, 153045.0, 153904.0, 162930.0, 127793.0, 132452.3377383126, 135598.0, 135679.0, 136741.7313862483, 114748.0, 115389.4230528842, 116648.0, 121726.39136804259, 122721.38639306746, 10426.061654451136, 10936.45317734126, 11039.613613523488, 11351.37567433799, 11570.421478926188, 48340.0, 51497.97004059973, 51751.2237316433, 51846.0, 53503.73248133734, 59822.0, 67999.0, 68222.0, 68708.96936546055, 70417.0, 13891.0, 14500.984931054725, 14549.272536373352, 14638.707225855636, 14664.120152790867, 14321.928390357982, 15134.394624215349, 15396.46112386061, 15666.295016724047, 15703.371865125268, 14031.719365612835, 14407.639809004859, 14597.12417254968, 14848.40606375733, 15115.395384184509, 13213.933930330288, 13307.001974851732, 13562.796558051834, 13589.116707413987, 13623.65940851447, 14267.286635667968, 14514.346854391366, 14522.854771452572, 14563.85436145625, 14784.0, 9928.404295742279, 10151.238657100599, 10265.948670256601, 10409.375437473776, 10786.460676966277, 11427.485763140845, 11429.199956003, 11514.30914145154, 11697.473613687574, 11920.940395298316], "value": 8587.208799513679, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1504.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 123.0, 145.0, 116.0, 120.0, 168.0, 169.0, 151.0, 112.0, 119.0], "value": 132.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 108.0, 139.0, 164.0, 142.0, 136.0, 135.0, 126.0, 131.0, 100.0], "value": 131.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 197.0, 195.0, 195.0, 200.0, 204.0, 203.0, 197.0, 202.0, 195.0], "value": 198.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 78.0, 82.0, 145.0, 57.0, 57.0, 61.0, 63.0, 126.0, 63.0], "value": 80.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 72.0, 119.0, 66.0, 70.0, 75.0, 109.0, 81.0, 95.0, 125.0], "value": 88.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 90.0, 89.0, 89.0, 92.0, 90.0, 90.0, 89.0, 89.0, 90.0], "value": 90.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 98.0, 96.0, 97.0, 98.0, 96.0, 119.0, 101.0, 96.0, 98.0], "value": 99.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 94.0, 92.0, 91.0, 93.0, 94.0, 91.0, 93.0, 93.0, 123.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 49.0, 49.0, 49.0, 51.0, 52.0, 51.0, 52.0, 50.0, 42.0], "value": 49.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 54.0, 57.0, 60.0, 60.0, 56.0, 54.0, 55.0, 55.0], "value": 55.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 94.0, 78.0, 70.0, 100.0, 97.0, 80.0, 82.0, 78.0, 80.0], "value": 83.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 119.0, 135.0, 133.0, 132.0, 144.0, 135.0, 140.0, 142.0, 160.0], "value": 137.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [206.0, 219.0, 214.0, 171.0, 175.0, 170.0, 179.0, 182.0, 174.0, 175.0], "value": 186.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 63.0, 66.0, 73.0, 92.0, 92.0, 86.0, 74.0, 68.0, 66.0], "value": 75.0, "unit": "score"}]}]} 07:56:06 INFO - Return code: 0 07:56:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:56:06 INFO - # TBPL SUCCESS # 07:56:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:56:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:56:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:56:06 INFO - 2017-06-13 07:56:06.929 screenresolution[1670:10871] starting screenresolution argv=screenresolution get 07:56:06 INFO - 2017-06-13 07:56:06.931 screenresolution[1670:10871] Display 0: 1600x1200x32@60 07:56:06 INFO - 2017-06-13 07:56:06.937 screenresolution[1671:10874] starting screenresolution argv=screenresolution list 07:56:06 INFO - Available Modes on Display 0 07:56:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:56:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:56:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:56:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:56:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:56:07 INFO - Intel Iris: 07:56:07 INFO - Chipset Model: Intel Iris 07:56:07 INFO - Type: GPU 07:56:07 INFO - Bus: Built-In 07:56:07 INFO - VRAM (Dynamic, Max): 1536 MB 07:56:07 INFO - Vendor: Intel (0x8086) 07:56:07 INFO - Device ID: 0x0a2e 07:56:07 INFO - Revision ID: 0x0009 07:56:07 INFO - Displays: 07:56:07 INFO - Display: 07:56:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:56:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:56:07 INFO - Display Serial Number: 1600x1200 60 07:56:07 INFO - Main Display: Yes 07:56:07 INFO - Mirror: Off 07:56:07 INFO - Online: Yes 07:56:07 INFO - Rotation: Supported 07:56:07 INFO - Return code: 0 07:56:07 INFO - Running post-action listener: _resource_record_post_action 07:56:07 INFO - [mozharness: 2017-06-13 14:56:07.176444Z] Finished run-tests step (success) 07:56:07 INFO - Running post-run listener: _resource_record_post_run 07:56:07 INFO - Total resource usage - Wall time: 1254s; CPU: 53.0%; Read bytes: 42490368; Write bytes: 561848320; Read time: 419; Write time: 2001 07:56:07 INFO - TinderboxPrint: CPU usage
53.1% 07:56:07 INFO - TinderboxPrint: I/O read bytes / time
42,490,368 / 419 07:56:07 INFO - TinderboxPrint: I/O write bytes / time
561,848,320 / 2,001 07:56:07 INFO - TinderboxPrint: CPU idle
2,355.4 (46.9%) 07:56:07 INFO - TinderboxPrint: CPU system
1,013.4 (20.2%) 07:56:07 INFO - TinderboxPrint: CPU user
1,648.8 (32.9%) 07:56:07 INFO - TinderboxPrint: Swap in / out
284,655,616 / 0 07:56:07 INFO - install - Wall time: 21s; CPU: 55.0%; Read bytes: 241568256; Write bytes: 232404992; Read time: 17056; Write time: 564 07:56:07 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 07:56:07 INFO - run-tests - Wall time: 1234s; CPU: 53.0%; Read bytes: 35095040; Write bytes: 313194496; Read time: 331; Write time: 1398 07:56:07 INFO - Running post-run listener: _upload_blobber_files 07:56:07 INFO - Blob upload gear active. 07:56:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:56:07 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 07:56:07 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:56:07 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:56:08 INFO - (blobuploader) - INFO - Open directory for files ... 07:56:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:56:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:56:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:56:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:56:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:56:08 INFO - (blobuploader) - INFO - Done attempting. 07:56:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:56:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:56:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:56:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:56:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:56:09 INFO - (blobuploader) - INFO - Done attempting. 07:56:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 07:56:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:56:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:56:10 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 07:56:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:56:10 INFO - (blobuploader) - INFO - Done attempting. 07:56:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:56:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:56:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:56:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:56:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:56:13 INFO - (blobuploader) - INFO - Done attempting. 07:56:13 INFO - (blobuploader) - INFO - Iteration through files over. 07:56:13 INFO - Return code: 0 07:56:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:56:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:56:13 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7fe74f2e84799893eb75d7533f2d101ab3bdff398aaf30a2a65ce88d369de604c37271406f3a19702ee0547b24150335270279cf373e34b7e402407bc6f94f40", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/837611a126ba008251b1b8339feefdf7883410e25e9fe381965429ef7df43237fed0113ee62839ee946fe1cc67ec89b37dcf64d3a8bc3442eeb6d4a28d93bda9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/52fa113e773d1a0a9979f2be99980ecb73583985e67980db902176e9d6ad2a1af6c7854146357dbd53fbf826009177dd8499c938b20a1d93cbb1ac92d3b82d23", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e6649816c843add185822892aca02721ae6d5d35b047a4339eaeda29905b92cc33f141726387ce9c451a43f44a9db383c5c0a98ee1d11a265638be62cae6b072"} 07:56:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:56:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:56:13 INFO - Contents: 07:56:13 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7fe74f2e84799893eb75d7533f2d101ab3bdff398aaf30a2a65ce88d369de604c37271406f3a19702ee0547b24150335270279cf373e34b7e402407bc6f94f40", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/837611a126ba008251b1b8339feefdf7883410e25e9fe381965429ef7df43237fed0113ee62839ee946fe1cc67ec89b37dcf64d3a8bc3442eeb6d4a28d93bda9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/52fa113e773d1a0a9979f2be99980ecb73583985e67980db902176e9d6ad2a1af6c7854146357dbd53fbf826009177dd8499c938b20a1d93cbb1ac92d3b82d23", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e6649816c843add185822892aca02721ae6d5d35b047a4339eaeda29905b92cc33f141726387ce9c451a43f44a9db383c5c0a98ee1d11a265638be62cae6b072"} 07:56:13 INFO - Running post-run listener: copy_logs_to_upload_dir 07:56:13 INFO - Copying logs to upload dir... 07:56:13 INFO - mkdir: /builds/slave/test/build/upload/logs 07:56:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1336.029834 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 16 secs) (at 2017-06-13 07:56:13.322668) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-13 07:56:13.326294) ========= 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.19z0cLWthw/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.rgyKTtcATO/Listeners TMPDIR=/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7fe74f2e84799893eb75d7533f2d101ab3bdff398aaf30a2a65ce88d369de604c37271406f3a19702ee0547b24150335270279cf373e34b7e402407bc6f94f40", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/837611a126ba008251b1b8339feefdf7883410e25e9fe381965429ef7df43237fed0113ee62839ee946fe1cc67ec89b37dcf64d3a8bc3442eeb6d4a28d93bda9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/52fa113e773d1a0a9979f2be99980ecb73583985e67980db902176e9d6ad2a1af6c7854146357dbd53fbf826009177dd8499c938b20a1d93cbb1ac92d3b82d23", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e6649816c843add185822892aca02721ae6d5d35b047a4339eaeda29905b92cc33f141726387ce9c451a43f44a9db383c5c0a98ee1d11a265638be62cae6b072"} build_url:https://queue.taskcluster.net/v1/task/dEXmm596REujASXgiBhkdA/artifacts/public/build/firefox-55.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011199 build_url: 'https://queue.taskcluster.net/v1/task/dEXmm596REujASXgiBhkdA/artifacts/public/build/firefox-55.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7fe74f2e84799893eb75d7533f2d101ab3bdff398aaf30a2a65ce88d369de604c37271406f3a19702ee0547b24150335270279cf373e34b7e402407bc6f94f40", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/837611a126ba008251b1b8339feefdf7883410e25e9fe381965429ef7df43237fed0113ee62839ee946fe1cc67ec89b37dcf64d3a8bc3442eeb6d4a28d93bda9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/52fa113e773d1a0a9979f2be99980ecb73583985e67980db902176e9d6ad2a1af6c7854146357dbd53fbf826009177dd8499c938b20a1d93cbb1ac92d3b82d23", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e6649816c843add185822892aca02721ae6d5d35b047a4339eaeda29905b92cc33f141726387ce9c451a43f44a9db383c5c0a98ee1d11a265638be62cae6b072"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-13 07:56:13.359792) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 07:56:13.360128) ========= 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.19z0cLWthw/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.rgyKTtcATO/Listeners TMPDIR=/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005209 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 07:56:13.411276) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-13 07:56:13.412017) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-13 07:56:13.412516) ========= ========= Total master_lag: 2.23 =========