builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs slave: t-yosemite-r7-0387 starttime: 1496765764.91 results: success (0) buildid: 20170606081521 builduid: ed94bfc3ba194992a050c833444230e4 revision: fe438f1ec216c0269d353df141ea376055f6130c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-06 09:16:04.910719) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-06 09:16:04.911134) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-06 09:16:04.911419) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-06 09:16:04.936703) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 09:16:04.937069) ========= 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.07Po6i8QBy/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.xUFQYrImG5/Listeners TMPDIR=/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-06 09:16:04-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.3M=0.001s 2017-06-06 09:16:05 (18.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.152551 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 09:16:05.112753) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 09:16:05.113065) ========= 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.07Po6i8QBy/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.xUFQYrImG5/Listeners TMPDIR=/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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.026762 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 09:16:05.161450) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-06 09:16:05.161801) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev fe438f1ec216c0269d353df141ea376055f6130c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev fe438f1ec216c0269d353df141ea376055f6130c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.07Po6i8QBy/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.xUFQYrImG5/Listeners TMPDIR=/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-06 09:16:05,263 truncating revision to first 12 chars 2017-06-06 09:16:05,263 Setting DEBUG logging. 2017-06-06 09:16:05,263 attempt 1/10 2017-06-06 09:16:05,263 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/fe438f1ec216?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-06 09:16:06,487 unpacking tar archive at: mozilla-beta-fe438f1ec216/testing/mozharness/ program finished with exit code 0 elapsedTime=1.498443 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-06 09:16:06.682915) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-06 09:16:06.683266) ========= script_repo_revision: fe438f1ec216c0269d353df141ea376055f6130c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-06 09:16:06.683763) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-06 09:16:06.684038) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-06 09:16:06.703193) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-06-06 09:16:06.703609) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.07Po6i8QBy/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.xUFQYrImG5/Listeners TMPDIR=/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 09:16:06 INFO - MultiFileLogger online at 20170606 09:16:06 in /builds/slave/test 09:16:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 09:16:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:16:06 INFO - {'append_to_log': False, 09:16:06 INFO - 'base_work_dir': '/builds/slave/test', 09:16:06 INFO - 'blob_upload_branch': 'Mozilla-Beta', 09:16:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:16:06 INFO - 'branch': 'Mozilla-Beta', 09:16:06 INFO - 'buildbot_json_path': 'buildprops.json', 09:16:06 INFO - 'config_files': ('talos/mac_config.py',), 09:16:06 INFO - 'default_actions': ('clobber', 09:16:06 INFO - 'read-buildbot-config', 09:16:06 INFO - 'download-and-extract', 09:16:06 INFO - 'populate-webroot', 09:16:06 INFO - 'create-virtualenv', 09:16:06 INFO - 'install', 09:16:06 INFO - 'run-tests'), 09:16:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:16:06 INFO - 'download_minidump_stackwalk': True, 09:16:06 INFO - 'download_symbols': 'ondemand', 09:16:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:16:06 INFO - 'tooltool.py': '/tools/tooltool.py', 09:16:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:16:06 INFO - '/tools/misc-python/virtualenv.py')}, 09:16:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:16:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:16:06 INFO - 'gecko_profile': False, 09:16:06 INFO - 'gecko_profile_interval': 0, 09:16:06 INFO - 'installer_path': 'installer.exe', 09:16:06 INFO - 'log_level': 'info', 09:16:06 INFO - 'log_name': 'talos', 09:16:06 INFO - 'log_to_console': True, 09:16:06 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:16:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:16:06 INFO - 'opt_config_files': (), 09:16:06 INFO - 'pip_index': False, 09:16:06 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:16:06 INFO - 'cmd': ('bash', 09:16:06 INFO - '-c', 09:16:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:16:06 INFO - 'enabled': True, 09:16:06 INFO - 'halt_on_failure': False, 09:16:06 INFO - 'name': 'check_screen_resolution'},), 09:16:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:16:06 INFO - 'cmd': ('bash', 09:16:06 INFO - '-c', 09:16:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:16:06 INFO - 'enabled': True, 09:16:06 INFO - 'halt_on_failure': False, 09:16:06 INFO - 'name': 'check_screen_resolution'},), 09:16:06 INFO - 'run_cmd_checks_enabled': True, 09:16:06 INFO - 'suite': 'dromaeojs', 09:16:06 INFO - 'system_bits': '32', 09:16:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:16:06 INFO - 'title': 't-yosemite-r7-0387', 09:16:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:16:06 INFO - 'use_talos_json': True, 09:16:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:16:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:16:06 INFO - 'work_dir': 'build'} 09:16:06 INFO - [mozharness: 2017-06-06 16:16:06.909008Z] Running clobber step. 09:16:06 INFO - Running pre-action listener: _resource_record_pre_action 09:16:06 INFO - Running main action method: clobber 09:16:06 INFO - rmtree: /builds/slave/test/build 09:16:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:16:09 INFO - Running post-action listener: _resource_record_post_action 09:16:09 INFO - [mozharness: 2017-06-06 16:16:09.380282Z] Finished clobber step (success) 09:16:09 INFO - [mozharness: 2017-06-06 16:16:09.380411Z] Running read-buildbot-config step. 09:16:09 INFO - Running pre-action listener: _resource_record_pre_action 09:16:09 INFO - Running main action method: read_buildbot_config 09:16:09 INFO - Using buildbot properties: 09:16:09 INFO - { 09:16:09 INFO - "project": "", 09:16:09 INFO - "product": "firefox", 09:16:09 INFO - "script_repo_revision": "production", 09:16:09 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs", 09:16:09 INFO - "repository": "", 09:16:09 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs", 09:16:09 INFO - "buildid": "20170606081521", 09:16:09 INFO - "pgo_build": "False", 09:16:09 INFO - "basedir": "/builds/slave/test", 09:16:09 INFO - "buildnumber": 1, 09:16:09 INFO - "slavename": "t-yosemite-r7-0387", 09:16:09 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 09:16:09 INFO - "platform": "yosemite_r7_devedition", 09:16:09 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 09:16:09 INFO - "branch": "mozilla-beta", 09:16:09 INFO - "repo_path": "releases/mozilla-beta", 09:16:09 INFO - "revision": "fe438f1ec216c0269d353df141ea376055f6130c", 09:16:09 INFO - "stage_platform": "macosx64-devedition", 09:16:09 INFO - "builduid": "ed94bfc3ba194992a050c833444230e4", 09:16:09 INFO - "slavebuilddir": "test" 09:16:09 INFO - } 09:16:09 INFO - Found installer url https://queue.taskcluster.net/v1/task/Dg1BZSsvQW2LgvqdjVZQFw/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 09:16:09 INFO - Running post-action listener: _resource_record_post_action 09:16:09 INFO - [mozharness: 2017-06-06 16:16:09.383731Z] Finished read-buildbot-config step (success) 09:16:09 INFO - [mozharness: 2017-06-06 16:16:09.383863Z] Running download-and-extract step. 09:16:09 INFO - Running pre-action listener: _resource_record_pre_action 09:16:09 INFO - Running main action method: download_and_extract 09:16:09 INFO - mkdir: /builds/slave/test/build/tests 09:16:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:09 INFO - trying https://queue.taskcluster.net/v1/task/Dg1BZSsvQW2LgvqdjVZQFw/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 09:16:09 INFO - Downloading https://queue.taskcluster.net/v1/task/Dg1BZSsvQW2LgvqdjVZQFw/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 09:16:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Dg1BZSsvQW2LgvqdjVZQFw/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 09:16:10 INFO - Downloaded 1287 bytes. 09:16:10 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 09:16:10 INFO - Using the following test package requirements: 09:16:10 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:16:10 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 09:16:10 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 09:16:10 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:16:10 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 09:16:10 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:16:10 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 09:16:10 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 09:16:10 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:16:10 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 09:16:10 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 09:16:10 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:16:10 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 09:16:10 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:16:10 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 09:16:10 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:16:10 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 09:16:10 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:16:10 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 09:16:10 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 09:16:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Dg1BZSsvQW2LgvqdjVZQFw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 09:16:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Dg1BZSsvQW2LgvqdjVZQFw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 09:16:10 INFO - Fetch https://queue.taskcluster.net/v1/task/Dg1BZSsvQW2LgvqdjVZQFw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 09:16:12 INFO - Expected file size: 51963910 09:16:12 INFO - Obtained file size: 51963910 09:16:28 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 09:16:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Dg1BZSsvQW2LgvqdjVZQFw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 09:16:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Dg1BZSsvQW2LgvqdjVZQFw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 09:16:28 INFO - Fetch https://queue.taskcluster.net/v1/task/Dg1BZSsvQW2LgvqdjVZQFw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 09:16:30 INFO - Expected file size: 51963910 09:16:30 INFO - Obtained file size: 51963910 09:16:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Dg1BZSsvQW2LgvqdjVZQFw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 09:16:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Dg1BZSsvQW2LgvqdjVZQFw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 09:16:45 INFO - Fetch https://queue.taskcluster.net/v1/task/Dg1BZSsvQW2LgvqdjVZQFw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 09:16:46 INFO - Expected file size: 14404461 09:16:46 INFO - Obtained file size: 14404461 09:16: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')]} 09:16:47 INFO - trying https://queue.taskcluster.net/v1/task/Dg1BZSsvQW2LgvqdjVZQFw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 09:16:47 INFO - Downloading https://queue.taskcluster.net/v1/task/Dg1BZSsvQW2LgvqdjVZQFw/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 09:16:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Dg1BZSsvQW2LgvqdjVZQFw/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 09:16:49 INFO - Downloaded 74884596 bytes. 09:16:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Dg1BZSsvQW2LgvqdjVZQFw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 09:16:49 INFO - mkdir: /builds/slave/test/properties 09:16:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:16:49 INFO - Writing to file /builds/slave/test/properties/build_url 09:16:49 INFO - Contents: 09:16:49 INFO - build_url:https://queue.taskcluster.net/v1/task/Dg1BZSsvQW2LgvqdjVZQFw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 09:16:50 INFO - Running post-action listener: _resource_record_post_action 09:16:50 INFO - Running post-action listener: set_extra_try_arguments 09:16:50 INFO - [mozharness: 2017-06-06 16:16:50.301624Z] Finished download-and-extract step (success) 09:16:50 INFO - [mozharness: 2017-06-06 16:16:50.301772Z] Running populate-webroot step. 09:16:50 INFO - Running pre-action listener: _resource_record_pre_action 09:16:50 INFO - Running main action method: populate_webroot 09:16:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:16:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:16:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:16:50 INFO - '--tppagecycles', 09:16:50 INFO - '7'], 09:16:50 INFO - 'tests': ['tsvgm']}}, 09:16:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:16:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:16:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:16:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:16:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:16:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:16:50 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:16:50 INFO - 'talos_options': ['--disable-e10s'], 09:16:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:16:50 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:16:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:16:50 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:16:50 INFO - 'talos_options': ['--disable-e10s'], 09:16:50 INFO - 'tests': ['damp', 'tps']}, 09:16:50 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:16:50 INFO - 'tests': ['damp', 'tps']}, 09:16:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:16:50 INFO - 'tests': ['dromaeo_dom']}, 09:16:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:16:50 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:16:50 INFO - 'tests': ['basic_compositor_video']}, 09:16:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:16:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:16:50 INFO - 'tests': ['a11yr', 09:16:50 INFO - 'ts_paint', 09:16:50 INFO - 'tpaint', 09:16:50 INFO - 'sessionrestore', 09:16:50 INFO - 'sessionrestore_no_auto_restore', 09:16:50 INFO - 'tabpaint']}, 09:16:50 INFO - 'other-e10s': {'tests': ['a11yr', 09:16:50 INFO - 'ts_paint', 09:16:50 INFO - 'tpaint', 09:16:50 INFO - 'sessionrestore', 09:16:50 INFO - 'sessionrestore_no_auto_restore', 09:16:50 INFO - 'tabpaint']}, 09:16:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:16:50 INFO - 'ts_paint', 09:16:50 INFO - 'tpaint', 09:16:50 INFO - 'sessionrestore', 09:16:50 INFO - 'sessionrestore_no_auto_restore', 09:16:50 INFO - 'tabpaint']}, 09:16:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:16:50 INFO - 'ts_paint', 09:16:50 INFO - 'tpaint', 09:16:50 INFO - 'sessionrestore', 09:16:50 INFO - 'sessionrestore_no_auto_restore', 09:16:50 INFO - 'tabpaint']}, 09:16:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:16:50 INFO - 'tests': ['a11yr', 09:16:50 INFO - 'ts_paint', 09:16:50 INFO - 'tpaint', 09:16:50 INFO - 'sessionrestore', 09:16:50 INFO - 'sessionrestore_no_auto_restore', 09:16:50 INFO - 'tabpaint']}, 09:16:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:16:50 INFO - 'tests': ['a11yr', 09:16:50 INFO - 'ts_paint', 09:16:50 INFO - 'tpaint', 09:16:50 INFO - 'sessionrestore', 09:16:50 INFO - 'sessionrestore_no_auto_restore', 09:16:50 INFO - 'tabpaint']}, 09:16:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:16:50 INFO - 'tests': ['tsvgx', 09:16:50 INFO - 'tsvgr_opacity', 09:16:50 INFO - 'tart', 09:16:50 INFO - 'tscrollx', 09:16:50 INFO - 'cart', 09:16:50 INFO - 'tsvg_static']}, 09:16:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:16:50 INFO - 'tsvgr_opacity', 09:16:50 INFO - 'tart', 09:16:50 INFO - 'tscrollx', 09:16:50 INFO - 'cart', 09:16:50 INFO - 'tsvg_static']}, 09:16:50 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:16:50 INFO - 'talos_options': ['--disable-e10s'], 09:16:50 INFO - 'tests': ['tp5o']}, 09:16:50 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:16:50 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:16:50 INFO - 'talos_options': ['--disable-e10s', 09:16:50 INFO - '--xperf_path', 09:16:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:16:50 INFO - 'tests': ['tp5n']}, 09:16:50 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:16:50 INFO - 'talos_options': ['--xperf_path', 09:16:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:16:50 INFO - 'tests': ['tp5n']}}, 09:16:50 INFO - 'talos.zip': {'path': '', 09:16:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:16:50 INFO - Running post-action listener: _resource_record_post_action 09:16:50 INFO - [mozharness: 2017-06-06 16:16:50.320590Z] Finished populate-webroot step (success) 09:16:50 INFO - [mozharness: 2017-06-06 16:16:50.320728Z] Running create-virtualenv step. 09:16:50 INFO - Running pre-action listener: _resource_record_pre_action 09:16:50 INFO - Running main action method: create_virtualenv 09:16:50 INFO - Creating virtualenv /builds/slave/test/build/venv 09:16:50 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 09:16:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:16:50 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 09:16:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:16:50 INFO - Using real prefix '/tools/python27' 09:16:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:16:51 INFO - Installing distribute.............................................................................................................................................................................................done. 09:16:53 INFO - Installing pip.................done. 09:16:53 INFO - Return code: 0 09:16:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:16:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:16:54 INFO - Reading from file tmpfile_stdout 09:16:54 INFO - Output received: 09:16:54 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) 09:16:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:16:54 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')]} 09:16:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:54 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')]} 09:16:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:54 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 0x10db1cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db1f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0784a19a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8fad30>, '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 0x10d821db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8efbe8>, '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.07Po6i8QBy/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.xUFQYrImG5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 09:16:54 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 09:16:54 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 09:16:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.07Po6i8QBy/Render', 09:16:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:54 INFO - 'HOME': '/Users/cltbld', 09:16:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:54 INFO - 'LOGNAME': 'cltbld', 09:16:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:54 INFO - 'MOZ_NO_REMOTE': '1', 09:16:54 INFO - 'NO_EM_RESTART': '1', 09:16:54 INFO - 'PAGER': '/bin/cat', 09:16:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:54 INFO - 'PWD': '/builds/slave/test', 09:16:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:54 INFO - 'SHELL': '/bin/bash', 09:16:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xUFQYrImG5/Listeners', 09:16:54 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 09:16:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:54 INFO - 'USER': 'cltbld', 09:16:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:54 INFO - 'XPC_FLAGS': '0x0', 09:16:54 INFO - 'XPC_SERVICE_NAME': '0', 09:16:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:54 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 09:16:54 INFO - Cleaning up... 09:16:54 INFO - Return code: 0 09:16:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:16:54 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')]} 09:16:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:54 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')]} 09:16:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:54 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 0x10db1cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db1f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0784a19a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8fad30>, '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 0x10d821db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8efbe8>, '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.07Po6i8QBy/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.xUFQYrImG5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 09:16:54 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 09:16:54 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 09:16:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.07Po6i8QBy/Render', 09:16:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:54 INFO - 'HOME': '/Users/cltbld', 09:16:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:54 INFO - 'LOGNAME': 'cltbld', 09:16:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:54 INFO - 'MOZ_NO_REMOTE': '1', 09:16:54 INFO - 'NO_EM_RESTART': '1', 09:16:54 INFO - 'PAGER': '/bin/cat', 09:16:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:54 INFO - 'PWD': '/builds/slave/test', 09:16:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:54 INFO - 'SHELL': '/bin/bash', 09:16:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xUFQYrImG5/Listeners', 09:16:54 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 09:16:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:54 INFO - 'USER': 'cltbld', 09:16:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:54 INFO - 'XPC_FLAGS': '0x0', 09:16:54 INFO - 'XPC_SERVICE_NAME': '0', 09:16:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:54 INFO - Downloading/unpacking psutil>=3.1.1 09:16:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:16:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:16:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:16:56 INFO - Installing collected packages: psutil 09:16:56 INFO - Running setup.py install for psutil 09:16:56 INFO - building 'psutil._psutil_osx' extension 09:16:56 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 09:16:57 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 09:16:57 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 09:16:57 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 09:16:57 INFO - building 'psutil._psutil_posix' extension 09:16:57 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 09:16:57 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:16:57 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:16:57 INFO - ^ 09:16:57 INFO - 1 warning generated. 09:16:57 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 09:16:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:16:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:16:57 INFO - Successfully installed psutil 09:16:57 INFO - Cleaning up... 09:16:57 INFO - Return code: 0 09:16:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:16:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10db1cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db1f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0784a19a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8fad30>, '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 0x10d821db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8efbe8>, '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.07Po6i8QBy/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.xUFQYrImG5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 09:16:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:16:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:16:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.07Po6i8QBy/Render', 09:16:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:57 INFO - 'HOME': '/Users/cltbld', 09:16:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:57 INFO - 'LOGNAME': 'cltbld', 09:16:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:57 INFO - 'MOZ_NO_REMOTE': '1', 09:16:57 INFO - 'NO_EM_RESTART': '1', 09:16:57 INFO - 'PAGER': '/bin/cat', 09:16:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:57 INFO - 'PWD': '/builds/slave/test', 09:16:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:57 INFO - 'SHELL': '/bin/bash', 09:16:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xUFQYrImG5/Listeners', 09:16:57 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 09:16:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:57 INFO - 'USER': 'cltbld', 09:16:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:57 INFO - 'XPC_FLAGS': '0x0', 09:16:57 INFO - 'XPC_SERVICE_NAME': '0', 09:16:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:16:58 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 09:16:58 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 09:16:58 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 09:16:58 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 09:16:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:16:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:16:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:16:59 INFO - Installing collected packages: mozsystemmonitor 09:16:59 INFO - Running setup.py install for mozsystemmonitor 09:16:59 INFO - Successfully installed mozsystemmonitor 09:16:59 INFO - Cleaning up... 09:16:59 INFO - Return code: 0 09:16:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:16: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')]} 09:16:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:00 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 0x10db1cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db1f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0784a19a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8fad30>, '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 0x10d821db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8efbe8>, '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.07Po6i8QBy/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.xUFQYrImG5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 09:17:00 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 09:17:00 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 09:17:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.07Po6i8QBy/Render', 09:17:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:00 INFO - 'HOME': '/Users/cltbld', 09:17:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:00 INFO - 'LOGNAME': 'cltbld', 09:17:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:00 INFO - 'MOZ_NO_REMOTE': '1', 09:17:00 INFO - 'NO_EM_RESTART': '1', 09:17:00 INFO - 'PAGER': '/bin/cat', 09:17:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:00 INFO - 'PWD': '/builds/slave/test', 09:17:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:00 INFO - 'SHELL': '/bin/bash', 09:17:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xUFQYrImG5/Listeners', 09:17:00 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 09:17:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:00 INFO - 'USER': 'cltbld', 09:17:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:00 INFO - 'XPC_FLAGS': '0x0', 09:17:00 INFO - 'XPC_SERVICE_NAME': '0', 09:17:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:00 INFO - Downloading/unpacking jsonschema==2.5.1 09:17:00 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 09:17:00 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 09:17:00 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 09:17:00 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 09:17:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:17:02 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:17:02 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 09:17:02 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 09:17:02 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 09:17:02 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 09:17:02 INFO - Downloading functools32-3.2.3-2.tar.gz 09:17:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:17:02 INFO - warning: no files found matching '*.txt' 09:17:02 INFO - no previously-included directories found matching 'build' 09:17:02 INFO - no previously-included directories found matching 'dist' 09:17:02 INFO - no previously-included directories found matching '.git*' 09:17:02 INFO - Installing collected packages: jsonschema, functools32 09:17:02 INFO - Running setup.py install for functools32 09:17:02 INFO - warning: no files found matching '*.txt' 09:17:02 INFO - no previously-included directories found matching 'build' 09:17:02 INFO - no previously-included directories found matching 'dist' 09:17:02 INFO - no previously-included directories found matching '.git*' 09:17:02 INFO - Successfully installed jsonschema functools32 09:17:02 INFO - Cleaning up... 09:17:02 INFO - Return code: 0 09:17:02 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:17:02 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')]} 09:17:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:02 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')]} 09:17:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:02 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 0x10db1cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db1f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0784a19a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8fad30>, '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 0x10d821db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8efbe8>, '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.07Po6i8QBy/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.xUFQYrImG5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 09:17:02 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 09:17:02 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 09:17:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.07Po6i8QBy/Render', 09:17:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:02 INFO - 'HOME': '/Users/cltbld', 09:17:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:02 INFO - 'LOGNAME': 'cltbld', 09:17:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:02 INFO - 'MOZ_NO_REMOTE': '1', 09:17:02 INFO - 'NO_EM_RESTART': '1', 09:17:02 INFO - 'PAGER': '/bin/cat', 09:17:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:02 INFO - 'PWD': '/builds/slave/test', 09:17:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:02 INFO - 'SHELL': '/bin/bash', 09:17:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xUFQYrImG5/Listeners', 09:17:02 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 09:17:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:02 INFO - 'USER': 'cltbld', 09:17:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:02 INFO - 'XPC_FLAGS': '0x0', 09:17:02 INFO - 'XPC_SERVICE_NAME': '0', 09:17:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:02 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:17:02 INFO - Cleaning up... 09:17:02 INFO - Return code: 0 09:17:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:17:02 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')]} 09:17:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:02 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')]} 09:17:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:02 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 0x10db1cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db1f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0784a19a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8fad30>, '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 0x10d821db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8efbe8>, '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.07Po6i8QBy/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.xUFQYrImG5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 09:17:02 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 09:17:02 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 09:17:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.07Po6i8QBy/Render', 09:17:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:02 INFO - 'HOME': '/Users/cltbld', 09:17:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:02 INFO - 'LOGNAME': 'cltbld', 09:17:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:02 INFO - 'MOZ_NO_REMOTE': '1', 09:17:02 INFO - 'NO_EM_RESTART': '1', 09:17:02 INFO - 'PAGER': '/bin/cat', 09:17:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:02 INFO - 'PWD': '/builds/slave/test', 09:17:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:02 INFO - 'SHELL': '/bin/bash', 09:17:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xUFQYrImG5/Listeners', 09:17:02 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 09:17:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:02 INFO - 'USER': 'cltbld', 09:17:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:02 INFO - 'XPC_FLAGS': '0x0', 09:17:02 INFO - 'XPC_SERVICE_NAME': '0', 09:17:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:03 INFO - Downloading/unpacking blobuploader==1.2.4 09:17:03 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 09:17:03 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 09:17:03 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 09:17:03 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 09:17:04 INFO - Downloading blobuploader-1.2.4.tar.gz 09:17:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:17:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:17:04 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 09:17:04 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 09:17:04 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 09:17:04 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 09:17:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:17:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:17:05 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 09:17:05 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 09:17:05 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 09:17:05 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 09:17:05 INFO - Downloading docopt-0.6.1.tar.gz 09:17:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:17:05 INFO - Installing collected packages: blobuploader, requests, docopt 09:17:05 INFO - Running setup.py install for blobuploader 09:17:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:17:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:17:05 INFO - Running setup.py install for requests 09:17:06 INFO - Running setup.py install for docopt 09:17:06 INFO - Successfully installed blobuploader requests docopt 09:17:06 INFO - Cleaning up... 09:17:06 INFO - Return code: 0 09:17:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:17:06 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')]} 09:17:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:06 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')]} 09:17:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:06 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 0x10db1cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db1f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0784a19a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8fad30>, '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 0x10d821db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8efbe8>, '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.07Po6i8QBy/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.xUFQYrImG5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 09:17:06 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 09:17:06 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 09:17:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.07Po6i8QBy/Render', 09:17:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:06 INFO - 'HOME': '/Users/cltbld', 09:17:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:06 INFO - 'LOGNAME': 'cltbld', 09:17:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:06 INFO - 'MOZ_NO_REMOTE': '1', 09:17:06 INFO - 'NO_EM_RESTART': '1', 09:17:06 INFO - 'PAGER': '/bin/cat', 09:17:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:06 INFO - 'PWD': '/builds/slave/test', 09:17:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:06 INFO - 'SHELL': '/bin/bash', 09:17:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xUFQYrImG5/Listeners', 09:17:06 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 09:17:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:06 INFO - 'USER': 'cltbld', 09:17:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:06 INFO - 'XPC_FLAGS': '0x0', 09:17:06 INFO - 'XPC_SERVICE_NAME': '0', 09:17:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:17:06 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-fkkNpS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:17:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:17:06 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-mNfutF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:17:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:17:06 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-Z_RWYS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:17:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:17:07 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-iCTbAK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:17:07 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-x5sUvV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:17:07 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-LosyZb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:17:07 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-Xmvcby-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:17:07 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-HFUYTZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:17:07 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-w_H1il-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:17:07 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-s145Ew-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:17:07 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-YHSsYV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:17:07 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-OgdV20-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:17:07 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-I43Xzy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:17:08 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-J09Vof-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:17:08 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-69OeRH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:17:08 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-14P2Qq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:17:08 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-vjWa68-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:17:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:17:08 INFO - Running setup.py install for manifestparser 09:17:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:17:08 INFO - Running setup.py install for mozcrash 09:17:08 INFO - Running setup.py install for mozdebug 09:17:08 INFO - Running setup.py install for mozdevice 09:17:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:17:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:17:09 INFO - Running setup.py install for mozfile 09:17:09 INFO - Running setup.py install for mozhttpd 09:17:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:17:09 INFO - Running setup.py install for mozinfo 09:17:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:17:09 INFO - Running setup.py install for mozInstall 09:17:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:17:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:17:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:17:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:17:09 INFO - Running setup.py install for mozleak 09:17:09 INFO - Running setup.py install for mozlog 09:17:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:17:09 INFO - Running setup.py install for moznetwork 09:17:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:17:10 INFO - Running setup.py install for mozprocess 09:17:10 INFO - Running setup.py install for mozprofile 09:17:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:17:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:17:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:17:10 INFO - Running setup.py install for mozrunner 09:17:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:17:10 INFO - Running setup.py install for mozscreenshot 09:17:10 INFO - Running setup.py install for moztest 09:17:10 INFO - Running setup.py install for mozversion 09:17:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:17:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:17:11 INFO - Cleaning up... 09:17:11 INFO - Return code: 0 09:17:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:17:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:11 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 0x10db1cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db1f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0784a19a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8fad30>, '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 0x10d821db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8efbe8>, '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.07Po6i8QBy/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.xUFQYrImG5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 09:17:11 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 09:17:11 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 09:17:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.07Po6i8QBy/Render', 09:17:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:11 INFO - 'HOME': '/Users/cltbld', 09:17:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:11 INFO - 'LOGNAME': 'cltbld', 09:17:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:11 INFO - 'MOZ_NO_REMOTE': '1', 09:17:11 INFO - 'NO_EM_RESTART': '1', 09:17:11 INFO - 'PAGER': '/bin/cat', 09:17:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:11 INFO - 'PWD': '/builds/slave/test', 09:17:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:11 INFO - 'SHELL': '/bin/bash', 09:17:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xUFQYrImG5/Listeners', 09:17:11 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 09:17:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:11 INFO - 'USER': 'cltbld', 09:17:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:11 INFO - 'XPC_FLAGS': '0x0', 09:17:11 INFO - 'XPC_SERVICE_NAME': '0', 09:17:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:17:11 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-p0F0ae-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:17:11 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)) 09:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:17:11 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-NTBkMS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:17:11 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)) 09:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:17:11 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-ph6npm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:17:11 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)) 09:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:17:11 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-fbckKP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:17:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 09:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:17:11 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-UHaUqU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:17:11 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)) 09:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:17:11 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-dQQJbE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:17:11 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)) 09:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:17:11 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-ca59qE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:17:12 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)) 09:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:17:12 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-geskx8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:17:12 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)) 09:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:17:12 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-I6qX_n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:17:12 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)) 09:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:17:12 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-vvFUe5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:17:12 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)) 09:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:17:12 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-uVF8m0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:17:12 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)) 09:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:17:12 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-Sa15fP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:17:12 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)) 09:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:17:12 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-0tAQRD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:17:12 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)) 09:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:17:12 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-Dcsfog-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:17:12 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)) 09:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:17:12 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-N01sJd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:17:12 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)) 09:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:17:12 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-rsw4o8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:17:13 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)) 09:17:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:17:13 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-cYeg6i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:17:13 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)) 09:17:13 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)) 09:17:13 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)) 09:17:13 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)) 09:17:13 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:17:13 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:17:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:17:13 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 09:17:13 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 09:17:13 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 09:17:13 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 09:17:14 INFO - Downloading blessings-1.6.tar.gz 09:17:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:17:14 INFO - Installing collected packages: blessings 09:17:14 INFO - Running setup.py install for blessings 09:17:14 INFO - Successfully installed blessings 09:17:14 INFO - Cleaning up... 09:17:14 INFO - Return code: 0 09:17:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:17:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:17:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:17:15 INFO - Reading from file tmpfile_stdout 09:17:15 INFO - Current package versions: 09:17:15 INFO - blessings == 1.6 09:17:15 INFO - blobuploader == 1.2.4 09:17:15 INFO - docopt == 0.6.1 09:17:15 INFO - functools32 == 3.2.3-2 09:17:15 INFO - jsonschema == 2.5.1 09:17:15 INFO - manifestparser == 1.1 09:17:15 INFO - mozInstall == 1.12 09:17:15 INFO - mozcrash == 1.0 09:17:15 INFO - mozdebug == 0.1 09:17:15 INFO - mozdevice == 0.49 09:17:15 INFO - mozfile == 1.2 09:17:15 INFO - mozhttpd == 0.7 09:17:15 INFO - mozinfo == 0.9 09:17:15 INFO - mozleak == 0.1 09:17:15 INFO - mozlog == 3.4 09:17:15 INFO - moznetwork == 0.27 09:17:15 INFO - mozprocess == 0.25 09:17:15 INFO - mozprofile == 0.28 09:17:15 INFO - mozrunner == 6.13 09:17:15 INFO - mozscreenshot == 0.1 09:17:15 INFO - mozsystemmonitor == 0.3 09:17:15 INFO - moztest == 0.8 09:17:15 INFO - mozversion == 1.4 09:17:15 INFO - psutil == 3.1.1 09:17:15 INFO - requests == 1.2.3 09:17:15 INFO - wsgiref == 0.1.2 09:17:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:17:15 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')]} 09:17:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:15 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')]} 09:17:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:15 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 0x10db1cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db1f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0784a19a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8fad30>, '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 0x10d821db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8efbe8>, '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.07Po6i8QBy/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.xUFQYrImG5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 09:17:15 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 09:17:15 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 09:17:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.07Po6i8QBy/Render', 09:17:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:15 INFO - 'HOME': '/Users/cltbld', 09:17:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:15 INFO - 'LOGNAME': 'cltbld', 09:17:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:15 INFO - 'MOZ_NO_REMOTE': '1', 09:17:15 INFO - 'NO_EM_RESTART': '1', 09:17:15 INFO - 'PAGER': '/bin/cat', 09:17:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:15 INFO - 'PWD': '/builds/slave/test', 09:17:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:15 INFO - 'SHELL': '/bin/bash', 09:17:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xUFQYrImG5/Listeners', 09:17:15 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 09:17:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:15 INFO - 'USER': 'cltbld', 09:17:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:15 INFO - 'XPC_FLAGS': '0x0', 09:17:15 INFO - 'XPC_SERVICE_NAME': '0', 09:17:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:15 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)) 09:17:15 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)) 09:17:15 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)) 09:17:15 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)) 09:17:15 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)) 09:17:15 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)) 09:17:15 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)) 09:17:15 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)) 09:17:15 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)) 09:17:15 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)) 09:17:15 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)) 09:17:15 INFO - Cleaning up... 09:17:15 INFO - Return code: 0 09:17:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:17:15 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')]} 09:17:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:15 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')]} 09:17:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:15 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 0x10db1cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db1f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0784a19a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8fad30>, '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 0x10d821db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8efbe8>, '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.07Po6i8QBy/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.xUFQYrImG5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 09:17:15 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 09:17:15 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 09:17:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.07Po6i8QBy/Render', 09:17:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:15 INFO - 'HOME': '/Users/cltbld', 09:17:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:15 INFO - 'LOGNAME': 'cltbld', 09:17:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:15 INFO - 'MOZ_NO_REMOTE': '1', 09:17:15 INFO - 'NO_EM_RESTART': '1', 09:17:15 INFO - 'PAGER': '/bin/cat', 09:17:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:15 INFO - 'PWD': '/builds/slave/test', 09:17:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:15 INFO - 'SHELL': '/bin/bash', 09:17:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xUFQYrImG5/Listeners', 09:17:15 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 09:17:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:15 INFO - 'USER': 'cltbld', 09:17:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:15 INFO - 'XPC_FLAGS': '0x0', 09:17:15 INFO - 'XPC_SERVICE_NAME': '0', 09:17:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:17:15 INFO - Cleaning up... 09:17:15 INFO - Return code: 0 09:17:15 INFO - Running post-action listener: _resource_record_post_action 09:17:15 INFO - Running post-action listener: _start_resource_monitoring 09:17:15 INFO - Starting resource monitoring. 09:17:15 INFO - [mozharness: 2017-06-06 16:17:15.684817Z] Finished create-virtualenv step (success) 09:17:15 INFO - [mozharness: 2017-06-06 16:17:15.685765Z] Running install step. 09:17:15 INFO - Running pre-action listener: _resource_record_pre_action 09:17:15 INFO - Running main action method: install 09:17:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:17:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:17:15 INFO - Reading from file tmpfile_stdout 09:17:15 INFO - Detecting whether we're running mozinstall >=1.0... 09:17:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:17:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:17:16 INFO - Reading from file tmpfile_stdout 09:17:16 INFO - Output received: 09:17:16 INFO - Usage: mozinstall [options] installer 09:17:16 INFO - Options: 09:17:16 INFO - -h, --help show this help message and exit 09:17:16 INFO - -d DEST, --destination=DEST 09:17:16 INFO - Directory to install application into. [default: 09:17:16 INFO - "/builds/slave/test"] 09:17:16 INFO - --app=APP Application being installed. [default: firefox] 09:17:16 INFO - mkdir: /builds/slave/test/build/application 09:17:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:17:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 09:17:36 INFO - Reading from file tmpfile_stdout 09:17:36 INFO - Output received: 09:17:36 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:17:36 INFO - Running post-action listener: _resource_record_post_action 09:17:36 INFO - [mozharness: 2017-06-06 16:17:36.203898Z] Finished install step (success) 09:17:36 INFO - [mozharness: 2017-06-06 16:17:36.204055Z] Running run-tests step. 09:17:36 INFO - Running pre-action listener: _resource_record_pre_action 09:17:36 INFO - Running main action method: run_tests 09:17:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:17:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:17:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:17:36 INFO - 2017-06-06 09:17:36.218 screenresolution[1624:7406] starting screenresolution argv=screenresolution get 09:17:36 INFO - 2017-06-06 09:17:36.221 screenresolution[1624:7406] Display 0: 1600x1200x32@60 09:17:36 INFO - 2017-06-06 09:17:36.227 screenresolution[1625:7409] starting screenresolution argv=screenresolution list 09:17:36 INFO - Available Modes on Display 0 09:17:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:17:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:17:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:17:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:17:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:17:36 INFO - Intel Iris: 09:17:36 INFO - Chipset Model: Intel Iris 09:17:36 INFO - Type: GPU 09:17:36 INFO - Bus: Built-In 09:17:36 INFO - VRAM (Dynamic, Max): 1536 MB 09:17:36 INFO - Vendor: Intel (0x8086) 09:17:36 INFO - Device ID: 0x0a2e 09:17:36 INFO - Revision ID: 0x0009 09:17:36 INFO - Displays: 09:17:36 INFO - Display: 09:17:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:17:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:17:36 INFO - Display Serial Number: 1600x1200 60 09:17:36 INFO - Main Display: Yes 09:17:36 INFO - Mirror: Off 09:17:36 INFO - Online: Yes 09:17:36 INFO - Rotation: Supported 09:17:36 INFO - Return code: 0 09:17:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:17:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:17:36 INFO - Python 2.7.3 09:17:36 INFO - Return code: 0 09:17:36 INFO - grabbing minidump binary from tooltool 09:17:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:36 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 0x10d8fad30>, '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 0x10d821db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8efbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:17:36 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 09:17:36 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 09:17:36 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 09:17:36 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:17:36 INFO - Return code: 0 09:17:36 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:17:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:17:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:17:36 INFO - ENV: RUST_BACKTRACE is now 1 09:17:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:17:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:17:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Dg1BZSsvQW2LgvqdjVZQFw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0387', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:17:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Dg1BZSsvQW2LgvqdjVZQFw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0387 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:17:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.07Po6i8QBy/Render', 09:17:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:36 INFO - 'HOME': '/Users/cltbld', 09:17:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:36 INFO - 'LOGNAME': 'cltbld', 09:17:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:17:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:17:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:36 INFO - 'MOZ_NO_REMOTE': '1', 09:17:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:17:36 INFO - 'NO_EM_RESTART': '1', 09:17:36 INFO - 'PAGER': '/bin/cat', 09:17:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:36 INFO - 'PWD': '/builds/slave/test', 09:17:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:17:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:36 INFO - 'RUST_BACKTRACE': '1', 09:17:36 INFO - 'SHELL': '/bin/bash', 09:17:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xUFQYrImG5/Listeners', 09:17:36 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 09:17:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:36 INFO - 'USER': 'cltbld', 09:17:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:36 INFO - 'XPC_FLAGS': '0x0', 09:17:36 INFO - 'XPC_SERVICE_NAME': '0', 09:17:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:36 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Dg1BZSsvQW2LgvqdjVZQFw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0387', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:17:36 INFO - mozversion application_buildid: 20170606081521 09:17:36 INFO - mozversion application_changeset: fe438f1ec216c0269d353df141ea376055f6130c 09:17:36 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:17:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:17:36 INFO - mozversion application_name: Firefox 09:17:36 INFO - mozversion application_remotingname: firefox-dev 09:17:36 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:17:36 INFO - mozversion application_vendor: Mozilla 09:17:36 INFO - mozversion application_version: 54.0 09:17:36 INFO - mozversion platform_buildid: 20170606081521 09:17:36 INFO - mozversion platform_changeset: fe438f1ec216c0269d353df141ea376055f6130c 09:17:36 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:17:36 INFO - mozversion platform_version: 54.0 09:17:36 INFO - using testdate: 1496765856 09:17:36 INFO - actual date: 1496765856 09:17:36 INFO - starting webserver on 'localhost:49224' 09:17:36 INFO - SUITE-START | Running 2 tests 09:17:36 INFO - TEST-START | dromaeo_css 09:17:36 INFO - operating with platform_type : mac_ 09:17:36 INFO - Initialising browser for dromaeo_css test... 09:17:36 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/tmpJrM0lf/profile http://localhost:49224/getInfo.html) 09:17:39 INFO - PROCESS | 1635 | __metrics Screen width/height:1600/1200 09:17:39 INFO - PROCESS | 1635 | colorDepth:24 09:17:39 INFO - PROCESS | 1635 | Browser inner width/height: 1024/689 09:17:39 INFO - PROCESS | 1635 | __metrics 09:17:39 INFO - PROCESS | 1635 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:17:39 INFO - PROCESS | 1635 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:17:40 INFO - TEST-INFO | 1635: exit 0 09:17:40 INFO - Browser initialized. 09:17:40 INFO - Running cycle 1/1 for dromaeo_css test... 09:17:40 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/tmpJrM0lf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:17:52 INFO - PROCESS | 1638 | RSS: Main: 193953792 09:17:52 INFO - PROCESS | 1638 | 09:20:55 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49224/tests/dromaeo/cssquery-ext.html) 09:20:58 INFO - PROCESS | 1638 | RSS: Main: 404553728 09:20:58 INFO - PROCESS | 1638 | 09:24:01 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-ext.html (next: http://localhost:49224/tests/dromaeo/cssquery-jquery.html) 09:24:02 INFO - PROCESS | 1638 | RSS: Main: 328118272 09:24:02 INFO - PROCESS | 1638 | 09:27:04 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49224/tests/dromaeo/cssquery-mootools.html) 09:27:04 INFO - PROCESS | 1638 | RSS: Main: 325902336 09:27:04 INFO - PROCESS | 1638 | 09:30:07 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49224/tests/dromaeo/cssquery-prototype.html) 09:30:07 INFO - PROCESS | 1638 | RSS: Main: 297558016 09:30:07 INFO - PROCESS | 1638 | 09:33:11 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49224/tests/dromaeo/cssquery-yui.html) 09:33:11 INFO - PROCESS | 1638 | RSS: Main: 292741120 09:33:11 INFO - PROCESS | 1638 | 09:36:14 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-yui.html (next: http://localhost:49224/tests/dromaeo/cssquery-dojo.html) 09:36:15 INFO - PROCESS | 1638 | RSS: Main: 336052224 09:36:15 INFO - PROCESS | 1638 | 09:36:15 INFO - PROCESS | 1638 | __start_tp_report 09:36:15 INFO - PROCESS | 1638 | _x_x_mozilla_page_load 09:36:15 INFO - PROCESS | 1638 | _x_x_mozilla_page_load_details 09:36:15 INFO - PROCESS | 1638 | |i|pagename|runs| 09:36:15 INFO - PROCESS | 1638 | |0;dojo.html;2878.3235939554206;3111.284404586945;3432.176277693354;3434.175797808526;3466.1681196512845;5923.318818335887;5926.407359264072;5964.194833697449;5974.970125149368;6012.639241645499;5924.289085309758;5996.52027837773;6002.3097343805475;6016.60892042017;6019.337872833985;5359.356877174744;5365.839024829242;5433.157860531624;5446.945530544684;5471.835844924639;5289.735513224343;5290.1006828839;5294.100002999499;5481.835544933659;5487.176923461493;5376.677399356032;5397.46025397461;5397.811076612319;5404.7838086476495;5410.161424979135;5363.999999999981;5371.623986320959;5384.488473595002;5384.67691938481;5386.272853164812;5236.92144617831;5316.255724198615;5346.438623944468;5351.143816989302;5367.597430192751;4961.883576195327;5905.320888097838;5943.583949123563;5950;5958.672273024982;6323.33604971476;6398.2641996170105;6407.8398040048905;6408.647524386157;6410.358964103599;6399.584027038228;6401.711922963478;6404.263509696398;6408.743650253984;6424.486041116699;5299.602529810202;5811.767529298865;6077.787277445313;6089;6116.633002019893;5509.311336082989;6309.842253943688;6366.236051673736;6395.456386207132;6402.679866006774;6392.4886009119155;6395.808125756234;6402.743890244338;6406.8718625627225;6413.518986076063;6397.872042559123;6412;6424.486041116699;6430.678466076677;6431.871362572722;6235.501159907197;6285.842853928597;6292.874142517216;6302.842428939313;6397.712102955378;6247.9062814057825;6354.459870911024;6378.681065946684;6397;6399.712012959428;4768.809247630125;4875.146849301358;4898.093852637274;4900.6814557054395;4906.190478570996;4383.254846676073;4416.29339305716;4424.225760491901;4425.247707889666;4452.220861349251;5641.407652196543;5689.6586204827845;5719.3136823581435;5764.884702305931;5828.825135245949;6378.138951241615;6409;6411.743530258737;6415.67921603918;6416.679166041679;5792.710364481759;5802.390748971382;5813.76744930198;5827.621204621686;5848.385919478478;5378.300820893259;5400.945990540123;5478.287822583039;5546.223528705985;5559.249501317351;5386.811461598825;5638.238837756932;6431.163948686641;6546.083548303146;6552.475801935739;5368.2484452177505;6341.936580634135;6376.234851817811;6379.489640828815;6385.13800636908;6382.266039405527;6452.257990330984;6492.38322359394;6541.378569035934;6549.508786840911;6267.2165979252595;6290.937090629035;6335.968320158369;6400.967995159995;6411.551191416556;6361.586496877873;6369.33122022181;6380.7447702091395;6388.233411990591;6410.487161027013;6293.465055470337;6305.148804911279;6351.555391122577;6355;6358.950773122568;6136.335271946613;6158.753649853956;6351.841203969938;6361.363863613602;6383.329750376143;6103.42017508336;6115.694215289307;6201.348858369806;6256.937430625636;6262.655553944577;5417.729113544228;6110.1445797587485;6124.197855633957;6195.783147589813;6218.689065546614;6005.36943620931;6027.78902738402;6052.878942421215;6059.303180134341;6064.636121832704 09:36:15 INFO - PROCESS | 1638 | |1;ext.html;799.5882120707836;818.4271010292797;873.9300855931526;877.6928075173686;980.1178938954944;27642;37414;37776.622233777656;38974.61025389745;39163.80418097905;37805.432918506245;37944.43083353745;38051.238975220476;38073.857784266445;38128.42807357885;7360.447966402515;7533.434992375566;7908.485948413363;8047.3562115030945;11197.384143872085;11312.434378281094;11437.885621143765;11442.713932151679;11453;11454.942725286404;11419.54321827126;11445.542178312857;11447.427628618578;11449.96950274475;11469.483873225683;10413.79172416555;11033.613823516218;11399.316041037522;11426.143039272089;11426.48580813857;11141.220114591983;11189.888101119048;11202.887971120348;11211.887881121247;11213.10295176384;33885.644574217;36370;38421.80789096065;38508;38585.80707096447;3862.864799732024;3886.2810380079495;3894.941575876364;3914.8042597869994;3950.624690654412;24175.87912060446;26786.598201027;27719.168424947286;29831.70168298311;29892.402151956838;3047.8150728308906;3092.2887735820887;3277.4592192288483;3277.475603903364;3290.7696461247483;347450;348333;348354;349242;349803;52251.73874130605;52572.21141682878;52644.73677631664;52725.472745272826;52727;44781.77609111933;55148;55188.17217741737;55268.723656382266;55350.72324638351;14499.710005799825;14907.627309317353;14910.627234319229;14916.328765205591;14918.328675209641;12919.966402687762;12933.935330323477;12944.15862968904;12947.964162867136;12973.221606703628;10104;12041.217320874115;12515.49938002488;12548.81176782349;12637;4945.579625731782;5761.740721667535;5850.33265519325;5857.853553661193;5863.589548731547;1103.4165971830448;1145.1811954452605;1159.8260260960924;1162.9709257268466;1209.7399059202314;25461;27122.72877271202;27398.45203095927;27485.86257068702;27508.44983100327;1068.9251752377415;1255.2029461292145;1261.8675039120944;1277.4506962006426;1290.2648621513897;13826.30868456573;13868.298270057554;13874.93062534681;14058.43766249359;14095.647608809863;452785;453509;454150;454204;454661;56462.15306770402;56676;56688.43311566914;56692;56695.43304566984;1216.3614102596207;1421.5664222412263;1452.3391856705384;1460.6129375715443;1496.3191747754745;18952.24191032343;27216.183514494598;28318.858405708663;28365.71634283631;28754.137375878756;45809.77095114503;46016.7699161502;46065.30902036539;46101.76949115233;46215.76892115518;448244;452661;452822;452873;453769;24054.639180412658;24335.391615209763;29259.7074029257;29601.703982959894;29731.10806675803;37435.25129497449;37515.249695005405;38312;38430.423543646306;38531.6146838528;27107.86446067757;27315.180544583694;27970.440591187657;28018;28121.89085818613;27139.91440342364;27182.456350872475;27229;28105.156845294674;28254.869805208386;30494.6669189626;31715;31823.36353272968;31824;31959;27309.453810924068;27352.72647273502;27926.86036569804;27988.860055700407;28165.859170704014;21337.35987920365;22410.215642452436;22436.663450047938;22512.32463026112;22739.545209096057 09:36:15 INFO - PROCESS | 1638 | |2;jquery.html;403.7093292829163;411.4507132977475;416.49603979185184;425.1497005988023;451.6567408769336;19638.607227855453;20950.580988380225;22321.33036008918;22345.329640110784;22408.215712450066;21281;21714;22931.312060638207;23191.840407979576;23264.41838954027;6632.668366581664;6892.965535172317;6899.310068993111;6915.204751453572;6916.515843890929;7255.8911616325795;7583.469157159001;7705.576193309365;7720.266574675396;7725.111612164616;7332.2301158378405;7628.275313845176;7702.8844567331535;7719.266669666401;7793.025871766029;7529.849403011964;7704.345130663873;7755.534667919943;7807.024121984752;7811.140774514799;7599.924000760032;7600.011998440167;7600.7458769303;7607.581583012932;7615.733449329302;26700;27136.592951105747;27770.8891644334;27833.02584409536;28199.71800282012;2981.806182598124;3000.5049166887657;3001.0096668099477;3040.5895204147378;3062.8009179403266;15383.692326153527;15508.844911550967;15562.844371556253;16251.268692908847;16870.83129168705;2007.6988451732357;2038.6840039793856;2076.6677331626865;2080.0847627044054;2097.4022403614895;166612;171315;172120;175764;176948;16153.676926461405;19855.702164467257;20073;20214.191432342835;20695.965201740153;25223.74776252214;32255.032349029567;32257.548410321593;32636.510452343235;32643.510347344807;10405.167586593054;10420.68737937863;10485.895141048644;10526.263161578616;10759.3544387337;8777.648894044167;8794.824103517894;8800;8956.313106068154;9000.63997440095;7513.549187048795;7795.064592248853;9391.34260601765;9509;9560.952195239119;3812;3843.962130224824;3877.2827027000094;3889.727719059695;3913.8434462621744;804.6057431858347;990.1187942730975;997.8652881861001;1007.9143272821746;1019.6417824333082;15877.52367428956;16225.918870405809;20810.47973800667;20991.790082099287;21425.357239282846;861.8276344731079;959.12240300126;990.9950450247846;992.6724181020246;998.1366118307757;10573.418461984666;10787.56849726018;10895;10906.90930906903;10922.29005114665;753003;755556.2222188854;757786;769099;773583;126757;139378.6683464818;165511;167774;177248;1186.1696812231473;1212.4483360071147;1232.580922486359;1242.726600147966;1243.3720970909633;17796.644067118843;17979.370722024665;18179.363722269656;18535.629287414446;18887.016863229164;64643.67678161767;65534.344656554735;65710;65714;67253.66373168102;74028.62985685037;91140.08859911317;92310.53844731001;95019;95423;9198.586063627286;10732.73168170802;11202.439878005904;11685;11762.94118529402;12701.301428421526;12867.420966056363;12889.419976101286;12907.870921290667;12915.289659068842;11088.501017454395;11260.88739112631;11267.605633802777;11577.536898524302;11592.95663390299;8648;10365.792684146425;10399.740006499898;10411.583536658753;10452.111570516287;11176.329420234812;11285.548578056785;11316.660500185008;11471.885281147082;11583.884161158281;11287;11298.717532061764;11318.886811132114;11472.426378681199;11619.95420412167;9945.850812237966;10823.084200567708;11726.179167458196;11873;12019.519219231133 09:36:15 INFO - PROCESS | 1638 | |3;mootools.html;1341.973390356377;1367.8495365509796;1553.1069634959895;1575.4643421236776;1588.0154304331313;9244.214241789448;9671.709848704533;10860.891391086077;10973.725656858582;11265.15511336651;11017.724556886082;11143.554257829677;11146.21976461646;11163.720906977329;11212.215144939837;2298.9310320690365;2332.7667233276707;2342.824288178385;2343.55472460232;2346.3078391874437;1961.892095934723;2047.406252186864;2292.1862738727677;2366.96449553256;2378.131981826638;2316.444053427182;2343.3907184132163;2347.4014126397747;2348.518553696497;2356.1400088967575;2294.541091781642;2317.40906068953;2317.640765681322;2320.640300753386;2333.824963127772;2258.153192552793;2291.2667946257206;2293.20884294918;2297.5060362022245;2305.181660510528;11154.274972126786;11254.212205145643;11279.32324060559;11294.26587271833;11314.490847911864;5363.088274993261;5554;5564.137558678412;5567.137093750475;5572.080606699889;3230.8223047732367;3384.5938487381427;3469.774464659789;3476.774009689387;3477.9826100869586;1058.6453538064757;1115.827046807746;1133.0878642692628;1148.465963327057;1148.500402324983;15041.097534148095;15542.611434714223;15564.92217538905;15576.299066542033;15592.298346574431;4336.284513055373;4404.1411924674385;4457.153140903218;4460.620847228022;4469.217886869785;11739.530418783153;11741.589044383576;11742.295462272292;11751.942325190774;11759.412029398496;5445.646033007842;5466.34403871538;5472.616916815864;5472.863178420492;5482.424345443751;4297.527272000078;4901.362822833009;4968.900621987612;5023.6985780853265;5080.923786143211;3278.4426647469986;3361.2941282330985;3603.5855876574005;3680.7975561343846;3686.3180311641895;899.964001439935;901.8016036471965;916.1205242966694;925.4586067150658;928.3455164109287;877.0922095630991;928.6471140966389;938.887333519982;966.1884017425397;966.5747071288611;6682.164729408824;6696.397324240842;6717.395434410926;6895.482838787011;6929.618870962146;982.2044144243185;1019.4342140112309;1040.604570263295;1092.2190633696946;1121.8317252412203;4205.158968206371;4212.873613791591;4227.640650544677;4262.168877069003;4331.263685173528;13161.47354105825;13754.793678094837;13770.104943178661;13776.724465510633;13791.724165516835;11737.765244695229;12683.68290792719;13170.407331670098;13228.610995845298;13597.932010339884;9983.700488985342;10235.334703244265;10261.179105671528;10266.538005789758;10297.330673506198;6018.638881667114;6634.701438435379;6691.230508491391;6908.067410899464;6950.6177160256675;12131.696707582381;12210.816837747618;12324.691882703004;12340.444679989243;12376.07179461526;11902.285862848257;12271.079669025037;12394.504219831466;12549.686257843627;12650.493980240688;7659.00432943736;9268.72193834186;9453.858192127262;9484.383515071499;9489.240860730986;10231.28381013322;10350.223733219887;10456.895431045592;10474.319169253975;10480.68557943263;8675.66905964459;8872.955635221782;8980.685676001309;9022.097790220925;9195;8832.425892317237;9016.729498115066;9042.50266235337;9286.210672092677;9323.906760932305;7730.497517661334;7889.250521200477;8063.596820159086;8068.193180681885;8068.83862322762;5974.641521508724;5995.550333724901;6078.635281883101;6118.357572454828;6201.10084037803;5939.67331796738;6009.589027441301;6128.63228206309;6188.628682279078;6236.376362363909 09:36:15 INFO - PROCESS | 1638 | |4;prototype.html;306.86497940905997;372.17377422122894;378.9393697008476;381.28318760729843;384.4387194695743;4986.152354099802;4989.777504511395;4993.800247990081;5003.874903127424;5014.423341315741;4146.896327591804;4670.976645116778;4926;4945.901081978358;4946.406431228249;4072.9389059164137;4103.8563650272245;4136.855210067648;4149;4157.979210103946;3476.461148521983;3959.227950549644;4079.9592004080023;4124.19554702802;4171.395147703581;4128.669706423479;4135.710500264982;4179.895502612444;4182.351735481006;4203.85286514972;3437.081726831414;3965.405189221611;4016.578259282792;4187.7277976931555;4337.327714204272;3655.9086022849374;3799.373103437957;4130.860545985043;4161.667066634662;4166.208420400114;4853.767267057158;4864.489228631013;4875.513488121048;4882;4910.484399138075;4656.231721765905;4765.571098601143;4990.476000020019;5029.597632189452;5062.012907483006;4764.404449443819;5051.722155281458;5205.609579281531;5279.287296215;5315.415304316522;3903.6877049835944;4035.1438338364096;4446.844360447369;4675.4155730533685;4729.722974796786;4668.229742092584;4767.713937163712;5281.313429254211;5611.8877622447735;5674.971625141848;4192.538820729717;4235.639970602471;4591.724496530219;4620.752396852831;4716.033213191287;4079.326911059701;4356.4336636237085;4808.807647694053;4869.70781753096;4890.449339999055;4269.765162916069;4360.514506731196;4597.172508948354;4686.992296656167;4974.403071631427;5203.375594928632;5211.5830733541225;5262.71055091966;5266.789328426897;5307.328191619627;1392.8578565576333;1533.3516469795015;1642.2363600925628;1652.0996057148882;1669.3072374964254;1273.758085866273;1298.6688394459352;1357.803118547805;1362.6902400431372;1376.5457399058287;4121.835126594962;4275.508316543637;4595.333676616939;4710.481846996828;4714.905701886011;4270.48754149504;4860.108235599812;5197.272381866542;5208.296879921162;5316.255724198615;4302.612764851116;4601.562851529099;4607.170709272363;4712.740799256074;4769.6661233714;2339.8830058497006;2477.9752202478103;2534.4804315115725;2782.7773778097703;2846.558783388578;4977.527134922177;5397.541208997279;5519.917201241985;5584.692841893653;5594.776208951596;5273;5411;5460.262864513319;5524.558035357162;5542.8337149885565;4537;4746.5728084471875;4817.67513933664;4922.384701912261;5456.890862182813;4316.546762590008;4729.527047295243;4841.927371089366;5252.133397989442;5400.486953739389;4604.700694454849;4909.26361045846;5054.562897229919;5319.361676598833;5380.542653874464;4634.72191668501;4717.599004084691;5305.734713264244;5545.750441230073;5630;4302.677299202635;4458.910821783481;4710.505396933273;4743;4744.644151688568;4704.553067458586;4873.780679869344;5022.824201152942;5045.86468044687;5390.649607775482;4620.214563524141;5135.537801597874;5195.558377537952;5221.347331583552;5221.467588219634;4466.776661166994;4615.607673347803;5158.742062896915;5176.896462070662;5289.338832645919;3622.1799917201697;4069.7558146511305;4206.768627725504;4593.28804035375;4764.809407623795;4161.500619925629;4603.493615702337;4926.852194434173;5052.418971818288;5171.715960365271;4635.7218566886095;4739.170645137018;5033.018561380496;5100.438951715382;5251.133562962068 09:36:15 INFO - PROCESS | 1638 | |5;yui.html;735.4667865797301;779.5634444710962;784.6076961519241;857.7083791510887;860.1355637584227;15864.603384915383;16406.425775097872;16473.505794826146;17889.284428622872;18745;16404.83595164048;16628.50114496564;16644.66710665786;17051.82948170518;17454.214560344783;976.4873441443264;997.5860018092519;1000.7898341348325;1001.1890368801294;1002.4236064263041;968.7529679931607;994.7861209839883;995.21378111292;995.6764051683195;997.4314640654829;1040.3757745352802;1046.691226088302;1048.7849990751915;1054.7995880782664;1061.3950048472375;1040.089921318846;1040.432964034603;1041.0942480042336;1042.1246153231284;1042.5777560088177;776.4370831147429;776.658270361045;777.2771322669914;777.8211011467338;778.6145857800368;16548.834511654848;16779.244933978;16974.066426346548;17006.829931700773;17312.48062558125;14006.579802605938;14161.78757318641;14177.503787367496;14262.92868535661;14269.500567480192;10003.594823454203;11275.774484510264;11871.465784039652;11904.58333958316;11962.1626486146;3666;3698.2973235085515;3700.5929347771726;3861.4593956846065;3886.417037444378;112102;113570.99999999917;113591;114457;115451.42274288574;10485.423301718327;10731.678049658523;10762.73093172677;10831.079358254636;10906.291091079054;22213.55572888533;22244.666330005064;22281.66577501339;22290.777092229193;22291.55416891653;7692.42306826979;7765.922340776633;7781.688732450639;7945.1657575953695;8051.63767630458;3898.3957486589625;3905.628965248297;3994.820233089518;3995.320795464778;4010.839566417311;3424.9657503425146;3496.8076755778675;3531.5762108546623;3539.59294681115;3547.3614749345365;608.2579253310954;616.8951278282702;619.1826788639044;623.8409205652621;624.2789578037374;636.4113195294334;667.9332066793281;686.3452117474468;698.3679769808332;721.6932803558467;9589.520523973773;10391.896081039093;10421.9478902605;10434.843477347846;10520.473976301155;651.6774196772533;687.388224480213;693.299767235086;708.727759329926;736.6022347932056;4855.854324370274;4999.775010124553;5005.499450055038;5019.42276638184;5024.598032157419;85115;85793.57103214443;86853;102088;107513.46243268733;49822;68167.88022253502;90068.5496572513;92475.07524924664;93736.53131734297;61526;73338.63330683313;74525.62737186279;78179.21820781719;80170;10763.677089687322;10893.719088539105;11095.389753563632;11330;11441.427928603704;35581.64418355783;47777.56667299986;49236;49327.50672493229;49774.253386198514;45597.54402455933;62055.689721551105;64231;64669.67665161644;64834.351656484716;12553.623391298275;12556.112094218564;12702.618921432371;12894.226346419246;12935.935320323339;14262.57212283633;14404.063735857137;14407.279636018367;14630.780538291721;14984.850151498782;13376.276636923978;13535.796963045364;13827.58517244444;13995.650108747363;14008.649783755487;12982.350882456029;13712.65718357009;13768.449262029406;13776;13832.792508112185;13191.142575732547;13207.537736179187;13441.193528388329;13445.731085378433;13946.4421423146;9320.72037838866;9728.17310528613;9848.045586323931;9856.5564549594;10092.394456332644;10763.677089687322;11118.888811111785;11171.162162837658;11324.660260192208;11362.715932101764 09:36:15 INFO - PROCESS | 1638 | __end_tp_report 09:36:15 INFO - PROCESS | 1638 | __start_cc_report 09:36:15 INFO - PROCESS | 1638 | _x_x_mozilla_cycle_collect,2575 09:36:15 INFO - PROCESS | 1638 | __end_cc_report 09:36:15 INFO - PROCESS | 1638 | __startTimestamp1496766975220__endTimestamp 09:36:15 INFO - PROCESS | 1638 | 09:36:15 INFO - PROCESS | 1638 | ------- Summary: start ------- 09:36:15 INFO - PROCESS | 1638 | Number of tests: 6 09:36:15 INFO - PROCESS | 1638 | 09:36:15 INFO - PROCESS | 1638 | [#0] dojo.html Cycles:165 Average:5856.19 Median:6077.79 stddev:694.30 (11.4%) stddev-sans-first:655.95 09:36:15 INFO - PROCESS | 1638 | Values: 2878.3 3111.3 3432.2 3434.2 3466.2 5923.3 5926.4 5964.2 5975.0 6012.6 5924.3 5996.5 6002.3 6016.6 6019.3 5359.4 5365.8 5433.2 5446.9 5471.8 5289.7 5290.1 5294.1 5481.8 5487.2 5376.7 5397.5 5397.8 5404.8 5410.2 5364.0 5371.6 5384.5 5384.7 5386.3 5236.9 5316.3 5346.4 5351.1 5367.6 4961.9 5905.3 5943.6 5950.0 5958.7 6323.3 6398.3 6407.8 6408.6 6410.4 6399.6 6401.7 6404.3 6408.7 6424.5 5299.6 5811.8 6077.8 6089.0 6116.6 5509.3 6309.8 6366.2 6395.5 6402.7 6392.5 6395.8 6402.7 6406.9 6413.5 6397.9 6412.0 6424.5 6430.7 6431.9 6235.5 6285.8 6292.9 6302.8 6397.7 6247.9 6354.5 6378.7 6397.0 6399.7 4768.8 4875.1 4898.1 4900.7 4906.2 4383.3 4416.3 4424.2 4425.2 4452.2 5641.4 5689.7 5719.3 5764.9 5828.8 6378.1 6409.0 6411.7 6415.7 6416.7 5792.7 5802.4 5813.8 5827.6 5848.4 5378.3 5400.9 5478.3 5546.2 5559.2 5386.8 5638.2 6431.2 6546.1 6552.5 5368.2 6341.9 6376.2 6379.5 6385.1 6382.3 6452.3 6492.4 6541.4 6549.5 6267.2 6290.9 6336.0 6401.0 6411.6 6361.6 6369.3 6380.7 6388.2 6410.5 6293.5 6305.1 6351.6 6355.0 6359.0 6136.3 6158.8 6351.8 6361.4 6383.3 6103.4 6115.7 6201.3 6256.9 6262.7 5417.7 6110.1 6124.2 6195.8 6218.7 6005.4 6027.8 6052.9 6059.3 6064.6 09:36:15 INFO - PROCESS | 1638 | 09:36:15 INFO - PROCESS | 1638 | [#1] ext.html Cycles:180 Average:55066.17 Median:28559.93 stddev:111793.51 (391.4%) stddev-sans-first:112032.87 09:36:15 INFO - PROCESS | 1638 | Values: 799.6 818.4 873.9 877.7 980.1 27642.0 37414.0 37776.6 38974.6 39163.8 37805.4 37944.4 38051.2 38073.9 38128.4 7360.4 7533.4 7908.5 8047.4 11197.4 11312.4 11437.9 11442.7 11453.0 11454.9 11419.5 11445.5 11447.4 11450.0 11469.5 10413.8 11033.6 11399.3 11426.1 11426.5 11141.2 11189.9 11202.9 11211.9 11213.1 33885.6 36370.0 38421.8 38508.0 38585.8 3862.9 3886.3 3894.9 3914.8 3950.6 24175.9 26786.6 27719.2 29831.7 29892.4 3047.8 3092.3 3277.5 3277.5 3290.8 347450.0 348333.0 348354.0 349242.0 349803.0 52251.7 52572.2 52644.7 52725.5 52727.0 44781.8 55148.0 55188.2 55268.7 55350.7 14499.7 14907.6 14910.6 14916.3 14918.3 12920.0 12933.9 12944.2 12948.0 12973.2 10104.0 12041.2 12515.5 12548.8 12637.0 4945.6 5761.7 5850.3 5857.9 5863.6 1103.4 1145.2 1159.8 1163.0 1209.7 25461.0 27122.7 27398.5 27485.9 27508.4 1068.9 1255.2 1261.9 1277.5 1290.3 13826.3 13868.3 13874.9 14058.4 14095.6 452785.0 453509.0 454150.0 454204.0 454661.0 56462.2 56676.0 56688.4 56692.0 56695.4 1216.4 1421.6 1452.3 1460.6 1496.3 18952.2 27216.2 28318.9 28365.7 28754.1 45809.8 46016.8 46065.3 46101.8 46215.8 448244.0 452661.0 452822.0 452873.0 453769.0 24054.6 24335.4 29259.7 29601.7 29731.1 37435.3 37515.2 38312.0 38430.4 38531.6 27107.9 27315.2 27970.4 28018.0 28121.9 27139.9 27182.5 27229.0 28105.2 28254.9 30494.7 31715.0 31823.4 31824.0 31959.0 27309.5 27352.7 27926.9 27988.9 28165.9 21337.4 22410.2 22436.7 22512.3 22739.5 09:36:15 INFO - PROCESS | 1638 | 09:36:15 INFO - PROCESS | 1638 | [#2] jquery.html Cycles:180 Average:44120.94 Median:22669.76 stddev:127575.11 (562.8%) stddev-sans-first:127890.76 09:36:15 INFO - PROCESS | 1638 | Values: 403.7 411.5 416.5 425.1 451.7 19638.6 20950.6 22321.3 22345.3 22408.2 21281.0 21714.0 22931.3 23191.8 23264.4 6632.7 6893.0 6899.3 6915.2 6916.5 7255.9 7583.5 7705.6 7720.3 7725.1 7332.2 7628.3 7702.9 7719.3 7793.0 7529.8 7704.3 7755.5 7807.0 7811.1 7599.9 7600.0 7600.7 7607.6 7615.7 26700.0 27136.6 27770.9 27833.0 28199.7 2981.8 3000.5 3001.0 3040.6 3062.8 15383.7 15508.8 15562.8 16251.3 16870.8 2007.7 2038.7 2076.7 2080.1 2097.4 166612.0 171315.0 172120.0 175764.0 176948.0 16153.7 19855.7 20073.0 20214.2 20696.0 25223.7 32255.0 32257.5 32636.5 32643.5 10405.2 10420.7 10485.9 10526.3 10759.4 8777.6 8794.8 8800.0 8956.3 9000.6 7513.5 7795.1 9391.3 9509.0 9561.0 3812.0 3844.0 3877.3 3889.7 3913.8 804.6 990.1 997.9 1007.9 1019.6 15877.5 16225.9 20810.5 20991.8 21425.4 861.8 959.1 991.0 992.7 998.1 10573.4 10787.6 10895.0 10906.9 10922.3 753003.0 755556.2 757786.0 769099.0 773583.0 126757.0 139378.7 165511.0 167774.0 177248.0 1186.2 1212.4 1232.6 1242.7 1243.4 17796.6 17979.4 18179.4 18535.6 18887.0 64643.7 65534.3 65710.0 65714.0 67253.7 74028.6 91140.1 92310.5 95019.0 95423.0 9198.6 10732.7 11202.4 11685.0 11762.9 12701.3 12867.4 12889.4 12907.9 12915.3 11088.5 11260.9 11267.6 11577.5 11593.0 8648.0 10365.8 10399.7 10411.6 10452.1 11176.3 11285.5 11316.7 11471.9 11583.9 11287.0 11298.7 11318.9 11472.4 11620.0 9945.9 10823.1 11726.2 11873.0 12019.5 09:36:15 INFO - PROCESS | 1638 | 09:36:15 INFO - PROCESS | 1638 | [#3] mootools.html Cycles:180 Average:6692.04 Median:2804.48 stddev:4245.54 (151.4%) stddev-sans-first:4238.42 09:36:15 INFO - PROCESS | 1638 | Values: 1342.0 1367.8 1553.1 1575.5 1588.0 9244.2 9671.7 10860.9 10973.7 11265.2 11017.7 11143.6 11146.2 11163.7 11212.2 2298.9 2332.8 2342.8 2343.6 2346.3 1961.9 2047.4 2292.2 2367.0 2378.1 2316.4 2343.4 2347.4 2348.5 2356.1 2294.5 2317.4 2317.6 2320.6 2333.8 2258.2 2291.3 2293.2 2297.5 2305.2 11154.3 11254.2 11279.3 11294.3 11314.5 5363.1 5554.0 5564.1 5567.1 5572.1 3230.8 3384.6 3469.8 3476.8 3478.0 1058.6 1115.8 1133.1 1148.5 1148.5 15041.1 15542.6 15564.9 15576.3 15592.3 4336.3 4404.1 4457.2 4460.6 4469.2 11739.5 11741.6 11742.3 11751.9 11759.4 5445.6 5466.3 5472.6 5472.9 5482.4 4297.5 4901.4 4968.9 5023.7 5080.9 3278.4 3361.3 3603.6 3680.8 3686.3 900.0 901.8 916.1 925.5 928.3 877.1 928.6 938.9 966.2 966.6 6682.2 6696.4 6717.4 6895.5 6929.6 982.2 1019.4 1040.6 1092.2 1121.8 4205.2 4212.9 4227.6 4262.2 4331.3 13161.5 13754.8 13770.1 13776.7 13791.7 11737.8 12683.7 13170.4 13228.6 13597.9 9983.7 10235.3 10261.2 10266.5 10297.3 6018.6 6634.7 6691.2 6908.1 6950.6 12131.7 12210.8 12324.7 12340.4 12376.1 11902.3 12271.1 12394.5 12549.7 12650.5 7659.0 9268.7 9453.9 9484.4 9489.2 10231.3 10350.2 10456.9 10474.3 10480.7 8675.7 8873.0 8980.7 9022.1 9195.0 8832.4 9016.7 9042.5 9286.2 9323.9 7730.5 7889.3 8063.6 8068.2 8068.8 5974.6 5995.6 6078.6 6118.4 6201.1 5939.7 6009.6 6128.6 6188.6 6236.4 09:36:15 INFO - PROCESS | 1638 | 09:36:15 INFO - PROCESS | 1638 | [#4] prototype.html Cycles:180 Average:4381.21 Median:4715.47 stddev:1162.83 (24.7%) stddev-sans-first:1125.16 09:36:15 INFO - PROCESS | 1638 | Values: 306.9 372.2 378.9 381.3 384.4 4986.2 4989.8 4993.8 5003.9 5014.4 4146.9 4671.0 4926.0 4945.9 4946.4 4072.9 4103.9 4136.9 4149.0 4158.0 3476.5 3959.2 4080.0 4124.2 4171.4 4128.7 4135.7 4179.9 4182.4 4203.9 3437.1 3965.4 4016.6 4187.7 4337.3 3655.9 3799.4 4130.9 4161.7 4166.2 4853.8 4864.5 4875.5 4882.0 4910.5 4656.2 4765.6 4990.5 5029.6 5062.0 4764.4 5051.7 5205.6 5279.3 5315.4 3903.7 4035.1 4446.8 4675.4 4729.7 4668.2 4767.7 5281.3 5611.9 5675.0 4192.5 4235.6 4591.7 4620.8 4716.0 4079.3 4356.4 4808.8 4869.7 4890.4 4269.8 4360.5 4597.2 4687.0 4974.4 5203.4 5211.6 5262.7 5266.8 5307.3 1392.9 1533.4 1642.2 1652.1 1669.3 1273.8 1298.7 1357.8 1362.7 1376.5 4121.8 4275.5 4595.3 4710.5 4714.9 4270.5 4860.1 5197.3 5208.3 5316.3 4302.6 4601.6 4607.2 4712.7 4769.7 2339.9 2478.0 2534.5 2782.8 2846.6 4977.5 5397.5 5519.9 5584.7 5594.8 5273.0 5411.0 5460.3 5524.6 5542.8 4537.0 4746.6 4817.7 4922.4 5456.9 4316.5 4729.5 4841.9 5252.1 5400.5 4604.7 4909.3 5054.6 5319.4 5380.5 4634.7 4717.6 5305.7 5545.8 5630.0 4302.7 4458.9 4710.5 4743.0 4744.6 4704.6 4873.8 5022.8 5045.9 5390.6 4620.2 5135.5 5195.6 5221.3 5221.5 4466.8 4615.6 5158.7 5176.9 5289.3 3622.2 4069.8 4206.8 4593.3 4764.8 4161.5 4603.5 4926.9 5052.4 5171.7 4635.7 4739.2 5033.0 5100.4 5251.1 09:36:15 INFO - PROCESS | 1638 | 09:36:15 INFO - PROCESS | 1638 | [#5] yui.html Cycles:180 Average:19939.86 Median:22229.11 stddev:28275.81 (127.2%) stddev-sans-first:28318.37 09:36:15 INFO - PROCESS | 1638 | Values: 735.5 779.6 784.6 857.7 860.1 15864.6 16406.4 16473.5 17889.3 18745.0 16404.8 16628.5 16644.7 17051.8 17454.2 976.5 997.6 1000.8 1001.2 1002.4 968.8 994.8 995.2 995.7 997.4 1040.4 1046.7 1048.8 1054.8 1061.4 1040.1 1040.4 1041.1 1042.1 1042.6 776.4 776.7 777.3 777.8 778.6 16548.8 16779.2 16974.1 17006.8 17312.5 14006.6 14161.8 14177.5 14262.9 14269.5 10003.6 11275.8 11871.5 11904.6 11962.2 3666.0 3698.3 3700.6 3861.5 3886.4 112102.0 113571.0 113591.0 114457.0 115451.4 10485.4 10731.7 10762.7 10831.1 10906.3 22213.6 22244.7 22281.7 22290.8 22291.6 7692.4 7765.9 7781.7 7945.2 8051.6 3898.4 3905.6 3994.8 3995.3 4010.8 3425.0 3496.8 3531.6 3539.6 3547.4 608.3 616.9 619.2 623.8 624.3 636.4 667.9 686.3 698.4 721.7 9589.5 10391.9 10421.9 10434.8 10520.5 651.7 687.4 693.3 708.7 736.6 4855.9 4999.8 5005.5 5019.4 5024.6 85115.0 85793.6 86853.0 102088.0 107513.5 49822.0 68167.9 90068.5 92475.1 93736.5 61526.0 73338.6 74525.6 78179.2 80170.0 10763.7 10893.7 11095.4 11330.0 11441.4 35581.6 47777.6 49236.0 49327.5 49774.3 45597.5 62055.7 64231.0 64669.7 64834.4 12553.6 12556.1 12702.6 12894.2 12935.9 14262.6 14404.1 14407.3 14630.8 14984.9 13376.3 13535.8 13827.6 13995.7 14008.6 12982.4 13712.7 13768.4 13776.0 13832.8 13191.1 13207.5 13441.2 13445.7 13946.4 9320.7 9728.2 9848.0 9856.6 10092.4 10763.7 11118.9 11171.2 11324.7 11362.7 09:36:15 INFO - PROCESS | 1638 | -------- Summary: end -------- 09:36:15 INFO - PROCESS | 1638 | 09:36:15 INFO - TEST-INFO | 1638: exit 0 09:36:15 INFO - TEST-OK | dromaeo_css | took 1118901ms 09:36:15 INFO - TEST-START | kraken 09:36:15 INFO - operating with platform_type : mac_ 09:36:15 INFO - Initialising browser for kraken test... 09:36:15 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/tmpIT_DuW/profile http://localhost:49224/getInfo.html) 09:36:18 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 09:36:18 INFO - PROCESS | 1648 | colorDepth:24 09:36:18 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 09:36:18 INFO - PROCESS | 1648 | __metrics 09:36:18 INFO - PROCESS | 1648 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:36:18 INFO - PROCESS | 1648 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:36:18 INFO - TEST-INFO | 1648: exit 0 09:36:18 INFO - Browser initialized. 09:36:18 INFO - Running cycle 1/1 for kraken test... 09:36:18 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/tmpIT_DuW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:36:30 INFO - PROCESS | 1650 | RSS: Main: 193671168 09:36:30 INFO - PROCESS | 1650 | 09:36:33 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-beat-detection) 09:36:34 INFO - PROCESS | 1650 | RSS: Main: 244662272 09:36:34 INFO - PROCESS | 1650 | 09:36:37 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-dft) 09:36:38 INFO - PROCESS | 1650 | RSS: Main: 258621440 09:36:38 INFO - PROCESS | 1650 | 09:36:41 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-fft) 09:36:41 INFO - PROCESS | 1650 | RSS: Main: 357179392 09:36:41 INFO - PROCESS | 1650 | 09:36:43 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-oscillator) 09:36:44 INFO - PROCESS | 1650 | RSS: Main: 258310144 09:36:44 INFO - PROCESS | 1650 | 09:36:46 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:36:47 INFO - PROCESS | 1650 | RSS: Main: 260050944 09:36:47 INFO - PROCESS | 1650 | 09:36:49 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-darkroom) 09:36:50 INFO - PROCESS | 1650 | RSS: Main: 299298816 09:36:50 INFO - PROCESS | 1650 | 09:36:53 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-desaturate) 09:36:54 INFO - PROCESS | 1650 | RSS: Main: 301408256 09:36:54 INFO - PROCESS | 1650 | 09:36:57 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49224/tests/kraken/driver.html?testName=json-parse-financial) 09:36:57 INFO - PROCESS | 1650 | RSS: Main: 297050112 09:36:57 INFO - PROCESS | 1650 | 09:36:59 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49224/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:37:00 INFO - PROCESS | 1650 | RSS: Main: 276545536 09:37:00 INFO - PROCESS | 1650 | 09:37:01 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:37:02 INFO - PROCESS | 1650 | RSS: Main: 279764992 09:37:02 INFO - PROCESS | 1650 | 09:37:04 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:37:05 INFO - PROCESS | 1650 | RSS: Main: 284782592 09:37:05 INFO - PROCESS | 1650 | 09:37:08 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:37:09 INFO - PROCESS | 1650 | RSS: Main: 288960512 09:37:09 INFO - PROCESS | 1650 | 09:37:12 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:37:12 INFO - PROCESS | 1650 | RSS: Main: 290275328 09:37:12 INFO - PROCESS | 1650 | 09:37:14 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49224/tests/kraken/driver.html?testName=ai-astar) 09:37:14 INFO - PROCESS | 1650 | RSS: Main: 334663680 09:37:14 INFO - PROCESS | 1650 | 09:37:14 INFO - PROCESS | 1650 | __start_tp_report 09:37:14 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 09:37:14 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 09:37:14 INFO - PROCESS | 1650 | |i|pagename|runs| 09:37:14 INFO - PROCESS | 1650 | |0;ai-astar;110;118;117;117;116;117;119;164;174;117 09:37:14 INFO - PROCESS | 1650 | |1;audio-beat-detection;145;111;112;143;111;108;114;110;128;108 09:37:14 INFO - PROCESS | 1650 | |2;audio-dft;185;185;184;185;186;183;183;185;185;184 09:37:14 INFO - PROCESS | 1650 | |3;audio-fft;84;60;59;92;66;60;58;60;75;61 09:37:14 INFO - PROCESS | 1650 | |4;audio-oscillator;83;70;87;70;89;98;115;80;68;82 09:37:14 INFO - PROCESS | 1650 | |5;imaging-gaussian-blur;115;87;89;88;89;90;89;91;90;88 09:37:14 INFO - PROCESS | 1650 | |6;imaging-darkroom;95;95;95;96;95;97;142;151;95;95 09:37:14 INFO - PROCESS | 1650 | |7;imaging-desaturate;91;114;85;83;86;86;85;86;86;84 09:37:14 INFO - PROCESS | 1650 | |8;json-parse-financial;63;59;62;64;65;67;62;63;64;65 09:37:14 INFO - PROCESS | 1650 | |9;json-stringify-tinderbox;61;60;69;64;62;58;59;58;57;61 09:37:14 INFO - PROCESS | 1650 | |10;stanford-crypto-aes;86;81;83;86;84;84;85;89;90;103 09:37:14 INFO - PROCESS | 1650 | |11;stanford-crypto-ccm;136;157;143;135;143;142;130;138;151;138 09:37:14 INFO - PROCESS | 1650 | |12;stanford-crypto-pbkdf2;165;166;165;169;165;161;135;178;223;218 09:37:14 INFO - PROCESS | 1650 | |13;stanford-crypto-sha256-iterative;63;65;65;67;79;80;80;81;61;63 09:37:14 INFO - PROCESS | 1650 | __end_tp_report 09:37:14 INFO - PROCESS | 1650 | __start_cc_report 09:37:14 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,2126 09:37:14 INFO - PROCESS | 1650 | __end_cc_report 09:37:14 INFO - PROCESS | 1650 | __startTimestamp1496767034719__endTimestamp 09:37:14 INFO - PROCESS | 1650 | 09:37:14 INFO - PROCESS | 1650 | ------- Summary: start ------- 09:37:14 INFO - PROCESS | 1650 | Number of tests: 14 09:37:14 INFO - PROCESS | 1650 | 09:37:14 INFO - PROCESS | 1650 | [#0] ai-astar Cycles:10 Average:126.90 Median:117.50 stddev:22.44 (19.1%) stddev-sans-first:22.96 09:37:14 INFO - PROCESS | 1650 | Values: 110.0 118.0 117.0 117.0 116.0 117.0 119.0 164.0 174.0 117.0 09:37:14 INFO - PROCESS | 1650 | 09:37:14 INFO - PROCESS | 1650 | [#1] audio-beat-detection Cycles:10 Average:119.00 Median:113.00 stddev:14.37 (12.7%) stddev-sans-first:11.76 09:37:14 INFO - PROCESS | 1650 | Values: 145.0 111.0 112.0 143.0 111.0 108.0 114.0 110.0 128.0 108.0 09:37:14 INFO - PROCESS | 1650 | 09:37:14 INFO - PROCESS | 1650 | [#2] audio-dft Cycles:10 Average:184.50 Median:185.00 stddev:0.97 (0.5%) stddev-sans-first:1.01 09:37:14 INFO - PROCESS | 1650 | Values: 185.0 185.0 184.0 185.0 186.0 183.0 183.0 185.0 185.0 184.0 09:37:14 INFO - PROCESS | 1650 | 09:37:14 INFO - PROCESS | 1650 | [#3] audio-fft Cycles:10 Average:67.50 Median:63.50 stddev:12.04 (19.0%) stddev-sans-first:11.19 09:37:14 INFO - PROCESS | 1650 | Values: 84.0 60.0 59.0 92.0 66.0 60.0 58.0 60.0 75.0 61.0 09:37:14 INFO - PROCESS | 1650 | 09:37:14 INFO - PROCESS | 1650 | [#4] audio-oscillator Cycles:10 Average:84.20 Median:82.50 stddev:14.37 (17.4%) stddev-sans-first:15.24 09:37:14 INFO - PROCESS | 1650 | Values: 83.0 70.0 87.0 70.0 89.0 98.0 115.0 80.0 68.0 82.0 09:37:14 INFO - PROCESS | 1650 | 09:37:14 INFO - PROCESS | 1650 | [#5] imaging-gaussian-blur Cycles:10 Average:91.60 Median:89.00 stddev:8.30 (9.3%) stddev-sans-first:1.22 09:37:14 INFO - PROCESS | 1650 | Values: 115.0 87.0 89.0 88.0 89.0 90.0 89.0 91.0 90.0 88.0 09:37:14 INFO - PROCESS | 1650 | 09:37:14 INFO - PROCESS | 1650 | [#6] imaging-darkroom Cycles:10 Average:105.60 Median:95.00 stddev:21.67 (22.8%) stddev-sans-first:22.64 09:37:14 INFO - PROCESS | 1650 | Values: 95.0 95.0 95.0 96.0 95.0 97.0 142.0 151.0 95.0 95.0 09:37:14 INFO - PROCESS | 1650 | 09:37:14 INFO - PROCESS | 1650 | [#7] imaging-desaturate Cycles:10 Average:88.60 Median:86.00 stddev:9.17 (10.7%) stddev-sans-first:9.68 09:37:14 INFO - PROCESS | 1650 | Values: 91.0 114.0 85.0 83.0 86.0 86.0 85.0 86.0 86.0 84.0 09:37:14 INFO - PROCESS | 1650 | 09:37:14 INFO - PROCESS | 1650 | [#8] json-parse-financial Cycles:10 Average:63.40 Median:64.00 stddev:2.17 (3.4%) stddev-sans-first:2.30 09:37:14 INFO - PROCESS | 1650 | Values: 63.0 59.0 62.0 64.0 65.0 67.0 62.0 63.0 64.0 65.0 09:37:14 INFO - PROCESS | 1650 | 09:37:14 INFO - PROCESS | 1650 | [#9] json-stringify-tinderbox Cycles:10 Average:60.90 Median:61.00 stddev:3.54 (5.8%) stddev-sans-first:3.76 09:37:14 INFO - PROCESS | 1650 | Values: 61.0 60.0 69.0 64.0 62.0 58.0 59.0 58.0 57.0 61.0 09:37:14 INFO - PROCESS | 1650 | 09:37:14 INFO - PROCESS | 1650 | [#10] stanford-crypto-aes Cycles:10 Average:87.10 Median:85.50 stddev:6.19 (7.2%) stddev-sans-first:6.55 09:37:14 INFO - PROCESS | 1650 | Values: 86.0 81.0 83.0 86.0 84.0 84.0 85.0 89.0 90.0 103.0 09:37:14 INFO - PROCESS | 1650 | 09:37:14 INFO - PROCESS | 1650 | [#11] stanford-crypto-ccm Cycles:10 Average:141.30 Median:142.50 stddev:7.92 (5.6%) stddev-sans-first:8.16 09:37:14 INFO - PROCESS | 1650 | Values: 136.0 157.0 143.0 135.0 143.0 142.0 130.0 138.0 151.0 138.0 09:37:14 INFO - PROCESS | 1650 | 09:37:14 INFO - PROCESS | 1650 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.50 Median:167.50 stddev:26.61 (15.9%) stddev-sans-first:28.00 09:37:14 INFO - PROCESS | 1650 | Values: 165.0 166.0 165.0 169.0 165.0 161.0 135.0 178.0 223.0 218.0 09:37:14 INFO - PROCESS | 1650 | 09:37:14 INFO - PROCESS | 1650 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.40 Median:73.00 stddev:8.42 (11.5%) stddev-sans-first:8.50 09:37:14 INFO - PROCESS | 1650 | Values: 63.0 65.0 65.0 67.0 79.0 80.0 80.0 81.0 61.0 63.0 09:37:14 INFO - PROCESS | 1650 | -------- Summary: end -------- 09:37:14 INFO - PROCESS | 1650 | 09:37:15 INFO - TEST-INFO | 1650: exit 0 09:37:15 INFO - TEST-OK | kraken | took 59844ms 09:37:15 INFO - SUITE-END | took 1178s 09:37:15 INFO - Completed test suite (00:19:39) 09:37:15 INFO - javascript benchmark 09:37:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7637.855891658002, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2878.3235939554206, 3111.284404586945, 3432.176277693354, 3434.175797808526, 3466.1681196512845, 5923.318818335887, 5926.407359264072, 5964.194833697449, 5974.970125149368, 6012.639241645499, 5924.289085309758, 5996.52027837773, 6002.3097343805475, 6016.60892042017, 6019.337872833985, 5359.356877174744, 5365.839024829242, 5433.157860531624, 5446.945530544684, 5471.835844924639, 5289.735513224343, 5290.1006828839, 5294.100002999499, 5481.835544933659, 5487.176923461493, 5376.677399356032, 5397.46025397461, 5397.811076612319, 5404.7838086476495, 5410.161424979135, 5363.999999999981, 5371.623986320959, 5384.488473595002, 5384.67691938481, 5386.272853164812, 5236.92144617831, 5316.255724198615, 5346.438623944468, 5351.143816989302, 5367.597430192751, 4961.883576195327, 5905.320888097838, 5943.583949123563, 5950.0, 5958.672273024982, 6323.33604971476, 6398.2641996170105, 6407.8398040048905, 6408.647524386157, 6410.358964103599, 6399.584027038228, 6401.711922963478, 6404.263509696398, 6408.743650253984, 6424.486041116699, 5299.602529810202, 5811.767529298865, 6077.787277445313, 6089.0, 6116.633002019893, 5509.311336082989, 6309.842253943688, 6366.236051673736, 6395.456386207132, 6402.679866006774, 6392.4886009119155, 6395.808125756234, 6402.743890244338, 6406.8718625627225, 6413.518986076063, 6397.872042559123, 6412.0, 6424.486041116699, 6430.678466076677, 6431.871362572722, 6235.501159907197, 6285.842853928597, 6292.874142517216, 6302.842428939313, 6397.712102955378, 6247.9062814057825, 6354.459870911024, 6378.681065946684, 6397.0, 6399.712012959428, 4768.809247630125, 4875.146849301358, 4898.093852637274, 4900.6814557054395, 4906.190478570996, 4383.254846676073, 4416.29339305716, 4424.225760491901, 4425.247707889666, 4452.220861349251, 5641.407652196543, 5689.6586204827845, 5719.3136823581435, 5764.884702305931, 5828.825135245949, 6378.138951241615, 6409.0, 6411.743530258737, 6415.67921603918, 6416.679166041679, 5792.710364481759, 5802.390748971382, 5813.76744930198, 5827.621204621686, 5848.385919478478, 5378.300820893259, 5400.945990540123, 5478.287822583039, 5546.223528705985, 5559.249501317351, 5386.811461598825, 5638.238837756932, 6431.163948686641, 6546.083548303146, 6552.475801935739, 5368.2484452177505, 6341.936580634135, 6376.234851817811, 6379.489640828815, 6385.13800636908, 6382.266039405527, 6452.257990330984, 6492.38322359394, 6541.378569035934, 6549.508786840911, 6267.2165979252595, 6290.937090629035, 6335.968320158369, 6400.967995159995, 6411.551191416556, 6361.586496877873, 6369.33122022181, 6380.7447702091395, 6388.233411990591, 6410.487161027013, 6293.465055470337, 6305.148804911279, 6351.555391122577, 6355.0, 6358.950773122568, 6136.335271946613, 6158.753649853956, 6351.841203969938, 6361.363863613602, 6383.329750376143, 6103.42017508336, 6115.694215289307, 6201.348858369806, 6256.937430625636, 6262.655553944577, 5417.729113544228, 6110.1445797587485, 6124.197855633957, 6195.783147589813, 6218.689065546614, 6005.36943620931, 6027.78902738402, 6052.878942421215, 6059.303180134341, 6064.636121832704], "value": 5809.155886306055, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [799.5882120707836, 818.4271010292797, 873.9300855931526, 877.6928075173686, 980.1178938954944, 27642.0, 37414.0, 37776.622233777656, 38974.61025389745, 39163.80418097905, 37805.432918506245, 37944.43083353745, 38051.238975220476, 38073.857784266445, 38128.42807357885, 7360.447966402515, 7533.434992375566, 7908.485948413363, 8047.3562115030945, 11197.384143872085, 11312.434378281094, 11437.885621143765, 11442.713932151679, 11453.0, 11454.942725286404, 11419.54321827126, 11445.542178312857, 11447.427628618578, 11449.96950274475, 11469.483873225683, 10413.79172416555, 11033.613823516218, 11399.316041037522, 11426.143039272089, 11426.48580813857, 11141.220114591983, 11189.888101119048, 11202.887971120348, 11211.887881121247, 11213.10295176384, 33885.644574217, 36370.0, 38421.80789096065, 38508.0, 38585.80707096447, 3862.864799732024, 3886.2810380079495, 3894.941575876364, 3914.8042597869994, 3950.624690654412, 24175.87912060446, 26786.598201027, 27719.168424947286, 29831.70168298311, 29892.402151956838, 3047.8150728308906, 3092.2887735820887, 3277.4592192288483, 3277.475603903364, 3290.7696461247483, 347450.0, 348333.0, 348354.0, 349242.0, 349803.0, 52251.73874130605, 52572.21141682878, 52644.73677631664, 52725.472745272826, 52727.0, 44781.77609111933, 55148.0, 55188.17217741737, 55268.723656382266, 55350.72324638351, 14499.710005799825, 14907.627309317353, 14910.627234319229, 14916.328765205591, 14918.328675209641, 12919.966402687762, 12933.935330323477, 12944.15862968904, 12947.964162867136, 12973.221606703628, 10104.0, 12041.217320874115, 12515.49938002488, 12548.81176782349, 12637.0, 4945.579625731782, 5761.740721667535, 5850.33265519325, 5857.853553661193, 5863.589548731547, 1103.4165971830448, 1145.1811954452605, 1159.8260260960924, 1162.9709257268466, 1209.7399059202314, 25461.0, 27122.72877271202, 27398.45203095927, 27485.86257068702, 27508.44983100327, 1068.9251752377415, 1255.2029461292145, 1261.8675039120944, 1277.4506962006426, 1290.2648621513897, 13826.30868456573, 13868.298270057554, 13874.93062534681, 14058.43766249359, 14095.647608809863, 452785.0, 453509.0, 454150.0, 454204.0, 454661.0, 56462.15306770402, 56676.0, 56688.43311566914, 56692.0, 56695.43304566984, 1216.3614102596207, 1421.5664222412263, 1452.3391856705384, 1460.6129375715443, 1496.3191747754745, 18952.24191032343, 27216.183514494598, 28318.858405708663, 28365.71634283631, 28754.137375878756, 45809.77095114503, 46016.7699161502, 46065.30902036539, 46101.76949115233, 46215.76892115518, 448244.0, 452661.0, 452822.0, 452873.0, 453769.0, 24054.639180412658, 24335.391615209763, 29259.7074029257, 29601.703982959894, 29731.10806675803, 37435.25129497449, 37515.249695005405, 38312.0, 38430.423543646306, 38531.6146838528, 27107.86446067757, 27315.180544583694, 27970.440591187657, 28018.0, 28121.89085818613, 27139.91440342364, 27182.456350872475, 27229.0, 28105.156845294674, 28254.869805208386, 30494.6669189626, 31715.0, 31823.36353272968, 31824.0, 31959.0, 27309.453810924068, 27352.72647273502, 27926.86036569804, 27988.860055700407, 28165.859170704014, 21337.35987920365, 22410.215642452436, 22436.663450047938, 22512.32463026112, 22739.545209096057], "value": 18532.356526386193, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [403.7093292829163, 411.4507132977475, 416.49603979185184, 425.1497005988023, 451.6567408769336, 19638.607227855453, 20950.580988380225, 22321.33036008918, 22345.329640110784, 22408.215712450066, 21281.0, 21714.0, 22931.312060638207, 23191.840407979576, 23264.41838954027, 6632.668366581664, 6892.965535172317, 6899.310068993111, 6915.204751453572, 6916.515843890929, 7255.8911616325795, 7583.469157159001, 7705.576193309365, 7720.266574675396, 7725.111612164616, 7332.2301158378405, 7628.275313845176, 7702.8844567331535, 7719.266669666401, 7793.025871766029, 7529.849403011964, 7704.345130663873, 7755.534667919943, 7807.024121984752, 7811.140774514799, 7599.924000760032, 7600.011998440167, 7600.7458769303, 7607.581583012932, 7615.733449329302, 26700.0, 27136.592951105747, 27770.8891644334, 27833.02584409536, 28199.71800282012, 2981.806182598124, 3000.5049166887657, 3001.0096668099477, 3040.5895204147378, 3062.8009179403266, 15383.692326153527, 15508.844911550967, 15562.844371556253, 16251.268692908847, 16870.83129168705, 2007.6988451732357, 2038.6840039793856, 2076.6677331626865, 2080.0847627044054, 2097.4022403614895, 166612.0, 171315.0, 172120.0, 175764.0, 176948.0, 16153.676926461405, 19855.702164467257, 20073.0, 20214.191432342835, 20695.965201740153, 25223.74776252214, 32255.032349029567, 32257.548410321593, 32636.510452343235, 32643.510347344807, 10405.167586593054, 10420.68737937863, 10485.895141048644, 10526.263161578616, 10759.3544387337, 8777.648894044167, 8794.824103517894, 8800.0, 8956.313106068154, 9000.63997440095, 7513.549187048795, 7795.064592248853, 9391.34260601765, 9509.0, 9560.952195239119, 3812.0, 3843.962130224824, 3877.2827027000094, 3889.727719059695, 3913.8434462621744, 804.6057431858347, 990.1187942730975, 997.8652881861001, 1007.9143272821746, 1019.6417824333082, 15877.52367428956, 16225.918870405809, 20810.47973800667, 20991.790082099287, 21425.357239282846, 861.8276344731079, 959.12240300126, 990.9950450247846, 992.6724181020246, 998.1366118307757, 10573.418461984666, 10787.56849726018, 10895.0, 10906.90930906903, 10922.29005114665, 753003.0, 755556.2222188854, 757786.0, 769099.0, 773583.0, 126757.0, 139378.6683464818, 165511.0, 167774.0, 177248.0, 1186.1696812231473, 1212.4483360071147, 1232.580922486359, 1242.726600147966, 1243.3720970909633, 17796.644067118843, 17979.370722024665, 18179.363722269656, 18535.629287414446, 18887.016863229164, 64643.67678161767, 65534.344656554735, 65710.0, 65714.0, 67253.66373168102, 74028.62985685037, 91140.08859911317, 92310.53844731001, 95019.0, 95423.0, 9198.586063627286, 10732.73168170802, 11202.439878005904, 11685.0, 11762.94118529402, 12701.301428421526, 12867.420966056363, 12889.419976101286, 12907.870921290667, 12915.289659068842, 11088.501017454395, 11260.88739112631, 11267.605633802777, 11577.536898524302, 11592.95663390299, 8648.0, 10365.792684146425, 10399.740006499898, 10411.583536658753, 10452.111570516287, 11176.329420234812, 11285.548578056785, 11316.660500185008, 11471.885281147082, 11583.884161158281, 11287.0, 11298.717532061764, 11318.886811132114, 11472.426378681199, 11619.95420412167, 9945.850812237966, 10823.084200567708, 11726.179167458196, 11873.0, 12019.519219231133], "value": 11714.631885330835, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1341.973390356377, 1367.8495365509796, 1553.1069634959895, 1575.4643421236776, 1588.0154304331313, 9244.214241789448, 9671.709848704533, 10860.891391086077, 10973.725656858582, 11265.15511336651, 11017.724556886082, 11143.554257829677, 11146.21976461646, 11163.720906977329, 11212.215144939837, 2298.9310320690365, 2332.7667233276707, 2342.824288178385, 2343.55472460232, 2346.3078391874437, 1961.892095934723, 2047.406252186864, 2292.1862738727677, 2366.96449553256, 2378.131981826638, 2316.444053427182, 2343.3907184132163, 2347.4014126397747, 2348.518553696497, 2356.1400088967575, 2294.541091781642, 2317.40906068953, 2317.640765681322, 2320.640300753386, 2333.824963127772, 2258.153192552793, 2291.2667946257206, 2293.20884294918, 2297.5060362022245, 2305.181660510528, 11154.274972126786, 11254.212205145643, 11279.32324060559, 11294.26587271833, 11314.490847911864, 5363.088274993261, 5554.0, 5564.137558678412, 5567.137093750475, 5572.080606699889, 3230.8223047732367, 3384.5938487381427, 3469.774464659789, 3476.774009689387, 3477.9826100869586, 1058.6453538064757, 1115.827046807746, 1133.0878642692628, 1148.465963327057, 1148.500402324983, 15041.097534148095, 15542.611434714223, 15564.92217538905, 15576.299066542033, 15592.298346574431, 4336.284513055373, 4404.1411924674385, 4457.153140903218, 4460.620847228022, 4469.217886869785, 11739.530418783153, 11741.589044383576, 11742.295462272292, 11751.942325190774, 11759.412029398496, 5445.646033007842, 5466.34403871538, 5472.616916815864, 5472.863178420492, 5482.424345443751, 4297.527272000078, 4901.362822833009, 4968.900621987612, 5023.6985780853265, 5080.923786143211, 3278.4426647469986, 3361.2941282330985, 3603.5855876574005, 3680.7975561343846, 3686.3180311641895, 899.964001439935, 901.8016036471965, 916.1205242966694, 925.4586067150658, 928.3455164109287, 877.0922095630991, 928.6471140966389, 938.887333519982, 966.1884017425397, 966.5747071288611, 6682.164729408824, 6696.397324240842, 6717.395434410926, 6895.482838787011, 6929.618870962146, 982.2044144243185, 1019.4342140112309, 1040.604570263295, 1092.2190633696946, 1121.8317252412203, 4205.158968206371, 4212.873613791591, 4227.640650544677, 4262.168877069003, 4331.263685173528, 13161.47354105825, 13754.793678094837, 13770.104943178661, 13776.724465510633, 13791.724165516835, 11737.765244695229, 12683.68290792719, 13170.407331670098, 13228.610995845298, 13597.932010339884, 9983.700488985342, 10235.334703244265, 10261.179105671528, 10266.538005789758, 10297.330673506198, 6018.638881667114, 6634.701438435379, 6691.230508491391, 6908.067410899464, 6950.6177160256675, 12131.696707582381, 12210.816837747618, 12324.691882703004, 12340.444679989243, 12376.07179461526, 11902.285862848257, 12271.079669025037, 12394.504219831466, 12549.686257843627, 12650.493980240688, 7659.00432943736, 9268.72193834186, 9453.858192127262, 9484.383515071499, 9489.240860730986, 10231.28381013322, 10350.223733219887, 10456.895431045592, 10474.319169253975, 10480.68557943263, 8675.66905964459, 8872.955635221782, 8980.685676001309, 9022.097790220925, 9195.0, 8832.425892317237, 9016.729498115066, 9042.50266235337, 9286.210672092677, 9323.906760932305, 7730.497517661334, 7889.250521200477, 8063.596820159086, 8068.193180681885, 8068.83862322762, 5974.641521508724, 5995.550333724901, 6078.635281883101, 6118.357572454828, 6201.10084037803, 5939.67331796738, 6009.589027441301, 6128.63228206309, 6188.628682279078, 6236.376362363909], "value": 5050.992673917076, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.86497940905997, 372.17377422122894, 378.9393697008476, 381.28318760729843, 384.4387194695743, 4986.152354099802, 4989.777504511395, 4993.800247990081, 5003.874903127424, 5014.423341315741, 4146.896327591804, 4670.976645116778, 4926.0, 4945.901081978358, 4946.406431228249, 4072.9389059164137, 4103.8563650272245, 4136.855210067648, 4149.0, 4157.979210103946, 3476.461148521983, 3959.227950549644, 4079.9592004080023, 4124.19554702802, 4171.395147703581, 4128.669706423479, 4135.710500264982, 4179.895502612444, 4182.351735481006, 4203.85286514972, 3437.081726831414, 3965.405189221611, 4016.578259282792, 4187.7277976931555, 4337.327714204272, 3655.9086022849374, 3799.373103437957, 4130.860545985043, 4161.667066634662, 4166.208420400114, 4853.767267057158, 4864.489228631013, 4875.513488121048, 4882.0, 4910.484399138075, 4656.231721765905, 4765.571098601143, 4990.476000020019, 5029.597632189452, 5062.012907483006, 4764.404449443819, 5051.722155281458, 5205.609579281531, 5279.287296215, 5315.415304316522, 3903.6877049835944, 4035.1438338364096, 4446.844360447369, 4675.4155730533685, 4729.722974796786, 4668.229742092584, 4767.713937163712, 5281.313429254211, 5611.8877622447735, 5674.971625141848, 4192.538820729717, 4235.639970602471, 4591.724496530219, 4620.752396852831, 4716.033213191287, 4079.326911059701, 4356.4336636237085, 4808.807647694053, 4869.70781753096, 4890.449339999055, 4269.765162916069, 4360.514506731196, 4597.172508948354, 4686.992296656167, 4974.403071631427, 5203.375594928632, 5211.5830733541225, 5262.71055091966, 5266.789328426897, 5307.328191619627, 1392.8578565576333, 1533.3516469795015, 1642.2363600925628, 1652.0996057148882, 1669.3072374964254, 1273.758085866273, 1298.6688394459352, 1357.803118547805, 1362.6902400431372, 1376.5457399058287, 4121.835126594962, 4275.508316543637, 4595.333676616939, 4710.481846996828, 4714.905701886011, 4270.48754149504, 4860.108235599812, 5197.272381866542, 5208.296879921162, 5316.255724198615, 4302.612764851116, 4601.562851529099, 4607.170709272363, 4712.740799256074, 4769.6661233714, 2339.8830058497006, 2477.9752202478103, 2534.4804315115725, 2782.7773778097703, 2846.558783388578, 4977.527134922177, 5397.541208997279, 5519.917201241985, 5584.692841893653, 5594.776208951596, 5273.0, 5411.0, 5460.262864513319, 5524.558035357162, 5542.8337149885565, 4537.0, 4746.5728084471875, 4817.67513933664, 4922.384701912261, 5456.890862182813, 4316.546762590008, 4729.527047295243, 4841.927371089366, 5252.133397989442, 5400.486953739389, 4604.700694454849, 4909.26361045846, 5054.562897229919, 5319.361676598833, 5380.542653874464, 4634.72191668501, 4717.599004084691, 5305.734713264244, 5545.750441230073, 5630.0, 4302.677299202635, 4458.910821783481, 4710.505396933273, 4743.0, 4744.644151688568, 4704.553067458586, 4873.780679869344, 5022.824201152942, 5045.86468044687, 5390.649607775482, 4620.214563524141, 5135.537801597874, 5195.558377537952, 5221.347331583552, 5221.467588219634, 4466.776661166994, 4615.607673347803, 5158.742062896915, 5176.896462070662, 5289.338832645919, 3622.1799917201697, 4069.7558146511305, 4206.768627725504, 4593.28804035375, 4764.809407623795, 4161.500619925629, 4603.493615702337, 4926.852194434173, 5052.418971818288, 5171.715960365271, 4635.7218566886095, 4739.170645137018, 5033.018561380496, 5100.438951715382, 5251.133562962068], "value": 4054.9316197062867, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [735.4667865797301, 779.5634444710962, 784.6076961519241, 857.7083791510887, 860.1355637584227, 15864.603384915383, 16406.425775097872, 16473.505794826146, 17889.284428622872, 18745.0, 16404.83595164048, 16628.50114496564, 16644.66710665786, 17051.82948170518, 17454.214560344783, 976.4873441443264, 997.5860018092519, 1000.7898341348325, 1001.1890368801294, 1002.4236064263041, 968.7529679931607, 994.7861209839883, 995.21378111292, 995.6764051683195, 997.4314640654829, 1040.3757745352802, 1046.691226088302, 1048.7849990751915, 1054.7995880782664, 1061.3950048472375, 1040.089921318846, 1040.432964034603, 1041.0942480042336, 1042.1246153231284, 1042.5777560088177, 776.4370831147429, 776.658270361045, 777.2771322669914, 777.8211011467338, 778.6145857800368, 16548.834511654848, 16779.244933978, 16974.066426346548, 17006.829931700773, 17312.48062558125, 14006.579802605938, 14161.78757318641, 14177.503787367496, 14262.92868535661, 14269.500567480192, 10003.594823454203, 11275.774484510264, 11871.465784039652, 11904.58333958316, 11962.1626486146, 3666.0, 3698.2973235085515, 3700.5929347771726, 3861.4593956846065, 3886.417037444378, 112102.0, 113570.99999999917, 113591.0, 114457.0, 115451.42274288574, 10485.423301718327, 10731.678049658523, 10762.73093172677, 10831.079358254636, 10906.291091079054, 22213.55572888533, 22244.666330005064, 22281.66577501339, 22290.777092229193, 22291.55416891653, 7692.42306826979, 7765.922340776633, 7781.688732450639, 7945.1657575953695, 8051.63767630458, 3898.3957486589625, 3905.628965248297, 3994.820233089518, 3995.320795464778, 4010.839566417311, 3424.9657503425146, 3496.8076755778675, 3531.5762108546623, 3539.59294681115, 3547.3614749345365, 608.2579253310954, 616.8951278282702, 619.1826788639044, 623.8409205652621, 624.2789578037374, 636.4113195294334, 667.9332066793281, 686.3452117474468, 698.3679769808332, 721.6932803558467, 9589.520523973773, 10391.896081039093, 10421.9478902605, 10434.843477347846, 10520.473976301155, 651.6774196772533, 687.388224480213, 693.299767235086, 708.727759329926, 736.6022347932056, 4855.854324370274, 4999.775010124553, 5005.499450055038, 5019.42276638184, 5024.598032157419, 85115.0, 85793.57103214443, 86853.0, 102088.0, 107513.46243268733, 49822.0, 68167.88022253502, 90068.5496572513, 92475.07524924664, 93736.53131734297, 61526.0, 73338.63330683313, 74525.62737186279, 78179.21820781719, 80170.0, 10763.677089687322, 10893.719088539105, 11095.389753563632, 11330.0, 11441.427928603704, 35581.64418355783, 47777.56667299986, 49236.0, 49327.50672493229, 49774.253386198514, 45597.54402455933, 62055.689721551105, 64231.0, 64669.67665161644, 64834.351656484716, 12553.623391298275, 12556.112094218564, 12702.618921432371, 12894.226346419246, 12935.935320323339, 14262.57212283633, 14404.063735857137, 14407.279636018367, 14630.780538291721, 14984.850151498782, 13376.276636923978, 13535.796963045364, 13827.58517244444, 13995.650108747363, 14008.649783755487, 12982.350882456029, 13712.65718357009, 13768.449262029406, 13776.0, 13832.792508112185, 13191.142575732547, 13207.537736179187, 13441.193528388329, 13445.731085378433, 13946.4421423146, 9320.72037838866, 9728.17310528613, 9848.045586323931, 9856.5564549594, 10092.394456332644, 10763.677089687322, 11118.888811111785, 11171.162162837658, 11324.660260192208, 11362.715932101764], "value": 7685.152260672113, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1465.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 118.0, 117.0, 117.0, 116.0, 117.0, 119.0, 164.0, 174.0, 117.0], "value": 126.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 111.0, 112.0, 143.0, 111.0, 108.0, 114.0, 110.0, 128.0, 108.0], "value": 119.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 184.0, 185.0, 186.0, 183.0, 183.0, 185.0, 185.0, 184.0], "value": 184.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 60.0, 59.0, 92.0, 66.0, 60.0, 58.0, 60.0, 75.0, 61.0], "value": 67.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 70.0, 87.0, 70.0, 89.0, 98.0, 115.0, 80.0, 68.0, 82.0], "value": 84.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 87.0, 89.0, 88.0, 89.0, 90.0, 89.0, 91.0, 90.0, 88.0], "value": 91.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 95.0, 97.0, 142.0, 151.0, 95.0, 95.0], "value": 105.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 114.0, 85.0, 83.0, 86.0, 86.0, 85.0, 86.0, 86.0, 84.0], "value": 88.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 59.0, 62.0, 64.0, 65.0, 67.0, 62.0, 63.0, 64.0, 65.0], "value": 63.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 69.0, 64.0, 62.0, 58.0, 59.0, 58.0, 57.0, 61.0], "value": 60.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 81.0, 83.0, 86.0, 84.0, 84.0, 85.0, 89.0, 90.0, 103.0], "value": 87.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 157.0, 143.0, 135.0, 143.0, 142.0, 130.0, 138.0, 151.0, 138.0], "value": 141.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 166.0, 165.0, 169.0, 165.0, 161.0, 135.0, 178.0, 223.0, 218.0], "value": 174.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 65.0, 67.0, 79.0, 80.0, 80.0, 81.0, 61.0, 63.0], "value": 70.4, "unit": "score"}]}]} 09:37:15 INFO - Return code: 0 09:37:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:37:16 INFO - # TBPL SUCCESS # 09:37:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:37:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:37:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:37:16 INFO - 2017-06-06 09:37:16.077 screenresolution[1654:9811] starting screenresolution argv=screenresolution get 09:37:16 INFO - 2017-06-06 09:37:16.080 screenresolution[1654:9811] Display 0: 1600x1200x32@60 09:37:16 INFO - 2017-06-06 09:37:16.086 screenresolution[1655:9814] starting screenresolution argv=screenresolution list 09:37:16 INFO - Available Modes on Display 0 09:37:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:37:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:37:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:37:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:37:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:37:16 INFO - Intel Iris: 09:37:16 INFO - Chipset Model: Intel Iris 09:37:16 INFO - Type: GPU 09:37:16 INFO - Bus: Built-In 09:37:16 INFO - VRAM (Dynamic, Max): 1536 MB 09:37:16 INFO - Vendor: Intel (0x8086) 09:37:16 INFO - Device ID: 0x0a2e 09:37:16 INFO - Revision ID: 0x0009 09:37:16 INFO - Displays: 09:37:16 INFO - Display: 09:37:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:37:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:37:16 INFO - Display Serial Number: 1600x1200 60 09:37:16 INFO - Main Display: Yes 09:37:16 INFO - Mirror: Off 09:37:16 INFO - Online: Yes 09:37:16 INFO - Rotation: Supported 09:37:16 INFO - Return code: 0 09:37:16 INFO - Running post-action listener: _resource_record_post_action 09:37:16 INFO - [mozharness: 2017-06-06 16:37:16.323258Z] Finished run-tests step (success) 09:37:16 INFO - Running post-run listener: _resource_record_post_run 09:37:16 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 38675456; Write bytes: 509658624; Read time: 243; Write time: 1566 09:37:16 INFO - TinderboxPrint: CPU usage
53.9% 09:37:16 INFO - TinderboxPrint: I/O read bytes / time
38,675,456 / 243 09:37:16 INFO - TinderboxPrint: I/O write bytes / time
509,658,624 / 1,566 09:37:16 INFO - TinderboxPrint: CPU idle
2,211.8 (46.1%) 09:37:16 INFO - TinderboxPrint: CPU system
960.8 (20.0%) 09:37:16 INFO - TinderboxPrint: CPU user
1,629.0 (33.9%) 09:37:16 INFO - TinderboxPrint: Swap in / out
247,410,688 / 0 09:37:16 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 204278272; Write bytes: 202594304; Read time: 15607; Write time: 433 09:37:16 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 36672512; Write bytes: 296271360; Read time: 224; Write time: 1098 09:37:16 INFO - Running post-run listener: _upload_blobber_files 09:37:16 INFO - Blob upload gear active. 09:37:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:37:16 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 09:37:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:37:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:37:17 INFO - (blobuploader) - INFO - Open directory for files ... 09:37:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:37:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:37:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:37:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:37:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:37:18 INFO - (blobuploader) - INFO - Done attempting. 09:37:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:37:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:37:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:37:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:37:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:37:19 INFO - (blobuploader) - INFO - Done attempting. 09:37:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:37:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:37:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:37:20 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:37:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:37:20 INFO - (blobuploader) - INFO - Done attempting. 09:37:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:37:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:37:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:37:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:37:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:37:22 INFO - (blobuploader) - INFO - Done attempting. 09:37:22 INFO - (blobuploader) - INFO - Iteration through files over. 09:37:22 INFO - Return code: 0 09:37:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:37:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:37:22 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/02aa846969660afd63a2ac50a06493acc2beee0d77056dafcae26b63f571a64899797870712d8f3c8530d542a7c88a8bd8b6a7a82c94e0eb14539bef5f7c43b5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/96fc9322f24ba3e14238aa7c25bf5b1a15e7a66024257faf5150d095e3e95074d1b06df9d548f6cf4a65f8336c1ff96f2d438f38900d85fd994c2a3eb8d3c09a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/abbaab0ae6bef58f831821f21930404749d07fb29d303aeef6d51d641d53d88be5bd865c6ba550c41902547658814010497b555f29aed78fba0a59e110d3b747", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d0ec8a749200ec2a00b83c01553d448773e5a0d9855280fbb32ae078ed4643e70c2c5230057a6760ed8c8588d4fc4dac930bb531d58fde82352aa79bd2dc47f2"} 09:37:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:37:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:37:22 INFO - Contents: 09:37:22 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/02aa846969660afd63a2ac50a06493acc2beee0d77056dafcae26b63f571a64899797870712d8f3c8530d542a7c88a8bd8b6a7a82c94e0eb14539bef5f7c43b5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/96fc9322f24ba3e14238aa7c25bf5b1a15e7a66024257faf5150d095e3e95074d1b06df9d548f6cf4a65f8336c1ff96f2d438f38900d85fd994c2a3eb8d3c09a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/abbaab0ae6bef58f831821f21930404749d07fb29d303aeef6d51d641d53d88be5bd865c6ba550c41902547658814010497b555f29aed78fba0a59e110d3b747", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d0ec8a749200ec2a00b83c01553d448773e5a0d9855280fbb32ae078ed4643e70c2c5230057a6760ed8c8588d4fc4dac930bb531d58fde82352aa79bd2dc47f2"} 09:37:22 INFO - Running post-run listener: copy_logs_to_upload_dir 09:37:22 INFO - Copying logs to upload dir... 09:37:22 INFO - mkdir: /builds/slave/test/build/upload/logs 09:37:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.355669 ========= master_lag: 0.87 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-06-06 09:37:22.928345) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-06 09:37:22.935823) ========= 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.07Po6i8QBy/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.xUFQYrImG5/Listeners TMPDIR=/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/02aa846969660afd63a2ac50a06493acc2beee0d77056dafcae26b63f571a64899797870712d8f3c8530d542a7c88a8bd8b6a7a82c94e0eb14539bef5f7c43b5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/96fc9322f24ba3e14238aa7c25bf5b1a15e7a66024257faf5150d095e3e95074d1b06df9d548f6cf4a65f8336c1ff96f2d438f38900d85fd994c2a3eb8d3c09a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/abbaab0ae6bef58f831821f21930404749d07fb29d303aeef6d51d641d53d88be5bd865c6ba550c41902547658814010497b555f29aed78fba0a59e110d3b747", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d0ec8a749200ec2a00b83c01553d448773e5a0d9855280fbb32ae078ed4643e70c2c5230057a6760ed8c8588d4fc4dac930bb531d58fde82352aa79bd2dc47f2"} build_url:https://queue.taskcluster.net/v1/task/Dg1BZSsvQW2LgvqdjVZQFw/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011056 build_url: 'https://queue.taskcluster.net/v1/task/Dg1BZSsvQW2LgvqdjVZQFw/artifacts/public/build/firefox-54.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/02aa846969660afd63a2ac50a06493acc2beee0d77056dafcae26b63f571a64899797870712d8f3c8530d542a7c88a8bd8b6a7a82c94e0eb14539bef5f7c43b5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/96fc9322f24ba3e14238aa7c25bf5b1a15e7a66024257faf5150d095e3e95074d1b06df9d548f6cf4a65f8336c1ff96f2d438f38900d85fd994c2a3eb8d3c09a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/abbaab0ae6bef58f831821f21930404749d07fb29d303aeef6d51d641d53d88be5bd865c6ba550c41902547658814010497b555f29aed78fba0a59e110d3b747", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d0ec8a749200ec2a00b83c01553d448773e5a0d9855280fbb32ae078ed4643e70c2c5230057a6760ed8c8588d4fc4dac930bb531d58fde82352aa79bd2dc47f2"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-06 09:37:22.979003) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 09:37:22.979327) ========= 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.07Po6i8QBy/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.xUFQYrImG5/Listeners TMPDIR=/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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.005022 ========= master_lag: 0.37 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 09:37:23.351915) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-06 09:37:23.352231) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-06 09:37:23.352645) ========= ========= Total master_lag: 1.34 =========