builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs slave: t-yosemite-r7-0196 starttime: 1496056049.94 results: success (0) buildid: 20170529024915 builduid: 03fa2df149914ab7ac5eeef7dbe81ea2 revision: 6fb66467485a8beee11dad38a08768c2a9955fe7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-29 04:07:29.941996) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-29 04:07:29.942331) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-29 04:07:29.942593) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-29 04:07:30.130826) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 04:07:30.131083) ========= 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.43g8oLvNs3/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.N6RjKhZUDI/Listeners TMPDIR=/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-29 04:07:30-- 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% 171M=0s 2017-05-29 04:07:30 (171 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.595440 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 04:07:30.752651) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 04:07:30.755987) ========= 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.43g8oLvNs3/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.N6RjKhZUDI/Listeners TMPDIR=/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.036369 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 04:07:30.841508) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-29 04:07:30.842011) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 6fb66467485a8beee11dad38a08768c2a9955fe7 --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 6fb66467485a8beee11dad38a08768c2a9955fe7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.43g8oLvNs3/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.N6RjKhZUDI/Listeners TMPDIR=/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-29 04:07:30,936 truncating revision to first 12 chars 2017-05-29 04:07:30,936 Setting DEBUG logging. 2017-05-29 04:07:30,936 attempt 1/10 2017-05-29 04:07:30,936 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/6fb66467485a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-29 04:07:32,180 unpacking tar archive at: mozilla-beta-6fb66467485a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.520159 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-29 04:07:32.384685) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-29 04:07:32.385005) ========= script_repo_revision: 6fb66467485a8beee11dad38a08768c2a9955fe7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-29 04:07:32.385377) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-29 04:07:32.385639) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-29 04:07:32.404606) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-05-29 04:07:32.405025) ========= /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.43g8oLvNs3/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.N6RjKhZUDI/Listeners TMPDIR=/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 04:07:32 INFO - MultiFileLogger online at 20170529 04:07:32 in /builds/slave/test 04:07:32 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:07:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:07:32 INFO - {'append_to_log': False, 04:07:32 INFO - 'base_work_dir': '/builds/slave/test', 04:07:32 INFO - 'blob_upload_branch': 'Mozilla-Beta', 04:07:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:07:32 INFO - 'branch': 'Mozilla-Beta', 04:07:32 INFO - 'buildbot_json_path': 'buildprops.json', 04:07:32 INFO - 'config_files': ('talos/mac_config.py',), 04:07:32 INFO - 'default_actions': ('clobber', 04:07:32 INFO - 'read-buildbot-config', 04:07:32 INFO - 'download-and-extract', 04:07:32 INFO - 'populate-webroot', 04:07:32 INFO - 'create-virtualenv', 04:07:32 INFO - 'install', 04:07:32 INFO - 'run-tests'), 04:07:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:07:32 INFO - 'download_minidump_stackwalk': True, 04:07:32 INFO - 'download_symbols': 'ondemand', 04:07:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:07:32 INFO - 'tooltool.py': '/tools/tooltool.py', 04:07:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:07:32 INFO - '/tools/misc-python/virtualenv.py')}, 04:07:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:07:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:07:32 INFO - 'gecko_profile': False, 04:07:32 INFO - 'gecko_profile_interval': 0, 04:07:32 INFO - 'installer_path': 'installer.exe', 04:07:32 INFO - 'log_level': 'info', 04:07:32 INFO - 'log_name': 'talos', 04:07:32 INFO - 'log_to_console': True, 04:07:32 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:07:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:07:32 INFO - 'opt_config_files': (), 04:07:32 INFO - 'pip_index': False, 04:07:32 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:07:32 INFO - 'cmd': ('bash', 04:07:32 INFO - '-c', 04:07:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:07:32 INFO - 'enabled': True, 04:07:32 INFO - 'halt_on_failure': False, 04:07:32 INFO - 'name': 'check_screen_resolution'},), 04:07:32 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:07:32 INFO - 'cmd': ('bash', 04:07:32 INFO - '-c', 04:07:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:07:32 INFO - 'enabled': True, 04:07:32 INFO - 'halt_on_failure': False, 04:07:32 INFO - 'name': 'check_screen_resolution'},), 04:07:32 INFO - 'run_cmd_checks_enabled': True, 04:07:32 INFO - 'suite': 'dromaeojs', 04:07:32 INFO - 'system_bits': '32', 04:07:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:07:32 INFO - 'title': 't-yosemite-r7-0196', 04:07:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:07:32 INFO - 'use_talos_json': True, 04:07:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:07:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:07:32 INFO - 'work_dir': 'build'} 04:07:32 INFO - [mozharness: 2017-05-29 11:07:32.614804Z] Running clobber step. 04:07:32 INFO - Running pre-action listener: _resource_record_pre_action 04:07:32 INFO - Running main action method: clobber 04:07:32 INFO - rmtree: /builds/slave/test/build 04:07:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:07:35 INFO - Running post-action listener: _resource_record_post_action 04:07:35 INFO - [mozharness: 2017-05-29 11:07:35.228561Z] Finished clobber step (success) 04:07:35 INFO - [mozharness: 2017-05-29 11:07:35.228729Z] Running read-buildbot-config step. 04:07:35 INFO - Running pre-action listener: _resource_record_pre_action 04:07:35 INFO - Running main action method: read_buildbot_config 04:07:35 INFO - Using buildbot properties: 04:07:35 INFO - { 04:07:35 INFO - "project": "", 04:07:35 INFO - "product": "firefox", 04:07:35 INFO - "script_repo_revision": "production", 04:07:35 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs", 04:07:35 INFO - "repository": "", 04:07:35 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs", 04:07:35 INFO - "buildid": "20170529024915", 04:07:35 INFO - "pgo_build": "False", 04:07:35 INFO - "basedir": "/builds/slave/test", 04:07:35 INFO - "buildnumber": 0, 04:07:35 INFO - "slavename": "t-yosemite-r7-0196", 04:07:35 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 04:07:35 INFO - "platform": "yosemite_r7_devedition", 04:07:35 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 04:07:35 INFO - "branch": "mozilla-beta", 04:07:35 INFO - "repo_path": "releases/mozilla-beta", 04:07:35 INFO - "revision": "6fb66467485a8beee11dad38a08768c2a9955fe7", 04:07:35 INFO - "stage_platform": "macosx64-devedition", 04:07:35 INFO - "builduid": "03fa2df149914ab7ac5eeef7dbe81ea2", 04:07:35 INFO - "slavebuilddir": "test" 04:07:35 INFO - } 04:07:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/Ur1N-XzpRC2SW2bYKyD7Lg/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 04:07:35 INFO - Running post-action listener: _resource_record_post_action 04:07:35 INFO - [mozharness: 2017-05-29 11:07:35.232054Z] Finished read-buildbot-config step (success) 04:07:35 INFO - [mozharness: 2017-05-29 11:07:35.232171Z] Running download-and-extract step. 04:07:35 INFO - Running pre-action listener: _resource_record_pre_action 04:07:35 INFO - Running main action method: download_and_extract 04:07:35 INFO - mkdir: /builds/slave/test/build/tests 04:07: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:07:35 INFO - trying https://queue.taskcluster.net/v1/task/Ur1N-XzpRC2SW2bYKyD7Lg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 04:07:35 INFO - Downloading https://queue.taskcluster.net/v1/task/Ur1N-XzpRC2SW2bYKyD7Lg/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:07:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ur1N-XzpRC2SW2bYKyD7Lg/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:07:36 INFO - Downloaded 1287 bytes. 04:07:36 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 04:07:36 INFO - Using the following test package requirements: 04:07:36 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:07:36 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 04:07:36 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 04:07:36 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:07:36 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 04:07:36 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:07:36 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 04:07:36 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 04:07:36 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:07:36 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 04:07:36 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 04:07:36 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:07:36 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 04:07:36 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:07:36 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 04:07:36 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:07:36 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 04:07:36 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:07:36 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 04:07:36 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 04:07:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ur1N-XzpRC2SW2bYKyD7Lg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 04:07:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ur1N-XzpRC2SW2bYKyD7Lg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 04:07:36 INFO - Fetch https://queue.taskcluster.net/v1/task/Ur1N-XzpRC2SW2bYKyD7Lg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 04:07:38 INFO - Expected file size: 51969094 04:07:38 INFO - Obtained file size: 51969094 04:07:54 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:07:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ur1N-XzpRC2SW2bYKyD7Lg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 04:07:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ur1N-XzpRC2SW2bYKyD7Lg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 04:07:54 INFO - Fetch https://queue.taskcluster.net/v1/task/Ur1N-XzpRC2SW2bYKyD7Lg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 04:07:55 INFO - Expected file size: 51969094 04:07:55 INFO - Obtained file size: 51969094 04:08:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ur1N-XzpRC2SW2bYKyD7Lg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 04:08:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ur1N-XzpRC2SW2bYKyD7Lg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 04:08:11 INFO - Fetch https://queue.taskcluster.net/v1/task/Ur1N-XzpRC2SW2bYKyD7Lg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 04:08:12 INFO - Expected file size: 14404461 04:08:12 INFO - Obtained file size: 14404461 04:08:12 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:08:12 INFO - trying https://queue.taskcluster.net/v1/task/Ur1N-XzpRC2SW2bYKyD7Lg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 04:08:12 INFO - Downloading https://queue.taskcluster.net/v1/task/Ur1N-XzpRC2SW2bYKyD7Lg/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 04:08:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ur1N-XzpRC2SW2bYKyD7Lg/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:08:14 INFO - Downloaded 74896488 bytes. 04:08:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ur1N-XzpRC2SW2bYKyD7Lg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 04:08:14 INFO - mkdir: /builds/slave/test/properties 04:08:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:08:14 INFO - Writing to file /builds/slave/test/properties/build_url 04:08:14 INFO - Contents: 04:08:14 INFO - build_url:https://queue.taskcluster.net/v1/task/Ur1N-XzpRC2SW2bYKyD7Lg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 04:08:15 INFO - Running post-action listener: _resource_record_post_action 04:08:15 INFO - Running post-action listener: set_extra_try_arguments 04:08:15 INFO - [mozharness: 2017-05-29 11:08:15.509846Z] Finished download-and-extract step (success) 04:08:15 INFO - [mozharness: 2017-05-29 11:08:15.509963Z] Running populate-webroot step. 04:08:15 INFO - Running pre-action listener: _resource_record_pre_action 04:08:15 INFO - Running main action method: populate_webroot 04:08:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:08:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:08:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:08:15 INFO - '--tppagecycles', 04:08:15 INFO - '7'], 04:08:15 INFO - 'tests': ['tsvgm']}}, 04:08:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:08:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:08:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:08:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:08:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:08:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:08:15 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:08:15 INFO - 'talos_options': ['--disable-e10s'], 04:08:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:08:15 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:08:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:08:15 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:08:15 INFO - 'talos_options': ['--disable-e10s'], 04:08:15 INFO - 'tests': ['damp', 'tps']}, 04:08:15 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:08:15 INFO - 'tests': ['damp', 'tps']}, 04:08:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:08:15 INFO - 'tests': ['dromaeo_dom']}, 04:08:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:08:15 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:08:15 INFO - 'tests': ['basic_compositor_video']}, 04:08:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:08:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:08:15 INFO - 'tests': ['a11yr', 04:08:15 INFO - 'ts_paint', 04:08:15 INFO - 'tpaint', 04:08:15 INFO - 'sessionrestore', 04:08:15 INFO - 'sessionrestore_no_auto_restore', 04:08:15 INFO - 'tabpaint']}, 04:08:15 INFO - 'other-e10s': {'tests': ['a11yr', 04:08:15 INFO - 'ts_paint', 04:08:15 INFO - 'tpaint', 04:08:15 INFO - 'sessionrestore', 04:08:15 INFO - 'sessionrestore_no_auto_restore', 04:08:15 INFO - 'tabpaint']}, 04:08:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:08:15 INFO - 'ts_paint', 04:08:15 INFO - 'tpaint', 04:08:15 INFO - 'sessionrestore', 04:08:15 INFO - 'sessionrestore_no_auto_restore', 04:08:15 INFO - 'tabpaint']}, 04:08:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:08:15 INFO - 'ts_paint', 04:08:15 INFO - 'tpaint', 04:08:15 INFO - 'sessionrestore', 04:08:15 INFO - 'sessionrestore_no_auto_restore', 04:08:15 INFO - 'tabpaint']}, 04:08:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:08:15 INFO - 'tests': ['a11yr', 04:08:15 INFO - 'ts_paint', 04:08:15 INFO - 'tpaint', 04:08:15 INFO - 'sessionrestore', 04:08:15 INFO - 'sessionrestore_no_auto_restore', 04:08:15 INFO - 'tabpaint']}, 04:08:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:08:15 INFO - 'tests': ['a11yr', 04:08:15 INFO - 'ts_paint', 04:08:15 INFO - 'tpaint', 04:08:15 INFO - 'sessionrestore', 04:08:15 INFO - 'sessionrestore_no_auto_restore', 04:08:15 INFO - 'tabpaint']}, 04:08:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:08:15 INFO - 'tests': ['tsvgx', 04:08:15 INFO - 'tsvgr_opacity', 04:08:15 INFO - 'tart', 04:08:15 INFO - 'tscrollx', 04:08:15 INFO - 'cart', 04:08:15 INFO - 'tsvg_static']}, 04:08:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:08:15 INFO - 'tsvgr_opacity', 04:08:15 INFO - 'tart', 04:08:15 INFO - 'tscrollx', 04:08:15 INFO - 'cart', 04:08:15 INFO - 'tsvg_static']}, 04:08:15 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:08:15 INFO - 'talos_options': ['--disable-e10s'], 04:08:15 INFO - 'tests': ['tp5o']}, 04:08:15 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:08:15 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:08:15 INFO - 'talos_options': ['--disable-e10s', 04:08:15 INFO - '--xperf_path', 04:08:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:08:15 INFO - 'tests': ['tp5n']}, 04:08:15 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:08:15 INFO - 'talos_options': ['--xperf_path', 04:08:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:08:15 INFO - 'tests': ['tp5n']}}, 04:08:15 INFO - 'talos.zip': {'path': '', 04:08:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:08:15 INFO - Running post-action listener: _resource_record_post_action 04:08:15 INFO - [mozharness: 2017-05-29 11:08:15.528523Z] Finished populate-webroot step (success) 04:08:15 INFO - [mozharness: 2017-05-29 11:08:15.528648Z] Running create-virtualenv step. 04:08:15 INFO - Running pre-action listener: _resource_record_pre_action 04:08:15 INFO - Running main action method: create_virtualenv 04:08:15 INFO - Creating virtualenv /builds/slave/test/build/venv 04:08:15 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:08:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:08:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:08:15 INFO - Using real prefix '/tools/python27' 04:08:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:08:16 INFO - Installing distribute.............................................................................................................................................................................................done. 04:08:19 INFO - Installing pip.................done. 04:08:19 INFO - Return code: 0 04:08:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:08:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:08:19 INFO - Reading from file tmpfile_stdout 04:08:19 INFO - Output received: 04:08:19 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:08:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:08:19 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:08:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:08:19 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:08:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:08:19 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 0x10eb73b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb76768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc303c4ae90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e951d30>, '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 0x10e878db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e946be8>, '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.43g8oLvNs3/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.N6RjKhZUDI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:08:19 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:08:19 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:08:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.43g8oLvNs3/Render', 04:08:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:08:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:08:19 INFO - 'HOME': '/Users/cltbld', 04:08:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:08:19 INFO - 'LOGNAME': 'cltbld', 04:08:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:08:19 INFO - 'MOZ_NO_REMOTE': '1', 04:08:19 INFO - 'NO_EM_RESTART': '1', 04:08:19 INFO - 'PAGER': '/bin/cat', 04:08:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:08:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:08:19 INFO - 'PWD': '/builds/slave/test', 04:08:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:08:19 INFO - 'SHELL': '/bin/bash', 04:08:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N6RjKhZUDI/Listeners', 04:08:19 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 04:08:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:08:19 INFO - 'USER': 'cltbld', 04:08:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:08:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:08:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:08:19 INFO - 'XPC_FLAGS': '0x0', 04:08:19 INFO - 'XPC_SERVICE_NAME': '0', 04:08:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:08:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:08:19 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:08:19 INFO - Cleaning up... 04:08:19 INFO - Return code: 0 04:08:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:08:19 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:08:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:08:19 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:08:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:08:19 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 0x10eb73b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb76768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc303c4ae90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e951d30>, '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 0x10e878db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e946be8>, '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.43g8oLvNs3/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.N6RjKhZUDI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:08:19 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:08:19 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:08:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.43g8oLvNs3/Render', 04:08:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:08:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:08:19 INFO - 'HOME': '/Users/cltbld', 04:08:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:08:19 INFO - 'LOGNAME': 'cltbld', 04:08:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:08:19 INFO - 'MOZ_NO_REMOTE': '1', 04:08:19 INFO - 'NO_EM_RESTART': '1', 04:08:19 INFO - 'PAGER': '/bin/cat', 04:08:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:08:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:08:19 INFO - 'PWD': '/builds/slave/test', 04:08:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:08:19 INFO - 'SHELL': '/bin/bash', 04:08:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N6RjKhZUDI/Listeners', 04:08:19 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 04:08:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:08:19 INFO - 'USER': 'cltbld', 04:08:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:08:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:08:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:08:19 INFO - 'XPC_FLAGS': '0x0', 04:08:19 INFO - 'XPC_SERVICE_NAME': '0', 04:08:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:08:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:08:20 INFO - Downloading/unpacking psutil>=3.1.1 04:08:20 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:08:20 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:08:20 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:08:20 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:08:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:08:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:08:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:08:22 INFO - Installing collected packages: psutil 04:08:22 INFO - Running setup.py install for psutil 04:08:22 INFO - building 'psutil._psutil_osx' extension 04:08:22 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:08:23 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:08:23 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:08:23 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:08:23 INFO - building 'psutil._psutil_posix' extension 04:08:23 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:08:23 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:08:23 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:08:23 INFO - ^ 04:08:23 INFO - 1 warning generated. 04:08:23 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:08:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:08:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:08:23 INFO - Successfully installed psutil 04:08:23 INFO - Cleaning up... 04:08:23 INFO - Return code: 0 04:08:23 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:08: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:08:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:08: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:08:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:08: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10eb73b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb76768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc303c4ae90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e951d30>, '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 0x10e878db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e946be8>, '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.43g8oLvNs3/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.N6RjKhZUDI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:08: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:08: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 mozsystemmonitor==0.3 04:08:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.43g8oLvNs3/Render', 04:08:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:08:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:08:23 INFO - 'HOME': '/Users/cltbld', 04:08:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:08:23 INFO - 'LOGNAME': 'cltbld', 04:08:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:08:23 INFO - 'MOZ_NO_REMOTE': '1', 04:08:23 INFO - 'NO_EM_RESTART': '1', 04:08:23 INFO - 'PAGER': '/bin/cat', 04:08:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:08:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:08:23 INFO - 'PWD': '/builds/slave/test', 04:08:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:08:23 INFO - 'SHELL': '/bin/bash', 04:08:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N6RjKhZUDI/Listeners', 04:08:23 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 04:08:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:08:23 INFO - 'USER': 'cltbld', 04:08:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:08:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:08:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:08:23 INFO - 'XPC_FLAGS': '0x0', 04:08:23 INFO - 'XPC_SERVICE_NAME': '0', 04:08:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:08:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:08:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:08: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:08: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:08: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:08: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:08:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:08:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:08:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:08:25 INFO - Installing collected packages: mozsystemmonitor 04:08:25 INFO - Running setup.py install for mozsystemmonitor 04:08:25 INFO - Successfully installed mozsystemmonitor 04:08:25 INFO - Cleaning up... 04:08:25 INFO - Return code: 0 04:08:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:08:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:08:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:08:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:08:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:08:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10eb73b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb76768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc303c4ae90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e951d30>, '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 0x10e878db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e946be8>, '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.43g8oLvNs3/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.N6RjKhZUDI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:08:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:08:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:08:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.43g8oLvNs3/Render', 04:08:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:08:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:08:25 INFO - 'HOME': '/Users/cltbld', 04:08:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:08:25 INFO - 'LOGNAME': 'cltbld', 04:08:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:08:25 INFO - 'MOZ_NO_REMOTE': '1', 04:08:25 INFO - 'NO_EM_RESTART': '1', 04:08:25 INFO - 'PAGER': '/bin/cat', 04:08:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:08:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:08:25 INFO - 'PWD': '/builds/slave/test', 04:08:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:08:25 INFO - 'SHELL': '/bin/bash', 04:08:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N6RjKhZUDI/Listeners', 04:08:25 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 04:08:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:08:25 INFO - 'USER': 'cltbld', 04:08:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:08:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:08:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:08:25 INFO - 'XPC_FLAGS': '0x0', 04:08:25 INFO - 'XPC_SERVICE_NAME': '0', 04:08:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:08:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:08:25 INFO - Downloading/unpacking jsonschema==2.5.1 04:08:25 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:08:25 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:08:25 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:08:25 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:08:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:08:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:08: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:08: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:08: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:08: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:08:27 INFO - Downloading functools32-3.2.3-2.tar.gz 04:08:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:08:27 INFO - warning: no files found matching '*.txt' 04:08:27 INFO - no previously-included directories found matching 'build' 04:08:27 INFO - no previously-included directories found matching 'dist' 04:08:27 INFO - no previously-included directories found matching '.git*' 04:08:27 INFO - Installing collected packages: jsonschema, functools32 04:08:27 INFO - Running setup.py install for functools32 04:08:27 INFO - warning: no files found matching '*.txt' 04:08:27 INFO - no previously-included directories found matching 'build' 04:08:27 INFO - no previously-included directories found matching 'dist' 04:08:27 INFO - no previously-included directories found matching '.git*' 04:08:27 INFO - Successfully installed jsonschema functools32 04:08:27 INFO - Cleaning up... 04:08:27 INFO - Return code: 0 04:08:27 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:08: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:08:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:08: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:08:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:08: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', '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 0x10eb73b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb76768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc303c4ae90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e951d30>, '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 0x10e878db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e946be8>, '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.43g8oLvNs3/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.N6RjKhZUDI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:08: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:08: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 functools32==3.2.3-2 04:08:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.43g8oLvNs3/Render', 04:08:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:08:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:08:27 INFO - 'HOME': '/Users/cltbld', 04:08:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:08:27 INFO - 'LOGNAME': 'cltbld', 04:08:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:08:27 INFO - 'MOZ_NO_REMOTE': '1', 04:08:27 INFO - 'NO_EM_RESTART': '1', 04:08:27 INFO - 'PAGER': '/bin/cat', 04:08:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:08:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:08:27 INFO - 'PWD': '/builds/slave/test', 04:08:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:08:27 INFO - 'SHELL': '/bin/bash', 04:08:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N6RjKhZUDI/Listeners', 04:08:27 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 04:08:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:08:27 INFO - 'USER': 'cltbld', 04:08:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:08:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:08:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:08:27 INFO - 'XPC_FLAGS': '0x0', 04:08:27 INFO - 'XPC_SERVICE_NAME': '0', 04:08:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:08:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:08:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:08:27 INFO - Cleaning up... 04:08:27 INFO - Return code: 0 04:08:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:08: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:08:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:08: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:08:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:08: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 0x10eb73b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb76768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc303c4ae90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e951d30>, '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 0x10e878db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e946be8>, '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.43g8oLvNs3/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.N6RjKhZUDI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:08: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:08: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:08:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.43g8oLvNs3/Render', 04:08:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:08:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:08:27 INFO - 'HOME': '/Users/cltbld', 04:08:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:08:27 INFO - 'LOGNAME': 'cltbld', 04:08:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:08:27 INFO - 'MOZ_NO_REMOTE': '1', 04:08:27 INFO - 'NO_EM_RESTART': '1', 04:08:27 INFO - 'PAGER': '/bin/cat', 04:08:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:08:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:08:27 INFO - 'PWD': '/builds/slave/test', 04:08:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:08:27 INFO - 'SHELL': '/bin/bash', 04:08:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N6RjKhZUDI/Listeners', 04:08:27 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 04:08:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:08:27 INFO - 'USER': 'cltbld', 04:08:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:08:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:08:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:08:27 INFO - 'XPC_FLAGS': '0x0', 04:08:27 INFO - 'XPC_SERVICE_NAME': '0', 04:08:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:08:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:08:28 INFO - Downloading/unpacking blobuploader==1.2.4 04:08: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:08: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:08: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:08: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:08:29 INFO - Downloading blobuploader-1.2.4.tar.gz 04:08:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:08:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:08: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:08: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:08: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:08: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:08:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:08:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:08:30 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:08:30 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:08:30 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:08:30 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:08:30 INFO - Downloading docopt-0.6.1.tar.gz 04:08:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:08:30 INFO - Installing collected packages: blobuploader, requests, docopt 04:08:30 INFO - Running setup.py install for blobuploader 04:08:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:08:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:08:30 INFO - Running setup.py install for requests 04:08:31 INFO - Running setup.py install for docopt 04:08:31 INFO - Successfully installed blobuploader requests docopt 04:08:31 INFO - Cleaning up... 04:08:31 INFO - Return code: 0 04:08:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:08:31 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:08:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:08:31 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:08:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:08:31 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 0x10eb73b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb76768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc303c4ae90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e951d30>, '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 0x10e878db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e946be8>, '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.43g8oLvNs3/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.N6RjKhZUDI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:08:31 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:08:31 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:08:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.43g8oLvNs3/Render', 04:08:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:08:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:08:31 INFO - 'HOME': '/Users/cltbld', 04:08:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:08:31 INFO - 'LOGNAME': 'cltbld', 04:08:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:08:31 INFO - 'MOZ_NO_REMOTE': '1', 04:08:31 INFO - 'NO_EM_RESTART': '1', 04:08:31 INFO - 'PAGER': '/bin/cat', 04:08:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:08:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:08:31 INFO - 'PWD': '/builds/slave/test', 04:08:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:08:31 INFO - 'SHELL': '/bin/bash', 04:08:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N6RjKhZUDI/Listeners', 04:08:31 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 04:08:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:08:31 INFO - 'USER': 'cltbld', 04:08:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:08:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:08:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:08:31 INFO - 'XPC_FLAGS': '0x0', 04:08:31 INFO - 'XPC_SERVICE_NAME': '0', 04:08:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:08:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:08:31 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-hNMaT3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:08:31 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-UwiXDF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:08:31 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-k02AbB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:08:31 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-ccLL5l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:08:32 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-5Zmk7Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:08:32 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-w7OV_y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:08:32 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-Jc7o70-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:08:32 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-PQAMaE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:08:32 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-N9B5VN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:08:32 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-LHgUOc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:08:32 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-VWHZyx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:08:32 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-LNmmih-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:08:32 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-tgOJfA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:08:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:08:33 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-lTGg82-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:08:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:08:33 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-HrayRz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:08:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:08:33 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-VFopL7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:08:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:08:33 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-P2OxoE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:08:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:08:33 INFO - Running setup.py install for manifestparser 04:08:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:08:33 INFO - Running setup.py install for mozcrash 04:08:33 INFO - Running setup.py install for mozdebug 04:08:33 INFO - Running setup.py install for mozdevice 04:08:34 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:08:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:08:34 INFO - Running setup.py install for mozfile 04:08:34 INFO - Running setup.py install for mozhttpd 04:08:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:08:34 INFO - Running setup.py install for mozinfo 04:08:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:08:34 INFO - Running setup.py install for mozInstall 04:08:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:08:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:08:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:08:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:08:34 INFO - Running setup.py install for mozleak 04:08:34 INFO - Running setup.py install for mozlog 04:08:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:08:34 INFO - Running setup.py install for moznetwork 04:08:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:08:35 INFO - Running setup.py install for mozprocess 04:08:35 INFO - Running setup.py install for mozprofile 04:08:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:08:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:08:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:08:35 INFO - Running setup.py install for mozrunner 04:08:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:08:35 INFO - Running setup.py install for mozscreenshot 04:08:35 INFO - Running setup.py install for moztest 04:08:35 INFO - Running setup.py install for mozversion 04:08:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:08:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:08:35 INFO - Cleaning up... 04:08:36 INFO - Return code: 0 04:08:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:08:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:08:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:08:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:08:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:08:36 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 0x10eb73b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb76768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc303c4ae90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e951d30>, '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 0x10e878db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e946be8>, '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.43g8oLvNs3/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.N6RjKhZUDI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:08:36 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:08:36 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:08:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.43g8oLvNs3/Render', 04:08:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:08:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:08:36 INFO - 'HOME': '/Users/cltbld', 04:08:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:08:36 INFO - 'LOGNAME': 'cltbld', 04:08:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:08:36 INFO - 'MOZ_NO_REMOTE': '1', 04:08:36 INFO - 'NO_EM_RESTART': '1', 04:08:36 INFO - 'PAGER': '/bin/cat', 04:08:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:08:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:08:36 INFO - 'PWD': '/builds/slave/test', 04:08:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:08:36 INFO - 'SHELL': '/bin/bash', 04:08:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N6RjKhZUDI/Listeners', 04:08:36 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 04:08:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:08:36 INFO - 'USER': 'cltbld', 04:08:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:08:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:08:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:08:36 INFO - 'XPC_FLAGS': '0x0', 04:08:36 INFO - 'XPC_SERVICE_NAME': '0', 04:08:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:08:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:08:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:08:36 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-RgPizp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:08:36 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:08:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:08:36 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-PK7Lax-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:08:36 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:08:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:08:36 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-ik1TP2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:08:36 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:08:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:08:36 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-k_tTec-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:08:36 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:08:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:08:36 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-B9cpfY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:08:36 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:08:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:08:36 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-DKwqEU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:08: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:08:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:08:36 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-jvRvUJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:08:37 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:08:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:08:37 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-IH8WPI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:08:37 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:08:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:08:37 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-71TDOg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:08:37 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:08:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:08:37 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-cJVw2u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:08:37 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:08:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:08:37 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-V8DTTX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:08:37 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:08:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:08:37 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-W7SxMi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:08:37 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:08:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:08:37 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-C8iPHc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:08:37 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:08:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:08:37 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-l2u5r8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:08:37 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:08:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:08:37 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-zO3Vnj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:08:37 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:08:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:08:37 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-0znBvH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:08: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:08:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:08:37 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-9Lw8W9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:08:38 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:08:38 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:08:38 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:08:38 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:08:38 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:08:38 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:08:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:08:38 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:08:38 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:08:38 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:08:38 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:08:39 INFO - Downloading blessings-1.6.tar.gz 04:08:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:08:39 INFO - Installing collected packages: blessings 04:08:39 INFO - Running setup.py install for blessings 04:08:39 INFO - Successfully installed blessings 04:08:39 INFO - Cleaning up... 04:08:39 INFO - Return code: 0 04:08:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:08:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:08:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:08:40 INFO - Reading from file tmpfile_stdout 04:08:40 INFO - Current package versions: 04:08:40 INFO - blessings == 1.6 04:08:40 INFO - blobuploader == 1.2.4 04:08:40 INFO - docopt == 0.6.1 04:08:40 INFO - functools32 == 3.2.3-2 04:08:40 INFO - jsonschema == 2.5.1 04:08:40 INFO - manifestparser == 1.1 04:08:40 INFO - mozInstall == 1.12 04:08:40 INFO - mozcrash == 1.0 04:08:40 INFO - mozdebug == 0.1 04:08:40 INFO - mozdevice == 0.49 04:08:40 INFO - mozfile == 1.2 04:08:40 INFO - mozhttpd == 0.7 04:08:40 INFO - mozinfo == 0.9 04:08:40 INFO - mozleak == 0.1 04:08:40 INFO - mozlog == 3.4 04:08:40 INFO - moznetwork == 0.27 04:08:40 INFO - mozprocess == 0.25 04:08:40 INFO - mozprofile == 0.28 04:08:40 INFO - mozrunner == 6.13 04:08:40 INFO - mozscreenshot == 0.1 04:08:40 INFO - mozsystemmonitor == 0.3 04:08:40 INFO - moztest == 0.8 04:08:40 INFO - mozversion == 1.4 04:08:40 INFO - psutil == 3.1.1 04:08:40 INFO - requests == 1.2.3 04:08:40 INFO - wsgiref == 0.1.2 04:08:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:08:40 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:08:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:08:40 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:08:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:08:40 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 0x10eb73b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb76768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc303c4ae90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e951d30>, '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 0x10e878db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e946be8>, '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.43g8oLvNs3/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.N6RjKhZUDI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:08:40 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:08:40 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:08:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.43g8oLvNs3/Render', 04:08:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:08:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:08:40 INFO - 'HOME': '/Users/cltbld', 04:08:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:08:40 INFO - 'LOGNAME': 'cltbld', 04:08:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:08:40 INFO - 'MOZ_NO_REMOTE': '1', 04:08:40 INFO - 'NO_EM_RESTART': '1', 04:08:40 INFO - 'PAGER': '/bin/cat', 04:08:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:08:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:08:40 INFO - 'PWD': '/builds/slave/test', 04:08:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:08:40 INFO - 'SHELL': '/bin/bash', 04:08:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N6RjKhZUDI/Listeners', 04:08:40 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 04:08:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:08:40 INFO - 'USER': 'cltbld', 04:08:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:08:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:08:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:08:40 INFO - 'XPC_FLAGS': '0x0', 04:08:40 INFO - 'XPC_SERVICE_NAME': '0', 04:08:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:08:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:08:40 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:08:40 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:08:40 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:08:40 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:08:40 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:08:40 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:08:40 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:08:40 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:08:40 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:08:40 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:08:40 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:08:40 INFO - Cleaning up... 04:08:40 INFO - Return code: 0 04:08:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:08:40 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:08:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:08:40 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:08:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:08:40 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 0x10eb73b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb76768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc303c4ae90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e951d30>, '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 0x10e878db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e946be8>, '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.43g8oLvNs3/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.N6RjKhZUDI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:08:40 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:08:40 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:08:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.43g8oLvNs3/Render', 04:08:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:08:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:08:40 INFO - 'HOME': '/Users/cltbld', 04:08:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:08:40 INFO - 'LOGNAME': 'cltbld', 04:08:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:08:40 INFO - 'MOZ_NO_REMOTE': '1', 04:08:40 INFO - 'NO_EM_RESTART': '1', 04:08:40 INFO - 'PAGER': '/bin/cat', 04:08:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:08:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:08:40 INFO - 'PWD': '/builds/slave/test', 04:08:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:08:40 INFO - 'SHELL': '/bin/bash', 04:08:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N6RjKhZUDI/Listeners', 04:08:40 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 04:08:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:08:40 INFO - 'USER': 'cltbld', 04:08:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:08:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:08:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:08:40 INFO - 'XPC_FLAGS': '0x0', 04:08:40 INFO - 'XPC_SERVICE_NAME': '0', 04:08:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:08:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:08:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:08:40 INFO - Cleaning up... 04:08:40 INFO - Return code: 0 04:08:40 INFO - Running post-action listener: _resource_record_post_action 04:08:40 INFO - Running post-action listener: _start_resource_monitoring 04:08:40 INFO - Starting resource monitoring. 04:08:40 INFO - [mozharness: 2017-05-29 11:08:40.668950Z] Finished create-virtualenv step (success) 04:08:40 INFO - [mozharness: 2017-05-29 11:08:40.669978Z] Running install step. 04:08:40 INFO - Running pre-action listener: _resource_record_pre_action 04:08:40 INFO - Running main action method: install 04:08:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:08:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:08:40 INFO - Reading from file tmpfile_stdout 04:08:40 INFO - Detecting whether we're running mozinstall >=1.0... 04:08:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:08:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:08:41 INFO - Reading from file tmpfile_stdout 04:08:41 INFO - Output received: 04:08:41 INFO - Usage: mozinstall [options] installer 04:08:41 INFO - Options: 04:08:41 INFO - -h, --help show this help message and exit 04:08:41 INFO - -d DEST, --destination=DEST 04:08:41 INFO - Directory to install application into. [default: 04:08:41 INFO - "/builds/slave/test"] 04:08:41 INFO - --app=APP Application being installed. [default: firefox] 04:08:41 INFO - mkdir: /builds/slave/test/build/application 04:08:41 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:08:41 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:09:00 INFO - Reading from file tmpfile_stdout 04:09:00 INFO - Output received: 04:09:00 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 04:09:00 INFO - Running post-action listener: _resource_record_post_action 04:09:00 INFO - [mozharness: 2017-05-29 11:09:00.892332Z] Finished install step (success) 04:09:00 INFO - [mozharness: 2017-05-29 11:09:00.892504Z] Running run-tests step. 04:09:00 INFO - Running pre-action listener: _resource_record_pre_action 04:09:00 INFO - Running main action method: run_tests 04:09:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:09:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:09:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:09:00 INFO - 2017-05-29 04:09:00.907 screenresolution[1634:8800] starting screenresolution argv=screenresolution get 04:09:00 INFO - 2017-05-29 04:09:00.909 screenresolution[1634:8800] Display 0: 1600x1200x32@60 04:09:00 INFO - 2017-05-29 04:09:00.915 screenresolution[1635:8803] starting screenresolution argv=screenresolution list 04:09:00 INFO - Available Modes on Display 0 04:09:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:09:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:09:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:09:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:09:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:09:01 INFO - Intel Iris: 04:09:01 INFO - Chipset Model: Intel Iris 04:09:01 INFO - Type: GPU 04:09:01 INFO - Bus: Built-In 04:09:01 INFO - VRAM (Dynamic, Max): 1536 MB 04:09:01 INFO - Vendor: Intel (0x8086) 04:09:01 INFO - Device ID: 0x0a2e 04:09:01 INFO - Revision ID: 0x0009 04:09:01 INFO - Displays: 04:09:01 INFO - Display: 04:09:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:09:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:09:01 INFO - Display Serial Number: 1600x1200 60 04:09:01 INFO - Main Display: Yes 04:09:01 INFO - Mirror: Off 04:09:01 INFO - Online: Yes 04:09:01 INFO - Rotation: Supported 04:09:01 INFO - Return code: 0 04:09:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:09:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:09:01 INFO - Python 2.7.3 04:09:01 INFO - Return code: 0 04:09:01 INFO - grabbing minidump binary from tooltool 04:09:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:01 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 0x10e951d30>, '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 0x10e878db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e946be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:09:01 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:09:01 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:09:01 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:09:01 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:09:01 INFO - Return code: 0 04:09:01 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:09:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:09:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:09:01 INFO - ENV: RUST_BACKTRACE is now 1 04:09:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:09:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:09:01 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/Ur1N-XzpRC2SW2bYKyD7Lg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0196', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 04:09:01 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/Ur1N-XzpRC2SW2bYKyD7Lg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0196 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 04:09:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.43g8oLvNs3/Render', 04:09:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:09:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:09:01 INFO - 'HOME': '/Users/cltbld', 04:09:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:09:01 INFO - 'LOGNAME': 'cltbld', 04:09:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:09:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:09:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:01 INFO - 'MOZ_NO_REMOTE': '1', 04:09:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:09:01 INFO - 'NO_EM_RESTART': '1', 04:09:01 INFO - 'PAGER': '/bin/cat', 04:09:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:09:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:01 INFO - 'PWD': '/builds/slave/test', 04:09:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:09:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:09:01 INFO - 'RUST_BACKTRACE': '1', 04:09:01 INFO - 'SHELL': '/bin/bash', 04:09:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N6RjKhZUDI/Listeners', 04:09:01 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 04:09:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:09:01 INFO - 'USER': 'cltbld', 04:09:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:09:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:09:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:01 INFO - 'XPC_FLAGS': '0x0', 04:09:01 INFO - 'XPC_SERVICE_NAME': '0', 04:09:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:09:01 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/Ur1N-XzpRC2SW2bYKyD7Lg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0196', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 04:09:01 INFO - mozversion application_buildid: 20170529024915 04:09:01 INFO - mozversion application_changeset: 6fb66467485a8beee11dad38a08768c2a9955fe7 04:09:01 INFO - mozversion application_display_name: FirefoxDeveloperEdition 04:09:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:09:01 INFO - mozversion application_name: Firefox 04:09:01 INFO - mozversion application_remotingname: firefox-dev 04:09:01 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 04:09:01 INFO - mozversion application_vendor: Mozilla 04:09:01 INFO - mozversion application_version: 54.0 04:09:01 INFO - mozversion platform_buildid: 20170529024915 04:09:01 INFO - mozversion platform_changeset: 6fb66467485a8beee11dad38a08768c2a9955fe7 04:09:01 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 04:09:01 INFO - mozversion platform_version: 54.0 04:09:01 INFO - using testdate: 1496056141 04:09:01 INFO - actual date: 1496056141 04:09:01 INFO - starting webserver on 'localhost:49225' 04:09:01 INFO - SUITE-START | Running 2 tests 04:09:01 INFO - TEST-START | dromaeo_css 04:09:01 INFO - operating with platform_type : mac_ 04:09:01 INFO - Initialising browser for dromaeo_css test... 04:09:01 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/tmp6aJcIo/profile http://localhost:49225/getInfo.html) 04:09:04 INFO - PROCESS | 1645 | __metrics Screen width/height:1600/1200 04:09:04 INFO - PROCESS | 1645 | colorDepth:24 04:09:04 INFO - PROCESS | 1645 | Browser inner width/height: 1024/689 04:09:04 INFO - PROCESS | 1645 | __metrics 04:09:04 INFO - PROCESS | 1645 | 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:09:04 INFO - PROCESS | 1645 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:09:04 INFO - TEST-INFO | 1645: exit 0 04:09:04 INFO - Browser initialized. 04:09:04 INFO - Running cycle 1/1 for dromaeo_css test... 04:09:04 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/tmp6aJcIo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:09:16 INFO - PROCESS | 1649 | RSS: Main: 197152768 04:09:16 INFO - PROCESS | 1649 | 04:12:19 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49225/tests/dromaeo/cssquery-ext.html) 04:12:22 INFO - PROCESS | 1649 | RSS: Main: 414105600 04:12:22 INFO - PROCESS | 1649 | 04:15:24 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-ext.html (next: http://localhost:49225/tests/dromaeo/cssquery-jquery.html) 04:15:25 INFO - PROCESS | 1649 | RSS: Main: 329490432 04:15:25 INFO - PROCESS | 1649 | 04:18:27 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49225/tests/dromaeo/cssquery-mootools.html) 04:18:28 INFO - PROCESS | 1649 | RSS: Main: 318078976 04:18:28 INFO - PROCESS | 1649 | 04:21:30 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49225/tests/dromaeo/cssquery-prototype.html) 04:21:31 INFO - PROCESS | 1649 | RSS: Main: 297107456 04:21:31 INFO - PROCESS | 1649 | 04:24:34 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49225/tests/dromaeo/cssquery-yui.html) 04:24:35 INFO - PROCESS | 1649 | RSS: Main: 302288896 04:24:35 INFO - PROCESS | 1649 | 04:27:38 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-yui.html (next: http://localhost:49225/tests/dromaeo/cssquery-dojo.html) 04:27:39 INFO - PROCESS | 1649 | RSS: Main: 546394112 04:27:39 INFO - PROCESS | 1649 | 04:27:39 INFO - PROCESS | 1649 | __start_tp_report 04:27:39 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 04:27:39 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 04:27:39 INFO - PROCESS | 1649 | |i|pagename|runs| 04:27:39 INFO - PROCESS | 1649 | |0;dojo.html;3348.7153591944716;3379.543761592184;3382.3066271414355;3394.575678040245;3414.334204830059;5700.458456446636;5709.143628455734;5712.800051998181;5734.397888221734;5771.855703607406;4697.718136911788;5679.801206957757;5772.393898640646;5776.220210271612;5785.681787501696;5214.861458447488;5231.712255825947;5242.947570524284;5277.604179686539;5300.893982120355;4171.0823618803815;5133.537981581657;5187.19598462239;5331.040412725708;5358.544523715489;5209.921851172236;5216.113260745682;5243.606729495303;5248.947510524884;5277.366715994086;4386.692931494781;5239.899621079578;5300.363956325227;5305.363356397218;5365.114756065244;5210.713410762407;5246.31797866275;5251.76367063483;5269.104252277122;5283.392409872876;5544.16837474378;5608.214849921014;5675.5459563234845;5690.48785609293;5707.1724599933195;6123.498714075266;6159.35326790685;6170.691465426711;6173.290071641729;6185.505159587267;6190.257169139687;6220.626762394271;6229.37706229378;6229.4704950079295;6249.656268905208;5672.716364181774;5750.453706897922;5854.443827836349;5952.851178720523;5959.731812068424;6297.149884765498;6333.778317758856;6399.328070552618;6425.518086143558;6433.356664333623;5277.472252774692;5775.104858746901;6120.479759220427;6127.417895299939;6140.938590614126;6093.847653808691;6179.196704428396;6179.907301390483;6226.688665566703;6244.063390491372;6113.449789518876;6118.110902583122;6118.497354392036;6143.508519318444;6184.319724830265;6190.628562286277;6193.194884664965;6199.442050215503;6220.844478888064;6224.221972253468;4794.113089078532;4807.519248075165;4813.855584332476;4829.082474329866;4847.660663753574;4210.6841986851105;4257.467816522934;4279.893002674896;4284.700070995063;4285.100128972888;5641.52047075995;5687.687177205211;5696.174054762037;5700.087985922232;5719.885602287931;6202.596831206048;6228.5017198624;6245.0632405139595;6250.031245157008;6256.0303153011355;5580.386157522669;5645.887082258414;5676.176954341598;5700.087985922315;5703.572232082611;5234.555062819627;5262.710550919736;5287.841364759063;5383.380911195184;5396.08266594672;6347.587406818542;6388.616682999035;6404.935950640527;6413.518986075969;6421.518386120966;6185.381461853779;6200.100985357008;6211.534134939988;6212.844678883064;6223.066540018853;6113.689152827286;6171;6341.270753863365;6413.941699619697;6435.32429094957;6127.14220009208;6128;6128.571000029955;6133.233345831771;6138.754449822136;5191;6119.112728654409;6133.916185115316;6156.846078848064;6159.81520554384;4762.833300834454;6123.357047509948;6183.288921773874;6186.412290832363;6195.845103872439;6164.4143806338325;6188.876222475616;6191.102290167991;6205.968970155121;6207.348228435949;5756.3380211276235;5810.128480727924;5881.05903055509;5896.911546326723;6018.2778066630535;6043.09353596964;6057.121717351048;6064.363241859718;6093.6039157454625;6155.938440615537;5983.551233657406;6016.729247183975;6024.819255422344;6030.065339872327;6032.366601506778 04:27:39 INFO - PROCESS | 1649 | |1;ext.html;795.2008231727115;825.3397282174259;875.5534677314567;886.7871710789409;889.8798662180611;38273.23453530931;38940.41589376158;39146.608533914616;39262.01844953877;39461.01347466308;39891.401628975516;40119.79640610779;40133.197336053265;40290;40307;7427.591482468462;9169.266458683305;9344.953275233614;9345.95327023364;9348.065193480665;7450.105987281534;11126.499307531181;11397.145214108934;11513.712157196054;11531.962123408892;11321.32072075674;11331.206815522917;11353.375564344;11354.43227838609;11358.432078396088;11209.775804483905;11283;11286.097112231002;11295.096392288679;11300.208985370986;11032.669019929415;11078.778424431546;11079.944600276947;11096.55613775448;11103.666889993314;38084.23831523354;38313.61686383128;38363.23273534514;38364.80817595894;38447.03882402935;3843.8078096095364;3886.8639597613947;3899.1421887184774;3906.43356713275;3962.7622342659533;27691.861540692367;29538.409231815243;29711.851440742874;29860.805567777265;30033.69966300331;2964.614600101973;3041.574179614856;3060.6786287439945;3129.092563156682;3200.487921932503;347767;348485;348564.99999999744;349104.99999999744;349123;52314;52415.42753717394;52461.73769131206;52501.474985250425;52567;54233;54552.45447545553;54619.90760184774;54693.453065468835;54742.72628636831;12261.632151035483;14929.626759331104;14934.32795524204;14970.550883473514;14971.925140374446;12782.872171278168;12797.168184068007;12802.551910683082;12822.422990965242;12827.743445131231;11108.888911110947;12255.896969272808;12272.754544909052;12320;12374;5052.418971818288;5307.867303317448;5468.812475010028;5723.8569035774435;5744.138379243063;1059.9735006624896;1093.9179561532917;1115.754534002518;1154.3535620052796;1160.4719852467108;26809.514763080675;27555.724442755716;27605.723942760716;27870.442591148065;28458.430831383255;1078.1536493852288;1083.0847933496184;1113.2374323588367;1116.7040734205443;1121.3552207480732;12926.935365323301;13270.867291326964;13585.796213056812;13613.659658508617;13618.18290902549;454405;457537;461365;461454;461892;45144;45148.77425612917;45222.09555808931;45267.32099018517;45303;1404.838443578981;1442.401403417591;1453.7715630292387;1453.978190327146;1454.142056186855;22155.446113847283;26947.66847899208;27137.72862271352;27388;27812.721872781014;41580.376294355;41763.373549397395;41790;41832.58167418287;41921.95195120147;395009;396466;396726;397509;398128;28972.565411519266;28974;29176.854115730133;29752.404951901273;30834.383312334077;38627;38829.41755873721;39834.800825995684;40032.199356012134;40302.798486007385;28069.578956315265;28106.43787124287;28648.85675571609;28851.7114828849;29050;28876.566851497664;28948.27629309284;28953.710462895102;28955.855220723763;29022.854885725435;32666.673333266364;33135.502967455024;33250.16874578058;33422.99731008073;33469.497957531144;26860.59709104326;28633.28416789597;29202.415951681272;29223.85388073046;29336.413271734873;22656.30077744143;22713.09147634076;23147.07411703513;23181.652275215547;23193.956271968138 04:27:39 INFO - PROCESS | 1649 | |2;jquery.html;385.2852957763348;418.5354256774979;425.93185090385543;437.8620734468642;445.40760788151744;20183.798162018375;21339.466513337156;22670.979805908733;22890.54218915623;22938.541229175447;21684.8915755421;22769;23008.964596593152;23030.53938921225;23376.532469350605;6690.397864192209;6847.931520684804;6884.001819736135;6930.237673855871;6949.374556289933;6550.836229094263;6617.007448882682;6779.423748981318;6789.660516974156;6790.694418751168;6429.807105786834;6681.966590167042;6775;6793.048973143764;6796.184457865063;6478.967605161944;6739.7978060658015;6805.931940680579;6809.795706128824;6812.52312338139;6547.377999090127;6648.235452922928;6655.267920528738;6656.467482601381;6657.101291325657;26680.799364028666;27311.45377092447;27481;27496.862515687295;27846.60766961644;3230.8707651693903;3232.4666430039015;3419.333230020147;3456.6197718250974;3492.703120234783;16968.660626787518;17015.914920425443;17300.961942283502;17475.126243687893;17527.824721752746;1998.82010619045;2067.824234940032;2069.1309649946907;2073;2083.499960009602;117846;119423;119493.40253298677;119774.40112799467;120617.39691301575;17263.3957811476;21573.137074516842;21834.23580174686;21920.671189932164;21975.780242197692;36288.36702348401;37124.62875371212;37160.62839371571;37176;37182.8140859294;9159.49622770754;10715;10728.034476897117;10891.98498277821;10920.781584368267;7595.240475952361;8731.257843083282;8754.21212090915;8854.955725221333;8904.8664270036;8374.832503349899;10603.681889543326;10641.946790266;10736.624218152327;10762.19283553721;3665.780053196763;3668.9633103669157;4296.377025331372;4336.6313863321975;4367;837.8575642140851;942.8491441369348;943.7593413679468;951.8429459139164;960.3853533738413;21258.893705531373;22657.773422265895;22713.432164195696;22735.658965115537;22781.582762585414;973.1047436358567;978.2125389061873;997.5062344139698;1000.45975173407;1001.9949900250452;11481.655550333504;11688.766224675628;11942.701432464259;12137;12143.089268304735;722643;738594.5245746478;745205;762388;763558;87231.56384218039;112073;127592;136689;137587.31206343905;1246.1837496439846;1313.4155300891202;1327.9004074694244;1328.7209685965863;1329.736750087421;19606.964058880556;20737.377878663665;20917.790822091585;20921.37235882926;20967.58064838664;45154.322685159095;49626.270555119816;53900.46099538954;57903.4209657898;60270.39729602823;58804;80144.59927700325;82374;82439.70241190285;82737;10142.49287535635;12258.325792081521;12443.914436908472;12705.047121465743;12769.170003949714;13474;13520.661983450493;13866;13921;14056.718865622835;11443;11516.481758320728;11809.055275578105;11847;11916.880831191576;10815.743414065093;10835;10867;10943.01512863846;10994.560217591206;10444.529996150344;11807.114466415225;11903.76192476163;11927.284362938251;12007.399630018639;10147.746306342106;12273.447694853574;12354.382280886099;12529.561465348668;12660.620181394574;9909.207263419054;11498;11534.59628912984;11749.76500470003;11805.645830625095 04:27:39 INFO - PROCESS | 1649 | |3;mootools.html;1586.4050980882168;1589.125980710609;1616.8383161683832;1616.9272382742774;1620.805503339599;10561.155107591394;11151.665450036491;11152.665420037392;11159.553617855287;11162.05122564581;10759.515821788018;10885.673429797118;11153.776924461506;11314.830277545843;11317.773644527104;2394.425337918896;2431.4286142756437;2442.694663167104;2443.657887895696;2447.5349683560157;2287.462446325112;2328.476092879097;2346.3195673254736;2349.0368948731025;2417.419819243378;2362.598358279088;2427.332483567024;2434.1480481831395;2437.427204606916;2438.7073551173885;2332.1604222479896;2381.0951838301507;2396.472775989297;2405.651180578815;2442.890069946857;2402.9279121626378;2425.745296743834;2427.5919966419433;2432.7323994360604;2432.793212576933;10963.068139208097;11204.551817927273;11206.15953803468;11219.663410097628;11225.101991840633;5804.564657650693;5962.910556341659;5982.7906023289415;5991.400859914017;6002.609830361056;3606.2246616977213;3645.2709458108484;3721.0139313082054;3724.7392682512236;3737.364648009845;1202.4528839378063;1214.2410993129295;1249.2005116725213;1265.753178130265;1275.7576060548558;13636.659083522893;14058.297085145701;14364.784528232085;15295.923520382326;15510.534683959499;4295.484541854998;4385.934210986838;4430.601245887886;4710.528947105262;4884.633652476078;11791.646250612495;11893.345865977455;11911.821322680167;11917.046636269077;11927.343996080126;4922.680025798311;5132.28148059272;5949.226600541989;5956.463918247292;5959.702014899238;4472.217361961643;5346.4118946915805;5386.596005299618;5394.8921021579345;5398.1902714593125;3900.551436584772;3923.7841918694176;3995.780232087205;4025.255327764373;4041.939370909439;1045.1273186888882;1049.0610903241557;1067.6530127708527;1068.385678235018;1072.5977758340623;807.4025221336286;826.7519744076753;833.9482298408872;834.0700119366862;842.6755699055908;6584.703688333941;6648.634325112165;6714.9664251679405;6940.757073502403;6959.826004349932;1013.483123606951;1016.3190662256159;1043.634727845248;1082.890037711352;1116.1238427834112;3661.901429571118;3863.459115723801;3996.480457540559;4070.776107314126;4342.32693932441;12994.935025324812;13406.463741450429;13967.37146828395;14015.789763153562;14017.369218385196;13660.385282662304;13730.867028967563;13837.30813459323;13871.335439747094;13898.818600418876;10141.543630536644;10399.064084232456;10502.894971050344;10536.947315263375;10593.523291451904;6566.967165164144;6622.933770662425;6633.1045308882685;6784.626845523544;6859.108315918898;12078.75842483163;12119.272843629411;12456.75086498283;12500.31248281317;12519.624411267676;9453.810923781624;9996.250281228791;12148.635540933787;12502.499900004263;12687.682807929876;10070.597176113166;10131.594736210469;10272.075513203847;10293.125084367912;10382.584696612052;9983.550740216562;10070.748231294276;10284.331518451278;10296.691099267035;10301.124404425407;8746.51894145828;8751.037385887674;8790.648374065222;8893.510856902933;9021.097890210927;8661.956690216508;8672.046074931626;8764.386492945432;8819.911800882166;8825;7882.645280962485;7950.920490795018;8077.192280771876;8103.8379232413845;8108.675652973815;5983.132445795422;6012.458878700763;6087.512998959984;6090.390960904051;6094.177286066324;5872.588918775645;6077.301110372364;6176.4441200292185;6228.56400051992;6292.366272362132 04:27:39 INFO - PROCESS | 1649 | |4;prototype.html;384.22770231834005;387.7731527056672;390.77335145615547;396.3618574095705;402.6677990657706;4879.316895634614;4907.411110666721;4935.432425271095;4957.40511138663;5002.749862506878;3941;4220.788960551976;4439.156560253549;4568.565986231302;4864.954034882499;3500.492428597852;3598.956302672222;4040.3939409088653;4136.565660605638;4137.896552586194;3532.4348104303317;3578.7852728836224;3695.8891233263043;4105;4232.915341693164;3878.263130005304;4062.6749860011278;4125.9174816503655;4133.627973482386;4188.455500784894;3962.7820469874146;4067.132671466609;4199.475065616798;4207.137536804963;4243.639290660298;2956.5703783611125;3411.4541673332146;3470.861165553375;3589.8564057437934;3720.8883733488037;3959;4288.828446862121;4575.862724118282;4638.304254361873;4745.335653008547;4123.97938010308;4752.554398061412;4757.95242047582;4823.493533179036;4850.8059677612855;5016.824411145592;5171.37943446785;5235.397929238148;5249.265102885599;5298.390685071189;3846.826892789831;4087.8364865405647;4269.057578801638;4289.313709806447;4378.474583050054;5293.788248470057;5389.541888939445;5456.835035679296;5507.366652834935;5584.21820945068;3559.483874838186;3807.6192380761704;3968.345223038224;3977.9204415911518;4358.389825424443;3855.479510266134;3958.8614398495915;4074.6536544394057;4085.9387109193385;4457.531959144308;4132.223144048088;4495.876030992252;4674.883127921761;4678.8596342109795;4739.526047395233;4079.63283304504;4661.930071048937;5170.948290517122;5177.844664660066;5229.947700522946;1301.3688361144837;1323.232525135419;1359.8708122728524;1394.9930250348887;1400.586826886071;1062.8830828608848;1066.7279843639978;1094.8631421072366;1111.9714264305646;1118.9537782173693;4393.538678438782;4398.538153493901;4719;4766.855954570878;4771.117343291502;4903.975480122648;4980.078685443204;5332.573394128461;5392.91910621341;5394.406615272317;4138.771758028743;4345.565443455629;4411.470623525198;4546.227141385973;4661.743604101739;2666.2534490342737;2735.849528275924;2784.259837601499;2785.8607069646437;2843.076000299911;4892.850180207648;5350.652207606493;5477.945220547744;5487.682956090589;5525.364583072998;4654.557817007379;4729.810807567727;5079.663354856807;5459.890802183934;5535.6955367454375;4757.52516719818;4790.8323208687525;4966.925496117489;5055.6632622469;5383.650062745909;4683.953160468352;4766.213574760264;4869.342638743724;4910.140725373117;5407.270018547446;4383.736975781463;4836.274558816206;5286.440500052417;5302.496262855023;5321.3348331458565;5099.490050994871;5177.844664660066;5337.27798822611;5346.705931173823;5444.727763611882;3695.796731179811;3855.6021569801383;3999.6600288975765;4278.887489252755;4342.891427714332;4579.573205751196;4652.767361631837;5098.776293689562;5349.812756553502;5436.6194366393975;4336.674749393871;4412.889677758082;4801.471838097877;5137.357830271216;5142.485751424828;4298.5271620122385;4351.34648833454;4362.607365337135;4884.291777692144;5230.660007099526;4509.9549004510845;4728.787204575733;5041.495850414929;5049.84850454487;5078.771455284447;4270.50889147752;4775.092732380836;4965.577925876341;5144.742762861767;5168.922466162935;4420.756858372821;4608.423947006624;5078.936850519496;5118.59238709343;5124.6412751108555 04:27:39 INFO - PROCESS | 1649 | |5;yui.html;743.57244584364;788.1645455816835;791.7980914866712;845.7674139611607;858.3991206155692;14918.5524434267;16002.119883406407;16162.111083890397;17721.7341739874;18648.81351186488;17037;17272.395466158687;17657.381991630296;17702.64594708105;17946;993.349356171705;1008.4050410257946;1014.7463134216446;1015.725754046407;1016.2530540053056;979.8187335342984;992.9821932519153;995.4823491784268;997.6109317366233;1005.1606908231599;1065.0627447845923;1068.6580294305825;1068.6793961811463;1068.754186537097;1069.2087854987292;1004.9196064314838;1062.8033813744444;1063.4044934836515;1064.4464878263336;1069.3156379916782;794.0114557376104;794.7258195922398;795.264380448089;795.3041089047084;799.6681377228443;15913.761293580606;16140.919295403566;16143.596410089842;16901.83098169015;17155.228014739365;14264.928675356661;14402.351894164683;14419.206943618097;14517.70964580713;14556.927215363961;11446.313221206754;11839.171258011942;11858.703532411759;11919.404029798563;12399.194052386567;3690.6862916652117;3727.235916637083;3773.3773927301963;3859.150986782903;3894.883153505371;111390;113854;116888.41555792252;118805;121193;10655.786884262272;10779.407132607625;11159.27464714791;11446.484908179153;11755.647330580096;21713.891430542746;23275;23910.88044559801;23949.880250598984;24007.159749408685;7783.883241751379;7813.257740514642;7861.842763144705;7870.685172593032;8115.269625733594;3988.9002774930277;4124.443200167939;4141.565135660712;4146.212219678251;4163.97918010408;3565.4830049642655;3612;3675;3679.448082787603;3680.0431887709387;645.3610925184034;653.2716021635937;653.633964979613;669.9799006029826;671.691022129816;614.3825455417366;672.9192496900404;726.8037629840019;728.351766927435;728.9153022356057;9868.703938881845;10028.398296102258;10084.294099412971;10098.394096354243;10274.537645805956;676.7665155521436;678.6776281266359;697.3584302441766;716.4769718105812;717.7810767715897;4945.554900059012;4954.5788607968725;4974.1543937530705;5000.824971125993;5034.395872495324;95714;102337;102946;105302;106444;54493.72753136209;60590.091148632804;73267.63366183135;73726;96193.5190324044;64819.02771458526;72163.50528452464;72729.6363518179;74534.25465745381;75444.62277688686;10733.731656708333;10873.891261087289;11051.226414150933;11202.83195752048;11209.383483908463;39586.40620390754;46749.76625116853;48441.5155848437;48934.75532622314;49084.263736044704;59712.70143649428;60688.69655651694;61659;63521.68239158775;63555;12424.254544727346;12673.619791406272;12860;13291.534796282083;13393.933030334785;14470.348834302271;15282.541523754306;15622.531324060297;15774.211289435252;15849.841501585297;13310.800337994744;13585.041101080447;14361.497347593202;14408.351624177147;14580.562583122522;13178.472402306019;13827.654308642364;14051.36768845425;14420.278986050445;15039.398424062914;13818.654533636338;14119.364628591533;14122.50571230002;14532.128072315272;14595.635109122357;9343.34596578233;10135.391876487434;10204.336718113298;10569.154467642724;10877.347359158475;7810.415552879491;8559.17547209407;10140.391576505433;11486.655400338002;11519.53921843117 04:27:39 INFO - PROCESS | 1649 | __end_tp_report 04:27:39 INFO - PROCESS | 1649 | __start_cc_report 04:27:39 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,2697 04:27:39 INFO - PROCESS | 1649 | __end_cc_report 04:27:39 INFO - PROCESS | 1649 | __startTimestamp1496057259221__endTimestamp 04:27:39 INFO - PROCESS | 1649 | 04:27:39 INFO - PROCESS | 1649 | ------- Summary: start ------- 04:27:39 INFO - PROCESS | 1649 | Number of tests: 6 04:27:39 INFO - PROCESS | 1649 | 04:27:39 INFO - PROCESS | 1649 | [#0] dojo.html Cycles:165 Average:5703.97 Median:5959.73 stddev:671.54 (11.3%) stddev-sans-first:647.68 04:27:39 INFO - PROCESS | 1649 | Values: 3348.7 3379.5 3382.3 3394.6 3414.3 5700.5 5709.1 5712.8 5734.4 5771.9 4697.7 5679.8 5772.4 5776.2 5785.7 5214.9 5231.7 5242.9 5277.6 5300.9 4171.1 5133.5 5187.2 5331.0 5358.5 5209.9 5216.1 5243.6 5248.9 5277.4 4386.7 5239.9 5300.4 5305.4 5365.1 5210.7 5246.3 5251.8 5269.1 5283.4 5544.2 5608.2 5675.5 5690.5 5707.2 6123.5 6159.4 6170.7 6173.3 6185.5 6190.3 6220.6 6229.4 6229.5 6249.7 5672.7 5750.5 5854.4 5952.9 5959.7 6297.1 6333.8 6399.3 6425.5 6433.4 5277.5 5775.1 6120.5 6127.4 6140.9 6093.8 6179.2 6179.9 6226.7 6244.1 6113.4 6118.1 6118.5 6143.5 6184.3 6190.6 6193.2 6199.4 6220.8 6224.2 4794.1 4807.5 4813.9 4829.1 4847.7 4210.7 4257.5 4279.9 4284.7 4285.1 5641.5 5687.7 5696.2 5700.1 5719.9 6202.6 6228.5 6245.1 6250.0 6256.0 5580.4 5645.9 5676.2 5700.1 5703.6 5234.6 5262.7 5287.8 5383.4 5396.1 6347.6 6388.6 6404.9 6413.5 6421.5 6185.4 6200.1 6211.5 6212.8 6223.1 6113.7 6171.0 6341.3 6413.9 6435.3 6127.1 6128.0 6128.6 6133.2 6138.8 5191.0 6119.1 6133.9 6156.8 6159.8 4762.8 6123.4 6183.3 6186.4 6195.8 6164.4 6188.9 6191.1 6206.0 6207.3 5756.3 5810.1 5881.1 5896.9 6018.3 6043.1 6057.1 6064.4 6093.6 6155.9 5983.6 6016.7 6024.8 6030.1 6032.4 04:27:39 INFO - PROCESS | 1649 | 04:27:39 INFO - PROCESS | 1649 | [#1] ext.html Cycles:180 Average:53715.88 Median:29732.13 stddev:107067.41 (360.1%) stddev-sans-first:107294.04 04:27:39 INFO - PROCESS | 1649 | Values: 795.2 825.3 875.6 886.8 889.9 38273.2 38940.4 39146.6 39262.0 39461.0 39891.4 40119.8 40133.2 40290.0 40307.0 7427.6 9169.3 9345.0 9346.0 9348.1 7450.1 11126.5 11397.1 11513.7 11532.0 11321.3 11331.2 11353.4 11354.4 11358.4 11209.8 11283.0 11286.1 11295.1 11300.2 11032.7 11078.8 11079.9 11096.6 11103.7 38084.2 38313.6 38363.2 38364.8 38447.0 3843.8 3886.9 3899.1 3906.4 3962.8 27691.9 29538.4 29711.9 29860.8 30033.7 2964.6 3041.6 3060.7 3129.1 3200.5 347767.0 348485.0 348565.0 349105.0 349123.0 52314.0 52415.4 52461.7 52501.5 52567.0 54233.0 54552.5 54619.9 54693.5 54742.7 12261.6 14929.6 14934.3 14970.6 14971.9 12782.9 12797.2 12802.6 12822.4 12827.7 11108.9 12255.9 12272.8 12320.0 12374.0 5052.4 5307.9 5468.8 5723.9 5744.1 1060.0 1093.9 1115.8 1154.4 1160.5 26809.5 27555.7 27605.7 27870.4 28458.4 1078.2 1083.1 1113.2 1116.7 1121.4 12926.9 13270.9 13585.8 13613.7 13618.2 454405.0 457537.0 461365.0 461454.0 461892.0 45144.0 45148.8 45222.1 45267.3 45303.0 1404.8 1442.4 1453.8 1454.0 1454.1 22155.4 26947.7 27137.7 27388.0 27812.7 41580.4 41763.4 41790.0 41832.6 41922.0 395009.0 396466.0 396726.0 397509.0 398128.0 28972.6 28974.0 29176.9 29752.4 30834.4 38627.0 38829.4 39834.8 40032.2 40302.8 28069.6 28106.4 28648.9 28851.7 29050.0 28876.6 28948.3 28953.7 28955.9 29022.9 32666.7 33135.5 33250.2 33423.0 33469.5 26860.6 28633.3 29202.4 29223.9 29336.4 22656.3 22713.1 23147.1 23181.7 23194.0 04:27:39 INFO - PROCESS | 1649 | 04:27:39 INFO - PROCESS | 1649 | [#2] jquery.html Cycles:180 Average:41067.53 Median:22752.33 stddev:122957.97 (540.4%) stddev-sans-first:123264.96 04:27:39 INFO - PROCESS | 1649 | Values: 385.3 418.5 425.9 437.9 445.4 20183.8 21339.5 22671.0 22890.5 22938.5 21684.9 22769.0 23009.0 23030.5 23376.5 6690.4 6847.9 6884.0 6930.2 6949.4 6550.8 6617.0 6779.4 6789.7 6790.7 6429.8 6682.0 6775.0 6793.0 6796.2 6479.0 6739.8 6805.9 6809.8 6812.5 6547.4 6648.2 6655.3 6656.5 6657.1 26680.8 27311.5 27481.0 27496.9 27846.6 3230.9 3232.5 3419.3 3456.6 3492.7 16968.7 17015.9 17301.0 17475.1 17527.8 1998.8 2067.8 2069.1 2073.0 2083.5 117846.0 119423.0 119493.4 119774.4 120617.4 17263.4 21573.1 21834.2 21920.7 21975.8 36288.4 37124.6 37160.6 37176.0 37182.8 9159.5 10715.0 10728.0 10892.0 10920.8 7595.2 8731.3 8754.2 8855.0 8904.9 8374.8 10603.7 10641.9 10736.6 10762.2 3665.8 3669.0 4296.4 4336.6 4367.0 837.9 942.8 943.8 951.8 960.4 21258.9 22657.8 22713.4 22735.7 22781.6 973.1 978.2 997.5 1000.5 1002.0 11481.7 11688.8 11942.7 12137.0 12143.1 722643.0 738594.5 745205.0 762388.0 763558.0 87231.6 112073.0 127592.0 136689.0 137587.3 1246.2 1313.4 1327.9 1328.7 1329.7 19607.0 20737.4 20917.8 20921.4 20967.6 45154.3 49626.3 53900.5 57903.4 60270.4 58804.0 80144.6 82374.0 82439.7 82737.0 10142.5 12258.3 12443.9 12705.0 12769.2 13474.0 13520.7 13866.0 13921.0 14056.7 11443.0 11516.5 11809.1 11847.0 11916.9 10815.7 10835.0 10867.0 10943.0 10994.6 10444.5 11807.1 11903.8 11927.3 12007.4 10147.7 12273.4 12354.4 12529.6 12660.6 9909.2 11498.0 11534.6 11749.8 11805.6 04:27:39 INFO - PROCESS | 1649 | 04:27:39 INFO - PROCESS | 1649 | [#3] mootools.html Cycles:180 Average:6771.24 Median:2427.46 stddev:4199.03 (173.0%) stddev-sans-first:4192.74 04:27:39 INFO - PROCESS | 1649 | Values: 1586.4 1589.1 1616.8 1616.9 1620.8 10561.2 11151.7 11152.7 11159.6 11162.1 10759.5 10885.7 11153.8 11314.8 11317.8 2394.4 2431.4 2442.7 2443.7 2447.5 2287.5 2328.5 2346.3 2349.0 2417.4 2362.6 2427.3 2434.1 2437.4 2438.7 2332.2 2381.1 2396.5 2405.7 2442.9 2402.9 2425.7 2427.6 2432.7 2432.8 10963.1 11204.6 11206.2 11219.7 11225.1 5804.6 5962.9 5982.8 5991.4 6002.6 3606.2 3645.3 3721.0 3724.7 3737.4 1202.5 1214.2 1249.2 1265.8 1275.8 13636.7 14058.3 14364.8 15295.9 15510.5 4295.5 4385.9 4430.6 4710.5 4884.6 11791.6 11893.3 11911.8 11917.0 11927.3 4922.7 5132.3 5949.2 5956.5 5959.7 4472.2 5346.4 5386.6 5394.9 5398.2 3900.6 3923.8 3995.8 4025.3 4041.9 1045.1 1049.1 1067.7 1068.4 1072.6 807.4 826.8 833.9 834.1 842.7 6584.7 6648.6 6715.0 6940.8 6959.8 1013.5 1016.3 1043.6 1082.9 1116.1 3661.9 3863.5 3996.5 4070.8 4342.3 12994.9 13406.5 13967.4 14015.8 14017.4 13660.4 13730.9 13837.3 13871.3 13898.8 10141.5 10399.1 10502.9 10536.9 10593.5 6567.0 6622.9 6633.1 6784.6 6859.1 12078.8 12119.3 12456.8 12500.3 12519.6 9453.8 9996.3 12148.6 12502.5 12687.7 10070.6 10131.6 10272.1 10293.1 10382.6 9983.6 10070.7 10284.3 10296.7 10301.1 8746.5 8751.0 8790.6 8893.5 9021.1 8662.0 8672.0 8764.4 8819.9 8825.0 7882.6 7950.9 8077.2 8103.8 8108.7 5983.1 6012.5 6087.5 6090.4 6094.2 5872.6 6077.3 6176.4 6228.6 6292.4 04:27:39 INFO - PROCESS | 1649 | 04:27:39 INFO - PROCESS | 1649 | [#4] prototype.html Cycles:180 Average:4266.98 Median:4577.72 stddev:1183.10 (25.8%) stddev-sans-first:1149.97 04:27:39 INFO - PROCESS | 1649 | Values: 384.2 387.8 390.8 396.4 402.7 4879.3 4907.4 4935.4 4957.4 5002.7 3941.0 4220.8 4439.2 4568.6 4865.0 3500.5 3599.0 4040.4 4136.6 4137.9 3532.4 3578.8 3695.9 4105.0 4232.9 3878.3 4062.7 4125.9 4133.6 4188.5 3962.8 4067.1 4199.5 4207.1 4243.6 2956.6 3411.5 3470.9 3589.9 3720.9 3959.0 4288.8 4575.9 4638.3 4745.3 4124.0 4752.6 4758.0 4823.5 4850.8 5016.8 5171.4 5235.4 5249.3 5298.4 3846.8 4087.8 4269.1 4289.3 4378.5 5293.8 5389.5 5456.8 5507.4 5584.2 3559.5 3807.6 3968.3 3977.9 4358.4 3855.5 3958.9 4074.7 4085.9 4457.5 4132.2 4495.9 4674.9 4678.9 4739.5 4079.6 4661.9 5170.9 5177.8 5229.9 1301.4 1323.2 1359.9 1395.0 1400.6 1062.9 1066.7 1094.9 1112.0 1119.0 4393.5 4398.5 4719.0 4766.9 4771.1 4904.0 4980.1 5332.6 5392.9 5394.4 4138.8 4345.6 4411.5 4546.2 4661.7 2666.3 2735.8 2784.3 2785.9 2843.1 4892.9 5350.7 5477.9 5487.7 5525.4 4654.6 4729.8 5079.7 5459.9 5535.7 4757.5 4790.8 4966.9 5055.7 5383.7 4684.0 4766.2 4869.3 4910.1 5407.3 4383.7 4836.3 5286.4 5302.5 5321.3 5099.5 5177.8 5337.3 5346.7 5444.7 3695.8 3855.6 3999.7 4278.9 4342.9 4579.6 4652.8 5098.8 5349.8 5436.6 4336.7 4412.9 4801.5 5137.4 5142.5 4298.5 4351.3 4362.6 4884.3 5230.7 4510.0 4728.8 5041.5 5049.8 5078.8 4270.5 4775.1 4965.6 5144.7 5168.9 4420.8 4608.4 5078.9 5118.6 5124.6 04:27:39 INFO - PROCESS | 1649 | 04:27:39 INFO - PROCESS | 1649 | [#5] yui.html Cycles:180 Average:20183.32 Median:17680.01 stddev:28665.48 (162.1%) stddev-sans-first:28708.73 04:27:39 INFO - PROCESS | 1649 | Values: 743.6 788.2 791.8 845.8 858.4 14918.6 16002.1 16162.1 17721.7 18648.8 17037.0 17272.4 17657.4 17702.6 17946.0 993.3 1008.4 1014.7 1015.7 1016.3 979.8 993.0 995.5 997.6 1005.2 1065.1 1068.7 1068.7 1068.8 1069.2 1004.9 1062.8 1063.4 1064.4 1069.3 794.0 794.7 795.3 795.3 799.7 15913.8 16140.9 16143.6 16901.8 17155.2 14264.9 14402.4 14419.2 14517.7 14556.9 11446.3 11839.2 11858.7 11919.4 12399.2 3690.7 3727.2 3773.4 3859.2 3894.9 111390.0 113854.0 116888.4 118805.0 121193.0 10655.8 10779.4 11159.3 11446.5 11755.6 21713.9 23275.0 23910.9 23949.9 24007.2 7783.9 7813.3 7861.8 7870.7 8115.3 3988.9 4124.4 4141.6 4146.2 4164.0 3565.5 3612.0 3675.0 3679.4 3680.0 645.4 653.3 653.6 670.0 671.7 614.4 672.9 726.8 728.4 728.9 9868.7 10028.4 10084.3 10098.4 10274.5 676.8 678.7 697.4 716.5 717.8 4945.6 4954.6 4974.2 5000.8 5034.4 95714.0 102337.0 102946.0 105302.0 106444.0 54493.7 60590.1 73267.6 73726.0 96193.5 64819.0 72163.5 72729.6 74534.3 75444.6 10733.7 10873.9 11051.2 11202.8 11209.4 39586.4 46749.8 48441.5 48934.8 49084.3 59712.7 60688.7 61659.0 63521.7 63555.0 12424.3 12673.6 12860.0 13291.5 13393.9 14470.3 15282.5 15622.5 15774.2 15849.8 13310.8 13585.0 14361.5 14408.4 14580.6 13178.5 13827.7 14051.4 14420.3 15039.4 13818.7 14119.4 14122.5 14532.1 14595.6 9343.3 10135.4 10204.3 10569.2 10877.3 7810.4 8559.2 10140.4 11486.7 11519.5 04:27:39 INFO - PROCESS | 1649 | -------- Summary: end -------- 04:27:39 INFO - PROCESS | 1649 | 04:27:39 INFO - TEST-INFO | 1649: exit 0 04:27:39 INFO - TEST-OK | dromaeo_css | took 1118242ms 04:27:39 INFO - TEST-START | kraken 04:27:39 INFO - operating with platform_type : mac_ 04:27:39 INFO - Initialising browser for kraken test... 04:27:39 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/tmpO3LCdH/profile http://localhost:49225/getInfo.html) 04:27:42 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 04:27:42 INFO - PROCESS | 1654 | colorDepth:24 04:27:42 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 04:27:42 INFO - PROCESS | 1654 | __metrics 04:27:42 INFO - PROCESS | 1654 | 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:27:42 INFO - PROCESS | 1654 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:27:42 INFO - TEST-INFO | 1654: exit 0 04:27:42 INFO - Browser initialized. 04:27:42 INFO - Running cycle 1/1 for kraken test... 04:27:42 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/tmpO3LCdH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:27:54 INFO - PROCESS | 1656 | RSS: Main: 197410816 04:27:54 INFO - PROCESS | 1656 | 04:27:57 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-beat-detection) 04:27:58 INFO - PROCESS | 1656 | RSS: Main: 247844864 04:27:58 INFO - PROCESS | 1656 | 04:28:01 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-dft) 04:28:02 INFO - PROCESS | 1656 | RSS: Main: 259764224 04:28:02 INFO - PROCESS | 1656 | 04:28:05 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-fft) 04:28:06 INFO - PROCESS | 1656 | RSS: Main: 355807232 04:28:06 INFO - PROCESS | 1656 | 04:28:07 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-oscillator) 04:28:08 INFO - PROCESS | 1656 | RSS: Main: 259665920 04:28:08 INFO - PROCESS | 1656 | 04:28:10 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49225/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:28:11 INFO - PROCESS | 1656 | RSS: Main: 261144576 04:28:11 INFO - PROCESS | 1656 | 04:28:14 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49225/tests/kraken/driver.html?testName=imaging-darkroom) 04:28:14 INFO - PROCESS | 1656 | RSS: Main: 301920256 04:28:14 INFO - PROCESS | 1656 | 04:28:17 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49225/tests/kraken/driver.html?testName=imaging-desaturate) 04:28:18 INFO - PROCESS | 1656 | RSS: Main: 302796800 04:28:18 INFO - PROCESS | 1656 | 04:28:21 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49225/tests/kraken/driver.html?testName=json-parse-financial) 04:28:21 INFO - PROCESS | 1656 | RSS: Main: 299589632 04:28:21 INFO - PROCESS | 1656 | 04:28:23 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49225/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:28:24 INFO - PROCESS | 1656 | RSS: Main: 276676608 04:28:24 INFO - PROCESS | 1656 | 04:28:26 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:28:26 INFO - PROCESS | 1656 | RSS: Main: 280494080 04:28:26 INFO - PROCESS | 1656 | 04:28:28 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:28:29 INFO - PROCESS | 1656 | RSS: Main: 286834688 04:28:29 INFO - PROCESS | 1656 | 04:28:32 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:28:33 INFO - PROCESS | 1656 | RSS: Main: 302268416 04:28:33 INFO - PROCESS | 1656 | 04:28:35 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:28:36 INFO - PROCESS | 1656 | RSS: Main: 295682048 04:28:36 INFO - PROCESS | 1656 | 04:28:38 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49225/tests/kraken/driver.html?testName=ai-astar) 04:28:38 INFO - PROCESS | 1656 | RSS: Main: 340299776 04:28:38 INFO - PROCESS | 1656 | 04:28:38 INFO - PROCESS | 1656 | __start_tp_report 04:28:38 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 04:28:38 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 04:28:38 INFO - PROCESS | 1656 | |i|pagename|runs| 04:28:38 INFO - PROCESS | 1656 | |0;ai-astar;118;117;116;115;115;117;117;115;115;115 04:28:38 INFO - PROCESS | 1656 | |1;audio-beat-detection;139;110;111;144;110;108;109;110;156;136 04:28:38 INFO - PROCESS | 1656 | |2;audio-dft;184;183;184;185;185;182;186;210;265;194 04:28:38 INFO - PROCESS | 1656 | |3;audio-fft;77;61;61;92;61;60;62;59;76;62 04:28:38 INFO - PROCESS | 1656 | |4;audio-oscillator;88;70;87;71;69;68;79;69;68;82 04:28:38 INFO - PROCESS | 1656 | |5;imaging-gaussian-blur;86;87;87;87;87;86;87;86;87;86 04:28:38 INFO - PROCESS | 1656 | |6;imaging-darkroom;94;99;94;94;94;94;94;94;108;150 04:28:38 INFO - PROCESS | 1656 | |7;imaging-desaturate;83;84;86;98;132;89;79;84;83;86 04:28:38 INFO - PROCESS | 1656 | |8;json-parse-financial;63;59;59;59;63;64;66;64;64;63 04:28:38 INFO - PROCESS | 1656 | |9;json-stringify-tinderbox;56;61;69;61;59;58;58;57;57;58 04:28:38 INFO - PROCESS | 1656 | |10;stanford-crypto-aes;85;80;83;81;85;84;85;81;75;84 04:28:38 INFO - PROCESS | 1656 | |11;stanford-crypto-ccm;137;148;145;139;135;143;138;138;182;147 04:28:38 INFO - PROCESS | 1656 | |12;stanford-crypto-pbkdf2;163;162;163;167;161;162;162;162;163;169 04:28:38 INFO - PROCESS | 1656 | |13;stanford-crypto-sha256-iterative;63;65;67;80;80;80;69;63;64;63 04:28:38 INFO - PROCESS | 1656 | __end_tp_report 04:28:38 INFO - PROCESS | 1656 | __start_cc_report 04:28:38 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,2154 04:28:38 INFO - PROCESS | 1656 | __end_cc_report 04:28:38 INFO - PROCESS | 1656 | __startTimestamp1496057318531__endTimestamp 04:28:38 INFO - PROCESS | 1656 | 04:28:38 INFO - PROCESS | 1656 | ------- Summary: start ------- 04:28:38 INFO - PROCESS | 1656 | Number of tests: 14 04:28:38 INFO - PROCESS | 1656 | 04:28:38 INFO - PROCESS | 1656 | [#0] ai-astar Cycles:10 Average:116.00 Median:116.50 stddev:1.15 (1.0%) stddev-sans-first:0.97 04:28:38 INFO - PROCESS | 1656 | Values: 118.0 117.0 116.0 115.0 115.0 117.0 117.0 115.0 115.0 115.0 04:28:38 INFO - PROCESS | 1656 | 04:28:38 INFO - PROCESS | 1656 | [#1] audio-beat-detection Cycles:10 Average:123.30 Median:123.50 stddev:18.34 (14.8%) stddev-sans-first:18.55 04:28:38 INFO - PROCESS | 1656 | Values: 139.0 110.0 111.0 144.0 110.0 108.0 109.0 110.0 156.0 136.0 04:28:38 INFO - PROCESS | 1656 | 04:28:38 INFO - PROCESS | 1656 | [#2] audio-dft Cycles:10 Average:195.80 Median:185.50 stddev:25.72 (13.9%) stddev-sans-first:26.93 04:28:38 INFO - PROCESS | 1656 | Values: 184.0 183.0 184.0 185.0 185.0 182.0 186.0 210.0 265.0 194.0 04:28:38 INFO - PROCESS | 1656 | 04:28:38 INFO - PROCESS | 1656 | [#3] audio-fft Cycles:10 Average:67.10 Median:62.00 stddev:10.94 (17.6%) stddev-sans-first:11.00 04:28:38 INFO - PROCESS | 1656 | Values: 77.0 61.0 61.0 92.0 61.0 60.0 62.0 59.0 76.0 62.0 04:28:38 INFO - PROCESS | 1656 | 04:28:38 INFO - PROCESS | 1656 | [#4] audio-oscillator Cycles:10 Average:75.10 Median:75.00 stddev:8.09 (10.8%) stddev-sans-first:7.11 04:28:38 INFO - PROCESS | 1656 | Values: 88.0 70.0 87.0 71.0 69.0 68.0 79.0 69.0 68.0 82.0 04:28:38 INFO - PROCESS | 1656 | 04:28:38 INFO - PROCESS | 1656 | [#5] imaging-gaussian-blur Cycles:10 Average:86.60 Median:87.00 stddev:0.52 (0.6%) stddev-sans-first:0.50 04:28:38 INFO - PROCESS | 1656 | Values: 86.0 87.0 87.0 87.0 87.0 86.0 87.0 86.0 87.0 86.0 04:28:38 INFO - PROCESS | 1656 | 04:28:38 INFO - PROCESS | 1656 | [#6] imaging-darkroom Cycles:10 Average:101.50 Median:94.00 stddev:17.62 (18.7%) stddev-sans-first:18.48 04:28:38 INFO - PROCESS | 1656 | Values: 94.0 99.0 94.0 94.0 94.0 94.0 94.0 94.0 108.0 150.0 04:28:38 INFO - PROCESS | 1656 | 04:28:38 INFO - PROCESS | 1656 | [#7] imaging-desaturate Cycles:10 Average:90.40 Median:85.00 stddev:15.46 (18.2%) stddev-sans-first:16.16 04:28:38 INFO - PROCESS | 1656 | Values: 83.0 84.0 86.0 98.0 132.0 89.0 79.0 84.0 83.0 86.0 04:28:38 INFO - PROCESS | 1656 | 04:28:38 INFO - PROCESS | 1656 | [#8] json-parse-financial Cycles:10 Average:62.40 Median:63.50 stddev:2.50 (3.9%) stddev-sans-first:2.65 04:28:38 INFO - PROCESS | 1656 | Values: 63.0 59.0 59.0 59.0 63.0 64.0 66.0 64.0 64.0 63.0 04:28:38 INFO - PROCESS | 1656 | 04:28:38 INFO - PROCESS | 1656 | [#9] json-stringify-tinderbox Cycles:10 Average:59.40 Median:58.50 stddev:3.75 (6.4%) stddev-sans-first:3.77 04:28:38 INFO - PROCESS | 1656 | Values: 56.0 61.0 69.0 61.0 59.0 58.0 58.0 57.0 57.0 58.0 04:28:38 INFO - PROCESS | 1656 | 04:28:38 INFO - PROCESS | 1656 | [#10] stanford-crypto-aes Cycles:10 Average:82.30 Median:84.00 stddev:3.16 (3.8%) stddev-sans-first:3.20 04:28:38 INFO - PROCESS | 1656 | Values: 85.0 80.0 83.0 81.0 85.0 84.0 85.0 81.0 75.0 84.0 04:28:38 INFO - PROCESS | 1656 | 04:28:38 INFO - PROCESS | 1656 | [#11] stanford-crypto-ccm Cycles:10 Average:145.20 Median:144.00 stddev:13.68 (9.5%) stddev-sans-first:14.18 04:28:38 INFO - PROCESS | 1656 | Values: 137.0 148.0 145.0 139.0 135.0 143.0 138.0 138.0 182.0 147.0 04:28:38 INFO - PROCESS | 1656 | 04:28:38 INFO - PROCESS | 1656 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.40 Median:163.00 stddev:2.55 (1.6%) stddev-sans-first:2.70 04:28:38 INFO - PROCESS | 1656 | Values: 163.0 162.0 163.0 167.0 161.0 162.0 162.0 162.0 163.0 169.0 04:28:38 INFO - PROCESS | 1656 | 04:28:38 INFO - PROCESS | 1656 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.40 Median:68.00 stddev:7.56 (11.1%) stddev-sans-first:7.66 04:28:38 INFO - PROCESS | 1656 | Values: 63.0 65.0 67.0 80.0 80.0 80.0 69.0 63.0 64.0 63.0 04:28:38 INFO - PROCESS | 1656 | -------- Summary: end -------- 04:28:38 INFO - PROCESS | 1656 | 04:28:39 INFO - TEST-INFO | 1656: exit 0 04:28:39 INFO - TEST-OK | kraken | took 59478ms 04:28:39 INFO - SUITE-END | took 1177s 04:28:39 INFO - Completed test suite (00:19:38) 04:28:39 INFO - javascript benchmark 04:28:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7603.068326384484, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3348.7153591944716, 3379.543761592184, 3382.3066271414355, 3394.575678040245, 3414.334204830059, 5700.458456446636, 5709.143628455734, 5712.800051998181, 5734.397888221734, 5771.855703607406, 4697.718136911788, 5679.801206957757, 5772.393898640646, 5776.220210271612, 5785.681787501696, 5214.861458447488, 5231.712255825947, 5242.947570524284, 5277.604179686539, 5300.893982120355, 4171.0823618803815, 5133.537981581657, 5187.19598462239, 5331.040412725708, 5358.544523715489, 5209.921851172236, 5216.113260745682, 5243.606729495303, 5248.947510524884, 5277.366715994086, 4386.692931494781, 5239.899621079578, 5300.363956325227, 5305.363356397218, 5365.114756065244, 5210.713410762407, 5246.31797866275, 5251.76367063483, 5269.104252277122, 5283.392409872876, 5544.16837474378, 5608.214849921014, 5675.5459563234845, 5690.48785609293, 5707.1724599933195, 6123.498714075266, 6159.35326790685, 6170.691465426711, 6173.290071641729, 6185.505159587267, 6190.257169139687, 6220.626762394271, 6229.37706229378, 6229.4704950079295, 6249.656268905208, 5672.716364181774, 5750.453706897922, 5854.443827836349, 5952.851178720523, 5959.731812068424, 6297.149884765498, 6333.778317758856, 6399.328070552618, 6425.518086143558, 6433.356664333623, 5277.472252774692, 5775.104858746901, 6120.479759220427, 6127.417895299939, 6140.938590614126, 6093.847653808691, 6179.196704428396, 6179.907301390483, 6226.688665566703, 6244.063390491372, 6113.449789518876, 6118.110902583122, 6118.497354392036, 6143.508519318444, 6184.319724830265, 6190.628562286277, 6193.194884664965, 6199.442050215503, 6220.844478888064, 6224.221972253468, 4794.113089078532, 4807.519248075165, 4813.855584332476, 4829.082474329866, 4847.660663753574, 4210.6841986851105, 4257.467816522934, 4279.893002674896, 4284.700070995063, 4285.100128972888, 5641.52047075995, 5687.687177205211, 5696.174054762037, 5700.087985922232, 5719.885602287931, 6202.596831206048, 6228.5017198624, 6245.0632405139595, 6250.031245157008, 6256.0303153011355, 5580.386157522669, 5645.887082258414, 5676.176954341598, 5700.087985922315, 5703.572232082611, 5234.555062819627, 5262.710550919736, 5287.841364759063, 5383.380911195184, 5396.08266594672, 6347.587406818542, 6388.616682999035, 6404.935950640527, 6413.518986075969, 6421.518386120966, 6185.381461853779, 6200.100985357008, 6211.534134939988, 6212.844678883064, 6223.066540018853, 6113.689152827286, 6171.0, 6341.270753863365, 6413.941699619697, 6435.32429094957, 6127.14220009208, 6128.0, 6128.571000029955, 6133.233345831771, 6138.754449822136, 5191.0, 6119.112728654409, 6133.916185115316, 6156.846078848064, 6159.81520554384, 4762.833300834454, 6123.357047509948, 6183.288921773874, 6186.412290832363, 6195.845103872439, 6164.4143806338325, 6188.876222475616, 6191.102290167991, 6205.968970155121, 6207.348228435949, 5756.3380211276235, 5810.128480727924, 5881.05903055509, 5896.911546326723, 6018.2778066630535, 6043.09353596964, 6057.121717351048, 6064.363241859718, 6093.6039157454625, 6155.938440615537, 5983.551233657406, 6016.729247183975, 6024.819255422344, 6030.065339872327, 6032.366601506778], "value": 5661.15410727465, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [795.2008231727115, 825.3397282174259, 875.5534677314567, 886.7871710789409, 889.8798662180611, 38273.23453530931, 38940.41589376158, 39146.608533914616, 39262.01844953877, 39461.01347466308, 39891.401628975516, 40119.79640610779, 40133.197336053265, 40290.0, 40307.0, 7427.591482468462, 9169.266458683305, 9344.953275233614, 9345.95327023364, 9348.065193480665, 7450.105987281534, 11126.499307531181, 11397.145214108934, 11513.712157196054, 11531.962123408892, 11321.32072075674, 11331.206815522917, 11353.375564344, 11354.43227838609, 11358.432078396088, 11209.775804483905, 11283.0, 11286.097112231002, 11295.096392288679, 11300.208985370986, 11032.669019929415, 11078.778424431546, 11079.944600276947, 11096.55613775448, 11103.666889993314, 38084.23831523354, 38313.61686383128, 38363.23273534514, 38364.80817595894, 38447.03882402935, 3843.8078096095364, 3886.8639597613947, 3899.1421887184774, 3906.43356713275, 3962.7622342659533, 27691.861540692367, 29538.409231815243, 29711.851440742874, 29860.805567777265, 30033.69966300331, 2964.614600101973, 3041.574179614856, 3060.6786287439945, 3129.092563156682, 3200.487921932503, 347767.0, 348485.0, 348564.99999999744, 349104.99999999744, 349123.0, 52314.0, 52415.42753717394, 52461.73769131206, 52501.474985250425, 52567.0, 54233.0, 54552.45447545553, 54619.90760184774, 54693.453065468835, 54742.72628636831, 12261.632151035483, 14929.626759331104, 14934.32795524204, 14970.550883473514, 14971.925140374446, 12782.872171278168, 12797.168184068007, 12802.551910683082, 12822.422990965242, 12827.743445131231, 11108.888911110947, 12255.896969272808, 12272.754544909052, 12320.0, 12374.0, 5052.418971818288, 5307.867303317448, 5468.812475010028, 5723.8569035774435, 5744.138379243063, 1059.9735006624896, 1093.9179561532917, 1115.754534002518, 1154.3535620052796, 1160.4719852467108, 26809.514763080675, 27555.724442755716, 27605.723942760716, 27870.442591148065, 28458.430831383255, 1078.1536493852288, 1083.0847933496184, 1113.2374323588367, 1116.7040734205443, 1121.3552207480732, 12926.935365323301, 13270.867291326964, 13585.796213056812, 13613.659658508617, 13618.18290902549, 454405.0, 457537.0, 461365.0, 461454.0, 461892.0, 45144.0, 45148.77425612917, 45222.09555808931, 45267.32099018517, 45303.0, 1404.838443578981, 1442.401403417591, 1453.7715630292387, 1453.978190327146, 1454.142056186855, 22155.446113847283, 26947.66847899208, 27137.72862271352, 27388.0, 27812.721872781014, 41580.376294355, 41763.373549397395, 41790.0, 41832.58167418287, 41921.95195120147, 395009.0, 396466.0, 396726.0, 397509.0, 398128.0, 28972.565411519266, 28974.0, 29176.854115730133, 29752.404951901273, 30834.383312334077, 38627.0, 38829.41755873721, 39834.800825995684, 40032.199356012134, 40302.798486007385, 28069.578956315265, 28106.43787124287, 28648.85675571609, 28851.7114828849, 29050.0, 28876.566851497664, 28948.27629309284, 28953.710462895102, 28955.855220723763, 29022.854885725435, 32666.673333266364, 33135.502967455024, 33250.16874578058, 33422.99731008073, 33469.497957531144, 26860.59709104326, 28633.28416789597, 29202.415951681272, 29223.85388073046, 29336.413271734873, 22656.30077744143, 22713.09147634076, 23147.07411703513, 23181.652275215547, 23193.956271968138], "value": 18442.499212591472, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.2852957763348, 418.5354256774979, 425.93185090385543, 437.8620734468642, 445.40760788151744, 20183.798162018375, 21339.466513337156, 22670.979805908733, 22890.54218915623, 22938.541229175447, 21684.8915755421, 22769.0, 23008.964596593152, 23030.53938921225, 23376.532469350605, 6690.397864192209, 6847.931520684804, 6884.001819736135, 6930.237673855871, 6949.374556289933, 6550.836229094263, 6617.007448882682, 6779.423748981318, 6789.660516974156, 6790.694418751168, 6429.807105786834, 6681.966590167042, 6775.0, 6793.048973143764, 6796.184457865063, 6478.967605161944, 6739.7978060658015, 6805.931940680579, 6809.795706128824, 6812.52312338139, 6547.377999090127, 6648.235452922928, 6655.267920528738, 6656.467482601381, 6657.101291325657, 26680.799364028666, 27311.45377092447, 27481.0, 27496.862515687295, 27846.60766961644, 3230.8707651693903, 3232.4666430039015, 3419.333230020147, 3456.6197718250974, 3492.703120234783, 16968.660626787518, 17015.914920425443, 17300.961942283502, 17475.126243687893, 17527.824721752746, 1998.82010619045, 2067.824234940032, 2069.1309649946907, 2073.0, 2083.499960009602, 117846.0, 119423.0, 119493.40253298677, 119774.40112799467, 120617.39691301575, 17263.3957811476, 21573.137074516842, 21834.23580174686, 21920.671189932164, 21975.780242197692, 36288.36702348401, 37124.62875371212, 37160.62839371571, 37176.0, 37182.8140859294, 9159.49622770754, 10715.0, 10728.034476897117, 10891.98498277821, 10920.781584368267, 7595.240475952361, 8731.257843083282, 8754.21212090915, 8854.955725221333, 8904.8664270036, 8374.832503349899, 10603.681889543326, 10641.946790266, 10736.624218152327, 10762.19283553721, 3665.780053196763, 3668.9633103669157, 4296.377025331372, 4336.6313863321975, 4367.0, 837.8575642140851, 942.8491441369348, 943.7593413679468, 951.8429459139164, 960.3853533738413, 21258.893705531373, 22657.773422265895, 22713.432164195696, 22735.658965115537, 22781.582762585414, 973.1047436358567, 978.2125389061873, 997.5062344139698, 1000.45975173407, 1001.9949900250452, 11481.655550333504, 11688.766224675628, 11942.701432464259, 12137.0, 12143.089268304735, 722643.0, 738594.5245746478, 745205.0, 762388.0, 763558.0, 87231.56384218039, 112073.0, 127592.0, 136689.0, 137587.31206343905, 1246.1837496439846, 1313.4155300891202, 1327.9004074694244, 1328.7209685965863, 1329.736750087421, 19606.964058880556, 20737.377878663665, 20917.790822091585, 20921.37235882926, 20967.58064838664, 45154.322685159095, 49626.270555119816, 53900.46099538954, 57903.4209657898, 60270.39729602823, 58804.0, 80144.59927700325, 82374.0, 82439.70241190285, 82737.0, 10142.49287535635, 12258.325792081521, 12443.914436908472, 12705.047121465743, 12769.170003949714, 13474.0, 13520.661983450493, 13866.0, 13921.0, 14056.718865622835, 11443.0, 11516.481758320728, 11809.055275578105, 11847.0, 11916.880831191576, 10815.743414065093, 10835.0, 10867.0, 10943.01512863846, 10994.560217591206, 10444.529996150344, 11807.114466415225, 11903.76192476163, 11927.284362938251, 12007.399630018639, 10147.746306342106, 12273.447694853574, 12354.382280886099, 12529.561465348668, 12660.620181394574, 9909.207263419054, 11498.0, 11534.59628912984, 11749.76500470003, 11805.645830625095], "value": 11682.04759245206, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1586.4050980882168, 1589.125980710609, 1616.8383161683832, 1616.9272382742774, 1620.805503339599, 10561.155107591394, 11151.665450036491, 11152.665420037392, 11159.553617855287, 11162.05122564581, 10759.515821788018, 10885.673429797118, 11153.776924461506, 11314.830277545843, 11317.773644527104, 2394.425337918896, 2431.4286142756437, 2442.694663167104, 2443.657887895696, 2447.5349683560157, 2287.462446325112, 2328.476092879097, 2346.3195673254736, 2349.0368948731025, 2417.419819243378, 2362.598358279088, 2427.332483567024, 2434.1480481831395, 2437.427204606916, 2438.7073551173885, 2332.1604222479896, 2381.0951838301507, 2396.472775989297, 2405.651180578815, 2442.890069946857, 2402.9279121626378, 2425.745296743834, 2427.5919966419433, 2432.7323994360604, 2432.793212576933, 10963.068139208097, 11204.551817927273, 11206.15953803468, 11219.663410097628, 11225.101991840633, 5804.564657650693, 5962.910556341659, 5982.7906023289415, 5991.400859914017, 6002.609830361056, 3606.2246616977213, 3645.2709458108484, 3721.0139313082054, 3724.7392682512236, 3737.364648009845, 1202.4528839378063, 1214.2410993129295, 1249.2005116725213, 1265.753178130265, 1275.7576060548558, 13636.659083522893, 14058.297085145701, 14364.784528232085, 15295.923520382326, 15510.534683959499, 4295.484541854998, 4385.934210986838, 4430.601245887886, 4710.528947105262, 4884.633652476078, 11791.646250612495, 11893.345865977455, 11911.821322680167, 11917.046636269077, 11927.343996080126, 4922.680025798311, 5132.28148059272, 5949.226600541989, 5956.463918247292, 5959.702014899238, 4472.217361961643, 5346.4118946915805, 5386.596005299618, 5394.8921021579345, 5398.1902714593125, 3900.551436584772, 3923.7841918694176, 3995.780232087205, 4025.255327764373, 4041.939370909439, 1045.1273186888882, 1049.0610903241557, 1067.6530127708527, 1068.385678235018, 1072.5977758340623, 807.4025221336286, 826.7519744076753, 833.9482298408872, 834.0700119366862, 842.6755699055908, 6584.703688333941, 6648.634325112165, 6714.9664251679405, 6940.757073502403, 6959.826004349932, 1013.483123606951, 1016.3190662256159, 1043.634727845248, 1082.890037711352, 1116.1238427834112, 3661.901429571118, 3863.459115723801, 3996.480457540559, 4070.776107314126, 4342.32693932441, 12994.935025324812, 13406.463741450429, 13967.37146828395, 14015.789763153562, 14017.369218385196, 13660.385282662304, 13730.867028967563, 13837.30813459323, 13871.335439747094, 13898.818600418876, 10141.543630536644, 10399.064084232456, 10502.894971050344, 10536.947315263375, 10593.523291451904, 6566.967165164144, 6622.933770662425, 6633.1045308882685, 6784.626845523544, 6859.108315918898, 12078.75842483163, 12119.272843629411, 12456.75086498283, 12500.31248281317, 12519.624411267676, 9453.810923781624, 9996.250281228791, 12148.635540933787, 12502.499900004263, 12687.682807929876, 10070.597176113166, 10131.594736210469, 10272.075513203847, 10293.125084367912, 10382.584696612052, 9983.550740216562, 10070.748231294276, 10284.331518451278, 10296.691099267035, 10301.124404425407, 8746.51894145828, 8751.037385887674, 8790.648374065222, 8893.510856902933, 9021.097890210927, 8661.956690216508, 8672.046074931626, 8764.386492945432, 8819.911800882166, 8825.0, 7882.645280962485, 7950.920490795018, 8077.192280771876, 8103.8379232413845, 8108.675652973815, 5983.132445795422, 6012.458878700763, 6087.512998959984, 6090.390960904051, 6094.177286066324, 5872.588918775645, 6077.301110372364, 6176.4441200292185, 6228.56400051992, 6292.366272362132], "value": 5169.604295192604, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [384.22770231834005, 387.7731527056672, 390.77335145615547, 396.3618574095705, 402.6677990657706, 4879.316895634614, 4907.411110666721, 4935.432425271095, 4957.40511138663, 5002.749862506878, 3941.0, 4220.788960551976, 4439.156560253549, 4568.565986231302, 4864.954034882499, 3500.492428597852, 3598.956302672222, 4040.3939409088653, 4136.565660605638, 4137.896552586194, 3532.4348104303317, 3578.7852728836224, 3695.8891233263043, 4105.0, 4232.915341693164, 3878.263130005304, 4062.6749860011278, 4125.9174816503655, 4133.627973482386, 4188.455500784894, 3962.7820469874146, 4067.132671466609, 4199.475065616798, 4207.137536804963, 4243.639290660298, 2956.5703783611125, 3411.4541673332146, 3470.861165553375, 3589.8564057437934, 3720.8883733488037, 3959.0, 4288.828446862121, 4575.862724118282, 4638.304254361873, 4745.335653008547, 4123.97938010308, 4752.554398061412, 4757.95242047582, 4823.493533179036, 4850.8059677612855, 5016.824411145592, 5171.37943446785, 5235.397929238148, 5249.265102885599, 5298.390685071189, 3846.826892789831, 4087.8364865405647, 4269.057578801638, 4289.313709806447, 4378.474583050054, 5293.788248470057, 5389.541888939445, 5456.835035679296, 5507.366652834935, 5584.21820945068, 3559.483874838186, 3807.6192380761704, 3968.345223038224, 3977.9204415911518, 4358.389825424443, 3855.479510266134, 3958.8614398495915, 4074.6536544394057, 4085.9387109193385, 4457.531959144308, 4132.223144048088, 4495.876030992252, 4674.883127921761, 4678.8596342109795, 4739.526047395233, 4079.63283304504, 4661.930071048937, 5170.948290517122, 5177.844664660066, 5229.947700522946, 1301.3688361144837, 1323.232525135419, 1359.8708122728524, 1394.9930250348887, 1400.586826886071, 1062.8830828608848, 1066.7279843639978, 1094.8631421072366, 1111.9714264305646, 1118.9537782173693, 4393.538678438782, 4398.538153493901, 4719.0, 4766.855954570878, 4771.117343291502, 4903.975480122648, 4980.078685443204, 5332.573394128461, 5392.91910621341, 5394.406615272317, 4138.771758028743, 4345.565443455629, 4411.470623525198, 4546.227141385973, 4661.743604101739, 2666.2534490342737, 2735.849528275924, 2784.259837601499, 2785.8607069646437, 2843.076000299911, 4892.850180207648, 5350.652207606493, 5477.945220547744, 5487.682956090589, 5525.364583072998, 4654.557817007379, 4729.810807567727, 5079.663354856807, 5459.890802183934, 5535.6955367454375, 4757.52516719818, 4790.8323208687525, 4966.925496117489, 5055.6632622469, 5383.650062745909, 4683.953160468352, 4766.213574760264, 4869.342638743724, 4910.140725373117, 5407.270018547446, 4383.736975781463, 4836.274558816206, 5286.440500052417, 5302.496262855023, 5321.3348331458565, 5099.490050994871, 5177.844664660066, 5337.27798822611, 5346.705931173823, 5444.727763611882, 3695.796731179811, 3855.6021569801383, 3999.6600288975765, 4278.887489252755, 4342.891427714332, 4579.573205751196, 4652.767361631837, 5098.776293689562, 5349.812756553502, 5436.6194366393975, 4336.674749393871, 4412.889677758082, 4801.471838097877, 5137.357830271216, 5142.485751424828, 4298.5271620122385, 4351.34648833454, 4362.607365337135, 4884.291777692144, 5230.660007099526, 4509.9549004510845, 4728.787204575733, 5041.495850414929, 5049.84850454487, 5078.771455284447, 4270.50889147752, 4775.092732380836, 4965.577925876341, 5144.742762861767, 5168.922466162935, 4420.756858372821, 4608.423947006624, 5078.936850519496, 5118.59238709343, 5124.6412751108555], "value": 3931.885400901798, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [743.57244584364, 788.1645455816835, 791.7980914866712, 845.7674139611607, 858.3991206155692, 14918.5524434267, 16002.119883406407, 16162.111083890397, 17721.7341739874, 18648.81351186488, 17037.0, 17272.395466158687, 17657.381991630296, 17702.64594708105, 17946.0, 993.349356171705, 1008.4050410257946, 1014.7463134216446, 1015.725754046407, 1016.2530540053056, 979.8187335342984, 992.9821932519153, 995.4823491784268, 997.6109317366233, 1005.1606908231599, 1065.0627447845923, 1068.6580294305825, 1068.6793961811463, 1068.754186537097, 1069.2087854987292, 1004.9196064314838, 1062.8033813744444, 1063.4044934836515, 1064.4464878263336, 1069.3156379916782, 794.0114557376104, 794.7258195922398, 795.264380448089, 795.3041089047084, 799.6681377228443, 15913.761293580606, 16140.919295403566, 16143.596410089842, 16901.83098169015, 17155.228014739365, 14264.928675356661, 14402.351894164683, 14419.206943618097, 14517.70964580713, 14556.927215363961, 11446.313221206754, 11839.171258011942, 11858.703532411759, 11919.404029798563, 12399.194052386567, 3690.6862916652117, 3727.235916637083, 3773.3773927301963, 3859.150986782903, 3894.883153505371, 111390.0, 113854.0, 116888.41555792252, 118805.0, 121193.0, 10655.786884262272, 10779.407132607625, 11159.27464714791, 11446.484908179153, 11755.647330580096, 21713.891430542746, 23275.0, 23910.88044559801, 23949.880250598984, 24007.159749408685, 7783.883241751379, 7813.257740514642, 7861.842763144705, 7870.685172593032, 8115.269625733594, 3988.9002774930277, 4124.443200167939, 4141.565135660712, 4146.212219678251, 4163.97918010408, 3565.4830049642655, 3612.0, 3675.0, 3679.448082787603, 3680.0431887709387, 645.3610925184034, 653.2716021635937, 653.633964979613, 669.9799006029826, 671.691022129816, 614.3825455417366, 672.9192496900404, 726.8037629840019, 728.351766927435, 728.9153022356057, 9868.703938881845, 10028.398296102258, 10084.294099412971, 10098.394096354243, 10274.537645805956, 676.7665155521436, 678.6776281266359, 697.3584302441766, 716.4769718105812, 717.7810767715897, 4945.554900059012, 4954.5788607968725, 4974.1543937530705, 5000.824971125993, 5034.395872495324, 95714.0, 102337.0, 102946.0, 105302.0, 106444.0, 54493.72753136209, 60590.091148632804, 73267.63366183135, 73726.0, 96193.5190324044, 64819.02771458526, 72163.50528452464, 72729.6363518179, 74534.25465745381, 75444.62277688686, 10733.731656708333, 10873.891261087289, 11051.226414150933, 11202.83195752048, 11209.383483908463, 39586.40620390754, 46749.76625116853, 48441.5155848437, 48934.75532622314, 49084.263736044704, 59712.70143649428, 60688.69655651694, 61659.0, 63521.68239158775, 63555.0, 12424.254544727346, 12673.619791406272, 12860.0, 13291.534796282083, 13393.933030334785, 14470.348834302271, 15282.541523754306, 15622.531324060297, 15774.211289435252, 15849.841501585297, 13310.800337994744, 13585.041101080447, 14361.497347593202, 14408.351624177147, 14580.562583122522, 13178.472402306019, 13827.654308642364, 14051.36768845425, 14420.278986050445, 15039.398424062914, 13818.654533636338, 14119.364628591533, 14122.50571230002, 14532.128072315272, 14595.635109122357, 9343.34596578233, 10135.391876487434, 10204.336718113298, 10569.154467642724, 10877.347359158475, 7810.415552879491, 8559.17547209407, 10140.391576505433, 11486.655400338002, 11519.53921843117], "value": 7790.881088290912, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1437.9000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 116.0, 115.0, 115.0, 117.0, 117.0, 115.0, 115.0, 115.0], "value": 116.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 110.0, 111.0, 144.0, 110.0, 108.0, 109.0, 110.0, 156.0, 136.0], "value": 123.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 184.0, 185.0, 185.0, 182.0, 186.0, 210.0, 265.0, 194.0], "value": 195.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 61.0, 61.0, 92.0, 61.0, 60.0, 62.0, 59.0, 76.0, 62.0], "value": 67.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 70.0, 87.0, 71.0, 69.0, 68.0, 79.0, 69.0, 68.0, 82.0], "value": 75.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 87.0, 87.0, 87.0, 86.0, 87.0, 86.0, 87.0, 86.0], "value": 86.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 99.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 108.0, 150.0], "value": 101.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 86.0, 98.0, 132.0, 89.0, 79.0, 84.0, 83.0, 86.0], "value": 90.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 59.0, 59.0, 59.0, 63.0, 64.0, 66.0, 64.0, 64.0, 63.0], "value": 62.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 61.0, 69.0, 61.0, 59.0, 58.0, 58.0, 57.0, 57.0, 58.0], "value": 59.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 80.0, 83.0, 81.0, 85.0, 84.0, 85.0, 81.0, 75.0, 84.0], "value": 82.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 148.0, 145.0, 139.0, 135.0, 143.0, 138.0, 138.0, 182.0, 147.0], "value": 145.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 163.0, 167.0, 161.0, 162.0, 162.0, 162.0, 163.0, 169.0], "value": 163.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 67.0, 80.0, 80.0, 80.0, 69.0, 63.0, 64.0, 63.0], "value": 69.4, "unit": "score"}]}]} 04:28:39 INFO - Return code: 0 04:28:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:28:39 INFO - # TBPL SUCCESS # 04:28:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:28:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:28:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:28:39 INFO - 2017-05-29 04:28:39.876 screenresolution[1660:10998] starting screenresolution argv=screenresolution get 04:28:39 INFO - 2017-05-29 04:28:39.878 screenresolution[1660:10998] Display 0: 1600x1200x32@60 04:28:39 INFO - 2017-05-29 04:28:39.884 screenresolution[1661:11001] starting screenresolution argv=screenresolution list 04:28:39 INFO - Available Modes on Display 0 04:28:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:28:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:28:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:28:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:28:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:28:40 INFO - Intel Iris: 04:28:40 INFO - Chipset Model: Intel Iris 04:28:40 INFO - Type: GPU 04:28:40 INFO - Bus: Built-In 04:28:40 INFO - VRAM (Dynamic, Max): 1536 MB 04:28:40 INFO - Vendor: Intel (0x8086) 04:28:40 INFO - Device ID: 0x0a2e 04:28:40 INFO - Revision ID: 0x0009 04:28:40 INFO - Displays: 04:28:40 INFO - Display: 04:28:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:28:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:28:40 INFO - Display Serial Number: 1600x1200 60 04:28:40 INFO - Main Display: Yes 04:28:40 INFO - Mirror: Off 04:28:40 INFO - Online: Yes 04:28:40 INFO - Rotation: Supported 04:28:40 INFO - Return code: 0 04:28:40 INFO - Running post-action listener: _resource_record_post_action 04:28:40 INFO - [mozharness: 2017-05-29 11:28:40.117758Z] Finished run-tests step (success) 04:28:40 INFO - Running post-run listener: _resource_record_post_run 04:28:40 INFO - Total resource usage - Wall time: 1198s; CPU: 54.0%; Read bytes: 33971200; Write bytes: 512387072; Read time: 318; Write time: 1584 04:28:40 INFO - TinderboxPrint: CPU usage
53.9% 04:28:40 INFO - TinderboxPrint: I/O read bytes / time
33,971,200 / 318 04:28:40 INFO - TinderboxPrint: I/O write bytes / time
512,387,072 / 1,584 04:28:40 INFO - TinderboxPrint: CPU idle
2,210.2 (46.1%) 04:28:40 INFO - TinderboxPrint: CPU system
958.2 (20.0%) 04:28:40 INFO - TinderboxPrint: CPU user
1,625.2 (33.9%) 04:28:40 INFO - TinderboxPrint: Swap in / out
238,501,888 / 0 04:28:40 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 208431616; Write bytes: 206399488; Read time: 15903; Write time: 455 04:28:40 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 31845376; Write bytes: 295837696; Read time: 288; Write time: 1092 04:28:40 INFO - Running post-run listener: _upload_blobber_files 04:28:40 INFO - Blob upload gear active. 04:28:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:28: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:28: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:28: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:28:41 INFO - (blobuploader) - INFO - Open directory for files ... 04:28:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:28:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:28:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:28:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:28:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:28:42 INFO - (blobuploader) - INFO - Done attempting. 04:28:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:28:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:28:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:28:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:28:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:28:43 INFO - (blobuploader) - INFO - Done attempting. 04:28:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:28:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:28:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:28:44 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:28:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:28:44 INFO - (blobuploader) - INFO - Done attempting. 04:28:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:28:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:28:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:28:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:28:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:28:46 INFO - (blobuploader) - INFO - Done attempting. 04:28:46 INFO - (blobuploader) - INFO - Iteration through files over. 04:28:46 INFO - Return code: 0 04:28:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:28:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:28:46 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a5209be372b62cd2fc9bc5a32023adc2814d9f3856965341030299bced44f8e59f1527a229da8e6266ecefcdc2e4337858432d5cc4ff540c132c5dea8c2fc4dc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4d18856ec09b48b1d59c45e9e7df9990d336b5759304aef6bb2625ac0b8ae298468adcc9e1bc3ccc5049c719db7677ce759c451a5b56cbbf2e651861a48651b1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1b0d57d8b4a283812897059bac6b7678785a635786f468297110881f3ce700fc226819767268b999275dffeddebefdbdc926fe9531b2326617e7c615475c8546", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b43ad3516f22da4d73e20a315564fec69d315c6a1a8a1e476367922e815e45546ca7fec9bcb7e05eeb10e0b146a3c2a41657b111935550e23a057f96309dff9f"} 04:28:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:28:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:28:46 INFO - Contents: 04:28:46 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a5209be372b62cd2fc9bc5a32023adc2814d9f3856965341030299bced44f8e59f1527a229da8e6266ecefcdc2e4337858432d5cc4ff540c132c5dea8c2fc4dc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4d18856ec09b48b1d59c45e9e7df9990d336b5759304aef6bb2625ac0b8ae298468adcc9e1bc3ccc5049c719db7677ce759c451a5b56cbbf2e651861a48651b1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1b0d57d8b4a283812897059bac6b7678785a635786f468297110881f3ce700fc226819767268b999275dffeddebefdbdc926fe9531b2326617e7c615475c8546", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b43ad3516f22da4d73e20a315564fec69d315c6a1a8a1e476367922e815e45546ca7fec9bcb7e05eeb10e0b146a3c2a41657b111935550e23a057f96309dff9f"} 04:28:46 INFO - Running post-run listener: copy_logs_to_upload_dir 04:28:46 INFO - Copying logs to upload dir... 04:28:46 INFO - mkdir: /builds/slave/test/build/upload/logs 04:28:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.062820 ========= master_lag: 0.84 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-05-29 04:28:47.308188) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-29 04:28:47.311138) ========= 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.43g8oLvNs3/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.N6RjKhZUDI/Listeners TMPDIR=/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a5209be372b62cd2fc9bc5a32023adc2814d9f3856965341030299bced44f8e59f1527a229da8e6266ecefcdc2e4337858432d5cc4ff540c132c5dea8c2fc4dc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4d18856ec09b48b1d59c45e9e7df9990d336b5759304aef6bb2625ac0b8ae298468adcc9e1bc3ccc5049c719db7677ce759c451a5b56cbbf2e651861a48651b1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1b0d57d8b4a283812897059bac6b7678785a635786f468297110881f3ce700fc226819767268b999275dffeddebefdbdc926fe9531b2326617e7c615475c8546", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b43ad3516f22da4d73e20a315564fec69d315c6a1a8a1e476367922e815e45546ca7fec9bcb7e05eeb10e0b146a3c2a41657b111935550e23a057f96309dff9f"} build_url:https://queue.taskcluster.net/v1/task/Ur1N-XzpRC2SW2bYKyD7Lg/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011361 build_url: 'https://queue.taskcluster.net/v1/task/Ur1N-XzpRC2SW2bYKyD7Lg/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/a5209be372b62cd2fc9bc5a32023adc2814d9f3856965341030299bced44f8e59f1527a229da8e6266ecefcdc2e4337858432d5cc4ff540c132c5dea8c2fc4dc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4d18856ec09b48b1d59c45e9e7df9990d336b5759304aef6bb2625ac0b8ae298468adcc9e1bc3ccc5049c719db7677ce759c451a5b56cbbf2e651861a48651b1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1b0d57d8b4a283812897059bac6b7678785a635786f468297110881f3ce700fc226819767268b999275dffeddebefdbdc926fe9531b2326617e7c615475c8546", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b43ad3516f22da4d73e20a315564fec69d315c6a1a8a1e476367922e815e45546ca7fec9bcb7e05eeb10e0b146a3c2a41657b111935550e23a057f96309dff9f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-29 04:28:47.345556) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 04:28:47.345915) ========= 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.43g8oLvNs3/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.N6RjKhZUDI/Listeners TMPDIR=/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005196 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 04:28:47.394805) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-29 04:28:47.395124) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-29 04:28:47.395465) ========= ========= Total master_lag: 1.00 =========