builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0322 starttime: 1494718006.46 results: success (0) revision: 73b3fc64525b6816842c737e104ef2ac5482d217 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-13 16:26:46.456269) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-13 16:26:46.456883) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-13 16:26:46.457197) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-13 16:26:46.568450) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-13 16:26:46.568787) ========= 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.ky9jgJHvLk/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.uUUW5eRe5Q/Listeners TMPDIR=/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-13 16:26:46-- 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% 168M=0s 2017-05-13 16:26:46 (168 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.145683 ========= master_lag: 0.08 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-13 16:26:46.790348) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-13 16:26:46.790697) ========= 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.ky9jgJHvLk/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.uUUW5eRe5Q/Listeners TMPDIR=/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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.026239 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-13 16:26:46.838225) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-13 16:26:46.838555) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 73b3fc64525b6816842c737e104ef2ac5482d217 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 73b3fc64525b6816842c737e104ef2ac5482d217 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ky9jgJHvLk/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.uUUW5eRe5Q/Listeners TMPDIR=/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-13 16:26:46,944 truncating revision to first 12 chars 2017-05-13 16:26:46,944 Setting DEBUG logging. 2017-05-13 16:26:46,944 attempt 1/10 2017-05-13 16:26:46,944 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/73b3fc64525b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-13 16:26:48,068 unpacking tar archive at: mozilla-central-73b3fc64525b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.404998 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-13 16:26:48.265284) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-13 16:26:48.265584) ========= script_repo_revision: 73b3fc64525b6816842c737e104ef2ac5482d217 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-13 16:26:48.265969) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-13 16:26:48.266233) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-13 16:26:48.285366) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-05-13 16:26:48.285648) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ky9jgJHvLk/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.uUUW5eRe5Q/Listeners TMPDIR=/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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 16:26:48 INFO - MultiFileLogger online at 20170513 16:26:48 in /builds/slave/test 16:26:48 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 16:26:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:26:48 INFO - {'append_to_log': False, 16:26:48 INFO - 'base_work_dir': '/builds/slave/test', 16:26:48 INFO - 'blob_upload_branch': 'Firefox', 16:26:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:26:48 INFO - 'branch': 'Firefox', 16:26:48 INFO - 'buildbot_json_path': 'buildprops.json', 16:26:48 INFO - 'config_files': ('talos/mac_config.py',), 16:26:48 INFO - 'default_actions': ('clobber', 16:26:48 INFO - 'read-buildbot-config', 16:26:48 INFO - 'download-and-extract', 16:26:48 INFO - 'populate-webroot', 16:26:48 INFO - 'create-virtualenv', 16:26:48 INFO - 'install', 16:26:48 INFO - 'run-tests'), 16:26:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:26:48 INFO - 'download_minidump_stackwalk': True, 16:26:48 INFO - 'download_symbols': 'ondemand', 16:26:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:26:48 INFO - 'tooltool.py': '/tools/tooltool.py', 16:26:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:26:48 INFO - '/tools/misc-python/virtualenv.py')}, 16:26:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:26:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:26:48 INFO - 'gecko_profile': False, 16:26:48 INFO - 'gecko_profile_interval': 0, 16:26:48 INFO - 'installer_path': 'installer.exe', 16:26:48 INFO - 'log_level': 'info', 16:26:48 INFO - 'log_name': 'talos', 16:26:48 INFO - 'log_to_console': True, 16:26:48 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:26:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:26:48 INFO - 'opt_config_files': (), 16:26:48 INFO - 'pip_index': False, 16:26:48 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:26:48 INFO - 'cmd': ('bash', 16:26:48 INFO - '-c', 16:26:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:26:48 INFO - 'enabled': True, 16:26:48 INFO - 'halt_on_failure': False, 16:26:48 INFO - 'name': 'check_screen_resolution'},), 16:26:48 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:26:48 INFO - 'cmd': ('bash', 16:26:48 INFO - '-c', 16:26:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:26:48 INFO - 'enabled': True, 16:26:48 INFO - 'halt_on_failure': False, 16:26:48 INFO - 'name': 'check_screen_resolution'},), 16:26:48 INFO - 'run_cmd_checks_enabled': True, 16:26:48 INFO - 'suite': 'dromaeojs-e10s', 16:26:48 INFO - 'system_bits': '32', 16:26:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:26:48 INFO - 'title': 't-yosemite-r7-0322', 16:26:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:26:48 INFO - 'use_talos_json': True, 16:26:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:26:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:26:48 INFO - 'work_dir': 'build'} 16:26:48 INFO - [mozharness: 2017-05-13 23:26:48.495970Z] Running clobber step. 16:26:48 INFO - Running pre-action listener: _resource_record_pre_action 16:26:48 INFO - Running main action method: clobber 16:26:48 INFO - rmtree: /builds/slave/test/build 16:26:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:26:50 INFO - Running post-action listener: _resource_record_post_action 16:26:50 INFO - [mozharness: 2017-05-13 23:26:50.954687Z] Finished clobber step (success) 16:26:50 INFO - [mozharness: 2017-05-13 23:26:50.954770Z] Running read-buildbot-config step. 16:26:50 INFO - Running pre-action listener: _resource_record_pre_action 16:26:50 INFO - Running main action method: read_buildbot_config 16:26:50 INFO - Using buildbot properties: 16:26:50 INFO - { 16:26:50 INFO - "project": "", 16:26:50 INFO - "product": "firefox", 16:26:50 INFO - "who": "archaeopteryx@coole-files.de", 16:26:50 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 16:26:50 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 16:26:50 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 16:26:50 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 16:26:50 INFO - "stage_platform": "macosx64", 16:26:50 INFO - "basedir": "/builds/slave/test", 16:26:50 INFO - "buildnumber": 25, 16:26:50 INFO - "platform": "yosemite_r7", 16:26:50 INFO - "parent_task_id": "RNwKFkV7RymduV-VV4Eb5Q", 16:26:50 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 16:26:50 INFO - "slavebuilddir": "test", 16:26:50 INFO - "taskId": "TCE4nFqTTWqdbc7lkvNFZw", 16:26:50 INFO - "branch": "mozilla-central", 16:26:50 INFO - "script_repo_revision": "production", 16:26:50 INFO - "revision": "73b3fc64525b6816842c737e104ef2ac5482d217", 16:26:50 INFO - "slavename": "t-yosemite-r7-0322", 16:26:50 INFO - "repo_path": "mozilla-central" 16:26:50 INFO - } 16:26:50 INFO - Finding installer, test and symbols from parent task. 16:26:50 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TCE4nFqTTWqdbc7lkvNFZw'}, attempt #1 16:26:51 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TCE4nFqTTWqdbc7lkvNFZw'}, attempt #1 16:26:52 INFO - Task dependencies: dnHJSs8HTUmEgdeAGoL9Vg 16:26:52 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RNwKFkV7RymduV-VV4Eb5Q'}, attempt #1 16:26:52 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/RNwKFkV7RymduV-VV4Eb5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:26:52 INFO - Set test_url: https://queue.taskcluster.net/v1/task/RNwKFkV7RymduV-VV4Eb5Q/artifacts/public/build/test_packages.json 16:26:52 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/RNwKFkV7RymduV-VV4Eb5Q/artifacts/public/build/target.crashreporter-symbols.zip 16:26:52 INFO - Running post-action listener: _resource_record_post_action 16:26:52 INFO - [mozharness: 2017-05-13 23:26:52.658260Z] Finished read-buildbot-config step (success) 16:26:52 INFO - [mozharness: 2017-05-13 23:26:52.658410Z] Running download-and-extract step. 16:26:52 INFO - Running pre-action listener: _resource_record_pre_action 16:26:52 INFO - Running main action method: download_and_extract 16:26:52 INFO - mkdir: /builds/slave/test/build/tests 16:26: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')]} 16:26:52 INFO - trying https://queue.taskcluster.net/v1/task/RNwKFkV7RymduV-VV4Eb5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 16:26:52 INFO - Downloading https://queue.taskcluster.net/v1/task/RNwKFkV7RymduV-VV4Eb5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 16:26:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RNwKFkV7RymduV-VV4Eb5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 16:26:53 INFO - Downloaded 1325 bytes. 16:26:53 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 16:26:53 INFO - Using the following test package requirements: 16:26:53 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:26:53 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 16:26:53 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 16:26:53 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:26:53 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 16:26:53 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:26:53 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 16:26:53 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:26:53 INFO - u'jsshell-mac.zip'], 16:26:53 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:26:53 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 16:26:53 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 16:26:53 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:26:53 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 16:26:53 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:26:53 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 16:26:53 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:26:53 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 16:26:53 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:26:53 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 16:26:53 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:26:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RNwKFkV7RymduV-VV4Eb5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 16:26:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RNwKFkV7RymduV-VV4Eb5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:26:53 INFO - Fetch https://queue.taskcluster.net/v1/task/RNwKFkV7RymduV-VV4Eb5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 16:26:55 INFO - Content-Length response header: 56563585 16:26:55 INFO - Bytes received: 56563585 16:27:13 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 16:27:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RNwKFkV7RymduV-VV4Eb5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 16:27:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RNwKFkV7RymduV-VV4Eb5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:27:13 INFO - Fetch https://queue.taskcluster.net/v1/task/RNwKFkV7RymduV-VV4Eb5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 16:27:14 INFO - Content-Length response header: 56563585 16:27:14 INFO - Bytes received: 56563585 16:27:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RNwKFkV7RymduV-VV4Eb5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 16:27:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RNwKFkV7RymduV-VV4Eb5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:27:31 INFO - Fetch https://queue.taskcluster.net/v1/task/RNwKFkV7RymduV-VV4Eb5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 16:27:32 INFO - Content-Length response header: 14409411 16:27:32 INFO - Bytes received: 14409411 16:27:32 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')]} 16:27:32 INFO - trying https://queue.taskcluster.net/v1/task/RNwKFkV7RymduV-VV4Eb5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:27:32 INFO - Downloading https://queue.taskcluster.net/v1/task/RNwKFkV7RymduV-VV4Eb5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 16:27:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RNwKFkV7RymduV-VV4Eb5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 16:27:34 INFO - Downloaded 75903846 bytes. 16:27:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RNwKFkV7RymduV-VV4Eb5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:27:34 INFO - mkdir: /builds/slave/test/properties 16:27:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:27:34 INFO - Writing to file /builds/slave/test/properties/build_url 16:27:34 INFO - Contents: 16:27:34 INFO - build_url:https://queue.taskcluster.net/v1/task/RNwKFkV7RymduV-VV4Eb5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:27:34 INFO - Running post-action listener: _resource_record_post_action 16:27:34 INFO - Running post-action listener: set_extra_try_arguments 16:27:34 INFO - [mozharness: 2017-05-13 23:27:34.906137Z] Finished download-and-extract step (success) 16:27:34 INFO - [mozharness: 2017-05-13 23:27:34.906269Z] Running populate-webroot step. 16:27:34 INFO - Running pre-action listener: _resource_record_pre_action 16:27:34 INFO - Running main action method: populate_webroot 16:27:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:27:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:27:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:27:34 INFO - '--tppagecycles', 16:27:34 INFO - '7'], 16:27:34 INFO - 'tests': ['tsvgm']}}, 16:27:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:27:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:27:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:27:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:27:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:27:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:27:34 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:27:34 INFO - 'talos_options': ['--disable-e10s'], 16:27:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:27:34 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:27:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:27:34 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:27:34 INFO - 'talos_options': ['--disable-e10s'], 16:27:34 INFO - 'tests': ['damp', 'tps']}, 16:27:34 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:27:34 INFO - 'tests': ['damp', 'tps']}, 16:27:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:27:34 INFO - 'tests': ['dromaeo_dom']}, 16:27:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:27:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:27:34 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 16:27:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 16:27:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:27:34 INFO - 'tests': ['a11yr', 16:27:34 INFO - 'ts_paint', 16:27:34 INFO - 'tpaint', 16:27:34 INFO - 'sessionrestore', 16:27:34 INFO - 'sessionrestore_no_auto_restore', 16:27:34 INFO - 'tabpaint']}, 16:27:34 INFO - 'other-e10s': {'tests': ['a11yr', 16:27:34 INFO - 'ts_paint', 16:27:34 INFO - 'tpaint', 16:27:34 INFO - 'sessionrestore', 16:27:34 INFO - 'sessionrestore_no_auto_restore', 16:27:34 INFO - 'tabpaint']}, 16:27:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:27:34 INFO - 'ts_paint', 16:27:34 INFO - 'tpaint', 16:27:34 INFO - 'sessionrestore', 16:27:34 INFO - 'sessionrestore_no_auto_restore', 16:27:34 INFO - 'tabpaint']}, 16:27:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:27:34 INFO - 'ts_paint', 16:27:34 INFO - 'tpaint', 16:27:34 INFO - 'sessionrestore', 16:27:34 INFO - 'sessionrestore_no_auto_restore', 16:27:34 INFO - 'tabpaint']}, 16:27:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:27:34 INFO - 'tests': ['a11yr', 16:27:34 INFO - 'ts_paint', 16:27:34 INFO - 'tpaint', 16:27:34 INFO - 'sessionrestore', 16:27:34 INFO - 'sessionrestore_no_auto_restore', 16:27:34 INFO - 'tabpaint']}, 16:27:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:27:34 INFO - 'tests': ['a11yr', 16:27:34 INFO - 'ts_paint', 16:27:34 INFO - 'tpaint', 16:27:34 INFO - 'sessionrestore', 16:27:34 INFO - 'sessionrestore_no_auto_restore', 16:27:34 INFO - 'tabpaint']}, 16:27:34 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 16:27:34 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 16:27:34 INFO - 'bloom_basic_ref']}, 16:27:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:27:34 INFO - 'tests': ['tsvgx', 16:27:34 INFO - 'tsvgr_opacity', 16:27:34 INFO - 'tart', 16:27:34 INFO - 'tscrollx', 16:27:34 INFO - 'cart', 16:27:34 INFO - 'tsvg_static']}, 16:27:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:27:34 INFO - 'tsvgr_opacity', 16:27:34 INFO - 'tart', 16:27:34 INFO - 'tscrollx', 16:27:34 INFO - 'cart', 16:27:34 INFO - 'tsvg_static']}, 16:27:34 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:27:34 INFO - 'talos_options': ['--disable-e10s'], 16:27:34 INFO - 'tests': ['tp5o']}, 16:27:34 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:27:34 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:27:34 INFO - 'talos_options': ['--disable-e10s', 16:27:34 INFO - '--xperf_path', 16:27:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:27:34 INFO - 'tests': ['tp5n']}, 16:27:34 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:27:34 INFO - 'talos_options': ['--xperf_path', 16:27:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:27:34 INFO - 'tests': ['tp5n']}}, 16:27:34 INFO - 'talos.zip': {'path': '', 16:27:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:27:34 INFO - Running post-action listener: _resource_record_post_action 16:27:34 INFO - [mozharness: 2017-05-13 23:27:34.926918Z] Finished populate-webroot step (success) 16:27:34 INFO - [mozharness: 2017-05-13 23:27:34.927028Z] Running create-virtualenv step. 16:27:34 INFO - Running pre-action listener: _resource_record_pre_action 16:27:34 INFO - Running main action method: create_virtualenv 16:27:34 INFO - Creating virtualenv /builds/slave/test/build/venv 16:27:34 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 16:27:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:27:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:27:35 INFO - Using real prefix '/tools/python27' 16:27:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:27:35 INFO - Installing distribute.............................................................................................................................................................................................done. 16:27:38 INFO - Installing pip.................done. 16:27:38 INFO - Return code: 0 16:27:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:27:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:27:38 INFO - Reading from file tmpfile_stdout 16:27:38 INFO - Output received: 16:27:38 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) 16:27:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:27:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d1beb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1c1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0e0da61b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cecfd30>, '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 0x10ce82db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cec3be8>, '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.ky9jgJHvLk/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.uUUW5eRe5Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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 16:27:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:27:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:27:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ky9jgJHvLk/Render', 16:27:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:27:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:27:38 INFO - 'HOME': '/Users/cltbld', 16:27:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:27:38 INFO - 'LOGNAME': 'cltbld', 16:27:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:38 INFO - 'MOZ_NO_REMOTE': '1', 16:27:38 INFO - 'NO_EM_RESTART': '1', 16:27:38 INFO - 'PAGER': '/bin/cat', 16:27:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:27:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:38 INFO - 'PWD': '/builds/slave/test', 16:27:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:27:38 INFO - 'SHELL': '/bin/bash', 16:27:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uUUW5eRe5Q/Listeners', 16:27:38 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 16:27:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:27:38 INFO - 'USER': 'cltbld', 16:27:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:27:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:27:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:38 INFO - 'XPC_FLAGS': '0x0', 16:27:38 INFO - 'XPC_SERVICE_NAME': '0', 16:27:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:27:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:39 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 16:27:39 INFO - Cleaning up... 16:27:39 INFO - Return code: 0 16:27:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:27:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d1beb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1c1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0e0da61b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cecfd30>, '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 0x10ce82db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cec3be8>, '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.ky9jgJHvLk/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.uUUW5eRe5Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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 16:27:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:27:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:27:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ky9jgJHvLk/Render', 16:27:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:27:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:27:39 INFO - 'HOME': '/Users/cltbld', 16:27:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:27:39 INFO - 'LOGNAME': 'cltbld', 16:27:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:39 INFO - 'MOZ_NO_REMOTE': '1', 16:27:39 INFO - 'NO_EM_RESTART': '1', 16:27:39 INFO - 'PAGER': '/bin/cat', 16:27:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:27:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:39 INFO - 'PWD': '/builds/slave/test', 16:27:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:27:39 INFO - 'SHELL': '/bin/bash', 16:27:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uUUW5eRe5Q/Listeners', 16:27:39 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 16:27:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:27:39 INFO - 'USER': 'cltbld', 16:27:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:27:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:27:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:39 INFO - 'XPC_FLAGS': '0x0', 16:27:39 INFO - 'XPC_SERVICE_NAME': '0', 16:27:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:27:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:39 INFO - Downloading/unpacking psutil>=3.1.1 16:27:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:27:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:27:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:27:41 INFO - Installing collected packages: psutil 16:27:41 INFO - Running setup.py install for psutil 16:27:41 INFO - building 'psutil._psutil_osx' extension 16:27:41 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 16:27:42 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 16:27:42 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 16:27:42 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 16:27:42 INFO - building 'psutil._psutil_posix' extension 16:27:42 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 16:27:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:27:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:27:42 INFO - ^ 16:27:42 INFO - 1 warning generated. 16:27:42 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 16:27:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:27:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:27:42 INFO - Successfully installed psutil 16:27:42 INFO - Cleaning up... 16:27:42 INFO - Return code: 0 16:27:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:27:42 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')]} 16:27:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:42 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')]} 16:27:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:42 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 0x10d1beb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1c1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0e0da61b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cecfd30>, '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 0x10ce82db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cec3be8>, '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.ky9jgJHvLk/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.uUUW5eRe5Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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 16:27:42 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 16:27:42 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 16:27:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ky9jgJHvLk/Render', 16:27:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:27:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:27:42 INFO - 'HOME': '/Users/cltbld', 16:27:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:27:42 INFO - 'LOGNAME': 'cltbld', 16:27:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:42 INFO - 'MOZ_NO_REMOTE': '1', 16:27:42 INFO - 'NO_EM_RESTART': '1', 16:27:42 INFO - 'PAGER': '/bin/cat', 16:27:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:27:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:42 INFO - 'PWD': '/builds/slave/test', 16:27:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:27:42 INFO - 'SHELL': '/bin/bash', 16:27:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uUUW5eRe5Q/Listeners', 16:27:42 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 16:27:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:27:42 INFO - 'USER': 'cltbld', 16:27:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:27:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:27:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:42 INFO - 'XPC_FLAGS': '0x0', 16:27:42 INFO - 'XPC_SERVICE_NAME': '0', 16:27:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:27:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:27:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:27:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:27:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:27:44 INFO - Installing collected packages: mozsystemmonitor 16:27:44 INFO - Running setup.py install for mozsystemmonitor 16:27:44 INFO - Successfully installed mozsystemmonitor 16:27:44 INFO - Cleaning up... 16:27:44 INFO - Return code: 0 16:27:44 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:27:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d1beb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1c1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0e0da61b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cecfd30>, '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 0x10ce82db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cec3be8>, '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.ky9jgJHvLk/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.uUUW5eRe5Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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 16:27:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:27:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:27:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ky9jgJHvLk/Render', 16:27:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:27:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:27:44 INFO - 'HOME': '/Users/cltbld', 16:27:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:27:44 INFO - 'LOGNAME': 'cltbld', 16:27:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:44 INFO - 'MOZ_NO_REMOTE': '1', 16:27:44 INFO - 'NO_EM_RESTART': '1', 16:27:44 INFO - 'PAGER': '/bin/cat', 16:27:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:27:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:44 INFO - 'PWD': '/builds/slave/test', 16:27:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:27:44 INFO - 'SHELL': '/bin/bash', 16:27:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uUUW5eRe5Q/Listeners', 16:27:44 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 16:27:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:27:44 INFO - 'USER': 'cltbld', 16:27:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:27:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:27:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:44 INFO - 'XPC_FLAGS': '0x0', 16:27:44 INFO - 'XPC_SERVICE_NAME': '0', 16:27:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:27:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:45 INFO - Downloading/unpacking jsonschema==2.5.1 16:27:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:27:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:27:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:46 INFO - Downloading functools32-3.2.3-2.tar.gz 16:27:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:27:46 INFO - warning: no files found matching '*.txt' 16:27:46 INFO - no previously-included directories found matching 'build' 16:27:46 INFO - no previously-included directories found matching 'dist' 16:27:46 INFO - no previously-included directories found matching '.git*' 16:27:46 INFO - Installing collected packages: jsonschema, functools32 16:27:46 INFO - Running setup.py install for functools32 16:27:47 INFO - warning: no files found matching '*.txt' 16:27:47 INFO - no previously-included directories found matching 'build' 16:27:47 INFO - no previously-included directories found matching 'dist' 16:27:47 INFO - no previously-included directories found matching '.git*' 16:27:47 INFO - Successfully installed jsonschema functools32 16:27:47 INFO - Cleaning up... 16:27:47 INFO - Return code: 0 16:27:47 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:27:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:47 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 0x10d1beb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1c1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0e0da61b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cecfd30>, '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 0x10ce82db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cec3be8>, '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.ky9jgJHvLk/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.uUUW5eRe5Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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 16:27:47 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 16:27:47 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 16:27:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ky9jgJHvLk/Render', 16:27:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:27:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:27:47 INFO - 'HOME': '/Users/cltbld', 16:27:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:27:47 INFO - 'LOGNAME': 'cltbld', 16:27:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:47 INFO - 'MOZ_NO_REMOTE': '1', 16:27:47 INFO - 'NO_EM_RESTART': '1', 16:27:47 INFO - 'PAGER': '/bin/cat', 16:27:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:27:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:47 INFO - 'PWD': '/builds/slave/test', 16:27:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:27:47 INFO - 'SHELL': '/bin/bash', 16:27:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uUUW5eRe5Q/Listeners', 16:27:47 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 16:27:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:27:47 INFO - 'USER': 'cltbld', 16:27:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:27:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:27:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:47 INFO - 'XPC_FLAGS': '0x0', 16:27:47 INFO - 'XPC_SERVICE_NAME': '0', 16:27:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:27:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:47 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:27:47 INFO - Cleaning up... 16:27:47 INFO - Return code: 0 16:27:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:27:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:47 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 0x10d1beb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1c1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0e0da61b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cecfd30>, '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 0x10ce82db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cec3be8>, '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.ky9jgJHvLk/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.uUUW5eRe5Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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 16:27:47 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 16:27:47 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 16:27:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ky9jgJHvLk/Render', 16:27:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:27:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:27:47 INFO - 'HOME': '/Users/cltbld', 16:27:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:27:47 INFO - 'LOGNAME': 'cltbld', 16:27:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:47 INFO - 'MOZ_NO_REMOTE': '1', 16:27:47 INFO - 'NO_EM_RESTART': '1', 16:27:47 INFO - 'PAGER': '/bin/cat', 16:27:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:27:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:47 INFO - 'PWD': '/builds/slave/test', 16:27:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:27:47 INFO - 'SHELL': '/bin/bash', 16:27:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uUUW5eRe5Q/Listeners', 16:27:47 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 16:27:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:27:47 INFO - 'USER': 'cltbld', 16:27:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:27:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:27:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:47 INFO - 'XPC_FLAGS': '0x0', 16:27:47 INFO - 'XPC_SERVICE_NAME': '0', 16:27:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:27:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:47 INFO - Downloading/unpacking blobuploader==1.2.4 16:27:47 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 16:27:47 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 16:27:47 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 16:27:47 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 16:27:49 INFO - Downloading blobuploader-1.2.4.tar.gz 16:27:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:27:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:27:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:27:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:27:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:49 INFO - Downloading docopt-0.6.1.tar.gz 16:27:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:27:50 INFO - Installing collected packages: blobuploader, requests, docopt 16:27:50 INFO - Running setup.py install for blobuploader 16:27:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:27:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:27:50 INFO - Running setup.py install for requests 16:27:50 INFO - Running setup.py install for docopt 16:27:50 INFO - Successfully installed blobuploader requests docopt 16:27:50 INFO - Cleaning up... 16:27:50 INFO - Return code: 0 16:27:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:27:50 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')]} 16:27:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:50 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')]} 16:27:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:50 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 0x10d1beb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1c1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0e0da61b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cecfd30>, '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 0x10ce82db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cec3be8>, '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.ky9jgJHvLk/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.uUUW5eRe5Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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 16:27:50 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 16:27:50 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 16:27:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ky9jgJHvLk/Render', 16:27:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:27:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:27:50 INFO - 'HOME': '/Users/cltbld', 16:27:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:27:50 INFO - 'LOGNAME': 'cltbld', 16:27:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:50 INFO - 'MOZ_NO_REMOTE': '1', 16:27:50 INFO - 'NO_EM_RESTART': '1', 16:27:50 INFO - 'PAGER': '/bin/cat', 16:27:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:27:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:50 INFO - 'PWD': '/builds/slave/test', 16:27:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:27:50 INFO - 'SHELL': '/bin/bash', 16:27:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uUUW5eRe5Q/Listeners', 16:27:50 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 16:27:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:27:50 INFO - 'USER': 'cltbld', 16:27:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:27:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:27:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:50 INFO - 'XPC_FLAGS': '0x0', 16:27:50 INFO - 'XPC_SERVICE_NAME': '0', 16:27:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:27:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:27:51 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-VHbZRd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:27:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:27:51 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-rDDXck-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:27:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:27:51 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-RXTFvI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:27:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:27:51 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-MGhZTI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:27:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:27:51 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-WEDroO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:27:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:27:51 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-qa5PG7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:27:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:27:51 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-STAJ46-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:27:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:27:51 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-x5lLCh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:27:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:27:51 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-ns9UYj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:27:52 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-3PokB9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:27:52 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-o9qWmI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:27:52 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-77e982-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:27:52 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-OV1NmD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:27:52 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-vPJVhj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:27:52 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-CKOC6I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:27:52 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-9okW0p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:27:52 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-1icVfz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:27:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:27:52 INFO - Running setup.py install for manifestparser 16:27:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:27:53 INFO - Running setup.py install for mozcrash 16:27:53 INFO - Running setup.py install for mozdebug 16:27:53 INFO - Running setup.py install for mozdevice 16:27:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:27:53 INFO - Running setup.py install for mozfile 16:27:53 INFO - Running setup.py install for mozhttpd 16:27:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:27:53 INFO - Running setup.py install for mozinfo 16:27:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:27:53 INFO - Running setup.py install for mozInstall 16:27:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:27:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:27:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:27:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:27:54 INFO - Running setup.py install for mozleak 16:27:54 INFO - Running setup.py install for mozlog 16:27:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:27:54 INFO - Running setup.py install for moznetwork 16:27:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:27:54 INFO - Running setup.py install for mozprocess 16:27:54 INFO - Running setup.py install for mozprofile 16:27:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:27:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:27:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:27:54 INFO - Running setup.py install for mozrunner 16:27:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:27:55 INFO - Running setup.py install for mozscreenshot 16:27:55 INFO - Running setup.py install for moztest 16:27:55 INFO - Running setup.py install for mozversion 16:27:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:27:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:27:55 INFO - Cleaning up... 16:27:55 INFO - Return code: 0 16:27:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:27: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')]} 16:27:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27: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')]} 16:27:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:55 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 0x10d1beb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1c1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0e0da61b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cecfd30>, '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 0x10ce82db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cec3be8>, '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.ky9jgJHvLk/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.uUUW5eRe5Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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 16:27:55 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 16:27:55 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 16:27:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ky9jgJHvLk/Render', 16:27:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:27:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:27:55 INFO - 'HOME': '/Users/cltbld', 16:27:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:27:55 INFO - 'LOGNAME': 'cltbld', 16:27:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:55 INFO - 'MOZ_NO_REMOTE': '1', 16:27:55 INFO - 'NO_EM_RESTART': '1', 16:27:55 INFO - 'PAGER': '/bin/cat', 16:27:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:27:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:55 INFO - 'PWD': '/builds/slave/test', 16:27:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:27:55 INFO - 'SHELL': '/bin/bash', 16:27:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uUUW5eRe5Q/Listeners', 16:27:55 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 16:27:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:27:55 INFO - 'USER': 'cltbld', 16:27:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:27:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:27:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:55 INFO - 'XPC_FLAGS': '0x0', 16:27:55 INFO - 'XPC_SERVICE_NAME': '0', 16:27:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:27:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:27:55 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-UUuOab-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:27:55 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)) 16:27:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:27:55 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-qKJQWq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:27:55 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)) 16:27:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:27:55 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-1wNYF5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:27:56 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)) 16:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:27:56 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-UQJcfD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:27:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:27:56 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-q1Ib8G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:27:56 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)) 16:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:27:56 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-wlTS8R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:27:56 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)) 16:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:27:56 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-W7bl37-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:27:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 16:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:27:56 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-9aGZO4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:27:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:27:56 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-2s2eWP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:27:56 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)) 16:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:27:56 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-9C55l2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:27:56 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)) 16:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:27:56 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-PKwHl3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:27:56 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)) 16:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:27:56 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-KqJKtm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:27:57 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)) 16:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:27:57 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-DDLEXm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:27:57 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)) 16:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:27:57 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-5CcpyT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:27:57 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)) 16:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:27:57 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-iA5yCm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:27:57 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)) 16:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:27:57 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-m0Z1_O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:27:57 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)) 16:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:27:57 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-_P8efe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:27:57 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)) 16:27:57 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)) 16:27:57 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)) 16:27:57 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)) 16:27:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:27:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:27:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:27: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 16:27: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 16:27: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 16:27: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 16:27:58 INFO - Downloading blessings-1.6.tar.gz 16:27:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:27:59 INFO - Installing collected packages: blessings 16:27:59 INFO - Running setup.py install for blessings 16:27:59 INFO - Successfully installed blessings 16:27:59 INFO - Cleaning up... 16:27:59 INFO - Return code: 0 16:27:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:27:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:27:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:27:59 INFO - Reading from file tmpfile_stdout 16:27:59 INFO - Current package versions: 16:27:59 INFO - blessings == 1.6 16:27:59 INFO - blobuploader == 1.2.4 16:27:59 INFO - docopt == 0.6.1 16:27:59 INFO - functools32 == 3.2.3-2 16:27:59 INFO - jsonschema == 2.5.1 16:27:59 INFO - manifestparser == 1.1 16:27:59 INFO - mozInstall == 1.12 16:27:59 INFO - mozcrash == 1.0 16:27:59 INFO - mozdebug == 0.1 16:27:59 INFO - mozdevice == 0.50 16:27:59 INFO - mozfile == 1.2 16:27:59 INFO - mozhttpd == 0.7 16:27:59 INFO - mozinfo == 0.9 16:27:59 INFO - mozleak == 0.1 16:27:59 INFO - mozlog == 3.4 16:27:59 INFO - moznetwork == 0.27 16:27:59 INFO - mozprocess == 0.25 16:27:59 INFO - mozprofile == 0.28 16:27:59 INFO - mozrunner == 6.13 16:27:59 INFO - mozscreenshot == 0.1 16:27:59 INFO - mozsystemmonitor == 0.3 16:27:59 INFO - moztest == 0.8 16:27:59 INFO - mozversion == 1.4 16:27:59 INFO - psutil == 3.1.1 16:27:59 INFO - requests == 1.2.3 16:27:59 INFO - wsgiref == 0.1.2 16:27:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:27:59 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')]} 16:27:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:59 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')]} 16:27:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:59 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 0x10d1beb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1c1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0e0da61b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cecfd30>, '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 0x10ce82db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cec3be8>, '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.ky9jgJHvLk/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.uUUW5eRe5Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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 16:27:59 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 16:27:59 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 16:27:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ky9jgJHvLk/Render', 16:27:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:27:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:27:59 INFO - 'HOME': '/Users/cltbld', 16:27:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:27:59 INFO - 'LOGNAME': 'cltbld', 16:27:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:59 INFO - 'MOZ_NO_REMOTE': '1', 16:27:59 INFO - 'NO_EM_RESTART': '1', 16:27:59 INFO - 'PAGER': '/bin/cat', 16:27:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:27:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:59 INFO - 'PWD': '/builds/slave/test', 16:27:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:27:59 INFO - 'SHELL': '/bin/bash', 16:27:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uUUW5eRe5Q/Listeners', 16:27:59 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 16:27:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:27:59 INFO - 'USER': 'cltbld', 16:27:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:27:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:27:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:59 INFO - 'XPC_FLAGS': '0x0', 16:27:59 INFO - 'XPC_SERVICE_NAME': '0', 16:27:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:27:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:59 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)) 16:27:59 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)) 16:27:59 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)) 16:27:59 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)) 16:27:59 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)) 16:27:59 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)) 16:27:59 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)) 16:27:59 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)) 16:27:59 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)) 16:27:59 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)) 16:27:59 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)) 16:27:59 INFO - Cleaning up... 16:27:59 INFO - Return code: 0 16:27:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:27:59 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')]} 16:27:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:59 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')]} 16:27:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:59 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 0x10d1beb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1c1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0e0da61b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cecfd30>, '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 0x10ce82db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cec3be8>, '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.ky9jgJHvLk/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.uUUW5eRe5Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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 16:27:59 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 16:27:59 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 16:27:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ky9jgJHvLk/Render', 16:27:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:27:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:27:59 INFO - 'HOME': '/Users/cltbld', 16:27:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:27:59 INFO - 'LOGNAME': 'cltbld', 16:27:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:59 INFO - 'MOZ_NO_REMOTE': '1', 16:27:59 INFO - 'NO_EM_RESTART': '1', 16:27:59 INFO - 'PAGER': '/bin/cat', 16:27:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:27:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:59 INFO - 'PWD': '/builds/slave/test', 16:27:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:27:59 INFO - 'SHELL': '/bin/bash', 16:27:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uUUW5eRe5Q/Listeners', 16:27:59 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 16:27:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:27:59 INFO - 'USER': 'cltbld', 16:27:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:27:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:27:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:59 INFO - 'XPC_FLAGS': '0x0', 16:27:59 INFO - 'XPC_SERVICE_NAME': '0', 16:27:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:28:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:28:00 INFO - Cleaning up... 16:28:00 INFO - Return code: 0 16:28:00 INFO - Running post-action listener: _resource_record_post_action 16:28:00 INFO - Running post-action listener: _start_resource_monitoring 16:28:00 INFO - Starting resource monitoring. 16:28:00 INFO - [mozharness: 2017-05-13 23:28:00.222284Z] Finished create-virtualenv step (success) 16:28:00 INFO - [mozharness: 2017-05-13 23:28:00.223223Z] Running install step. 16:28:00 INFO - Running pre-action listener: _resource_record_pre_action 16:28:00 INFO - Running main action method: install 16:28:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:28:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:28:00 INFO - Reading from file tmpfile_stdout 16:28:00 INFO - Detecting whether we're running mozinstall >=1.0... 16:28:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:28:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:28:00 INFO - Reading from file tmpfile_stdout 16:28:00 INFO - Output received: 16:28:00 INFO - Usage: mozinstall [options] installer 16:28:00 INFO - Options: 16:28:00 INFO - -h, --help show this help message and exit 16:28:00 INFO - -d DEST, --destination=DEST 16:28:00 INFO - Directory to install application into. [default: 16:28:00 INFO - "/builds/slave/test"] 16:28:00 INFO - --app=APP Application being installed. [default: firefox] 16:28:00 INFO - mkdir: /builds/slave/test/build/application 16:28:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:28:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 16:28:21 INFO - Reading from file tmpfile_stdout 16:28:21 INFO - Output received: 16:28:21 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:28:21 INFO - Running post-action listener: _resource_record_post_action 16:28:21 INFO - [mozharness: 2017-05-13 23:28:21.557798Z] Finished install step (success) 16:28:21 INFO - [mozharness: 2017-05-13 23:28:21.557981Z] Running run-tests step. 16:28:21 INFO - Running pre-action listener: _resource_record_pre_action 16:28:21 INFO - Running main action method: run_tests 16:28:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:28:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:28:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:28:21 INFO - 2017-05-13 16:28:21.572 screenresolution[1653:19434] starting screenresolution argv=screenresolution get 16:28:21 INFO - 2017-05-13 16:28:21.575 screenresolution[1653:19434] Display 0: 1600x1200x32@60 16:28:21 INFO - 2017-05-13 16:28:21.581 screenresolution[1654:19437] starting screenresolution argv=screenresolution list 16:28:21 INFO - Available Modes on Display 0 16:28:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:28:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:28:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:28:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:28:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:28:21 INFO - Intel Iris: 16:28:21 INFO - Chipset Model: Intel Iris 16:28:21 INFO - Type: GPU 16:28:21 INFO - Bus: Built-In 16:28:21 INFO - VRAM (Dynamic, Max): 1536 MB 16:28:21 INFO - Vendor: Intel (0x8086) 16:28:21 INFO - Device ID: 0x0a2e 16:28:21 INFO - Revision ID: 0x0009 16:28:21 INFO - Displays: 16:28:21 INFO - Display: 16:28:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:28:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:28:21 INFO - Display Serial Number: 1600x1200 60 16:28:21 INFO - Main Display: Yes 16:28:21 INFO - Mirror: Off 16:28:21 INFO - Online: Yes 16:28:21 INFO - Rotation: Supported 16:28:21 INFO - Return code: 0 16:28:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:28:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:28:21 INFO - Python 2.7.3 16:28:21 INFO - Return code: 0 16:28:21 INFO - grabbing minidump binary from tooltool 16:28:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:28:21 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cecfd30>, '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 0x10ce82db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cec3be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:28:21 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 16:28:21 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 16:28:21 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 16:28:21 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:28:21 INFO - Return code: 0 16:28:21 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:28:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:28:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:28:21 INFO - ENV: RUST_BACKTRACE is now 1 16:28:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:28:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:28:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RNwKFkV7RymduV-VV4Eb5Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0322', '--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 16:28:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RNwKFkV7RymduV-VV4Eb5Q/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0322 --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 16:28:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ky9jgJHvLk/Render', 16:28:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:28:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:28:21 INFO - 'HOME': '/Users/cltbld', 16:28:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:28:21 INFO - 'LOGNAME': 'cltbld', 16:28:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:28:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:28:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:21 INFO - 'MOZ_NO_REMOTE': '1', 16:28:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:28:21 INFO - 'NO_EM_RESTART': '1', 16:28:21 INFO - 'PAGER': '/bin/cat', 16:28:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:28:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:21 INFO - 'PWD': '/builds/slave/test', 16:28:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:28:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:28:21 INFO - 'RUST_BACKTRACE': '1', 16:28:21 INFO - 'SHELL': '/bin/bash', 16:28:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uUUW5eRe5Q/Listeners', 16:28:21 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 16:28:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:28:21 INFO - 'USER': 'cltbld', 16:28:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:28:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:28:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:21 INFO - 'XPC_FLAGS': '0x0', 16:28:21 INFO - 'XPC_SERVICE_NAME': '0', 16:28:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:28:21 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RNwKFkV7RymduV-VV4Eb5Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0322', '--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 16:28:22 INFO - mozversion application_buildid: 20170513154306 16:28:22 INFO - mozversion application_changeset: 73b3fc64525b6816842c737e104ef2ac5482d217 16:28:22 INFO - mozversion application_display_name: Nightly 16:28:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:28:22 INFO - mozversion application_name: Firefox 16:28:22 INFO - mozversion application_remotingname: firefox 16:28:22 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:28:22 INFO - mozversion application_vendor: Mozilla 16:28:22 INFO - mozversion application_version: 55.0a1 16:28:22 INFO - mozversion platform_buildid: 20170513154306 16:28:22 INFO - mozversion platform_changeset: 73b3fc64525b6816842c737e104ef2ac5482d217 16:28:22 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:28:22 INFO - mozversion platform_version: 55.0a1 16:28:22 INFO - using testdate: 1494718102 16:28:22 INFO - actual date: 1494718102 16:28:22 INFO - starting webserver on 'localhost:49235' 16:28:22 INFO - SUITE-START | Running 2 tests 16:28:22 INFO - TEST-START | dromaeo_css 16:28:22 INFO - operating with platform_type : mac_ 16:28:22 INFO - Initialising browser for dromaeo_css test... 16:28:22 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/tmpgcH11E/profile http://localhost:49235/getInfo.html) 16:28:24 INFO - PID 1664 | 2017-05-13 16:28:24.626 plugin-container[1666:19621] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a3b, name = 'com.apple.tsm.portname' 16:28:24 INFO - PID 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:28:24 INFO - PID 1664 | 2017-05-13 16:28:24.629 plugin-container[1666:19621] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f03, name = 'com.apple.CFPasteboardClient' 16:28:24 INFO - PID 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:28:24 INFO - PID 1664 | 2017-05-13 16:28:24.630 plugin-container[1666:19621] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:28:24 INFO - PID 1664 | __metrics Screen width/height:1600/1200 16:28:24 INFO - PID 1664 | colorDepth:24 16:28:24 INFO - PID 1664 | Browser inner width/height: 1024/691 16:28:24 INFO - PID 1664 | __metrics 16:28:25 INFO - PID 1664 | 16:28:25 INFO - PID 1664 | ###!!! [Child][MessageChannel] Error: (msgtype=0x7C0029,name=PHal::Msg_EnableWakeLockNotifications) Channel closing: too late to send/recv, messages will be lost 16:28:25 INFO - PID 1664 | 16:28:25 INFO - PID 1664 | 2017-05-13 16:28:25.275 plugin-container[1668:19685] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9043, name = 'com.apple.tsm.portname' 16:28:25 INFO - PID 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:28:25 INFO - PID 1664 | 2017-05-13 16:28:25.276 plugin-container[1668:19685] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950f, name = 'com.apple.CFPasteboardClient' 16:28:25 INFO - PID 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:28:25 INFO - PID 1664 | 2017-05-13 16:28:25.276 plugin-container[1668:19685] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:28:25 INFO - TEST-INFO | 1664: exit 0 16:28:25 INFO - Browser initialized. 16:28:25 INFO - Running cycle 1/1 for dromaeo_css test... 16:28:25 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/tmpgcH11E/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:28:28 INFO - PID 1669 | 2017-05-13 16:28:28.223 plugin-container[1670:19790] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 16:28:28 INFO - PID 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:28:28 INFO - PID 1669 | 2017-05-13 16:28:28.224 plugin-container[1670:19790] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 16:28:28 INFO - PID 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:28:28 INFO - PID 1669 | 2017-05-13 16:28:28.224 plugin-container[1670:19790] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:28:28 INFO - PID 1669 | 2017-05-13 16:28:28.555 plugin-container[1671:19841] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 16:28:28 INFO - PID 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:28:28 INFO - PID 1669 | 2017-05-13 16:28:28.555 plugin-container[1671:19841] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9423, name = 'com.apple.CFPasteboardClient' 16:28:28 INFO - PID 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:28:28 INFO - PID 1669 | 2017-05-13 16:28:28.556 plugin-container[1671:19841] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:28:37 INFO - PID 1669 | RSS: Main: 228155392 16:28:37 INFO - PID 1669 | 16:31:42 INFO - PID 1669 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49235/tests/dromaeo/cssquery-ext.html) 16:31:43 INFO - PID 1669 | RSS: Main: 256937984 16:31:43 INFO - PID 1669 | 16:34:45 INFO - PID 1669 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-ext.html (next: http://localhost:49235/tests/dromaeo/cssquery-jquery.html) 16:34:46 INFO - PID 1669 | RSS: Main: 260759552 16:34:46 INFO - PID 1669 | 16:37:48 INFO - PID 1669 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49235/tests/dromaeo/cssquery-mootools.html) 16:37:48 INFO - PID 1669 | RSS: Main: 270876672 16:37:48 INFO - PID 1669 | 16:40:50 INFO - PID 1669 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49235/tests/dromaeo/cssquery-prototype.html) 16:40:51 INFO - PID 1669 | RSS: Main: 263864320 16:40:51 INFO - PID 1669 | 16:43:53 INFO - PID 1669 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49235/tests/dromaeo/cssquery-yui.html) 16:43:54 INFO - PID 1669 | RSS: Main: 267247616 16:43:54 INFO - PID 1669 | 16:46:56 INFO - PID 1669 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-yui.html (next: http://localhost:49235/tests/dromaeo/cssquery-dojo.html) 16:46:56 INFO - PID 1669 | RSS: Main: 274731008 16:46:56 INFO - PID 1669 | 16:46:56 INFO - PID 1669 | __start_tp_report 16:46:56 INFO - PID 1669 | _x_x_mozilla_page_load 16:46:56 INFO - PID 1669 | _x_x_mozilla_page_load_details 16:46:56 INFO - PID 1669 | |i|pagename|runs| 16:46:56 INFO - PID 1669 | |0;dojo.html;3204.5353423753554;3567.5183850180224;3620;3651.1602331463764;3654.5249117614712;4845.757712114393;6128.380904898417;6204.503639708818;6239.313675495698;6320.778772742955;6176.845578860531;6233.9402301608725;6248.187735594378;6289.716962736677;6310.49516038717;5522.972385138069;5531.778728850841;5569.470900264469;5581.609287349887;5601.831945041656;5603.523700485463;5677.687727175004;5683.488486036256;5692.146178073281;5705.343885453184;5445.210444485528;5504.587155963279;5606.299212598426;5628.099504079369;5680.147977803321;5552.694601796899;5616.129499927518;5621.747021384048;5644.858878528029;5674.233978412903;5505.174223866412;5526.834194974157;5554.444555544454;5581.107022876361;5584.329880414376;5388.5419739321815;5942.70286485674;6146.725711486779;6292.370762923717;6303.401176888188;6559.93440065598;6659.833504162386;6727.226368967583;6738.797836064926;6760.661966901635;6832.350926661959;6867.969804529361;6868.347506986828;6869.107016087876;6879.140107486564;6263.091851681481;6335.461485773669;6350.396712312369;6357.650329231937;6362;6724.092247546519;6830.7609233676585;6887.070245516891;6962.373386395248;6978.616176110363;6414.967925160345;6469.51478639104;6499.610023398611;6627.271000189975;6686.431653309426;5503.669779813224;6668;6794.320567943166;6800.795976120724;6815.829604259933;6663.433608143265;6666.500012499082;6712.865742685119;6720.529562930548;6742.528023038439;6477.319881412478;6559.442447392025;6662.633555154416;6700.49746269022;6749.966250168718;5339.439358867341;5347.839564813062;5357.223202635674;5372.301600791872;5391.81128660495;4718.41019872516;4753.762311884392;4756.809727610926;4764.332993380929;4778.904421911542;6225.28409232935;6282.968585157137;6301.212348456443;6375.585586936834;6386.584871983307;6798.66006699663;6826.214985276657;6871.828204294832;6880.483963702743;6883.139607549057;4724.267738500538;6076.572005578685;6243.750249989949;6361.681915904279;6391.137196478416;5820.88358232833;5908.231929849092;6027.397260273938;6041.486473649701;6043.516518678495;6839.69221385029;6907.447404207752;6978.47661425385;6978.651067446709;7007.824804379931;5746.367899531132;6795.28649491816;6821.420179284816;6838.965805170942;6852.314768523108;6929.757458488929;6943.861122777617;6952.200496942714;7026.367626913602;7029.61337126463;6469.967650161719;6547.738090476328;6620.735170593122;6691.631960242234;6718.160229971254;6773.627450490073;6839.521233513607;6852.246252912275;6852.794416167523;6871.896921546081;4854.95145048545;5414.7021913795115;6347.111404403479;6519.8044058678315;6606.933930660632;5707.229524014371;6460.095586617783;6463.482921366185;6615.569987950767;6615.933840661724;6305.148804911279;6476.838079048061;6490;6491.545591808528;6509.153810004669;6421.133147025279;6512.413882750575;6557.639329836714;6578.572392794453;6638.8008359747355;6466.127072845294;6562.34376562359;6562.376574225441;6569.638669873012;6575 16:46:56 INFO - PID 1669 | |1;ext.html;691.4917535611324;790.0637744273035;819.5861090149475;1023.8054769593776;1283.473775751942;38723;39237.80381098098;39424.42302307908;39462.210755784865;39469.40795888064;39393.21213575727;39547.60452395475;39892.40161397582;39973;40091.799541002256;7863.13505514393;8034.915286436344;8290.046644635853;8461.746147615582;8568.400211985163;8363.95818020909;8543.060263371024;8615.396922215448;8622.35332350073;8659.177378149097;8550.52972086535;8600.56997150143;8614.224719775219;8630.913690863073;8662.913370866305;8520.062793092755;8573.142685731407;8610.698625548139;8611.35414843886;8620.78448038804;6819.5908245505425;7960.681572737083;8362.996440427187;8410.32717382608;8461.873071903927;36371.272574548624;36478.27043459116;36652.63347366545;36673;36731;3543.362194804934;3572.8928132156075;3581.068922080252;3581.1047238190454;3587.1032241939515;30150.54774178367;30207.395852083057;30295.848520757474;30365.696343036507;30372.240693982607;3098.581691471645;3134.670859559736;3137.278425962019;3150.322680623677;3150.54317124018;251646.74176629182;292852;299782.50108749536;309687;310825.44587276917;47308.763456182496;47355;47357;47401.52598473971;47474;49260;49476.0104797902;51021.97956040858;54586.36240912779;54642.726786366606;14126.293685315693;14205.21871297068;14215.715685686228;14268.072575282573;14288.285585720672;11204.215704900742;11363.261388009752;11451.25566838153;11456.19806613529;11471.311721296748;14098.71802563943;14112.50606228777;14148;14169.22069286199;14239.64400889986;4876.853694389174;5654.6324488908085;6231.345708700612;6250.749970001149;6322.514209160843;920.1383696703248;968.801395713877;1024.9846252306222;1036.8128492875767;1068.417712346773;23838.880805596207;24632.630510542356;24719.775220229905;24823.751762482145;30462.933797316986;1081.491698901515;1083.1226706367868;1110.9666709998712;1112.449337577904;1117.3910218930703;12096.334701591497;12400.503979840705;12425.19236249641;12444.564440244549;12449.875501245053;279969;285857;286061;287343;298381;55038;55312.72343638256;55467;55480.72259638676;55622;1346.501794336102;1497.1615895098562;1523.5657837516255;1530.50258665934;1542.8688561472177;28987.710122899345;29230;30007.549886752153;30056.098317050524;30129.698703013568;53205.73397133144;54120.188197177864;54146.72926635342;54219.64450888601;54634.45365546294;281925;286410;286482;287277;287673;37528.2494350106;37544.62455375411;37679;37825.2434951305;37830.62169378271;43953;46004;47567.76216118897;47594.762026189645;47791;27359.179224623294;30924;32361.352772943937;32758.508622370206;33208.50187247145;31909.68090319067;32811.50782738213;33012.50481242831;33018.504722429665;33206;38433.42349864698;38683.613163868;38778.41832372573;40070;40194;32207.194820129684;32496.83751581227;32775;33019;33236.50145247872;21847;22727.545449091256;24556.508869822148;25284.49431011333;26211.475770484863 16:46:56 INFO - PID 1669 | |2;jquery.html;423.0398576688328;424.3126135660231;435.9128174365128;445.7815670321542;449.3798557989973;20146.49633759157;21007.579848403042;21082.36752897412;21839.672404913937;22142.557148857035;22533.661995070088;23216.65175022376;23597.29208123755;23940.760592394072;24194.395140121505;6190.845228869281;6306.716197771111;6354.269259035201;6416.903746443807;6418.967905160468;6044.667543285118;6225.501959843202;6274.247090349165;6289.591176573531;6302.243730752316;5828.213191219194;6064.120702498135;6234.563580549363;6260.467860231885;6269.153664255313;5430.8913821723545;6015.428534289235;6105.847353816146;6144.662043587601;6147.2930612979635;5366.865828354284;5422.8373148805595;5455.236266922634;5475.780968761284;5485.0949593317055;28090.719092809013;28360.716392836013;28470.572941405895;28946.42107157845;29167;2207.536417352358;2254;2260.559190957764;2260.8756518391483;2265.818734501236;18123.72814407785;18155.185946178364;18302.359417420328;18411.355602553846;18668.346607868658;2174.6520556710852;2214.1032881682945;2229.3312006398014;2245.158065725353;2246.48330883896;136178.63821361857;162503;168437;169492;170091.99999999875;12913.935430322976;12915.612531624067;13139.211647301192;13231.470741170438;13371.729685679846;13930.164190148624;16919.492415227316;16932.23804928781;16941.15294235308;16945.237464314134;8762.956185219033;9100.22648074921;9126.863097053549;9211.63153473867;9230.907690923139;8631.093735157843;8661.177188167114;8688.174623410765;8783.385163038525;8829.072947340564;8027.197280272043;8115.147909469539;8128.065272493739;8186.222308880647;8245.546494942835;1963.6465436221617;2182.6289530779804;2194.3307291276315;2206.5917805206086;2361.1381845626565;904.6924045824451;932.9364524442142;945.8108378324363;960.9135177834029;973.8928717841031;20368.796312036986;20980.89509552473;21378.14487420517;21438.57122857534;21459.57080858374;944.551338114404;973.8782652168479;1000.8648832407678;1002.929794914349;1020.7295066807302;9591.952040239754;10606.355820006824;11093.914320296977;11169.72075698114;11187;518611;528555;534495;534523;535043.3247833736;128233;129513.35243323723;129730.99999999811;130808.3459582696;132057;1189.2864281431246;1222.5109956017664;1232.624049664843;1235.215638069832;1237.975240495185;22116.557668846854;23035.769642303363;23154.07383704633;23190.65214021757;23499;48060;57379;57904.71047644735;58588.12117818151;59741.402585975324;77362.61318693371;77743.83384249128;78845.97218838659;79560.60219698865;79778.60110699642;8276.834463310579;8406.369522285975;8873.511956842436;8961.193492585438;9036.23192028685;13501.459941602227;14200.360983755549;14245.002849800829;14435.422583096979;14605.269736513344;11786.705332366759;11831.290122592673;11853.170278080452;12030.879691203327;12123.818142728043;11154.776904462027;11162;11314.547418103184;11351.091912646803;11425.149700598802;11835.704107397383;11983.700407489883;11986.580469683518;12045.879541204826;12059.698507537381;8784.692535761218;8830.602622881857;9003.279737621106;9169.266458683156;9193.172614464731;10116.36617889433;11752.76494470123;11944.044476442037;12009.879901200877;12057.975072118968 16:46:56 INFO - PID 1669 | |3;mootools.html;1272.7454509098184;1326.4959315460123;1382.1430712957967;1389.854065323141;1465.4284828916725;11666.300021998664;11769.941150294248;12251.387430628467;12294.016478681706;12328.568500102487;12072.275663460177;12228.755424891497;12248.693782655439;12251.877481225185;12292.446839892204;2595.5457794885915;2641.247244535308;2653.8142330036903;2658.667666541682;2667.7465640764094;2561.9743802562016;2617.1625079974415;2620.973790262092;2621.8295810772333;2636.617690434886;2581.367564946591;2585.4441295121546;2619.7380261973744;2624.6719160104985;2626.776723978464;2565.589505679092;2581.59985202293;2607.7261887501822;2612.3469132716823;2615.3853844346563;2181.203860590889;2249.347689170139;2433.4889673168655;2454.2882564056404;2462.507498500307;11770.646880593597;12049.49381327334;12070.698232544257;12087.093467989936;12094.455749491206;6644.534882558223;6796.864062718718;6841.692123854438;6900.275471075565;6903.723851045952;3522.5068490411363;3609.2781443711365;4166.229247589205;4223.64099051581;4234.237837189305;1233.3771445420005;1241.9875801242054;1274.3182397417338;1284.113961366654;1287.871212878714;16593.336266549322;16623.50129496117;16637.91681041599;16727.16364181786;16852;5378.650387724785;5559.694216818114;5587.664740115606;5731.570132240098;5829.300483941869;11841.348725820162;11867.881321186678;11907.940460297643;11912.344821034927;11932.642020739391;6221.595596286317;6351.809445716636;6548.639824809681;6554.180727409074;6556.541042127101;6060.303065147476;6143.201383820164;6157.4458298753325;6177.938220617736;6233.75064997395;4353.303471444554;4450.087732014929;4463.732176069446;4465.6427485801705;4472.508024117345;1272.611853384708;1286.7876800328029;1294.2687381629348;1305.3799445263614;1307.6730817295677;792.1405275276275;794.9721759738381;823.7364043506141;832.9375296852639;847.1824689174975;5508.944910550923;6246.937530624727;6265.122882796412;6283.6858157091965;6529.347065293433;576.1127863090877;583.1573376470959;594.7888499582673;595.0598055073061;597.52496765071;3695.2055308108347;3990.920181596352;4004.039030632627;4511.571400716926;4666.113438446684;14964.850351496563;15009.324580393908;15343.539693809204;15451.536453906401;15460;14836.480723174853;14840.480583179537;15328;15409.537713868602;15428.228588570526;8542.572871356408;8750.47497150173;8755.212030917248;8763.729259257372;8769.166929141722;6587.143671322698;6998.685059172248;7107.076080109553;7168.283171682999;7189.640517973976;13496.257705826274;13545.932270338586;13746.65633359174;13879.918012297832;13886.583402497941;13974.441022358991;13988.58034258974;14131;14306.928465357607;14382;8015.318697910743;9175;9405.153536181777;9445.149936505748;9511.904880951102;10869.891301087204;10969.780604388026;10991.010809027226;11006.394648294101;11029.779404411707;8552.67433547801;8689.870316858767;9032.45805251687;9265.675701350421;9274.304427168125;9039.322050846344;9058;9070.501122438329;9110.954445227731;9118.996910339725;8254.628541715518;8260.463069900437;8264;8359.791005224917;8368.70709525188;5768.971155144365;6014.278286605635;6069.484093852072;6187.752489900354;6277.811665650038;6074.1799857020505;6209.068639704081;6228.5951413158;6276.529260305404;6325.146105275729 16:46:56 INFO - PID 1669 | |4;prototype.html;381.8243607940347;398.34074606526207;401.41193152032275;407.7981399207392;409.0265168898022;5042.4957504249605;5513.807016754415;6175.104609831572;6331.2459080300105;6332.303446620868;5064.949350506494;6078.361772013941;6220.502359811216;6225.564210505267;6248.187735594366;4547.909041819162;4562.338460923164;4566.4520257568975;4570.794314255866;4610.2854057621125;4379.890502737441;4545.47727011393;4559.45286565611;4562.494376855646;4591.989762252316;3704.481372607837;4067.474696988629;4203.264428724976;4495.505494395614;4510.751908645023;4517.774111294439;4518.977405112986;4520.502744698113;4530.343100250462;4552.567506086916;3807.124361396894;3835.2904712627974;3871.380579107329;3892.4550562921213;3899.512560929884;5820.692687411328;6023.247094113236;6092.482139018189;6095.634261944253;6137.140800287919;4488.5960263576435;5373.543248823855;5399;5410.945890541084;5434.375046869582;6428.935710642881;6526.314736952599;6590.176227971504;6609.504287178433;6638.568493047985;4048.1296521248073;4390.714603550759;4407.515173330931;4428.889277768049;4429.933550996738;6138.693065346715;6293.842653933642;6683.899741503881;6931.549449285781;6936.375726184617;4079.938800917989;4123.010477485382;4400.845970391021;4421.579949904753;4445.288753799441;3794.6774524165203;3825.8087095645665;4374.606285434326;4508.706934049276;4526.728396296233;5249.317588713519;5350.812721554805;5360.356757189163;5377.650452720561;5396.763540620673;5417.295751552273;6606.900896486556;6607.240167380813;6654.870441458784;6699.263081061079;1472.9631759206106;1497.004492012821;1537.8769698424098;1538.94550774717;1547.1490680125885;983.9754006149903;996.9800603987879;999.6950929966284;1000.5247507433911;1017.2777328096986;6247;6293.89154633203;6299.5275354348605;6303.447103922918;6321.304656487782;5680.914786278209;6842.794716158523;6927.445804335641;6948.548344357701;6968.616726080013;6149.108379285068;6198.729260501586;6233.438990490787;6274.937250627435;6310.684465776692;3120.3929976062345;3463.393906066479;3598.1649358827185;3603.72972027099;3611.476335931328;6787.457003439713;6812.386885180258;7095.254998225215;7135.750248741269;7141.428685705131;6982;7131.607761573059;7158.677859496335;7177.569345839266;7195.784126476109;6188.504919606421;6378.002338900709;6394.784990851724;6776.559523631046;6933.890577507575;5448.918266226089;6395.328490508429;6957;6995.475339349671;7002.544834585736;5053.083860008523;6052.455279024909;6561;6860.3139686031;6871.419573498023;6900.93099069023;7200.49596528238;7216.530925489827;7218.942601358797;7246.4202863771825;3908.511436070491;4019.1157945252;4416.712913660602;4458.375827384234;4476.3365797350525;6094.8171554853425;6223;6545.443637290884;6806.76176333826;6918.342757438036;6526;6627.4698024158915;6698.56459330142;6699.196096468455;6717.462602991846;5362.65142765719;6451.2903580607035;6674.799756007327;6678.76624318166;6691.64668265376;5314;6417.647029413427;6510.121133647087;6560.343965603402;6584.176977877765;5832.300123985149;6326.6520341381665;6619.172603424572;6668.499862510234;6732.293109223657;6304.810855674337;6452.870942581216;6475.740970361133;6548.24973511136;6551.80344589663 16:46:56 INFO - PID 1669 | |5;yui.html;699.6012273004383;701.9400704935546;810.1898101898101;818.4679958027283;820.4339006085801;15693.372265109396;16360.918195409036;16363.100029498373;17784.55538611532;18820.24719011241;17014.40449584265;17176.05531695756;17322.82677173231;17382.21780019899;17473.912630436862;986.4722373530162;986.8815742110921;999.8000399920008;1003.0771690045176;1003.2575893838543;883.1257055515058;961.4567769210408;1009.7576581620395;1013.3717095400862;1014.9035841595037;1023.8924912884152;1035.554711474066;1045.2839804733744;1045.3884477580625;1045.6235755128148;904.1771987491384;1043.8903915088881;1044.7492601775557;1048.025336437113;1051.043550369168;760.577879277001;766.5668897073136;771.3135309574483;771.4445599168589;771.9768406947801;13983.720325593431;15955.282012309473;16271.267792949346;16313.429029984012;16377.344906203738;15947.760783588255;15950;15986.641135503496;16105.194740263058;16315.265813038443;11160.553577856876;13202.471901123943;13210.405531751094;13687.452501899912;14174.92912535441;3565.9108522286892;3569.446735755962;3570.8571657133684;3571.714262858965;3579.570451545831;86019;118416;120077;120473.39763301215;121462;12249.755004899853;12503.249805011728;12553.121281510206;12576.874231257754;12580.496780128875;18188.454346369632;23711.407214819767;24146.51706965886;24160.03359865621;24161;6245.500359971283;7304.5252058616015;7382.815429614303;7455.739049133254;7457.776266712007;3665.7617254878346;3731.7574357666663;3735.3836616958442;3748.9437658435145;3749.6625303722794;4506.662000349987;4666.2067448533835;4676.625869930397;4707.293905914072;4712.693674911188;925.7593025813337;954.4368822394821;955.2358113509165;967.0184762466102;969.898160693117;641.9550631455753;646.8382904273932;660.4155322539588;671.4829581222432;680.9625470599165;8923.226074050352;11965.700857478458;12108.81836772449;12542;13158.60524184276;649.3344322069917;658.3548122839549;660.9900851487232;674.780696273713;687.5908834243617;4972.67677600962;5779.133130030529;5976.701164941735;6036.094585812163;6100.48145907594;99444;103958.96041039644;104404.47797761114;105142.95998920157;107186;83055.584722076;91392;95818.5209073964;98317.50841245748;98676;87079.56460217659;88533.55733221292;89041;89258.55370723235;89437.55281223683;11128.109751220045;11816.881831181921;12149.453274602842;12265;12271;38365.040873977254;39581.80209098936;40365.79817100896;40499;42980.71057868269;43892;58624.706876465345;66226.33773662202;69523;70305;12117.757644846877;15583.76624350613;15995.280212390238;16106.67786644284;16166.757498637766;14964.877634177697;16979.830201697823;17772.555686107433;17872.285108596032;17922;15654.843451565339;15752.60618484547;15939.123348215959;16709.582260443585;16904.07027613493;15629.374825006873;16310.266038028554;16322.591935201715;16395.016299022096;16396.180190990643;15403.460878869186;15445.84554154444;16181.352745890503;16358.754618680492;16548.172591370625;11013;11098.667039988813;11320.37737924422;11449.48477318506;11609.709757256136;11663.88336116628;12052.51789928393;13218;13317.533886313933;13366.465341386236 16:46:56 INFO - PID 1669 | __end_tp_report 16:46:56 INFO - PID 1669 | __start_cc_report 16:46:56 INFO - PID 1669 | _x_x_mozilla_cycle_collect,417 16:46:56 INFO - PID 1669 | __end_cc_report 16:46:56 INFO - PID 1669 | __startTimestamp1494719216702__endTimestamp 16:46:56 INFO - PID 1669 | 16:46:56 INFO - PID 1669 | ------- Summary: start ------- 16:46:56 INFO - PID 1669 | Number of tests: 6 16:46:56 INFO - PID 1669 | 16:46:56 INFO - PID 1669 | [#0] dojo.html Cycles:165 Average:6179.86 Median:6414.97 stddev:749.41 (11.7%) stddev-sans-first:714.43 16:46:56 INFO - PID 1669 | Values: 3204.5 3567.5 3620.0 3651.2 3654.5 4845.8 6128.4 6204.5 6239.3 6320.8 6176.8 6233.9 6248.2 6289.7 6310.5 5523.0 5531.8 5569.5 5581.6 5601.8 5603.5 5677.7 5683.5 5692.1 5705.3 5445.2 5504.6 5606.3 5628.1 5680.1 5552.7 5616.1 5621.7 5644.9 5674.2 5505.2 5526.8 5554.4 5581.1 5584.3 5388.5 5942.7 6146.7 6292.4 6303.4 6559.9 6659.8 6727.2 6738.8 6760.7 6832.4 6868.0 6868.3 6869.1 6879.1 6263.1 6335.5 6350.4 6357.7 6362.0 6724.1 6830.8 6887.1 6962.4 6978.6 6415.0 6469.5 6499.6 6627.3 6686.4 5503.7 6668.0 6794.3 6800.8 6815.8 6663.4 6666.5 6712.9 6720.5 6742.5 6477.3 6559.4 6662.6 6700.5 6750.0 5339.4 5347.8 5357.2 5372.3 5391.8 4718.4 4753.8 4756.8 4764.3 4778.9 6225.3 6283.0 6301.2 6375.6 6386.6 6798.7 6826.2 6871.8 6880.5 6883.1 4724.3 6076.6 6243.8 6361.7 6391.1 5820.9 5908.2 6027.4 6041.5 6043.5 6839.7 6907.4 6978.5 6978.7 7007.8 5746.4 6795.3 6821.4 6839.0 6852.3 6929.8 6943.9 6952.2 7026.4 7029.6 6470.0 6547.7 6620.7 6691.6 6718.2 6773.6 6839.5 6852.2 6852.8 6871.9 4855.0 5414.7 6347.1 6519.8 6606.9 5707.2 6460.1 6463.5 6615.6 6615.9 6305.1 6476.8 6490.0 6491.5 6509.2 6421.1 6512.4 6557.6 6578.6 6638.8 6466.1 6562.3 6562.4 6569.6 6575.0 16:46:56 INFO - PID 1669 | 16:46:56 INFO - PID 1669 | [#1] ext.html Cycles:180 Average:45334.59 Median:3558.13 stddev:75595.92 (2124.6%) stddev-sans-first:75733.67 16:46:56 INFO - PID 1669 | Values: 691.5 790.1 819.6 1023.8 1283.5 38723.0 39237.8 39424.4 39462.2 39469.4 39393.2 39547.6 39892.4 39973.0 40091.8 7863.1 8034.9 8290.0 8461.7 8568.4 8364.0 8543.1 8615.4 8622.4 8659.2 8550.5 8600.6 8614.2 8630.9 8662.9 8520.1 8573.1 8610.7 8611.4 8620.8 6819.6 7960.7 8363.0 8410.3 8461.9 36371.3 36478.3 36652.6 36673.0 36731.0 3543.4 3572.9 3581.1 3581.1 3587.1 30150.5 30207.4 30295.8 30365.7 30372.2 3098.6 3134.7 3137.3 3150.3 3150.5 251646.7 292852.0 299782.5 309687.0 310825.4 47308.8 47355.0 47357.0 47401.5 47474.0 49260.0 49476.0 51022.0 54586.4 54642.7 14126.3 14205.2 14215.7 14268.1 14288.3 11204.2 11363.3 11451.3 11456.2 11471.3 14098.7 14112.5 14148.0 14169.2 14239.6 4876.9 5654.6 6231.3 6250.7 6322.5 920.1 968.8 1025.0 1036.8 1068.4 23838.9 24632.6 24719.8 24823.8 30462.9 1081.5 1083.1 1111.0 1112.4 1117.4 12096.3 12400.5 12425.2 12444.6 12449.9 279969.0 285857.0 286061.0 287343.0 298381.0 55038.0 55312.7 55467.0 55480.7 55622.0 1346.5 1497.2 1523.6 1530.5 1542.9 28987.7 29230.0 30007.5 30056.1 30129.7 53205.7 54120.2 54146.7 54219.6 54634.5 281925.0 286410.0 286482.0 287277.0 287673.0 37528.2 37544.6 37679.0 37825.2 37830.6 43953.0 46004.0 47567.8 47594.8 47791.0 27359.2 30924.0 32361.4 32758.5 33208.5 31909.7 32811.5 33012.5 33018.5 33206.0 38433.4 38683.6 38778.4 40070.0 40194.0 32207.2 32496.8 32775.0 33019.0 33236.5 21847.0 22727.5 24556.5 25284.5 26211.5 16:46:56 INFO - PID 1669 | 16:46:56 INFO - PID 1669 | [#2] jquery.html Cycles:180 Average:35488.76 Median:23203.65 stddev:90525.52 (390.1%) stddev-sans-first:90741.18 16:46:56 INFO - PID 1669 | Values: 423.0 424.3 435.9 445.8 449.4 20146.5 21007.6 21082.4 21839.7 22142.6 22533.7 23216.7 23597.3 23940.8 24194.4 6190.8 6306.7 6354.3 6416.9 6419.0 6044.7 6225.5 6274.2 6289.6 6302.2 5828.2 6064.1 6234.6 6260.5 6269.2 5430.9 6015.4 6105.8 6144.7 6147.3 5366.9 5422.8 5455.2 5475.8 5485.1 28090.7 28360.7 28470.6 28946.4 29167.0 2207.5 2254.0 2260.6 2260.9 2265.8 18123.7 18155.2 18302.4 18411.4 18668.3 2174.7 2214.1 2229.3 2245.2 2246.5 136178.6 162503.0 168437.0 169492.0 170092.0 12913.9 12915.6 13139.2 13231.5 13371.7 13930.2 16919.5 16932.2 16941.2 16945.2 8763.0 9100.2 9126.9 9211.6 9230.9 8631.1 8661.2 8688.2 8783.4 8829.1 8027.2 8115.1 8128.1 8186.2 8245.5 1963.6 2182.6 2194.3 2206.6 2361.1 904.7 932.9 945.8 960.9 973.9 20368.8 20980.9 21378.1 21438.6 21459.6 944.6 973.9 1000.9 1002.9 1020.7 9592.0 10606.4 11093.9 11169.7 11187.0 518611.0 528555.0 534495.0 534523.0 535043.3 128233.0 129513.4 129731.0 130808.3 132057.0 1189.3 1222.5 1232.6 1235.2 1238.0 22116.6 23035.8 23154.1 23190.7 23499.0 48060.0 57379.0 57904.7 58588.1 59741.4 77362.6 77743.8 78846.0 79560.6 79778.6 8276.8 8406.4 8873.5 8961.2 9036.2 13501.5 14200.4 14245.0 14435.4 14605.3 11786.7 11831.3 11853.2 12030.9 12123.8 11154.8 11162.0 11314.5 11351.1 11425.1 11835.7 11983.7 11986.6 12045.9 12059.7 8784.7 8830.6 9003.3 9169.3 9193.2 10116.4 11752.8 11944.0 12009.9 12058.0 16:46:56 INFO - PID 1669 | 16:46:56 INFO - PID 1669 | [#3] mootools.html Cycles:180 Average:7208.71 Median:3565.89 stddev:4617.84 (129.5%) stddev-sans-first:4609.25 16:46:56 INFO - PID 1669 | Values: 1272.7 1326.5 1382.1 1389.9 1465.4 11666.3 11769.9 12251.4 12294.0 12328.6 12072.3 12228.8 12248.7 12251.9 12292.4 2595.5 2641.2 2653.8 2658.7 2667.7 2562.0 2617.2 2621.0 2621.8 2636.6 2581.4 2585.4 2619.7 2624.7 2626.8 2565.6 2581.6 2607.7 2612.3 2615.4 2181.2 2249.3 2433.5 2454.3 2462.5 11770.6 12049.5 12070.7 12087.1 12094.5 6644.5 6796.9 6841.7 6900.3 6903.7 3522.5 3609.3 4166.2 4223.6 4234.2 1233.4 1242.0 1274.3 1284.1 1287.9 16593.3 16623.5 16637.9 16727.2 16852.0 5378.7 5559.7 5587.7 5731.6 5829.3 11841.3 11867.9 11907.9 11912.3 11932.6 6221.6 6351.8 6548.6 6554.2 6556.5 6060.3 6143.2 6157.4 6177.9 6233.8 4353.3 4450.1 4463.7 4465.6 4472.5 1272.6 1286.8 1294.3 1305.4 1307.7 792.1 795.0 823.7 832.9 847.2 5508.9 6246.9 6265.1 6283.7 6529.3 576.1 583.2 594.8 595.1 597.5 3695.2 3990.9 4004.0 4511.6 4666.1 14964.9 15009.3 15343.5 15451.5 15460.0 14836.5 14840.5 15328.0 15409.5 15428.2 8542.6 8750.5 8755.2 8763.7 8769.2 6587.1 6998.7 7107.1 7168.3 7189.6 13496.3 13545.9 13746.7 13879.9 13886.6 13974.4 13988.6 14131.0 14306.9 14382.0 8015.3 9175.0 9405.2 9445.1 9511.9 10869.9 10969.8 10991.0 11006.4 11029.8 8552.7 8689.9 9032.5 9265.7 9274.3 9039.3 9058.0 9070.5 9111.0 9119.0 8254.6 8260.5 8264.0 8359.8 8368.7 5769.0 6014.3 6069.5 6187.8 6277.8 6074.2 6209.1 6228.6 6276.5 6325.1 16:46:56 INFO - PID 1669 | 16:46:56 INFO - PID 1669 | [#4] prototype.html Cycles:180 Average:5326.61 Median:6143.90 stddev:1703.74 (27.7%) stddev-sans-first:1667.60 16:46:56 INFO - PID 1669 | Values: 381.8 398.3 401.4 407.8 409.0 5042.5 5513.8 6175.1 6331.2 6332.3 5064.9 6078.4 6220.5 6225.6 6248.2 4547.9 4562.3 4566.5 4570.8 4610.3 4379.9 4545.5 4559.5 4562.5 4592.0 3704.5 4067.5 4203.3 4495.5 4510.8 4517.8 4519.0 4520.5 4530.3 4552.6 3807.1 3835.3 3871.4 3892.5 3899.5 5820.7 6023.2 6092.5 6095.6 6137.1 4488.6 5373.5 5399.0 5410.9 5434.4 6428.9 6526.3 6590.2 6609.5 6638.6 4048.1 4390.7 4407.5 4428.9 4429.9 6138.7 6293.8 6683.9 6931.5 6936.4 4079.9 4123.0 4400.8 4421.6 4445.3 3794.7 3825.8 4374.6 4508.7 4526.7 5249.3 5350.8 5360.4 5377.7 5396.8 5417.3 6606.9 6607.2 6654.9 6699.3 1473.0 1497.0 1537.9 1538.9 1547.1 984.0 997.0 999.7 1000.5 1017.3 6247.0 6293.9 6299.5 6303.4 6321.3 5680.9 6842.8 6927.4 6948.5 6968.6 6149.1 6198.7 6233.4 6274.9 6310.7 3120.4 3463.4 3598.2 3603.7 3611.5 6787.5 6812.4 7095.3 7135.8 7141.4 6982.0 7131.6 7158.7 7177.6 7195.8 6188.5 6378.0 6394.8 6776.6 6933.9 5448.9 6395.3 6957.0 6995.5 7002.5 5053.1 6052.5 6561.0 6860.3 6871.4 6900.9 7200.5 7216.5 7218.9 7246.4 3908.5 4019.1 4416.7 4458.4 4476.3 6094.8 6223.0 6545.4 6806.8 6918.3 6526.0 6627.5 6698.6 6699.2 6717.5 5362.7 6451.3 6674.8 6678.8 6691.6 5314.0 6417.6 6510.1 6560.3 6584.2 5832.3 6326.7 6619.2 6668.5 6732.3 6304.8 6452.9 6475.7 6548.2 6551.8 16:46:56 INFO - PID 1669 | 16:46:56 INFO - PID 1669 | [#5] yui.html Cycles:180 Average:21588.94 Median:17623.23 stddev:30458.25 (172.8%) stddev-sans-first:30503.31 16:46:56 INFO - PID 1669 | Values: 699.6 701.9 810.2 818.5 820.4 15693.4 16360.9 16363.1 17784.6 18820.2 17014.4 17176.1 17322.8 17382.2 17473.9 986.5 986.9 999.8 1003.1 1003.3 883.1 961.5 1009.8 1013.4 1014.9 1023.9 1035.6 1045.3 1045.4 1045.6 904.2 1043.9 1044.7 1048.0 1051.0 760.6 766.6 771.3 771.4 772.0 13983.7 15955.3 16271.3 16313.4 16377.3 15947.8 15950.0 15986.6 16105.2 16315.3 11160.6 13202.5 13210.4 13687.5 14174.9 3565.9 3569.4 3570.9 3571.7 3579.6 86019.0 118416.0 120077.0 120473.4 121462.0 12249.8 12503.2 12553.1 12576.9 12580.5 18188.5 23711.4 24146.5 24160.0 24161.0 6245.5 7304.5 7382.8 7455.7 7457.8 3665.8 3731.8 3735.4 3748.9 3749.7 4506.7 4666.2 4676.6 4707.3 4712.7 925.8 954.4 955.2 967.0 969.9 642.0 646.8 660.4 671.5 681.0 8923.2 11965.7 12108.8 12542.0 13158.6 649.3 658.4 661.0 674.8 687.6 4972.7 5779.1 5976.7 6036.1 6100.5 99444.0 103959.0 104404.5 105143.0 107186.0 83055.6 91392.0 95818.5 98317.5 98676.0 87079.6 88533.6 89041.0 89258.6 89437.6 11128.1 11816.9 12149.5 12265.0 12271.0 38365.0 39581.8 40365.8 40499.0 42980.7 43892.0 58624.7 66226.3 69523.0 70305.0 12117.8 15583.8 15995.3 16106.7 16166.8 14964.9 16979.8 17772.6 17872.3 17922.0 15654.8 15752.6 15939.1 16709.6 16904.1 15629.4 16310.3 16322.6 16395.0 16396.2 15403.5 15445.8 16181.4 16358.8 16548.2 11013.0 11098.7 11320.4 11449.5 11609.7 11663.9 12052.5 13218.0 13317.5 13366.5 16:46:56 INFO - PID 1669 | -------- Summary: end -------- 16:46:56 INFO - PID 1669 | 16:46:57 INFO - TEST-INFO | 1669: exit 0 16:46:57 INFO - TEST-OK | dromaeo_css | took 1115259ms 16:46:57 INFO - TEST-START | kraken 16:46:57 INFO - operating with platform_type : mac_ 16:46:57 INFO - Initialising browser for kraken test... 16:46:57 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/tmp7mXbam/profile http://localhost:49235/getInfo.html) 16:46:59 INFO - PID 1680 | 2017-05-13 16:46:59.860 plugin-container[1681:22361] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x933f, name = 'com.apple.tsm.portname' 16:46:59 INFO - PID 1680 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:46:59 INFO - PID 1680 | 2017-05-13 16:46:59.863 plugin-container[1681:22361] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4707, name = 'com.apple.CFPasteboardClient' 16:46:59 INFO - PID 1680 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:46:59 INFO - PID 1680 | 2017-05-13 16:46:59.863 plugin-container[1681:22361] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:46:59 INFO - PID 1680 | __metrics Screen width/height:1600/1200 16:46:59 INFO - PID 1680 | colorDepth:24 16:46:59 INFO - PID 1680 | Browser inner width/height: 1024/691 16:46:59 INFO - PID 1680 | __metrics 16:47:00 INFO - TEST-INFO | 1680: exit 0 16:47:00 INFO - Browser initialized. 16:47:00 INFO - Running cycle 1/1 for kraken test... 16:47:00 INFO - TEST-INFO | started process 1682 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/tmp7mXbam/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:47:03 INFO - PID 1682 | 2017-05-13 16:47:03.082 plugin-container[1683:22487] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x923f, name = 'com.apple.tsm.portname' 16:47:03 INFO - PID 1682 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:47:03 INFO - PID 1682 | 2017-05-13 16:47:03.083 plugin-container[1683:22487] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980b, name = 'com.apple.CFPasteboardClient' 16:47:03 INFO - PID 1682 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:47:03 INFO - PID 1682 | 2017-05-13 16:47:03.083 plugin-container[1683:22487] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:47:03 INFO - PID 1682 | 2017-05-13 16:47:03.544 plugin-container[1684:22531] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 16:47:03 INFO - PID 1682 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:47:03 INFO - PID 1682 | 2017-05-13 16:47:03.545 plugin-container[1684:22531] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x450f, name = 'com.apple.CFPasteboardClient' 16:47:03 INFO - PID 1682 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:47:03 INFO - PID 1682 | 2017-05-13 16:47:03.545 plugin-container[1684:22531] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:47:12 INFO - PID 1682 | RSS: Main: 244850688 16:47:12 INFO - PID 1682 | 16:47:15 INFO - PID 1682 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection) 16:47:16 INFO - PID 1682 | RSS: Main: 255565824 16:47:16 INFO - PID 1682 | 16:47:19 INFO - PID 1682 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-dft) 16:47:19 INFO - PID 1682 | RSS: Main: 262623232 16:47:19 INFO - PID 1682 | 16:47:23 INFO - PID 1682 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-fft) 16:47:23 INFO - PID 1682 | RSS: Main: 257720320 16:47:23 INFO - PID 1682 | 16:47:25 INFO - PID 1682 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator) 16:47:26 INFO - PID 1682 | RSS: Main: 259002368 16:47:26 INFO - PID 1682 | 16:47:28 INFO - PID 1682 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:47:29 INFO - PID 1682 | RSS: Main: 260567040 16:47:29 INFO - PID 1682 | 16:47:32 INFO - PID 1682 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom) 16:47:32 INFO - PID 1682 | RSS: Main: 266272768 16:47:32 INFO - PID 1682 | 16:47:35 INFO - PID 1682 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate) 16:47:36 INFO - PID 1682 | RSS: Main: 268390400 16:47:36 INFO - PID 1682 | 16:47:39 INFO - PID 1682 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial) 16:47:39 INFO - PID 1682 | RSS: Main: 267501568 16:47:39 INFO - PID 1682 | 16:47:41 INFO - PID 1682 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:47:42 INFO - PID 1682 | RSS: Main: 266756096 16:47:42 INFO - PID 1682 | 16:47:43 INFO - PID 1682 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:47:44 INFO - PID 1682 | RSS: Main: 267206656 16:47:44 INFO - PID 1682 | 16:47:46 INFO - PID 1682 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:47:47 INFO - PID 1682 | RSS: Main: 267067392 16:47:47 INFO - PID 1682 | 16:47:49 INFO - PID 1682 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:47:50 INFO - PID 1682 | RSS: Main: 266924032 16:47:50 INFO - PID 1682 | 16:47:53 INFO - PID 1682 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:47:54 INFO - PID 1682 | RSS: Main: 267173888 16:47:54 INFO - PID 1682 | 16:47:56 INFO - PID 1682 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49235/tests/kraken/driver.html?testName=ai-astar) 16:47:56 INFO - PID 1682 | RSS: Main: 268623872 16:47:56 INFO - PID 1682 | 16:47:56 INFO - PID 1682 | __start_tp_report 16:47:56 INFO - PID 1682 | _x_x_mozilla_page_load 16:47:56 INFO - PID 1682 | _x_x_mozilla_page_load_details 16:47:56 INFO - PID 1682 | |i|pagename|runs| 16:47:56 INFO - PID 1682 | |0;ai-astar;102;115;115;116;115;114;115;115;118;116 16:47:56 INFO - PID 1682 | |1;audio-beat-detection;139;109;110;148;108;114;111;109;159;143 16:47:56 INFO - PID 1682 | |2;audio-dft;200;199;200;216;215;223;243;204;197;200 16:47:56 INFO - PID 1682 | |3;audio-fft;97;84;69;109;85;82;59;60;77;59 16:47:56 INFO - PID 1682 | |4;audio-oscillator;77;61;88;103;98;101;97;72;70;90 16:47:56 INFO - PID 1682 | |5;imaging-gaussian-blur;91;91;90;90;90;89;90;125;128;125 16:47:56 INFO - PID 1682 | |6;imaging-darkroom;97;99;97;98;97;98;98;98;97;99 16:47:56 INFO - PID 1682 | |7;imaging-desaturate;91;94;93;90;92;90;93;101;146;145 16:47:56 INFO - PID 1682 | |8;json-parse-financial;50;45;49;49;52;50;51;49;49;51 16:47:56 INFO - PID 1682 | |9;json-stringify-tinderbox;58;61;59;68;64;58;56;57;56;57 16:47:56 INFO - PID 1682 | |10;stanford-crypto-aes;81;84;83;83;82;85;82;86;83;81 16:47:56 INFO - PID 1682 | |11;stanford-crypto-ccm;164;132;147;153;152;141;137;138;196;146 16:47:56 INFO - PID 1682 | |12;stanford-crypto-pbkdf2;195;235;184;184;185;187;189;183;183;185 16:47:56 INFO - PID 1682 | |13;stanford-crypto-sha256-iterative;72;71;70;67;72;82;58;63;68;85 16:47:56 INFO - PID 1682 | __end_tp_report 16:47:56 INFO - PID 1682 | __start_cc_report 16:47:56 INFO - PID 1682 | _x_x_mozilla_cycle_collect,991 16:47:56 INFO - PID 1682 | __end_cc_report 16:47:56 INFO - PID 1682 | __startTimestamp1494719276521__endTimestamp 16:47:56 INFO - PID 1682 | 16:47:56 INFO - PID 1682 | ------- Summary: start ------- 16:47:56 INFO - PID 1682 | Number of tests: 14 16:47:56 INFO - PID 1682 | 16:47:56 INFO - PID 1682 | [#0] ai-astar Cycles:10 Average:114.10 Median:115.00 stddev:4.38 (3.8%) stddev-sans-first:1.13 16:47:56 INFO - PID 1682 | Values: 102.0 115.0 115.0 116.0 115.0 114.0 115.0 115.0 118.0 116.0 16:47:56 INFO - PID 1682 | 16:47:56 INFO - PID 1682 | [#1] audio-beat-detection Cycles:10 Average:125.00 Median:126.50 stddev:19.86 (15.7%) stddev-sans-first:20.40 16:47:56 INFO - PID 1682 | Values: 139.0 109.0 110.0 148.0 108.0 114.0 111.0 109.0 159.0 143.0 16:47:56 INFO - PID 1682 | 16:47:56 INFO - PID 1682 | [#2] audio-dft Cycles:10 Average:209.70 Median:209.50 stddev:14.70 (7.0%) stddev-sans-first:15.16 16:47:56 INFO - PID 1682 | Values: 200.0 199.0 200.0 216.0 215.0 223.0 243.0 204.0 197.0 200.0 16:47:56 INFO - PID 1682 | 16:47:56 INFO - PID 1682 | [#3] audio-fft Cycles:10 Average:78.10 Median:79.50 stddev:16.84 (21.2%) stddev-sans-first:16.41 16:47:56 INFO - PID 1682 | Values: 97.0 84.0 69.0 109.0 85.0 82.0 59.0 60.0 77.0 59.0 16:47:56 INFO - PID 1682 | 16:47:56 INFO - PID 1682 | [#4] audio-oscillator Cycles:10 Average:85.70 Median:82.50 stddev:14.74 (17.9%) stddev-sans-first:15.30 16:47:56 INFO - PID 1682 | Values: 77.0 61.0 88.0 103.0 98.0 101.0 97.0 72.0 70.0 90.0 16:47:56 INFO - PID 1682 | 16:47:56 INFO - PID 1682 | [#5] imaging-gaussian-blur Cycles:10 Average:100.90 Median:90.00 stddev:17.35 (19.3%) stddev-sans-first:18.03 16:47:56 INFO - PID 1682 | Values: 91.0 91.0 90.0 90.0 90.0 89.0 90.0 125.0 128.0 125.0 16:47:56 INFO - PID 1682 | 16:47:56 INFO - PID 1682 | [#6] imaging-darkroom Cycles:10 Average:97.80 Median:98.00 stddev:0.79 (0.8%) stddev-sans-first:0.78 16:47:56 INFO - PID 1682 | Values: 97.0 99.0 97.0 98.0 97.0 98.0 98.0 98.0 97.0 99.0 16:47:56 INFO - PID 1682 | 16:47:56 INFO - PID 1682 | [#7] imaging-desaturate Cycles:10 Average:103.50 Median:91.50 stddev:22.36 (24.4%) stddev-sans-first:23.25 16:47:56 INFO - PID 1682 | Values: 91.0 94.0 93.0 90.0 92.0 90.0 93.0 101.0 146.0 145.0 16:47:56 INFO - PID 1682 | 16:47:56 INFO - PID 1682 | [#8] json-parse-financial Cycles:10 Average:49.50 Median:50.00 stddev:1.90 (3.8%) stddev-sans-first:2.01 16:47:56 INFO - PID 1682 | Values: 50.0 45.0 49.0 49.0 52.0 50.0 51.0 49.0 49.0 51.0 16:47:56 INFO - PID 1682 | 16:47:56 INFO - PID 1682 | [#9] json-stringify-tinderbox Cycles:10 Average:59.40 Median:58.50 stddev:3.89 (6.7%) stddev-sans-first:4.10 16:47:56 INFO - PID 1682 | Values: 58.0 61.0 59.0 68.0 64.0 58.0 56.0 57.0 56.0 57.0 16:47:56 INFO - PID 1682 | 16:47:56 INFO - PID 1682 | [#10] stanford-crypto-aes Cycles:10 Average:83.00 Median:83.00 stddev:1.63 (2.0%) stddev-sans-first:1.56 16:47:56 INFO - PID 1682 | Values: 81.0 84.0 83.0 83.0 82.0 85.0 82.0 86.0 83.0 81.0 16:47:56 INFO - PID 1682 | 16:47:56 INFO - PID 1682 | [#11] stanford-crypto-ccm Cycles:10 Average:150.60 Median:149.50 stddev:18.45 (12.3%) stddev-sans-first:18.92 16:47:56 INFO - PID 1682 | Values: 164.0 132.0 147.0 153.0 152.0 141.0 137.0 138.0 196.0 146.0 16:47:56 INFO - PID 1682 | 16:47:56 INFO - PID 1682 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:191.00 Median:186.00 stddev:15.88 (8.5%) stddev-sans-first:16.78 16:47:56 INFO - PID 1682 | Values: 195.0 235.0 184.0 184.0 185.0 187.0 189.0 183.0 183.0 185.0 16:47:56 INFO - PID 1682 | 16:47:56 INFO - PID 1682 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.80 Median:71.50 stddev:8.01 (11.2%) stddev-sans-first:8.49 16:47:56 INFO - PID 1682 | Values: 72.0 71.0 70.0 67.0 72.0 82.0 58.0 63.0 68.0 85.0 16:47:56 INFO - PID 1682 | -------- Summary: end -------- 16:47:56 INFO - PID 1682 | 16:47:57 INFO - TEST-INFO | 1682: exit 0 16:47:57 INFO - TEST-OK | kraken | took 60030ms 16:47:57 INFO - SUITE-END | took 1175s 16:47:57 INFO - Completed test suite (00:19:35) 16:47:57 INFO - javascript benchmark 16:47:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7931.3697889492005, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3204.5353423753554, 3567.5183850180224, 3620.0, 3651.1602331463764, 3654.5249117614712, 4845.757712114393, 6128.380904898417, 6204.503639708818, 6239.313675495698, 6320.778772742955, 6176.845578860531, 6233.9402301608725, 6248.187735594378, 6289.716962736677, 6310.49516038717, 5522.972385138069, 5531.778728850841, 5569.470900264469, 5581.609287349887, 5601.831945041656, 5603.523700485463, 5677.687727175004, 5683.488486036256, 5692.146178073281, 5705.343885453184, 5445.210444485528, 5504.587155963279, 5606.299212598426, 5628.099504079369, 5680.147977803321, 5552.694601796899, 5616.129499927518, 5621.747021384048, 5644.858878528029, 5674.233978412903, 5505.174223866412, 5526.834194974157, 5554.444555544454, 5581.107022876361, 5584.329880414376, 5388.5419739321815, 5942.70286485674, 6146.725711486779, 6292.370762923717, 6303.401176888188, 6559.93440065598, 6659.833504162386, 6727.226368967583, 6738.797836064926, 6760.661966901635, 6832.350926661959, 6867.969804529361, 6868.347506986828, 6869.107016087876, 6879.140107486564, 6263.091851681481, 6335.461485773669, 6350.396712312369, 6357.650329231937, 6362.0, 6724.092247546519, 6830.7609233676585, 6887.070245516891, 6962.373386395248, 6978.616176110363, 6414.967925160345, 6469.51478639104, 6499.610023398611, 6627.271000189975, 6686.431653309426, 5503.669779813224, 6668.0, 6794.320567943166, 6800.795976120724, 6815.829604259933, 6663.433608143265, 6666.500012499082, 6712.865742685119, 6720.529562930548, 6742.528023038439, 6477.319881412478, 6559.442447392025, 6662.633555154416, 6700.49746269022, 6749.966250168718, 5339.439358867341, 5347.839564813062, 5357.223202635674, 5372.301600791872, 5391.81128660495, 4718.41019872516, 4753.762311884392, 4756.809727610926, 4764.332993380929, 4778.904421911542, 6225.28409232935, 6282.968585157137, 6301.212348456443, 6375.585586936834, 6386.584871983307, 6798.66006699663, 6826.214985276657, 6871.828204294832, 6880.483963702743, 6883.139607549057, 4724.267738500538, 6076.572005578685, 6243.750249989949, 6361.681915904279, 6391.137196478416, 5820.88358232833, 5908.231929849092, 6027.397260273938, 6041.486473649701, 6043.516518678495, 6839.69221385029, 6907.447404207752, 6978.47661425385, 6978.651067446709, 7007.824804379931, 5746.367899531132, 6795.28649491816, 6821.420179284816, 6838.965805170942, 6852.314768523108, 6929.757458488929, 6943.861122777617, 6952.200496942714, 7026.367626913602, 7029.61337126463, 6469.967650161719, 6547.738090476328, 6620.735170593122, 6691.631960242234, 6718.160229971254, 6773.627450490073, 6839.521233513607, 6852.246252912275, 6852.794416167523, 6871.896921546081, 4854.95145048545, 5414.7021913795115, 6347.111404403479, 6519.8044058678315, 6606.933930660632, 5707.229524014371, 6460.095586617783, 6463.482921366185, 6615.569987950767, 6615.933840661724, 6305.148804911279, 6476.838079048061, 6490.0, 6491.545591808528, 6509.153810004669, 6421.133147025279, 6512.413882750575, 6557.639329836714, 6578.572392794453, 6638.8008359747355, 6466.127072845294, 6562.34376562359, 6562.376574225441, 6569.638669873012, 6575.0], "value": 6131.811135852816, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [691.4917535611324, 790.0637744273035, 819.5861090149475, 1023.8054769593776, 1283.473775751942, 38723.0, 39237.80381098098, 39424.42302307908, 39462.210755784865, 39469.40795888064, 39393.21213575727, 39547.60452395475, 39892.40161397582, 39973.0, 40091.799541002256, 7863.13505514393, 8034.915286436344, 8290.046644635853, 8461.746147615582, 8568.400211985163, 8363.95818020909, 8543.060263371024, 8615.396922215448, 8622.35332350073, 8659.177378149097, 8550.52972086535, 8600.56997150143, 8614.224719775219, 8630.913690863073, 8662.913370866305, 8520.062793092755, 8573.142685731407, 8610.698625548139, 8611.35414843886, 8620.78448038804, 6819.5908245505425, 7960.681572737083, 8362.996440427187, 8410.32717382608, 8461.873071903927, 36371.272574548624, 36478.27043459116, 36652.63347366545, 36673.0, 36731.0, 3543.362194804934, 3572.8928132156075, 3581.068922080252, 3581.1047238190454, 3587.1032241939515, 30150.54774178367, 30207.395852083057, 30295.848520757474, 30365.696343036507, 30372.240693982607, 3098.581691471645, 3134.670859559736, 3137.278425962019, 3150.322680623677, 3150.54317124018, 251646.74176629182, 292852.0, 299782.50108749536, 309687.0, 310825.44587276917, 47308.763456182496, 47355.0, 47357.0, 47401.52598473971, 47474.0, 49260.0, 49476.0104797902, 51021.97956040858, 54586.36240912779, 54642.726786366606, 14126.293685315693, 14205.21871297068, 14215.715685686228, 14268.072575282573, 14288.285585720672, 11204.215704900742, 11363.261388009752, 11451.25566838153, 11456.19806613529, 11471.311721296748, 14098.71802563943, 14112.50606228777, 14148.0, 14169.22069286199, 14239.64400889986, 4876.853694389174, 5654.6324488908085, 6231.345708700612, 6250.749970001149, 6322.514209160843, 920.1383696703248, 968.801395713877, 1024.9846252306222, 1036.8128492875767, 1068.417712346773, 23838.880805596207, 24632.630510542356, 24719.775220229905, 24823.751762482145, 30462.933797316986, 1081.491698901515, 1083.1226706367868, 1110.9666709998712, 1112.449337577904, 1117.3910218930703, 12096.334701591497, 12400.503979840705, 12425.19236249641, 12444.564440244549, 12449.875501245053, 279969.0, 285857.0, 286061.0, 287343.0, 298381.0, 55038.0, 55312.72343638256, 55467.0, 55480.72259638676, 55622.0, 1346.501794336102, 1497.1615895098562, 1523.5657837516255, 1530.50258665934, 1542.8688561472177, 28987.710122899345, 29230.0, 30007.549886752153, 30056.098317050524, 30129.698703013568, 53205.73397133144, 54120.188197177864, 54146.72926635342, 54219.64450888601, 54634.45365546294, 281925.0, 286410.0, 286482.0, 287277.0, 287673.0, 37528.2494350106, 37544.62455375411, 37679.0, 37825.2434951305, 37830.62169378271, 43953.0, 46004.0, 47567.76216118897, 47594.762026189645, 47791.0, 27359.179224623294, 30924.0, 32361.352772943937, 32758.508622370206, 33208.50187247145, 31909.68090319067, 32811.50782738213, 33012.50481242831, 33018.504722429665, 33206.0, 38433.42349864698, 38683.613163868, 38778.41832372573, 40070.0, 40194.0, 32207.194820129684, 32496.83751581227, 32775.0, 33019.0, 33236.50145247872, 21847.0, 22727.545449091256, 24556.508869822148, 25284.49431011333, 26211.475770484863], "value": 17998.88982331162, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [423.0398576688328, 424.3126135660231, 435.9128174365128, 445.7815670321542, 449.3798557989973, 20146.49633759157, 21007.579848403042, 21082.36752897412, 21839.672404913937, 22142.557148857035, 22533.661995070088, 23216.65175022376, 23597.29208123755, 23940.760592394072, 24194.395140121505, 6190.845228869281, 6306.716197771111, 6354.269259035201, 6416.903746443807, 6418.967905160468, 6044.667543285118, 6225.501959843202, 6274.247090349165, 6289.591176573531, 6302.243730752316, 5828.213191219194, 6064.120702498135, 6234.563580549363, 6260.467860231885, 6269.153664255313, 5430.8913821723545, 6015.428534289235, 6105.847353816146, 6144.662043587601, 6147.2930612979635, 5366.865828354284, 5422.8373148805595, 5455.236266922634, 5475.780968761284, 5485.0949593317055, 28090.719092809013, 28360.716392836013, 28470.572941405895, 28946.42107157845, 29167.0, 2207.536417352358, 2254.0, 2260.559190957764, 2260.8756518391483, 2265.818734501236, 18123.72814407785, 18155.185946178364, 18302.359417420328, 18411.355602553846, 18668.346607868658, 2174.6520556710852, 2214.1032881682945, 2229.3312006398014, 2245.158065725353, 2246.48330883896, 136178.63821361857, 162503.0, 168437.0, 169492.0, 170091.99999999875, 12913.935430322976, 12915.612531624067, 13139.211647301192, 13231.470741170438, 13371.729685679846, 13930.164190148624, 16919.492415227316, 16932.23804928781, 16941.15294235308, 16945.237464314134, 8762.956185219033, 9100.22648074921, 9126.863097053549, 9211.63153473867, 9230.907690923139, 8631.093735157843, 8661.177188167114, 8688.174623410765, 8783.385163038525, 8829.072947340564, 8027.197280272043, 8115.147909469539, 8128.065272493739, 8186.222308880647, 8245.546494942835, 1963.6465436221617, 2182.6289530779804, 2194.3307291276315, 2206.5917805206086, 2361.1381845626565, 904.6924045824451, 932.9364524442142, 945.8108378324363, 960.9135177834029, 973.8928717841031, 20368.796312036986, 20980.89509552473, 21378.14487420517, 21438.57122857534, 21459.57080858374, 944.551338114404, 973.8782652168479, 1000.8648832407678, 1002.929794914349, 1020.7295066807302, 9591.952040239754, 10606.355820006824, 11093.914320296977, 11169.72075698114, 11187.0, 518611.0, 528555.0, 534495.0, 534523.0, 535043.3247833736, 128233.0, 129513.35243323723, 129730.99999999811, 130808.3459582696, 132057.0, 1189.2864281431246, 1222.5109956017664, 1232.624049664843, 1235.215638069832, 1237.975240495185, 22116.557668846854, 23035.769642303363, 23154.07383704633, 23190.65214021757, 23499.0, 48060.0, 57379.0, 57904.71047644735, 58588.12117818151, 59741.402585975324, 77362.61318693371, 77743.83384249128, 78845.97218838659, 79560.60219698865, 79778.60110699642, 8276.834463310579, 8406.369522285975, 8873.511956842436, 8961.193492585438, 9036.23192028685, 13501.459941602227, 14200.360983755549, 14245.002849800829, 14435.422583096979, 14605.269736513344, 11786.705332366759, 11831.290122592673, 11853.170278080452, 12030.879691203327, 12123.818142728043, 11154.776904462027, 11162.0, 11314.547418103184, 11351.091912646803, 11425.149700598802, 11835.704107397383, 11983.700407489883, 11986.580469683518, 12045.879541204826, 12059.698507537381, 8784.692535761218, 8830.602622881857, 9003.279737621106, 9169.266458683156, 9193.172614464731, 10116.36617889433, 11752.76494470123, 11944.044476442037, 12009.879901200877, 12057.975072118968], "value": 10604.429665304844, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1272.7454509098184, 1326.4959315460123, 1382.1430712957967, 1389.854065323141, 1465.4284828916725, 11666.300021998664, 11769.941150294248, 12251.387430628467, 12294.016478681706, 12328.568500102487, 12072.275663460177, 12228.755424891497, 12248.693782655439, 12251.877481225185, 12292.446839892204, 2595.5457794885915, 2641.247244535308, 2653.8142330036903, 2658.667666541682, 2667.7465640764094, 2561.9743802562016, 2617.1625079974415, 2620.973790262092, 2621.8295810772333, 2636.617690434886, 2581.367564946591, 2585.4441295121546, 2619.7380261973744, 2624.6719160104985, 2626.776723978464, 2565.589505679092, 2581.59985202293, 2607.7261887501822, 2612.3469132716823, 2615.3853844346563, 2181.203860590889, 2249.347689170139, 2433.4889673168655, 2454.2882564056404, 2462.507498500307, 11770.646880593597, 12049.49381327334, 12070.698232544257, 12087.093467989936, 12094.455749491206, 6644.534882558223, 6796.864062718718, 6841.692123854438, 6900.275471075565, 6903.723851045952, 3522.5068490411363, 3609.2781443711365, 4166.229247589205, 4223.64099051581, 4234.237837189305, 1233.3771445420005, 1241.9875801242054, 1274.3182397417338, 1284.113961366654, 1287.871212878714, 16593.336266549322, 16623.50129496117, 16637.91681041599, 16727.16364181786, 16852.0, 5378.650387724785, 5559.694216818114, 5587.664740115606, 5731.570132240098, 5829.300483941869, 11841.348725820162, 11867.881321186678, 11907.940460297643, 11912.344821034927, 11932.642020739391, 6221.595596286317, 6351.809445716636, 6548.639824809681, 6554.180727409074, 6556.541042127101, 6060.303065147476, 6143.201383820164, 6157.4458298753325, 6177.938220617736, 6233.75064997395, 4353.303471444554, 4450.087732014929, 4463.732176069446, 4465.6427485801705, 4472.508024117345, 1272.611853384708, 1286.7876800328029, 1294.2687381629348, 1305.3799445263614, 1307.6730817295677, 792.1405275276275, 794.9721759738381, 823.7364043506141, 832.9375296852639, 847.1824689174975, 5508.944910550923, 6246.937530624727, 6265.122882796412, 6283.6858157091965, 6529.347065293433, 576.1127863090877, 583.1573376470959, 594.7888499582673, 595.0598055073061, 597.52496765071, 3695.2055308108347, 3990.920181596352, 4004.039030632627, 4511.571400716926, 4666.113438446684, 14964.850351496563, 15009.324580393908, 15343.539693809204, 15451.536453906401, 15460.0, 14836.480723174853, 14840.480583179537, 15328.0, 15409.537713868602, 15428.228588570526, 8542.572871356408, 8750.47497150173, 8755.212030917248, 8763.729259257372, 8769.166929141722, 6587.143671322698, 6998.685059172248, 7107.076080109553, 7168.283171682999, 7189.640517973976, 13496.257705826274, 13545.932270338586, 13746.65633359174, 13879.918012297832, 13886.583402497941, 13974.441022358991, 13988.58034258974, 14131.0, 14306.928465357607, 14382.0, 8015.318697910743, 9175.0, 9405.153536181777, 9445.149936505748, 9511.904880951102, 10869.891301087204, 10969.780604388026, 10991.010809027226, 11006.394648294101, 11029.779404411707, 8552.67433547801, 8689.870316858767, 9032.45805251687, 9265.675701350421, 9274.304427168125, 9039.322050846344, 9058.0, 9070.501122438329, 9110.954445227731, 9118.996910339725, 8254.628541715518, 8260.463069900437, 8264.0, 8359.791005224917, 8368.70709525188, 5768.971155144365, 6014.278286605635, 6069.484093852072, 6187.752489900354, 6277.811665650038, 6074.1799857020505, 6209.068639704081, 6228.5951413158, 6276.529260305404, 6325.146105275729], "value": 5378.205812848169, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.8243607940347, 398.34074606526207, 401.41193152032275, 407.7981399207392, 409.0265168898022, 5042.4957504249605, 5513.807016754415, 6175.104609831572, 6331.2459080300105, 6332.303446620868, 5064.949350506494, 6078.361772013941, 6220.502359811216, 6225.564210505267, 6248.187735594366, 4547.909041819162, 4562.338460923164, 4566.4520257568975, 4570.794314255866, 4610.2854057621125, 4379.890502737441, 4545.47727011393, 4559.45286565611, 4562.494376855646, 4591.989762252316, 3704.481372607837, 4067.474696988629, 4203.264428724976, 4495.505494395614, 4510.751908645023, 4517.774111294439, 4518.977405112986, 4520.502744698113, 4530.343100250462, 4552.567506086916, 3807.124361396894, 3835.2904712627974, 3871.380579107329, 3892.4550562921213, 3899.512560929884, 5820.692687411328, 6023.247094113236, 6092.482139018189, 6095.634261944253, 6137.140800287919, 4488.5960263576435, 5373.543248823855, 5399.0, 5410.945890541084, 5434.375046869582, 6428.935710642881, 6526.314736952599, 6590.176227971504, 6609.504287178433, 6638.568493047985, 4048.1296521248073, 4390.714603550759, 4407.515173330931, 4428.889277768049, 4429.933550996738, 6138.693065346715, 6293.842653933642, 6683.899741503881, 6931.549449285781, 6936.375726184617, 4079.938800917989, 4123.010477485382, 4400.845970391021, 4421.579949904753, 4445.288753799441, 3794.6774524165203, 3825.8087095645665, 4374.606285434326, 4508.706934049276, 4526.728396296233, 5249.317588713519, 5350.812721554805, 5360.356757189163, 5377.650452720561, 5396.763540620673, 5417.295751552273, 6606.900896486556, 6607.240167380813, 6654.870441458784, 6699.263081061079, 1472.9631759206106, 1497.004492012821, 1537.8769698424098, 1538.94550774717, 1547.1490680125885, 983.9754006149903, 996.9800603987879, 999.6950929966284, 1000.5247507433911, 1017.2777328096986, 6247.0, 6293.89154633203, 6299.5275354348605, 6303.447103922918, 6321.304656487782, 5680.914786278209, 6842.794716158523, 6927.445804335641, 6948.548344357701, 6968.616726080013, 6149.108379285068, 6198.729260501586, 6233.438990490787, 6274.937250627435, 6310.684465776692, 3120.3929976062345, 3463.393906066479, 3598.1649358827185, 3603.72972027099, 3611.476335931328, 6787.457003439713, 6812.386885180258, 7095.254998225215, 7135.750248741269, 7141.428685705131, 6982.0, 7131.607761573059, 7158.677859496335, 7177.569345839266, 7195.784126476109, 6188.504919606421, 6378.002338900709, 6394.784990851724, 6776.559523631046, 6933.890577507575, 5448.918266226089, 6395.328490508429, 6957.0, 6995.475339349671, 7002.544834585736, 5053.083860008523, 6052.455279024909, 6561.0, 6860.3139686031, 6871.419573498023, 6900.93099069023, 7200.49596528238, 7216.530925489827, 7218.942601358797, 7246.4202863771825, 3908.511436070491, 4019.1157945252, 4416.712913660602, 4458.375827384234, 4476.3365797350525, 6094.8171554853425, 6223.0, 6545.443637290884, 6806.76176333826, 6918.342757438036, 6526.0, 6627.4698024158915, 6698.56459330142, 6699.196096468455, 6717.462602991846, 5362.65142765719, 6451.2903580607035, 6674.799756007327, 6678.76624318166, 6691.64668265376, 5314.0, 6417.647029413427, 6510.121133647087, 6560.343965603402, 6584.176977877765, 5832.300123985149, 6326.6520341381665, 6619.172603424572, 6668.499862510234, 6732.293109223657, 6304.810855674337, 6452.870942581216, 6475.740970361133, 6548.24973511136, 6551.80344589663], "value": 4793.406307646855, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [699.6012273004383, 701.9400704935546, 810.1898101898101, 818.4679958027283, 820.4339006085801, 15693.372265109396, 16360.918195409036, 16363.100029498373, 17784.55538611532, 18820.24719011241, 17014.40449584265, 17176.05531695756, 17322.82677173231, 17382.21780019899, 17473.912630436862, 986.4722373530162, 986.8815742110921, 999.8000399920008, 1003.0771690045176, 1003.2575893838543, 883.1257055515058, 961.4567769210408, 1009.7576581620395, 1013.3717095400862, 1014.9035841595037, 1023.8924912884152, 1035.554711474066, 1045.2839804733744, 1045.3884477580625, 1045.6235755128148, 904.1771987491384, 1043.8903915088881, 1044.7492601775557, 1048.025336437113, 1051.043550369168, 760.577879277001, 766.5668897073136, 771.3135309574483, 771.4445599168589, 771.9768406947801, 13983.720325593431, 15955.282012309473, 16271.267792949346, 16313.429029984012, 16377.344906203738, 15947.760783588255, 15950.0, 15986.641135503496, 16105.194740263058, 16315.265813038443, 11160.553577856876, 13202.471901123943, 13210.405531751094, 13687.452501899912, 14174.92912535441, 3565.9108522286892, 3569.446735755962, 3570.8571657133684, 3571.714262858965, 3579.570451545831, 86019.0, 118416.0, 120077.0, 120473.39763301215, 121462.0, 12249.755004899853, 12503.249805011728, 12553.121281510206, 12576.874231257754, 12580.496780128875, 18188.454346369632, 23711.407214819767, 24146.51706965886, 24160.03359865621, 24161.0, 6245.500359971283, 7304.5252058616015, 7382.815429614303, 7455.739049133254, 7457.776266712007, 3665.7617254878346, 3731.7574357666663, 3735.3836616958442, 3748.9437658435145, 3749.6625303722794, 4506.662000349987, 4666.2067448533835, 4676.625869930397, 4707.293905914072, 4712.693674911188, 925.7593025813337, 954.4368822394821, 955.2358113509165, 967.0184762466102, 969.898160693117, 641.9550631455753, 646.8382904273932, 660.4155322539588, 671.4829581222432, 680.9625470599165, 8923.226074050352, 11965.700857478458, 12108.81836772449, 12542.0, 13158.60524184276, 649.3344322069917, 658.3548122839549, 660.9900851487232, 674.780696273713, 687.5908834243617, 4972.67677600962, 5779.133130030529, 5976.701164941735, 6036.094585812163, 6100.48145907594, 99444.0, 103958.96041039644, 104404.47797761114, 105142.95998920157, 107186.0, 83055.584722076, 91392.0, 95818.5209073964, 98317.50841245748, 98676.0, 87079.56460217659, 88533.55733221292, 89041.0, 89258.55370723235, 89437.55281223683, 11128.109751220045, 11816.881831181921, 12149.453274602842, 12265.0, 12271.0, 38365.040873977254, 39581.80209098936, 40365.79817100896, 40499.0, 42980.71057868269, 43892.0, 58624.706876465345, 66226.33773662202, 69523.0, 70305.0, 12117.757644846877, 15583.76624350613, 15995.280212390238, 16106.67786644284, 16166.757498637766, 14964.877634177697, 16979.830201697823, 17772.555686107433, 17872.285108596032, 17922.0, 15654.843451565339, 15752.60618484547, 15939.123348215959, 16709.582260443585, 16904.07027613493, 15629.374825006873, 16310.266038028554, 16322.591935201715, 16395.016299022096, 16396.180190990643, 15403.460878869186, 15445.84554154444, 16181.352745890503, 16358.754618680492, 16548.172591370625, 11013.0, 11098.667039988813, 11320.37737924422, 11449.48477318506, 11609.709757256136, 11663.88336116628, 12052.51789928393, 13218.0, 13317.533886313933, 13366.465341386236], "value": 8250.008004022056, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1519.0999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 115.0, 115.0, 116.0, 115.0, 114.0, 115.0, 115.0, 118.0, 116.0], "value": 114.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 109.0, 110.0, 148.0, 108.0, 114.0, 111.0, 109.0, 159.0, 143.0], "value": 125.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 199.0, 200.0, 216.0, 215.0, 223.0, 243.0, 204.0, 197.0, 200.0], "value": 209.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 84.0, 69.0, 109.0, 85.0, 82.0, 59.0, 60.0, 77.0, 59.0], "value": 78.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 61.0, 88.0, 103.0, 98.0, 101.0, 97.0, 72.0, 70.0, 90.0], "value": 85.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 90.0, 90.0, 90.0, 89.0, 90.0, 125.0, 128.0, 125.0], "value": 100.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 99.0, 97.0, 98.0, 97.0, 98.0, 98.0, 98.0, 97.0, 99.0], "value": 97.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 94.0, 93.0, 90.0, 92.0, 90.0, 93.0, 101.0, 146.0, 145.0], "value": 103.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 45.0, 49.0, 49.0, 52.0, 50.0, 51.0, 49.0, 49.0, 51.0], "value": 49.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 61.0, 59.0, 68.0, 64.0, 58.0, 56.0, 57.0, 56.0, 57.0], "value": 59.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 84.0, 83.0, 83.0, 82.0, 85.0, 82.0, 86.0, 83.0, 81.0], "value": 83.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 132.0, 147.0, 153.0, 152.0, 141.0, 137.0, 138.0, 196.0, 146.0], "value": 150.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 235.0, 184.0, 184.0, 185.0, 187.0, 189.0, 183.0, 183.0, 185.0], "value": 191.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 71.0, 70.0, 67.0, 72.0, 82.0, 58.0, 63.0, 68.0, 85.0], "value": 70.8, "unit": "score"}]}]} 16:47:57 INFO - Return code: 0 16:47:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:47:57 INFO - # TBPL SUCCESS # 16:47:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:47:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:47:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:47:57 INFO - 2017-05-13 16:47:57.843 screenresolution[1689:22912] starting screenresolution argv=screenresolution get 16:47:57 INFO - 2017-05-13 16:47:57.848 screenresolution[1689:22912] Display 0: 1600x1200x32@60 16:47:57 INFO - 2017-05-13 16:47:57.854 screenresolution[1690:22915] starting screenresolution argv=screenresolution list 16:47:57 INFO - Available Modes on Display 0 16:47:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:47:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:47:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:47:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:47:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:47:58 INFO - Intel Iris: 16:47:58 INFO - Chipset Model: Intel Iris 16:47:58 INFO - Type: GPU 16:47:58 INFO - Bus: Built-In 16:47:58 INFO - VRAM (Dynamic, Max): 1536 MB 16:47:58 INFO - Vendor: Intel (0x8086) 16:47:58 INFO - Device ID: 0x0a2e 16:47:58 INFO - Revision ID: 0x0009 16:47:58 INFO - Displays: 16:47:58 INFO - Display: 16:47:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:47:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:47:58 INFO - Display Serial Number: 1600x1200 60 16:47:58 INFO - Main Display: Yes 16:47:58 INFO - Mirror: Off 16:47:58 INFO - Online: Yes 16:47:58 INFO - Rotation: Supported 16:47:58 INFO - Return code: 0 16:47:58 INFO - Running post-action listener: _resource_record_post_action 16:47:58 INFO - [mozharness: 2017-05-13 23:47:58.089242Z] Finished run-tests step (success) 16:47:58 INFO - Running post-run listener: _resource_record_post_run 16:47:58 INFO - Total resource usage - Wall time: 1197s; CPU: 54.0%; Read bytes: 33817600; Write bytes: 530254848; Read time: 209; Write time: 1681 16:47:58 INFO - TinderboxPrint: CPU usage
54.3% 16:47:58 INFO - TinderboxPrint: I/O read bytes / time
33,817,600 / 209 16:47:58 INFO - TinderboxPrint: I/O write bytes / time
530,254,848 / 1,681 16:47:58 INFO - TinderboxPrint: CPU idle
2,190.4 (45.7%) 16:47:58 INFO - TinderboxPrint: CPU system
965.5 (20.2%) 16:47:58 INFO - TinderboxPrint: CPU user
1,633.9 (34.1%) 16:47:58 INFO - TinderboxPrint: Swap in / out
244,154,368 / 0 16:47:58 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 212019712; Write bytes: 206800896; Read time: 17057; Write time: 460 16:47:58 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 31736832; Write bytes: 312439808; Read time: 189; Write time: 1184 16:47:58 INFO - Running post-run listener: _upload_blobber_files 16:47:58 INFO - Blob upload gear active. 16:47:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:47:58 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 16:47:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:47:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:47:58 INFO - (blobuploader) - INFO - Open directory for files ... 16:47:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:47:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:47:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:48:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:48:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:48:00 INFO - (blobuploader) - INFO - Done attempting. 16:48:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:48:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:48:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:48:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:48:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:48:01 INFO - (blobuploader) - INFO - Done attempting. 16:48:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:48:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:48:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:48:02 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:48:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:48:02 INFO - (blobuploader) - INFO - Done attempting. 16:48:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:48:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:48:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:48:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:48:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:48:04 INFO - (blobuploader) - INFO - Done attempting. 16:48:04 INFO - (blobuploader) - INFO - Iteration through files over. 16:48:04 INFO - Return code: 0 16:48:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:48:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:48:04 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/be73d7bad52c8f5bd1da85269d428143fb4f18af91338b12c7c9ce0c7b4fab405ebf7bf0a6baf6b0b56416afd17b3d4f5f106ad12cc8cf54ecda0d4bb3e6afeb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/961ae626fd281d6831504b3a2413fad0bf36ad8f0316750639debde09f9a3019b246658bddc36742e9e7eab119ceed038c2d5c26f409426ced308b0a7fd093b1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/96b734cd02066438dc1e50965fa794e88af15b4aadbb77926423dc14616258ff91af2a988fed9c6ea0888dbabc3283b809a0c41f4a0e66da77e207db6e048281", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1820937ee18064067d9718ebca548c4a043f1ab32fafc2971a4c3a0d043aa9cf03287e41baac79ff9a543873c36380c9fabecf5bd9c3437309a160b62ee72b3f"} 16:48:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:48:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:48:04 INFO - Contents: 16:48:04 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/be73d7bad52c8f5bd1da85269d428143fb4f18af91338b12c7c9ce0c7b4fab405ebf7bf0a6baf6b0b56416afd17b3d4f5f106ad12cc8cf54ecda0d4bb3e6afeb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/961ae626fd281d6831504b3a2413fad0bf36ad8f0316750639debde09f9a3019b246658bddc36742e9e7eab119ceed038c2d5c26f409426ced308b0a7fd093b1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/96b734cd02066438dc1e50965fa794e88af15b4aadbb77926423dc14616258ff91af2a988fed9c6ea0888dbabc3283b809a0c41f4a0e66da77e207db6e048281", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1820937ee18064067d9718ebca548c4a043f1ab32fafc2971a4c3a0d043aa9cf03287e41baac79ff9a543873c36380c9fabecf5bd9c3437309a160b62ee72b3f"} 16:48:04 INFO - Running post-run listener: copy_logs_to_upload_dir 16:48:04 INFO - Copying logs to upload dir... 16:48:04 INFO - mkdir: /builds/slave/test/build/upload/logs 16:48:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.936331 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-05-13 16:48:04.261033) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-13 16:48:04.264225) ========= 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.ky9jgJHvLk/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.uUUW5eRe5Q/Listeners TMPDIR=/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/be73d7bad52c8f5bd1da85269d428143fb4f18af91338b12c7c9ce0c7b4fab405ebf7bf0a6baf6b0b56416afd17b3d4f5f106ad12cc8cf54ecda0d4bb3e6afeb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/961ae626fd281d6831504b3a2413fad0bf36ad8f0316750639debde09f9a3019b246658bddc36742e9e7eab119ceed038c2d5c26f409426ced308b0a7fd093b1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/96b734cd02066438dc1e50965fa794e88af15b4aadbb77926423dc14616258ff91af2a988fed9c6ea0888dbabc3283b809a0c41f4a0e66da77e207db6e048281", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1820937ee18064067d9718ebca548c4a043f1ab32fafc2971a4c3a0d043aa9cf03287e41baac79ff9a543873c36380c9fabecf5bd9c3437309a160b62ee72b3f"} build_url:https://queue.taskcluster.net/v1/task/RNwKFkV7RymduV-VV4Eb5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011052 build_url: 'https://queue.taskcluster.net/v1/task/RNwKFkV7RymduV-VV4Eb5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/be73d7bad52c8f5bd1da85269d428143fb4f18af91338b12c7c9ce0c7b4fab405ebf7bf0a6baf6b0b56416afd17b3d4f5f106ad12cc8cf54ecda0d4bb3e6afeb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/961ae626fd281d6831504b3a2413fad0bf36ad8f0316750639debde09f9a3019b246658bddc36742e9e7eab119ceed038c2d5c26f409426ced308b0a7fd093b1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/96b734cd02066438dc1e50965fa794e88af15b4aadbb77926423dc14616258ff91af2a988fed9c6ea0888dbabc3283b809a0c41f4a0e66da77e207db6e048281", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1820937ee18064067d9718ebca548c4a043f1ab32fafc2971a4c3a0d043aa9cf03287e41baac79ff9a543873c36380c9fabecf5bd9c3437309a160b62ee72b3f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-13 16:48:04.297996) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-13 16:48:04.298322) ========= 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.ky9jgJHvLk/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.uUUW5eRe5Q/Listeners TMPDIR=/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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.004982 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-13 16:48:04.346525) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-13 16:48:04.346880) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-13 16:48:04.347205) ========= ========= Total master_lag: 0.22 =========