builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs slave: t-yosemite-r7-0094 starttime: 1496276168.59 results: success (0) buildid: 20170531154528 builduid: 0bbfe5e8f79545b5a6eb751a07bec297 revision: 10cfa295a989879affa59adeb70f7e0847d19e7f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-31 17:16:08.589378) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-31 17:16:08.589817) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-31 17:16:08.590103) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-31 17:16:08.801022) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 17:16:08.801347) ========= 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.NBCwwGkSpL/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.2zgtfVtzic/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-31 17:16:08-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 53.0M=0s 2017-05-31 17:16:09 (53.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.493884 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 17:16:09.327936) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 17:16:09.328226) ========= 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.NBCwwGkSpL/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.2zgtfVtzic/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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.029725 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 17:16:09.379142) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-31 17:16:09.379521) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 10cfa295a989879affa59adeb70f7e0847d19e7f --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 10cfa295a989879affa59adeb70f7e0847d19e7f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NBCwwGkSpL/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.2zgtfVtzic/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-31 17:16:09,475 truncating revision to first 12 chars 2017-05-31 17:16:09,475 Setting DEBUG logging. 2017-05-31 17:16:09,475 attempt 1/10 2017-05-31 17:16:09,475 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/10cfa295a989?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-31 17:16:10,470 unpacking tar archive at: mozilla-beta-10cfa295a989/testing/mozharness/ program finished with exit code 0 elapsedTime=1.273619 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-31 17:16:10.675367) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-31 17:16:10.675659) ========= script_repo_revision: 10cfa295a989879affa59adeb70f7e0847d19e7f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-31 17:16:10.676033) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-31 17:16:10.676294) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-31 17:16:10.695074) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2017-05-31 17:16:10.695442) ========= /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.NBCwwGkSpL/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.2zgtfVtzic/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 17:16:10 INFO - MultiFileLogger online at 20170531 17:16:10 in /builds/slave/test 17:16:10 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 17:16:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:16:10 INFO - {'append_to_log': False, 17:16:10 INFO - 'base_work_dir': '/builds/slave/test', 17:16:10 INFO - 'blob_upload_branch': 'Mozilla-Beta', 17:16:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:16:10 INFO - 'branch': 'Mozilla-Beta', 17:16:10 INFO - 'buildbot_json_path': 'buildprops.json', 17:16:10 INFO - 'config_files': ('talos/mac_config.py',), 17:16:10 INFO - 'default_actions': ('clobber', 17:16:10 INFO - 'read-buildbot-config', 17:16:10 INFO - 'download-and-extract', 17:16:10 INFO - 'populate-webroot', 17:16:10 INFO - 'create-virtualenv', 17:16:10 INFO - 'install', 17:16:10 INFO - 'run-tests'), 17:16:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:16:10 INFO - 'download_minidump_stackwalk': True, 17:16:10 INFO - 'download_symbols': 'ondemand', 17:16:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:16:10 INFO - 'tooltool.py': '/tools/tooltool.py', 17:16:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:16:10 INFO - '/tools/misc-python/virtualenv.py')}, 17:16:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:16:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:16:10 INFO - 'gecko_profile': False, 17:16:10 INFO - 'gecko_profile_interval': 0, 17:16:10 INFO - 'installer_path': 'installer.exe', 17:16:10 INFO - 'log_level': 'info', 17:16:10 INFO - 'log_name': 'talos', 17:16:10 INFO - 'log_to_console': True, 17:16:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:16:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:16:10 INFO - 'opt_config_files': (), 17:16:10 INFO - 'pip_index': False, 17:16:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:16:10 INFO - 'cmd': ('bash', 17:16:10 INFO - '-c', 17:16:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:16:10 INFO - 'enabled': True, 17:16:10 INFO - 'halt_on_failure': False, 17:16:10 INFO - 'name': 'check_screen_resolution'},), 17:16:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:16:10 INFO - 'cmd': ('bash', 17:16:10 INFO - '-c', 17:16:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:16:10 INFO - 'enabled': True, 17:16:10 INFO - 'halt_on_failure': False, 17:16:10 INFO - 'name': 'check_screen_resolution'},), 17:16:10 INFO - 'run_cmd_checks_enabled': True, 17:16:10 INFO - 'suite': 'dromaeojs', 17:16:10 INFO - 'system_bits': '32', 17:16:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:16:10 INFO - 'title': 't-yosemite-r7-0094', 17:16:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:16:10 INFO - 'use_talos_json': True, 17:16:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:16:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:16:10 INFO - 'work_dir': 'build'} 17:16:10 INFO - [mozharness: 2017-06-01 00:16:10.904352Z] Running clobber step. 17:16:10 INFO - Running pre-action listener: _resource_record_pre_action 17:16:10 INFO - Running main action method: clobber 17:16:10 INFO - rmtree: /builds/slave/test/build 17:16:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:16:13 INFO - Running post-action listener: _resource_record_post_action 17:16:13 INFO - [mozharness: 2017-06-01 00:16:13.385556Z] Finished clobber step (success) 17:16:13 INFO - [mozharness: 2017-06-01 00:16:13.385687Z] Running read-buildbot-config step. 17:16:13 INFO - Running pre-action listener: _resource_record_pre_action 17:16:13 INFO - Running main action method: read_buildbot_config 17:16:13 INFO - Using buildbot properties: 17:16:13 INFO - { 17:16:13 INFO - "project": "", 17:16:13 INFO - "product": "firefox", 17:16:13 INFO - "script_repo_revision": "production", 17:16:13 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs", 17:16:13 INFO - "repository": "", 17:16:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs", 17:16:13 INFO - "buildid": "20170531154528", 17:16:13 INFO - "pgo_build": "False", 17:16:13 INFO - "basedir": "/builds/slave/test", 17:16:13 INFO - "buildnumber": 0, 17:16:13 INFO - "slavename": "t-yosemite-r7-0094", 17:16:13 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 17:16:13 INFO - "platform": "yosemite_r7_devedition", 17:16:13 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 17:16:13 INFO - "branch": "mozilla-beta", 17:16:13 INFO - "repo_path": "releases/mozilla-beta", 17:16:13 INFO - "revision": "10cfa295a989879affa59adeb70f7e0847d19e7f", 17:16:13 INFO - "stage_platform": "macosx64-devedition", 17:16:13 INFO - "builduid": "0bbfe5e8f79545b5a6eb751a07bec297", 17:16:13 INFO - "slavebuilddir": "test" 17:16:13 INFO - } 17:16:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/RtuXsD5lQZyt47PxvEoHYg/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 17:16:13 INFO - Running post-action listener: _resource_record_post_action 17:16:13 INFO - [mozharness: 2017-06-01 00:16:13.389120Z] Finished read-buildbot-config step (success) 17:16:13 INFO - [mozharness: 2017-06-01 00:16:13.389252Z] Running download-and-extract step. 17:16:13 INFO - Running pre-action listener: _resource_record_pre_action 17:16:13 INFO - Running main action method: download_and_extract 17:16:13 INFO - mkdir: /builds/slave/test/build/tests 17:16:13 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')]} 17:16:13 INFO - trying https://queue.taskcluster.net/v1/task/RtuXsD5lQZyt47PxvEoHYg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 17:16:13 INFO - Downloading https://queue.taskcluster.net/v1/task/RtuXsD5lQZyt47PxvEoHYg/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 17:16:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RtuXsD5lQZyt47PxvEoHYg/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 17:16:14 INFO - Downloaded 1287 bytes. 17:16:14 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 17:16:14 INFO - Using the following test package requirements: 17:16:14 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:16:14 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 17:16:14 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 17:16:14 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:16:14 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 17:16:14 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:16:14 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 17:16:14 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 17:16:14 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:16:14 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 17:16:14 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 17:16:14 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:16:14 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 17:16:14 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:16:14 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 17:16:14 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:16:14 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 17:16:14 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:16:14 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 17:16:14 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 17:16:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RtuXsD5lQZyt47PxvEoHYg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 17:16:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RtuXsD5lQZyt47PxvEoHYg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 17:16:14 INFO - Fetch https://queue.taskcluster.net/v1/task/RtuXsD5lQZyt47PxvEoHYg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 17:16:23 INFO - Expected file size: 51963966 17:16:23 INFO - Obtained file size: 51963966 17:16:39 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 17:16:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RtuXsD5lQZyt47PxvEoHYg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 17:16:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RtuXsD5lQZyt47PxvEoHYg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 17:16:39 INFO - Fetch https://queue.taskcluster.net/v1/task/RtuXsD5lQZyt47PxvEoHYg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 17:16:40 INFO - Expected file size: 51963966 17:16:40 INFO - Obtained file size: 51963966 17:16:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RtuXsD5lQZyt47PxvEoHYg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 17:16:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RtuXsD5lQZyt47PxvEoHYg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 17:16:55 INFO - Fetch https://queue.taskcluster.net/v1/task/RtuXsD5lQZyt47PxvEoHYg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 17:16:57 INFO - Expected file size: 14404461 17:16:57 INFO - Obtained file size: 14404461 17: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')]} 17:16:57 INFO - trying https://queue.taskcluster.net/v1/task/RtuXsD5lQZyt47PxvEoHYg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 17:16:57 INFO - Downloading https://queue.taskcluster.net/v1/task/RtuXsD5lQZyt47PxvEoHYg/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 17:16:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RtuXsD5lQZyt47PxvEoHYg/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 17:16:59 INFO - Downloaded 74891661 bytes. 17:16:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RtuXsD5lQZyt47PxvEoHYg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 17:16:59 INFO - mkdir: /builds/slave/test/properties 17:16:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:16:59 INFO - Writing to file /builds/slave/test/properties/build_url 17:16:59 INFO - Contents: 17:16:59 INFO - build_url:https://queue.taskcluster.net/v1/task/RtuXsD5lQZyt47PxvEoHYg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 17:17:00 INFO - Running post-action listener: _resource_record_post_action 17:17:00 INFO - Running post-action listener: set_extra_try_arguments 17:17:00 INFO - [mozharness: 2017-06-01 00:17:00.785996Z] Finished download-and-extract step (success) 17:17:00 INFO - [mozharness: 2017-06-01 00:17:00.786124Z] Running populate-webroot step. 17:17:00 INFO - Running pre-action listener: _resource_record_pre_action 17:17:00 INFO - Running main action method: populate_webroot 17:17:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:17:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:17:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:17:00 INFO - '--tppagecycles', 17:17:00 INFO - '7'], 17:17:00 INFO - 'tests': ['tsvgm']}}, 17:17:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:17:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:17:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:17:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:17:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:17:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:17:00 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:17:00 INFO - 'talos_options': ['--disable-e10s'], 17:17:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:17:00 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:17:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:17:00 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:17:00 INFO - 'talos_options': ['--disable-e10s'], 17:17:00 INFO - 'tests': ['damp', 'tps']}, 17:17:00 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:17:00 INFO - 'tests': ['damp', 'tps']}, 17:17:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:17:00 INFO - 'tests': ['dromaeo_dom']}, 17:17:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:17:00 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:17:00 INFO - 'tests': ['basic_compositor_video']}, 17:17:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:17:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:17:00 INFO - 'tests': ['a11yr', 17:17:00 INFO - 'ts_paint', 17:17:00 INFO - 'tpaint', 17:17:00 INFO - 'sessionrestore', 17:17:00 INFO - 'sessionrestore_no_auto_restore', 17:17:00 INFO - 'tabpaint']}, 17:17:00 INFO - 'other-e10s': {'tests': ['a11yr', 17:17:00 INFO - 'ts_paint', 17:17:00 INFO - 'tpaint', 17:17:00 INFO - 'sessionrestore', 17:17:00 INFO - 'sessionrestore_no_auto_restore', 17:17:00 INFO - 'tabpaint']}, 17:17:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:17:00 INFO - 'ts_paint', 17:17:00 INFO - 'tpaint', 17:17:00 INFO - 'sessionrestore', 17:17:00 INFO - 'sessionrestore_no_auto_restore', 17:17:00 INFO - 'tabpaint']}, 17:17:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:17:00 INFO - 'ts_paint', 17:17:00 INFO - 'tpaint', 17:17:00 INFO - 'sessionrestore', 17:17:00 INFO - 'sessionrestore_no_auto_restore', 17:17:00 INFO - 'tabpaint']}, 17:17:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:17:00 INFO - 'tests': ['a11yr', 17:17:00 INFO - 'ts_paint', 17:17:00 INFO - 'tpaint', 17:17:00 INFO - 'sessionrestore', 17:17:00 INFO - 'sessionrestore_no_auto_restore', 17:17:00 INFO - 'tabpaint']}, 17:17:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:17:00 INFO - 'tests': ['a11yr', 17:17:00 INFO - 'ts_paint', 17:17:00 INFO - 'tpaint', 17:17:00 INFO - 'sessionrestore', 17:17:00 INFO - 'sessionrestore_no_auto_restore', 17:17:00 INFO - 'tabpaint']}, 17:17:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:17:00 INFO - 'tests': ['tsvgx', 17:17:00 INFO - 'tsvgr_opacity', 17:17:00 INFO - 'tart', 17:17:00 INFO - 'tscrollx', 17:17:00 INFO - 'cart', 17:17:00 INFO - 'tsvg_static']}, 17:17:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:17:00 INFO - 'tsvgr_opacity', 17:17:00 INFO - 'tart', 17:17:00 INFO - 'tscrollx', 17:17:00 INFO - 'cart', 17:17:00 INFO - 'tsvg_static']}, 17:17:00 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:17:00 INFO - 'talos_options': ['--disable-e10s'], 17:17:00 INFO - 'tests': ['tp5o']}, 17:17:00 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:17:00 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:17:00 INFO - 'talos_options': ['--disable-e10s', 17:17:00 INFO - '--xperf_path', 17:17:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:17:00 INFO - 'tests': ['tp5n']}, 17:17:00 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:17:00 INFO - 'talos_options': ['--xperf_path', 17:17:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:17:00 INFO - 'tests': ['tp5n']}}, 17:17:00 INFO - 'talos.zip': {'path': '', 17:17:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:17:00 INFO - Running post-action listener: _resource_record_post_action 17:17:00 INFO - [mozharness: 2017-06-01 00:17:00.804581Z] Finished populate-webroot step (success) 17:17:00 INFO - [mozharness: 2017-06-01 00:17:00.804690Z] Running create-virtualenv step. 17:17:00 INFO - Running pre-action listener: _resource_record_pre_action 17:17:00 INFO - Running main action method: create_virtualenv 17:17:00 INFO - Creating virtualenv /builds/slave/test/build/venv 17:17:00 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 17:17:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:17:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:17:00 INFO - Using real prefix '/tools/python27' 17:17:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:17:01 INFO - Installing distribute.............................................................................................................................................................................................done. 17:17:04 INFO - Installing pip.................done. 17:17:04 INFO - Return code: 0 17:17:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:17:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:17:04 INFO - Reading from file tmpfile_stdout 17:17:04 INFO - Output received: 17:17:04 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) 17:17:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:17:04 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')]} 17:17:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:04 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')]} 17:17:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:04 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 0x10c8adb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8b0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82704b08c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c68bd30>, '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 0x10c5b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c680be8>, '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.NBCwwGkSpL/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.2zgtfVtzic/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 17:17:04 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 17:17:04 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 17:17:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NBCwwGkSpL/Render', 17:17:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:04 INFO - 'HOME': '/Users/cltbld', 17:17:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:04 INFO - 'LOGNAME': 'cltbld', 17:17:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:04 INFO - 'MOZ_NO_REMOTE': '1', 17:17:04 INFO - 'NO_EM_RESTART': '1', 17:17:04 INFO - 'PAGER': '/bin/cat', 17:17:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:04 INFO - 'PWD': '/builds/slave/test', 17:17:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:04 INFO - 'SHELL': '/bin/bash', 17:17:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2zgtfVtzic/Listeners', 17:17:04 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 17:17:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:04 INFO - 'USER': 'cltbld', 17:17:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:04 INFO - 'XPC_FLAGS': '0x0', 17:17:04 INFO - 'XPC_SERVICE_NAME': '0', 17:17:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:05 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 17:17:05 INFO - Cleaning up... 17:17:05 INFO - Return code: 0 17:17:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:17:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:17:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:17:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:05 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 0x10c8adb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8b0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82704b08c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c68bd30>, '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 0x10c5b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c680be8>, '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.NBCwwGkSpL/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.2zgtfVtzic/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 17:17:05 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 17:17:05 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 17:17:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NBCwwGkSpL/Render', 17:17:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:05 INFO - 'HOME': '/Users/cltbld', 17:17:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:05 INFO - 'LOGNAME': 'cltbld', 17:17:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:05 INFO - 'MOZ_NO_REMOTE': '1', 17:17:05 INFO - 'NO_EM_RESTART': '1', 17:17:05 INFO - 'PAGER': '/bin/cat', 17:17:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:05 INFO - 'PWD': '/builds/slave/test', 17:17:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:05 INFO - 'SHELL': '/bin/bash', 17:17:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2zgtfVtzic/Listeners', 17:17:05 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 17:17:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:05 INFO - 'USER': 'cltbld', 17:17:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:05 INFO - 'XPC_FLAGS': '0x0', 17:17:05 INFO - 'XPC_SERVICE_NAME': '0', 17:17:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:05 INFO - Downloading/unpacking psutil>=3.1.1 17: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 17: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 17: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 17: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 17:17:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:17:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:17:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:17:07 INFO - Installing collected packages: psutil 17:17:07 INFO - Running setup.py install for psutil 17:17:07 INFO - building 'psutil._psutil_osx' extension 17:17:07 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 17:17:08 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 17:17:08 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 17:17:08 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 17:17:08 INFO - building 'psutil._psutil_posix' extension 17:17:08 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 17:17:08 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:17:08 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:17:08 INFO - ^ 17:17:08 INFO - 1 warning generated. 17:17:08 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 17:17:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:17:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:17:08 INFO - Successfully installed psutil 17:17:08 INFO - Cleaning up... 17:17:08 INFO - Return code: 0 17:17:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:17:08 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')]} 17:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:08 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')]} 17:17:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:08 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 0x10c8adb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8b0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82704b08c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c68bd30>, '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 0x10c5b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c680be8>, '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.NBCwwGkSpL/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.2zgtfVtzic/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 17:17:08 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 17:17:08 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 17:17:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NBCwwGkSpL/Render', 17:17:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:08 INFO - 'HOME': '/Users/cltbld', 17:17:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:08 INFO - 'LOGNAME': 'cltbld', 17:17:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:08 INFO - 'MOZ_NO_REMOTE': '1', 17:17:08 INFO - 'NO_EM_RESTART': '1', 17:17:08 INFO - 'PAGER': '/bin/cat', 17:17:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:08 INFO - 'PWD': '/builds/slave/test', 17:17:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:08 INFO - 'SHELL': '/bin/bash', 17:17:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2zgtfVtzic/Listeners', 17:17:08 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 17:17:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:08 INFO - 'USER': 'cltbld', 17:17:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:08 INFO - 'XPC_FLAGS': '0x0', 17:17:08 INFO - 'XPC_SERVICE_NAME': '0', 17:17:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:17:09 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 17:17:09 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 17:17:09 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 17:17:09 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 17:17:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:17:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:17:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:17:10 INFO - Installing collected packages: mozsystemmonitor 17:17:10 INFO - Running setup.py install for mozsystemmonitor 17:17:10 INFO - Successfully installed mozsystemmonitor 17:17:10 INFO - Cleaning up... 17:17:11 INFO - Return code: 0 17:17:11 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17: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')]} 17:17:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17: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')]} 17:17:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c8adb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8b0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82704b08c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c68bd30>, '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 0x10c5b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c680be8>, '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.NBCwwGkSpL/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.2zgtfVtzic/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 17:17:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 17:17:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 17:17:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NBCwwGkSpL/Render', 17:17:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:11 INFO - 'HOME': '/Users/cltbld', 17:17:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:11 INFO - 'LOGNAME': 'cltbld', 17:17:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:11 INFO - 'MOZ_NO_REMOTE': '1', 17:17:11 INFO - 'NO_EM_RESTART': '1', 17:17:11 INFO - 'PAGER': '/bin/cat', 17:17:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:11 INFO - 'PWD': '/builds/slave/test', 17:17:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:11 INFO - 'SHELL': '/bin/bash', 17:17:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2zgtfVtzic/Listeners', 17:17:11 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 17:17:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:11 INFO - 'USER': 'cltbld', 17:17:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:11 INFO - 'XPC_FLAGS': '0x0', 17:17:11 INFO - 'XPC_SERVICE_NAME': '0', 17:17:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:11 INFO - Downloading/unpacking jsonschema==2.5.1 17:17:11 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 17:17:11 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 17:17:11 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 17:17:11 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 17:17:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:17:12 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:17:12 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 17:17:12 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 17:17:12 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 17:17:12 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 17:17:12 INFO - Downloading functools32-3.2.3-2.tar.gz 17:17:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:17:13 INFO - warning: no files found matching '*.txt' 17:17:13 INFO - no previously-included directories found matching 'build' 17:17:13 INFO - no previously-included directories found matching 'dist' 17:17:13 INFO - no previously-included directories found matching '.git*' 17:17:13 INFO - Installing collected packages: jsonschema, functools32 17:17:13 INFO - Running setup.py install for functools32 17:17:13 INFO - warning: no files found matching '*.txt' 17:17:13 INFO - no previously-included directories found matching 'build' 17:17:13 INFO - no previously-included directories found matching 'dist' 17:17:13 INFO - no previously-included directories found matching '.git*' 17:17:13 INFO - Successfully installed jsonschema functools32 17:17:13 INFO - Cleaning up... 17:17:13 INFO - Return code: 0 17:17:13 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:17:13 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')]} 17:17:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:13 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')]} 17:17:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:13 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 0x10c8adb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8b0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82704b08c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c68bd30>, '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 0x10c5b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c680be8>, '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.NBCwwGkSpL/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.2zgtfVtzic/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 17:17:13 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 17:17:13 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 17:17:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NBCwwGkSpL/Render', 17:17:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:13 INFO - 'HOME': '/Users/cltbld', 17:17:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:13 INFO - 'LOGNAME': 'cltbld', 17:17:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:13 INFO - 'MOZ_NO_REMOTE': '1', 17:17:13 INFO - 'NO_EM_RESTART': '1', 17:17:13 INFO - 'PAGER': '/bin/cat', 17:17:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:13 INFO - 'PWD': '/builds/slave/test', 17:17:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:13 INFO - 'SHELL': '/bin/bash', 17:17:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2zgtfVtzic/Listeners', 17:17:13 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 17:17:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:13 INFO - 'USER': 'cltbld', 17:17:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:13 INFO - 'XPC_FLAGS': '0x0', 17:17:13 INFO - 'XPC_SERVICE_NAME': '0', 17:17:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:13 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:17:13 INFO - Cleaning up... 17:17:13 INFO - Return code: 0 17:17:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:17:13 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')]} 17:17:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:13 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')]} 17:17:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:13 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 0x10c8adb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8b0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82704b08c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c68bd30>, '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 0x10c5b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c680be8>, '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.NBCwwGkSpL/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.2zgtfVtzic/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 17:17:13 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 17:17:13 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 17:17:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NBCwwGkSpL/Render', 17:17:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:13 INFO - 'HOME': '/Users/cltbld', 17:17:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:13 INFO - 'LOGNAME': 'cltbld', 17:17:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:13 INFO - 'MOZ_NO_REMOTE': '1', 17:17:13 INFO - 'NO_EM_RESTART': '1', 17:17:13 INFO - 'PAGER': '/bin/cat', 17:17:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:13 INFO - 'PWD': '/builds/slave/test', 17:17:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:13 INFO - 'SHELL': '/bin/bash', 17:17:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2zgtfVtzic/Listeners', 17:17:13 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 17:17:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:13 INFO - 'USER': 'cltbld', 17:17:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:13 INFO - 'XPC_FLAGS': '0x0', 17:17:13 INFO - 'XPC_SERVICE_NAME': '0', 17:17:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:13 INFO - Downloading/unpacking blobuploader==1.2.4 17: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 17: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 17: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 17: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 17:17:15 INFO - Downloading blobuploader-1.2.4.tar.gz 17:17:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:17:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:17:15 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 17:17:15 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 17:17:15 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 17:17:15 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 17:17:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:17:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:17:15 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 17:17:15 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 17:17:15 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 17:17:15 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 17:17:16 INFO - Downloading docopt-0.6.1.tar.gz 17:17:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:17:16 INFO - Installing collected packages: blobuploader, requests, docopt 17:17:16 INFO - Running setup.py install for blobuploader 17:17:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:17:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:17:16 INFO - Running setup.py install for requests 17:17:16 INFO - Running setup.py install for docopt 17:17:16 INFO - Successfully installed blobuploader requests docopt 17:17:16 INFO - Cleaning up... 17:17:16 INFO - Return code: 0 17:17:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:17:16 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')]} 17:17:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:16 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')]} 17:17:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:16 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 0x10c8adb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8b0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82704b08c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c68bd30>, '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 0x10c5b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c680be8>, '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.NBCwwGkSpL/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.2zgtfVtzic/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 17:17:16 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 17:17:16 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 17:17:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NBCwwGkSpL/Render', 17:17:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:16 INFO - 'HOME': '/Users/cltbld', 17:17:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:16 INFO - 'LOGNAME': 'cltbld', 17:17:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:16 INFO - 'MOZ_NO_REMOTE': '1', 17:17:16 INFO - 'NO_EM_RESTART': '1', 17:17:16 INFO - 'PAGER': '/bin/cat', 17:17:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:16 INFO - 'PWD': '/builds/slave/test', 17:17:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:16 INFO - 'SHELL': '/bin/bash', 17:17:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2zgtfVtzic/Listeners', 17:17:16 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 17:17:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:16 INFO - 'USER': 'cltbld', 17:17:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:16 INFO - 'XPC_FLAGS': '0x0', 17:17:16 INFO - 'XPC_SERVICE_NAME': '0', 17:17:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:17:17 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-Dv1eXH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:17:17 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-nVhmOG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:17:17 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-gGRykM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:17:17 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-tyWyf_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:17:17 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-GcR_M8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:17:17 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-s1ACcX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:17:17 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-Xuobtl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:17:17 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-UWwnpo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:17:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:17:18 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-PDFrAu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:17:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:17:18 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-5vljk3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:17:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:17:18 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-8RgPHL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:17:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:17:18 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-cPMEot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:17:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:17:18 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-Yp2LAD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:17:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:17:18 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-T2JRho-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:17:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:17:18 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-stzcxP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:17:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:17:18 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-S6KTD3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:17:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:17:18 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-H3ncrT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:17:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:17:18 INFO - Running setup.py install for manifestparser 17:17:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:17:19 INFO - Running setup.py install for mozcrash 17:17:19 INFO - Running setup.py install for mozdebug 17:17:19 INFO - Running setup.py install for mozdevice 17:17:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:17:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:17:19 INFO - Running setup.py install for mozfile 17:17:19 INFO - Running setup.py install for mozhttpd 17:17:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:17:19 INFO - Running setup.py install for mozinfo 17:17:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:17:20 INFO - Running setup.py install for mozInstall 17:17:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:17:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:17:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:17:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:17:20 INFO - Running setup.py install for mozleak 17:17:20 INFO - Running setup.py install for mozlog 17:17:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:17:20 INFO - Running setup.py install for moznetwork 17:17:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:17:20 INFO - Running setup.py install for mozprocess 17:17:20 INFO - Running setup.py install for mozprofile 17:17:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:17:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:17:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:17:20 INFO - Running setup.py install for mozrunner 17:17:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:17:21 INFO - Running setup.py install for mozscreenshot 17:17:21 INFO - Running setup.py install for moztest 17:17:21 INFO - Running setup.py install for mozversion 17:17:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:17:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:17:21 INFO - Cleaning up... 17:17:21 INFO - Return code: 0 17:17:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:17:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:17:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:17:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:21 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 0x10c8adb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8b0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82704b08c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c68bd30>, '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 0x10c5b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c680be8>, '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.NBCwwGkSpL/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.2zgtfVtzic/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 17:17:21 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 17:17:21 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 17:17:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NBCwwGkSpL/Render', 17:17:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:21 INFO - 'HOME': '/Users/cltbld', 17:17:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:21 INFO - 'LOGNAME': 'cltbld', 17:17:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:21 INFO - 'MOZ_NO_REMOTE': '1', 17:17:21 INFO - 'NO_EM_RESTART': '1', 17:17:21 INFO - 'PAGER': '/bin/cat', 17:17:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:21 INFO - 'PWD': '/builds/slave/test', 17:17:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:21 INFO - 'SHELL': '/bin/bash', 17:17:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2zgtfVtzic/Listeners', 17:17:21 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 17:17:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:21 INFO - 'USER': 'cltbld', 17:17:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:21 INFO - 'XPC_FLAGS': '0x0', 17:17:21 INFO - 'XPC_SERVICE_NAME': '0', 17:17:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:17:21 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-mojPfn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:17:21 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)) 17:17:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:17:21 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-O8bgJd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:17:22 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)) 17:17:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:17:22 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-jXs7Ad-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:17:22 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)) 17:17:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:17:22 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-pfxZ_0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:17:22 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)) 17:17:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:17:22 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-n36f2i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:17:22 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)) 17:17:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:17:22 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-n26zKD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:17:22 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)) 17:17:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:17:22 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-e2UyVE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:17:22 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)) 17:17:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:17:22 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-N3IPUP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:17:22 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)) 17:17:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:17:22 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-bbCyG4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:17:22 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)) 17:17:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:17:22 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-3MuHrO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:17:22 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)) 17:17:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:17:22 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-vLTaTg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:17:23 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)) 17:17:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:17:23 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-LikTzU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:17:23 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)) 17:17:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:17:23 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-cmy9Hw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:17:23 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)) 17:17:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:17:23 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-01fltV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:17:23 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)) 17:17:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:17:23 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-VYU0ZG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:17:23 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)) 17:17:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:17:23 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-fOiU1m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:17:23 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)) 17:17:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:17:23 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-_CDksZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:17:23 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)) 17:17:23 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)) 17:17:23 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)) 17:17:23 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)) 17:17:23 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)) 17:17:23 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)) 17:17:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:17:23 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 17:17:23 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 17:17:23 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 17:17:23 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 17:17:25 INFO - Downloading blessings-1.6.tar.gz 17:17:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:17:25 INFO - Installing collected packages: blessings 17:17:25 INFO - Running setup.py install for blessings 17:17:25 INFO - Successfully installed blessings 17:17:25 INFO - Cleaning up... 17:17:25 INFO - Return code: 0 17:17:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:17:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:17:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:17:25 INFO - Reading from file tmpfile_stdout 17:17:25 INFO - Current package versions: 17:17:25 INFO - blessings == 1.6 17:17:25 INFO - blobuploader == 1.2.4 17:17:25 INFO - docopt == 0.6.1 17:17:25 INFO - functools32 == 3.2.3-2 17:17:25 INFO - jsonschema == 2.5.1 17:17:25 INFO - manifestparser == 1.1 17:17:25 INFO - mozInstall == 1.12 17:17:25 INFO - mozcrash == 1.0 17:17:25 INFO - mozdebug == 0.1 17:17:25 INFO - mozdevice == 0.49 17:17:25 INFO - mozfile == 1.2 17:17:25 INFO - mozhttpd == 0.7 17:17:25 INFO - mozinfo == 0.9 17:17:25 INFO - mozleak == 0.1 17:17:25 INFO - mozlog == 3.4 17:17:25 INFO - moznetwork == 0.27 17:17:25 INFO - mozprocess == 0.25 17:17:25 INFO - mozprofile == 0.28 17:17:25 INFO - mozrunner == 6.13 17:17:25 INFO - mozscreenshot == 0.1 17:17:25 INFO - mozsystemmonitor == 0.3 17:17:25 INFO - moztest == 0.8 17:17:25 INFO - mozversion == 1.4 17:17:25 INFO - psutil == 3.1.1 17:17:25 INFO - requests == 1.2.3 17:17:25 INFO - wsgiref == 0.1.2 17:17:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:17:25 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')]} 17:17:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:25 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')]} 17:17:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:25 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 0x10c8adb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8b0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82704b08c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c68bd30>, '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 0x10c5b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c680be8>, '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.NBCwwGkSpL/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.2zgtfVtzic/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 17:17:25 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 17:17:25 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 17:17:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NBCwwGkSpL/Render', 17:17:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:25 INFO - 'HOME': '/Users/cltbld', 17:17:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:25 INFO - 'LOGNAME': 'cltbld', 17:17:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:25 INFO - 'MOZ_NO_REMOTE': '1', 17:17:25 INFO - 'NO_EM_RESTART': '1', 17:17:25 INFO - 'PAGER': '/bin/cat', 17:17:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:25 INFO - 'PWD': '/builds/slave/test', 17:17:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:25 INFO - 'SHELL': '/bin/bash', 17:17:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2zgtfVtzic/Listeners', 17:17:25 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 17:17:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:25 INFO - 'USER': 'cltbld', 17:17:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:25 INFO - 'XPC_FLAGS': '0x0', 17:17:25 INFO - 'XPC_SERVICE_NAME': '0', 17:17:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:25 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)) 17:17:25 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)) 17:17:25 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)) 17:17:25 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)) 17:17:25 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)) 17:17:25 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)) 17:17:25 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)) 17:17:25 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)) 17:17:25 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)) 17:17:25 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)) 17:17:25 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)) 17:17:25 INFO - Cleaning up... 17:17:25 INFO - Return code: 0 17:17:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:17:25 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')]} 17:17:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:25 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')]} 17:17:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:25 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 0x10c8adb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8b0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82704b08c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c68bd30>, '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 0x10c5b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c680be8>, '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.NBCwwGkSpL/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.2zgtfVtzic/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 17:17:25 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 17:17:25 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 17:17:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NBCwwGkSpL/Render', 17:17:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:25 INFO - 'HOME': '/Users/cltbld', 17:17:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:25 INFO - 'LOGNAME': 'cltbld', 17:17:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:25 INFO - 'MOZ_NO_REMOTE': '1', 17:17:25 INFO - 'NO_EM_RESTART': '1', 17:17:25 INFO - 'PAGER': '/bin/cat', 17:17:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:25 INFO - 'PWD': '/builds/slave/test', 17:17:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:25 INFO - 'SHELL': '/bin/bash', 17:17:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2zgtfVtzic/Listeners', 17:17:25 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 17:17:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:25 INFO - 'USER': 'cltbld', 17:17:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:25 INFO - 'XPC_FLAGS': '0x0', 17:17:25 INFO - 'XPC_SERVICE_NAME': '0', 17:17:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:26 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:17:26 INFO - Cleaning up... 17:17:26 INFO - Return code: 0 17:17:26 INFO - Running post-action listener: _resource_record_post_action 17:17:26 INFO - Running post-action listener: _start_resource_monitoring 17:17:26 INFO - Starting resource monitoring. 17:17:26 INFO - [mozharness: 2017-06-01 00:17:26.282416Z] Finished create-virtualenv step (success) 17:17:26 INFO - [mozharness: 2017-06-01 00:17:26.283396Z] Running install step. 17:17:26 INFO - Running pre-action listener: _resource_record_pre_action 17:17:26 INFO - Running main action method: install 17:17:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:17:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:17:26 INFO - Reading from file tmpfile_stdout 17:17:26 INFO - Detecting whether we're running mozinstall >=1.0... 17:17:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:17:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:17:26 INFO - Reading from file tmpfile_stdout 17:17:26 INFO - Output received: 17:17:26 INFO - Usage: mozinstall [options] installer 17:17:26 INFO - Options: 17:17:26 INFO - -h, --help show this help message and exit 17:17:26 INFO - -d DEST, --destination=DEST 17:17:26 INFO - Directory to install application into. [default: 17:17:26 INFO - "/builds/slave/test"] 17:17:26 INFO - --app=APP Application being installed. [default: firefox] 17:17:26 INFO - mkdir: /builds/slave/test/build/application 17:17:26 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'] 17:17:26 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 17:17:47 INFO - Reading from file tmpfile_stdout 17:17:47 INFO - Output received: 17:17:47 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 17:17:47 INFO - Running post-action listener: _resource_record_post_action 17:17:47 INFO - [mozharness: 2017-06-01 00:17:47.017950Z] Finished install step (success) 17:17:47 INFO - [mozharness: 2017-06-01 00:17:47.018121Z] Running run-tests step. 17:17:47 INFO - Running pre-action listener: _resource_record_pre_action 17:17:47 INFO - Running main action method: run_tests 17:17:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:17:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:17:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:17:47 INFO - 2017-05-31 17:17:47.032 screenresolution[1632:8291] starting screenresolution argv=screenresolution get 17:17:47 INFO - 2017-05-31 17:17:47.035 screenresolution[1632:8291] Display 0: 1600x1200x32@60 17:17:47 INFO - 2017-05-31 17:17:47.041 screenresolution[1633:8295] starting screenresolution argv=screenresolution list 17:17:47 INFO - Available Modes on Display 0 17:17:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:17:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:17:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:17:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:17:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:17:47 INFO - Intel Iris: 17:17:47 INFO - Chipset Model: Intel Iris 17:17:47 INFO - Type: GPU 17:17:47 INFO - Bus: Built-In 17:17:47 INFO - VRAM (Dynamic, Max): 1536 MB 17:17:47 INFO - Vendor: Intel (0x8086) 17:17:47 INFO - Device ID: 0x0a2e 17:17:47 INFO - Revision ID: 0x0009 17:17:47 INFO - Displays: 17:17:47 INFO - Display: 17:17:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:17:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:17:47 INFO - Display Serial Number: 1600x1200 60 17:17:47 INFO - Main Display: Yes 17:17:47 INFO - Mirror: Off 17:17:47 INFO - Online: Yes 17:17:47 INFO - Rotation: Supported 17:17:47 INFO - Return code: 0 17:17:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:17:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:17:47 INFO - Python 2.7.3 17:17:47 INFO - Return code: 0 17:17:47 INFO - grabbing minidump binary from tooltool 17:17: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')]} 17:17:47 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 0x10c68bd30>, '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 0x10c5b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c680be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:17:47 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 17:17:47 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 17:17:47 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 17:17:47 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:17:47 INFO - Return code: 0 17:17:47 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:17:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:17:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:17:47 INFO - ENV: RUST_BACKTRACE is now 1 17:17:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:17:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:17:47 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/RtuXsD5lQZyt47PxvEoHYg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0094', '--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 17:17:47 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/RtuXsD5lQZyt47PxvEoHYg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0094 --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 17:17:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NBCwwGkSpL/Render', 17:17:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:47 INFO - 'HOME': '/Users/cltbld', 17:17:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:47 INFO - 'LOGNAME': 'cltbld', 17:17:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:17:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:17:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:47 INFO - 'MOZ_NO_REMOTE': '1', 17:17:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:17:47 INFO - 'NO_EM_RESTART': '1', 17:17:47 INFO - 'PAGER': '/bin/cat', 17:17:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:47 INFO - 'PWD': '/builds/slave/test', 17:17:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:17:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:47 INFO - 'RUST_BACKTRACE': '1', 17:17:47 INFO - 'SHELL': '/bin/bash', 17:17:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2zgtfVtzic/Listeners', 17:17:47 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 17:17:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:47 INFO - 'USER': 'cltbld', 17:17:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:47 INFO - 'XPC_FLAGS': '0x0', 17:17:47 INFO - 'XPC_SERVICE_NAME': '0', 17:17:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:47 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/RtuXsD5lQZyt47PxvEoHYg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0094', '--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 17:17:47 INFO - mozversion application_buildid: 20170531154528 17:17:47 INFO - mozversion application_changeset: 10cfa295a989879affa59adeb70f7e0847d19e7f 17:17:47 INFO - mozversion application_display_name: FirefoxDeveloperEdition 17:17:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:17:47 INFO - mozversion application_name: Firefox 17:17:47 INFO - mozversion application_remotingname: firefox-dev 17:17:47 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 17:17:47 INFO - mozversion application_vendor: Mozilla 17:17:47 INFO - mozversion application_version: 54.0 17:17:47 INFO - mozversion platform_buildid: 20170531154528 17:17:47 INFO - mozversion platform_changeset: 10cfa295a989879affa59adeb70f7e0847d19e7f 17:17:47 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 17:17:47 INFO - mozversion platform_version: 54.0 17:17:47 INFO - using testdate: 1496276267 17:17:47 INFO - actual date: 1496276267 17:17:47 INFO - starting webserver on 'localhost:49230' 17:17:47 INFO - SUITE-START | Running 2 tests 17:17:47 INFO - TEST-START | dromaeo_css 17:17:47 INFO - operating with platform_type : mac_ 17:17:47 INFO - Initialising browser for dromaeo_css test... 17:17:47 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/tmpg9sOsp/profile http://localhost:49230/getInfo.html) 17:17:50 INFO - PROCESS | 1643 | __metrics Screen width/height:1600/1200 17:17:50 INFO - PROCESS | 1643 | colorDepth:24 17:17:50 INFO - PROCESS | 1643 | Browser inner width/height: 1024/689 17:17:50 INFO - PROCESS | 1643 | __metrics 17:17:50 INFO - PROCESS | 1643 | 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" 17:17:50 INFO - PROCESS | 1643 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:17:51 INFO - TEST-INFO | 1643: exit 0 17:17:51 INFO - Browser initialized. 17:17:51 INFO - Running cycle 1/1 for dromaeo_css test... 17:17:51 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/tmpg9sOsp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:18:03 INFO - PROCESS | 1648 | RSS: Main: 193654784 17:18:03 INFO - PROCESS | 1648 | 17:21:07 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49230/tests/dromaeo/cssquery-ext.html) 17:21:09 INFO - PROCESS | 1648 | RSS: Main: 312504320 17:21:09 INFO - PROCESS | 1648 | 17:24:12 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-ext.html (next: http://localhost:49230/tests/dromaeo/cssquery-jquery.html) 17:24:12 INFO - PROCESS | 1648 | RSS: Main: 328445952 17:24:12 INFO - PROCESS | 1648 | 17:27:15 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49230/tests/dromaeo/cssquery-mootools.html) 17:27:15 INFO - PROCESS | 1648 | RSS: Main: 299991040 17:27:15 INFO - PROCESS | 1648 | 17:30:18 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49230/tests/dromaeo/cssquery-prototype.html) 17:30:18 INFO - PROCESS | 1648 | RSS: Main: 325062656 17:30:18 INFO - PROCESS | 1648 | 17:33:22 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49230/tests/dromaeo/cssquery-yui.html) 17:33:23 INFO - PROCESS | 1648 | RSS: Main: 289161216 17:33:23 INFO - PROCESS | 1648 | 17:36:26 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-yui.html (next: http://localhost:49230/tests/dromaeo/cssquery-dojo.html) 17:36:26 INFO - PROCESS | 1648 | RSS: Main: 511680512 17:36:26 INFO - PROCESS | 1648 | 17:36:26 INFO - PROCESS | 1648 | __start_tp_report 17:36:26 INFO - PROCESS | 1648 | _x_x_mozilla_page_load 17:36:26 INFO - PROCESS | 1648 | _x_x_mozilla_page_load_details 17:36:26 INFO - PROCESS | 1648 | |i|pagename|runs| 17:36:26 INFO - PROCESS | 1648 | |0;dojo.html;3329.9667003329964;3438.9999999999986;3440.4667276572122;3458.325626502833;3501.9474707879385;5841.7955371562;5883.911741323884;5898.410158984099;5915.792947246847;5924.437178468044;5899.115132730092;5939.495142912858;5944.673042982634;5955.136505206753;5973.850653733648;4437.267850804614;5320.361556613212;5353.866153346178;5420.674759514442;5447.836564903039;5286.259923610698;5300.893982120355;5324.41431442541;5460.535854452376;5472.069748142825;4373.584509471595;5313.495217954308;5329.467053294678;5350.946490535084;5365.892682146355;5389.5688344932705;5432.375276843174;5441.727913604304;5453.945460545384;5464.12573988164;5263.289455923478;5382.838514844521;5391.973040134813;5421.756020979026;5422.53908417785;5733.713314334308;5759.5968282220265;5811.360750317462;5812.593118481708;5926.318473375574;6330.050492426127;6347.682615869234;6363.968180159116;6365;6367.885620016478;6319.526035547352;6394.904076438857;6403.48772098231;6405.711742971531;6407.070974708688;5899.439553242435;5929.822105336847;6051.364606716275;6080.391960803929;6126.050462178369;6127.938720612826;6356.809295721136;6394.552381333262;6414;6452.35476452351;6391.58454700443;6395.392437718502;6398.520110991694;6405.711742971577;6409.0066039763915;6305.873882522324;6315.115883776275;6375.553711240262;6404.48764098871;6404.935950640434;6250;6328.778492752824;6354.364563543609;6362.840928976812;6371.426571608485;6302.1177035215105;6340.619562826245;6354.142190804275;6373;6381.936180638227;4829.1790395632825;4898.779554920037;4904.8038078477175;4909.729964851896;4930.630202734809;4412.558744125562;4415.66882483815;4420.292753159543;4433.7118087324225;4455;5645.407232240637;5666.9716651416475;5722.284714410699;5740.913886291709;5764.365919748824;6344.651044192614;6354.523410744213;6361.9045714314325;6373.71318290678;6382.329855365213;5822.243108395882;5827.562932779973;5831.79588714393;5846.0646296593195;5882.970585147047;5361.678299302054;5483.369412517532;5494.120940649477;5549.417311182348;5560.582956278296;6373.139626150503;6374.968125159345;6411.903821442776;6478.060681201294;6558.344165583403;6389.616623002635;6391.552591318563;6397.552171347961;6397.616143031433;6400.359964003563;6462.256840463218;6487.967560162169;6519.902201466887;6538.117354157318;6553.311902250386;6223.502119830312;6243.157173781481;6322.968385158045;6334.4932405406535;6376.4261216488885;6212.192414986023;6309.3690630936535;6363.363663633785;6385.10608514799;6415.133956915756;6180.505559555315;6206.503479721701;6241.9139746421815;6259.374062593704;6306.684665766785;6349.619022858643;6353.650549219838;6385.138006369267;6398.328175541687;6404.135441715309;5987.461128498456;6038.245219347582;6158.6304821709045;6242.906356404748;6246.718897649525;6283.434490895841;6373.553851230369;6379.202599675041;6383.234011918599;6391.392817682313;6214.9689251553455;6226.40849119333;6236.968815155895;6239;6245.18812554365 17:36:26 INFO - PROCESS | 1648 | |1;ext.html;799.6561478564217;855.7689423855555;879.6833140069574;892.5537231384304;894.320316559415;38883.222335553306;39414.40878386823;41274;41329.79335103328;41502.584974150246;39239.215215695665;39409.21181576374;39443.40834887479;39502.01244968877;39554;7517.135529414105;8065.2741253287195;8193.590320483981;8195.139510351417;8205.425620206588;7586.544807311579;11564.363959982198;11820.468078936468;11970.521179152824;11978.640640780746;11800.940995295012;11808.055355571576;11828.7634247315;11834.93485586297;11836.585719499819;11701.47343369546;11737.647870563897;11738.823917641243;11739.413029348498;11753.823692644617;11505.424728763528;11539.30764154145;11555.826662600068;11578.826317605242;11591.6522504325;38971.80514097412;39193.21613567741;39223.80388098041;39266.80366598177;39411;3524.436090225577;3811.2377524495214;3857.366007024232;3884.1454879926373;3948.3879998600264;28055.018074367505;28080;29817.701822981708;30423.847880760677;30490.085297441114;3162.288485090859;3175.1109689287036;3188.8246146461934;3312.1222875937897;3318.1206980150278;337642.31178843946;338338;338895;339250;342724;42231.577684223375;43100.568994310284;52310;52354.738226308626;52508.737456312476;44162.779186103864;55011;55984.16023759647;57978;58558.12162817561;14922.552323430315;14985;15019.849801502063;15027.173505457306;15029.173395463136;12863.54977575799;12879.61361159148;12890.484380624857;12911.22532648044;12920.41858116387;12454.688632784071;12504.3747812609;12506.18709783861;12578.433970471167;12606;4825.420949486014;5001.09936236989;5679.722062535896;5791.218185544982;5797.9707102513785;988.0190218611638;997.6059456514781;1061.9751939378536;1071.49104175516;1148.586508856811;27819.860900695367;28280.29299267535;28583.856645734355;28602.713972860005;28673.28316792097;1042.9362050708235;1146.1346683254255;1158.3860553906443;1170.5434880396651;1214.2532342609409;12119.939400302943;14290.714185716228;14853.628659283388;15179.696406071816;16312.429064982669;437200;439117.8044109823;439518;439879;440664;50666.74666626643;50764.23853642198;50867.491325087016;50936.745316273926;51505.22742158871;1405.676694360303;1426.7431862264684;1442.322108608952;1444.826620805468;1446.3419144289533;23491.530169396858;25638.230853074438;27879;28391.858040709663;28528.57207141853;41533.58466415297;41602.791986039876;41663;41690.58309416867;41732;432647.8367608142;434095;434330.8283458562;434536;435819;24371.75628243695;24809.25572232836;29102;29712.55431168491;29776.85111574428;37951.81024094972;38199.284609146365;38356.849294521206;39267.41098883462;39451.60548394594;26892.865535672197;27417.31456713598;28054.85972570124;28109.297267568476;28352.716472835833;27236.04673836406;27543.31141721393;27565.448691026468;28153.888458366113;28325.575116373682;31697.207569810016;32312.192195195308;32482.67517324891;32521.186970325933;32904;22307.888460557595;27664.17007489698;28129.296767580974;28254.434911301247;28546.57180142258;22407.77592224057;22725.659115113616;22992.102518253745;23027.539449211257;23168.95739691684 17:36:26 INFO - PROCESS | 1648 | |2;jquery.html;411.5863557125091;413.70833562338555;416.69581205719817;419.5301262585903;450.833191719064;19584.51038724031;20594.691079633798;22115.778842211574;22159;22265.777342226593;21583.46041348967;21796.346109616738;22671.77328226717;22819.885900570473;22867.885660571716;6547.934520654792;6816.113905192343;6821.761238356658;6835.863282734343;6837.965810170942;7394.70421183152;7678.731244406447;7844.41166912481;7850.842983140334;7856.882146767774;7678.155402905676;7798.922010779905;7818.609069546528;7841.6079196040255;7911.723089691862;7556.886646700304;7813.179616140337;7813.726519571816;7886.80282992924;7903.130655627876;7661.655225514865;7666.003419555422;7670.27132422419;7670.7315243966195;7677.193894641038;25333;26202.08292709765;26579.335516612045;27343.45313093747;27690.446191076266;2949.808262462933;2962.185399015288;2970.6880777518268;2986.940261194764;2990.700929906992;15797.368105275775;16091.436799711953;16974.660506789794;17614.119294035376;17660.911695441442;1963.1558429875147;2015.2140665140607;2045.273927755655;2045.8465615078928;2101.3485819395883;160302;166034.3396566031;167653;169839;173368.1331593334;20503.4874128148;20597.48506287325;20757.273495427587;20794.48013799667;20966.16135354599;33233.83383083117;33911.66088339134;34057.31885362279;34099.829500852335;34125;10350.327228730108;10373.222008349405;10473.266871318934;10613.5223914924;10685.679429617125;8724;8755.956220218859;8768.386212965159;8936.374453788172;8946.68686595966;7935.801604959806;9639.18066964316;9640.084192001748;9731.416115032978;9877.209823214125;3293.637699853014;3423.640517745651;4023.617756313204;4067.877963661095;4132.33882578786;668.7258224128084;690.8169335126196;830.5847076461769;883;1117.7987962166887;20690.379288621367;22397.776022239894;22472.55054898893;22491;22537.323880283617;1072.2976450424985;1076.962306319275;1107.7869732642744;1112.4604566785104;1126.7239526316105;10808.081313088318;11261;11452.484638191134;11624.360660163604;11659.88340116605;600818;603535;751569;759230;780559;95058.04941950631;114664.56006319656;134567;152159;156308.21845890698;1341.2489006156584;1358.8573199814075;1396.3437184523257;1416.6954104867502;1418.5106138382241;19963.301284454974;20137.496562586053;20534.794652053886;20543.38369848907;20592.485187870425;61852.38147618466;62668.746625066335;62775.430614233184;63271;63518;81352;87924.1207587916;89829;90921;91220;11017.559297628006;11364.090872729996;11433.485493152662;11517.25137866036;11754.177207595387;13054.412551435018;13063.934680326538;13335.066545341733;13358.465661373824;13530.985176111633;11262.38056906878;11297.774044519228;11338.716532086764;11505.252158609663;11642.185047046625;8252.119226139142;10182.745431364276;10420.739481513023;10693.732656683645;10721.946390267998;11457.59898403552;11541.480633371686;11688.006519445942;11704.531818727155;11791.233569817934;11386.373749443861;11416.486258118572;11452.427378630868;11781.410929453665;11873.940630296793;11117.055050320814;11231.943840280746;11301.886981130412;11452.647993762317;11577.421128943688 17:36:26 INFO - PROCESS | 1648 | |3;mootools.html;1372.4167228927706;1440.3302464354076;1565.1469948877861;1611.6051567365996;1615.474970634544;10511.63209287675;11079.667609971693;11096.945790149943;11098.833517497234;11104.611338603141;10610.098141657969;11066.833997490045;11170.385628790413;11186.384748838811;11220.382878941655;2372.8813559322007;2399.160293897131;2401.267613377928;2402.147237730607;2405.4707964247837;2000.8999550022513;2303.49323148907;2372.1223147435385;2383.1778036577352;2393.8324317297797;2317.142657216832;2385.3082606044313;2389.8685572293516;2398.400399900025;2410.240774156144;2323.686302349195;2334.568104900599;2351.43565544269;2364.219807463533;2365.266767302142;2326.918557850484;2354.4937838364835;2355.222776483756;2362.11420717231;2367.763223677636;10794.352338859693;10856.565737370496;11036.779264414667;11066.39134847583;11077.833832492519;5594.964931487688;5738.598298119134;5756.165356023395;5760.222369980041;5763.682997435139;3047.8933237336587;3469;3605.296967091418;3666.7433279670436;3679.0802299425145;1199.6341115959628;1209.2381799466368;1232.3530146672979;1250.7123361626877;1275.132909621457;13778.793318100237;14115.929420352935;14383;15425.3829846806;15687.294071766797;3922.3332033554366;4499.617532509707;4689.413823272091;4696.8590942271785;4701;11625.88374116265;11626.360550169653;11653.242539234923;11655.475503602189;11656.766864662659;4814.542618451242;4969.950300497021;5511.338639363302;5646.943530564724;5671.602987790898;5060.5951523878;5175.353080864892;5184.170532714747;5239.580833533384;5257.579393648499;3768.9434658480145;3775.131719704483;3795.6963442925053;3839.865604703822;3848.9037774055873;830.4975489828687;987.234892957949;1000.0749306891242;1007.1388962437011;1014.3406785589426;802.1497212954287;826.6238861318086;835.2190701693814;843.0978852627752;844.994456596648;5966.403359664086;6556.606603603799;6678.632675202911;6767.018782276641;7010.929890701028;987.3088837813416;1019.592163134752;1039.5166247694858;1063.1654151491043;1114.8829372915889;3561.269939662363;4148.796848913781;4233.428487154195;4234.449521562177;4351.456067991501;13192;13742.519011834538;13768.655783605289;13771.242581658105;13778.173309601456;13558.322083895766;13560.321983900765;13696.315184240748;13703.040787145004;13703.931480342733;10059.899401006043;10209.081182693742;10390.532426040847;10408.739781505372;10410.115140213014;6300.149479820166;6550.475961922939;6616.966915165393;6648.301928297653;6686.866262674816;11967.700807479883;12124.939375303067;12458.31479268649;12466;12482.500699972263;12369.072319576248;12410.75178496443;12640.936795315965;12697.746045079231;12698.873011269769;9011.684591039544;9283.767905802139;9294.395864268801;9296.860547091665;9311.394759340892;9932.900670993198;10112.089911907964;10230.437325947145;10249.897501025192;10251.487425628839;8488.787780305542;8705.085965973482;8748.47509149453;8794.42836215644;8911.108889111296;8466.111058338785;8515.063343032385;8525.966982774979;8606.096359882122;8626.482411055356;7757.185495522889;7758.262965018314;7837.725679601187;7864.21357864209;7868.5672288022415;5702.60081794287;5730.799422020209;5851.21008663842;5881.353051164454;5932.288125424804;5699.544036477155;5893.793717220048;5918.940810591839;5937.9406205937385;6045.818625441244 17:36:26 INFO - PROCESS | 1648 | |4;prototype.html;319.84967065479225;371.67849809914435;387.40726688167115;388.3185010306911;389.1031173145901;4499.280115181567;4888.315635810989;4958.975205123969;5183.904715274933;5207.7866906564705;4412.889677758058;4450.443694538183;4855.295982082596;4882.584980276672;4889.779959901804;3607.188382613904;3659.304732100892;4011.338129208677;4018.3570628699413;4143.7720925349095;4096.975756060985;4238.765125070536;4302.41917341158;4333.58830911063;4384.457014931348;3905.6525498702904;4223.725457845246;4331;4332.263515202408;4369.257226271541;4063.6545893599077;4146.523149837747;4244.554321796198;4288.7212331198525;4405.625521830648;3509.9473507897146;3585.910352241189;3767.698584113292;4048.8582899598373;4163.5628259032665;4017.5982401759884;4061.939070913939;4808.062427826575;4917.655764096515;4976.527229913188;4640.79116439761;4863.416390033183;4907.975460122712;5005.924911126336;5079.263506791532;4790.904181916342;4931.580815630676;5107.157319042353;5337.946620533784;5339.973300133474;4148.128892932487;4604.976975115103;4637;4692.601128904047;4695.718256904596;4935.235038569028;5332.346972438557;5707.400722924075;5726.770929162829;5792.841431713674;4286.485621725413;4302.634276086505;4386.824527018884;4533.0253995390485;4546.909061818745;4314.784260786948;4418.668599854959;4868.123737727243;4870.125001874304;4884.877878053006;4382.890427739332;4785.521447855256;4935.260930183711;5012.548870601663;5096.884792810942;4462.866114016585;5232.2674825524455;5255.868603284948;5264.789408423697;5301.330081024465;1392.6518370407398;1431.291510702209;1550.2171403441416;1644.9588760281088;1670.938953764344;1153.296489141623;1234.5247079874314;1240.8883200511998;1321.0013269877325;1376.7659497885384;4440.666949978765;4860.392450943632;4862.854114376574;4867.448935245557;4895.681780684244;5121.61587880903;5256.712105534169;5434.940186663642;5572.1054841354135;5585.553155747531;4104.363823607346;4438.956845141389;4794.184988551954;4803.735794531264;4826.348442960226;2475.4306509502553;2809.946270148694;2843.1328444824526;2867.8279303241457;2868.4693331733697;4876.926846097312;4988.476209997971;4994.850154495371;5508.705454218256;5634.985702573576;5373;5591.329040515164;5592.3009623797025;5645.633033852787;5659.236003139606;4637.721736695809;5130.563902068291;5424;5599.272094627672;5684.744186511617;4541.865323298045;4975.875603109806;5098.46466121052;5329.786808527616;5417.783288668409;4594.793234304531;4690.3199036138885;4730.455997560324;4794.969081647462;4896.510348965075;4881.608012074129;5653.773849045992;5661.46216109469;5667.971660141673;5669.858253543694;4140.985458562551;4365.1448134543325;4378.452693413324;4411.536788637147;4493.887652808706;5185.351831021122;5204.412654140523;5520.10832363175;5571.882684579834;5589.385167631639;4653.557911998355;4928.310036594808;5123.74381280927;5182.352205974254;5334.733263336895;4723.055388922298;4910.558049775537;4985.700857948535;5363.865903352447;5377.946220537745;4939.407271127488;4951.653384263067;5000.624953128603;5152.433232344364;5244.5279924806955;4531.637469002406;4788.377510923697;4807.134715751058;5392.433794451526;5402.486763757437;4500.617447516866;5252.5535329497425;5280;5304.525535774074;5315.840524784263 17:36:26 INFO - PROCESS | 1648 | |5;yui.html;776.7941495503693;786.3787607789847;818.8812622169789;859.5272600069962;868.3877866104397;15590.532284031484;16419.58951026225;16452.67094658106;16454.506364809044;16455.094969776674;14980.325885335158;15086.547403577883;15114.924425377885;15123;15146.545603631908;949.4350861237552;952.5094576293214;954.3701157236226;971.7230589282055;996.282676472942;965.5268918230087;978.6525783346881;982.8181786369508;984.1585444445004;984.2175470500969;1024.5031159887485;1025.4001409175642;1025.4667572862106;1026.3226270661364;1026.573971801705;1018.9847152292722;1020.7907378987289;1020.9846852297221;1021.0096206679547;1021.8109649714827;688.3873352716087;734.1997223026873;764.1632412508294;764.2892110337384;765.9042619672541;15495.612609684735;15524.534263972098;15625.374985000586;16792.244349004322;17075.316987320493;14346.282685865664;14450.132992020408;14598.978071534997;14643.780343294753;14655;11670;11708.53165873364;11812.704682382922;11873.16887817853;11949.701257468545;3555.9466608000903;3664.8900532984053;3767.1523907120677;3771.547414310273;3801.7148713846295;103863.4806825961;112320.87679123267;113004;116413.41793291064;116784.41607791907;10137.898621013843;10703.46482675863;11129.721756956142;11195.216334856483;11432.085433165326;23333.416664583183;23608.40978975505;23771.405714856923;23804.642930356058;23945.52108957811;7896.052473703192;7939.801504962422;7954.761357159295;8075;8197.959010204911;4358.193734159191;4454.866354009385;4531.18438681034;4548.704334218265;4599;3026.3947210557976;3536.929261414757;3627.183883626163;3639.363111455538;3639.727020473475;625.7747211004034;635.1806170040596;642.2613993906951;648.0538413915735;651.4234902111571;667.7028722218566;673.2560520624737;698.2703075286338;708.4686485136148;711.5481669140133;10340.689779306633;10511.264211505275;10650.414227217423;10792.46037698112;10820.4048777318;655.1908393134476;686.8488932434956;690.7171797210268;730.5945200413821;740.0194741966808;4608.28571571407;4964.081644895706;4984.950150498448;5034.521720436553;5057.190849464142;103726;103757.48121259345;104757.95242047482;104832;105325.47337263418;79599.60200198962;81160;81904.18095819085;86766.56616716876;88659.55670221608;43128.49050283288;46462.76768616135;57584.71207643935;65851.68296633945;73598.26401735914;10520;11007.944960275147;11210.303087829132;11291.62875796092;11361.54553817838;48132.27801583049;48571.27143092927;48877;49788.75528111826;50183;59886;60920.972614074366;62455;62997;63334.04998924927;12387.256764594153;12474.965663628418;12730.23618582888;12859.678508037374;13124.606261812161;12725.427355769198;14083;14438.278086095863;14615.707685846011;14811.555653330417;13116.540921067717;13256.668583285495;13755;13891.166530008231;13893.305334733424;13077.57292650209;13805.309734513034;13956.302184890512;14111.22388268655;14344.784828227794;12604.30676312775;13555.59333220005;13728.794068089186;14007.43970241179;14213.715725685635;9184.908150918405;9190.586423611087;9433.198178154658;9469.479178644966;9707.6116955321;9974.700758977242;10158.746031349276;11300.265482743924;11511;11521.53913843437 17:36:26 INFO - PROCESS | 1648 | __end_tp_report 17:36:26 INFO - PROCESS | 1648 | __start_cc_report 17:36:26 INFO - PROCESS | 1648 | _x_x_mozilla_cycle_collect,1602 17:36:26 INFO - PROCESS | 1648 | __end_cc_report 17:36:26 INFO - PROCESS | 1648 | __startTimestamp1496277386485__endTimestamp 17:36:26 INFO - PROCESS | 1648 | 17:36:26 INFO - PROCESS | 1648 | ------- Summary: start ------- 17:36:26 INFO - PROCESS | 1648 | Number of tests: 6 17:36:26 INFO - PROCESS | 1648 | 17:36:26 INFO - PROCESS | 1648 | [#0] dojo.html Cycles:165 Average:5885.19 Median:6214.97 stddev:685.20 (11.0%) stddev-sans-first:657.33 17:36:26 INFO - PROCESS | 1648 | Values: 3330.0 3439.0 3440.5 3458.3 3501.9 5841.8 5883.9 5898.4 5915.8 5924.4 5899.1 5939.5 5944.7 5955.1 5973.9 4437.3 5320.4 5353.9 5420.7 5447.8 5286.3 5300.9 5324.4 5460.5 5472.1 4373.6 5313.5 5329.5 5350.9 5365.9 5389.6 5432.4 5441.7 5453.9 5464.1 5263.3 5382.8 5392.0 5421.8 5422.5 5733.7 5759.6 5811.4 5812.6 5926.3 6330.1 6347.7 6364.0 6365.0 6367.9 6319.5 6394.9 6403.5 6405.7 6407.1 5899.4 5929.8 6051.4 6080.4 6126.1 6127.9 6356.8 6394.6 6414.0 6452.4 6391.6 6395.4 6398.5 6405.7 6409.0 6305.9 6315.1 6375.6 6404.5 6404.9 6250.0 6328.8 6354.4 6362.8 6371.4 6302.1 6340.6 6354.1 6373.0 6381.9 4829.2 4898.8 4904.8 4909.7 4930.6 4412.6 4415.7 4420.3 4433.7 4455.0 5645.4 5667.0 5722.3 5740.9 5764.4 6344.7 6354.5 6361.9 6373.7 6382.3 5822.2 5827.6 5831.8 5846.1 5883.0 5361.7 5483.4 5494.1 5549.4 5560.6 6373.1 6375.0 6411.9 6478.1 6558.3 6389.6 6391.6 6397.6 6397.6 6400.4 6462.3 6488.0 6519.9 6538.1 6553.3 6223.5 6243.2 6323.0 6334.5 6376.4 6212.2 6309.4 6363.4 6385.1 6415.1 6180.5 6206.5 6241.9 6259.4 6306.7 6349.6 6353.7 6385.1 6398.3 6404.1 5987.5 6038.2 6158.6 6242.9 6246.7 6283.4 6373.6 6379.2 6383.2 6391.4 6215.0 6226.4 6237.0 6239.0 6245.2 17:36:26 INFO - PROCESS | 1648 | 17:36:26 INFO - PROCESS | 1648 | [#1] ext.html Cycles:180 Average:53945.86 Median:30456.97 stddev:107837.48 (354.1%) stddev-sans-first:108066.17 17:36:26 INFO - PROCESS | 1648 | Values: 799.7 855.8 879.7 892.6 894.3 38883.2 39414.4 41274.0 41329.8 41502.6 39239.2 39409.2 39443.4 39502.0 39554.0 7517.1 8065.3 8193.6 8195.1 8205.4 7586.5 11564.4 11820.5 11970.5 11978.6 11800.9 11808.1 11828.8 11834.9 11836.6 11701.5 11737.6 11738.8 11739.4 11753.8 11505.4 11539.3 11555.8 11578.8 11591.7 38971.8 39193.2 39223.8 39266.8 39411.0 3524.4 3811.2 3857.4 3884.1 3948.4 28055.0 28080.0 29817.7 30423.8 30490.1 3162.3 3175.1 3188.8 3312.1 3318.1 337642.3 338338.0 338895.0 339250.0 342724.0 42231.6 43100.6 52310.0 52354.7 52508.7 44162.8 55011.0 55984.2 57978.0 58558.1 14922.6 14985.0 15019.8 15027.2 15029.2 12863.5 12879.6 12890.5 12911.2 12920.4 12454.7 12504.4 12506.2 12578.4 12606.0 4825.4 5001.1 5679.7 5791.2 5798.0 988.0 997.6 1062.0 1071.5 1148.6 27819.9 28280.3 28583.9 28602.7 28673.3 1042.9 1146.1 1158.4 1170.5 1214.3 12119.9 14290.7 14853.6 15179.7 16312.4 437200.0 439117.8 439518.0 439879.0 440664.0 50666.7 50764.2 50867.5 50936.7 51505.2 1405.7 1426.7 1442.3 1444.8 1446.3 23491.5 25638.2 27879.0 28391.9 28528.6 41533.6 41602.8 41663.0 41690.6 41732.0 432647.8 434095.0 434330.8 434536.0 435819.0 24371.8 24809.3 29102.0 29712.6 29776.9 37951.8 38199.3 38356.8 39267.4 39451.6 26892.9 27417.3 28054.9 28109.3 28352.7 27236.0 27543.3 27565.4 28153.9 28325.6 31697.2 32312.2 32482.7 32521.2 32904.0 22307.9 27664.2 28129.3 28254.4 28546.6 22407.8 22725.7 22992.1 23027.5 23169.0 17:36:26 INFO - PROCESS | 1648 | 17:36:26 INFO - PROCESS | 1648 | [#2] jquery.html Cycles:180 Average:41763.80 Median:22212.39 stddev:117686.83 (529.8%) stddev-sans-first:117976.01 17:36:26 INFO - PROCESS | 1648 | Values: 411.6 413.7 416.7 419.5 450.8 19584.5 20594.7 22115.8 22159.0 22265.8 21583.5 21796.3 22671.8 22819.9 22867.9 6547.9 6816.1 6821.8 6835.9 6838.0 7394.7 7678.7 7844.4 7850.8 7856.9 7678.2 7798.9 7818.6 7841.6 7911.7 7556.9 7813.2 7813.7 7886.8 7903.1 7661.7 7666.0 7670.3 7670.7 7677.2 25333.0 26202.1 26579.3 27343.5 27690.4 2949.8 2962.2 2970.7 2986.9 2990.7 15797.4 16091.4 16974.7 17614.1 17660.9 1963.2 2015.2 2045.3 2045.8 2101.3 160302.0 166034.3 167653.0 169839.0 173368.1 20503.5 20597.5 20757.3 20794.5 20966.2 33233.8 33911.7 34057.3 34099.8 34125.0 10350.3 10373.2 10473.3 10613.5 10685.7 8724.0 8756.0 8768.4 8936.4 8946.7 7935.8 9639.2 9640.1 9731.4 9877.2 3293.6 3423.6 4023.6 4067.9 4132.3 668.7 690.8 830.6 883.0 1117.8 20690.4 22397.8 22472.6 22491.0 22537.3 1072.3 1077.0 1107.8 1112.5 1126.7 10808.1 11261.0 11452.5 11624.4 11659.9 600818.0 603535.0 751569.0 759230.0 780559.0 95058.0 114664.6 134567.0 152159.0 156308.2 1341.2 1358.9 1396.3 1416.7 1418.5 19963.3 20137.5 20534.8 20543.4 20592.5 61852.4 62668.7 62775.4 63271.0 63518.0 81352.0 87924.1 89829.0 90921.0 91220.0 11017.6 11364.1 11433.5 11517.3 11754.2 13054.4 13063.9 13335.1 13358.5 13531.0 11262.4 11297.8 11338.7 11505.3 11642.2 8252.1 10182.7 10420.7 10693.7 10721.9 11457.6 11541.5 11688.0 11704.5 11791.2 11386.4 11416.5 11452.4 11781.4 11873.9 11117.1 11231.9 11301.9 11452.6 11577.4 17:36:26 INFO - PROCESS | 1648 | 17:36:26 INFO - PROCESS | 1648 | [#3] mootools.html Cycles:180 Average:6684.39 Median:2398.78 stddev:4210.26 (175.5%) stddev-sans-first:4203.15 17:36:26 INFO - PROCESS | 1648 | Values: 1372.4 1440.3 1565.1 1611.6 1615.5 10511.6 11079.7 11096.9 11098.8 11104.6 10610.1 11066.8 11170.4 11186.4 11220.4 2372.9 2399.2 2401.3 2402.1 2405.5 2000.9 2303.5 2372.1 2383.2 2393.8 2317.1 2385.3 2389.9 2398.4 2410.2 2323.7 2334.6 2351.4 2364.2 2365.3 2326.9 2354.5 2355.2 2362.1 2367.8 10794.4 10856.6 11036.8 11066.4 11077.8 5595.0 5738.6 5756.2 5760.2 5763.7 3047.9 3469.0 3605.3 3666.7 3679.1 1199.6 1209.2 1232.4 1250.7 1275.1 13778.8 14115.9 14383.0 15425.4 15687.3 3922.3 4499.6 4689.4 4696.9 4701.0 11625.9 11626.4 11653.2 11655.5 11656.8 4814.5 4970.0 5511.3 5646.9 5671.6 5060.6 5175.4 5184.2 5239.6 5257.6 3768.9 3775.1 3795.7 3839.9 3848.9 830.5 987.2 1000.1 1007.1 1014.3 802.1 826.6 835.2 843.1 845.0 5966.4 6556.6 6678.6 6767.0 7010.9 987.3 1019.6 1039.5 1063.2 1114.9 3561.3 4148.8 4233.4 4234.4 4351.5 13192.0 13742.5 13768.7 13771.2 13778.2 13558.3 13560.3 13696.3 13703.0 13703.9 10059.9 10209.1 10390.5 10408.7 10410.1 6300.1 6550.5 6617.0 6648.3 6686.9 11967.7 12124.9 12458.3 12466.0 12482.5 12369.1 12410.8 12640.9 12697.7 12698.9 9011.7 9283.8 9294.4 9296.9 9311.4 9932.9 10112.1 10230.4 10249.9 10251.5 8488.8 8705.1 8748.5 8794.4 8911.1 8466.1 8515.1 8526.0 8606.1 8626.5 7757.2 7758.3 7837.7 7864.2 7868.6 5702.6 5730.8 5851.2 5881.4 5932.3 5699.5 5893.8 5918.9 5937.9 6045.8 17:36:26 INFO - PROCESS | 1648 | 17:36:26 INFO - PROCESS | 1648 | [#4] prototype.html Cycles:180 Average:4469.30 Median:4840.82 stddev:1199.44 (24.8%) stddev-sans-first:1161.67 17:36:26 INFO - PROCESS | 1648 | Values: 319.8 371.7 387.4 388.3 389.1 4499.3 4888.3 4959.0 5183.9 5207.8 4412.9 4450.4 4855.3 4882.6 4889.8 3607.2 3659.3 4011.3 4018.4 4143.8 4097.0 4238.8 4302.4 4333.6 4384.5 3905.7 4223.7 4331.0 4332.3 4369.3 4063.7 4146.5 4244.6 4288.7 4405.6 3509.9 3585.9 3767.7 4048.9 4163.6 4017.6 4061.9 4808.1 4917.7 4976.5 4640.8 4863.4 4908.0 5005.9 5079.3 4790.9 4931.6 5107.2 5337.9 5340.0 4148.1 4605.0 4637.0 4692.6 4695.7 4935.2 5332.3 5707.4 5726.8 5792.8 4286.5 4302.6 4386.8 4533.0 4546.9 4314.8 4418.7 4868.1 4870.1 4884.9 4382.9 4785.5 4935.3 5012.5 5096.9 4462.9 5232.3 5255.9 5264.8 5301.3 1392.7 1431.3 1550.2 1645.0 1670.9 1153.3 1234.5 1240.9 1321.0 1376.8 4440.7 4860.4 4862.9 4867.4 4895.7 5121.6 5256.7 5434.9 5572.1 5585.6 4104.4 4439.0 4794.2 4803.7 4826.3 2475.4 2809.9 2843.1 2867.8 2868.5 4876.9 4988.5 4994.9 5508.7 5635.0 5373.0 5591.3 5592.3 5645.6 5659.2 4637.7 5130.6 5424.0 5599.3 5684.7 4541.9 4975.9 5098.5 5329.8 5417.8 4594.8 4690.3 4730.5 4795.0 4896.5 4881.6 5653.8 5661.5 5668.0 5669.9 4141.0 4365.1 4378.5 4411.5 4493.9 5185.4 5204.4 5520.1 5571.9 5589.4 4653.6 4928.3 5123.7 5182.4 5334.7 4723.1 4910.6 4985.7 5363.9 5377.9 4939.4 4951.7 5000.6 5152.4 5244.5 4531.6 4788.4 4807.1 5392.4 5402.5 4500.6 5252.6 5280.0 5304.5 5315.8 17:36:26 INFO - PROCESS | 1648 | 17:36:26 INFO - PROCESS | 1648 | [#5] yui.html Cycles:180 Average:19985.89 Median:23689.91 stddev:28546.11 (120.5%) stddev-sans-first:28589.75 17:36:26 INFO - PROCESS | 1648 | Values: 776.8 786.4 818.9 859.5 868.4 15590.5 16419.6 16452.7 16454.5 16455.1 14980.3 15086.5 15114.9 15123.0 15146.5 949.4 952.5 954.4 971.7 996.3 965.5 978.7 982.8 984.2 984.2 1024.5 1025.4 1025.5 1026.3 1026.6 1019.0 1020.8 1021.0 1021.0 1021.8 688.4 734.2 764.2 764.3 765.9 15495.6 15524.5 15625.4 16792.2 17075.3 14346.3 14450.1 14599.0 14643.8 14655.0 11670.0 11708.5 11812.7 11873.2 11949.7 3555.9 3664.9 3767.2 3771.5 3801.7 103863.5 112320.9 113004.0 116413.4 116784.4 10137.9 10703.5 11129.7 11195.2 11432.1 23333.4 23608.4 23771.4 23804.6 23945.5 7896.1 7939.8 7954.8 8075.0 8198.0 4358.2 4454.9 4531.2 4548.7 4599.0 3026.4 3536.9 3627.2 3639.4 3639.7 625.8 635.2 642.3 648.1 651.4 667.7 673.3 698.3 708.5 711.5 10340.7 10511.3 10650.4 10792.5 10820.4 655.2 686.8 690.7 730.6 740.0 4608.3 4964.1 4985.0 5034.5 5057.2 103726.0 103757.5 104758.0 104832.0 105325.5 79599.6 81160.0 81904.2 86766.6 88659.6 43128.5 46462.8 57584.7 65851.7 73598.3 10520.0 11007.9 11210.3 11291.6 11361.5 48132.3 48571.3 48877.0 49788.8 50183.0 59886.0 60921.0 62455.0 62997.0 63334.0 12387.3 12475.0 12730.2 12859.7 13124.6 12725.4 14083.0 14438.3 14615.7 14811.6 13116.5 13256.7 13755.0 13891.2 13893.3 13077.6 13805.3 13956.3 14111.2 14344.8 12604.3 13555.6 13728.8 14007.4 14213.7 9184.9 9190.6 9433.2 9469.5 9707.6 9974.7 10158.7 11300.3 11511.0 11521.5 17:36:26 INFO - PROCESS | 1648 | -------- Summary: end -------- 17:36:26 INFO - PROCESS | 1648 | 17:36:26 INFO - TEST-INFO | 1648: exit 0 17:36:26 INFO - TEST-OK | dromaeo_css | took 1119349ms 17:36:26 INFO - TEST-START | kraken 17:36:26 INFO - operating with platform_type : mac_ 17:36:26 INFO - Initialising browser for kraken test... 17:36:26 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/tmpXEAzL5/profile http://localhost:49230/getInfo.html) 17:36:29 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 17:36:29 INFO - PROCESS | 1655 | colorDepth:24 17:36:29 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 17:36:29 INFO - PROCESS | 1655 | __metrics 17:36:29 INFO - PROCESS | 1655 | 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" 17:36:29 INFO - PROCESS | 1655 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:36:29 INFO - TEST-INFO | 1655: exit 0 17:36:29 INFO - Browser initialized. 17:36:29 INFO - Running cycle 1/1 for kraken test... 17:36:29 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/tmpXEAzL5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:36:41 INFO - PROCESS | 1657 | RSS: Main: 197509120 17:36:41 INFO - PROCESS | 1657 | 17:36:44 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-beat-detection) 17:36:45 INFO - PROCESS | 1657 | RSS: Main: 248254464 17:36:45 INFO - PROCESS | 1657 | 17:36:48 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-dft) 17:36:48 INFO - PROCESS | 1657 | RSS: Main: 260227072 17:36:48 INFO - PROCESS | 1657 | 17:36:51 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-fft) 17:36:52 INFO - PROCESS | 1657 | RSS: Main: 358162432 17:36:52 INFO - PROCESS | 1657 | 17:36:54 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-oscillator) 17:36:55 INFO - PROCESS | 1657 | RSS: Main: 260288512 17:36:55 INFO - PROCESS | 1657 | 17:36:57 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49230/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:36:57 INFO - PROCESS | 1657 | RSS: Main: 261554176 17:36:57 INFO - PROCESS | 1657 | 17:37:00 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49230/tests/kraken/driver.html?testName=imaging-darkroom) 17:37:01 INFO - PROCESS | 1657 | RSS: Main: 302276608 17:37:01 INFO - PROCESS | 1657 | 17:37:04 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49230/tests/kraken/driver.html?testName=imaging-desaturate) 17:37:05 INFO - PROCESS | 1657 | RSS: Main: 301461504 17:37:05 INFO - PROCESS | 1657 | 17:37:08 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49230/tests/kraken/driver.html?testName=json-parse-financial) 17:37:08 INFO - PROCESS | 1657 | RSS: Main: 301703168 17:37:08 INFO - PROCESS | 1657 | 17:37:10 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49230/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:37:11 INFO - PROCESS | 1657 | RSS: Main: 277319680 17:37:11 INFO - PROCESS | 1657 | 17:37:12 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:37:13 INFO - PROCESS | 1657 | RSS: Main: 281182208 17:37:13 INFO - PROCESS | 1657 | 17:37:15 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:37:16 INFO - PROCESS | 1657 | RSS: Main: 284291072 17:37:16 INFO - PROCESS | 1657 | 17:37:19 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:37:20 INFO - PROCESS | 1657 | RSS: Main: 296734720 17:37:20 INFO - PROCESS | 1657 | 17:37:22 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:37:23 INFO - PROCESS | 1657 | RSS: Main: 294424576 17:37:23 INFO - PROCESS | 1657 | 17:37:25 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49230/tests/kraken/driver.html?testName=ai-astar) 17:37:25 INFO - PROCESS | 1657 | RSS: Main: 339095552 17:37:25 INFO - PROCESS | 1657 | 17:37:25 INFO - PROCESS | 1657 | __start_tp_report 17:37:25 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 17:37:25 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 17:37:25 INFO - PROCESS | 1657 | |i|pagename|runs| 17:37:25 INFO - PROCESS | 1657 | |0;ai-astar;116;117;116;116;117;117;117;117;115;117 17:37:25 INFO - PROCESS | 1657 | |1;audio-beat-detection;138;108;108;151;107;114;107;105;126;128 17:37:25 INFO - PROCESS | 1657 | |2;audio-dft;184;186;185;185;185;184;183;184;186;185 17:37:25 INFO - PROCESS | 1657 | |3;audio-fft;81;63;64;106;87;85;76;60;74;62 17:37:25 INFO - PROCESS | 1657 | |4;audio-oscillator;87;69;88;70;70;68;82;70;92;113 17:37:25 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;89;87;128;89;88;90;88;88;89;90 17:37:25 INFO - PROCESS | 1657 | |6;imaging-darkroom;97;95;96;96;95;96;96;108;151;149 17:37:25 INFO - PROCESS | 1657 | |7;imaging-desaturate;86;85;87;85;85;86;85;85;84;85 17:37:25 INFO - PROCESS | 1657 | |8;json-parse-financial;65;60;60;60;63;68;64;63;63;63 17:37:25 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;54;63;57;59;64;58;58;59;59;59 17:37:25 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;82;84;82;84;84;81;83;88;101;99 17:37:25 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;142;134;136;140;145;143;140;137;227;141 17:37:25 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;161;165;163;163;166;160;163;163;163;160 17:37:25 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;62;64;65;64;63;66;63;65;68;81 17:37:25 INFO - PROCESS | 1657 | __end_tp_report 17:37:25 INFO - PROCESS | 1657 | __start_cc_report 17:37:25 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,2249 17:37:25 INFO - PROCESS | 1657 | __end_cc_report 17:37:25 INFO - PROCESS | 1657 | __startTimestamp1496277445576__endTimestamp 17:37:25 INFO - PROCESS | 1657 | 17:37:25 INFO - PROCESS | 1657 | ------- Summary: start ------- 17:37:25 INFO - PROCESS | 1657 | Number of tests: 14 17:37:25 INFO - PROCESS | 1657 | 17:37:25 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:116.50 Median:117.00 stddev:0.71 (0.6%) stddev-sans-first:0.73 17:37:25 INFO - PROCESS | 1657 | Values: 116.0 117.0 116.0 116.0 117.0 117.0 117.0 117.0 115.0 117.0 17:37:25 INFO - PROCESS | 1657 | 17:37:25 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:119.20 Median:120.00 stddev:15.87 (13.2%) stddev-sans-first:15.30 17:37:25 INFO - PROCESS | 1657 | Values: 138.0 108.0 108.0 151.0 107.0 114.0 107.0 105.0 126.0 128.0 17:37:25 INFO - PROCESS | 1657 | 17:37:25 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:184.70 Median:185.00 stddev:0.95 (0.5%) stddev-sans-first:0.97 17:37:25 INFO - PROCESS | 1657 | Values: 184.0 186.0 185.0 185.0 185.0 184.0 183.0 184.0 186.0 185.0 17:37:25 INFO - PROCESS | 1657 | 17:37:25 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:75.80 Median:75.00 stddev:14.51 (19.4%) stddev-sans-first:15.27 17:37:25 INFO - PROCESS | 1657 | Values: 81.0 63.0 64.0 106.0 87.0 85.0 76.0 60.0 74.0 62.0 17:37:25 INFO - PROCESS | 1657 | 17:37:25 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:80.90 Median:76.00 stddev:14.56 (19.2%) stddev-sans-first:15.27 17:37:25 INFO - PROCESS | 1657 | Values: 87.0 69.0 88.0 70.0 70.0 68.0 82.0 70.0 92.0 113.0 17:37:25 INFO - PROCESS | 1657 | 17:37:25 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:92.60 Median:89.00 stddev:12.47 (14.0%) stddev-sans-first:13.16 17:37:25 INFO - PROCESS | 1657 | Values: 89.0 87.0 128.0 89.0 88.0 90.0 88.0 88.0 89.0 90.0 17:37:25 INFO - PROCESS | 1657 | 17:37:25 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:107.90 Median:96.00 stddev:22.52 (23.5%) stddev-sans-first:23.54 17:37:25 INFO - PROCESS | 1657 | Values: 97.0 95.0 96.0 96.0 95.0 96.0 96.0 108.0 151.0 149.0 17:37:25 INFO - PROCESS | 1657 | 17:37:25 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:85.30 Median:85.00 stddev:0.82 (1.0%) stddev-sans-first:0.83 17:37:25 INFO - PROCESS | 1657 | Values: 86.0 85.0 87.0 85.0 85.0 86.0 85.0 85.0 84.0 85.0 17:37:25 INFO - PROCESS | 1657 | 17:37:25 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:62.90 Median:63.00 stddev:2.51 (4.0%) stddev-sans-first:2.55 17:37:25 INFO - PROCESS | 1657 | Values: 65.0 60.0 60.0 60.0 63.0 68.0 64.0 63.0 63.0 63.0 17:37:25 INFO - PROCESS | 1657 | 17:37:25 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:59.00 Median:59.00 stddev:2.83 (4.8%) stddev-sans-first:2.35 17:37:25 INFO - PROCESS | 1657 | Values: 54.0 63.0 57.0 59.0 64.0 58.0 58.0 59.0 59.0 59.0 17:37:25 INFO - PROCESS | 1657 | 17:37:25 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:86.80 Median:84.00 stddev:7.22 (8.6%) stddev-sans-first:7.45 17:37:25 INFO - PROCESS | 1657 | Values: 82.0 84.0 82.0 84.0 84.0 81.0 83.0 88.0 101.0 99.0 17:37:25 INFO - PROCESS | 1657 | 17:37:25 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:148.50 Median:141.50 stddev:27.78 (19.6%) stddev-sans-first:29.37 17:37:25 INFO - PROCESS | 1657 | Values: 142.0 134.0 136.0 140.0 145.0 143.0 140.0 137.0 227.0 141.0 17:37:25 INFO - PROCESS | 1657 | 17:37:25 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.70 Median:163.00 stddev:1.95 (1.2%) stddev-sans-first:1.96 17:37:25 INFO - PROCESS | 1657 | Values: 161.0 165.0 163.0 163.0 166.0 160.0 163.0 163.0 163.0 160.0 17:37:25 INFO - PROCESS | 1657 | 17:37:25 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:65.00 stddev:5.51 (8.5%) stddev-sans-first:5.64 17:37:25 INFO - PROCESS | 1657 | Values: 62.0 64.0 65.0 64.0 63.0 66.0 63.0 65.0 68.0 81.0 17:37:25 INFO - PROCESS | 1657 | -------- Summary: end -------- 17:37:25 INFO - PROCESS | 1657 | 17:37:26 INFO - TEST-INFO | 1657: exit 0 17:37:26 INFO - TEST-OK | kraken | took 59460ms 17:37:26 INFO - SUITE-END | took 1178s 17:37:26 INFO - Completed test suite (00:19:39) 17:37:26 INFO - javascript benchmark 17:37:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7682.870336193746, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3329.9667003329964, 3438.9999999999986, 3440.4667276572122, 3458.325626502833, 3501.9474707879385, 5841.7955371562, 5883.911741323884, 5898.410158984099, 5915.792947246847, 5924.437178468044, 5899.115132730092, 5939.495142912858, 5944.673042982634, 5955.136505206753, 5973.850653733648, 4437.267850804614, 5320.361556613212, 5353.866153346178, 5420.674759514442, 5447.836564903039, 5286.259923610698, 5300.893982120355, 5324.41431442541, 5460.535854452376, 5472.069748142825, 4373.584509471595, 5313.495217954308, 5329.467053294678, 5350.946490535084, 5365.892682146355, 5389.5688344932705, 5432.375276843174, 5441.727913604304, 5453.945460545384, 5464.12573988164, 5263.289455923478, 5382.838514844521, 5391.973040134813, 5421.756020979026, 5422.53908417785, 5733.713314334308, 5759.5968282220265, 5811.360750317462, 5812.593118481708, 5926.318473375574, 6330.050492426127, 6347.682615869234, 6363.968180159116, 6365.0, 6367.885620016478, 6319.526035547352, 6394.904076438857, 6403.48772098231, 6405.711742971531, 6407.070974708688, 5899.439553242435, 5929.822105336847, 6051.364606716275, 6080.391960803929, 6126.050462178369, 6127.938720612826, 6356.809295721136, 6394.552381333262, 6414.0, 6452.35476452351, 6391.58454700443, 6395.392437718502, 6398.520110991694, 6405.711742971577, 6409.0066039763915, 6305.873882522324, 6315.115883776275, 6375.553711240262, 6404.48764098871, 6404.935950640434, 6250.0, 6328.778492752824, 6354.364563543609, 6362.840928976812, 6371.426571608485, 6302.1177035215105, 6340.619562826245, 6354.142190804275, 6373.0, 6381.936180638227, 4829.1790395632825, 4898.779554920037, 4904.8038078477175, 4909.729964851896, 4930.630202734809, 4412.558744125562, 4415.66882483815, 4420.292753159543, 4433.7118087324225, 4455.0, 5645.407232240637, 5666.9716651416475, 5722.284714410699, 5740.913886291709, 5764.365919748824, 6344.651044192614, 6354.523410744213, 6361.9045714314325, 6373.71318290678, 6382.329855365213, 5822.243108395882, 5827.562932779973, 5831.79588714393, 5846.0646296593195, 5882.970585147047, 5361.678299302054, 5483.369412517532, 5494.120940649477, 5549.417311182348, 5560.582956278296, 6373.139626150503, 6374.968125159345, 6411.903821442776, 6478.060681201294, 6558.344165583403, 6389.616623002635, 6391.552591318563, 6397.552171347961, 6397.616143031433, 6400.359964003563, 6462.256840463218, 6487.967560162169, 6519.902201466887, 6538.117354157318, 6553.311902250386, 6223.502119830312, 6243.157173781481, 6322.968385158045, 6334.4932405406535, 6376.4261216488885, 6212.192414986023, 6309.3690630936535, 6363.363663633785, 6385.10608514799, 6415.133956915756, 6180.505559555315, 6206.503479721701, 6241.9139746421815, 6259.374062593704, 6306.684665766785, 6349.619022858643, 6353.650549219838, 6385.138006369267, 6398.328175541687, 6404.135441715309, 5987.461128498456, 6038.245219347582, 6158.6304821709045, 6242.906356404748, 6246.718897649525, 6283.434490895841, 6373.553851230369, 6379.202599675041, 6383.234011918599, 6391.392817682313, 6214.9689251553455, 6226.40849119333, 6236.968815155895, 6239.0, 6245.18812554365], "value": 5839.106796088316, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [799.6561478564217, 855.7689423855555, 879.6833140069574, 892.5537231384304, 894.320316559415, 38883.222335553306, 39414.40878386823, 41274.0, 41329.79335103328, 41502.584974150246, 39239.215215695665, 39409.21181576374, 39443.40834887479, 39502.01244968877, 39554.0, 7517.135529414105, 8065.2741253287195, 8193.590320483981, 8195.139510351417, 8205.425620206588, 7586.544807311579, 11564.363959982198, 11820.468078936468, 11970.521179152824, 11978.640640780746, 11800.940995295012, 11808.055355571576, 11828.7634247315, 11834.93485586297, 11836.585719499819, 11701.47343369546, 11737.647870563897, 11738.823917641243, 11739.413029348498, 11753.823692644617, 11505.424728763528, 11539.30764154145, 11555.826662600068, 11578.826317605242, 11591.6522504325, 38971.80514097412, 39193.21613567741, 39223.80388098041, 39266.80366598177, 39411.0, 3524.436090225577, 3811.2377524495214, 3857.366007024232, 3884.1454879926373, 3948.3879998600264, 28055.018074367505, 28080.0, 29817.701822981708, 30423.847880760677, 30490.085297441114, 3162.288485090859, 3175.1109689287036, 3188.8246146461934, 3312.1222875937897, 3318.1206980150278, 337642.31178843946, 338338.0, 338895.0, 339250.0, 342724.0, 42231.577684223375, 43100.568994310284, 52310.0, 52354.738226308626, 52508.737456312476, 44162.779186103864, 55011.0, 55984.16023759647, 57978.0, 58558.12162817561, 14922.552323430315, 14985.0, 15019.849801502063, 15027.173505457306, 15029.173395463136, 12863.54977575799, 12879.61361159148, 12890.484380624857, 12911.22532648044, 12920.41858116387, 12454.688632784071, 12504.3747812609, 12506.18709783861, 12578.433970471167, 12606.0, 4825.420949486014, 5001.09936236989, 5679.722062535896, 5791.218185544982, 5797.9707102513785, 988.0190218611638, 997.6059456514781, 1061.9751939378536, 1071.49104175516, 1148.586508856811, 27819.860900695367, 28280.29299267535, 28583.856645734355, 28602.713972860005, 28673.28316792097, 1042.9362050708235, 1146.1346683254255, 1158.3860553906443, 1170.5434880396651, 1214.2532342609409, 12119.939400302943, 14290.714185716228, 14853.628659283388, 15179.696406071816, 16312.429064982669, 437200.0, 439117.8044109823, 439518.0, 439879.0, 440664.0, 50666.74666626643, 50764.23853642198, 50867.491325087016, 50936.745316273926, 51505.22742158871, 1405.676694360303, 1426.7431862264684, 1442.322108608952, 1444.826620805468, 1446.3419144289533, 23491.530169396858, 25638.230853074438, 27879.0, 28391.858040709663, 28528.57207141853, 41533.58466415297, 41602.791986039876, 41663.0, 41690.58309416867, 41732.0, 432647.8367608142, 434095.0, 434330.8283458562, 434536.0, 435819.0, 24371.75628243695, 24809.25572232836, 29102.0, 29712.55431168491, 29776.85111574428, 37951.81024094972, 38199.284609146365, 38356.849294521206, 39267.41098883462, 39451.60548394594, 26892.865535672197, 27417.31456713598, 28054.85972570124, 28109.297267568476, 28352.716472835833, 27236.04673836406, 27543.31141721393, 27565.448691026468, 28153.888458366113, 28325.575116373682, 31697.207569810016, 32312.192195195308, 32482.67517324891, 32521.186970325933, 32904.0, 22307.888460557595, 27664.17007489698, 28129.296767580974, 28254.434911301247, 28546.57180142258, 22407.77592224057, 22725.659115113616, 22992.102518253745, 23027.539449211257, 23168.95739691684], "value": 18498.94527519028, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [411.5863557125091, 413.70833562338555, 416.69581205719817, 419.5301262585903, 450.833191719064, 19584.51038724031, 20594.691079633798, 22115.778842211574, 22159.0, 22265.777342226593, 21583.46041348967, 21796.346109616738, 22671.77328226717, 22819.885900570473, 22867.885660571716, 6547.934520654792, 6816.113905192343, 6821.761238356658, 6835.863282734343, 6837.965810170942, 7394.70421183152, 7678.731244406447, 7844.41166912481, 7850.842983140334, 7856.882146767774, 7678.155402905676, 7798.922010779905, 7818.609069546528, 7841.6079196040255, 7911.723089691862, 7556.886646700304, 7813.179616140337, 7813.726519571816, 7886.80282992924, 7903.130655627876, 7661.655225514865, 7666.003419555422, 7670.27132422419, 7670.7315243966195, 7677.193894641038, 25333.0, 26202.08292709765, 26579.335516612045, 27343.45313093747, 27690.446191076266, 2949.808262462933, 2962.185399015288, 2970.6880777518268, 2986.940261194764, 2990.700929906992, 15797.368105275775, 16091.436799711953, 16974.660506789794, 17614.119294035376, 17660.911695441442, 1963.1558429875147, 2015.2140665140607, 2045.273927755655, 2045.8465615078928, 2101.3485819395883, 160302.0, 166034.3396566031, 167653.0, 169839.0, 173368.1331593334, 20503.4874128148, 20597.48506287325, 20757.273495427587, 20794.48013799667, 20966.16135354599, 33233.83383083117, 33911.66088339134, 34057.31885362279, 34099.829500852335, 34125.0, 10350.327228730108, 10373.222008349405, 10473.266871318934, 10613.5223914924, 10685.679429617125, 8724.0, 8755.956220218859, 8768.386212965159, 8936.374453788172, 8946.68686595966, 7935.801604959806, 9639.18066964316, 9640.084192001748, 9731.416115032978, 9877.209823214125, 3293.637699853014, 3423.640517745651, 4023.617756313204, 4067.877963661095, 4132.33882578786, 668.7258224128084, 690.8169335126196, 830.5847076461769, 883.0, 1117.7987962166887, 20690.379288621367, 22397.776022239894, 22472.55054898893, 22491.0, 22537.323880283617, 1072.2976450424985, 1076.962306319275, 1107.7869732642744, 1112.4604566785104, 1126.7239526316105, 10808.081313088318, 11261.0, 11452.484638191134, 11624.360660163604, 11659.88340116605, 600818.0, 603535.0, 751569.0, 759230.0, 780559.0, 95058.04941950631, 114664.56006319656, 134567.0, 152159.0, 156308.21845890698, 1341.2489006156584, 1358.8573199814075, 1396.3437184523257, 1416.6954104867502, 1418.5106138382241, 19963.301284454974, 20137.496562586053, 20534.794652053886, 20543.38369848907, 20592.485187870425, 61852.38147618466, 62668.746625066335, 62775.430614233184, 63271.0, 63518.0, 81352.0, 87924.1207587916, 89829.0, 90921.0, 91220.0, 11017.559297628006, 11364.090872729996, 11433.485493152662, 11517.25137866036, 11754.177207595387, 13054.412551435018, 13063.934680326538, 13335.066545341733, 13358.465661373824, 13530.985176111633, 11262.38056906878, 11297.774044519228, 11338.716532086764, 11505.252158609663, 11642.185047046625, 8252.119226139142, 10182.745431364276, 10420.739481513023, 10693.732656683645, 10721.946390267998, 11457.59898403552, 11541.480633371686, 11688.006519445942, 11704.531818727155, 11791.233569817934, 11386.373749443861, 11416.486258118572, 11452.427378630868, 11781.410929453665, 11873.940630296793, 11117.055050320814, 11231.943840280746, 11301.886981130412, 11452.647993762317, 11577.421128943688], "value": 11852.988269188492, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1372.4167228927706, 1440.3302464354076, 1565.1469948877861, 1611.6051567365996, 1615.474970634544, 10511.63209287675, 11079.667609971693, 11096.945790149943, 11098.833517497234, 11104.611338603141, 10610.098141657969, 11066.833997490045, 11170.385628790413, 11186.384748838811, 11220.382878941655, 2372.8813559322007, 2399.160293897131, 2401.267613377928, 2402.147237730607, 2405.4707964247837, 2000.8999550022513, 2303.49323148907, 2372.1223147435385, 2383.1778036577352, 2393.8324317297797, 2317.142657216832, 2385.3082606044313, 2389.8685572293516, 2398.400399900025, 2410.240774156144, 2323.686302349195, 2334.568104900599, 2351.43565544269, 2364.219807463533, 2365.266767302142, 2326.918557850484, 2354.4937838364835, 2355.222776483756, 2362.11420717231, 2367.763223677636, 10794.352338859693, 10856.565737370496, 11036.779264414667, 11066.39134847583, 11077.833832492519, 5594.964931487688, 5738.598298119134, 5756.165356023395, 5760.222369980041, 5763.682997435139, 3047.8933237336587, 3469.0, 3605.296967091418, 3666.7433279670436, 3679.0802299425145, 1199.6341115959628, 1209.2381799466368, 1232.3530146672979, 1250.7123361626877, 1275.132909621457, 13778.793318100237, 14115.929420352935, 14383.0, 15425.3829846806, 15687.294071766797, 3922.3332033554366, 4499.617532509707, 4689.413823272091, 4696.8590942271785, 4701.0, 11625.88374116265, 11626.360550169653, 11653.242539234923, 11655.475503602189, 11656.766864662659, 4814.542618451242, 4969.950300497021, 5511.338639363302, 5646.943530564724, 5671.602987790898, 5060.5951523878, 5175.353080864892, 5184.170532714747, 5239.580833533384, 5257.579393648499, 3768.9434658480145, 3775.131719704483, 3795.6963442925053, 3839.865604703822, 3848.9037774055873, 830.4975489828687, 987.234892957949, 1000.0749306891242, 1007.1388962437011, 1014.3406785589426, 802.1497212954287, 826.6238861318086, 835.2190701693814, 843.0978852627752, 844.994456596648, 5966.403359664086, 6556.606603603799, 6678.632675202911, 6767.018782276641, 7010.929890701028, 987.3088837813416, 1019.592163134752, 1039.5166247694858, 1063.1654151491043, 1114.8829372915889, 3561.269939662363, 4148.796848913781, 4233.428487154195, 4234.449521562177, 4351.456067991501, 13192.0, 13742.519011834538, 13768.655783605289, 13771.242581658105, 13778.173309601456, 13558.322083895766, 13560.321983900765, 13696.315184240748, 13703.040787145004, 13703.931480342733, 10059.899401006043, 10209.081182693742, 10390.532426040847, 10408.739781505372, 10410.115140213014, 6300.149479820166, 6550.475961922939, 6616.966915165393, 6648.301928297653, 6686.866262674816, 11967.700807479883, 12124.939375303067, 12458.31479268649, 12466.0, 12482.500699972263, 12369.072319576248, 12410.75178496443, 12640.936795315965, 12697.746045079231, 12698.873011269769, 9011.684591039544, 9283.767905802139, 9294.395864268801, 9296.860547091665, 9311.394759340892, 9932.900670993198, 10112.089911907964, 10230.437325947145, 10249.897501025192, 10251.487425628839, 8488.787780305542, 8705.085965973482, 8748.47509149453, 8794.42836215644, 8911.108889111296, 8466.111058338785, 8515.063343032385, 8525.966982774979, 8606.096359882122, 8626.482411055356, 7757.185495522889, 7758.262965018314, 7837.725679601187, 7864.21357864209, 7868.5672288022415, 5702.60081794287, 5730.799422020209, 5851.21008663842, 5881.353051164454, 5932.288125424804, 5699.544036477155, 5893.793717220048, 5918.940810591839, 5937.9406205937385, 6045.818625441244], "value": 5071.4801112011055, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [319.84967065479225, 371.67849809914435, 387.40726688167115, 388.3185010306911, 389.1031173145901, 4499.280115181567, 4888.315635810989, 4958.975205123969, 5183.904715274933, 5207.7866906564705, 4412.889677758058, 4450.443694538183, 4855.295982082596, 4882.584980276672, 4889.779959901804, 3607.188382613904, 3659.304732100892, 4011.338129208677, 4018.3570628699413, 4143.7720925349095, 4096.975756060985, 4238.765125070536, 4302.41917341158, 4333.58830911063, 4384.457014931348, 3905.6525498702904, 4223.725457845246, 4331.0, 4332.263515202408, 4369.257226271541, 4063.6545893599077, 4146.523149837747, 4244.554321796198, 4288.7212331198525, 4405.625521830648, 3509.9473507897146, 3585.910352241189, 3767.698584113292, 4048.8582899598373, 4163.5628259032665, 4017.5982401759884, 4061.939070913939, 4808.062427826575, 4917.655764096515, 4976.527229913188, 4640.79116439761, 4863.416390033183, 4907.975460122712, 5005.924911126336, 5079.263506791532, 4790.904181916342, 4931.580815630676, 5107.157319042353, 5337.946620533784, 5339.973300133474, 4148.128892932487, 4604.976975115103, 4637.0, 4692.601128904047, 4695.718256904596, 4935.235038569028, 5332.346972438557, 5707.400722924075, 5726.770929162829, 5792.841431713674, 4286.485621725413, 4302.634276086505, 4386.824527018884, 4533.0253995390485, 4546.909061818745, 4314.784260786948, 4418.668599854959, 4868.123737727243, 4870.125001874304, 4884.877878053006, 4382.890427739332, 4785.521447855256, 4935.260930183711, 5012.548870601663, 5096.884792810942, 4462.866114016585, 5232.2674825524455, 5255.868603284948, 5264.789408423697, 5301.330081024465, 1392.6518370407398, 1431.291510702209, 1550.2171403441416, 1644.9588760281088, 1670.938953764344, 1153.296489141623, 1234.5247079874314, 1240.8883200511998, 1321.0013269877325, 1376.7659497885384, 4440.666949978765, 4860.392450943632, 4862.854114376574, 4867.448935245557, 4895.681780684244, 5121.61587880903, 5256.712105534169, 5434.940186663642, 5572.1054841354135, 5585.553155747531, 4104.363823607346, 4438.956845141389, 4794.184988551954, 4803.735794531264, 4826.348442960226, 2475.4306509502553, 2809.946270148694, 2843.1328444824526, 2867.8279303241457, 2868.4693331733697, 4876.926846097312, 4988.476209997971, 4994.850154495371, 5508.705454218256, 5634.985702573576, 5373.0, 5591.329040515164, 5592.3009623797025, 5645.633033852787, 5659.236003139606, 4637.721736695809, 5130.563902068291, 5424.0, 5599.272094627672, 5684.744186511617, 4541.865323298045, 4975.875603109806, 5098.46466121052, 5329.786808527616, 5417.783288668409, 4594.793234304531, 4690.3199036138885, 4730.455997560324, 4794.969081647462, 4896.510348965075, 4881.608012074129, 5653.773849045992, 5661.46216109469, 5667.971660141673, 5669.858253543694, 4140.985458562551, 4365.1448134543325, 4378.452693413324, 4411.536788637147, 4493.887652808706, 5185.351831021122, 5204.412654140523, 5520.10832363175, 5571.882684579834, 5589.385167631639, 4653.557911998355, 4928.310036594808, 5123.74381280927, 5182.352205974254, 5334.733263336895, 4723.055388922298, 4910.558049775537, 4985.700857948535, 5363.865903352447, 5377.946220537745, 4939.407271127488, 4951.653384263067, 5000.624953128603, 5152.433232344364, 5244.5279924806955, 4531.637469002406, 4788.377510923697, 4807.134715751058, 5392.433794451526, 5402.486763757437, 4500.617447516866, 5252.5535329497425, 5280.0, 5304.525535774074, 5315.840524784263], "value": 4126.446701653815, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [776.7941495503693, 786.3787607789847, 818.8812622169789, 859.5272600069962, 868.3877866104397, 15590.532284031484, 16419.58951026225, 16452.67094658106, 16454.506364809044, 16455.094969776674, 14980.325885335158, 15086.547403577883, 15114.924425377885, 15123.0, 15146.545603631908, 949.4350861237552, 952.5094576293214, 954.3701157236226, 971.7230589282055, 996.282676472942, 965.5268918230087, 978.6525783346881, 982.8181786369508, 984.1585444445004, 984.2175470500969, 1024.5031159887485, 1025.4001409175642, 1025.4667572862106, 1026.3226270661364, 1026.573971801705, 1018.9847152292722, 1020.7907378987289, 1020.9846852297221, 1021.0096206679547, 1021.8109649714827, 688.3873352716087, 734.1997223026873, 764.1632412508294, 764.2892110337384, 765.9042619672541, 15495.612609684735, 15524.534263972098, 15625.374985000586, 16792.244349004322, 17075.316987320493, 14346.282685865664, 14450.132992020408, 14598.978071534997, 14643.780343294753, 14655.0, 11670.0, 11708.53165873364, 11812.704682382922, 11873.16887817853, 11949.701257468545, 3555.9466608000903, 3664.8900532984053, 3767.1523907120677, 3771.547414310273, 3801.7148713846295, 103863.4806825961, 112320.87679123267, 113004.0, 116413.41793291064, 116784.41607791907, 10137.898621013843, 10703.46482675863, 11129.721756956142, 11195.216334856483, 11432.085433165326, 23333.416664583183, 23608.40978975505, 23771.405714856923, 23804.642930356058, 23945.52108957811, 7896.052473703192, 7939.801504962422, 7954.761357159295, 8075.0, 8197.959010204911, 4358.193734159191, 4454.866354009385, 4531.18438681034, 4548.704334218265, 4599.0, 3026.3947210557976, 3536.929261414757, 3627.183883626163, 3639.363111455538, 3639.727020473475, 625.7747211004034, 635.1806170040596, 642.2613993906951, 648.0538413915735, 651.4234902111571, 667.7028722218566, 673.2560520624737, 698.2703075286338, 708.4686485136148, 711.5481669140133, 10340.689779306633, 10511.264211505275, 10650.414227217423, 10792.46037698112, 10820.4048777318, 655.1908393134476, 686.8488932434956, 690.7171797210268, 730.5945200413821, 740.0194741966808, 4608.28571571407, 4964.081644895706, 4984.950150498448, 5034.521720436553, 5057.190849464142, 103726.0, 103757.48121259345, 104757.95242047482, 104832.0, 105325.47337263418, 79599.60200198962, 81160.0, 81904.18095819085, 86766.56616716876, 88659.55670221608, 43128.49050283288, 46462.76768616135, 57584.71207643935, 65851.68296633945, 73598.26401735914, 10520.0, 11007.944960275147, 11210.303087829132, 11291.62875796092, 11361.54553817838, 48132.27801583049, 48571.27143092927, 48877.0, 49788.75528111826, 50183.0, 59886.0, 60920.972614074366, 62455.0, 62997.0, 63334.04998924927, 12387.256764594153, 12474.965663628418, 12730.23618582888, 12859.678508037374, 13124.606261812161, 12725.427355769198, 14083.0, 14438.278086095863, 14615.707685846011, 14811.555653330417, 13116.540921067717, 13256.668583285495, 13755.0, 13891.166530008231, 13893.305334733424, 13077.57292650209, 13805.309734513034, 13956.302184890512, 14111.22388268655, 14344.784828227794, 12604.30676312775, 13555.59333220005, 13728.794068089186, 14007.43970241179, 14213.715725685635, 9184.908150918405, 9190.586423611087, 9433.198178154658, 9469.479178644966, 9707.6116955321, 9974.700758977242, 10158.746031349276, 11300.265482743924, 11511.0, 11521.53913843437], "value": 7674.78166973596, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1448.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 116.0, 117.0, 117.0, 117.0, 117.0, 115.0, 117.0], "value": 116.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 108.0, 108.0, 151.0, 107.0, 114.0, 107.0, 105.0, 126.0, 128.0], "value": 119.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 186.0, 185.0, 185.0, 185.0, 184.0, 183.0, 184.0, 186.0, 185.0], "value": 184.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 63.0, 64.0, 106.0, 87.0, 85.0, 76.0, 60.0, 74.0, 62.0], "value": 75.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 69.0, 88.0, 70.0, 70.0, 68.0, 82.0, 70.0, 92.0, 113.0], "value": 80.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 128.0, 89.0, 88.0, 90.0, 88.0, 88.0, 89.0, 90.0], "value": 92.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 95.0, 96.0, 96.0, 95.0, 96.0, 96.0, 108.0, 151.0, 149.0], "value": 107.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 87.0, 85.0, 85.0, 86.0, 85.0, 85.0, 84.0, 85.0], "value": 85.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 60.0, 60.0, 60.0, 63.0, 68.0, 64.0, 63.0, 63.0, 63.0], "value": 62.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 63.0, 57.0, 59.0, 64.0, 58.0, 58.0, 59.0, 59.0, 59.0], "value": 59.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 82.0, 84.0, 84.0, 81.0, 83.0, 88.0, 101.0, 99.0], "value": 86.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 134.0, 136.0, 140.0, 145.0, 143.0, 140.0, 137.0, 227.0, 141.0], "value": 148.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 165.0, 163.0, 163.0, 166.0, 160.0, 163.0, 163.0, 163.0, 160.0], "value": 162.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 64.0, 65.0, 64.0, 63.0, 66.0, 63.0, 65.0, 68.0, 81.0], "value": 66.1, "unit": "score"}]}]} 17:37:26 INFO - Return code: 0 17:37:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:37:26 INFO - # TBPL SUCCESS # 17:37:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:37:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:37:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:37:26 INFO - 2017-05-31 17:37:26.876 screenresolution[1661:10566] starting screenresolution argv=screenresolution get 17:37:26 INFO - 2017-05-31 17:37:26.879 screenresolution[1661:10566] Display 0: 1600x1200x32@60 17:37:26 INFO - 2017-05-31 17:37:26.885 screenresolution[1662:10569] starting screenresolution argv=screenresolution list 17:37:26 INFO - Available Modes on Display 0 17:37:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:37:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:37:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:37:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:37:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:37:27 INFO - Intel Iris: 17:37:27 INFO - Chipset Model: Intel Iris 17:37:27 INFO - Type: GPU 17:37:27 INFO - Bus: Built-In 17:37:27 INFO - VRAM (Dynamic, Max): 1536 MB 17:37:27 INFO - Vendor: Intel (0x8086) 17:37:27 INFO - Device ID: 0x0a2e 17:37:27 INFO - Revision ID: 0x0009 17:37:27 INFO - Displays: 17:37:27 INFO - Display: 17:37:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:37:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:37:27 INFO - Display Serial Number: 1600x1200 60 17:37:27 INFO - Main Display: Yes 17:37:27 INFO - Mirror: Off 17:37:27 INFO - Online: Yes 17:37:27 INFO - Rotation: Supported 17:37:27 INFO - Return code: 0 17:37:27 INFO - Running post-action listener: _resource_record_post_action 17:37:27 INFO - [mozharness: 2017-06-01 00:37:27.124484Z] Finished run-tests step (success) 17:37:27 INFO - Running post-run listener: _resource_record_post_run 17:37:27 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 34257920; Write bytes: 510214144; Read time: 317; Write time: 1589 17:37:27 INFO - TinderboxPrint: CPU usage
53.7% 17:37:27 INFO - TinderboxPrint: I/O read bytes / time
34,257,920 / 317 17:37:27 INFO - TinderboxPrint: I/O write bytes / time
510,214,144 / 1,589 17:37:27 INFO - TinderboxPrint: CPU idle
2,222.4 (46.3%) 17:37:27 INFO - TinderboxPrint: CPU system
953.9 (19.9%) 17:37:27 INFO - TinderboxPrint: CPU user
1,625.2 (33.8%) 17:37:27 INFO - TinderboxPrint: Swap in / out
239,423,488 / 0 17:37:27 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 201140736; Write bytes: 199704576; Read time: 15612; Write time: 379 17:37:27 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 33258496; Write bytes: 300580864; Read time: 302; Write time: 1177 17:37:27 INFO - Running post-run listener: _upload_blobber_files 17:37:27 INFO - Blob upload gear active. 17:37:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:37:27 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 17:37:27 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'] 17:37:27 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 17:37:28 INFO - (blobuploader) - INFO - Open directory for files ... 17:37:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:37:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:37:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:37:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:37:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:37:28 INFO - (blobuploader) - INFO - Done attempting. 17:37:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:37:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:37:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:37:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:37:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:37:29 INFO - (blobuploader) - INFO - Done attempting. 17:37:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:37:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:37:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:37:30 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:37:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:37:30 INFO - (blobuploader) - INFO - Done attempting. 17:37:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:37:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:37:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:37:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:37:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:37:32 INFO - (blobuploader) - INFO - Done attempting. 17:37:32 INFO - (blobuploader) - INFO - Iteration through files over. 17:37:32 INFO - Return code: 0 17:37:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:37:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:37:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/08c27d95900bb2c4fad2e0118bf7e79c22217173367d1560578c6f2765ddbbce07cd9f5dbb1a897ec770f35bcfda9d55ad3a0c9ac462441e0c81126e18986677", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0c5ae5218a910acdbc4ef2617acf4bd5b5ad44af5bac2f7f4cee4fa3655e0acba99f699fe3f53fd2bdc221432ee99d588dde4bde1473f280f4b7119bb6aa8633", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7d0c4397c79bb3dafe60df46f86ae96aeeba65412a03c5f8ad3a4c026765c91926987596ccccbbc5d031b1e38a9bc91d6bbe6e685896ad6ee49ba63bd45919d6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/94bed2e484b8bc09c98bbbd2f93d8d48d3476e64c52f0a0730e350cbda7b91eb8663b82e783d408d0a2303739a0fb4120832043d6229c9ace9c7cdb66503f125"} 17:37:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:37:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:37:32 INFO - Contents: 17:37:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/08c27d95900bb2c4fad2e0118bf7e79c22217173367d1560578c6f2765ddbbce07cd9f5dbb1a897ec770f35bcfda9d55ad3a0c9ac462441e0c81126e18986677", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0c5ae5218a910acdbc4ef2617acf4bd5b5ad44af5bac2f7f4cee4fa3655e0acba99f699fe3f53fd2bdc221432ee99d588dde4bde1473f280f4b7119bb6aa8633", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7d0c4397c79bb3dafe60df46f86ae96aeeba65412a03c5f8ad3a4c026765c91926987596ccccbbc5d031b1e38a9bc91d6bbe6e685896ad6ee49ba63bd45919d6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/94bed2e484b8bc09c98bbbd2f93d8d48d3476e64c52f0a0730e350cbda7b91eb8663b82e783d408d0a2303739a0fb4120832043d6229c9ace9c7cdb66503f125"} 17:37:32 INFO - Running post-run listener: copy_logs_to_upload_dir 17:37:32 INFO - Copying logs to upload dir... 17:37:32 INFO - mkdir: /builds/slave/test/build/upload/logs 17:37:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1281.925121 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2017-05-31 17:37:32.658877) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-31 17:37:32.662494) ========= 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.NBCwwGkSpL/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.2zgtfVtzic/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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/08c27d95900bb2c4fad2e0118bf7e79c22217173367d1560578c6f2765ddbbce07cd9f5dbb1a897ec770f35bcfda9d55ad3a0c9ac462441e0c81126e18986677", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0c5ae5218a910acdbc4ef2617acf4bd5b5ad44af5bac2f7f4cee4fa3655e0acba99f699fe3f53fd2bdc221432ee99d588dde4bde1473f280f4b7119bb6aa8633", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7d0c4397c79bb3dafe60df46f86ae96aeeba65412a03c5f8ad3a4c026765c91926987596ccccbbc5d031b1e38a9bc91d6bbe6e685896ad6ee49ba63bd45919d6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/94bed2e484b8bc09c98bbbd2f93d8d48d3476e64c52f0a0730e350cbda7b91eb8663b82e783d408d0a2303739a0fb4120832043d6229c9ace9c7cdb66503f125"} build_url:https://queue.taskcluster.net/v1/task/RtuXsD5lQZyt47PxvEoHYg/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011361 build_url: 'https://queue.taskcluster.net/v1/task/RtuXsD5lQZyt47PxvEoHYg/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/08c27d95900bb2c4fad2e0118bf7e79c22217173367d1560578c6f2765ddbbce07cd9f5dbb1a897ec770f35bcfda9d55ad3a0c9ac462441e0c81126e18986677", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0c5ae5218a910acdbc4ef2617acf4bd5b5ad44af5bac2f7f4cee4fa3655e0acba99f699fe3f53fd2bdc221432ee99d588dde4bde1473f280f4b7119bb6aa8633", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7d0c4397c79bb3dafe60df46f86ae96aeeba65412a03c5f8ad3a4c026765c91926987596ccccbbc5d031b1e38a9bc91d6bbe6e685896ad6ee49ba63bd45919d6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/94bed2e484b8bc09c98bbbd2f93d8d48d3476e64c52f0a0730e350cbda7b91eb8663b82e783d408d0a2303739a0fb4120832043d6229c9ace9c7cdb66503f125"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-31 17:37:32.696732) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 17:37:32.697102) ========= 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.NBCwwGkSpL/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.2zgtfVtzic/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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.005300 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 17:37:32.745935) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-31 17:37:32.746264) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-31 17:37:32.746598) ========= ========= Total master_lag: 0.18 =========