builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs slave: t-yosemite-r7-0007 starttime: 1496057000.01 results: success (0) buildid: 20170529025115 builduid: d7dbdec079b24dfbad3997e1e98e8b26 revision: 715d88d0bd826c9e4a5403d6561a2fcc229dd208 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-29 04:23:20.011641) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-29 04:23:20.012296) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-29 04:23:20.012588) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-29 04:23:20.036685) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 04:23:20.037015) ========= 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.i5kNfndone/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.LCPmITI8ed/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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-29 04:23:20-- 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% 164M=0s 2017-05-29 04:23:20 (164 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.622209 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 04:23:20.680331) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 04:23:20.680696) ========= 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.i5kNfndone/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.LCPmITI8ed/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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.021980 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 04:23:20.729547) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 6 secs) (at 2017-05-29 04:23:20.729891) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 715d88d0bd826c9e4a5403d6561a2fcc229dd208 --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 715d88d0bd826c9e4a5403d6561a2fcc229dd208 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.i5kNfndone/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.LCPmITI8ed/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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-29 04:23:20,810 truncating revision to first 12 chars 2017-05-29 04:23:20,810 Setting DEBUG logging. 2017-05-29 04:23:20,810 attempt 1/10 2017-05-29 04:23:20,810 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/715d88d0bd82?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-29 04:23:23,155 unpacking tar archive at: mozilla-beta-715d88d0bd82/testing/mozharness/ program finished with exit code 0 elapsedTime=6.211521 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 6 secs) (at 2017-05-29 04:23:26.962991) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-29 04:23:26.963320) ========= script_repo_revision: 715d88d0bd826c9e4a5403d6561a2fcc229dd208 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-29 04:23:26.963699) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-29 04:23:26.963982) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-29 04:23:26.982594) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2017-05-29 04:23:26.982858) ========= /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.i5kNfndone/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.LCPmITI8ed/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 04:23:27 INFO - MultiFileLogger online at 20170529 04:23:27 in /builds/slave/test 04:23:27 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 04:23:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:23:27 INFO - {'append_to_log': False, 04:23:27 INFO - 'base_work_dir': '/builds/slave/test', 04:23:27 INFO - 'blob_upload_branch': 'Mozilla-Beta', 04:23:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:23:27 INFO - 'branch': 'Mozilla-Beta', 04:23:27 INFO - 'buildbot_json_path': 'buildprops.json', 04:23:27 INFO - 'config_files': ('talos/mac_config.py',), 04:23:27 INFO - 'default_actions': ('clobber', 04:23:27 INFO - 'read-buildbot-config', 04:23:27 INFO - 'download-and-extract', 04:23:27 INFO - 'populate-webroot', 04:23:27 INFO - 'create-virtualenv', 04:23:27 INFO - 'install', 04:23:27 INFO - 'run-tests'), 04:23:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:23:27 INFO - 'download_minidump_stackwalk': True, 04:23:27 INFO - 'download_symbols': 'ondemand', 04:23:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:23:27 INFO - 'tooltool.py': '/tools/tooltool.py', 04:23:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:23:27 INFO - '/tools/misc-python/virtualenv.py')}, 04:23:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:23:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:23:27 INFO - 'gecko_profile': False, 04:23:27 INFO - 'gecko_profile_interval': 0, 04:23:27 INFO - 'installer_path': 'installer.exe', 04:23:27 INFO - 'log_level': 'info', 04:23:27 INFO - 'log_name': 'talos', 04:23:27 INFO - 'log_to_console': True, 04:23:27 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:23:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:23:27 INFO - 'opt_config_files': (), 04:23:27 INFO - 'pip_index': False, 04:23:27 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:23:27 INFO - 'cmd': ('bash', 04:23:27 INFO - '-c', 04:23:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:23:27 INFO - 'enabled': True, 04:23:27 INFO - 'halt_on_failure': False, 04:23:27 INFO - 'name': 'check_screen_resolution'},), 04:23:27 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:23:27 INFO - 'cmd': ('bash', 04:23:27 INFO - '-c', 04:23:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:23:27 INFO - 'enabled': True, 04:23:27 INFO - 'halt_on_failure': False, 04:23:27 INFO - 'name': 'check_screen_resolution'},), 04:23:27 INFO - 'run_cmd_checks_enabled': True, 04:23:27 INFO - 'suite': 'dromaeojs', 04:23:27 INFO - 'system_bits': '32', 04:23:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:23:27 INFO - 'title': 't-yosemite-r7-0007', 04:23:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:23:27 INFO - 'use_talos_json': True, 04:23:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:23:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:23:27 INFO - 'work_dir': 'build'} 04:23:27 INFO - [mozharness: 2017-05-29 11:23:27.188772Z] Running clobber step. 04:23:27 INFO - Running pre-action listener: _resource_record_pre_action 04:23:27 INFO - Running main action method: clobber 04:23:27 INFO - rmtree: /builds/slave/test/build 04:23:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:23:29 INFO - Running post-action listener: _resource_record_post_action 04:23:29 INFO - [mozharness: 2017-05-29 11:23:29.570674Z] Finished clobber step (success) 04:23:29 INFO - [mozharness: 2017-05-29 11:23:29.570805Z] Running read-buildbot-config step. 04:23:29 INFO - Running pre-action listener: _resource_record_pre_action 04:23:29 INFO - Running main action method: read_buildbot_config 04:23:29 INFO - Using buildbot properties: 04:23:29 INFO - { 04:23:29 INFO - "project": "", 04:23:29 INFO - "product": "firefox", 04:23:29 INFO - "script_repo_revision": "production", 04:23:29 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs", 04:23:29 INFO - "repository": "", 04:23:29 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs", 04:23:29 INFO - "buildid": "20170529025115", 04:23:29 INFO - "pgo_build": "False", 04:23:29 INFO - "basedir": "/builds/slave/test", 04:23:29 INFO - "buildnumber": 0, 04:23:29 INFO - "slavename": "t-yosemite-r7-0007", 04:23:29 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 04:23:29 INFO - "platform": "yosemite_r7_devedition", 04:23:29 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 04:23:29 INFO - "branch": "mozilla-beta", 04:23:29 INFO - "repo_path": "releases/mozilla-beta", 04:23:29 INFO - "revision": "715d88d0bd826c9e4a5403d6561a2fcc229dd208", 04:23:29 INFO - "stage_platform": "macosx64-devedition", 04:23:29 INFO - "builduid": "d7dbdec079b24dfbad3997e1e98e8b26", 04:23:29 INFO - "slavebuilddir": "test" 04:23:29 INFO - } 04:23:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/JUNOfCotQ22MMyMNx_CQqg/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 04:23:29 INFO - Running post-action listener: _resource_record_post_action 04:23:29 INFO - [mozharness: 2017-05-29 11:23:29.574064Z] Finished read-buildbot-config step (success) 04:23:29 INFO - [mozharness: 2017-05-29 11:23:29.574176Z] Running download-and-extract step. 04:23:29 INFO - Running pre-action listener: _resource_record_pre_action 04:23:29 INFO - Running main action method: download_and_extract 04:23:29 INFO - mkdir: /builds/slave/test/build/tests 04:23:29 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')]} 04:23:29 INFO - trying https://queue.taskcluster.net/v1/task/JUNOfCotQ22MMyMNx_CQqg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 04:23:29 INFO - Downloading https://queue.taskcluster.net/v1/task/JUNOfCotQ22MMyMNx_CQqg/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 04:23:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JUNOfCotQ22MMyMNx_CQqg/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 04:23:31 INFO - Downloaded 1287 bytes. 04:23:31 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 04:23:31 INFO - Using the following test package requirements: 04:23:31 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:23:31 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 04:23:31 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 04:23:31 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:23:31 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 04:23:31 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:23:31 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 04:23:31 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 04:23:31 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:23:31 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 04:23:31 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 04:23:31 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:23:31 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 04:23:31 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:23:31 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 04:23:31 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:23:31 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 04:23:31 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:23:31 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 04:23:31 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 04:23:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JUNOfCotQ22MMyMNx_CQqg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 04:23:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JUNOfCotQ22MMyMNx_CQqg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 04:23:31 INFO - Fetch https://queue.taskcluster.net/v1/task/JUNOfCotQ22MMyMNx_CQqg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 04:23:33 INFO - Expected file size: 51963674 04:23:33 INFO - Obtained file size: 51963674 04:23:49 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 04:23:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JUNOfCotQ22MMyMNx_CQqg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 04:23:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JUNOfCotQ22MMyMNx_CQqg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 04:23:49 INFO - Fetch https://queue.taskcluster.net/v1/task/JUNOfCotQ22MMyMNx_CQqg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 04:23:50 INFO - Expected file size: 51963674 04:23:50 INFO - Obtained file size: 51963674 04:24:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JUNOfCotQ22MMyMNx_CQqg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 04:24:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JUNOfCotQ22MMyMNx_CQqg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 04:24:06 INFO - Fetch https://queue.taskcluster.net/v1/task/JUNOfCotQ22MMyMNx_CQqg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 04:24:08 INFO - Expected file size: 14404461 04:24:08 INFO - Obtained file size: 14404461 04:24: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')]} 04:24:08 INFO - trying https://queue.taskcluster.net/v1/task/JUNOfCotQ22MMyMNx_CQqg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 04:24:08 INFO - Downloading https://queue.taskcluster.net/v1/task/JUNOfCotQ22MMyMNx_CQqg/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 04:24:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JUNOfCotQ22MMyMNx_CQqg/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 04:24:10 INFO - Downloaded 74895525 bytes. 04:24:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JUNOfCotQ22MMyMNx_CQqg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 04:24:10 INFO - mkdir: /builds/slave/test/properties 04:24:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:24:10 INFO - Writing to file /builds/slave/test/properties/build_url 04:24:10 INFO - Contents: 04:24:10 INFO - build_url:https://queue.taskcluster.net/v1/task/JUNOfCotQ22MMyMNx_CQqg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 04:24:13 INFO - Running post-action listener: _resource_record_post_action 04:24:13 INFO - Running post-action listener: set_extra_try_arguments 04:24:13 INFO - [mozharness: 2017-05-29 11:24:13.234518Z] Finished download-and-extract step (success) 04:24:13 INFO - [mozharness: 2017-05-29 11:24:13.234630Z] Running populate-webroot step. 04:24:13 INFO - Running pre-action listener: _resource_record_pre_action 04:24:13 INFO - Running main action method: populate_webroot 04:24:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:24:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:24:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:24:13 INFO - '--tppagecycles', 04:24:13 INFO - '7'], 04:24:13 INFO - 'tests': ['tsvgm']}}, 04:24:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:24:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:24:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:24:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:24:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:24:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:24:13 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:24:13 INFO - 'talos_options': ['--disable-e10s'], 04:24:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:24:13 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:24:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:24:13 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:24:13 INFO - 'talos_options': ['--disable-e10s'], 04:24:13 INFO - 'tests': ['damp', 'tps']}, 04:24:13 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:24:13 INFO - 'tests': ['damp', 'tps']}, 04:24:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:24:13 INFO - 'tests': ['dromaeo_dom']}, 04:24:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:24:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:24:13 INFO - 'tests': ['basic_compositor_video']}, 04:24:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:24:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:24:13 INFO - 'tests': ['a11yr', 04:24:13 INFO - 'ts_paint', 04:24:13 INFO - 'tpaint', 04:24:13 INFO - 'sessionrestore', 04:24:13 INFO - 'sessionrestore_no_auto_restore', 04:24:13 INFO - 'tabpaint']}, 04:24:13 INFO - 'other-e10s': {'tests': ['a11yr', 04:24:13 INFO - 'ts_paint', 04:24:13 INFO - 'tpaint', 04:24:13 INFO - 'sessionrestore', 04:24:13 INFO - 'sessionrestore_no_auto_restore', 04:24:13 INFO - 'tabpaint']}, 04:24:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:24:13 INFO - 'ts_paint', 04:24:13 INFO - 'tpaint', 04:24:13 INFO - 'sessionrestore', 04:24:13 INFO - 'sessionrestore_no_auto_restore', 04:24:13 INFO - 'tabpaint']}, 04:24:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:24:13 INFO - 'ts_paint', 04:24:13 INFO - 'tpaint', 04:24:13 INFO - 'sessionrestore', 04:24:13 INFO - 'sessionrestore_no_auto_restore', 04:24:13 INFO - 'tabpaint']}, 04:24:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:24:13 INFO - 'tests': ['a11yr', 04:24:13 INFO - 'ts_paint', 04:24:13 INFO - 'tpaint', 04:24:13 INFO - 'sessionrestore', 04:24:13 INFO - 'sessionrestore_no_auto_restore', 04:24:13 INFO - 'tabpaint']}, 04:24:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:24:13 INFO - 'tests': ['a11yr', 04:24:13 INFO - 'ts_paint', 04:24:13 INFO - 'tpaint', 04:24:13 INFO - 'sessionrestore', 04:24:13 INFO - 'sessionrestore_no_auto_restore', 04:24:13 INFO - 'tabpaint']}, 04:24:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:24:13 INFO - 'tests': ['tsvgx', 04:24:13 INFO - 'tsvgr_opacity', 04:24:13 INFO - 'tart', 04:24:13 INFO - 'tscrollx', 04:24:13 INFO - 'cart', 04:24:13 INFO - 'tsvg_static']}, 04:24:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:24:13 INFO - 'tsvgr_opacity', 04:24:13 INFO - 'tart', 04:24:13 INFO - 'tscrollx', 04:24:13 INFO - 'cart', 04:24:13 INFO - 'tsvg_static']}, 04:24:13 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:24:13 INFO - 'talos_options': ['--disable-e10s'], 04:24:13 INFO - 'tests': ['tp5o']}, 04:24:13 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:24:13 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:24:13 INFO - 'talos_options': ['--disable-e10s', 04:24:13 INFO - '--xperf_path', 04:24:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:24:13 INFO - 'tests': ['tp5n']}, 04:24:13 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:24:13 INFO - 'talos_options': ['--xperf_path', 04:24:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:24:13 INFO - 'tests': ['tp5n']}}, 04:24:13 INFO - 'talos.zip': {'path': '', 04:24:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:24:13 INFO - Running post-action listener: _resource_record_post_action 04:24:13 INFO - [mozharness: 2017-05-29 11:24:13.253125Z] Finished populate-webroot step (success) 04:24:13 INFO - [mozharness: 2017-05-29 11:24:13.253266Z] Running create-virtualenv step. 04:24:13 INFO - Running pre-action listener: _resource_record_pre_action 04:24:13 INFO - Running main action method: create_virtualenv 04:24:13 INFO - Creating virtualenv /builds/slave/test/build/venv 04:24:13 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 04:24:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:24:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:24:13 INFO - Using real prefix '/tools/python27' 04:24:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:24:14 INFO - Installing distribute.............................................................................................................................................................................................done. 04:24:17 INFO - Installing pip.................done. 04:24:17 INFO - Return code: 0 04:24:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:24:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:24:17 INFO - Reading from file tmpfile_stdout 04:24:17 INFO - Output received: 04:24:17 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) 04:24:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:24:17 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')]} 04:24:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:24:17 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')]} 04:24:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:24:17 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 0x10b27fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b282768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7facc348e5b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b05dd30>, '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 0x10af84db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b052be8>, '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.i5kNfndone/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.LCPmITI8ed/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 04:24:17 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 04:24:17 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 04:24:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.i5kNfndone/Render', 04:24:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:24:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:24:17 INFO - 'HOME': '/Users/cltbld', 04:24:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:24:17 INFO - 'LOGNAME': 'cltbld', 04:24:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:24:17 INFO - 'MOZ_NO_REMOTE': '1', 04:24:17 INFO - 'NO_EM_RESTART': '1', 04:24:17 INFO - 'PAGER': '/bin/cat', 04:24:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:24:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:24:17 INFO - 'PWD': '/builds/slave/test', 04:24:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:24:17 INFO - 'SHELL': '/bin/bash', 04:24:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LCPmITI8ed/Listeners', 04:24:17 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 04:24:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:24:17 INFO - 'USER': 'cltbld', 04:24:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:24:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:24:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:24:17 INFO - 'XPC_FLAGS': '0x0', 04:24:17 INFO - 'XPC_SERVICE_NAME': '0', 04:24:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:24:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:24:17 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 04:24:17 INFO - Cleaning up... 04:24:17 INFO - Return code: 0 04:24:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:24:17 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')]} 04:24:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:24:17 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')]} 04:24:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:24:17 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 0x10b27fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b282768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7facc348e5b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b05dd30>, '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 0x10af84db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b052be8>, '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.i5kNfndone/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.LCPmITI8ed/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 04:24:17 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 04:24:17 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 04:24:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.i5kNfndone/Render', 04:24:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:24:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:24:17 INFO - 'HOME': '/Users/cltbld', 04:24:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:24:17 INFO - 'LOGNAME': 'cltbld', 04:24:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:24:17 INFO - 'MOZ_NO_REMOTE': '1', 04:24:17 INFO - 'NO_EM_RESTART': '1', 04:24:17 INFO - 'PAGER': '/bin/cat', 04:24:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:24:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:24:17 INFO - 'PWD': '/builds/slave/test', 04:24:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:24:17 INFO - 'SHELL': '/bin/bash', 04:24:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LCPmITI8ed/Listeners', 04:24:17 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 04:24:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:24:17 INFO - 'USER': 'cltbld', 04:24:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:24:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:24:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:24:17 INFO - 'XPC_FLAGS': '0x0', 04:24:17 INFO - 'XPC_SERVICE_NAME': '0', 04:24:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:24:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:24:17 INFO - Downloading/unpacking psutil>=3.1.1 04:24:17 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 04:24:17 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 04:24:17 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 04:24:17 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 04:24:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:24:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:24:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:24:20 INFO - Installing collected packages: psutil 04:24:20 INFO - Running setup.py install for psutil 04:24:20 INFO - building 'psutil._psutil_osx' extension 04:24:20 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 04:24:20 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 04:24:20 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 04:24:21 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 04:24:21 INFO - building 'psutil._psutil_posix' extension 04:24:21 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 04:24:21 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:24:21 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:24:21 INFO - ^ 04:24:21 INFO - 1 warning generated. 04:24:21 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 04:24:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:24:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:24:21 INFO - Successfully installed psutil 04:24:21 INFO - Cleaning up... 04:24:21 INFO - Return code: 0 04:24:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:24: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')]} 04:24:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:24: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')]} 04:24:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:24:21 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 0x10b27fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b282768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7facc348e5b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b05dd30>, '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 0x10af84db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b052be8>, '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.i5kNfndone/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.LCPmITI8ed/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 04:24:21 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 04:24:21 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 04:24:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.i5kNfndone/Render', 04:24:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:24:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:24:21 INFO - 'HOME': '/Users/cltbld', 04:24:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:24:21 INFO - 'LOGNAME': 'cltbld', 04:24:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:24:21 INFO - 'MOZ_NO_REMOTE': '1', 04:24:21 INFO - 'NO_EM_RESTART': '1', 04:24:21 INFO - 'PAGER': '/bin/cat', 04:24:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:24:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:24:21 INFO - 'PWD': '/builds/slave/test', 04:24:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:24:21 INFO - 'SHELL': '/bin/bash', 04:24:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LCPmITI8ed/Listeners', 04:24:21 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 04:24:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:24:21 INFO - 'USER': 'cltbld', 04:24:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:24:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:24:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:24:21 INFO - 'XPC_FLAGS': '0x0', 04:24:21 INFO - 'XPC_SERVICE_NAME': '0', 04:24:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:24:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:24:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:24:21 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 04:24:21 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 04:24:21 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 04:24:21 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 04:24:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:24:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:24:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:24:23 INFO - Installing collected packages: mozsystemmonitor 04:24:23 INFO - Running setup.py install for mozsystemmonitor 04:24:23 INFO - Successfully installed mozsystemmonitor 04:24:23 INFO - Cleaning up... 04:24:23 INFO - Return code: 0 04:24:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:24:23 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')]} 04:24:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:24:23 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')]} 04:24:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:24:23 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 0x10b27fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b282768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7facc348e5b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b05dd30>, '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 0x10af84db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b052be8>, '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.i5kNfndone/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.LCPmITI8ed/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 04:24:23 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 04:24:23 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 04:24:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.i5kNfndone/Render', 04:24:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:24:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:24:23 INFO - 'HOME': '/Users/cltbld', 04:24:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:24:23 INFO - 'LOGNAME': 'cltbld', 04:24:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:24:23 INFO - 'MOZ_NO_REMOTE': '1', 04:24:23 INFO - 'NO_EM_RESTART': '1', 04:24:23 INFO - 'PAGER': '/bin/cat', 04:24:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:24:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:24:23 INFO - 'PWD': '/builds/slave/test', 04:24:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:24:23 INFO - 'SHELL': '/bin/bash', 04:24:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LCPmITI8ed/Listeners', 04:24:23 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 04:24:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:24:23 INFO - 'USER': 'cltbld', 04:24:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:24:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:24:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:24:23 INFO - 'XPC_FLAGS': '0x0', 04:24:23 INFO - 'XPC_SERVICE_NAME': '0', 04:24:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:24:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:24:23 INFO - Downloading/unpacking jsonschema==2.5.1 04:24: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 04:24: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 04:24: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 04:24: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 04:24:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:24:26 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:24:26 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 04:24:26 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 04:24:26 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 04:24:26 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 04:24:26 INFO - Downloading functools32-3.2.3-2.tar.gz 04:24:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:24:26 INFO - warning: no files found matching '*.txt' 04:24:26 INFO - no previously-included directories found matching 'build' 04:24:26 INFO - no previously-included directories found matching 'dist' 04:24:26 INFO - no previously-included directories found matching '.git*' 04:24:26 INFO - Installing collected packages: jsonschema, functools32 04:24:26 INFO - Running setup.py install for functools32 04:24:26 INFO - warning: no files found matching '*.txt' 04:24:26 INFO - no previously-included directories found matching 'build' 04:24:26 INFO - no previously-included directories found matching 'dist' 04:24:26 INFO - no previously-included directories found matching '.git*' 04:24:26 INFO - Successfully installed jsonschema functools32 04:24:26 INFO - Cleaning up... 04:24:26 INFO - Return code: 0 04:24:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:24:26 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')]} 04:24:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:24:26 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')]} 04:24:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:24:26 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 0x10b27fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b282768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7facc348e5b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b05dd30>, '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 0x10af84db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b052be8>, '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.i5kNfndone/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.LCPmITI8ed/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 04:24:26 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 04:24:26 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 04:24:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.i5kNfndone/Render', 04:24:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:24:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:24:26 INFO - 'HOME': '/Users/cltbld', 04:24:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:24:26 INFO - 'LOGNAME': 'cltbld', 04:24:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:24:26 INFO - 'MOZ_NO_REMOTE': '1', 04:24:26 INFO - 'NO_EM_RESTART': '1', 04:24:26 INFO - 'PAGER': '/bin/cat', 04:24:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:24:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:24:26 INFO - 'PWD': '/builds/slave/test', 04:24:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:24:26 INFO - 'SHELL': '/bin/bash', 04:24:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LCPmITI8ed/Listeners', 04:24:26 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 04:24:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:24:26 INFO - 'USER': 'cltbld', 04:24:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:24:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:24:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:24:26 INFO - 'XPC_FLAGS': '0x0', 04:24:26 INFO - 'XPC_SERVICE_NAME': '0', 04:24:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:24:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:24:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:24:27 INFO - Cleaning up... 04:24:27 INFO - Return code: 0 04:24:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:24:27 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')]} 04:24:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:24:27 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')]} 04:24:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:24:27 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 0x10b27fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b282768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7facc348e5b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b05dd30>, '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 0x10af84db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b052be8>, '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.i5kNfndone/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.LCPmITI8ed/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 04:24:27 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 04:24:27 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 04:24:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.i5kNfndone/Render', 04:24:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:24:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:24:27 INFO - 'HOME': '/Users/cltbld', 04:24:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:24:27 INFO - 'LOGNAME': 'cltbld', 04:24:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:24:27 INFO - 'MOZ_NO_REMOTE': '1', 04:24:27 INFO - 'NO_EM_RESTART': '1', 04:24:27 INFO - 'PAGER': '/bin/cat', 04:24:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:24:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:24:27 INFO - 'PWD': '/builds/slave/test', 04:24:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:24:27 INFO - 'SHELL': '/bin/bash', 04:24:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LCPmITI8ed/Listeners', 04:24:27 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 04:24:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:24:27 INFO - 'USER': 'cltbld', 04:24:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:24:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:24:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:24:27 INFO - 'XPC_FLAGS': '0x0', 04:24:27 INFO - 'XPC_SERVICE_NAME': '0', 04:24:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:24:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:24:27 INFO - Downloading/unpacking blobuploader==1.2.4 04:24:27 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 04:24:27 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 04:24:27 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 04:24:27 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 04:24:28 INFO - Downloading blobuploader-1.2.4.tar.gz 04:24:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:24:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:24:28 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 04:24:28 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 04:24:28 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 04:24:28 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 04:24:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:24:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:24:29 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 04:24:29 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 04:24:29 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 04:24:29 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 04:24:29 INFO - Downloading docopt-0.6.1.tar.gz 04:24:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:24:29 INFO - Installing collected packages: blobuploader, requests, docopt 04:24:29 INFO - Running setup.py install for blobuploader 04:24:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:24:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:24:29 INFO - Running setup.py install for requests 04:24:30 INFO - Running setup.py install for docopt 04:24:30 INFO - Successfully installed blobuploader requests docopt 04:24:30 INFO - Cleaning up... 04:24:30 INFO - Return code: 0 04:24:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:24:30 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')]} 04:24:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:24:30 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')]} 04:24:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:24:30 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 0x10b27fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b282768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7facc348e5b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b05dd30>, '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 0x10af84db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b052be8>, '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.i5kNfndone/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.LCPmITI8ed/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 04:24:30 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 04:24:30 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 04:24:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.i5kNfndone/Render', 04:24:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:24:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:24:30 INFO - 'HOME': '/Users/cltbld', 04:24:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:24:30 INFO - 'LOGNAME': 'cltbld', 04:24:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:24:30 INFO - 'MOZ_NO_REMOTE': '1', 04:24:30 INFO - 'NO_EM_RESTART': '1', 04:24:30 INFO - 'PAGER': '/bin/cat', 04:24:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:24:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:24:30 INFO - 'PWD': '/builds/slave/test', 04:24:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:24:30 INFO - 'SHELL': '/bin/bash', 04:24:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LCPmITI8ed/Listeners', 04:24:30 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 04:24:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:24:30 INFO - 'USER': 'cltbld', 04:24:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:24:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:24:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:24:30 INFO - 'XPC_FLAGS': '0x0', 04:24:30 INFO - 'XPC_SERVICE_NAME': '0', 04:24:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:24:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:24:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:24:30 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-5DZfIe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:24:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:24:30 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-hMuDRW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:24:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:24:30 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-7xLCCZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:24:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:24:31 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-RjOLM0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:24:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:24:31 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-eqbcnm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:24:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:24:31 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-wt7ne6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:24:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:24:31 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-w5QInk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:24:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:24:31 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-lWnjtm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:24:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:24:31 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-0fW09f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:24:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:24:31 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-ignH6f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:24:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:24:31 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-5VHtwg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:24:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:24:31 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-EhiZyk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:24:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:24:31 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-eRLT1B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:24:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:24:32 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-wFsByM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:24:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:24:32 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-c9QXgx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:24:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:24:32 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-2uEs_c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:24:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:24:32 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-FUfP4U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:24:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:24:32 INFO - Running setup.py install for manifestparser 04:24:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:24:32 INFO - Running setup.py install for mozcrash 04:24:32 INFO - Running setup.py install for mozdebug 04:24:32 INFO - Running setup.py install for mozdevice 04:24:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:24:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:24:33 INFO - Running setup.py install for mozfile 04:24:33 INFO - Running setup.py install for mozhttpd 04:24:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:24:33 INFO - Running setup.py install for mozinfo 04:24:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:24:33 INFO - Running setup.py install for mozInstall 04:24:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:24:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:24:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:24:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:24:33 INFO - Running setup.py install for mozleak 04:24:33 INFO - Running setup.py install for mozlog 04:24:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:24:34 INFO - Running setup.py install for moznetwork 04:24:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:24:34 INFO - Running setup.py install for mozprocess 04:24:34 INFO - Running setup.py install for mozprofile 04:24:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:24:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:24:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:24:34 INFO - Running setup.py install for mozrunner 04:24:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:24:34 INFO - Running setup.py install for mozscreenshot 04:24:34 INFO - Running setup.py install for moztest 04:24:34 INFO - Running setup.py install for mozversion 04:24:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:24:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:24:35 INFO - Cleaning up... 04:24:35 INFO - Return code: 0 04:24:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:24:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:24:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:24:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:24:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:24:35 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 0x10b27fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b282768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7facc348e5b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b05dd30>, '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 0x10af84db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b052be8>, '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.i5kNfndone/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.LCPmITI8ed/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 04:24:35 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 04:24:35 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 04:24:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.i5kNfndone/Render', 04:24:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:24:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:24:35 INFO - 'HOME': '/Users/cltbld', 04:24:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:24:35 INFO - 'LOGNAME': 'cltbld', 04:24:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:24:35 INFO - 'MOZ_NO_REMOTE': '1', 04:24:35 INFO - 'NO_EM_RESTART': '1', 04:24:35 INFO - 'PAGER': '/bin/cat', 04:24:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:24:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:24:35 INFO - 'PWD': '/builds/slave/test', 04:24:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:24:35 INFO - 'SHELL': '/bin/bash', 04:24:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LCPmITI8ed/Listeners', 04:24:35 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 04:24:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:24:35 INFO - 'USER': 'cltbld', 04:24:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:24:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:24:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:24:35 INFO - 'XPC_FLAGS': '0x0', 04:24:35 INFO - 'XPC_SERVICE_NAME': '0', 04:24:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:24:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:24:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:24:35 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-cln7Vn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:24:35 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)) 04:24:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:24:35 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-g6Um7l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:24:35 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)) 04:24:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:24:35 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-8pCeA2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:24:35 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)) 04:24:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:24:35 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-bWd033-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:24:35 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)) 04:24:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:24:35 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-7IT9vq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:24:35 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)) 04:24:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:24:35 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-U38bkX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:24:36 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)) 04:24:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:24:36 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-glY_6K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:24:36 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)) 04:24:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:24:36 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-wWOJsp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:24:36 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)) 04:24:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:24:36 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-tW2_gc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:24:36 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)) 04:24:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:24:36 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-KzoRW9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:24:36 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)) 04:24:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:24:36 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-YWzFmD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:24:36 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)) 04:24:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:24:36 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-90ssIl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:24:36 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)) 04:24:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:24:36 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-PRkiaJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:24:36 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)) 04:24:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:24:36 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-UlawEB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:24:36 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)) 04:24:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:24:36 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-az4lX5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:24:36 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)) 04:24:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:24:36 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-zDL24Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:24:37 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)) 04:24:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:24:37 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-zXis0e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:24:37 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)) 04:24:37 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)) 04:24:37 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)) 04:24:37 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)) 04:24:37 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)) 04:24:37 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)) 04:24:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:24:37 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 04:24:37 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 04:24:37 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 04:24:37 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 04:24:38 INFO - Downloading blessings-1.6.tar.gz 04:24:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:24:38 INFO - Installing collected packages: blessings 04:24:38 INFO - Running setup.py install for blessings 04:24:38 INFO - Successfully installed blessings 04:24:38 INFO - Cleaning up... 04:24:38 INFO - Return code: 0 04:24:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:24:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:24:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:24:39 INFO - Reading from file tmpfile_stdout 04:24:39 INFO - Current package versions: 04:24:39 INFO - blessings == 1.6 04:24:39 INFO - blobuploader == 1.2.4 04:24:39 INFO - docopt == 0.6.1 04:24:39 INFO - functools32 == 3.2.3-2 04:24:39 INFO - jsonschema == 2.5.1 04:24:39 INFO - manifestparser == 1.1 04:24:39 INFO - mozInstall == 1.12 04:24:39 INFO - mozcrash == 1.0 04:24:39 INFO - mozdebug == 0.1 04:24:39 INFO - mozdevice == 0.49 04:24:39 INFO - mozfile == 1.2 04:24:39 INFO - mozhttpd == 0.7 04:24:39 INFO - mozinfo == 0.9 04:24:39 INFO - mozleak == 0.1 04:24:39 INFO - mozlog == 3.4 04:24:39 INFO - moznetwork == 0.27 04:24:39 INFO - mozprocess == 0.25 04:24:39 INFO - mozprofile == 0.28 04:24:39 INFO - mozrunner == 6.13 04:24:39 INFO - mozscreenshot == 0.1 04:24:39 INFO - mozsystemmonitor == 0.3 04:24:39 INFO - moztest == 0.8 04:24:39 INFO - mozversion == 1.4 04:24:39 INFO - psutil == 3.1.1 04:24:39 INFO - requests == 1.2.3 04:24:39 INFO - wsgiref == 0.1.2 04:24:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:24:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:24:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:24:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:24:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:24:39 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 0x10b27fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b282768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7facc348e5b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b05dd30>, '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 0x10af84db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b052be8>, '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.i5kNfndone/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.LCPmITI8ed/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 04:24:39 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 04:24:39 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 04:24:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.i5kNfndone/Render', 04:24:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:24:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:24:39 INFO - 'HOME': '/Users/cltbld', 04:24:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:24:39 INFO - 'LOGNAME': 'cltbld', 04:24:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:24:39 INFO - 'MOZ_NO_REMOTE': '1', 04:24:39 INFO - 'NO_EM_RESTART': '1', 04:24:39 INFO - 'PAGER': '/bin/cat', 04:24:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:24:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:24:39 INFO - 'PWD': '/builds/slave/test', 04:24:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:24:39 INFO - 'SHELL': '/bin/bash', 04:24:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LCPmITI8ed/Listeners', 04:24:39 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 04:24:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:24:39 INFO - 'USER': 'cltbld', 04:24:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:24:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:24:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:24:39 INFO - 'XPC_FLAGS': '0x0', 04:24:39 INFO - 'XPC_SERVICE_NAME': '0', 04:24:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:24:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:24:39 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)) 04:24:39 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)) 04:24:39 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)) 04:24:39 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)) 04:24:39 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)) 04:24:39 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)) 04:24:39 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)) 04:24:39 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)) 04:24:39 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)) 04:24:39 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)) 04:24:39 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)) 04:24:39 INFO - Cleaning up... 04:24:39 INFO - Return code: 0 04:24:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:24:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:24:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:24:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:24:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:24:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b27fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b282768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7facc348e5b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b05dd30>, '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 0x10af84db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b052be8>, '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.i5kNfndone/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.LCPmITI8ed/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 04:24:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:24:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:24:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.i5kNfndone/Render', 04:24:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:24:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:24:39 INFO - 'HOME': '/Users/cltbld', 04:24:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:24:39 INFO - 'LOGNAME': 'cltbld', 04:24:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:24:39 INFO - 'MOZ_NO_REMOTE': '1', 04:24:39 INFO - 'NO_EM_RESTART': '1', 04:24:39 INFO - 'PAGER': '/bin/cat', 04:24:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:24:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:24:39 INFO - 'PWD': '/builds/slave/test', 04:24:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:24:39 INFO - 'SHELL': '/bin/bash', 04:24:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LCPmITI8ed/Listeners', 04:24:39 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 04:24:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:24:39 INFO - 'USER': 'cltbld', 04:24:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:24:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:24:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:24:39 INFO - 'XPC_FLAGS': '0x0', 04:24:39 INFO - 'XPC_SERVICE_NAME': '0', 04:24:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:24:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:24:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:24:39 INFO - Cleaning up... 04:24:39 INFO - Return code: 0 04:24:39 INFO - Running post-action listener: _resource_record_post_action 04:24:39 INFO - Running post-action listener: _start_resource_monitoring 04:24:39 INFO - Starting resource monitoring. 04:24:39 INFO - [mozharness: 2017-05-29 11:24:39.843027Z] Finished create-virtualenv step (success) 04:24:39 INFO - [mozharness: 2017-05-29 11:24:39.843988Z] Running install step. 04:24:39 INFO - Running pre-action listener: _resource_record_pre_action 04:24:39 INFO - Running main action method: install 04:24:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:24:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:24:40 INFO - Reading from file tmpfile_stdout 04:24:40 INFO - Detecting whether we're running mozinstall >=1.0... 04:24:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:24:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:24:40 INFO - Reading from file tmpfile_stdout 04:24:40 INFO - Output received: 04:24:40 INFO - Usage: mozinstall [options] installer 04:24:40 INFO - Options: 04:24:40 INFO - -h, --help show this help message and exit 04:24:40 INFO - -d DEST, --destination=DEST 04:24:40 INFO - Directory to install application into. [default: 04:24:40 INFO - "/builds/slave/test"] 04:24:40 INFO - --app=APP Application being installed. [default: firefox] 04:24:40 INFO - mkdir: /builds/slave/test/build/application 04:24:40 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'] 04:24:40 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 04:25:00 INFO - Reading from file tmpfile_stdout 04:25:00 INFO - Output received: 04:25:00 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 04:25:00 INFO - Running post-action listener: _resource_record_post_action 04:25:00 INFO - [mozharness: 2017-05-29 11:25:00.422124Z] Finished install step (success) 04:25:00 INFO - [mozharness: 2017-05-29 11:25:00.422261Z] Running run-tests step. 04:25:00 INFO - Running pre-action listener: _resource_record_pre_action 04:25:00 INFO - Running main action method: run_tests 04:25:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:25:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:25:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:25:00 INFO - 2017-05-29 04:25:00.436 screenresolution[1924:16688] starting screenresolution argv=screenresolution get 04:25:00 INFO - 2017-05-29 04:25:00.439 screenresolution[1924:16688] Display 0: 1600x1200x32@60 04:25:00 INFO - 2017-05-29 04:25:00.445 screenresolution[1925:16692] starting screenresolution argv=screenresolution list 04:25:00 INFO - Available Modes on Display 0 04:25:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:25:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:25:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:25:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:25:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:25:00 INFO - Intel Iris: 04:25:00 INFO - Chipset Model: Intel Iris 04:25:00 INFO - Type: GPU 04:25:00 INFO - Bus: Built-In 04:25:00 INFO - VRAM (Dynamic, Max): 1536 MB 04:25:00 INFO - Vendor: Intel (0x8086) 04:25:00 INFO - Device ID: 0x0a2e 04:25:00 INFO - Revision ID: 0x0009 04:25:00 INFO - Displays: 04:25:00 INFO - Display: 04:25:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:25:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:25:00 INFO - Display Serial Number: 1600x1200 60 04:25:00 INFO - Main Display: Yes 04:25:00 INFO - Mirror: Off 04:25:00 INFO - Online: Yes 04:25:00 INFO - Rotation: Supported 04:25:00 INFO - Return code: 0 04:25:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:25:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:25:00 INFO - Python 2.7.3 04:25:00 INFO - Return code: 0 04:25:00 INFO - grabbing minidump binary from tooltool 04:25:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:00 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 0x10b05dd30>, '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 0x10af84db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b052be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:25:00 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 04:25:00 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 04:25:00 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 04:25:00 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:25:00 INFO - Return code: 0 04:25:00 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:25:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:25:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:25:00 INFO - ENV: RUST_BACKTRACE is now 1 04:25:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:25:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:25:00 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/JUNOfCotQ22MMyMNx_CQqg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0007', '--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 04:25:00 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/JUNOfCotQ22MMyMNx_CQqg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0007 --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 04:25:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.i5kNfndone/Render', 04:25:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:25:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:25:00 INFO - 'HOME': '/Users/cltbld', 04:25:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:25:00 INFO - 'LOGNAME': 'cltbld', 04:25:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:25:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:25:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:00 INFO - 'MOZ_NO_REMOTE': '1', 04:25:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:25:00 INFO - 'NO_EM_RESTART': '1', 04:25:00 INFO - 'PAGER': '/bin/cat', 04:25:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:25:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:00 INFO - 'PWD': '/builds/slave/test', 04:25:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:25:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:25:00 INFO - 'RUST_BACKTRACE': '1', 04:25:00 INFO - 'SHELL': '/bin/bash', 04:25:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LCPmITI8ed/Listeners', 04:25:00 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 04:25:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:25:00 INFO - 'USER': 'cltbld', 04:25:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:25:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:25:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:00 INFO - 'XPC_FLAGS': '0x0', 04:25:00 INFO - 'XPC_SERVICE_NAME': '0', 04:25:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:25:00 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/JUNOfCotQ22MMyMNx_CQqg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0007', '--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 04:25:01 INFO - mozversion application_buildid: 20170529025115 04:25:01 INFO - mozversion application_changeset: 715d88d0bd826c9e4a5403d6561a2fcc229dd208 04:25:01 INFO - mozversion application_display_name: FirefoxDeveloperEdition 04:25:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:25:01 INFO - mozversion application_name: Firefox 04:25:01 INFO - mozversion application_remotingname: firefox-dev 04:25:01 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 04:25:01 INFO - mozversion application_vendor: Mozilla 04:25:01 INFO - mozversion application_version: 54.0 04:25:01 INFO - mozversion platform_buildid: 20170529025115 04:25:01 INFO - mozversion platform_changeset: 715d88d0bd826c9e4a5403d6561a2fcc229dd208 04:25:01 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 04:25:01 INFO - mozversion platform_version: 54.0 04:25:01 INFO - using testdate: 1496057101 04:25:01 INFO - actual date: 1496057101 04:25:01 INFO - starting webserver on 'localhost:49355' 04:25:01 INFO - SUITE-START | Running 2 tests 04:25:01 INFO - TEST-START | dromaeo_css 04:25:01 INFO - operating with platform_type : mac_ 04:25:01 INFO - Initialising browser for dromaeo_css test... 04:25:01 INFO - TEST-INFO | started process 1935 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/tmpxMwfsj/profile http://localhost:49355/getInfo.html) 04:25:03 INFO - PROCESS | 1935 | __metrics Screen width/height:1600/1200 04:25:03 INFO - PROCESS | 1935 | colorDepth:24 04:25:03 INFO - PROCESS | 1935 | Browser inner width/height: 1024/689 04:25:03 INFO - PROCESS | 1935 | __metrics 04:25:03 INFO - PROCESS | 1935 | 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" 04:25:03 INFO - PROCESS | 1935 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:25:04 INFO - TEST-INFO | 1935: exit 0 04:25:04 INFO - Browser initialized. 04:25:04 INFO - Running cycle 1/1 for dromaeo_css test... 04:25:04 INFO - TEST-INFO | started process 1938 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/tmpxMwfsj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:25:16 INFO - PROCESS | 1938 | RSS: Main: 193679360 04:25:16 INFO - PROCESS | 1938 | 04:28:19 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:49355/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49355/tests/dromaeo/cssquery-ext.html) 04:28:21 INFO - PROCESS | 1938 | RSS: Main: 302190592 04:28:21 INFO - PROCESS | 1938 | 04:31:24 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:49355/tests/dromaeo/cssquery-ext.html (next: http://localhost:49355/tests/dromaeo/cssquery-jquery.html) 04:31:25 INFO - PROCESS | 1938 | RSS: Main: 324952064 04:31:25 INFO - PROCESS | 1938 | 04:34:27 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:49355/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49355/tests/dromaeo/cssquery-mootools.html) 04:34:28 INFO - PROCESS | 1938 | RSS: Main: 309895168 04:34:28 INFO - PROCESS | 1938 | 04:37:30 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:49355/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49355/tests/dromaeo/cssquery-prototype.html) 04:37:31 INFO - PROCESS | 1938 | RSS: Main: 293294080 04:37:31 INFO - PROCESS | 1938 | 04:40:34 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:49355/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49355/tests/dromaeo/cssquery-yui.html) 04:40:35 INFO - PROCESS | 1938 | RSS: Main: 293863424 04:40:35 INFO - PROCESS | 1938 | 04:43:38 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:49355/tests/dromaeo/cssquery-yui.html (next: http://localhost:49355/tests/dromaeo/cssquery-dojo.html) 04:43:38 INFO - PROCESS | 1938 | RSS: Main: 366510080 04:43:38 INFO - PROCESS | 1938 | 04:43:38 INFO - PROCESS | 1938 | __start_tp_report 04:43:38 INFO - PROCESS | 1938 | _x_x_mozilla_page_load 04:43:38 INFO - PROCESS | 1938 | _x_x_mozilla_page_load_details 04:43:38 INFO - PROCESS | 1938 | |i|pagename|runs| 04:43:38 INFO - PROCESS | 1938 | |0;dojo.html;2866.7849911256653;3308.454105072665;3334.766566340354;3374.510695949087;3385.390629686657;5863.560232982527;5880.647161170327;5882.970585147068;5886.2347894773675;5910.615809972349;5845.123231515275;5904.763809447617;5905.6751878646655;5918.822435326936;5936.168936348904;5312.495312945273;5354.303940487731;5382.811601593945;5405.4324295948945;5428.67427954324;5284.180951952453;5306.124489459235;5332.733363331838;5470.781168753245;5479.972600136994;5364.409914909357;5374.113271310229;5374.946250537484;5411.458854114577;5412.675239485624;5445.292112025451;5472.616916815825;5477.780888764445;5485.561155107622;5506.834794956158;5321.760520776575;5420.214068959979;5423;5429.104197807396;5486.643368181095;5076.619253555961;5598.440155984369;5733.140028995643;6058.212432383762;6069.57512974092;6313.2424109107205;6352.333005034498;6368.554201205918;6381;6391.968040159816;6348.3334249903555;6415.973444248898;6421.8073457796345;6431.742730290783;6438.678066096676;5866.178734977107;6037.7584896604085;6039.9396006039815;6086.878262434727;6109.694515274218;6368.363163683687;6370.490360771128;6419.807405777834;6447.387498187664;6515.185601799775;6371.904421433682;6382.936170638327;6390.233172019294;6393.680315984182;6415.582987105823;6344.714487848058;6346.682665866688;6417.262014868256;6418.582792118497;6423.582467139621;6236.314005459396;6259.405356491217;6331.651759153198;6394.9680251599375;6473.482121430275;6280.277768056732;6321.620702757849;6321.747130114744;6365.522585806083;6366.108744775735;4909.165441874889;4909.975450122726;4910.745502312491;4912.680675756064;4930.087933732271;4390;4392.802323895432;4408.096340250241;4411.889702757457;4441.689081764245;5606.943930560724;5652.773889044474;5713.342965558931;5715.799947001835;5791.5656325775735;6332.905006424908;6346.682665866688;6352.237731472161;6360.204974378203;6367.61794292344;5722.542196624343;5733.111367738007;5807.796727114531;5830.854228644318;5835.503982161564;5463.972680136653;5519.917201241985;5529.447055294519;5532.889342213133;5542.196381524737;6299.023651334051;6540.771073012612;6548.934510654833;6564.474842012628;6595.241547222131;6330.49356051524;6358.872822543616;6360.618362898241;6403.583767055126;6433.356664333716;6509.511786616117;6549.508786841101;6568.474522038321;6574.276829548841;6589.538732288694;6396.392342727433;6398.360163983564;6398.840028999312;6402.455791257796;6430.871382572416;6294.3076261612105;6335.239771227482;6381.106645069601;6385.010323399694;6385.393387628168;6199.008158694767;6308.053791931247;6338.65137417428;6343.365663433619;6384.776532821329;6308.716107775253;6342.52431067681;6349.269833969152;6358.968205158944;6366.808995730135;6242.094896240111;6254.343293954252;6258.061290806416;6259.843503912439;6260.154879091265;6040.335563088145;6125.937554509019;6201.472874805511;6226.750929962751;6357.491400687842;6196;6206.565540412308;6217;6226.470749986302;6226.937730622636 04:43:38 INFO - PROCESS | 1938 | |1;ext.html;810.4488947515689;828.3207769628905;883.3683916000062;893.4728510178993;899.4962820820346;42043.78978105109;43213.56786432135;44697;44721.552784472144;44762.6571202864;44346;44574.88562785932;44595.554044459546;44626.33060504087;44757.10485790282;7634.923650763504;9557.28320375971;9718.465484398355;9719.61121555137;9721.562529686145;7599.582022988733;11637.30176189427;11931.761364772743;12047.45786439608;12080;11838.822417663741;11867.52529898803;11873.346965916871;11889.692133865312;11910.702232444215;10683.14534837211;11161.665150045512;11854.170208085437;11888.048956083536;11904.226225295328;11366.37484938328;11410.657680269522;11430;11436;11446.828297575543;41163.76508704744;41462.79268603668;41541.37687934683;41565.792171039255;41658.79170604158;3358.4626459766564;3875.1280961783655;3954.6638535724496;3983.581723918976;4016.638502534786;28199;28199.295017624518;28215.717842821516;29525.40949181026;30532.69467305343;3017.4568577656014;3151.543026261202;3189.1229911774217;3243.6431992480807;3270.73834093272;372727;375305;375527.1223643892;375771;375847;43709.56290437119;43896.12207755827;54815.72592137014;54917.45082549203;54991.45008549943;39962.00189990489;46231;46680.26557875362;46781.5321846784;46802.06395872063;14741.705165896621;14952.92523537397;15162.848371516364;15200.695986080216;15250.923745381424;12815.91064759506;12818.102732808615;12870.356482176041;12878.678033049062;12887.48450061987;12765.744685106432;12783.552575659807;12814.103012789015;12835.486580536674;12839.871601283867;5292.259083728281;5417.3770016447825;5641.294838145232;5832.970835145882;5874.5887787854435;708.2810946888912;721.6973363079692;764.6406189091118;848.9872651910226;853.4068822168533;19965.900170499055;24353.269401917616;25292.241232763048;26043.218703438928;26096.60855087175;749.539033494397;947.4362754161263;1265.8227848101274;1300.07694536878;1307.0981023094034;14198.574042778733;14229.715405691828;14385.992980491474;14473.85526144725;14478.203698796457;480959;482053;484585;487927;488886;60328.698356508816;60573.78852422927;60673.786524269264;60681.39318606757;60911.39088609146;1462.8464011278663;1502.4891536877076;1516.0676184146892;1519.4985654733912;1522.9466968656043;25149.371265718502;26522.939082436485;27135.72864271332;27136;27149.321266968484;49444.50555494399;49598;49676.75161624169;49761.75119124526;49766.755831104514;438797;443056;445415;446175;446509;26747.86626066935;33380.33239335151;33537.82617608372;33566.66433335733;33597.32805343928;45341.77329113333;45754;45967.850803728834;46201.30698039465;47005.529944701484;26568.53962613464;26849.328766780985;30176.69823301739;30558.236044099074;31467.68532314739;30356;30732.385352292382;30825.845870770503;31845.681543184273;31882;29862.701372985994;29956.25109372196;30437;31293;37788.24423511569;25054.749452505243;29195.41609167847;33302.33395332031;33555.49666755049;34435;22391.77608223897;23657.29028129159;24166.637500437155;24611.384715382257;26157.953681852512 04:43:38 INFO - PROCESS | 1938 | |2;jquery.html;390.0891418537714;403.3324847377591;413.53890412190395;424.0797469491203;428.4644194756554;11705.712371639112;13017.088803783727;21113.68329475059;21742;21948.792816395093;22808;23046.308610741664;23510;23521.176758813446;23786.881065594647;5942.821715348525;6622.966885165568;6741.865162696743;6806.931930680679;6854.314568543131;4193.119444916571;4514.571115743999;6783.389494945454;6918.930810691904;6928.168619765635;4448.421705178338;8029.919700803005;8030.718924837632;8036.316913062396;8049.839003219932;7800.570968596725;7901.407394445382;7936.880946785803;7975.920240797575;8025.518468891856;7459.328660420588;7653.617319134021;7748.7675369739;7768.300852923264;7769.53382797034;27606.585901211296;27712.861435692896;27725.722742772516;27746.861265693544;27907.441851162865;3037.9088627341216;3068.5704001439817;3112.439760843047;3141.607299087614;3144.716975472196;16111.516654500383;16371.754423683655;16388.180590970405;16982.235799388935;17251.482455526355;1980.3167907072036;2061.5567652954537;2153.870767753924;2202.757696653367;2209.49181688211;95116;122848;132345.33827330897;132756;157112;19527;19976.700349494768;20089.799102009085;20092.497687557927;20144.697829532568;30819.537706934414;31160.844195778875;31379.215519612193;31457.528137077963;31487.21281967969;10283.125934295518;10356.17150627948;10516.737081572868;10617.097546708463;10631.149508039338;7579.711449053674;8422.24199822019;8753.956230218808;8755.518446485505;8929.687460938836;9246.86129708055;9310.348275620641;9425.340226184102;9465.810683786285;9520.952395237979;2949.926251843678;3526.7531272810656;3977.7414468059483;4040.544985199338;4061.5329237137516;888.9422187557788;910.2354022620898;911.3165126155384;943.099340130174;957.2868213181224;21238.575228495345;21445.78554214469;21756.782432175474;21766;21922.58851526331;925.921296689789;942.7501712046313;963.2486660404895;963.2631037256365;969.2439896880445;11003.17476189289;11327.830082548768;11379.715507112389;11426.542938282542;11455.59905403307;686672;749952;759757;761277;762704;138120;149664;156018.21990889971;164984;165731.1713441449;1268.5940499040403;1341.1416693316285;1363.638635761524;1369.383777300219;1370.1094288712218;19907.502312442306;20100.59798804045;20448.488787780425;20526.486837829172;20537.48656283605;55651.72174139103;56166.87666246734;63080.68459657672;65333.673331633036;66335;57786.7110664444;84439.5778021106;85664.71502927586;88047.55976220334;88240.11759882318;11070.335779853234;11528.942355288169;11551.711207219887;11858.525658973544;12005.819912701141;10768.51541680642;11077.836827133357;12741.617751467471;13333.399997000352;13370.331483425594;11279.548818047515;11352;11430.657080287605;11534.826977595176;11578.76842463163;10465.9476702616;10471.581136754445;10746.516406761872;10757.408342541235;10761.89238107609;11106.555737770723;11419.942900285445;11533.596324128615;11637.650870473899;11685.649430517098;11538.134639901873;11587.478563464496;11831.881681183078;11888.048956083665;11915.225510342147;10668.786624267315;11059.557617695202;11113.110951123728;11334.14993875446;11517.596884109016 04:43:38 INFO - PROCESS | 1938 | |3;mootools.html;1362.9522966696165;1383.4673650644504;1548.736714758491;1579.6208909861637;1594.9282282297295;10681.145508359332;11255;11272.661820145388;11276.267042642223;11286.943565282161;10941.726456838584;10954.342739435639;11257.831132533018;11394.25937314074;11401.657950261506;2446.926592202237;2452.2030340139436;2454.055188752325;2457.3610861176044;2458.864762438065;2366.4438856868624;2429.0283886445477;2442.3039433761382;2454.22691852066;2456.987715061422;2357.95284094318;2402.1112188490283;2409.4819613783034;2446.889889954947;2451.4974430241846;2074.6618431111924;2273.4771002669313;2323.4307594639254;2388.4984153327823;2425.575524283261;2373.988130059339;2397.700287464067;2397.9040838366445;2405.2183040511727;2411.5538625354366;10852.186086043579;11087.390193539351;11116.221864469491;11123.388213648246;11126.4993075311;5764.250647415809;5937.52499800015;5940.881182376328;5943.900378430004;5948.524118070544;3548.8580456781697;3590.622984586633;3632.382494975834;3665.7617254878614;3671.76133551321;1162.2096974057638;1170.5025364220173;1206.9215500992407;1217.2209785737136;1237.7648246833073;13777.173369597756;14140.505082322172;14368.209748463934;14700.926495367561;14936.25318734059;4636.83771068011;4782.139214941274;4788.592969597623;4796.088743138862;4818.638602104857;11792.056635469142;11796.938275555241;11799.233049851731;11802.763944721057;11817.172797904235;4935.914098898236;5102.566281866083;5142.202958541432;5816.447437493432;5816.970915145397;4485.304777759387;5277.454404346145;5293.073712100367;5306.469353064663;5331.813386531453;3577.910552236163;3714.7771133732062;3834.10211945089;3890.3191941410705;3943.2310699413338;1020.5917632946732;1022.4018948914968;1024.329064462778;1034.5654824973528;1037.4708898461688;931.9347645664739;936.4755736787421;955.5461155950868;966.2849491376331;1007.5314978535016;5848.152017957373;6188.8762224755255;6644.235912869985;6716.026176204423;7050.717971281194;976.8485884687884;990.9752256193509;1024.2215915903967;1026.1841835740604;1080.2492267873752;4205.495340559152;4226.1336426032585;4228.682848786353;4252.596003379674;4329.437173167531;13198.47206111764;13813.51652692151;13817.309134543233;13833.584992450242;13838;13205;13500.93249533746;13754.724905501835;13789.655258618615;13808.826249768681;10102.595896164217;10123.544440500047;10192.847107293397;10344.741381465523;10351.861295257515;6510.088587597835;6556.377144171296;6577.408033277028;6587.308332625005;6740.831479213059;11964.76070478603;12179;12319.44562494672;12365.319907405179;12483.625491235278;9738.951305243712;12450.81323780126;12606.621801345975;12672.74654506923;12701;8473.66105355779;8494.235518803338;8654.956725216334;8656.48061116335;8675.305975521816;10211.23415743837;10428.687139385831;10432.217583681406;10437.895621043692;10451.790964180826;8604.225619694258;8916.955415223141;8928.10718928128;8987.730368088967;8993.370464067453;7221.169565500035;8743.475391476532;8818;8822;9022;6752.763653272112;7853;8035.67857285702;8046;8052.758417247492;5882.558808089325;5902.852428689317;5943.791967281124;6112;6125.754969801158;5695.860827834533;5912.940870591411;6007.969960150346;6188.350223226496;6331.810045698637 04:43:38 INFO - PROCESS | 1938 | |4;prototype.html;383.57423260181196;385.11615841643436;388.7162371468829;392.42313798715895;396.74410005546423;4853.514648535145;5037.1688671369275;5109.912070343724;5121.5646670033;5123.897522049557;4061.6141466560703;4414.852138444004;5019.372578427697;5051.141305977983;5083.491650834914;3401.9829900850586;3956.4065390191486;3989.7207195496326;3998.3802510610903;4006;3689.631036896316;3805.6004119567324;4027.395890616416;4058.147788964321;4075.4498142750645;3439.931201375971;3546.982265088671;3809.047738065484;4029.81865816039;4087.795610219492;3350.4471762159214;3403.0811680846155;4008.2584721826265;4026.3759117336567;4060.37584966013;2856.4572731181215;3164.844831636436;3364.2094107884627;3561.6794488495902;3575.4458059000895;4297.979949423766;4909.484504127086;4952.192449755849;4965.864678188377;4973.383650313626;3962.7820469874146;4505.52691967342;4654.883627909296;4659.836905708284;4724.5984091352275;4844.467108618049;4857.878553036202;4995.075910956485;5142.071723103836;5384.980632262901;3638.872639457633;3700.4449332600057;3969.7221194516396;3976;3978.8806335809973;5413.864653383697;5465.398806131283;5651.943480565183;5706.686132262724;5786.797462088806;4144.274751918462;4322.740635561876;4406.294992801137;4821.927671084936;4884.853454396374;4035.7982100895424;4152.750834949913;4381.539938306496;4909.085456671901;4987.850364489072;4179.665626749853;4336.479622445327;4425.933610995838;4750.928736068961;4810.350602668665;5183.585313174937;5374.951884382508;5392.218128371443;5392.757325920343;5459.2629994950175;1366.3987845348015;1600.663860589266;1608.8230294667578;1631.6247263129546;1689.7803285572797;1194.1760185471996;1250.724840535099;1299.91550549214;1329.876254564891;1333.714206912847;4338.414314067623;4511.503734589258;4561.589456948891;4754.453237877619;4790.173797459515;4393.692441529126;5490.399792212002;5532.612717109844;5568.49883510486;5661.858453538694;4231.894202644959;4236.046889449849;4496.95388598187;4795.976020119877;4830.3237546743485;2625.186192280399;2693.5959606058855;2788.47642883191;2857.9428411431654;2890.841003744772;5099.909037294691;5676.153561831238;5681.630694004876;5686.488216060575;5692.857678557986;5119.105930805626;5173.473825221568;5284.784499565121;5351.732413379315;5678.801241956594;5148.665336753099;5306.97346513265;5355.785768569292;5721.141828725725;5814.854628634318;4670.719756814602;4950.77721502526;5035.496450355082;5465.617406781487;5562.332520097614;4740.6444516660695;5323.520883120537;5384.380796208329;5425.294711687456;5483.259759932358;4720.583824852599;5331.733413329395;5429.782808687608;5460.5085542301385;5764.15547024948;4272.636825869836;4639.628829693549;4793.9280910787065;4855.975720121377;4928.236123400879;4767;5286.889753151881;5446.727663616883;5450.373207081237;5455.027010279487;4547.681662283609;5102.311187989574;5124.180131178993;5225.498915726548;5305.708186049805;4915.483874193301;5169.948300517097;5429.565634749131;5438.401775804579;5461.808836690697;4439.511653718153;4548.818047278071;5003.574696150722;5058.6206034548295;5301.038615712211;4649.930251046305;5418.965379387556;5436.075867102523;5467.343918729778;5469.453054694658;4548.499665036909;4632.328312394751;5060.22891987816;5282.207668849704;5401.594880383908 04:43:38 INFO - PROCESS | 1938 | |5;yui.html;784.2432053068787;795.6260557537959;819.7827575692439;872.0568082720815;881.7310720230329;12903.290319032449;16576.58558536037;16581.917090414532;16629.085400302978;16631.66736665266;15323.080615163099;15360.923195384035;15364.923175384107;15397.999130056538;15418.30617622207;952.752284406056;969.0696930946265;985.1281615770024;988.574912787501;1000.6697789729408;930.5765876526198;964.5225613321415;968.1818863060703;989.9059589339038;990.0940639315019;1055.1031623120325;1056.8467572202026;1057.3814318623615;1057.9576816927313;1059.1050562274866;1050.154625526451;1050.6952983634737;1051.6897515233024;1052.7631282961272;1053.052252972327;773.6827900560743;775.7052320118319;776.6388629287409;776.6543887969799;776.7242628866784;16665.583360415967;16710.832891671173;16738.16309184548;17268;17576.824231757648;14408.495702650356;14545.563633091024;14624.780628290584;14640.780388294077;14674;11546.422678866023;11824.23142495735;11896.881031189663;12018.939905300505;12081.395930203455;3743.9625504567107;3772.245550889833;3789.8876739266475;3809.5999920008276;3819.159784847357;112149;116578.83421165764;117009.41495292554;118592;121281;11027.669169924915;11138.33170009802;11207.66377008691;11208.943955280334;11786.94106529479;23581.88209058978;23665.7633423667;23902.282931512083;23927.042918283074;23957.640635390482;7968.270777346081;8032.317253033558;8118.3099436548555;8131.422174243432;8180.877286840702;4307.913841723148;4415.845445409395;4487.102579484051;4626.629869610423;4736.005438857878;3254.5931758530182;3312.089175476763;3313.370459612666;3321.020299011749;3336.566246387954;508.28640601383364;621.6262060845492;627.1940556385115;629.5624540944;631.4979591224987;648.9978416584888;649.3084864619166;660.96364699941;663.2604645819877;675.1391975231619;9146.405483643543;9669.776100829422;9993.949673183248;10172.694819155437;10343.327683700685;633.9461145802659;647.6179054357951;648.8767134244479;671.7380221713536;686.5743239191734;4653.790579423934;4854;4871.780769865364;4878.390201224847;4878.414590249122;109081.90918090877;109871;112813.30780038306;115128.42435787768;115463;53073.469265306856;80448;81646;93769.06230937739;98656.52015219777;68852.31147688559;72796;73630;73942.99999999892;74638.25361746312;11063.446827658423;11139.275947063416;11323.879309054604;11425.657230283105;11441.942790285995;47903.76048119737;49023.50976490189;49154.50845491499;49951.25073123833;50278.49721502738;54658.45341546534;61893.45266368377;64743;64993.35006649873;66043.00935486068;12737.363131843185;12892.226466412045;13051.869481305444;13074.910938939933;13591.932040339734;14831.851681483047;14845.92577037108;14898.478553250585;15231.923840380727;15813.130277834829;13782.310884455537;13832.853025936567;13867.653308667364;14055.789163162357;14161.291935403395;13457.596272111461;13477.326133693472;13656.726865462833;13823.930880345533;14340.484297957562;13536.932315338361;13571.321433928462;13734.519291824738;14072.437102515785;14680.192589407257;10145.688082565028;10196.694099177037;10480.475976201313;10586.788264234827;10756.031957123896;10871.836922446328;11260.7747845041;11404.201705880509;11523.13576481784;11710.882891170979 04:43:38 INFO - PROCESS | 1938 | __end_tp_report 04:43:38 INFO - PROCESS | 1938 | __start_cc_report 04:43:38 INFO - PROCESS | 1938 | _x_x_mozilla_cycle_collect,1930 04:43:38 INFO - PROCESS | 1938 | __end_cc_report 04:43:38 INFO - PROCESS | 1938 | __startTimestamp1496058218941__endTimestamp 04:43:38 INFO - PROCESS | 1938 | 04:43:38 INFO - PROCESS | 1938 | ------- Summary: start ------- 04:43:38 INFO - PROCESS | 1938 | Number of tests: 6 04:43:38 INFO - PROCESS | 1938 | 04:43:38 INFO - PROCESS | 1938 | [#0] dojo.html Cycles:165 Average:5898.76 Median:6226.47 stddev:696.46 (11.2%) stddev-sans-first:656.73 04:43:38 INFO - PROCESS | 1938 | Values: 2866.8 3308.5 3334.8 3374.5 3385.4 5863.6 5880.6 5883.0 5886.2 5910.6 5845.1 5904.8 5905.7 5918.8 5936.2 5312.5 5354.3 5382.8 5405.4 5428.7 5284.2 5306.1 5332.7 5470.8 5480.0 5364.4 5374.1 5374.9 5411.5 5412.7 5445.3 5472.6 5477.8 5485.6 5506.8 5321.8 5420.2 5423.0 5429.1 5486.6 5076.6 5598.4 5733.1 6058.2 6069.6 6313.2 6352.3 6368.6 6381.0 6392.0 6348.3 6416.0 6421.8 6431.7 6438.7 5866.2 6037.8 6039.9 6086.9 6109.7 6368.4 6370.5 6419.8 6447.4 6515.2 6371.9 6382.9 6390.2 6393.7 6415.6 6344.7 6346.7 6417.3 6418.6 6423.6 6236.3 6259.4 6331.7 6395.0 6473.5 6280.3 6321.6 6321.7 6365.5 6366.1 4909.2 4910.0 4910.7 4912.7 4930.1 4390.0 4392.8 4408.1 4411.9 4441.7 5606.9 5652.8 5713.3 5715.8 5791.6 6332.9 6346.7 6352.2 6360.2 6367.6 5722.5 5733.1 5807.8 5830.9 5835.5 5464.0 5519.9 5529.4 5532.9 5542.2 6299.0 6540.8 6548.9 6564.5 6595.2 6330.5 6358.9 6360.6 6403.6 6433.4 6509.5 6549.5 6568.5 6574.3 6589.5 6396.4 6398.4 6398.8 6402.5 6430.9 6294.3 6335.2 6381.1 6385.0 6385.4 6199.0 6308.1 6338.7 6343.4 6384.8 6308.7 6342.5 6349.3 6359.0 6366.8 6242.1 6254.3 6258.1 6259.8 6260.2 6040.3 6125.9 6201.5 6226.8 6357.5 6196.0 6206.6 6217.0 6226.5 6226.9 04:43:38 INFO - PROCESS | 1938 | 04:43:38 INFO - PROCESS | 1938 | [#1] ext.html Cycles:180 Average:57570.21 Median:33459.08 stddev:115932.72 (346.5%) stddev-sans-first:116179.61 04:43:38 INFO - PROCESS | 1938 | Values: 810.4 828.3 883.4 893.5 899.5 42043.8 43213.6 44697.0 44721.6 44762.7 44346.0 44574.9 44595.6 44626.3 44757.1 7634.9 9557.3 9718.5 9719.6 9721.6 7599.6 11637.3 11931.8 12047.5 12080.0 11838.8 11867.5 11873.3 11889.7 11910.7 10683.1 11161.7 11854.2 11888.0 11904.2 11366.4 11410.7 11430.0 11436.0 11446.8 41163.8 41462.8 41541.4 41565.8 41658.8 3358.5 3875.1 3954.7 3983.6 4016.6 28199.0 28199.3 28215.7 29525.4 30532.7 3017.5 3151.5 3189.1 3243.6 3270.7 372727.0 375305.0 375527.1 375771.0 375847.0 43709.6 43896.1 54815.7 54917.5 54991.5 39962.0 46231.0 46680.3 46781.5 46802.1 14741.7 14952.9 15162.8 15200.7 15250.9 12815.9 12818.1 12870.4 12878.7 12887.5 12765.7 12783.6 12814.1 12835.5 12839.9 5292.3 5417.4 5641.3 5833.0 5874.6 708.3 721.7 764.6 849.0 853.4 19965.9 24353.3 25292.2 26043.2 26096.6 749.5 947.4 1265.8 1300.1 1307.1 14198.6 14229.7 14386.0 14473.9 14478.2 480959.0 482053.0 484585.0 487927.0 488886.0 60328.7 60573.8 60673.8 60681.4 60911.4 1462.8 1502.5 1516.1 1519.5 1522.9 25149.4 26522.9 27135.7 27136.0 27149.3 49444.5 49598.0 49676.8 49761.8 49766.8 438797.0 443056.0 445415.0 446175.0 446509.0 26747.9 33380.3 33537.8 33566.7 33597.3 45341.8 45754.0 45967.9 46201.3 47005.5 26568.5 26849.3 30176.7 30558.2 31467.7 30356.0 30732.4 30825.8 31845.7 31882.0 29862.7 29956.3 30437.0 31293.0 37788.2 25054.7 29195.4 33302.3 33555.5 34435.0 22391.8 23657.3 24166.6 24611.4 26158.0 04:43:38 INFO - PROCESS | 1938 | 04:43:38 INFO - PROCESS | 1938 | [#2] jquery.html Cycles:180 Average:42107.13 Median:23278.15 stddev:123700.10 (531.4%) stddev-sans-first:124007.45 04:43:38 INFO - PROCESS | 1938 | Values: 390.1 403.3 413.5 424.1 428.5 11705.7 13017.1 21113.7 21742.0 21948.8 22808.0 23046.3 23510.0 23521.2 23786.9 5942.8 6623.0 6741.9 6806.9 6854.3 4193.1 4514.6 6783.4 6918.9 6928.2 4448.4 8029.9 8030.7 8036.3 8049.8 7800.6 7901.4 7936.9 7975.9 8025.5 7459.3 7653.6 7748.8 7768.3 7769.5 27606.6 27712.9 27725.7 27746.9 27907.4 3037.9 3068.6 3112.4 3141.6 3144.7 16111.5 16371.8 16388.2 16982.2 17251.5 1980.3 2061.6 2153.9 2202.8 2209.5 95116.0 122848.0 132345.3 132756.0 157112.0 19527.0 19976.7 20089.8 20092.5 20144.7 30819.5 31160.8 31379.2 31457.5 31487.2 10283.1 10356.2 10516.7 10617.1 10631.1 7579.7 8422.2 8754.0 8755.5 8929.7 9246.9 9310.3 9425.3 9465.8 9521.0 2949.9 3526.8 3977.7 4040.5 4061.5 888.9 910.2 911.3 943.1 957.3 21238.6 21445.8 21756.8 21766.0 21922.6 925.9 942.8 963.2 963.3 969.2 11003.2 11327.8 11379.7 11426.5 11455.6 686672.0 749952.0 759757.0 761277.0 762704.0 138120.0 149664.0 156018.2 164984.0 165731.2 1268.6 1341.1 1363.6 1369.4 1370.1 19907.5 20100.6 20448.5 20526.5 20537.5 55651.7 56166.9 63080.7 65333.7 66335.0 57786.7 84439.6 85664.7 88047.6 88240.1 11070.3 11528.9 11551.7 11858.5 12005.8 10768.5 11077.8 12741.6 13333.4 13370.3 11279.5 11352.0 11430.7 11534.8 11578.8 10465.9 10471.6 10746.5 10757.4 10761.9 11106.6 11419.9 11533.6 11637.7 11685.6 11538.1 11587.5 11831.9 11888.0 11915.2 10668.8 11059.6 11113.1 11334.1 11517.6 04:43:38 INFO - PROCESS | 1938 | 04:43:38 INFO - PROCESS | 1938 | [#3] mootools.html Cycles:180 Average:6699.95 Median:2449.21 stddev:4171.53 (170.3%) stddev-sans-first:4163.95 04:43:38 INFO - PROCESS | 1938 | Values: 1363.0 1383.5 1548.7 1579.6 1594.9 10681.1 11255.0 11272.7 11276.3 11286.9 10941.7 10954.3 11257.8 11394.3 11401.7 2446.9 2452.2 2454.1 2457.4 2458.9 2366.4 2429.0 2442.3 2454.2 2457.0 2358.0 2402.1 2409.5 2446.9 2451.5 2074.7 2273.5 2323.4 2388.5 2425.6 2374.0 2397.7 2397.9 2405.2 2411.6 10852.2 11087.4 11116.2 11123.4 11126.5 5764.3 5937.5 5940.9 5943.9 5948.5 3548.9 3590.6 3632.4 3665.8 3671.8 1162.2 1170.5 1206.9 1217.2 1237.8 13777.2 14140.5 14368.2 14700.9 14936.3 4636.8 4782.1 4788.6 4796.1 4818.6 11792.1 11796.9 11799.2 11802.8 11817.2 4935.9 5102.6 5142.2 5816.4 5817.0 4485.3 5277.5 5293.1 5306.5 5331.8 3577.9 3714.8 3834.1 3890.3 3943.2 1020.6 1022.4 1024.3 1034.6 1037.5 931.9 936.5 955.5 966.3 1007.5 5848.2 6188.9 6644.2 6716.0 7050.7 976.8 991.0 1024.2 1026.2 1080.2 4205.5 4226.1 4228.7 4252.6 4329.4 13198.5 13813.5 13817.3 13833.6 13838.0 13205.0 13500.9 13754.7 13789.7 13808.8 10102.6 10123.5 10192.8 10344.7 10351.9 6510.1 6556.4 6577.4 6587.3 6740.8 11964.8 12179.0 12319.4 12365.3 12483.6 9739.0 12450.8 12606.6 12672.7 12701.0 8473.7 8494.2 8655.0 8656.5 8675.3 10211.2 10428.7 10432.2 10437.9 10451.8 8604.2 8917.0 8928.1 8987.7 8993.4 7221.2 8743.5 8818.0 8822.0 9022.0 6752.8 7853.0 8035.7 8046.0 8052.8 5882.6 5902.9 5943.8 6112.0 6125.8 5695.9 5912.9 6008.0 6188.4 6331.8 04:43:38 INFO - PROCESS | 1938 | 04:43:38 INFO - PROCESS | 1938 | [#4] prototype.html Cycles:180 Average:4421.23 Median:4803.16 stddev:1239.61 (25.8%) stddev-sans-first:1205.47 04:43:38 INFO - PROCESS | 1938 | Values: 383.6 385.1 388.7 392.4 396.7 4853.5 5037.2 5109.9 5121.6 5123.9 4061.6 4414.9 5019.4 5051.1 5083.5 3402.0 3956.4 3989.7 3998.4 4006.0 3689.6 3805.6 4027.4 4058.1 4075.4 3439.9 3547.0 3809.0 4029.8 4087.8 3350.4 3403.1 4008.3 4026.4 4060.4 2856.5 3164.8 3364.2 3561.7 3575.4 4298.0 4909.5 4952.2 4965.9 4973.4 3962.8 4505.5 4654.9 4659.8 4724.6 4844.5 4857.9 4995.1 5142.1 5385.0 3638.9 3700.4 3969.7 3976.0 3978.9 5413.9 5465.4 5651.9 5706.7 5786.8 4144.3 4322.7 4406.3 4821.9 4884.9 4035.8 4152.8 4381.5 4909.1 4987.9 4179.7 4336.5 4425.9 4750.9 4810.4 5183.6 5375.0 5392.2 5392.8 5459.3 1366.4 1600.7 1608.8 1631.6 1689.8 1194.2 1250.7 1299.9 1329.9 1333.7 4338.4 4511.5 4561.6 4754.5 4790.2 4393.7 5490.4 5532.6 5568.5 5661.9 4231.9 4236.0 4497.0 4796.0 4830.3 2625.2 2693.6 2788.5 2857.9 2890.8 5099.9 5676.2 5681.6 5686.5 5692.9 5119.1 5173.5 5284.8 5351.7 5678.8 5148.7 5307.0 5355.8 5721.1 5814.9 4670.7 4950.8 5035.5 5465.6 5562.3 4740.6 5323.5 5384.4 5425.3 5483.3 4720.6 5331.7 5429.8 5460.5 5764.2 4272.6 4639.6 4793.9 4856.0 4928.2 4767.0 5286.9 5446.7 5450.4 5455.0 4547.7 5102.3 5124.2 5225.5 5305.7 4915.5 5169.9 5429.6 5438.4 5461.8 4439.5 4548.8 5003.6 5058.6 5301.0 4649.9 5419.0 5436.1 5467.3 5469.5 4548.5 4632.3 5060.2 5282.2 5401.6 04:43:38 INFO - PROCESS | 1938 | 04:43:38 INFO - PROCESS | 1938 | [#5] yui.html Cycles:180 Average:20839.38 Median:17003.08 stddev:30222.31 (177.7%) stddev-sans-first:30269.58 04:43:38 INFO - PROCESS | 1938 | Values: 784.2 795.6 819.8 872.1 881.7 12903.3 16576.6 16581.9 16629.1 16631.7 15323.1 15360.9 15364.9 15398.0 15418.3 952.8 969.1 985.1 988.6 1000.7 930.6 964.5 968.2 989.9 990.1 1055.1 1056.8 1057.4 1058.0 1059.1 1050.2 1050.7 1051.7 1052.8 1053.1 773.7 775.7 776.6 776.7 776.7 16665.6 16710.8 16738.2 17268.0 17576.8 14408.5 14545.6 14624.8 14640.8 14674.0 11546.4 11824.2 11896.9 12018.9 12081.4 3744.0 3772.2 3789.9 3809.6 3819.2 112149.0 116578.8 117009.4 118592.0 121281.0 11027.7 11138.3 11207.7 11208.9 11786.9 23581.9 23665.8 23902.3 23927.0 23957.6 7968.3 8032.3 8118.3 8131.4 8180.9 4307.9 4415.8 4487.1 4626.6 4736.0 3254.6 3312.1 3313.4 3321.0 3336.6 508.3 621.6 627.2 629.6 631.5 649.0 649.3 661.0 663.3 675.1 9146.4 9669.8 9993.9 10172.7 10343.3 633.9 647.6 648.9 671.7 686.6 4653.8 4854.0 4871.8 4878.4 4878.4 109081.9 109871.0 112813.3 115128.4 115463.0 53073.5 80448.0 81646.0 93769.1 98656.5 68852.3 72796.0 73630.0 73943.0 74638.3 11063.4 11139.3 11323.9 11425.7 11441.9 47903.8 49023.5 49154.5 49951.3 50278.5 54658.5 61893.5 64743.0 64993.4 66043.0 12737.4 12892.2 13051.9 13074.9 13591.9 14831.9 14845.9 14898.5 15231.9 15813.1 13782.3 13832.9 13867.7 14055.8 14161.3 13457.6 13477.3 13656.7 13823.9 14340.5 13536.9 13571.3 13734.5 14072.4 14680.2 10145.7 10196.7 10480.5 10586.8 10756.0 10871.8 11260.8 11404.2 11523.1 11710.9 04:43:38 INFO - PROCESS | 1938 | -------- Summary: end -------- 04:43:38 INFO - PROCESS | 1938 | 04:43:39 INFO - TEST-INFO | 1938: exit 0 04:43:39 INFO - TEST-OK | dromaeo_css | took 1118456ms 04:43:39 INFO - TEST-START | kraken 04:43:39 INFO - operating with platform_type : mac_ 04:43:39 INFO - Initialising browser for kraken test... 04:43:39 INFO - TEST-INFO | started process 1941 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/tmpzojuoK/profile http://localhost:49355/getInfo.html) 04:43:41 INFO - PROCESS | 1941 | __metrics Screen width/height:1600/1200 04:43:41 INFO - PROCESS | 1941 | colorDepth:24 04:43:41 INFO - PROCESS | 1941 | Browser inner width/height: 1024/689 04:43:41 INFO - PROCESS | 1941 | __metrics 04:43:41 INFO - PROCESS | 1941 | 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" 04:43:41 INFO - PROCESS | 1941 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:43:42 INFO - TEST-INFO | 1941: exit 0 04:43:42 INFO - Browser initialized. 04:43:42 INFO - Running cycle 1/1 for kraken test... 04:43:42 INFO - TEST-INFO | started process 1943 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/tmpzojuoK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:43:54 INFO - PROCESS | 1943 | RSS: Main: 193667072 04:43:54 INFO - PROCESS | 1943 | 04:43:57 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:49355/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49355/tests/kraken/driver.html?testName=audio-beat-detection) 04:43:58 INFO - PROCESS | 1943 | RSS: Main: 244928512 04:43:58 INFO - PROCESS | 1943 | 04:44:00 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:49355/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49355/tests/kraken/driver.html?testName=audio-dft) 04:44:01 INFO - PROCESS | 1943 | RSS: Main: 260231168 04:44:01 INFO - PROCESS | 1943 | 04:44:04 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:49355/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49355/tests/kraken/driver.html?testName=audio-fft) 04:44:05 INFO - PROCESS | 1943 | RSS: Main: 356679680 04:44:05 INFO - PROCESS | 1943 | 04:44:07 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:49355/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49355/tests/kraken/driver.html?testName=audio-oscillator) 04:44:07 INFO - PROCESS | 1943 | RSS: Main: 259506176 04:44:07 INFO - PROCESS | 1943 | 04:44:10 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:49355/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49355/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:44:10 INFO - PROCESS | 1943 | RSS: Main: 261062656 04:44:10 INFO - PROCESS | 1943 | 04:44:13 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:49355/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49355/tests/kraken/driver.html?testName=imaging-darkroom) 04:44:14 INFO - PROCESS | 1943 | RSS: Main: 300720128 04:44:14 INFO - PROCESS | 1943 | 04:44:17 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:49355/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49355/tests/kraken/driver.html?testName=imaging-desaturate) 04:44:17 INFO - PROCESS | 1943 | RSS: Main: 303300608 04:44:17 INFO - PROCESS | 1943 | 04:44:20 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:49355/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49355/tests/kraken/driver.html?testName=json-parse-financial) 04:44:21 INFO - PROCESS | 1943 | RSS: Main: 301551616 04:44:21 INFO - PROCESS | 1943 | 04:44:23 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:49355/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49355/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:44:23 INFO - PROCESS | 1943 | RSS: Main: 276930560 04:44:23 INFO - PROCESS | 1943 | 04:44:25 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:49355/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49355/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:44:26 INFO - PROCESS | 1943 | RSS: Main: 281673728 04:44:26 INFO - PROCESS | 1943 | 04:44:28 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:49355/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49355/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:44:28 INFO - PROCESS | 1943 | RSS: Main: 289792000 04:44:28 INFO - PROCESS | 1943 | 04:44:31 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:49355/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49355/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:44:32 INFO - PROCESS | 1943 | RSS: Main: 302313472 04:44:32 INFO - PROCESS | 1943 | 04:44:35 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:49355/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49355/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:44:36 INFO - PROCESS | 1943 | RSS: Main: 296235008 04:44:36 INFO - PROCESS | 1943 | 04:44:37 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:49355/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49355/tests/kraken/driver.html?testName=ai-astar) 04:44:37 INFO - PROCESS | 1943 | RSS: Main: 341024768 04:44:37 INFO - PROCESS | 1943 | 04:44:37 INFO - PROCESS | 1943 | __start_tp_report 04:44:37 INFO - PROCESS | 1943 | _x_x_mozilla_page_load 04:44:37 INFO - PROCESS | 1943 | _x_x_mozilla_page_load_details 04:44:37 INFO - PROCESS | 1943 | |i|pagename|runs| 04:44:37 INFO - PROCESS | 1943 | |0;ai-astar;106;115;113;115;116;116;116;116;120;171 04:44:37 INFO - PROCESS | 1943 | |1;audio-beat-detection;142;107;107;144;109;130;138;109;127;108 04:44:37 INFO - PROCESS | 1943 | |2;audio-dft;185;184;184;185;189;268;220;186;185;184 04:44:37 INFO - PROCESS | 1943 | |3;audio-fft;83;63;59;93;63;62;62;60;75;61 04:44:37 INFO - PROCESS | 1943 | |4;audio-oscillator;87;70;115;67;59;91;110;100;100;83 04:44:37 INFO - PROCESS | 1943 | |5;imaging-gaussian-blur;88;87;87;87;87;86;87;87;88;87 04:44:37 INFO - PROCESS | 1943 | |6;imaging-darkroom;95;95;95;99;94;95;95;98;95;94 04:44:37 INFO - PROCESS | 1943 | |7;imaging-desaturate;86;86;83;86;83;86;83;86;85;84 04:44:37 INFO - PROCESS | 1943 | |8;json-parse-financial;63;62;62;63;65;63;63;63;65;63 04:44:37 INFO - PROCESS | 1943 | |9;json-stringify-tinderbox;63;60;71;62;59;58;60;59;59;60 04:44:37 INFO - PROCESS | 1943 | |10;stanford-crypto-aes;85;84;85;86;85;85;84;93;75;85 04:44:37 INFO - PROCESS | 1943 | |11;stanford-crypto-ccm;140;138;129;134;134;133;144;145;181;155 04:44:37 INFO - PROCESS | 1943 | |12;stanford-crypto-pbkdf2;165;163;165;163;164;166;161;158;160;146 04:44:37 INFO - PROCESS | 1943 | |13;stanford-crypto-sha256-iterative;65;65;64;64;69;64;63;66;64;63 04:44:37 INFO - PROCESS | 1943 | __end_tp_report 04:44:37 INFO - PROCESS | 1943 | __start_cc_report 04:44:37 INFO - PROCESS | 1943 | _x_x_mozilla_cycle_collect,2188 04:44:37 INFO - PROCESS | 1943 | __end_cc_report 04:44:37 INFO - PROCESS | 1943 | __startTimestamp1496058277973__endTimestamp 04:44:37 INFO - PROCESS | 1943 | 04:44:37 INFO - PROCESS | 1943 | ------- Summary: start ------- 04:44:37 INFO - PROCESS | 1943 | Number of tests: 14 04:44:37 INFO - PROCESS | 1943 | 04:44:37 INFO - PROCESS | 1943 | [#0] ai-astar Cycles:10 Average:120.40 Median:116.00 stddev:18.13 (15.6%) stddev-sans-first:18.47 04:44:37 INFO - PROCESS | 1943 | Values: 106.0 115.0 113.0 115.0 116.0 116.0 116.0 116.0 120.0 171.0 04:44:37 INFO - PROCESS | 1943 | 04:44:37 INFO - PROCESS | 1943 | [#1] audio-beat-detection Cycles:10 Average:122.10 Median:128.50 stddev:15.68 (12.2%) stddev-sans-first:14.89 04:44:37 INFO - PROCESS | 1943 | Values: 142.0 107.0 107.0 144.0 109.0 130.0 138.0 109.0 127.0 108.0 04:44:37 INFO - PROCESS | 1943 | 04:44:37 INFO - PROCESS | 1943 | [#2] audio-dft Cycles:10 Average:197.00 Median:185.50 stddev:27.27 (14.7%) stddev-sans-first:28.58 04:44:37 INFO - PROCESS | 1943 | Values: 185.0 184.0 184.0 185.0 189.0 268.0 220.0 186.0 185.0 184.0 04:44:37 INFO - PROCESS | 1943 | 04:44:37 INFO - PROCESS | 1943 | [#3] audio-fft Cycles:10 Average:68.10 Median:63.00 stddev:11.62 (18.4%) stddev-sans-first:11.00 04:44:37 INFO - PROCESS | 1943 | Values: 83.0 63.0 59.0 93.0 63.0 62.0 62.0 60.0 75.0 61.0 04:44:37 INFO - PROCESS | 1943 | 04:44:37 INFO - PROCESS | 1943 | [#4] audio-oscillator Cycles:10 Average:88.20 Median:68.50 stddev:18.68 (27.3%) stddev-sans-first:19.81 04:44:37 INFO - PROCESS | 1943 | Values: 87.0 70.0 115.0 67.0 59.0 91.0 110.0 100.0 100.0 83.0 04:44:37 INFO - PROCESS | 1943 | 04:44:37 INFO - PROCESS | 1943 | [#5] imaging-gaussian-blur Cycles:10 Average:87.10 Median:87.00 stddev:0.57 (0.7%) stddev-sans-first:0.50 04:44:37 INFO - PROCESS | 1943 | Values: 88.0 87.0 87.0 87.0 87.0 86.0 87.0 87.0 88.0 87.0 04:44:37 INFO - PROCESS | 1943 | 04:44:37 INFO - PROCESS | 1943 | [#6] imaging-darkroom Cycles:10 Average:95.50 Median:95.00 stddev:1.65 (1.7%) stddev-sans-first:1.74 04:44:37 INFO - PROCESS | 1943 | Values: 95.0 95.0 95.0 99.0 94.0 95.0 95.0 98.0 95.0 94.0 04:44:37 INFO - PROCESS | 1943 | 04:44:37 INFO - PROCESS | 1943 | [#7] imaging-desaturate Cycles:10 Average:84.80 Median:86.00 stddev:1.40 (1.6%) stddev-sans-first:1.41 04:44:37 INFO - PROCESS | 1943 | Values: 86.0 86.0 83.0 86.0 83.0 86.0 83.0 86.0 85.0 84.0 04:44:37 INFO - PROCESS | 1943 | 04:44:37 INFO - PROCESS | 1943 | [#8] json-parse-financial Cycles:10 Average:63.20 Median:63.00 stddev:1.03 (1.6%) stddev-sans-first:1.09 04:44:37 INFO - PROCESS | 1943 | Values: 63.0 62.0 62.0 63.0 65.0 63.0 63.0 63.0 65.0 63.0 04:44:37 INFO - PROCESS | 1943 | 04:44:37 INFO - PROCESS | 1943 | [#9] json-stringify-tinderbox Cycles:10 Average:61.10 Median:60.00 stddev:3.78 (6.3%) stddev-sans-first:3.95 04:44:37 INFO - PROCESS | 1943 | Values: 63.0 60.0 71.0 62.0 59.0 58.0 60.0 59.0 59.0 60.0 04:44:37 INFO - PROCESS | 1943 | 04:44:37 INFO - PROCESS | 1943 | [#10] stanford-crypto-aes Cycles:10 Average:84.70 Median:85.00 stddev:4.30 (5.1%) stddev-sans-first:4.56 04:44:37 INFO - PROCESS | 1943 | Values: 85.0 84.0 85.0 86.0 85.0 85.0 84.0 93.0 75.0 85.0 04:44:37 INFO - PROCESS | 1943 | 04:44:37 INFO - PROCESS | 1943 | [#11] stanford-crypto-ccm Cycles:10 Average:143.30 Median:142.00 stddev:15.22 (10.7%) stddev-sans-first:16.09 04:44:37 INFO - PROCESS | 1943 | Values: 140.0 138.0 129.0 134.0 134.0 133.0 144.0 145.0 181.0 155.0 04:44:37 INFO - PROCESS | 1943 | 04:44:37 INFO - PROCESS | 1943 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.10 Median:163.50 stddev:5.86 (3.6%) stddev-sans-first:6.04 04:44:37 INFO - PROCESS | 1943 | Values: 165.0 163.0 165.0 163.0 164.0 166.0 161.0 158.0 160.0 146.0 04:44:37 INFO - PROCESS | 1943 | 04:44:37 INFO - PROCESS | 1943 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.70 Median:64.50 stddev:1.77 (2.7%) stddev-sans-first:1.87 04:44:37 INFO - PROCESS | 1943 | Values: 65.0 65.0 64.0 64.0 69.0 64.0 63.0 66.0 64.0 63.0 04:44:37 INFO - PROCESS | 1943 | -------- Summary: end -------- 04:44:37 INFO - PROCESS | 1943 | 04:44:38 INFO - TEST-INFO | 1943: exit 0 04:44:38 INFO - TEST-OK | kraken | took 59189ms 04:44:38 INFO - SUITE-END | took 1177s 04:44:39 INFO - Completed test suite (00:19:38) 04:44:39 INFO - javascript benchmark 04:44:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7710.774895355129, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2866.7849911256653, 3308.454105072665, 3334.766566340354, 3374.510695949087, 3385.390629686657, 5863.560232982527, 5880.647161170327, 5882.970585147068, 5886.2347894773675, 5910.615809972349, 5845.123231515275, 5904.763809447617, 5905.6751878646655, 5918.822435326936, 5936.168936348904, 5312.495312945273, 5354.303940487731, 5382.811601593945, 5405.4324295948945, 5428.67427954324, 5284.180951952453, 5306.124489459235, 5332.733363331838, 5470.781168753245, 5479.972600136994, 5364.409914909357, 5374.113271310229, 5374.946250537484, 5411.458854114577, 5412.675239485624, 5445.292112025451, 5472.616916815825, 5477.780888764445, 5485.561155107622, 5506.834794956158, 5321.760520776575, 5420.214068959979, 5423.0, 5429.104197807396, 5486.643368181095, 5076.619253555961, 5598.440155984369, 5733.140028995643, 6058.212432383762, 6069.57512974092, 6313.2424109107205, 6352.333005034498, 6368.554201205918, 6381.0, 6391.968040159816, 6348.3334249903555, 6415.973444248898, 6421.8073457796345, 6431.742730290783, 6438.678066096676, 5866.178734977107, 6037.7584896604085, 6039.9396006039815, 6086.878262434727, 6109.694515274218, 6368.363163683687, 6370.490360771128, 6419.807405777834, 6447.387498187664, 6515.185601799775, 6371.904421433682, 6382.936170638327, 6390.233172019294, 6393.680315984182, 6415.582987105823, 6344.714487848058, 6346.682665866688, 6417.262014868256, 6418.582792118497, 6423.582467139621, 6236.314005459396, 6259.405356491217, 6331.651759153198, 6394.9680251599375, 6473.482121430275, 6280.277768056732, 6321.620702757849, 6321.747130114744, 6365.522585806083, 6366.108744775735, 4909.165441874889, 4909.975450122726, 4910.745502312491, 4912.680675756064, 4930.087933732271, 4390.0, 4392.802323895432, 4408.096340250241, 4411.889702757457, 4441.689081764245, 5606.943930560724, 5652.773889044474, 5713.342965558931, 5715.799947001835, 5791.5656325775735, 6332.905006424908, 6346.682665866688, 6352.237731472161, 6360.204974378203, 6367.61794292344, 5722.542196624343, 5733.111367738007, 5807.796727114531, 5830.854228644318, 5835.503982161564, 5463.972680136653, 5519.917201241985, 5529.447055294519, 5532.889342213133, 5542.196381524737, 6299.023651334051, 6540.771073012612, 6548.934510654833, 6564.474842012628, 6595.241547222131, 6330.49356051524, 6358.872822543616, 6360.618362898241, 6403.583767055126, 6433.356664333716, 6509.511786616117, 6549.508786841101, 6568.474522038321, 6574.276829548841, 6589.538732288694, 6396.392342727433, 6398.360163983564, 6398.840028999312, 6402.455791257796, 6430.871382572416, 6294.3076261612105, 6335.239771227482, 6381.106645069601, 6385.010323399694, 6385.393387628168, 6199.008158694767, 6308.053791931247, 6338.65137417428, 6343.365663433619, 6384.776532821329, 6308.716107775253, 6342.52431067681, 6349.269833969152, 6358.968205158944, 6366.808995730135, 6242.094896240111, 6254.343293954252, 6258.061290806416, 6259.843503912439, 6260.154879091265, 6040.335563088145, 6125.937554509019, 6201.472874805511, 6226.750929962751, 6357.491400687842, 6196.0, 6206.565540412308, 6217.0, 6226.470749986302, 6226.937730622636], "value": 5849.3518359780865, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [810.4488947515689, 828.3207769628905, 883.3683916000062, 893.4728510178993, 899.4962820820346, 42043.78978105109, 43213.56786432135, 44697.0, 44721.552784472144, 44762.6571202864, 44346.0, 44574.88562785932, 44595.554044459546, 44626.33060504087, 44757.10485790282, 7634.923650763504, 9557.28320375971, 9718.465484398355, 9719.61121555137, 9721.562529686145, 7599.582022988733, 11637.30176189427, 11931.761364772743, 12047.45786439608, 12080.0, 11838.822417663741, 11867.52529898803, 11873.346965916871, 11889.692133865312, 11910.702232444215, 10683.14534837211, 11161.665150045512, 11854.170208085437, 11888.048956083536, 11904.226225295328, 11366.37484938328, 11410.657680269522, 11430.0, 11436.0, 11446.828297575543, 41163.76508704744, 41462.79268603668, 41541.37687934683, 41565.792171039255, 41658.79170604158, 3358.4626459766564, 3875.1280961783655, 3954.6638535724496, 3983.581723918976, 4016.638502534786, 28199.0, 28199.295017624518, 28215.717842821516, 29525.40949181026, 30532.69467305343, 3017.4568577656014, 3151.543026261202, 3189.1229911774217, 3243.6431992480807, 3270.73834093272, 372727.0, 375305.0, 375527.1223643892, 375771.0, 375847.0, 43709.56290437119, 43896.12207755827, 54815.72592137014, 54917.45082549203, 54991.45008549943, 39962.00189990489, 46231.0, 46680.26557875362, 46781.5321846784, 46802.06395872063, 14741.705165896621, 14952.92523537397, 15162.848371516364, 15200.695986080216, 15250.923745381424, 12815.91064759506, 12818.102732808615, 12870.356482176041, 12878.678033049062, 12887.48450061987, 12765.744685106432, 12783.552575659807, 12814.103012789015, 12835.486580536674, 12839.871601283867, 5292.259083728281, 5417.3770016447825, 5641.294838145232, 5832.970835145882, 5874.5887787854435, 708.2810946888912, 721.6973363079692, 764.6406189091118, 848.9872651910226, 853.4068822168533, 19965.900170499055, 24353.269401917616, 25292.241232763048, 26043.218703438928, 26096.60855087175, 749.539033494397, 947.4362754161263, 1265.8227848101274, 1300.07694536878, 1307.0981023094034, 14198.574042778733, 14229.715405691828, 14385.992980491474, 14473.85526144725, 14478.203698796457, 480959.0, 482053.0, 484585.0, 487927.0, 488886.0, 60328.698356508816, 60573.78852422927, 60673.786524269264, 60681.39318606757, 60911.39088609146, 1462.8464011278663, 1502.4891536877076, 1516.0676184146892, 1519.4985654733912, 1522.9466968656043, 25149.371265718502, 26522.939082436485, 27135.72864271332, 27136.0, 27149.321266968484, 49444.50555494399, 49598.0, 49676.75161624169, 49761.75119124526, 49766.755831104514, 438797.0, 443056.0, 445415.0, 446175.0, 446509.0, 26747.86626066935, 33380.33239335151, 33537.82617608372, 33566.66433335733, 33597.32805343928, 45341.77329113333, 45754.0, 45967.850803728834, 46201.30698039465, 47005.529944701484, 26568.53962613464, 26849.328766780985, 30176.69823301739, 30558.236044099074, 31467.68532314739, 30356.0, 30732.385352292382, 30825.845870770503, 31845.681543184273, 31882.0, 29862.701372985994, 29956.25109372196, 30437.0, 31293.0, 37788.24423511569, 25054.749452505243, 29195.41609167847, 33302.33395332031, 33555.49666755049, 34435.0, 22391.77608223897, 23657.29028129159, 24166.637500437155, 24611.384715382257, 26157.953681852512], "value": 19053.410315826965, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.0891418537714, 403.3324847377591, 413.53890412190395, 424.0797469491203, 428.4644194756554, 11705.712371639112, 13017.088803783727, 21113.68329475059, 21742.0, 21948.792816395093, 22808.0, 23046.308610741664, 23510.0, 23521.176758813446, 23786.881065594647, 5942.821715348525, 6622.966885165568, 6741.865162696743, 6806.931930680679, 6854.314568543131, 4193.119444916571, 4514.571115743999, 6783.389494945454, 6918.930810691904, 6928.168619765635, 4448.421705178338, 8029.919700803005, 8030.718924837632, 8036.316913062396, 8049.839003219932, 7800.570968596725, 7901.407394445382, 7936.880946785803, 7975.920240797575, 8025.518468891856, 7459.328660420588, 7653.617319134021, 7748.7675369739, 7768.300852923264, 7769.53382797034, 27606.585901211296, 27712.861435692896, 27725.722742772516, 27746.861265693544, 27907.441851162865, 3037.9088627341216, 3068.5704001439817, 3112.439760843047, 3141.607299087614, 3144.716975472196, 16111.516654500383, 16371.754423683655, 16388.180590970405, 16982.235799388935, 17251.482455526355, 1980.3167907072036, 2061.5567652954537, 2153.870767753924, 2202.757696653367, 2209.49181688211, 95116.0, 122848.0, 132345.33827330897, 132756.0, 157112.0, 19527.0, 19976.700349494768, 20089.799102009085, 20092.497687557927, 20144.697829532568, 30819.537706934414, 31160.844195778875, 31379.215519612193, 31457.528137077963, 31487.21281967969, 10283.125934295518, 10356.17150627948, 10516.737081572868, 10617.097546708463, 10631.149508039338, 7579.711449053674, 8422.24199822019, 8753.956230218808, 8755.518446485505, 8929.687460938836, 9246.86129708055, 9310.348275620641, 9425.340226184102, 9465.810683786285, 9520.952395237979, 2949.926251843678, 3526.7531272810656, 3977.7414468059483, 4040.544985199338, 4061.5329237137516, 888.9422187557788, 910.2354022620898, 911.3165126155384, 943.099340130174, 957.2868213181224, 21238.575228495345, 21445.78554214469, 21756.782432175474, 21766.0, 21922.58851526331, 925.921296689789, 942.7501712046313, 963.2486660404895, 963.2631037256365, 969.2439896880445, 11003.17476189289, 11327.830082548768, 11379.715507112389, 11426.542938282542, 11455.59905403307, 686672.0, 749952.0, 759757.0, 761277.0, 762704.0, 138120.0, 149664.0, 156018.21990889971, 164984.0, 165731.1713441449, 1268.5940499040403, 1341.1416693316285, 1363.638635761524, 1369.383777300219, 1370.1094288712218, 19907.502312442306, 20100.59798804045, 20448.488787780425, 20526.486837829172, 20537.48656283605, 55651.72174139103, 56166.87666246734, 63080.68459657672, 65333.673331633036, 66335.0, 57786.7110664444, 84439.5778021106, 85664.71502927586, 88047.55976220334, 88240.11759882318, 11070.335779853234, 11528.942355288169, 11551.711207219887, 11858.525658973544, 12005.819912701141, 10768.51541680642, 11077.836827133357, 12741.617751467471, 13333.399997000352, 13370.331483425594, 11279.548818047515, 11352.0, 11430.657080287605, 11534.826977595176, 11578.76842463163, 10465.9476702616, 10471.581136754445, 10746.516406761872, 10757.408342541235, 10761.89238107609, 11106.555737770723, 11419.942900285445, 11533.596324128615, 11637.650870473899, 11685.649430517098, 11538.134639901873, 11587.478563464496, 11831.881681183078, 11888.048956083665, 11915.225510342147, 10668.786624267315, 11059.557617695202, 11113.110951123728, 11334.14993875446, 11517.596884109016], "value": 11577.997780859281, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1362.9522966696165, 1383.4673650644504, 1548.736714758491, 1579.6208909861637, 1594.9282282297295, 10681.145508359332, 11255.0, 11272.661820145388, 11276.267042642223, 11286.943565282161, 10941.726456838584, 10954.342739435639, 11257.831132533018, 11394.25937314074, 11401.657950261506, 2446.926592202237, 2452.2030340139436, 2454.055188752325, 2457.3610861176044, 2458.864762438065, 2366.4438856868624, 2429.0283886445477, 2442.3039433761382, 2454.22691852066, 2456.987715061422, 2357.95284094318, 2402.1112188490283, 2409.4819613783034, 2446.889889954947, 2451.4974430241846, 2074.6618431111924, 2273.4771002669313, 2323.4307594639254, 2388.4984153327823, 2425.575524283261, 2373.988130059339, 2397.700287464067, 2397.9040838366445, 2405.2183040511727, 2411.5538625354366, 10852.186086043579, 11087.390193539351, 11116.221864469491, 11123.388213648246, 11126.4993075311, 5764.250647415809, 5937.52499800015, 5940.881182376328, 5943.900378430004, 5948.524118070544, 3548.8580456781697, 3590.622984586633, 3632.382494975834, 3665.7617254878614, 3671.76133551321, 1162.2096974057638, 1170.5025364220173, 1206.9215500992407, 1217.2209785737136, 1237.7648246833073, 13777.173369597756, 14140.505082322172, 14368.209748463934, 14700.926495367561, 14936.25318734059, 4636.83771068011, 4782.139214941274, 4788.592969597623, 4796.088743138862, 4818.638602104857, 11792.056635469142, 11796.938275555241, 11799.233049851731, 11802.763944721057, 11817.172797904235, 4935.914098898236, 5102.566281866083, 5142.202958541432, 5816.447437493432, 5816.970915145397, 4485.304777759387, 5277.454404346145, 5293.073712100367, 5306.469353064663, 5331.813386531453, 3577.910552236163, 3714.7771133732062, 3834.10211945089, 3890.3191941410705, 3943.2310699413338, 1020.5917632946732, 1022.4018948914968, 1024.329064462778, 1034.5654824973528, 1037.4708898461688, 931.9347645664739, 936.4755736787421, 955.5461155950868, 966.2849491376331, 1007.5314978535016, 5848.152017957373, 6188.8762224755255, 6644.235912869985, 6716.026176204423, 7050.717971281194, 976.8485884687884, 990.9752256193509, 1024.2215915903967, 1026.1841835740604, 1080.2492267873752, 4205.495340559152, 4226.1336426032585, 4228.682848786353, 4252.596003379674, 4329.437173167531, 13198.47206111764, 13813.51652692151, 13817.309134543233, 13833.584992450242, 13838.0, 13205.0, 13500.93249533746, 13754.724905501835, 13789.655258618615, 13808.826249768681, 10102.595896164217, 10123.544440500047, 10192.847107293397, 10344.741381465523, 10351.861295257515, 6510.088587597835, 6556.377144171296, 6577.408033277028, 6587.308332625005, 6740.831479213059, 11964.76070478603, 12179.0, 12319.44562494672, 12365.319907405179, 12483.625491235278, 9738.951305243712, 12450.81323780126, 12606.621801345975, 12672.74654506923, 12701.0, 8473.66105355779, 8494.235518803338, 8654.956725216334, 8656.48061116335, 8675.305975521816, 10211.23415743837, 10428.687139385831, 10432.217583681406, 10437.895621043692, 10451.790964180826, 8604.225619694258, 8916.955415223141, 8928.10718928128, 8987.730368088967, 8993.370464067453, 7221.169565500035, 8743.475391476532, 8818.0, 8822.0, 9022.0, 6752.763653272112, 7853.0, 8035.67857285702, 8046.0, 8052.758417247492, 5882.558808089325, 5902.852428689317, 5943.791967281124, 6112.0, 6125.754969801158, 5695.860827834533, 5912.940870591411, 6007.969960150346, 6188.350223226496, 6331.810045698637], "value": 5121.8368680778785, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.57423260181196, 385.11615841643436, 388.7162371468829, 392.42313798715895, 396.74410005546423, 4853.514648535145, 5037.1688671369275, 5109.912070343724, 5121.5646670033, 5123.897522049557, 4061.6141466560703, 4414.852138444004, 5019.372578427697, 5051.141305977983, 5083.491650834914, 3401.9829900850586, 3956.4065390191486, 3989.7207195496326, 3998.3802510610903, 4006.0, 3689.631036896316, 3805.6004119567324, 4027.395890616416, 4058.147788964321, 4075.4498142750645, 3439.931201375971, 3546.982265088671, 3809.047738065484, 4029.81865816039, 4087.795610219492, 3350.4471762159214, 3403.0811680846155, 4008.2584721826265, 4026.3759117336567, 4060.37584966013, 2856.4572731181215, 3164.844831636436, 3364.2094107884627, 3561.6794488495902, 3575.4458059000895, 4297.979949423766, 4909.484504127086, 4952.192449755849, 4965.864678188377, 4973.383650313626, 3962.7820469874146, 4505.52691967342, 4654.883627909296, 4659.836905708284, 4724.5984091352275, 4844.467108618049, 4857.878553036202, 4995.075910956485, 5142.071723103836, 5384.980632262901, 3638.872639457633, 3700.4449332600057, 3969.7221194516396, 3976.0, 3978.8806335809973, 5413.864653383697, 5465.398806131283, 5651.943480565183, 5706.686132262724, 5786.797462088806, 4144.274751918462, 4322.740635561876, 4406.294992801137, 4821.927671084936, 4884.853454396374, 4035.7982100895424, 4152.750834949913, 4381.539938306496, 4909.085456671901, 4987.850364489072, 4179.665626749853, 4336.479622445327, 4425.933610995838, 4750.928736068961, 4810.350602668665, 5183.585313174937, 5374.951884382508, 5392.218128371443, 5392.757325920343, 5459.2629994950175, 1366.3987845348015, 1600.663860589266, 1608.8230294667578, 1631.6247263129546, 1689.7803285572797, 1194.1760185471996, 1250.724840535099, 1299.91550549214, 1329.876254564891, 1333.714206912847, 4338.414314067623, 4511.503734589258, 4561.589456948891, 4754.453237877619, 4790.173797459515, 4393.692441529126, 5490.399792212002, 5532.612717109844, 5568.49883510486, 5661.858453538694, 4231.894202644959, 4236.046889449849, 4496.95388598187, 4795.976020119877, 4830.3237546743485, 2625.186192280399, 2693.5959606058855, 2788.47642883191, 2857.9428411431654, 2890.841003744772, 5099.909037294691, 5676.153561831238, 5681.630694004876, 5686.488216060575, 5692.857678557986, 5119.105930805626, 5173.473825221568, 5284.784499565121, 5351.732413379315, 5678.801241956594, 5148.665336753099, 5306.97346513265, 5355.785768569292, 5721.141828725725, 5814.854628634318, 4670.719756814602, 4950.77721502526, 5035.496450355082, 5465.617406781487, 5562.332520097614, 4740.6444516660695, 5323.520883120537, 5384.380796208329, 5425.294711687456, 5483.259759932358, 4720.583824852599, 5331.733413329395, 5429.782808687608, 5460.5085542301385, 5764.15547024948, 4272.636825869836, 4639.628829693549, 4793.9280910787065, 4855.975720121377, 4928.236123400879, 4767.0, 5286.889753151881, 5446.727663616883, 5450.373207081237, 5455.027010279487, 4547.681662283609, 5102.311187989574, 5124.180131178993, 5225.498915726548, 5305.708186049805, 4915.483874193301, 5169.948300517097, 5429.565634749131, 5438.401775804579, 5461.808836690697, 4439.511653718153, 4548.818047278071, 5003.574696150722, 5058.6206034548295, 5301.038615712211, 4649.930251046305, 5418.965379387556, 5436.075867102523, 5467.343918729778, 5469.453054694658, 4548.499665036909, 4632.328312394751, 5060.22891987816, 5282.207668849704, 5401.594880383908], "value": 4079.209808319821, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [784.2432053068787, 795.6260557537959, 819.7827575692439, 872.0568082720815, 881.7310720230329, 12903.290319032449, 16576.58558536037, 16581.917090414532, 16629.085400302978, 16631.66736665266, 15323.080615163099, 15360.923195384035, 15364.923175384107, 15397.999130056538, 15418.30617622207, 952.752284406056, 969.0696930946265, 985.1281615770024, 988.574912787501, 1000.6697789729408, 930.5765876526198, 964.5225613321415, 968.1818863060703, 989.9059589339038, 990.0940639315019, 1055.1031623120325, 1056.8467572202026, 1057.3814318623615, 1057.9576816927313, 1059.1050562274866, 1050.154625526451, 1050.6952983634737, 1051.6897515233024, 1052.7631282961272, 1053.052252972327, 773.6827900560743, 775.7052320118319, 776.6388629287409, 776.6543887969799, 776.7242628866784, 16665.583360415967, 16710.832891671173, 16738.16309184548, 17268.0, 17576.824231757648, 14408.495702650356, 14545.563633091024, 14624.780628290584, 14640.780388294077, 14674.0, 11546.422678866023, 11824.23142495735, 11896.881031189663, 12018.939905300505, 12081.395930203455, 3743.9625504567107, 3772.245550889833, 3789.8876739266475, 3809.5999920008276, 3819.159784847357, 112149.0, 116578.83421165764, 117009.41495292554, 118592.0, 121281.0, 11027.669169924915, 11138.33170009802, 11207.66377008691, 11208.943955280334, 11786.94106529479, 23581.88209058978, 23665.7633423667, 23902.282931512083, 23927.042918283074, 23957.640635390482, 7968.270777346081, 8032.317253033558, 8118.3099436548555, 8131.422174243432, 8180.877286840702, 4307.913841723148, 4415.845445409395, 4487.102579484051, 4626.629869610423, 4736.005438857878, 3254.5931758530182, 3312.089175476763, 3313.370459612666, 3321.020299011749, 3336.566246387954, 508.28640601383364, 621.6262060845492, 627.1940556385115, 629.5624540944, 631.4979591224987, 648.9978416584888, 649.3084864619166, 660.96364699941, 663.2604645819877, 675.1391975231619, 9146.405483643543, 9669.776100829422, 9993.949673183248, 10172.694819155437, 10343.327683700685, 633.9461145802659, 647.6179054357951, 648.8767134244479, 671.7380221713536, 686.5743239191734, 4653.790579423934, 4854.0, 4871.780769865364, 4878.390201224847, 4878.414590249122, 109081.90918090877, 109871.0, 112813.30780038306, 115128.42435787768, 115463.0, 53073.469265306856, 80448.0, 81646.0, 93769.06230937739, 98656.52015219777, 68852.31147688559, 72796.0, 73630.0, 73942.99999999892, 74638.25361746312, 11063.446827658423, 11139.275947063416, 11323.879309054604, 11425.657230283105, 11441.942790285995, 47903.76048119737, 49023.50976490189, 49154.50845491499, 49951.25073123833, 50278.49721502738, 54658.45341546534, 61893.45266368377, 64743.0, 64993.35006649873, 66043.00935486068, 12737.363131843185, 12892.226466412045, 13051.869481305444, 13074.910938939933, 13591.932040339734, 14831.851681483047, 14845.92577037108, 14898.478553250585, 15231.923840380727, 15813.130277834829, 13782.310884455537, 13832.853025936567, 13867.653308667364, 14055.789163162357, 14161.291935403395, 13457.596272111461, 13477.326133693472, 13656.726865462833, 13823.930880345533, 14340.484297957562, 13536.932315338361, 13571.321433928462, 13734.519291824738, 14072.437102515785, 14680.192589407257, 10145.688082565028, 10196.694099177037, 10480.475976201313, 10586.788264234827, 10756.031957123896, 10871.836922446328, 11260.7747845041, 11404.201705880509, 11523.13576481784, 11710.882891170979], "value": 7795.228765477374, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1441.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 115.0, 113.0, 115.0, 116.0, 116.0, 116.0, 116.0, 120.0, 171.0], "value": 120.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 107.0, 107.0, 144.0, 109.0, 130.0, 138.0, 109.0, 127.0, 108.0], "value": 122.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 184.0, 185.0, 189.0, 268.0, 220.0, 186.0, 185.0, 184.0], "value": 197.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 63.0, 59.0, 93.0, 63.0, 62.0, 62.0, 60.0, 75.0, 61.0], "value": 68.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 70.0, 115.0, 67.0, 59.0, 91.0, 110.0, 100.0, 100.0, 83.0], "value": 88.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 87.0, 87.0, 87.0, 86.0, 87.0, 87.0, 88.0, 87.0], "value": 87.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 99.0, 94.0, 95.0, 95.0, 98.0, 95.0, 94.0], "value": 95.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 83.0, 86.0, 83.0, 86.0, 83.0, 86.0, 85.0, 84.0], "value": 84.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 62.0, 63.0, 65.0, 63.0, 63.0, 63.0, 65.0, 63.0], "value": 63.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 60.0, 71.0, 62.0, 59.0, 58.0, 60.0, 59.0, 59.0, 60.0], "value": 61.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 85.0, 86.0, 85.0, 85.0, 84.0, 93.0, 75.0, 85.0], "value": 84.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 138.0, 129.0, 134.0, 134.0, 133.0, 144.0, 145.0, 181.0, 155.0], "value": 143.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 163.0, 165.0, 163.0, 164.0, 166.0, 161.0, 158.0, 160.0, 146.0], "value": 161.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 64.0, 64.0, 69.0, 64.0, 63.0, 66.0, 64.0, 63.0], "value": 64.7, "unit": "score"}]}]} 04:44:39 INFO - Return code: 0 04:44:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:44:39 INFO - # TBPL SUCCESS # 04:44:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:44:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:44:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:44:39 INFO - 2017-05-29 04:44:39.302 screenresolution[1947:18699] starting screenresolution argv=screenresolution get 04:44:39 INFO - 2017-05-29 04:44:39.304 screenresolution[1947:18699] Display 0: 1600x1200x32@60 04:44:39 INFO - 2017-05-29 04:44:39.310 screenresolution[1948:18702] starting screenresolution argv=screenresolution list 04:44:39 INFO - Available Modes on Display 0 04:44:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:44:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:44:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:44:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:44:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:44:39 INFO - Intel Iris: 04:44:39 INFO - Chipset Model: Intel Iris 04:44:39 INFO - Type: GPU 04:44:39 INFO - Bus: Built-In 04:44:39 INFO - VRAM (Dynamic, Max): 1536 MB 04:44:39 INFO - Vendor: Intel (0x8086) 04:44:39 INFO - Device ID: 0x0a2e 04:44:39 INFO - Revision ID: 0x0009 04:44:39 INFO - Displays: 04:44:39 INFO - Display: 04:44:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:44:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:44:39 INFO - Display Serial Number: 1600x1200 60 04:44:39 INFO - Main Display: Yes 04:44:39 INFO - Mirror: Off 04:44:39 INFO - Online: Yes 04:44:39 INFO - Rotation: Supported 04:44:39 INFO - Return code: 0 04:44:39 INFO - Running post-action listener: _resource_record_post_action 04:44:39 INFO - [mozharness: 2017-05-29 11:44:39.546610Z] Finished run-tests step (success) 04:44:39 INFO - Running post-run listener: _resource_record_post_run 04:44:39 INFO - Total resource usage - Wall time: 1199s; CPU: 54.0%; Read bytes: 851968; Write bytes: 509640192; Read time: 64; Write time: 1843 04:44:39 INFO - TinderboxPrint: CPU usage
53.8% 04:44:39 INFO - TinderboxPrint: I/O read bytes / time
851,968 / 64 04:44:39 INFO - TinderboxPrint: I/O write bytes / time
509,640,192 / 1,843 04:44:39 INFO - TinderboxPrint: CPU idle
2,217.8 (46.2%) 04:44:39 INFO - TinderboxPrint: CPU system
959.4 (20.0%) 04:44:39 INFO - TinderboxPrint: CPU user
1,620.6 (33.8%) 04:44:39 INFO - TinderboxPrint: Swap in / out
209,502,208 / 0 04:44:39 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 201878016; Write bytes: 202118656; Read time: 15559; Write time: 453 04:44:39 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 851968; Write bytes: 296728576; Read time: 64; Write time: 1354 04:44:40 INFO - Running post-run listener: _upload_blobber_files 04:44:40 INFO - Blob upload gear active. 04:44:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:44:40 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 04:44:40 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'] 04:44:40 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 04:44:40 INFO - (blobuploader) - INFO - Open directory for files ... 04:44:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:44:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:44:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:44:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:44:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:44:41 INFO - (blobuploader) - INFO - Done attempting. 04:44:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:44:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:44:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:44:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:44:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:44:42 INFO - (blobuploader) - INFO - Done attempting. 04:44:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:44:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:44:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:44:43 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:44:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:44:43 INFO - (blobuploader) - INFO - Done attempting. 04:44:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:44:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:44:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:44:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:44:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:44:45 INFO - (blobuploader) - INFO - Done attempting. 04:44:45 INFO - (blobuploader) - INFO - Iteration through files over. 04:44:45 INFO - Return code: 0 04:44:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:44:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:44:45 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a44345c71ccbae3e583719822dbf7e7ca8f1dea6c132bb20c2adce92c21ae4bf62b57189b049f3e3c24cdbdc7d393b41717993ea8ac6c0b45010d78e0ae35bb8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ebace148b8f2d42fb7f62b8173b75363f3222750ff312f79b654dc19764990c5838b7bf7cef4c739f372af0739dabbff343d269671e486f74591148198eee549", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5d59292e674b2d114012e3c41c40238040189f4147ff53f7ea75f283843676b98cc387cae313e67e558cf4cbf96e5f8e8b773f9d88e885476095a531c9c028c5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0f3c01dff29fd5a060ff366d23e74c1cfcae8f1e97dd6d2dc2289351841910da919bb0224c7fea5230a86b052bf737938876c0c5cdd1a0f50926b12507bd7eac"} 04:44:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:44:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:44:45 INFO - Contents: 04:44:45 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a44345c71ccbae3e583719822dbf7e7ca8f1dea6c132bb20c2adce92c21ae4bf62b57189b049f3e3c24cdbdc7d393b41717993ea8ac6c0b45010d78e0ae35bb8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ebace148b8f2d42fb7f62b8173b75363f3222750ff312f79b654dc19764990c5838b7bf7cef4c739f372af0739dabbff343d269671e486f74591148198eee549", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5d59292e674b2d114012e3c41c40238040189f4147ff53f7ea75f283843676b98cc387cae313e67e558cf4cbf96e5f8e8b773f9d88e885476095a531c9c028c5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0f3c01dff29fd5a060ff366d23e74c1cfcae8f1e97dd6d2dc2289351841910da919bb0224c7fea5230a86b052bf737938876c0c5cdd1a0f50926b12507bd7eac"} 04:44:45 INFO - Running post-run listener: copy_logs_to_upload_dir 04:44:45 INFO - Copying logs to upload dir... 04:44:45 INFO - mkdir: /builds/slave/test/build/upload/logs 04:44:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1278.880915 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2017-05-29 04:44:45.900450) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-29 04:44:45.906340) ========= 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.i5kNfndone/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.LCPmITI8ed/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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/a44345c71ccbae3e583719822dbf7e7ca8f1dea6c132bb20c2adce92c21ae4bf62b57189b049f3e3c24cdbdc7d393b41717993ea8ac6c0b45010d78e0ae35bb8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ebace148b8f2d42fb7f62b8173b75363f3222750ff312f79b654dc19764990c5838b7bf7cef4c739f372af0739dabbff343d269671e486f74591148198eee549", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5d59292e674b2d114012e3c41c40238040189f4147ff53f7ea75f283843676b98cc387cae313e67e558cf4cbf96e5f8e8b773f9d88e885476095a531c9c028c5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0f3c01dff29fd5a060ff366d23e74c1cfcae8f1e97dd6d2dc2289351841910da919bb0224c7fea5230a86b052bf737938876c0c5cdd1a0f50926b12507bd7eac"} build_url:https://queue.taskcluster.net/v1/task/JUNOfCotQ22MMyMNx_CQqg/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011100 build_url: 'https://queue.taskcluster.net/v1/task/JUNOfCotQ22MMyMNx_CQqg/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/a44345c71ccbae3e583719822dbf7e7ca8f1dea6c132bb20c2adce92c21ae4bf62b57189b049f3e3c24cdbdc7d393b41717993ea8ac6c0b45010d78e0ae35bb8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ebace148b8f2d42fb7f62b8173b75363f3222750ff312f79b654dc19764990c5838b7bf7cef4c739f372af0739dabbff343d269671e486f74591148198eee549", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5d59292e674b2d114012e3c41c40238040189f4147ff53f7ea75f283843676b98cc387cae313e67e558cf4cbf96e5f8e8b773f9d88e885476095a531c9c028c5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0f3c01dff29fd5a060ff366d23e74c1cfcae8f1e97dd6d2dc2289351841910da919bb0224c7fea5230a86b052bf737938876c0c5cdd1a0f50926b12507bd7eac"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-29 04:44:45.943407) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 04:44:45.943739) ========= 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.i5kNfndone/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.LCPmITI8ed/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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.006475 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 04:44:45.997048) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-29 04:44:45.997364) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-29 04:44:45.997680) ========= ========= Total master_lag: 0.18 =========